Connection lost after 20min. with latest UDM PRO Protect version 2.10.11

marioschouten commented 7 months ago

Hi,

First of all, thanks for this binding, it’s a great asset to the OH system. I use it for gate control to see who is there, one UI for the whole system and switching the lights around the house for motion at night so my wife can see where the cats go beside the security benefits :-).

Issue: Since latest UDM PRO upgrade having issues with motion detection not working and motion picture updates stop updating. The things go offline and the log shows:

ERROR] [otect.internal.model.UniFiProtectNvr] - Failed to updated Cameras since we can't seem to login status: TOKEN_MISSING

I've upgrade to OH 4.1.0 but did not help. On 4.1.0 I've removed the binding, stopped OH, cleared the cache and restarted but did not that also did not solve the issue. It works fine for an hour and then starts trowing the TOKEN_MISSING errors.

When I just hit save on the offline thing config it stays offline with the folowing error:

2023-12-27 11:29:27.386 [INFO ] [otect.internal.model.UniFiProtectNvr] - Initializing the binding, with config: UniFiProtectNvrThingConfig [host=unifi.xxxxxxxxx.com, username=openhab,  password=*************, refresh=10, eventsTimePeriodLength=60, thumbnailWidth=640.0, imageFolder=/var/lib/openhab/tmp, g4SnapshotWidth=3840, g4SnapshotHeight=2160, defaultSnapshotWidth=1920, defaultSnapshotHeight=1080, watchDog=true, g4SnapshotWidthAsString=3840, g4SnapshotHeightAsString=2160, defaultSnapshotWidthAsString=1920, defaultSnapshotHeightAsString=1080]
2023-12-27 11:29:27.855 [WARN ] [org.eclipse.jetty.io.ManagedSelector] - java.lang.IllegalStateException: !STARTED: SslContextFactory@6d18ee11[provider=null,keyStore=null,trustStore=null]

When I pause/start it comes back online.

2023-12-27 11:30:10.855 [INFO ] [otect.internal.model.UniFiProtectNvr] - Initializing the binding, with config: UniFiProtectNvrThingConfig [host=unifi.xxxxxxxxxx.com, username=openhab,  password=*************, refresh=10, eventsTimePeriodLength=60, thumbnailWidth=640.0, imageFolder=/var/lib/openhab/tmp, g4SnapshotWidth=3840, g4SnapshotHeight=2160, defaultSnapshotWidth=1920, defaultSnapshotHeight=1080, watchDog=true, g4SnapshotWidthAsString=3840, g4SnapshotHeightAsString=2160, defaultSnapshotWidthAsString=1920, defaultSnapshotHeightAsString=1080]

Then bit later the watchdog kicks eventhough the thing shows online at this stage:

2023-12-27 11:37:21.030 [INFO ] [ernal.event.UniFiProtectEventManager] - Watch dog Detected no events recieved, reinitializing!
2023-12-27 11:37:31.030 [INFO ] [ernal.event.UniFiProtectEventManager] - Socket failed, reinitializing!

Other observations that may or not be related are that all Cameras go offline regularly on a whole hour, for example 09:00 or 11:00, looks like some process is running somewhere 3-4 times a day. Also noticed the Unifi Network binding is showing more offline events (average 20 per day), did not see this behaviour before the latest UDM PRO update.

Current versions are: OH 4.1.0 with java 17.0.9 running on Proxmox VM. UDM PRO is running v3.2.7 OS and 2.10.11 for Protect.

Please let me know if I can help with any specific logging, just talk me thought the procedure.

Thanks and best regards,

Mario

dschoepel commented 6 months ago

I just had the same issue. If you configure the binding with a user id/password that requires Multifactor Authentication you will get this error. I needed to create a local account on my UNVR to be used by the binding to connect to it. See this conversation... Unifi Protect Binding in OH Marketplace Community.

Hope that helps...

marioschouten commented 6 months ago

My id/password has been local for over 2 years, only change was the latest Unifi update. To be 100% sure I made new id/password, did notice the password min. lenght is now 12 characters so had to make a longer one. Restarted the binding but no progress. Log messages are:

2024-01-03 10:27:32.286 [INFO ] [otect.internal.model.UniFiProtectNvr] - Initializing the binding, with config: UniFiProtectNvrThingConfig [host=unifi.xxxxxxxx.com, username=OH-Protect,  password=*************, refresh=10, eventsTimePeriodLength=60, thumbnailWidth=640.0, imageFolder=/var/lib/openhab/tmp, g4SnapshotWidth=3840, g4SnapshotHeight=2160, defaultSnapshotWidth=1920, defaultSnapshotHeight=1080, watchDog=true, g4SnapshotWidthAsString=3840, g4SnapshotHeightAsString=2160, defaultSnapshotWidthAsString=1920, defaultSnapshotHeightAsString=1080]

And a bit later:

2024-01-03 10:45:57.570 [INFO ] [ernal.event.UniFiProtectEventManager] - Watch dog Detected no events recieved, reinitializing!
2024-01-03 10:46:07.570 [INFO ] [ernal.event.UniFiProtectEventManager] - Socket failed, reinitializing!

Motion events pictures are not updated. Then proceedeed to clear tmp & cache, after restart pictures are updating again for approx. 1 hr. at which time I have the following log (No UnifiProtect messages in the info log) this is what my watchdog rules produce:

2024-01-03 11:47:40.905 [INFO ] [g.openhab.core.model.script.Watchdog] - THING Alarm   - Thing:UniFi Protect G3 Camera 1 - Garage walkway state: OFFLINE , item:WDGLAN1Camera01HW counter: 1 new count: 1 dropped OFFLINE and counted
2024-01-03 11:47:41.407 [INFO ] [g.openhab.core.model.script.Watchdog] - THING Alarm   - Thing:UniFi Protect G3 Camera 2 - Driveway state: OFFLINE , item:WDGLAN1Camera02HW counter: 1 new count: 2 dropped OFFLINE and counted
2024-01-03 11:47:41.908 [INFO ] [g.openhab.core.model.script.Watchdog] - THING Alarm   - Thing:UniFi Protect G3 Camera 3 - Pool state: OFFLINE , item:WDGLAN1Camera03HW counter: 1 new count: 3 dropped OFFLINE and counted
2024-01-03 11:47:42.410 [INFO ] [g.openhab.core.model.script.Watchdog] - THING Alarm   - Thing:UniFi Protect G3 Camera 4 - Terrace state: OFFLINE , item:WDGLAN1Camera04HW counter: 1 new count: 4 dropped OFFLINE and counted
2024-01-03 11:47:42.911 [INFO ] [g.openhab.core.model.script.Watchdog] - THING Alarm   - Thing:UniFi Protect G3 Camera 5 - Left walkway state: OFFLINE , item:WDGLAN1Camera05HW counter: 1 new count: 5 dropped OFFLINE and counted
2024-01-03 11:47:43.412 [INFO ] [g.openhab.core.model.script.Watchdog] - THING Alarm   - Thing:UniFi Protect G3 Camera 6 - Front state: OFFLINE , item:WDGLAN1Camera06HW counter: 1 new count: 6 dropped OFFLINE and counted
2024-01-03 11:47:43.913 [INFO ] [g.openhab.core.model.script.Watchdog] - THING Alarm   - Thing:UniFi Protect G3 Camera 7 - Garage state: OFFLINE , item:WDGLAN1Camera07HW counter: 1 new count: 7 dropped OFFLINE and counted
2024-01-03 11:47:44.416 [INFO ] [g.openhab.core.model.script.Watchdog] - THING Alarm   - Thing:UniFi Protect NVR state: OFFLINE , item:WDGLAN1ProtecServ counter: 1 new count: 8 dropped OFFLINE and counted
2024-01-03 11:47:44.418 [WARN ] [g.openhab.core.model.script.Watchdog] - THING Alarm   - OpenHAB Watchdog alert counter updated from: 1 to: 9
2024-01-03 11:47:50.405 [INFO ] [g.openhab.core.model.script.Watchdog] - THING Alarm   - Thing:UniFi Protect G3 Camera 1 - Garage walkway state: ONLINE , item:WDGLAN1Camera01HW counter: 2 new count: -1 returned ONLINE and counted
2024-01-03 11:47:50.406 [INFO ] [g.openhab.core.model.script.Watchdog] - THING Alarm   - Thing:UniFi Protect G3 Camera 2 - Driveway state: ONLINE , item:WDGLAN1Camera02HW counter: 2 new count: -2 returned ONLINE and counted
2024-01-03 11:47:50.407 [INFO ] [g.openhab.core.model.script.Watchdog] - THING Alarm   - Thing:UniFi Protect G3 Camera 3 - Pool state: ONLINE , item:WDGLAN1Camera03HW counter: 2 new count: -3 returned ONLINE and counted
2024-01-03 11:47:50.408 [INFO ] [g.openhab.core.model.script.Watchdog] - THING Alarm   - Thing:UniFi Protect G3 Camera 4 - Terrace state: ONLINE , item:WDGLAN1Camera04HW counter: 2 new count: -4 returned ONLINE and counted
2024-01-03 11:47:50.409 [INFO ] [g.openhab.core.model.script.Watchdog] - THING Alarm   - Thing:UniFi Protect G3 Camera 5 - Left walkway state: ONLINE , item:WDGLAN1Camera05HW counter: 2 new count: -5 returned ONLINE and counted
2024-01-03 11:47:50.410 [INFO ] [g.openhab.core.model.script.Watchdog] - THING Alarm   - Thing:UniFi Protect G3 Camera 6 - Front state: ONLINE , item:WDGLAN1Camera06HW counter: 2 new count: -6 returned ONLINE and counted
2024-01-03 11:47:50.411 [INFO ] [g.openhab.core.model.script.Watchdog] - THING Alarm   - Thing:UniFi Protect G3 Camera 7 - Garage state: ONLINE , item:WDGLAN1Camera07HW counter: 2 new count: -7 returned ONLINE and counted
2024-01-03 11:47:50.413 [INFO ] [g.openhab.core.model.script.Watchdog] - THING Alarm   - Thing:UniFi Protect NVR state: ONLINE , item:WDGLAN1ProtecServ counter: 2 new count: -8 returned ONLINE and counted
2024-01-03 11:47:50.416 [WARN ] [g.openhab.core.model.script.Watchdog] - THING Alarm   - OpenHAB Watchdog alert counter updated from: 9 to: 1

Next I noticed 13minutes later the following (Maybe related or not):

2024-01-03 12:00:00.359 [INFO ] [.internal.api.UniFiControllerRequest] - UniFi returned a status code 503:

At least it seems that the password being 12 characters now got rid of the TOKEN errors.

But the connection seems to be lost for Protect at 12:07 which is approx 1hr 6 minutes after the restart according to the binding watchdog while effectively it worked from 11:00 to 11:47. If the binding watchdog is approx. 20 minutes before triggering then that would make sense but that I don't know.

2024-01-03 12:07:06.096 [INFO ] [ernal.event.UniFiProtectEventManager] - Watch dog Detected no events recieved, reinitializing!
2024-01-03 12:07:16.096 [INFO ] [ernal.event.UniFiProtectEventManager] - Socket failed, reinitializing!

Will try a debug log as there is nothing in the info log when the things go off-line.

marioschouten commented 6 months ago

With DEBUG log on and after restart the binding was working for approx. 20 minutes after which the following error shows:

2024-01-03 12:47:29.252 [DEBUG] [al.thing.UniFiProtectNvrThingHandler] - Executing refresh job
2024-01-03 12:47:29.252 [DEBUG] [al.thing.UniFiProtectNvrThingHandler] - Refreshing Protect: 2106929337
2024-01-03 12:47:29.252 [DEBUG] [al.thing.UniFiProtectNvrThingHandler] - Refreshing the UniFi Protect Controller unifiprotect:nvr:protect
2024-01-03 12:47:29.253 [DEBUG] [al.model.request.UniFiProtectRequest] - Key: x-csrf-token, value: 5584cc59-3488-47d4-9846-b125e4d94783
2024-01-03 12:47:29.253 [DEBUG] [al.model.request.UniFiProtectRequest] - Created new request host: unifi.xxxxxxxx.com, scheme: https, path: /proxy/protect/api/bootstrap
2024-01-03 12:47:29.253 [DEBUG] [al.model.request.UniFiProtectRequest] - New request: HttpRequest[GET /proxy/protect/api/bootstrap HTTP/1.1]@1fca4264
2024-01-03 12:47:29.253 [DEBUG] [al.model.request.UniFiProtectRequest] - >> GET https://unifi.xxxxxxxx.com/proxy/protect/api/bootstrap
2024-01-03 12:47:32.523 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"7cabf961-145b-46e9-8a94-b4ad77353aff","modelKey":"bridge","id":"60f12c750056ec0387000402","mac":"F492BFA51440","nvrMac":"68D79A3ECA9C"}
2024-01-03 12:47:32.581 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"9467ad98-c579-4985-8f26-5f1a15c5cd15","modelKey":"bridge","id":"60f12c750051ec0387000401","mac":"F492BFA50CE4","nvrMac":"68D79A3ECA9C"}
2024-01-03 12:47:32.584 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"1b8a5802-1d5f-4a99-9646-0a90cbb9bdec","modelKey":"camera","id":"60d82c5d0300e503870097d3","mac":"74ACB90B7947","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:32.584 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"1b8a5802-1d5f-4a99-9646-0a90cbb9bdec","modelKey":"camera","id":"60d82c5d0300e503870097d3","mac":"74ACB90B7947","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:32.588 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"3ee3cce3-815a-424f-8bef-e109661e5675","modelKey":"camera","id":"62b69ade0257f8038701e12d","mac":"D021F9902D60","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:32.588 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"3ee3cce3-815a-424f-8bef-e109661e5675","modelKey":"camera","id":"62b69ade0257f8038701e12d","mac":"D021F9902D60","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:32.591 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"191e4a70-a323-44d4-95a5-166eff794a45","modelKey":"camera","id":"60f13ca40230ec0387000449","mac":"7483C23F7C9E","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:32.591 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"191e4a70-a323-44d4-95a5-166eff794a45","modelKey":"camera","id":"60f13ca40230ec0387000449","mac":"7483C23F7C9E","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:32.595 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"411b928b-a58a-4e9c-83dd-3ef6b5e9c3b0","modelKey":"camera","id":"60f13c2c02c0ec0387000446","mac":"7483C23F7D0A","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:32.595 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"411b928b-a58a-4e9c-83dd-3ef6b5e9c3b0","modelKey":"camera","id":"60f13c2c02c0ec0387000446","mac":"7483C23F7D0A","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:32.599 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"2c6fb4d0-2dad-44f6-9cac-38281686c4d5","modelKey":"camera","id":"619d020200505703870003ea","mac":"74ACB90B74C3","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:32.600 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"2c6fb4d0-2dad-44f6-9cac-38281686c4d5","modelKey":"camera","id":"619d020200505703870003ea","mac":"74ACB90B74C3","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:32.606 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"26e1da6e-11d2-4fb3-ae18-26aa9da60057","modelKey":"camera","id":"62072abf00174c03870049da","mac":"74ACB90B7373","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:32.606 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"26e1da6e-11d2-4fb3-ae18-26aa9da60057","modelKey":"camera","id":"62072abf00174c03870049da","mac":"74ACB90B7373","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:32.613 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"a0cc16ad-1f65-4675-8983-261c1a68f764","modelKey":"camera","id":"60499e2400ae0f03870003f1","mac":"7483C23F7D61","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:32.613 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"a0cc16ad-1f65-4675-8983-261c1a68f764","modelKey":"camera","id":"60499e2400ae0f03870003f1","mac":"7483C23F7D61","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:32.646 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"5cfeed56-fa34-414f-b92a-3a0594d66000","modelKey":"bridge","id":"60f12c750051ec0387000401","mac":"F492BFA50CE4","nvrMac":"68D79A3ECA9C"}
2024-01-03 12:47:32.689 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"0a5954b0-05e6-4eed-b48a-f9278c602d8c","modelKey":"camera","id":"60499e2400ae0f03870003f1","mac":"7483C23F7D61","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:32.690 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"0a5954b0-05e6-4eed-b48a-f9278c602d8c","modelKey":"camera","id":"60499e2400ae0f03870003f1","mac":"7483C23F7D61","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:32.693 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"ab0d3a7e-0802-46f6-b345-fa719b9876ca","modelKey":"camera","id":"60f13ca40230ec0387000449","mac":"7483C23F7C9E","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:32.694 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"ab0d3a7e-0802-46f6-b345-fa719b9876ca","modelKey":"camera","id":"60f13ca40230ec0387000449","mac":"7483C23F7C9E","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:32.695 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"6f665e8c-8d9b-4480-8a21-91c93c0b08d7","modelKey":"camera","id":"62072abf00174c03870049da","mac":"74ACB90B7373","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:32.695 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"6f665e8c-8d9b-4480-8a21-91c93c0b08d7","modelKey":"camera","id":"62072abf00174c03870049da","mac":"74ACB90B7373","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:32.696 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"f1580f66-305d-4f29-a93c-f97bfae4b02f","modelKey":"camera","id":"619d020200505703870003ea","mac":"74ACB90B74C3","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:32.696 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"f1580f66-305d-4f29-a93c-f97bfae4b02f","modelKey":"camera","id":"619d020200505703870003ea","mac":"74ACB90B74C3","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:32.696 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"ddf3e0ba-2f86-4182-b4f1-16821a2ca60d","modelKey":"camera","id":"60f13c2c02c0ec0387000446","mac":"7483C23F7D0A","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:32.697 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"ddf3e0ba-2f86-4182-b4f1-16821a2ca60d","modelKey":"camera","id":"60f13c2c02c0ec0387000446","mac":"7483C23F7D0A","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:32.697 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"e0f1b57f-b251-4cab-9cd5-d15a3a7bf66c","modelKey":"bridge","id":"60f12c750056ec0387000402","mac":"F492BFA51440","nvrMac":"68D79A3ECA9C"}
2024-01-03 12:47:32.697 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"8529bdf4-a009-4f48-90c3-ba142d315eca","modelKey":"camera","id":"62b69ade0257f8038701e12d","mac":"D021F9902D60","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:32.697 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"8529bdf4-a009-4f48-90c3-ba142d315eca","modelKey":"camera","id":"62b69ade0257f8038701e12d","mac":"D021F9902D60","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:32.698 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"e33c2faf-d87b-4e89-8c76-67001ac32704","modelKey":"camera","id":"60d82c5d0300e503870097d3","mac":"74ACB90B7947","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:32.698 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"e33c2faf-d87b-4e89-8c76-67001ac32704","modelKey":"camera","id":"60d82c5d0300e503870097d3","mac":"74ACB90B7947","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:34.254 [DEBUG] [otect.internal.model.UniFiProtectNvr] - Request failed reason: TIMEOUT message: Timeout while sending request to UniFi Protect
java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed
    at org.eclipse.jetty.client.HttpConnection$RequestTimeouts.onExpired(HttpConnection.java:334) ~[?:?]
    at org.eclipse.jetty.client.HttpConnection$RequestTimeouts.onExpired(HttpConnection.java:314) ~[?:?]
    at org.eclipse.jetty.io.CyclicTimeouts.onTimeoutExpired(CyclicTimeouts.java:110) ~[?:?]
    at org.eclipse.jetty.io.CyclicTimeouts.access$100(CyclicTimeouts.java:50) ~[?:?]
    at org.eclipse.jetty.io.CyclicTimeouts$Timeouts.onTimeoutExpired(CyclicTimeouts.java:196) ~[?:?]
    at org.eclipse.jetty.io.CyclicTimeout$Wakeup.run(CyclicTimeout.java:298) ~[?:?]
    at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
    at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
    at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [?:?]
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
    at java.lang.Thread.run(Thread.java:840) [?:?]
2024-01-03 12:47:34.256 [DEBUG] [al.model.request.UniFiProtectRequest] - Response is null, it shouldn't be
2024-01-03 12:47:34.256 [DEBUG] [otect.internal.model.UniFiProtectNvr] - Credentials expired, logging in again
2024-01-03 12:47:34.256 [DEBUG] [al.model.request.UniFiProtectRequest] - Set json Body: {"remember":false,"password":"Yij3WgW^gUvTYE","rememberMe":false,"strict":false,"username":"OH-Protect","token":null}
2024-01-03 12:47:34.257 [DEBUG] [al.model.request.UniFiProtectRequest] - Created new request host: unifi.xxxxxxxx.com, scheme: https, path: /api/auth/login
2024-01-03 12:47:34.257 [DEBUG] [al.model.request.UniFiProtectRequest] - New request: HttpRequest[POST /api/auth/login HTTP/1.1]@2fdf4d55
2024-01-03 12:47:34.257 [DEBUG] [al.model.request.UniFiProtectRequest] - >> POST https://unifi.xxxxxxxx.com/api/auth/login
2024-01-03 12:47:38.091 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"bbfad459-7d5c-4a63-91d8-227254641d3b","modelKey":"camera","id":"62072abf00174c03870049da","mac":"74ACB90B7373","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:38.091 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"bbfad459-7d5c-4a63-91d8-227254641d3b","modelKey":"camera","id":"62072abf00174c03870049da","mac":"74ACB90B7373","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:38.107 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"e8ea203d-0a61-4c83-9177-c7569dd33d1b","modelKey":"camera","id":"62b69ade0257f8038701e12d","mac":"D021F9902D60","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:38.108 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"e8ea203d-0a61-4c83-9177-c7569dd33d1b","modelKey":"camera","id":"62b69ade0257f8038701e12d","mac":"D021F9902D60","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:38.140 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"9a19e280-69c2-47af-84ac-ac0dc95d2046","modelKey":"camera","id":"60d82c5d0300e503870097d3","mac":"74ACB90B7947","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:38.141 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"9a19e280-69c2-47af-84ac-ac0dc95d2046","modelKey":"camera","id":"60d82c5d0300e503870097d3","mac":"74ACB90B7947","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:38.162 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"a60bf398-08ad-415a-93db-d90b37a2c64a","modelKey":"camera","id":"619d020200505703870003ea","mac":"74ACB90B74C3","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:38.162 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"a60bf398-08ad-415a-93db-d90b37a2c64a","modelKey":"camera","id":"619d020200505703870003ea","mac":"74ACB90B74C3","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:38.173 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"322375d8-8170-42d2-af3d-9dbd00c5e6ef","modelKey":"camera","id":"60f13ca40230ec0387000449","mac":"7483C23F7C9E","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:38.173 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"322375d8-8170-42d2-af3d-9dbd00c5e6ef","modelKey":"camera","id":"60f13ca40230ec0387000449","mac":"7483C23F7C9E","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:38.174 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"590791fe-5134-4839-859e-98c5d2ae4b95","modelKey":"camera","id":"60f13c2c02c0ec0387000446","mac":"7483C23F7D0A","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:38.174 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"590791fe-5134-4839-859e-98c5d2ae4b95","modelKey":"camera","id":"60f13c2c02c0ec0387000446","mac":"7483C23F7D0A","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:38.174 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"abff7685-bf4a-484f-8ff5-272dce4b8185","modelKey":"camera","id":"60499e2400ae0f03870003f1","mac":"7483C23F7D61","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:38.175 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"abff7685-bf4a-484f-8ff5-272dce4b8185","modelKey":"camera","id":"60499e2400ae0f03870003f1","mac":"7483C23F7D61","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:38.410 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"a17f5278-5d73-4d53-bb06-db56f0abb8fd","modelKey":"nvr","id":"60499e1d039e0f03870003e9","_body":{"uptime":360084650,"lastSeen":1704260858391}}
2024-01-03 12:47:39.258 [DEBUG] [otect.internal.model.UniFiProtectNvr] - Request failed reason: TIMEOUT message: Timeout while sending request to UniFi Protect
java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed
    at org.eclipse.jetty.client.HttpConnection$RequestTimeouts.onExpired(HttpConnection.java:334) ~[?:?]
    at org.eclipse.jetty.client.HttpConnection$RequestTimeouts.onExpired(HttpConnection.java:314) ~[?:?]
    at org.eclipse.jetty.io.CyclicTimeouts.onTimeoutExpired(CyclicTimeouts.java:110) ~[?:?]
    at org.eclipse.jetty.io.CyclicTimeouts.access$100(CyclicTimeouts.java:50) ~[?:?]
    at org.eclipse.jetty.io.CyclicTimeouts$Timeouts.onTimeoutExpired(CyclicTimeouts.java:196) ~[?:?]
    at org.eclipse.jetty.io.CyclicTimeout$Wakeup.run(CyclicTimeout.java:298) ~[?:?]
    at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
    at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
    at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [?:?]
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
    at java.lang.Thread.run(Thread.java:840) [?:?]
2024-01-03 12:47:39.259 [DEBUG] [l.thing.UniFiProtectBaseThingHandler] - Handling event Download by getting event for camera: Front eventid: 6594f4e20109eb03e4041d02 event: UniFiProtectEvent [type=motion, start=1704260830502, end=null, camera=62072abf00174c03870049da, score=18, id=6594f4e20109eb03e4041d02, metadata=MetaData [objectType=null, objectCoords=null, objectConfidence=null], modelKey=event, partition=null, thumbnail=e-6594f4e20109eb03e4041d02, heatmap=e-6594f4e20109eb03e4041d02, smartDetectTypes=[], smartDetectEvents=[]]
2024-01-03 12:47:39.260 [DEBUG] [al.model.request.UniFiProtectRequest] - Key: x-csrf-token, value: 5584cc59-3488-47d4-9846-b125e4d94783
2024-01-03 12:47:39.260 [DEBUG] [al.model.request.UniFiProtectRequest] - Created new request host: unifi.xxxxxxxx.com, scheme: https, path: /proxy/protect/api/thumbnails/e-6594f4e20109eb03e4041d02
2024-01-03 12:47:39.260 [DEBUG] [al.model.request.UniFiProtectRequest] - New request: HttpRequest[GET /proxy/protect/api/thumbnails/e-6594f4e20109eb03e4041d02 HTTP/1.1]@2da8d2e2
2024-01-03 12:47:39.260 [DEBUG] [al.model.request.UniFiProtectRequest] - >> GET https://unifi.xxxxxxxx.com/proxy/protect/api/thumbnails/e-6594f4e20109eb03e4041d02?w=640.0&h=360.0
2024-01-03 12:47:39.266 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Connection closed: 1006 - Disconnected
2024-01-03 12:47:39.269 [DEBUG] [ernal.event.UniFiProtectEventManager] - Failed to stop manager
java.lang.NullPointerException: Cannot invoke "org.eclipse.jetty.websocket.api.Session.disconnect()" because "this.session" is null
    at org.openhab.binding.unifiprotect.websocket.UniFiProtectEventWebSocket.dispose(UniFiProtectEventWebSocket.java:153) ~[?:?]
    at org.openhab.binding.unifiprotect.internal.event.UniFiProtectEventManager.stop(UniFiProtectEventManager.java:169) ~[?:?]
    at org.openhab.binding.unifiprotect.internal.event.UniFiProtectEventManager.dispose(UniFiProtectEventManager.java:180) ~[?:?]
    at org.openhab.binding.unifiprotect.internal.thing.UniFiProtectNvrThingHandler.run(UniFiProtectNvrThingHandler.java:181) ~[?:?]
    at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
    at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?]
    at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) [?:?]
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [?:?]
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
    at java.lang.Thread.run(Thread.java:840) [?:?]

The connection will not restore itself.

For completeness the log of the last working request before above disconnect:

2024-01-03 12:47:26.247 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"dfd3591c-3b4a-492d-9523-d3bb754f132c","modelKey":"event","id":"6594f4e20109eb03e4041d02","recordModel":"camera","recordId":"62072abf00174c03870049da"}
2024-01-03 12:47:26.247 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyEvent Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=184, compressed=false, payload={"action":"update","newUpdateId":"dfd3591c-3b4a-492d-9523-d3bb754f132c","modelKey":"event","id":"6594f4e20109eb03e4041d02","recordModel":"camera","recordId":"62072abf00174c03870049da"}]
2024-01-03 12:47:26.248 [DEBUG] [ernal.event.UniFiProtectEventManager] - Property Changed: EVENT_ACTION_UPDATE
2024-01-03 12:47:26.248 [DEBUG] [ernal.event.UniFiProtectEventManager] - Got action property upd change: UniFiProtectAction [action=update, modelKey=event, id=6594f4e20109eb03e4041d02]
2024-01-03 12:47:26.248 [DEBUG] [el.request.UniFiProtectEventsRequest] - Requesting events start seconds: 60000s end seconds: 10000s
2024-01-03 12:47:26.248 [DEBUG] [al.model.request.UniFiProtectRequest] - Key: x-csrf-token, value: 5584cc59-3488-47d4-9846-b125e4d94783
2024-01-03 12:47:26.248 [DEBUG] [al.model.request.UniFiProtectRequest] - Created new request host: unifi.xxxxxxxx.com, scheme: https, path: /proxy/protect/api/events
2024-01-03 12:47:26.248 [DEBUG] [al.model.request.UniFiProtectRequest] - New request: HttpRequest[GET /proxy/protect/api/events HTTP/1.1]@56b85cb8
2024-01-03 12:47:26.249 [DEBUG] [al.model.request.UniFiProtectRequest] - >> GET https://unifi.xxxxxxxx.com/proxy/protect/api/events?start=1704260786248&end=1704260856248
2024-01-03 12:47:26.298 [DEBUG] [otect.internal.model.UniFiProtectNvr] - Successfullt sent request
2024-01-03 12:47:26.298 [DEBUG] [otect.internal.model.UniFiProtectNvr] - Got events json: [{"id":"6594f040034eeb03e4041946","modelKey":"event","type":"access","start":1704259648845,"end":null,"score":0,"smartDetectTypes":[],"smartDetectEvents":[],"camera":null,"partition":null,"user":"6594d40000feeb03e4040272","metadata":{"userName":"OH-Protect OH-Protect","clientPlatform":"web"},"thumbnail":"e-6594f040034eeb03e4041946","heatmap":"e-6594f040034eeb03e4041946","timestamp":null,"description":{"messageRaw":"{field0} accessed UniFi Protect using the web.","messageKeys":[{"text":"OH-Protect OH-Protect","style":["link"],"action":"viewUsers","key":"field0"}]}},{"id":"6594f4e20109eb03e4041d02","modelKey":"event","type":"motion","start":1704260830502,"end":null,"score":18,"smartDetectTypes":[],"smartDetectEvents":[],"camera":"62072abf00174c03870049da","partition":null,"user":null,"metadata":{"detectedThumbnails":[{"type":"motion","croppedId":"6594f4ee0056eb03e4041d0c","clockBestWall":1704260834603}]},"thumbnail":"e-6594f4e20109eb03e4041d02","heatmap":"e-6594f4e20109eb03e4041d02","timestamp":1704260840792,"description":{"messageRaw":"{field0} has recorded motion.{field1}","messageKeys":[{"text":"Front","style":["link"],"action":"viewDeviceDetails","params":{"deviceId":"62072abf00174c03870049da"},"key":"field0"},{"text":"View","style":["link"],"action":"viewCameraTimelapse","params":{"cameraId":"62072abf00174c03870049da","start":1704260830502},"key":"field1"}]}}]
2024-01-03 12:47:26.299 [DEBUG] [nternal.event.UniFiProtectEventCache] - Adding event to cache: UniFiProtectEvent [type=access, start=1704259648845, end=null, camera=null, score=0, id=6594f040034eeb03e4041946, metadata=MetaData [objectType=null, objectCoords=null, objectConfidence=null], modelKey=event, partition=null, thumbnail=e-6594f040034eeb03e4041946, heatmap=e-6594f040034eeb03e4041946, smartDetectTypes=[], smartDetectEvents=[]]
2024-01-03 12:47:26.299 [DEBUG] [nternal.event.UniFiProtectEventCache] - Ignoring non camera event: UniFiProtectEvent [type=access, start=1704259648845, end=null, camera=null, score=0, id=6594f040034eeb03e4041946, metadata=MetaData [objectType=null, objectCoords=null, objectConfidence=null], modelKey=event, partition=null, thumbnail=e-6594f040034eeb03e4041946, heatmap=e-6594f040034eeb03e4041946, smartDetectTypes=[], smartDetectEvents=[]]
2024-01-03 12:47:26.299 [DEBUG] [nternal.event.UniFiProtectEventCache] - Adding event to cache: UniFiProtectEvent [type=motion, start=1704260830502, end=null, camera=62072abf00174c03870049da, score=18, id=6594f4e20109eb03e4041d02, metadata=MetaData [objectType=null, objectCoords=null, objectConfidence=null], modelKey=event, partition=null, thumbnail=e-6594f4e20109eb03e4041d02, heatmap=e-6594f4e20109eb03e4041d02, smartDetectTypes=[], smartDetectEvents=[]]
2024-01-03 12:47:26.299 [DEBUG] [al.thing.UniFiProtectNvrThingHandler] - Trying to find camera for event
2024-01-03 12:47:26.299 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera cache configMac: 7483C23F7D61
2024-01-03 12:47:26.299 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera CameraInsightCache: UniFiProtectCameraCache [size= 7 macToCamera=]
2024-01-03 12:47:26.299 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera camera: UniFiProtectCamera [name=Garage walkway, id=60499e2400ae0f03870003f1]
2024-01-03 12:47:26.300 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera cache configMac: 7483C23F7D61
2024-01-03 12:47:26.300 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera CameraInsightCache: UniFiProtectCameraCache [size= 7 macToCamera=]
2024-01-03 12:47:26.300 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera camera: UniFiProtectCamera [name=Garage walkway, id=60499e2400ae0f03870003f1]
2024-01-03 12:47:26.300 [DEBUG] [al.thing.UniFiProtectNvrThingHandler] - Handler id: 60499e2400ae0f03870003f1 name: Garage walkway eventCame: 62072abf00174c03870049da
2024-01-03 12:47:26.300 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera cache configMac: 7483C23F7D61
2024-01-03 12:47:26.300 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera CameraInsightCache: UniFiProtectCameraCache [size= 7 macToCamera=]
2024-01-03 12:47:26.300 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera camera: UniFiProtectCamera [name=Garage walkway, id=60499e2400ae0f03870003f1]
2024-01-03 12:47:26.300 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera cache configMac: 7483C23F7D61
2024-01-03 12:47:26.301 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera CameraInsightCache: UniFiProtectCameraCache [size= 7 macToCamera=]
2024-01-03 12:47:26.301 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera camera: UniFiProtectCamera [name=Garage walkway, id=60499e2400ae0f03870003f1]
2024-01-03 12:47:26.301 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera cache configMac: 74ACB90B74C3
2024-01-03 12:47:26.301 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera CameraInsightCache: UniFiProtectCameraCache [size= 7 macToCamera=]
2024-01-03 12:47:26.301 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera camera: UniFiProtectCamera [name=Driveway, id=619d020200505703870003ea]
2024-01-03 12:47:26.301 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera cache configMac: 74ACB90B74C3
2024-01-03 12:47:26.301 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera CameraInsightCache: UniFiProtectCameraCache [size= 7 macToCamera=]
2024-01-03 12:47:26.301 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera camera: UniFiProtectCamera [name=Driveway, id=619d020200505703870003ea]
2024-01-03 12:47:26.302 [DEBUG] [al.thing.UniFiProtectNvrThingHandler] - Handler id: 619d020200505703870003ea name: Driveway eventCame: 62072abf00174c03870049da
2024-01-03 12:47:26.302 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera cache configMac: 74ACB90B74C3
2024-01-03 12:47:26.302 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera CameraInsightCache: UniFiProtectCameraCache [size= 7 macToCamera=]
2024-01-03 12:47:26.302 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera camera: UniFiProtectCamera [name=Driveway, id=619d020200505703870003ea]
2024-01-03 12:47:26.302 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera cache configMac: 74ACB90B74C3
2024-01-03 12:47:26.302 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera CameraInsightCache: UniFiProtectCameraCache [size= 7 macToCamera=]
2024-01-03 12:47:26.302 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera camera: UniFiProtectCamera [name=Driveway, id=619d020200505703870003ea]
2024-01-03 12:47:26.302 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera cache configMac: 7483C23F7C9E
2024-01-03 12:47:26.303 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera CameraInsightCache: UniFiProtectCameraCache [size= 7 macToCamera=]
2024-01-03 12:47:26.303 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera camera: UniFiProtectCamera [name=Pool, id=60f13ca40230ec0387000449]
2024-01-03 12:47:26.303 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera cache configMac: 7483C23F7C9E
2024-01-03 12:47:26.303 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera CameraInsightCache: UniFiProtectCameraCache [size= 7 macToCamera=]
2024-01-03 12:47:26.303 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera camera: UniFiProtectCamera [name=Pool, id=60f13ca40230ec0387000449]
2024-01-03 12:47:26.303 [DEBUG] [al.thing.UniFiProtectNvrThingHandler] - Handler id: 60f13ca40230ec0387000449 name: Pool eventCame: 62072abf00174c03870049da
2024-01-03 12:47:26.303 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera cache configMac: 7483C23F7C9E
2024-01-03 12:47:26.304 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera CameraInsightCache: UniFiProtectCameraCache [size= 7 macToCamera=]
2024-01-03 12:47:26.304 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera camera: UniFiProtectCamera [name=Pool, id=60f13ca40230ec0387000449]
2024-01-03 12:47:26.304 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera cache configMac: 7483C23F7C9E
2024-01-03 12:47:26.304 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera CameraInsightCache: UniFiProtectCameraCache [size= 7 macToCamera=]
2024-01-03 12:47:26.304 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera camera: UniFiProtectCamera [name=Pool, id=60f13ca40230ec0387000449]
2024-01-03 12:47:26.304 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera cache configMac: 74ACB90B7947
2024-01-03 12:47:26.304 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera CameraInsightCache: UniFiProtectCameraCache [size= 7 macToCamera=]
2024-01-03 12:47:26.304 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera camera: UniFiProtectCamera [name=Terrace, id=60d82c5d0300e503870097d3]
2024-01-03 12:47:26.305 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera cache configMac: 74ACB90B7947
2024-01-03 12:47:26.305 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera CameraInsightCache: UniFiProtectCameraCache [size= 7 macToCamera=]
2024-01-03 12:47:26.305 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera camera: UniFiProtectCamera [name=Terrace, id=60d82c5d0300e503870097d3]
2024-01-03 12:47:26.305 [DEBUG] [al.thing.UniFiProtectNvrThingHandler] - Handler id: 60d82c5d0300e503870097d3 name: Terrace eventCame: 62072abf00174c03870049da
2024-01-03 12:47:26.305 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera cache configMac: 74ACB90B7947
2024-01-03 12:47:26.305 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera CameraInsightCache: UniFiProtectCameraCache [size= 7 macToCamera=]
2024-01-03 12:47:26.305 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera camera: UniFiProtectCamera [name=Terrace, id=60d82c5d0300e503870097d3]
2024-01-03 12:47:26.305 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera cache configMac: 74ACB90B7947
2024-01-03 12:47:26.306 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera CameraInsightCache: UniFiProtectCameraCache [size= 7 macToCamera=]
2024-01-03 12:47:26.306 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera camera: UniFiProtectCamera [name=Terrace, id=60d82c5d0300e503870097d3]
2024-01-03 12:47:26.306 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera cache configMac: d021f9902d60
2024-01-03 12:47:26.306 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera CameraInsightCache: UniFiProtectCameraCache [size= 7 macToCamera=]
2024-01-03 12:47:26.306 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera camera: UniFiProtectCamera [name=Left walkway, id=62b69ade0257f8038701e12d]
2024-01-03 12:47:26.306 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera cache configMac: d021f9902d60
2024-01-03 12:47:26.306 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera CameraInsightCache: UniFiProtectCameraCache [size= 7 macToCamera=]
2024-01-03 12:47:26.307 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera camera: UniFiProtectCamera [name=Left walkway, id=62b69ade0257f8038701e12d]
2024-01-03 12:47:26.307 [DEBUG] [al.thing.UniFiProtectNvrThingHandler] - Handler id: 62b69ade0257f8038701e12d name: Left walkway eventCame: 62072abf00174c03870049da
2024-01-03 12:47:26.307 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera cache configMac: d021f9902d60
2024-01-03 12:47:26.307 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera CameraInsightCache: UniFiProtectCameraCache [size= 7 macToCamera=]
2024-01-03 12:47:26.307 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera camera: UniFiProtectCamera [name=Left walkway, id=62b69ade0257f8038701e12d]
2024-01-03 12:47:26.307 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera cache configMac: d021f9902d60
2024-01-03 12:47:26.307 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera CameraInsightCache: UniFiProtectCameraCache [size= 7 macToCamera=]
2024-01-03 12:47:26.307 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera camera: UniFiProtectCamera [name=Left walkway, id=62b69ade0257f8038701e12d]
2024-01-03 12:47:26.308 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera cache configMac: 74ACB90B7373
2024-01-03 12:47:26.308 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera CameraInsightCache: UniFiProtectCameraCache [size= 7 macToCamera=]
2024-01-03 12:47:26.308 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera camera: UniFiProtectCamera [name=Front, id=62072abf00174c03870049da]
2024-01-03 12:47:26.308 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera cache configMac: 74ACB90B7373
2024-01-03 12:47:26.308 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera CameraInsightCache: UniFiProtectCameraCache [size= 7 macToCamera=]
2024-01-03 12:47:26.308 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera camera: UniFiProtectCamera [name=Front, id=62072abf00174c03870049da]
2024-01-03 12:47:26.308 [DEBUG] [al.thing.UniFiProtectNvrThingHandler] - Handler id: 62072abf00174c03870049da name: Front eventCame: 62072abf00174c03870049da
2024-01-03 12:47:26.308 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera cache configMac: 74ACB90B7373
2024-01-03 12:47:26.309 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera CameraInsightCache: UniFiProtectCameraCache [size= 7 macToCamera=]
2024-01-03 12:47:26.309 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera camera: UniFiProtectCamera [name=Front, id=62072abf00174c03870049da]
2024-01-03 12:47:26.309 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera cache configMac: 74ACB90B7373
2024-01-03 12:47:26.309 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera CameraInsightCache: UniFiProtectCameraCache [size= 7 macToCamera=]
2024-01-03 12:47:26.309 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera camera: UniFiProtectCamera [name=Front, id=62072abf00174c03870049da]
2024-01-03 12:47:26.309 [DEBUG] [al.thing.UniFiProtectNvrThingHandler] - Got EventActionUpd action: UniFiProtectAction [action=update, modelKey=event, id=6594f4e20109eb03e4041d02] event: UniFiProtectEvent [type=motion, start=1704260830502, end=null, camera=62072abf00174c03870049da, score=18, id=6594f4e20109eb03e4041d02, metadata=MetaData [objectType=null, objectCoords=null, objectConfidence=null], modelKey=event, partition=null, thumbnail=e-6594f4e20109eb03e4041d02, heatmap=e-6594f4e20109eb03e4041d02, smartDetectTypes=[], smartDetectEvents=[]]
2024-01-03 12:47:26.309 [DEBUG] [al.thing.UniFiProtectNvrThingHandler] - Handling event motion
2024-01-03 12:47:26.310 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera cache configMac: 74ACB90B7373
2024-01-03 12:47:26.310 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera CameraInsightCache: UniFiProtectCameraCache [size= 7 macToCamera=]
2024-01-03 12:47:26.310 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera camera: UniFiProtectCamera [name=Front, id=62072abf00174c03870049da]
2024-01-03 12:47:26.310 [DEBUG] [l.thing.UniFiProtectBaseThingHandler] - Scehduling completable future for camera: Front delay: 5
2024-01-03 12:47:26.310 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera cache configMac: 74ACB90B7373
2024-01-03 12:47:26.310 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera CameraInsightCache: UniFiProtectCameraCache [size= 7 macToCamera=]
2024-01-03 12:47:26.310 [DEBUG] [otect.internal.model.UniFiProtectNvr] - getCamera camera: UniFiProtectCamera [name=Front, id=62072abf00174c03870049da]
2024-01-03 12:47:26.311 [DEBUG] [l.thing.UniFiProtectBaseThingHandler] - Scehduling completable future for camera: Front delay: 5
2024-01-03 12:47:27.785 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"cfa36c70-f099-44a4-9392-ea9dd9365555","modelKey":"nvr","id":"60499e1d039e0f03870003e9","_body":{"portStatus":[{"id":"eth0","enabled":true,"plugged":true,"type":"LAN","mac":"68:d7:9a:3e:ca:9c"},{"id":"eth1","enabled":true,"plugged":true,"type":"LAN","mac":"68:d7:9a:3e:ca:9d"},{"id":"eth10","enabled":true,"plugged":false,"type":"LAN","mac":"68:d7:9a:3e:ca:a6"},{"id":"eth2","enabled":true,"plugged":true,"type":"LAN","mac":"68:d7:9a:3e:ca:9e"},{"id":"eth3","enabled":true,"plugged":true,"type":"LAN","mac":"68:d7:9a:3e:ca:9f"},{"id":"eth4","enabled":true,"plugged":true,"type":"LAN","mac":"68:d7:9a:3e:ca:a0"},{"id":"eth5","enabled":true,"plugged":true,"type":"LAN","mac":"68:d7:9a:3e:ca:a1"},{"id":"eth6","enabled":true,"plugged":false,"type":"LAN","mac":"68:d7:9a:3e:ca:a2"},{"id":"eth7","enabled":true,"plugged":true,"type":"LAN","mac":"68:d7:9a:3e:ca:a3"},{"id":"eth8","enabled":true,"plugged":true,"type":"WAN","mac":"68:d7:9a:3e:ca:a4"},{"id":"eth9","enabled":true,"plugged":false,"type":"WAN2","mac":"68:d7:9a:3e:ca:a5"}]}}
2024-01-03 12:47:27.799 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"31051150-2e22-4912-83b3-b4a3357d592b","modelKey":"nvr","id":"60499e1d039e0f03870003e9","_body":{"systemInfo":{"cpu":{"averageLoad":28.000000000000004,"temperature":58},"memory":{"available":638972,"free":70868,"total":4040832},"storage":{"available":335180296192,"isRecycling":true,"size":5952785969152,"type":"hdd","used":5617605672960,"capability":"ok","devices":[{"model":"WDC WD62PURZ-85B3AY0","size":6001175126016,"healthy":"good"}]},"ustorage":{"disks":[{"slot":1,"type":"HDD","model":"WDC WD62PURZ-85B3AY0","size":6001175126016,"serial":"WD-C8045D8G","firmware":"80.00A80","rpm":5640,"ata":"ACS-3","sata":"SATA 3.1","action":"none","healthy":"good","state":"normal","reason":[],"temperature":59,"poweronhrs":24630,"life_span":null,"bad_sector":0,"threshold":10,"progress":null,"estimate":null}],"space":[{"device":"sda1","health":"health","total_bytes":5952785969152,"used_bytes":5617605672960,"action":"none","progress":0,"estimate":null,"space_type":"primary"},{"device":"overlay","health":"health","total_bytes":10057830400,"used_bytes":5837389824,"action":"none","progress":0,"estimate":null,"space_type":"root"}],"sdcards":[]},"tmpfs":{"available":516060,"total":524288,"used":8228,"path":"/var/opt/unifi-protect/tmp"}}}}
2024-01-03 12:47:28.065 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"76868db3-757e-42a2-accf-bfc74bcf5dc0","modelKey":"camera","id":"62b69ade0257f8038701e12d","mac":"D021F9902D60","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:28.065 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"76868db3-757e-42a2-accf-bfc74bcf5dc0","modelKey":"camera","id":"62b69ade0257f8038701e12d","mac":"D021F9902D60","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:28.103 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"5fc01e28-d023-4d00-8f6d-9acf07db1c4c","modelKey":"camera","id":"62072abf00174c03870049da","mac":"74ACB90B7373","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:28.103 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"5fc01e28-d023-4d00-8f6d-9acf07db1c4c","modelKey":"camera","id":"62072abf00174c03870049da","mac":"74ACB90B7373","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:28.117 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"be0f2b74-53cc-43a0-96b3-7e3fb2d10546","modelKey":"camera","id":"60499e2400ae0f03870003f1","mac":"7483C23F7D61","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:28.117 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"be0f2b74-53cc-43a0-96b3-7e3fb2d10546","modelKey":"camera","id":"60499e2400ae0f03870003f1","mac":"7483C23F7D61","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:28.140 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"63adc8c8-4a1d-492b-b19f-ca3bf5dce66a","modelKey":"camera","id":"619d020200505703870003ea","mac":"74ACB90B74C3","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:28.140 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"63adc8c8-4a1d-492b-b19f-ca3bf5dce66a","modelKey":"camera","id":"619d020200505703870003ea","mac":"74ACB90B74C3","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:28.162 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"718ad469-2870-4717-8860-871a77b108b6","modelKey":"camera","id":"60f13ca40230ec0387000449","mac":"7483C23F7C9E","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:28.162 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"718ad469-2870-4717-8860-871a77b108b6","modelKey":"camera","id":"60f13ca40230ec0387000449","mac":"7483C23F7C9E","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:28.164 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"29a60eb8-dd5e-4860-bf13-0227d716782c","modelKey":"camera","id":"60d82c5d0300e503870097d3","mac":"74ACB90B7947","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:28.164 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"29a60eb8-dd5e-4860-bf13-0227d716782c","modelKey":"camera","id":"60d82c5d0300e503870097d3","mac":"74ACB90B7947","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:28.165 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"919af743-828e-4b2e-851b-bd8f361b203a","modelKey":"camera","id":"60f13c2c02c0ec0387000446","mac":"7483C23F7D0A","nvrMac":"68D79A3ECA9C","token":null}
2024-01-03 12:47:28.165 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"919af743-828e-4b2e-851b-bd8f361b203a","modelKey":"camera","id":"60f13c2c02c0ec0387000446","mac":"7483C23F7D0A","nvrMac":"68D79A3ECA9C","token":null}]
2024-01-03 12:47:28.422 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"1db214bb-d86e-4aed-96a9-00dd652cae02","modelKey":"nvr","id":"60499e1d039e0f03870003e9","_body":{"uptime":360074650,"lastSeen":1704260848391}}
2024-01-03 12:47:28.984 [DEBUG] [ernal.event.UniFiProtectEventManager] - Schedule watchDog currentMillisDiff 2673

Hopes this helps, let me know if I can make any other logs. Thanks in advance.

Andre1Becker commented 6 months ago

Hey, Im using a Cloud Key Gen2 Plus (UnifiOS v3.2.7) and it seems that i got this issue as well. My Protect Version is: v2.10.11. I did change the User Password to meet the min 12 char length.

Also i removed and readded all things just a couple of minutes ago. Will report back if the issue reappears.

marioschouten commented 6 months ago

Hey, Im using a Cloud Key Gen2 Plus (UnifiOS v3.2.7) and it seems that i got this issue as well. My Protect Version is: v2.10.11. I did change the User Password to meet the min 12 char length.

Also i removed and readded all things just a couple of minutes ago. Will report back if the issue reappears.

Hi @Andre1Becker, DId this solve the issue?

Andre1Becker commented 6 months ago

Hi @Andre1Becker, DId this solve the issue?

No, it seems to work only for a couple of hours before cameras stop responding. And I mean there online, but no info is refreshed anymore. I will try to get debug logs later on

Andre1Becker commented 6 months ago

Now i have some debug logs - but i dont know if they any useful:

2024-01-06 17:11:22.195 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"a95b92a6-7944-4e18-a262-f5efbe3eada8","modelKey":"nvr","id":"5ec20e5c0097d903e70003e9","_body":{"systemInfo":{"cpu":{"averageLoad":14.3,"temperature":38},"memory":{"available":714228,"free":106040,"total":3051692},"storage":{"available":44408381440,"isRecycling":true,"size":942307909632,"type":"hdd","used":897899528192,"capability":"ok","devices":[{"model":"TOSHIBA MQ01ABD100V","size":1000204886016,"healthy":"good"}]},"ustorage":{"disks":[{"slot":1,"type":"HDD","model":"TOSHIBA MQ01ABD100V","size":1000204886016,"serial":"*********","firmware":"AX001Q","rpm":5400,"ata":"ATA8-ACS","sata":"SATA 2.6","action":"none","healthy":"good","state":"normal","reason":[],"temperature":40,"poweronhrs":24909,"life_span":null,"bad_sector":0,"threshold":10,"progress":null,"estimate":null}],"space":[{"device":"sda4","health":"health","total_bytes":942307909632,"used_bytes":897899528192,"action":"none","progress":0,"estimate":null,"space_type":"primary"},{"device":"sda1","health":"health","total_bytes":0,"used_bytes":0,"action":"none","progress":0,"estimate":null,"space_type":"swap"},{"device":"mmcblk0p46","health":"health","total_bytes":6274220032,"used_bytes":935485440,"action":"none","progress":0,"estimate":null,"space_type":"root"}],"sdcards":[]},"tmpfs":{"available":257728,"total":262144,"used":4416,"path":"/var/opt/unifi-protect/tmp"}}}}
2024-01-06 17:11:22.270 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"4ebf41e0-97de-4603-b960-f99f28948b88","modelKey":"camera","id":"603e4f9d00bba003e7000433","mac":"************","nvrMac":"************","token":null}
2024-01-06 17:11:22.271 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"4ebf41e0-97de-4603-b960-f99f28948b88","modelKey":"camera","id":"603e4f9d00bba003e7000433","mac":"************","nvrMac":"************","token":null}]
2024-01-06 17:11:22.363 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"e08b0fe7-0a85-48ea-9689-93e62aa12399","modelKey":"camera","id":"62fa25630179bb03e700063e","mac":"************","nvrMac":"************","token":null}
2024-01-06 17:11:22.363 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"e08b0fe7-0a85-48ea-9689-93e62aa12399","modelKey":"camera","id":"62fa25630179bb03e700063e","mac":"************","nvrMac":"************","token":null}]
2024-01-06 17:11:22.366 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"a609dbac-fbf0-4748-a2a7-e355c45acb7f","modelKey":"camera","id":"62f262ad03b7cc03e7009189","mac":"************","nvrMac":"************","token":null}
2024-01-06 17:11:22.367 [DEBUG] [websocket.UniFiProtectEventWebSocket] - ModelKeyCamera Got: update and event action: UniFiProtectFrame [type=ACTION, format=JSON_OBJECT, size=182, compressed=false, payload={"action":"update","newUpdateId":"a609dbac-fbf0-4748-a2a7-e355c45acb7f","modelKey":"camera","id":"62f262ad03b7cc03e7009189","mac":"************","nvrMac":"************","token":null}]
2024-01-06 17:11:22.483 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Frame: {"action":"update","newUpdateId":"65879998-23f9-4076-8dbd-9948c595a8dc","modelKey":"nvr","id":"5ec20e5c0097d903e70003e9","_body":{"uptime":2028758170,"lastSeen":1704557482609}}
2024-01-06 17:11:27.400 [DEBUG] [al.thing.UniFiProtectNvrThingHandler] - dispose()
2024-01-06 17:11:27.400 [DEBUG] [al.thing.UniFiProtectNvrThingHandler] - Cancelling refresh job
2024-01-06 17:11:27.401 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Connection closed: 1006 - Disconnected
2024-01-06 17:11:27.403 [DEBUG] [ernal.event.UniFiProtectEventManager] - Failed to stop manager
java.lang.NullPointerException: Cannot invoke "org.eclipse.jetty.websocket.api.Session.disconnect()" because "this.session" is null
    at org.openhab.binding.unifiprotect.websocket.UniFiProtectEventWebSocket.dispose(UniFiProtectEventWebSocket.java:153) ~[bundleFile:?]
    at org.openhab.binding.unifiprotect.internal.event.UniFiProtectEventManager.stop(UniFiProtectEventManager.java:169) [bundleFile:?]
    at org.openhab.binding.unifiprotect.internal.event.UniFiProtectEventManager.dispose(UniFiProtectEventManager.java:180) [bundleFile:?]
    at org.openhab.binding.unifiprotect.internal.thing.UniFiProtectNvrThingHandler.dispose(UniFiProtectNvrThingHandler.java:195) [bundleFile:?]
    at org.openhab.core.thing.binding.BaseThingHandler.thingUpdated(BaseThingHandler.java:155) [bundleFile:?]
    at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
    at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) ~[?:?]
    at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
    at java.lang.reflect.Method.invoke(Method.java:568) ~[?:?]
    at org.openhab.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:147) [bundleFile:?]
    at org.openhab.core.internal.common.Invocation.call(Invocation.java:52) [bundleFile:?]
    at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [?:?]
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
    at java.lang.Thread.run(Thread.java:840) [?:?]
2024-01-06 17:11:27.404 [DEBUG] [al.thing.UniFiProtectNvrThingHandler] - Initializing the UniFi Protect NVR Handler with config = UniFiProtectNvrThingConfig [host=172.18.0.250, username=openhab,  password=*************, refresh=60, eventsTimePeriodLength=30, thumbnailWidth=640.0, imageFolder=/openhab/userdata/tmp, g4SnapshotWidth=3840, g4SnapshotHeight=2160, defaultSnapshotWidth=1920, defaultSnapshotHeight=1080, watchDog=false, g4SnapshotWidthAsString=3840, g4SnapshotHeightAsString=2160, defaultSnapshotWidthAsString=1920, defaultSnapshotHeightAsString=1080]
2024-01-06 17:11:27.406 [INFO ] [otect.internal.model.UniFiProtectNvr] - Initializing the binding, with config: UniFiProtectNvrThingConfig [host=172.18.0.250, username=openhab,  password=*************, refresh=60, eventsTimePeriodLength=30, thumbnailWidth=640.0, imageFolder=/openhab/userdata/tmp, g4SnapshotWidth=3840, g4SnapshotHeight=2160, defaultSnapshotWidth=1920, defaultSnapshotHeight=1080, watchDog=false, g4SnapshotWidthAsString=3840, g4SnapshotHeightAsString=2160, defaultSnapshotWidthAsString=1920, defaultSnapshotHeightAsString=1080]
2024-01-06 17:11:27.407 [DEBUG] [al.model.request.UniFiProtectRequest] - Set json Body: {"remember":false,"password":"************","rememberMe":false,"strict":false,"username":"openhab","token":null}
2024-01-06 17:11:27.407 [DEBUG] [al.model.request.UniFiProtectRequest] - Created new request host: 172.18.0.250, scheme: https, path: /api/auth/login
2024-01-06 17:11:27.407 [DEBUG] [al.model.request.UniFiProtectRequest] - New request: HttpRequest[POST /api/auth/login HTTP/1.1]@6fdc7b07
2024-01-06 17:11:27.407 [DEBUG] [al.model.request.UniFiProtectRequest] - >> POST https://172.18.0.250/api/auth/login
2024-01-06 17:11:29.418 [DEBUG] [otect.internal.model.UniFiProtectNvr] - Successfullt sent request
2024-01-06 17:11:29.419 [DEBUG] [ernal.event.UniFiProtectEventManager] - Trying to start new websocket
2024-01-06 17:11:29.421 [DEBUG] [.websocket.UniFiProtectEventWsClient] - Connecting to: wss://172.18.0.250/proxy/protect/ws/updates
2024-01-06 17:11:29.422 [DEBUG] [websocket.UniFiProtectEventWebSocket] - Error in websocket: null
java.nio.channels.AsynchronousCloseException: null
    at org.eclipse.jetty.client.HttpDestination.close(HttpDestination.java:481) [bundleFile:9.4.52.v20230823]
    at org.eclipse.jetty.client.HttpClient.doStop(HttpClient.java:286) [bundleFile:9.4.52.v20230823]
    at org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:94) [bundleFile:9.4.52.v20230823]
    at org.eclipse.jetty.util.component.ContainerLifeCycle.stop(ContainerLifeCycle.java:180) [bundleFile:9.4.52.v20230823]
    at org.eclipse.jetty.util.component.ContainerLifeCycle.doStop(ContainerLifeCycle.java:201) [bundleFile:9.4.52.v20230823]
    at org.eclipse.jetty.websocket.client.WebSocketClient.doStop(WebSocketClient.java:429) [bundleFile:9.4.52.v20230823]
    at org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:94) [bundleFile:9.4.52.v20230823]
    at org.openhab.binding.unifiprotect.websocket.UniFiProtectEventWsClient.stop(UniFiProtectEventWsClient.java:62) [bundleFile:?]
    at org.openhab.binding.unifiprotect.internal.event.UniFiProtectEventManager.stop(UniFiProtectEventManager.java:168) [bundleFile:?]
    at org.openhab.binding.unifiprotect.internal.thing.UniFiProtectNvrThingHandler.updateStatus(UniFiProtectNvrThingHandler.java:127) [bundleFile:?]
    at org.openhab.binding.unifiprotect.internal.thing.UniFiProtectNvrThingHandler.initialize(UniFiProtectNvrThingHandler.java:114) [bundleFile:?]
    at org.openhab.core.thing.binding.BaseThingHandler.thingUpdated(BaseThingHandler.java:157) [bundleFile:?]
    at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
    at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) ~[?:?]
    at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
    at java.lang.reflect.Method.invoke(Method.java:568) ~[?:?]
    at org.openhab.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:147) [bundleFile:?]
    at org.openhab.core.internal.common.Invocation.call(Invocation.java:52) [bundleFile:?]
    at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [?:?]
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
    at java.lang.Thread.run(Thread.java:840) [?:?]
2024-01-06 17:11:29.424 [DEBUG] [ernal.event.UniFiProtectEventManager] - Failed to stop manager
java.lang.NullPointerException: Cannot invoke "org.eclipse.jetty.websocket.api.Session.disconnect()" because "this.session" is null
    at org.openhab.binding.unifiprotect.websocket.UniFiProtectEventWebSocket.dispose(UniFiProtectEventWebSocket.java:153) ~[bundleFile:?]
    at org.openhab.binding.unifiprotect.internal.event.UniFiProtectEventManager.stop(UniFiProtectEventManager.java:169) [bundleFile:?]
    at org.openhab.binding.unifiprotect.internal.thing.UniFiProtectNvrThingHandler.updateStatus(UniFiProtectNvrThingHandler.java:127) [bundleFile:?]
    at org.openhab.binding.unifiprotect.internal.thing.UniFiProtectNvrThingHandler.initialize(UniFiProtectNvrThingHandler.java:114) [bundleFile:?]
    at org.openhab.core.thing.binding.BaseThingHandler.thingUpdated(BaseThingHandler.java:157) [bundleFile:?]
    at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
    at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) ~[?:?]
    at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
    at java.lang.reflect.Method.invoke(Method.java:568) ~[?:?]
    at org.openhab.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:147) [bundleFile:?]
    at org.openhab.core.internal.common.Invocation.call(Invocation.java:52) [bundleFile:?]
    at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [?:?]
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
    at java.lang.Thread.run(Thread.java:840) [?:?]
2024-01-06 17:11:41.075 [DEBUG] [ing.UniFiProtectG3CameraThingHandler] - Initializing the UniFiProtect Client Handler with config = UniFiProtectG3CameraThingConfig [mac=************]

Andre1Becker commented 6 months ago

@marioschouten: you should remove the password from the logs you posted and immediately change it for the 'OH-Protect' user to be save.

I just noticed it.

marioschouten commented 6 months ago

@Andre1Becker Thanks, did mask my domain but missed that entry. All changed, luckly just a local account. Much appriciated you letting me know.

marioschouten commented 5 months ago

Hi, Been running with unifiprotect-4.0.0-v1.3 and it works great. I have no disconnectons and motion detection field and last motion picture are back. Thanks a lot for the work you are doing, very much appriciated.

seaside1 / unifiprotect

Connection lost after 20min. with latest UDM PRO Protect version 2.10.11 #19