Suddenly AVM Binding can no longer connect to Fritz!Box

  • Platform information:
    • Hardware: Intel Nuc NUC7CJYH Intel(R) Celeron(R) J4005/8GiB Systemspeicher/500GB Samsung SSD 860
    • OS: Ubuntu 22.04.3 LTS jammy
    • Java Runtime Environment: openjdk version “17.0.9” 2023-10-17 LTS OpenJDK Runtime / Environment Zulu17.46+19-CA (build 17.0.9+8-LTS) / OpenJDK 64-Bit Server VM Zulu17.46+19-CA (build 17.0.9+8-LTS, mixed mode, sharing)
    • openHAB version: openHAB 4.1.0

I started seting up openhab with version 4.0.x (don’t rember x) and installed the AVM binding and some other things. Then I added my han-fun items and everything went well.
Some days ago I updated to OH4.1, that means I run sudo apt-get update && sudo apt-get dist-upgrade and got the new openhab version. (Btw, this is the normal update route?)

Today the AVM binding to my fritz!box stopped connecting :" Status: OFFLINE COMMUNICATION_ERROR FRITZ!Box does not respond.". I tried to disable/enable the thing without luck. The delete the thing and added it again from the inbox. No luck again. Interestingly the han-fun channels do not show up also. The thing code is:

UID: avmfritz:fritzbox:e0bbd4b2d3
label: FRITZ!Box
thingTypeUID: avmfritz:fritzbox
configuration:
  syncTimeout: 2000
  password: <password>
  protocol: http
  asyncTimeout: 10000
  ipAddress: 198.168.178.1
  pollingInterval: 15
  user: <user>
channels:
  - id: incoming_call
    channelTypeUID: avmfritz:incoming_call
    label: Eingehender Anruf
    description: Zeigt Informationen zu anrufender und angerufener Telefonnummer an
      ("%2$s" anrufende Telefonnummer, "%1$s" angerufene Telefonnummer).
    configuration: {}
  - id: outgoing_call
    channelTypeUID: avmfritz:outgoing_call
    label: Ausgehender Anruf
    description: Zeigt Informationen zu angerufener und genutzter Telefonnummer an
      ("%1$s" angerufene Telefonnummer, "%2$s" genutzte Telefonnummer).
    configuration: {}
  - id: active_call
    channelTypeUID: avmfritz:active_call
    label: Aktiver Anruf
    description: Zeigt Informationen zur bestehenden Verbindung an ("%1$s" anrufende
      Telefonnummer, "%2$s" ist leer).
    configuration: {}
  - id: call_state
    channelTypeUID: avmfritz:call_state
    label: Anrufzustand
    description: Zeigt an, ob eine Anrufaktivität stattfindet.
    configuration: {}
  - id: apply_template
    channelTypeUID: avmfritz:apply_template
    label: Vorlage anwenden
    description: Ermöglicht die Anwendung einer vordefinierten Vorlage.
    configuration: {}

events.log:
events.log (26.7 KB)

The question is: “What can I do?”

  • did you try to clean the cache ?
  • you may try to set the binding to debug/trace level to check for more debug information and provide the openhab.log afterwards
  • was there an update on the Fritz!Box ?

That depends. In case you have openhabian-config you can use openhabian-config to do the update but in the backgroup the same / or similar command will be executed.
The way you did it is ok/correct/is the linux way to do the upgrade.

  • did you try to clean the cache ?

No, but did it now → No change

  • you may try to set the binding to debug/trace level to check for more debug information and provide the openhab.log afterwards

Not sure were to change this. I tried "Einstellungen > AVM Fritz!Box Binding > Drop down menu > Trace > Save → the events.log only shows [INFO ] see:

2024-01-05 18:34:47.996 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'avmfritz:fritzbox:192_168_178_1' changed from UNKNOWN to UNINITIALIZED                                 2024-01-05 18:34:48.003 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'avmfritz:fritzbox:192_168_178_1' changed from UNINITIALIZED to UNINITIALIZED (DISABLED)                2024-01-05 18:34:54.819 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'avmfritz:fritzbox:192_168_178_1' changed from UNINITIALIZED (DISABLED) to INITIALIZING                 2024-01-05 18:34:54.823 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'avmfritz:fritzbox:192_168_178_1' changed from INITIALIZING to UNKNOWN 
  • was there an update on the Fritz!Box ?

No, Last update was on 07.09.2023. Version 7.57.

The way you did it is ok/correct/is the linux way to do the upgrade.

Ok. That’s clear then.

I now deleted all items, the Fritz!Box thing, the AVM Binding and tried to re-install. But with no success.

Did you check the /var/log/openhab/openhab.log file. That file should contain the debug/trace information.

Did you check the /var/log/openhab/openhab.log file. That file should contain the debug/trace information.

No. Here are the last line of openhab.log:

2024-01-05 19:53:17.957 [TRACE] [amera.internal.onvif.OnvifConnection] - Sending ONVIF request: PullMessages
2024-01-05 19:53:18.455 [TRACE] [era.internal.handler.IpCameraHandler] - Sending camera: GET: http://192.168.178.44:80/SnapShot_Ch0.jpg
2024-01-05 19:53:18.981 [TRACE] [amera.internal.onvif.OnvifConnection] - ONVIF reply is: <?xml version="1.0" encoding="UTF-8"?><s:Envelope xmlns:s="http://www.w3.org/2003/05/soap-envelope" xmlns:e="http://www.w3.org/2003/05/soap-encoding" xmlns:wsa="http://www.w3.org/2005/08/addressing" xmlns:tns1="http://www.onvif.org/ver10/topics" xmlns:xs="http://www.w3.org/2001/XMLSchema" xmlns:tt="http://www.onvif.org/ver10/schema" xmlns:wsnt="http://docs.oasis-open.org/wsn/b-2" xmlns:wstop="http://docs.oasis-open.org/wsn/t-1" xmlns:tds="http://www.onvif.org/ver10/device/wsdl" xmlns:trt="http://www.onvif.org/ver10/media/wsdl" xmlns:tev="http://www.onvif.org/ver10/events/wsdl" xmlns:tptz="http://www.onvif.org/ver20/ptz/wsdl" xmlns:timg="http://www.onvif.org/ver20/imaging/wsdl" xmlns:tan="http://www.onvif.org/ver20/analytics/wsdl" xmlns:ter="http://www.onvif.org/ver10/error" ><s:Header><wsa:Action>http://www.onvif.org/ver10/events/wsdl/PullPointSubscription/PullMessagesResponse</wsa:Action></s:Header><s:Body><tev:PullMessagesResponse><tev:CurrentTime>2024-01-05T19:53:15Z</tev:CurrentTime><tev:TerminationTime>2024-01-05T19:54:15Z</tev:TerminationTime></tev:PullMessagesResponse></s:Body></s:Envelope>
2024-01-05 19:53:18.982 [TRACE] [amera.internal.onvif.OnvifConnection] - Sending ONVIF request: Renew
2024-01-05 19:53:19.454 [TRACE] [era.internal.handler.IpCameraHandler] - Sending camera: GET: http://192.168.178.44:80/SnapShot_Ch0.jpg
2024-01-05 19:53:20.010 [TRACE] [amera.internal.onvif.OnvifConnection] - ONVIF reply is: <?xml version="1.0" encoding="UTF-8"?><s:Envelope xmlns:s="http://www.w3.org/2003/05/soap-envelope" xmlns:e="http://www.w3.org/2003/05/soap-encoding" xmlns:wsa="http://www.w3.org/2005/08/addressing" xmlns:tns1="http://www.onvif.org/ver10/topics" xmlns:xs="http://www.w3.org/2001/XMLSchema" xmlns:tt="http://www.onvif.org/ver10/schema" xmlns:wsnt="http://docs.oasis-open.org/wsn/b-2" xmlns:wstop="http://docs.oasis-open.org/wsn/t-1" xmlns:tds="http://www.onvif.org/ver10/device/wsdl" xmlns:trt="http://www.onvif.org/ver10/media/wsdl" xmlns:tev="http://www.onvif.org/ver10/events/wsdl" xmlns:tptz="http://www.onvif.org/ver20/ptz/wsdl" xmlns:timg="http://www.onvif.org/ver20/imaging/wsdl" xmlns:tan="http://www.onvif.org/ver20/analytics/wsdl" xmlns:ter="http://www.onvif.org/ver10/error" ><s:Header><wsa:Action>http://www.onvif.org/ver10/events/wsdl/PullPointSubscription/PullMessagesResponse</wsa:Action></s:Header><s:Body><tev:PullMessagesResponse><tev:CurrentTime>2024-01-05T19:53:16Z</tev:CurrentTime><tev:TerminationTime>2024-01-05T19:54:16Z</tev:TerminationTime></tev:PullMessagesResponse></s:Body></s:Envelope>
2024-01-05 19:53:20.011 [TRACE] [amera.internal.onvif.OnvifConnection] - Sending ONVIF request: Renew
2024-01-05 19:53:20.056 [TRACE] [amera.internal.onvif.OnvifConnection] - ONVIF reply is: <?xml version="1.0" encoding="UTF-8"?><s:Envelope xmlns:s="http://www.w3.org/2003/05/soap-envelope" xmlns:e="http://www.w3.org/2003/05/soap-encoding" xmlns:wsa="http://www.w3.org/2005/08/addressing" xmlns:tns1="http://www.onvif.org/ver10/topics" xmlns:xs="http://www.w3.org/2001/XMLSchema" xmlns:tt="http://www.onvif.org/ver10/schema" xmlns:wsnt="http://docs.oasis-open.org/wsn/b-2" xmlns:wstop="http://docs.oasis-open.org/wsn/t-1" xmlns:tds="http://www.onvif.org/ver10/device/wsdl" xmlns:trt="http://www.onvif.org/ver10/media/wsdl" xmlns:tev="http://www.onvif.org/ver10/events/wsdl" xmlns:tptz="http://www.onvif.org/ver20/ptz/wsdl" xmlns:timg="http://www.onvif.org/ver20/imaging/wsdl" xmlns:tan="http://www.onvif.org/ver20/analytics/wsdl" xmlns:ter="http://www.onvif.org/ver10/error" ><s:Header><wsa:Action>http://docs.oasis-open.org/wsn/bw-2/SubscriptionManager/RenewResponse</wsa:Action></s:Header><s:Body><wsnt:RenewResponse><wsnt:TerminationTime>2024-01-05T19:54:17Z</wsnt:TerminationTime><wsnt:CurrentTime>2024-01-05T19:53:17Z</wsnt:CurrentTime></wsnt:RenewResponse></s:Body></s:Envelope>
2024-01-05 19:53:20.057 [TRACE] [amera.internal.onvif.OnvifConnection] - Sending ONVIF request: PullMessages
2024-01-05 19:53:20.077 [TRACE] [amera.internal.onvif.OnvifConnection] - ONVIF reply is: <?xml version="1.0" encoding="UTF-8"?><s:Envelope xmlns:s="http://www.w3.org/2003/05/soap-envelope" xmlns:e="http://www.w3.org/2003/05/soap-encoding" xmlns:wsa="http://www.w3.org/2005/08/addressing" xmlns:tns1="http://www.onvif.org/ver10/topics" xmlns:xs="http://www.w3.org/2001/XMLSchema" xmlns:tt="http://www.onvif.org/ver10/schema" xmlns:wsnt="http://docs.oasis-open.org/wsn/b-2" xmlns:wstop="http://docs.oasis-open.org/wsn/t-1" xmlns:tds="http://www.onvif.org/ver10/device/wsdl" xmlns:trt="http://www.onvif.org/ver10/media/wsdl" xmlns:tev="http://www.onvif.org/ver10/events/wsdl" xmlns:tptz="http://www.onvif.org/ver20/ptz/wsdl" xmlns:timg="http://www.onvif.org/ver20/imaging/wsdl" xmlns:tan="http://www.onvif.org/ver20/analytics/wsdl" xmlns:ter="http://www.onvif.org/ver10/error" ><s:Header><wsa:Action>http://docs.oasis-open.org/wsn/bw-2/SubscriptionManager/RenewResponse</wsa:Action></s:Header><s:Body><wsnt:RenewResponse><wsnt:TerminationTime>2024-01-05T19:54:17Z</wsnt:TerminationTime><wsnt:CurrentTime>2024-01-05T19:53:17Z</wsnt:CurrentTime></wsnt:RenewResponse></s:Body></s:Envelope>
2024-01-05 19:53:20.078 [TRACE] [amera.internal.onvif.OnvifConnection] - Sending ONVIF request: PullMessages
2024-01-05 19:53:20.454 [TRACE] [era.internal.handler.IpCameraHandler] - Sending camera: GET: http://192.168.178.44:80/SnapShot_Ch0.jpg
2024-01-05 19:53:21.101 [TRACE] [amera.internal.onvif.OnvifConnection] - ONVIF reply is: <?xml version="1.0" encoding="UTF-8"?><s:Envelope xmlns:s="http://www.w3.org/2003/05/soap-envelope" xmlns:e="http://www.w3.org/2003/05/soap-encoding" xmlns:wsa="http://www.w3.org/2005/08/addressing" xmlns:tns1="http://www.onvif.org/ver10/topics" xmlns:xs="http://www.w3.org/2001/XMLSchema" xmlns:tt="http://www.onvif.org/ver10/schema" xmlns:wsnt="http://docs.oasis-open.org/wsn/b-2" xmlns:wstop="http://docs.oasis-open.org/wsn/t-1" xmlns:tds="http://www.onvif.org/ver10/device/wsdl" xmlns:trt="http://www.onvif.org/ver10/media/wsdl" xmlns:tev="http://www.onvif.org/ver10/events/wsdl" xmlns:tptz="http://www.onvif.org/ver20/ptz/wsdl" xmlns:timg="http://www.onvif.org/ver20/imaging/wsdl" xmlns:tan="http://www.onvif.org/ver20/analytics/wsdl" xmlns:ter="http://www.onvif.org/ver10/error" ><s:Header><wsa:Action>http://www.onvif.org/ver10/events/wsdl/PullPointSubscription/PullMessagesResponse</wsa:Action></s:Header><s:Body><tev:PullMessagesResponse><tev:CurrentTime>2024-01-05T19:53:17Z</tev:CurrentTime><tev:TerminationTime>2024-01-05T19:54:17Z</tev:TerminationTime></tev:PullMessagesResponse></s:Body></s:Envelope>
2024-01-05 19:53:21.102 [TRACE] [amera.internal.onvif.OnvifConnection] - Sending ONVIF request: Renew
2024-01-05 19:56:53.257 [DEBUG] [ery.AVMFritzUpnpDiscoveryParticipant] - discovered on /192.168.178.38
2024-01-05 19:56:53.257 [DEBUG] [ery.AVMFritzUpnpDiscoveryParticipant] - discovered: AVM Berlin FRITZ!Box 7490 7490avm (FRITZ!Box 7490) at 192.168.178.1
2024-01-05 19:56:53.259 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'avmfritz:fritzbox:192_168_178_1' to inbox.
2024-01-05 19:58:48.782 [WARN ] [core.thing.internal.ThingManagerImpl] - Initializing handler for thing 'avmfritz:fritzbox:192_168_178_1' takes more than 5000ms.
2024-01-05 20:00:08.519 [WARN ] [core.thing.internal.ThingManagerImpl] - Initializing handler for thing 'avmfritz:fritzbox:192_168_178_1' takes more than 5000ms.

And the last lines of events.log:

2024-01-05 19:56:44.460 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'avmfritz:fritzbox:192_168_178_1' changed from UNKNOWN to REMOVING
2024-01-05 19:56:44.462 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'avmfritz:fritzbox:192_168_178_1' changed from REMOVING to REMOVED
2024-01-05 19:56:44.464 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'avmfritz:fritzbox:192_168_178_1' changed from REMOVED to UNINITIALIZED
2024-01-05 19:56:44.470 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'avmfritz:fritzbox:192_168_178_1' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2024-01-05 19:56:45.109 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Sensor_Schildkrote__Temperature' changed from 7.38 °C to 7.64 °C
2024-01-05 19:56:53.259 [INFO ] [openhab.event.InboxAddedEvent       ] - Discovery Result with UID 'avmfritz:fritzbox:192_168_178_1' has been added.
2024-01-05 19:57:10.656 [INFO ] [openhab.event.InboxRemovedEvent     ] - Discovery Result with UID 'avmfritz:fritzbox:192_168_178_1' has been removed.
2024-01-05 19:57:10.659 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'avmfritz:fritzbox:192_168_178_1' changed from UNINITIALIZED to INITIALIZING
2024-01-05 19:57:10.663 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'avmfritz:fritzbox:192_168_178_1' changed from INITIALIZING to UNKNOWN
2024-01-05 19:57:10.663 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'avmfritz:fritzbox:192_168_178_1' changed from UNKNOWN to OFFLINE (CONFIGURATION_ERROR): The 'password' parameter must be configured to use the AHA features.
2024-01-05 19:57:21.254 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'avmfritz:fritzbox:192_168_178_1' changed from OFFLINE (CONFIGURATION_ERROR): The 'password' parameter must be configured to use the AHA features. to UNKNOWN
2024-01-05 19:58:40.260 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'avmfritz:fritzbox:192_168_178_1' changed from UNKNOWN to UNINITIALIZED
2024-01-05 19:58:40.268 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'avmfritz:fritzbox:192_168_178_1' changed from UNINITIALIZED to UNINITIALIZED (DISABLED)
2024-01-05 19:58:43.774 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'avmfritz:fritzbox:192_168_178_1' changed from UNINITIALIZED (DISABLED) to INITIALIZING
2024-01-05 19:58:43.780 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'avmfritz:fritzbox:192_168_178_1' changed from INITIALIZING to UNKNOWN
2024-01-05 19:59:44.400 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'ipcamera:onvif:19216817844' changed from ONLINE to UNINITIALIZED
2024-01-05 19:59:44.415 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'ipcamera:onvif:19216817844' changed from UNINITIALIZED to UNINITIALIZED (DISABLED)
2024-01-05 19:59:53.227 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'avmfritz:fritzbox:192_168_178_1' changed from UNKNOWN to UNINITIALIZED
2024-01-05 19:59:53.236 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'avmfritz:fritzbox:192_168_178_1' changed from UNINITIALIZED to UNINITIALIZED (DISABLED)
2024-01-05 20:00:03.510 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'avmfritz:fritzbox:192_168_178_1' changed from UNINITIALIZED (DISABLED) to INITIALIZING
2024-01-05 20:00:03.516 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'avmfritz:fritzbox:192_168_178_1' changed from INITIALIZING to UNKNOWN
2024-01-05 20:01:43.866 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Sensor_Schildkrote__Temperature' changed from 7.64 °C to 7.38 °C

Unfortunately, that doesn’t help me.

Put the trace level back to info level if your not actively trying to fault find an issue

Put the trace level back to info level if your not actively trying to fault find an issue

You are right, I’m trying to get my camera working with the ipcamera binding. I didn’t understand everything with it but I’m getting closer.

Set the level back. Nevertheless, the previously working AVM binding didn’t come back.

I now uninstalled openhab (2 time) completly und reinstalled it. Fritz!Box shows up again. Let’s see how long it will work.