My Doorbrid device Things connected via IPcamera binding are getting offline from time to time. It seems to be working sometimes for a few days and sometimes only one day or so. Since the devices are doorbells it is hard to assess properly since they are not in action regularly and / or all the time.
The Things are going offline and the following error is shown in Main UI:
The IP and PORT are correct and nothing has been changed in the setup. When pausing the Things and re-activating them after a few seconds, they’re getting back online and working properly.
As discussed with @matt1, I set the binding to log level TRACE in order to check what happens. Please find the relevant logs between the time when the Things were definetely working (Mail with a snapshot was send) and the time were it was definetly not working anymore (Mail with broken picture only was send):
Working properly, proved by detected motion snapshot:
2022-03-13 19:48:07.039 [INFO ] [.model.script.DoorbirdHaustuer_IPcam] - Motion detected at Haustuer
then tons of those messages:
2022-03-13 19:48:08.258 [TRACE] [era.internal.handler.IpCameraHandler] - HTTP Result back from camera is :--ioboundary Content-Type: text/plain doorbell:L --ioboundary Content-Type: text/plain motionsensor:L
not sure whether this is relevant in this case or not, but to be 100% safe let me share the following error also:
2022-03-14 03:20:51.521 [ERROR] [io.openhabcloud.internal.CloudClient] - Error during communication 2022-03-14 03:20:51.521 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = XXX, base URL = http://localhost:8080) 2022-03-14 03:20:52.990 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = XXX, base URL = http://localhost:8080)
I am posting this, since before and after this log, there where a lot of errors from http binding. It seems somewhen in the night, the http binding failed somehow.
However, the first WARN log from IPcamera binding was > half an hour later. The connection was closed by remotehost:
2022-03-14 04:08:24.379 [WARN ] [era.internal.handler.IpCameraHandler] - !!!! Camera possibly closed the channel on the binding, cause reported is: Eine vorhandene Verbindung wurde vom Remotehost geschlossen
In the morning, there was a DEBUG message. When I remember correctly, I’ve seen those messages also when the binding was working. So I guess it is also not related to the offline issue:
2022-03-14 07:20:32.125 [DEBUG] [hab.binding.ipcamera.internal.Ffmpeg] - [mp4 @ 000001caecccc040] Non-monotonous DTS in output stream 0:0; previous: 0, current: -7636; changing to 1. This may result in incorrect timestamps in the output file. 2022-03-14 07:20:32.328 [DEBUG] [hab.binding.ipcamera.internal.Ffmpeg] - [mp4 @ 000001a7c97a00c0] Timestamps are unset in a packet for stream 0. This is deprecated and will stop working in the future. Fix your code to set the timestamps properly
Then a motion event happend, and the snapshot sent by Mail was a grey dot only:
2022-03-14 08:14:04.039 [INFO ] [re.model.script.DoorbirdPforte_IPcam] - Motion detected at Pforte
I guess the time of interest is 04:08:24.379 when the connection is closed. What exactly does this mean? Or is it somehow standard but the connection should be established again and does not?
When it’s helpfull I can of course share the whole log file by PM also.