Tr064 binding with OH3.0.1 not working

I just tried to configure TR064 binding under OH3.

With klick on add thing in inbox and choosing tr064 I got 3 new things shown for manual adding (Auto-Discovery don’t work for me):

  • tr064:fritzbox (Bridge)
  • tr064:generic (Bridge)
  • tr064:subdevice

After choosing tr064 Fritzbox I configured host, user and password and add the thing.

The thing is created by openhab, but directly got stuck with the following error:

COMMUNICATION_ERROR
java.util.concurrent.ExecutionException: java.net.UnknownHostException: fritz.box: Name or service not known

Because on same machine openhab is running (Dell Server with Ubuntu 20.04.1 LTS) there is a fully working bind-server too, fritz.box not resolveable.

So in my opinion problem is, the TR064 allways using fritz.box as domain instead of configured IP :frowning:

Can anyone confirm this?

Edit further informations:
After create entry in /etc/hosts with fritz.box and IP, I can see openhab logging in to fritzbox in Fritzboxs Logs. So really looks like binding always uses domain instead of IP.

Now I got error:

COMMUNICATION_ERROR
java.util.concurrent.TimeoutException: Total timeout 2000 ms elapsed

UPDATE:

I turned on DEBUG in karaf.

Now every Minute I go a debug entry in log:

2021-03-16 20:43:32.233 [DEBUG] [ding.tr064.internal.Tr064RootHandler] - endpointBaseURL is now 'https://192.168.10.XXX:49443'

But that’s all. Bridge still showing COMMUNICATION_ERROR
java.util.concurrent.TimeoutException: Total timeout 2000 ms elapsed

In logs of Fritzbox

Anmeldung einer App des Benutzers BENUTZER von IP Adresse OH-SERVER-IP

Firewall looks fine, no drops found. And didn’t changed since tr064 worked under OH 2.51

Here the logs from Support Information of Fritzbox, returning every 1 minute

2021-03-16 21:11:47.609 - urn:DeviceInfo-com:serviceId:DeviceInfo1:GetSecurityPort () action_sec_type 000, full_access 1
2021-03-16 21:11:47.609 - urn:DeviceInfo-com:serviceId:DeviceInfo1:GetSecurityPort () requested none for none, sid:0000000000000000
2021-03-16 21:11:47.609 - granted readwrite for , errorCode=0
2021-03-16 21:11:47.625 - upnp:handle_invoke_action: call check_async_auth
2021-03-16 21:11:47.625 - check_async_auth: check_async_auth failed: Digest: no HTTP-Auth from client sent: Auth requesting...
2021-03-16 21:11:47.627 - soap:check_async_auth failed with -1 (Action 'GetInfo' from 192.168.10.201:20704)
2021-03-16 21:11:47.678 - upnp:handle_invoke_action: call check_async_auth
2021-03-16 21:11:47.679 - check_async_auth: call auth_callback: Digest, user=USERNAME, realm=HTTPS Access, client='192.168.10.XXX:20704'
2021-03-16 21:11:47.686 - check_async_auth: auth_callback called, wait for completion...
2021-03-16 21:11:47.691 - authenticate_user_or_app: auth with username USERNAME against matching account USERNAME ok (password ok)
2021-03-16 21:11:47.691 - auth_callback_for_tr064_synchron: auth (tr064) from homenetwork with account USERNAME -> sid=91ab6e05bbXXXXXX
2021-03-16 21:11:47.691 - auth_callback_for_tr064_synchron: result is sid 91ab6e05bbXXXXXX
2021-03-16 21:11:47.692 - upnp:auth_async_soap_callback: sid=91ab6e05bbXXXXXX, sid_lifetime=0, auth_name='USERNAME'
2021-03-16 21:11:47.695 - urn:DeviceInfo-com:serviceId:DeviceInfo1:GetInfo () action_sec_type 011, full_access 0
2021-03-16 21:11:47.695 - urn:DeviceInfo-com:serviceId:DeviceInfo1:GetInfo () requested readonly for config, sid:91ab6e05bbXXXXXX
192.168.10.201 - - [16/Mar/2021:21:11:46 +0100] "GET /tr64desc.xml HTTP/1.1" 200 13327 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:46 +0100] "GET /deviceinfoSCPD.xml HTTP/1.1" 200 4007 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:46 +0100] "GET /deviceconfigSCPD.xml HTTP/1.1" 200 3116 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:46 +0100] "GET /layer3forwardingSCPD.xml HTTP/1.1" 200 7947 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:46 +0100] "GET /lanconfigsecuritySCPD.xml HTTP/1.1" 200 3283 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:46 +0100] "GET /mgmsrvSCPD.xml HTTP/1.1" 200 6016 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:46 +0100] "GET /timeSCPD.xml HTTP/1.1" 200 2863 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:46 +0100] "GET /userifSCPD.xml HTTP/1.1" 200 8996 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:46 +0100] "GET /x_storageSCPD.xml HTTP/1.1" 200 4580 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:46 +0100] "GET /x_webdavSCPD.xml HTTP/1.1" 200 2047 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:46 +0100] "GET /x_upnpSCPD.xml HTTP/1.1" 200 1150 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:46 +0100] "GET /x_speedtestSCPD.xml HTTP/1.1" 200 3176 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:46 +0100] "GET /x_remoteSCPD.xml HTTP/1.1" 200 6030 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:46 +0100] "GET /x_myfritzSCPD.xml HTTP/1.1" 200 6823 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:46 +0100] "GET /x_voipSCPD.xml HTTP/1.1" 200 34672 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:47 +0100] "GET /x_contactSCPD.xml HTTP/1.1" 200 18618 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:47 +0100] "GET /x_dectSCPD.xml HTTP/1.1" 200 4101 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:47 +0100] "GET /x_tamSCPD.xml HTTP/1.1" 200 5229 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:47 +0100] "GET /x_appsetupSCPD.xml HTTP/1.1" 200 21801 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:47 +0100] "GET /x_homeautoSCPD.xml HTTP/1.1" 200 16925 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:47 +0100] "GET /x_homeplugSCPD.xml HTTP/1.1" 200 3479 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:47 +0100] "GET /x_filelinksSCPD.xml HTTP/1.1" 200 6210 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:47 +0100] "GET /x_authSCPD.xml HTTP/1.1" 200 1805 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:47 +0100] "GET /x_hostfilterSCPD.xml HTTP/1.1" 200 2463 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:47 +0100] "GET /wlanconfigSCPD.xml HTTP/1.1" 200 29498 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:47 +0100] "GET /wlanconfigSCPD.xml HTTP/1.1" 200 29498 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:47 +0100] "GET /wlanconfigSCPD.xml HTTP/1.1" 200 29498 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:47 +0100] "GET /hostsSCPD.xml HTTP/1.1" 200 9555 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:47 +0100] "GET /ethifconfigSCPD.xml HTTP/1.1" 200 2848 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:47 +0100] "GET /lanhostconfigmgmSCPD.xml HTTP/1.1" 200 6255 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:47 +0100] "GET /wancommonifconfigSCPD.xml HTTP/1.1" 200 6568 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:47 +0100] "GET /wandslifconfigSCPD.xml HTTP/1.1" 200 15806 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:47 +0100] "GET /wandsllinkconfigSCPD.xml HTTP/1.1" 200 6110 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:47 +0100] "GET /wanethlinkconfigSCPD.xml HTTP/1.1" 200 548 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:47 +0100] "GET /wanpppconnSCPD.xml HTTP/1.1" 200 21580 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:47 +0100] "GET /wanipconnSCPD.xml HTTP/1.1" 200 15086 - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:47 +0100] "POST /upnp/control/deviceinfo HTTP/1.1" 200 - - "Jetty/9.4.20.v20190813"
192.168.10.201 - - [16/Mar/2021:21:11:47 +0100] "POST /upnp/control/deviceinfo HTTP/1.1" 401 - - "Jetty/9.4.20.v20190813"

After a lot of research, I found the solution:

Update on openhab 3.1.0 M2 solves this issue.