openhab-addons
openhab-addons copied to clipboard
[tr064] Failed to get list from FritzBox
There is still a bug in the tr064 binding. Issue #9689 was closed 09.01.2021 but the bug is not corrected.
Some people, include me, get errors like this:
My Equipment: Raspbian Buster OH 3.1.0 Release Build FritzBox 7490 7.27
Can someone please check this. Thanks. Günter
(It's my first bug report, hope i made not to many mistakes)
Please provide a log at TRACE level (request before that message and result). Otherwise we can't see what went wrong.
Hello @J-N-K
Please provide a log at TRACE level (request before that message and result). Otherwise we can't see what went wrong.
I have this on openHAB 3.2.0 M1 and the new openHAB 3.2.0 M2 builds, too. Fritzbox 7490 software is latest release 07.28.
I set
log:set TRACE org.openhab.binding.tr064
This is what I got:
2021-09-11 08:33:21.886 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Request: <SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/" SOAP-ENV:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><SOAP-ENV:Header/><SOAP-ENV:Body><u:GetCommonLinkProperties xmlns:u="urn:dslforum-org:service:WANCommonInterfaceConfig:1"/></SOAP-ENV:Body></SOAP-ENV:Envelope>
2021-09-11 08:33:21.917 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Re-Auth needed.
2021-09-11 08:33:22.052 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Received response: <?xml version="1.0"?>
2021-09-11 08:33:22.058 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Storing in cache: com.sun.xml.messaging.saaj.soap.ver1_1.Message1_1Impl@1c3fc4d
2021-09-11 08:33:22.059 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Returning from cache: com.sun.xml.messaging.saaj.soap.ver1_1.Message1_1Impl@1c3fc4d
2021-09-11 08:33:22.066 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Request: <SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/" SOAP-ENV:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><SOAP-ENV:Header/><SOAP-ENV:Body><u:GetInfo xmlns:u="urn:dslforum-org:service:WANDSLInterfaceConfig:1"/></SOAP-ENV:Body></SOAP-ENV:Envelope>
2021-09-11 08:33:22.169 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Received response: <?xml version="1.0"?>
2021-09-11 08:33:22.178 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Storing in cache: com.sun.xml.messaging.saaj.soap.ver1_1.Message1_1Impl@1588392
2021-09-11 08:33:22.179 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Returning from cache: com.sun.xml.messaging.saaj.soap.ver1_1.Message1_1Impl@1588392
2021-09-11 08:33:22.268 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Request: <SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/" SOAP-ENV:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><SOAP-ENV:Header/><SOAP-ENV:Body><u:GetCallList xmlns:u="urn:dslforum-org:service:X_AVM-DE_OnTel:1"/></SOAP-ENV:Body></SOAP-ENV:Envelope>
2021-09-11 08:33:22.296 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Received response: <?xml version="1.0"?>
2021-09-11 08:33:22.301 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Storing in cache: com.sun.xml.messaging.saaj.soap.ver1_1.Message1_1Impl@c9bdd2
2021-09-11 08:33:22.302 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Returning from cache: com.sun.xml.messaging.saaj.soap.ver1_1.Message1_1Impl@c9bdd2
2021-09-11 08:33:22.304 [TRACE] [hab.binding.tr064.internal.util.Util] - Refreshing cache for 'https://192.168.178.1:49443/calllist.lua?sid=56c9850080cf0fc7&days=10'
2021-09-11 08:33:22.826 [TRACE] [hab.binding.tr064.internal.util.Util] - XML = <?xml version="1.0" encoding="UTF-8"?>
2021-09-11 08:33:22.857 [TRACE] [hab.binding.tr064.internal.util.Util] - Storing in cache org.openhab.binding.tr064.internal.dto.additions.Root@6f424f
2021-09-11 08:33:22.858 [TRACE] [hab.binding.tr064.internal.util.Util] - Returning from cache: org.openhab.binding.tr064.internal.dto.additions.Root@6f424f
2021-09-11 08:33:22.862 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Request: <SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/" SOAP-ENV:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><SOAP-ENV:Header/><SOAP-ENV:Body><u:GetMessageList xmlns:u="urn:dslforum-org:service:X_AVM-DE_TAM:1"><NewIndex>1</NewIndex></u:GetMessageList></SOAP-ENV:Body></SOAP-ENV:Envelope>
2021-09-11 08:33:22.893 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Received response: <?xml version="1.0"?>
2021-09-11 08:33:22.899 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Storing in cache: com.sun.xml.messaging.saaj.soap.ver1_1.Message1_1Impl@17ea485
2021-09-11 08:33:22.901 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Returning from cache: com.sun.xml.messaging.saaj.soap.ver1_1.Message1_1Impl@17ea485
2021-09-11 08:33:23.120 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Returning from cache: com.sun.xml.messaging.saaj.soap.ver1_1.Message1_1Impl@c9bdd2
2021-09-11 08:33:23.122 [TRACE] [hab.binding.tr064.internal.util.Util] - Returning from cache: org.openhab.binding.tr064.internal.dto.additions.Root@6f424f
2021-09-11 08:33:23.128 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Request: <SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/" SOAP-ENV:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><SOAP-ENV:Header/><SOAP-ENV:Body><u:GetMessageList xmlns:u="urn:dslforum-org:service:X_AVM-DE_TAM:1"><NewIndex>0</NewIndex></u:GetMessageList></SOAP-ENV:Body></SOAP-ENV:Envelope>
2021-09-11 08:33:23.156 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Received response: <?xml version="1.0"?>
2021-09-11 08:33:23.160 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Storing in cache: com.sun.xml.messaging.saaj.soap.ver1_1.Message1_1Impl@1533116
2021-09-11 08:33:23.161 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Returning from cache: com.sun.xml.messaging.saaj.soap.ver1_1.Message1_1Impl@1533116
2021-09-11 08:33:23.514 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Returning from cache: com.sun.xml.messaging.saaj.soap.ver1_1.Message1_1Impl@c9bdd2
2021-09-11 08:33:23.516 [TRACE] [hab.binding.tr064.internal.util.Util] - Returning from cache: org.openhab.binding.tr064.internal.dto.additions.Root@6f424f
2021-09-11 08:33:23.519 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Returning from cache: com.sun.xml.messaging.saaj.soap.ver1_1.Message1_1Impl@c9bdd2
2021-09-11 08:33:23.521 [TRACE] [hab.binding.tr064.internal.util.Util] - Returning from cache: org.openhab.binding.tr064.internal.dto.additions.Root@6f424f
2021-09-11 08:33:31.990 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Request: <SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/" SOAP-ENV:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><SOAP-ENV:Header/><SOAP-ENV:Body><u:GetSpecificAssociatedDeviceInfo xmlns:u="urn:dslforum-org:service:WLANConfiguration:2"><NewAssociatedDeviceMACAddress>C4:4F:33:C3:AF:9F</NewAssociatedDeviceMACAddress></u:GetSpecificAssociatedDeviceInfo></SOAP-ENV:Body></SOAP-ENV:Envelope>
2021-09-11 08:33:32.028 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Received response: <?xml version="1.0"?>
2021-09-11 08:33:32.033 [DEBUG] [ng.tr064.internal.soap.SOAPConnector] - Failed to get Tr064ChannelConfig{channelType=macSpeed2, getAction=GetSpecificAssociatedDeviceInfo, dataType='ui2, parameter='C4:4F:33:C3:AF:9F'}: HTTP-Response-Code 500 (Internal Server Error), SOAP-Fault: 714 (NoSuchEntryInArray)
2021-09-11 08:33:32.039 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Request: <SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/" SOAP-ENV:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><SOAP-ENV:Header/><SOAP-ENV:Body><u:GetSpecificAssociatedDeviceInfo xmlns:u="urn:dslforum-org:service:WLANConfiguration:1"><NewAssociatedDeviceMACAddress>C4:4F:33:C3:AF:9F</NewAssociatedDeviceMACAddress></u:GetSpecificAssociatedDeviceInfo></SOAP-ENV:Body></SOAP-ENV:Envelope>
2021-09-11 08:33:32.108 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Received response: <?xml version="1.0"?>
2021-09-11 08:33:32.115 [DEBUG] [ng.tr064.internal.soap.SOAPConnector] - Failed to get Tr064ChannelConfig{channelType=macSpeed1, getAction=GetSpecificAssociatedDeviceInfo, dataType='ui2, parameter='C4:4F:33:C3:AF:9F'}: HTTP-Response-Code 500 (Internal Server Error), SOAP-Fault: 714 (NoSuchEntryInArray)
2021-09-11 08:33:32.122 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Request: <SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/" SOAP-ENV:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><SOAP-ENV:Header/><SOAP-ENV:Body><u:GetInfo xmlns:u="urn:dslforum-org:service:WLANConfiguration:1"/></SOAP-ENV:Body></SOAP-ENV:Envelope>
2021-09-11 08:33:32.278 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Received response: <?xml version="1.0"?>
2021-09-11 08:33:32.288 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Storing in cache: com.sun.xml.messaging.saaj.soap.ver1_1.Message1_1Impl@99d385
2021-09-11 08:33:32.290 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Returning from cache: com.sun.xml.messaging.saaj.soap.ver1_1.Message1_1Impl@99d385
2021-09-11 08:33:32.298 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Request: <SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/" SOAP-ENV:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><SOAP-ENV:Header/><SOAP-ENV:Body><u:GetSpecificHostEntry xmlns:u="urn:dslforum-org:service:Hosts:1"><NewMACAddress>C4:4F:33:C3:AF:9F</NewMACAddress></u:GetSpecificHostEntry></SOAP-ENV:Body></SOAP-ENV:Envelope>
2021-09-11 08:33:33.092 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Received response: <?xml version="1.0"?>
2021-09-11 08:33:33.096 [DEBUG] [ng.tr064.internal.soap.SOAPConnector] - Failed to get Tr064ChannelConfig{channelType=macOnline, getAction=GetSpecificHostEntry, dataType='boolean, parameter='C4:4F:33:C3:AF:9F'}: HTTP-Response-Code 500 (Internal Server Error), SOAP-Fault: 714 (NoSuchEntryInArray)
2021-09-11 08:33:33.100 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Request: <SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/" SOAP-ENV:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><SOAP-ENV:Header/><SOAP-ENV:Body><u:GetInfo xmlns:u="urn:dslforum-org:service:WLANConfiguration:3"/></SOAP-ENV:Body></SOAP-ENV:Envelope>
2021-09-11 08:33:33.253 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Received response: <?xml version="1.0"?>
2021-09-11 08:33:33.260 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Storing in cache: com.sun.xml.messaging.saaj.soap.ver1_1.Message1_1Impl@179cbd7
2021-09-11 08:33:33.261 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Returning from cache: com.sun.xml.messaging.saaj.soap.ver1_1.Message1_1Impl@179cbd7
2021-09-11 08:33:33.266 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Request: <SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/" SOAP-ENV:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><SOAP-ENV:Header/><SOAP-ENV:Body><u:GetInfo xmlns:u="urn:dslforum-org:service:WLANConfiguration:2"/></SOAP-ENV:Body></SOAP-ENV:Envelope>
2021-09-11 08:33:33.432 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Received response: <?xml version="1.0"?>
2021-09-11 08:33:33.439 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Storing in cache: com.sun.xml.messaging.saaj.soap.ver1_1.Message1_1Impl@1672a3c
2021-09-11 08:33:33.441 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Returning from cache: com.sun.xml.messaging.saaj.soap.ver1_1.Message1_1Impl@1672a3c
Log trace:
2021-09-11 04:54:32.222 [TRACE] [hab.binding.tr064.internal.util.Util] - Storing in cache org.openhab.binding.tr064.internal.dto.additions.Root@1a4b2fd
2021-09-11 04:54:32.225 [TRACE] [hab.binding.tr064.internal.util.Util] - Returning from cache: org.openhab.binding.tr064.internal.dto.additions.Root@1a4b2fd
2021-09-11 04:54:32.233 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Request: <SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/" SOAP-ENV:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><SOAP-ENV:Header/><SOAP-ENV:Body><u:GetMessageList xmlns:u="urn:dslforum-org:service:X_AVM-DE_TAM:1"><NewIndex>1</NewIndex></u:GetMessageList></SOAP-ENV:Body></SOAP-ENV:Envelope>
2021-09-11 04:54:32.262 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Received response: <?xml version="1.0"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
<s:Body>
<u:GetMessageListResponse xmlns:u="urn:dslforum-org:service:X_AVM-DE_TAM:1">
<NewURL>https://192.168.178.1:49443/tamcalllist.lua?sid=7d570917b2da11e3&tamindex=1</NewURL>
</u:GetMessageListResponse>
</s:Body>
</s:Envelope>
2021-09-11 04:54:32.270 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Storing in cache: com.sun.xml.messaging.saaj.soap.ver1_1.Message1_1Impl@140724f
2021-09-11 04:54:32.271 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Returning from cache: com.sun.xml.messaging.saaj.soap.ver1_1.Message1_1Impl@140724f
2021-09-11 04:54:32.423 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Request: <SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/" SOAP-ENV:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><SOAP-ENV:Header/><SOAP-ENV:Body><u:GetSpecificHostEntry xmlns:u="urn:dslforum-org:service:Hosts:1"><NewMACAddress>58:B1:0F:FA:71:22</NewMACAddress></u:GetSpecificHostEntry></SOAP-ENV:Body></SOAP-ENV:Envelope>
2021-09-11 04:54:33.278 [INFO ] [064.internal.soap.SOAPValueConverter] - Postprocessor processTamListURL failed: Failed to get TAM list from URL https://192.168.178.1:49443/tamcalllist.lua?sid=7d570917b2da11e3&tamindex=1
2021-09-11 04:54:33.280 [WARN ] [ng.tr064.internal.soap.SOAPConnector] - Failed to get Tr064ChannelConfig{channelType=tamNewMessages, getAction=GetMessageList, dataType='string, parameter='1'}: failed to transform 'NewURL'
2021-09-11 04:54:33.282 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Returning from cache: com.sun.xml.messaging.saaj.soap.ver1_1.Message1_1Impl@17f0d58
2021-09-11 04:54:33.285 [TRACE] [hab.binding.tr064.internal.util.Util] - Returning from cache: org.openhab.binding.tr064.internal.dto.additions.Root@1a4b2fd
2021-09-11 04:54:33.356 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Received response: <?xml version="1.0"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
<s:Body>
<u:GetSpecificHostEntryResponse xmlns:u="urn:dslforum-org:service:Hosts:1">
<NewIPAddress>192.168.178.36</NewIPAddress>
<NewAddressSource>DHCP</NewAddressSource>
<NewLeaseTimeRemaining>0</NewLeaseTimeRemaining>
<NewInterfaceType>802.11</NewInterfaceType>
<NewActive>1</NewActive>
<NewHostName>Galaxy-A8</NewHostName>
</u:GetSpecificHostEntryResponse>
</s:Body>
</s:Envelope>
2021-09-11 04:54:33.366 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Storing in cache: com.sun.xml.messaging.saaj.soap.ver1_1.Message1_1Impl@71c278
2021-09-11 04:54:33.370 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Returning from cache: com.sun.xml.messaging.saaj.soap.ver1_1.Message1_1Impl@71c278
2021-09-11 04:54:33.373 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Request: <SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/" SOAP-ENV:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><SOAP-ENV:Header/><SOAP-ENV:Body><u:GetInfo xmlns:u="urn:dslforum-org:service:X_AVM-DE_TAM:1"><NewIndex>1</NewIndex></u:GetInfo></SOAP-ENV:Body></SOAP-ENV:Envelope>
2021-09-11 04:54:33.439 [TRACE] [ng.tr064.internal.soap.SOAPConnector] - Received response: <?xml version="1.0"?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
<s:Body>
<u:GetInfoResponse xmlns:u="urn:dslforum-org:service:X_AVM-DE_TAM:1">
<NewEnable>1</NewEnable>
<NewName>AB2 Unbekannte Anrufer</NewName>
<NewTAMRunning>1</NewTAMRunning>
<NewStick>2</NewStick>
<NewStatus>32768</NewStatus>
<NewCapacity>2710</NewCapacity>
<NewMode>record_message</NewMode>
<NewRingSeconds>30</NewRingSeconds>
<NewPhoneNumbers></NewPhoneNumbers>
</u:GetInfoResponse>
</s:Body>
</s:Envelope>
2021-09-11 04:54:33.278 [INFO ] [064.internal.soap.SOAPValueConverter] - Postprocessor processTamListURL failed: Failed to get TAM list from URL https://192.168.178.1:49443/tamcalllist.lua?sid=7d570917b2da11e3&tamindex=1
The problem is that the URL for the answering machine call list (which in general looks good) can't be requested from the Fritz!Box. This could be a timeout issue. I'm not sure if you have to enable the access to that list.
In my case (log given above) the user used to access the Fritzbox 7490 has all the rights (except for VPN), i.e. settings, messages, call list, smarthome, NAS, .... So missing rights shouldn't be the problem in my case.
As I said: it could also be a timeout issue. The timeout is hard-coded and can't be changed by the user.
That sounds like it would make sense to either : increase the hardcoded timeout value, or improve the logging, or make the timeout value configurable?
Is there any update on this issue? Still getting the same error message (sometimes ... and sometimes it works)
To get this going i have a updated jar. It has the timeout changed from 1000 to 1500 ms. And also added explicit logging about the time out. The JAR is available here: https://1drv.ms/u/s!AnMcxmvEeupwjpk1SGaHtptxxOD3PQ?e=TKT4Ie
Please report trace logs if the problems is not resolved.
The branch here. https://github.com/lsiepel/openhab-addons/tree/TR064-issues