Unifi binding: unhandled exception java.io.EOFException

I have this behaviour of the Unifi binding in the log with DEBUG turned on. My controller is in the cloud in a Google VM. I have about 120 of these on a daily basis.

What is going on? How to mitigate or what do I need to change?

2019-03-25 21:04:47.877 [DEBUG] [.handler.UniFiControllerThingHandler] - Refreshing the UniFi Controller unifi:controller:c1255fb8

2019-03-25 21:04:47.895 [WARN ] [.handler.UniFiControllerThingHandler] - Unhandled exception while refreshing the UniFi Controller unifi:controller:c1255fb8 - java.io.EOFException: HttpConnectionOverHTTP@8aee21::DecryptedEndPoint@163ac2c{/35.195.220.51:443<->/192.168.1.13:32996,OPEN,fill=-,flush=-,to=60115/0}

2019-03-25 21:04:47.906 [hingStatusInfoChangedEvent] - 'unifi:controller:c1255fb8' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): java.io.EOFException: HttpConnectionOverHTTP@8aee21::DecryptedEndPoint@163ac2c{/35.195.220.51:443<->/192.168.1.13:32996,OPEN,fill=-,flush=-,to=60115/0}

2019-03-25 21:04:47.918 [hingStatusInfoChangedEvent] - 'unifi:wirelessClient:53de8e86' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)

2019-03-25 21:04:47.927 [hingStatusInfoChangedEvent] - 'unifi:wirelessClient:7d47ee6e' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)

2019-03-25 21:04:47.933 [hingStatusInfoChangedEvent] - 'unifi:wirelessClient:1118885e' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)

2019-03-25 21:04:47.937 [hingStatusInfoChangedEvent] - 'unifi:wirelessClient:f8d1c94d' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)

2019-03-25 21:04:56.392 [vent.ItemStateChangedEvent] - dsmr_device_v5_default_p1_timestamp changed from 2019-03-25T21:03:59.000+0100 to 2019-03-25T21:04:59.000+0100

2019-03-25 21:05:29.505 [vent.ItemStateChangedEvent] - dsmr_electricity_v5_0_0_emeter_delivery_tariff2 changed from 3042.981 kWh to 3042.995 kWh

2019-03-25 21:05:29.514 [vent.ItemStateChangedEvent] - dsmr_electricity_v5_0_0_emeter_actual_delivery changed from 0.798 kW to 0.876 kW

2019-03-25 21:05:38.829 [vent.ItemStateChangedEvent] - ntp_ntp_local_dateTime changed from 2019-03-25T21:04:38.782+0100 to 2019-03-25T21:05:38.786+0100

2019-03-25 21:05:38.834 [vent.ItemStateChangedEvent] - ntp_ntp_local_string changed from 2019-03-25 21:04:38 CET to 2019-03-25 21:05:38 CET

2019-03-25 21:05:47.923 [DEBUG] [.handler.UniFiControllerThingHandler] - Refreshing the UniFi Controller unifi:controller:c1255fb8

2019-03-25 21:05:48.138 [DEBUG] [i.internal.api.model.UniFiController] - Found 2 UniFi Site(s): 

2019-03-25 21:05:48.201 [DEBUG] [i.internal.api.model.UniFiController] - Found 2 UniFi Device(s): 

2019-03-25 21:05:48.347 [DEBUG] [i.internal.api.model.UniFiController] - Found 6 UniFi Device(s): 

2019-03-25 21:05:48.383 [DEBUG] [i.internal.api.model.UniFiController] - Found 3 UniFi Client(s): 

2019-03-25 21:05:48.462 [DEBUG] [i.internal.api.model.UniFiController] - Found 19 UniFi Client(s): 

2019-03-25 21:05:48.501 [DEBUG] [i.internal.api.model.UniFiController] - Found 3 UniFi Insights(s): 

2019-03-25 21:05:48.561 [DEBUG] [i.internal.api.model.UniFiController] - Found 21 UniFi Insights(s): 

 - UniFiClient{mac: 'e2:21:04:40:cc:44', ip: 'null', hostname: 'null', alias: 'null', wired: true, blocked: false, device: null}

2019-03-25 21:05:48.576 [hingStatusInfoChangedEvent] - 'unifi:controller:c1255fb8' changed from OFFLINE (COMMUNICATION_ERROR): java.io.EOFException: HttpConnectionOverHTTP@8aee21::DecryptedEndPoint@163ac2c{/35.195.220.51:443<->/192.168.1.13:32996,OPEN,fill=-,flush=-,to=60115/0} to ONLINE

2019-03-25 21:05:48.584 [hingStatusInfoChangedEvent] - 'unifi:wirelessClient:53de8e86' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE

2019-03-25 21:05:48.593 [hingStatusInfoChangedEvent] - 'unifi:wirelessClient:f8d1c94d' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE

2019-03-25 21:05:48.606 [hingStatusInfoChangedEvent] - 'unifi:wirelessClient:7d47ee6e' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE

2019-03-25 21:05:48.611 [hingStatusInfoChangedEvent] - 'unifi:wirelessClient:1118885e' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE


I still have these errors. Is there no one that encounters the same or has a solution?

I’m getting these errors every 3-4 minutes. It’s been going on for several months. I haven’t found a solution yet. I recently upgraded to v6.5.55 of the UniFi Network Controller but it didn’t help.

Yesterday I increased the refresh interval to 70 seconds (from the default of 60) and haven’t had an error since.

There is a beta version of the UniFi binding in the Marketplace. Can you test if that has also the same issue? Maybe I can improve the binding to handle this case?

Done, I will let you know how it goes.

That didn’t take long…the errors are back…

2022-01-21 18:15:29.840 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'unifi:controller:82079779' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): java.io.EOFException: HttpConnectionOverHTTP@4fcc7cdb::DecryptedEndPoint@43da02bd{l=/192.168.7.2:55384,r=/192.168.7.11:8443,OPEN,fill=-,flush=-,to=60083/0}

2022-01-21 18:15:29.841 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'unifi:wirelessClient:56f83da7' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)

2022-01-21 18:15:29.842 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'unifi:wirelessClient:43a7d0e7' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)

2022-01-21 18:16:30.164 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'unifi:controller:82079779' changed from OFFLINE (COMMUNICATION_ERROR): java.io.EOFException: HttpConnectionOverHTTP@4fcc7cdb::DecryptedEndPoint@43da02bd{l=/192.168.7.2:55384,r=/192.168.7.11:8443,OPEN,fill=-,flush=-,to=60083/0} to ONLINE

2022-01-21 18:16:30.165 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'unifi:wirelessClient:56f83da7' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE

2022-01-21 18:16:30.165 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'unifi:wirelessClient:43a7d0e7' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE

==> /var/log/openhab/openhab.log <==

2022-01-21 18:16:51.344 [INFO ] [control.internal.WebSocketConnection] - Web Socket close 1005. Reason: null

==> /var/log/openhab/openhab.log <==

2022-01-21 18:18:15.162 [INFO ] [control.internal.WebSocketConnection] - Web Socket close 1005. Reason: null

==> /var/log/openhab/events.log <==

2022-01-21 18:18:30.572 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'unifi:controller:82079779' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): java.io.EOFException: HttpConnectionOverHTTP@1f7f17e9::DecryptedEndPoint@11b8ea76{l=/192.168.7.2:56010,r=/192.168.7.11:8443,OPEN,fill=-,flush=-,to=60085/0}

2022-01-21 18:18:30.572 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'unifi:wirelessClient:56f83da7' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)

2022-01-21 18:18:30.573 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'unifi:wirelessClient:43a7d0e7' changed from ONLINE to OFFLINE (BRIDGE_OFFLINE)

2022-01-21 18:19:30.893 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'unifi:controller:82079779' changed from OFFLINE (COMMUNICATION_ERROR): java.io.EOFException: HttpConnectionOverHTTP@1f7f17e9::DecryptedEndPoint@11b8ea76{l=/192.168.7.2:56010,r=/192.168.7.11:8443,OPEN,fill=-,flush=-,to=60085/0} to ONLINE

2022-01-21 18:19:30.894 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'unifi:wirelessClient:56f83da7' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE

2022-01-21 18:19:30.894 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'unifi:wirelessClient:43a7d0e7' changed from OFFLINE (BRIDGE_OFFLINE) to ONLINE