QuantumEntangledAndy / neolink

An RTSP bridge to Reolink IP cameras
GNU Affero General Public License v3.0
322 stars 44 forks source link

Error streaming from Argus 3 Pro #38

Closed dkerr64 closed 1 year ago

dkerr64 commented 1 year ago

Getting this recoverable error.

david@neolink:~/github/neolink$ ~/github/neolink/target/debug/neolink rtsp --config=/home/david/neolink.toml
[2023-02-26T02:51:19Z INFO  neolink] Neolink 0.5.2 (unknown commit) debug
[2023-02-26T02:51:19Z WARN  neolink::rtsp::gst] Missing the gstreamer plugin `x264 (gst-plugins-ugly)` for `x264enc` element. Required to paused certain cameras
[2023-02-26T02:51:19Z WARN  neolink::rtsp::gst] Missing the gstreamer plugin `libav (gst-libav)` for `avdec_h264` element. Required to paused certain cameras
[2023-02-26T02:51:19Z WARN  neolink::rtsp::gst] Missing the gstreamer plugin `libav (gst-libav)` for `avdec_h265` element. Required to paused certain cameras
[2023-02-26T02:51:19Z INFO  neolink::rtsp] Starting RTSP Server at 0.0.0.0:8554
[2023-02-26T02:51:19Z INFO  neolink::rtsp::states] driveway: Connecting to camera at UID: <redacted>
[2023-02-26T02:51:22Z INFO  neolink_core::bc_protocol] Local discovery success <redacted> at 192.168.21.38:34892
[2023-02-26T02:51:22Z INFO  neolink::rtsp::states] driveway: Logging in
[2023-02-26T02:51:22Z INFO  neolink::rtsp::states] driveway: Successfully logged in
[2023-02-26T02:51:23Z INFO  neolink::rtsp::states] driveway: Camera time is already set: 2023-02-25 21:51:30 -5
[2023-02-26T02:51:23Z INFO  neolink::rtsp::states] driveway: Camera reports firmware version v3.0.0.973_22051000
[2023-02-26T02:51:23Z INFO  neolink::rtsp::states] driveway: Starting stream
[2023-02-26T02:51:23Z INFO  neolink::rtsp::states::streaming] driveway: Starting video stream Main Stream (Clear)
[2023-02-26T02:51:23Z INFO  neolink::rtsp::states] driveway: Successfully started streaming
[2023-02-26T02:52:28Z WARN  neolink::rtsp] driveway: Retryable error: Camera has unexpectanely stopped the streaming state: On stream: Main

    Caused by:
        Nom Parsing error: Nom Error: VerboseError { errors: [([8C, 32, 37, 7A, A1, 36, 24, D0, 86, 13, 4D, B7, A3, E6, 5B, 52, EF, EC, 33, 88, FB, B2, 3C, A, 41, B0, 70, 4C, 64, 53, C5, 11, 5C, AC, F9, 2A, 82, 93, 9D, F2, 4, 62, 73, 2, 51, F4, 9F, E0, A7, 10, 53, 93, A2, EA, AD, 2A, F5, D7, C6, 11, CF, B4, 64, B4, BA, B, 4, C1, 2F, 6F, F4, C, B2, D4, C, FB, B4, B8, 29, EE, F5, 89, F4, F5, 37, 68, BD, A0, C4, 15, 1C, 3, 39, 95, 5F, 4C, 19, A4, 9F, B5, E3, C6, A6, C9, 74, 1, C1, C1, DD, C8, DE, F, 24, 43, E8, F5, CA, 81, 61, 19, FF, 84, 1B, C4, 82, A0, 83, 46, 3D, 96, 6D, 84, 66, C, A5, E, 68, 8C, 1D, D0, D8, 7F, 7A, F4, F4, 5D, 64, DE, 80, D9, 77, 44, 73, EE, 53, A1, B9, 3A, 9F, 7E, F, C6, 4A, AB, 71, BB, 69, B7, FF, 7D, BF, DF, 47, A4, 45, 51, 6, F6, FD, 4B, DA, 9, E0, 31, 15, D2, 88, 98, 77, AD, CC, 80, 9, EC, 1C, C4, B3, 39, 2B, CF, 9D, 23, D, EA, 35, FF, B, 50, 50, 91, 4A, 56, 38, F3, 27, B9, 17, A5, FD, 1F, A4, CE, 2E, FB, CB, 3E, AD, 6D, E0, 58, 18, C3, DB, 45, C1, D3, 80, 50, 4F, D, 41, 8B, 4C, B, 2F, 0, A0, 93, 50, 2D, 78, C4, DD, 26, 51, 5B, 68, ED, 0, 60, BB, F2, A8, 1F, 7F, 4A, BE, B9, 87, 2B, F4, E1, 99, 8, A5, 4, B1, 4E, 1E, 2C, AE, 4C, 62, 66, D7, EA, D6, 22, 35, C2, D6, 1A, C, 81, DF, 34, 3, D1, 8C, 4A, A5, 95, BB, 87, CD, AE, D0, 89, D9, B3, B7, 34, 19, 77, 52, D, 80, F2, E3, 5E, C2, 69, E1, 70, 99, 6E, D9, EE, B5, 74, 4, D4, B3, E, DE, 4D, 4A, BC, 2B, 72, BC, A8, 43, 1D, F, CB, 62, B2, 97, 92, 5A, F7, BC, B4, 7F, 48, 9B, 6C, 89, 1C, 34, 33, AF, AC, 35, 80, 92, 51, 3E, 39, C1, E4, F9, 15, 46, 94, A3, 3F, E0, 7C, F5, 3A, 62, 64, 70, 71, C9, 90, 79, 3D, A6, 1D, FF, 3A, 16, CB, 5A, EE, CC, 36, 8B, E7, A, E4, 5, 43, 93, 91, 42, 4D, 98, 71, A0, 16, 89, 33, D2, 82, EB, E5, 33, 7, 62, DD, AC, 4E, CD, 24, 3F, 92, FB, 34, 45, 2B, EF, 81, 7C, 1B, B1, 76, 11, 5A, 42, 53, C4, BD, 63, FF, E1, 66, E6, 22, 96, 54, 9E, E7, A, C4, 0, 5E, AB, D, 6F, BD, 3C, A2, EF, 86, 86, 57, 48, F9, 3, 8A, F2, 10, 97, 1A, F3, A4, 64, 73, 32, C4, EC, C0, DC, 1D, 38, 94, 9D, A2, 38, D8, 69, 26, A7, 9, 3A, EA, B, 39, 41, 72, 2C, 1D, DB, 2B, 2A, 1E, CE, BF, 7, 9D, CF, 81, A7, B0, 65, D8, 88, 6B, 9C, 4E, 3B, A6, C8, 9B], Nom(Verify)), ([8C, 32, 37, 7A, A1, 36, 24, D0, 86, 13, 4D, B7, A3, E6, 5B, 52, EF, EC, 33, 88, FB, B2, 3C, A, 41, B0, 70, 4C, 64, 53, C5, 11, 5C, AC, F9, 2A, 82, 93, 9D, F2, 4, 62, 73, 2, 51, F4, 9F, E0, A7, 10, 53, 93, A2, EA, AD, 2A, F5, D7, C6, 11, CF, B4, 64, B4, BA, B, 4, C1, 2F, 6F, F4, C, B2, D4, C, FB, B4, B8, 29, EE, F5, 89, F4, F5, 37, 68, BD, A0, C4, 15, 1C, 3, 39, 95, 5F, 4C, 19, A4, 9F, B5, E3, C6, A6, C9, 74, 1, C1, C1, DD, C8, DE, F, 24, 43, E8, F5, CA, 81, 61, 19, FF, 84, 1B, C4, 82, A0, 83, 46, 3D, 96, 6D, 84, 66, C, A5, E, 68, 8C, 1D, D0, D8, 7F, 7A, F4, F4, 5D, 64, DE, 80, D9, 77, 44, 73, EE, 53, A1, B9, 3A, 9F, 7E, F, C6, 4A, AB, 71, BB, 69, B7, FF, 7D, BF, DF, 47, A4, 45, 51, 6, F6, FD, 4B, DA, 9, E0, 31, 15, D2, 88, 98, 77, AD, CC, 80, 9, EC, 1C, C4, B3, 39, 2B, CF, 9D, 23, D, EA, 35, FF, B, 50, 50, 91, 4A, 56, 38, F3, 27, B9, 17, A5, FD, 1F, A4, CE, 2E, FB, CB, 3E, AD, 6D, E0, 58, 18, C3, DB, 45, C1, D3, 80, 50, 4F, D, 41, 8B, 4C, B, 2F, 0, A0, 93, 50, 2D, 78, C4, DD, 26, 51, 5B, 68, ED, 0, 60, BB, F2, A8, 1F, 7F, 4A, BE, B9, 87, 2B, F4, E1, 99, 8, A5, 4, B1, 4E, 1E, 2C, AE, 4C, 62, 66, D7, EA, D6, 22, 35, C2, D6, 1A, C, 81, DF, 34, 3, D1, 8C, 4A, A5, 95, BB, 87, CD, AE, D0, 89, D9, B3, B7, 34, 19, 77, 52, D, 80, F2, E3, 5E, C2, 69, E1, 70, 99, 6E, D9, EE, B5, 74, 4, D4, B3, E, DE, 4D, 4A, BC, 2B, 72, BC, A8, 43, 1D, F, CB, 62, B2, 97, 92, 5A, F7, BC, B4, 7F, 48, 9B, 6C, 89, 1C, 34, 33, AF, AC, 35, 80, 92, 51, 3E, 39, C1, E4, F9, 15, 46, 94, A3, 3F, E0, 7C, F5, 3A, 62, 64, 70, 71, C9, 90, 79, 3D, A6, 1D, FF, 3A, 16, CB, 5A, EE, CC, 36, 8B, E7, A, E4, 5, 43, 93, 91, 42, 4D, 98, 71, A0, 16, 89, 33, D2, 82, EB, E5, 33, 7, 62, DD, AC, 4E, CD, 24, 3F, 92, FB, 34, 45, 2B, EF, 81, 7C, 1B, B1, 76, 11, 5A, 42, 53, C4, BD, 63, FF, E1, 66, E6, 22, 96, 54, 9E, E7, A, C4, 0, 5E, AB, D, 6F, BD, 3C, A2, EF, 86, 86, 57, 48, F9, 3, 8A, F2, 10, 97, 1A, F3, A4, 64, 73, 32, C4, EC, C0, DC, 1D, 38, 94, 9D, A2, 38, D8, 69, 26, A7, 9, 3A, EA, B, 39, 41, 72, 2C, 1D, DB, 2B, 2A, 1E, CE, BF, 7, 9D, CF, 81, A7, B0, 65, D8, 88, 6B, 9C, 4E, 3B, A6, C8, 9B], Context("Failed to match any known bcmedia"))] }
[2023-02-26T02:52:28Z INFO  neolink::rtsp::states] driveway: Connecting to camera at UID: <redacted>
[2023-02-26T02:52:28Z INFO  neolink_core::bc_protocol] Local discovery success <redacted> at 192.168.21.38:34892
[2023-02-26T02:52:28Z INFO  neolink::rtsp::states] driveway: Logging in
[2023-02-26T02:52:29Z INFO  neolink::rtsp::states] driveway: Successfully logged in
[2023-02-26T02:52:29Z INFO  neolink::rtsp::states] driveway: Camera time is already set: 2023-02-25 21:52:37 -5
[2023-02-26T02:52:30Z INFO  neolink::rtsp::states] driveway: Camera reports firmware version v3.0.0.973_22051000
[2023-02-26T02:52:30Z INFO  neolink::rtsp::states] driveway: Starting stream
[2023-02-26T02:52:30Z INFO  neolink::rtsp::states::streaming] driveway: Starting video stream Main Stream (Clear)
[2023-02-26T02:52:30Z INFO  neolink::rtsp::states] driveway: Successfully started streaming
^C
QuantumEntangledAndy commented 1 year ago

Know issue I'm working on. It's the one where the camera seems to send us data not related to the stream. I think I'll have to add error recovery to the stream and instead advance the bytes one at a time until I can find the next good packet.

QuantumEntangledAndy commented 1 year ago

Ok this should now be addressed in feature/passdown. I've added error recovery in the event that the stream is not as expected by advancing one byte in the stream until we get a known BcMedia magic header

dkerr64 commented 1 year ago

Now I am seeing warning messages. But I expect this is what you intend...

[2023-02-28T02:59:20Z INFO  neolink::rtsp::states::streaming] driveway: Starting video stream Main Stream (Clear)
[2023-02-28T02:59:20Z INFO  neolink::rtsp::states] driveway: Successfully started streaming
[2023-02-28T03:00:18Z WARN  neolink_core::bc_protocol::connection::bcconn] Reaching limit of channel
[2023-02-28T03:00:18Z WARN  neolink_core::bc_protocol::connection::bcconn] Remaining: 0 of 100 message space for 3 (ID: 3)
[2023-02-28T03:01:03Z WARN  neolink_core::bc_protocol::connection::bcconn] Reaching limit of channel
[2023-02-28T03:01:03Z WARN  neolink_core::bc_protocol::connection::bcconn] Remaining: 0 of 100 message space for 3 (ID: 3)

My VLC client sometimes has trouble connecting to the neolink stream. I have not established a pattern yet, but it is not a new problem.

QuantumEntangledAndy commented 1 year ago

Yea this is the next issue I'm trying to address only it seems a bit random. Sometimes the packets aren't being pulled off from the stream and fed into gsteamer fast enough and I'm not sure why.

QuantumEntangledAndy commented 1 year ago

Have you had the Reaching limit of channel message in awhile? I think it might be fixed but its one of those errors that are hard to replicate on demand

dkerr64 commented 1 year ago

I am not running rtsp much now that image is working. I am not able to test with rtsp until next week. I think I occasionally got it in the past with image but not recently.

Is there any way we can get RC codes other than 0 or 1? I have image running every 10 minutes. Occasionally it fails in which case I log the RC and stderr text to syslog (linux logger command). It looks like the log text is truncated and often the real cause is quite a long way in.

Here is example of failure (recent one where now fails if port specified in config) followed by success after I removed that.

Mar  7 03:01:26 pbx user.debug poll-cameras: Camera capture activated, enable capture of 'slow' cameras
Mar  7 03:01:32 pbx user.err poll-cameras: Capture for camera 0 failed with neolink return code 1
Mar  7 03:01:32 pbx user.err poll-cameras: [2023-03-07T08:01:26Z INFO neolink] Neolink 0.5.5 (unknown commit) release [2023-03-07T08:01:26Z INFO neolink::utils] driveway: Connecting to camera at Address: 192.168.21.38:2015, UID: <redacted> [2023-03-07T08:01:28Z INFO neolink_core::bc_protocol] driveway: Remote discovery success <redacted> at 192.168.21.38:26194 [2023-03-07T08:01:31Z INFO neolink::utils] driveway: Logging in Error: Failed to connect to the camera, check credentials and network Caused by: 
Mar  7 03:05:31 pbx user.debug poll-cameras: Camera 0; Temperature 0°C, Battery 98% 

You can see logger text ends at "Caused by:"

David

QuantumEntangledAndy commented 1 year ago

Are you grepping this? Because there should be another line underneath caused by

dkerr64 commented 1 year ago

It looks like I need to replace newline characters with a space to get it all into the log.

QuantumEntangledAndy commented 1 year ago

The caused by errors don't really help. I added them to see what the chain of errors were but learned that they are just the same information as the error that makes it but broken up into smaller chunks. Ive been considering removing them so that the the logs are on one line anyways.

What rc do you want? Some sort of distinction between non retryable? I think currently we consider everything retryable except connection and login errors.

dkerr64 commented 1 year ago

It looks like I need to replace newline characters with a space to get it all into the log.

It turns out my problem is that the syslogd I am using (part of busybox) is truncating messages at ~250 characters. I need to build busybox with larger buffer size to accommodate longer messages.

eddbeale commented 1 year ago

I have been seeing the

Have you had the Reaching limit of channel message in awhile? I think it might be fixed but its one of those errors that are hard to replicate on demand

I've been getting this error after a few hours of streaming with the 0.5.5 release on my arm64 raspberrypi. It seems to happen eventually every time (I've ended up setting it to reboot every 6 hours to keep it 'alive' beyond this).

I can grab logs or whatever, or try new builds, if you can give me a few details if what you'd like me to do/try.

QuantumEntangledAndy commented 1 year ago

I'm working on a full rework of the gstreamer part of the code.

This happens when the gstreamer part of the code isn't pulling the packets from the camera quickly enough so hopefully I can fix that with this.

The buffer is currently set to 100 messages. Where each message contains at maximum and IFrame. Im not sure why it's not pulling that off the queue quickly enough at times. I could increase the size of the buffer but I need to make sure that won't just hide the issue for later.

Veuchez commented 1 year ago

I agree with the problem, with Argus 3 PRO I see the subtream flow all jerky even if the cam has excellent wifi coverage. The mainstream stream is unwatchable. After about a minute, this warning appears and streaming stops.

[2023-04-03T10:17:29Z INFO  neolink::rtsp::states::streaming] ingresso: Starting video stream Main Stream (Clear)
[2023-04-03T10:17:29Z INFO  neolink::rtsp::states::streaming] ingresso: Starting video stream Sub Stream (Fluent)
[2023-04-03T10:17:29Z INFO  neolink::rtsp::states] ingresso: Successfully started streaming
[2023-04-03T10:18:22Z WARN  neolink_core::bc_protocol::connection::bcconn] Reaching limit of channel
[2023-04-03T10:18:22Z WARN  neolink_core::bc_protocol::connection::bcconn] Remaining: 0 of 100 message space for 3 (ID: 3)
[2023-04-03T10:24:56Z ERROR neolink_core::bc_protocol::connection::bcconn] Subscription error: Io(Custom { kind: Other, error: CameraTerminate })
[2023-04-03T10:24:56Z WARN  neolink::rtsp] ingresso: Retryable error: Camera has unexpectanely stopped the streaming state: On stream: Main

    Caused by:
        0: IO Error: Custom { kind: Other, error: Io(Custom { kind: Other, error: CameraTerminate }) }
        1: IO Error: Custom { kind: Other, error: CameraTerminate }
        2: Camera terminated the connection

Additionally what are the requirements to run Neolink on a VM with Ubuntu and 5 Reolink Argus 3 PRO? Can the jerky streaming depend on the hardware configuration of the VM?

QuantumEntangledAndy commented 1 year ago

I've never really bench marked the code. So not sure about the requirements. It usually runs ok on my rpi 3b though.

Veuchez commented 1 year ago

With the latest release these errors are gone. The video quality has greatly improved although the video stream is still a bit jerky. Great work, I hope you can improve the video stream even more to the same smoothness as the Reolink app.

Veuchez commented 1 year ago

@QuantumEntangledAndy After some time the video stream stops and errors come out which close neolink. If I launch neolink with GST_DEBUG=3 I see this:

roberto@neolink:/NEOLINK$ GST_DEBUG=3 ./neolink rtsp --config=neolink.toml
[2023-04-14T19:04:47Z INFO  neolink] Neolink a424fa55c0bcfdf9519b4361992ba57c928c9995 release
[2023-04-14T19:04:47Z WARN  neolink::rtsp::gst::factory] Constructing Factor Impl
[2023-04-14T19:04:47Z WARN  neolink::rtsp::gst::factory] Constructing Factor Impl
[2023-04-14T19:04:47Z INFO  neolink::rtsp] Starting RTSP Server at 0.0.0.0:8554
[2023-04-14T19:04:47Z INFO  neolink_core::bc_protocol] ingresso: Local discovery success 95270002NXXXXXF4 at 192.168.2.101:59469
[2023-04-14T19:04:48Z INFO  neolink::rtsp::states::loggedin] ingresso: Camera time is already set: 2023-04-14 21:04:48 +1
[2023-04-14T19:04:49Z INFO  neolink::rtsp::states::loggedin] ingresso: Camera reports firmware version v3.0.0.907_22042201
[2023-04-14T19:04:49Z INFO  neolink::rtsp::states::streaming] ingresso: Starting video stream Main Stream (Clear)
[2023-04-14T19:04:49Z INFO  neolink::rtsp::states::streaming] ingresso: Starting video stream Sub Stream (Fluent)
0:00:08.588299619  4762 0x7fe11c00d6a0 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<audsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:00:08.588723670  4762 0x7fe11c010240 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<vidsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:00:08.592059350  4762 0x7fe130005920 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
0:00:08.592831594  4762 0x7fe130005920 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
0:00:08.592856322  4762 0x7fe130005920 WARN               rtspmedia rtsp-media.c:4623:gst_rtsp_media_suspend: media 0x7fe1240841b0 was not prepared
0:00:08.598445882  4762 0x7fe130005920 FIXME              rtspmedia rtsp-media.c:2884:gst_rtsp_media_seek_trickmode:<GstRTSPMedia@0x7fe1240841b0> Handle going back to 0 for none live not seekable streams.

After some time these warnings:


0:04:38.050202238  4857 0x7f5650005920 WARN               rtspmedia rtsp-media.c:4935:gst_rtsp_media_set_state: media 0x7f56440591d0 was not prepared
0:04:39.675262658  4857 0x7f5644003180 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<audsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:04:39.675824822  4857 0x7f56380104c0 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<vidsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:04:39.678833100  4857 0x7f5638010300 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixingStyling with Markdown is supported

0:04:39.680051387  4857 0x7f5638010300 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
0:04:39.680069812  4857 0x7f5638010300 WARN               rtspmedia rtsp-media.c:4623:gst_rtsp_media_suspend: media 0x7f563c01c1c0 was not prepared
0:04:39.682871826  4857 0x7f5638010300 FIXME              rtspmedia rtsp-media.c:2884:gst_rtsp_media_seek_trickmode:<GstRTSPMedia@0x7f563c01c1c0> Handle going back to 0 for none live not seekable streams.
0:04:39.846818968  4857 0x7f5638010300 WARN               rtspmedia rtsp-media.c:4935:gst_rtsp_media_set_state: media 0x7f563c01c1c0 was not prepared
0:04:39.849970722  4857 0x7f5644003180 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<audsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:04:39.850202799  4857 0x7f56380104c0 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<vidsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:04:39.851690604  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
0:04:39.852373747  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
0:04:39.852392766  4857 0x7f563c0175e0 WARN               rtspmedia rtsp-media.c:4623:gst_rtsp_media_suspend: media 0x7f563803f1d0 was not prepared
0:04:39.854524861  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:2884:gst_rtsp_media_seek_trickmode:<GstRTSPMedia@0x7f563803f1d0> Handle going back to 0 for none live not seekable streams.

and then again..


0:30:12.798349742  4857 0x7f5644003180 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<audsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:30:12.799036543  4857 0x7f56380104c0 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<vidsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:30:12.800585800  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
0:30:12.801577514  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
0:30:12.801597425  4857 0x7f563c0175e0 WARN               rtspmedia rtsp-media.c:4623:gst_rtsp_media_suspend: media 0x7f563803f3c0 was not prepared
0:30:12.804064802  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:2884:gst_rtsp_media_seek_trickmode:<GstRTSPMedia@0x7f563803f3c0> Handle going back to 0 for none live not seekable streams.
[2023-04-14T19:41:45Z INFO  neolink::rtsp] Join Pause
[2023-04-14T19:41:45Z WARN  neolink::rtsp] ingresso: Retryable error: ingresso: Error while streaming

    Caused by:
        deadline has elapsed
[2023-04-14T19:41:47Z INFO  neolink_core::bc_protocol] ingresso: Local discovery success 95270002NFXXXXX4 at 192.168.2.101:59469
[2023-04-14T19:41:49Z INFO  neolink::rtsp::states::loggedin] ingresso: Camera time is already set: 2023-04-14 21:41:48 +1
[2023-04-14T19:41:49Z INFO  neolink::rtsp::states::loggedin] ingresso: Camera reports firmware version v3.0.0.907_22042201
[2023-04-14T19:41:49Z INFO  neolink::rtsp::states::streaming] ingresso: Starting video stream Sub Stream (Fluent)
[2023-04-14T19:41:49Z INFO  neolink::rtsp::states::streaming] ingresso: Starting video stream Main Stream (Clear)
0:30:33.794228459  4857 0x7f563c0175e0 WARN               rtspmedia rtsp-media.c:4935:gst_rtsp_media_set_state: media 0x7f563803f3c0 was not prepared
0:30:33.797201967  4857 0x7f563c017400 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<audsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:30:33.797273708  4857 0x7f5638010460 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<vidsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:30:33.813657346  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
0:30:33.814547495  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
0:30:33.814564672  4857 0x7f563c0175e0 WARN               rtspmedia rtsp-media.c:4623:gst_rtsp_media_suspend: media 0x7f563803f5b0 was not prepared
0:30:33.816677851  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:2884:gst_rtsp_media_seek_trickmode:<GstRTSPMedia@0x7f563803f5b0> Handle going back to 0 for none live not seekable streams.
1:00:30.788595228  4857 0x7f563c0175e0 WARN               rtspmedia rtsp-media.c:4935:gst_rtsp_media_set_state: media 0x7f563803f5b0 was not prepared
1:00:30.794960043  4857 0x7f5644003180 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<audsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
1:00:30.795748472  4857 0x7f5638010460 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<vidsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
1:00:30.797450355  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
1:00:30.798491541  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
1:00:30.798509230  4857 0x7f563c0175e0 WARN               rtspmedia rtsp-media.c:4623:gst_rtsp_media_suspend: media 0x7f563803f7a0 was not prepared
1:00:30.801459458  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:2884:gst_rtsp_media_seek_trickmode:<GstRTSPMedia@0x7f563803f7a0> Handle going back to 0 for none live not seekable streams.
[2023-04-14T20:12:03Z INFO  neolink::rtsp] Join Pause
[2023-04-14T20:12:03Z WARN  neolink::rtsp] ingresso: Retryable error: ingresso: Error while streaming

    Caused by:
        deadline has elapsed
[2023-04-14T20:12:05Z INFO  neolink_core::bc_protocol] ingresso: Local discovery success 95270002NFXXXXX4 at 192.168.2.101:59469
[2023-04-14T20:12:06Z INFO  neolink::rtsp::states::loggedin] ingresso: Camera time is already set: 2023-04-14 22:12:05 +1
[2023-04-14T20:12:07Z INFO  neolink::rtsp::states::loggedin] ingresso: Camera reports firmware version v3.0.0.907_22042201
[2023-04-14T20:12:07Z INFO  neolink::rtsp::states::streaming] ingresso: Starting video stream Sub Stream (Fluent)
[2023-04-14T20:12:07Z INFO  neolink::rtsp::states::streaming] ingresso: Starting video stream Main Stream (Clear)
1:00:52.752628101  4857 0x7f563c0175e0 WARN               rtspmedia rtsp-media.c:4935:gst_rtsp_media_set_state: media 0x7f563803f7a0 was not prepared
1:00:52.754838634  4857 0x7f563c017400 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<audsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
1:00:52.754911819  4857 0x7f5638010460 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<vidsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
1:00:52.756728041  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
1:00:52.757447304  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
1:00:52.757463611  4857 0x7f563c0175e0 WARN               rtspmedia rtsp-media.c:4623:gst_rtsp_media_suspend: media 0x7f56380521c0 was not prepared
1:00:52.759785652  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:2884:gst_rtsp_media_seek_trickmode:<GstRTSPMedia@0x7f56380521c0> Handle going back to 0 for none live not seekable streams.
1:30:48.740760627  4857 0x7f563c0175e0 WARN               rtspmedia rtsp-media.c:4935:gst_rtsp_media_set_state: media 0x7f56380521c0 was not prepared
1:30:48.748425111  4857 0x7f563c017400 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<audsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
1:30:48.748775524  4857 0x7f56380104c0 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<vidsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
1:30:48.766339921  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
1:30:48.767847005  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
1:30:48.767870093  4857 0x7f563c0175e0 WARN               rtspmedia rtsp-media.c:4623:gst_rtsp_media_suspend: media 0x7f56380523b0 was not prepared
1:30:48.770887191  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:2884:gst_rtsp_media_seek_trickmode:<GstRTSPMedia@0x7f56380523b0> Handle going back to 0 for none live not seekable streams.
[2023-04-14T20:42:20Z INFO  neolink::rtsp] Join Pause
[2023-04-14T20:42:20Z WARN  neolink::rtsp] ingresso: Retryable error: ingresso: Error while streaming

    Caused by:
        deadline has elapsed
[2023-04-14T20:42:23Z INFO  neolink_core::bc_protocol] ingresso: Local discovery success 95270002NFXXXXX4 at 192.168.2.101:59469
[2023-04-14T20:42:24Z INFO  neolink::rtsp::states::loggedin] ingresso: Camera time is already set: 2023-04-14 22:42:23 +1
[2023-04-14T20:42:25Z INFO  neolink::rtsp::states::loggedin] ingresso: Camera reports firmware version v3.0.0.907_22042201
[2023-04-14T20:42:25Z INFO  neolink::rtsp::states::streaming] ingresso: Starting video stream Sub Stream (Fluent)
[2023-04-14T20:42:25Z INFO  neolink::rtsp::states::streaming] ingresso: Starting video stream Main Stream (Clear)
1:31:09.720289251  4857 0x7f563c0175e0 WARN               rtspmedia rtsp-media.c:4935:gst_rtsp_media_set_state: media 0x7f56380523b0 was not prepared
1:31:09.722659990  4857 0x7f5638010460 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<audsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
1:31:09.722744269  4857 0x7f5644003180 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<vidsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
1:31:09.725876210  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
1:31:09.726792817  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
1:31:09.726808637  4857 0x7f563c0175e0 WARN               rtspmedia rtsp-media.c:4623:gst_rtsp_media_suspend: media 0x7f56380525a0 was not prepared
1:31:09.729131990  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:2884:gst_rtsp_media_seek_trickmode:<GstRTSPMedia@0x7f56380525a0> Handle going back to 0 for none live not seekable streams.
2:01:05.781022122  4857 0x7f563c0175e0 WARN               rtspmedia rtsp-media.c:4935:gst_rtsp_media_set_state: media 0x7f56380525a0 was not prepared
2:01:05.787536537  4857 0x7f5638010460 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<audsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
2:01:05.788477933  4857 0x7f5644003180 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<vidsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
2:01:05.790276115  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
2:01:05.791278355  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
2:01:05.791293958  4857 0x7f563c0175e0 WARN               rtspmedia rtsp-media.c:4623:gst_rtsp_media_suspend: media 0x7f5638052790 was not prepared
2:01:05.793648541  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:2884:gst_rtsp_media_seek_trickmode:<GstRTSPMedia@0x7f5638052790> Handle going back to 0 for none live not seekable streams.
[2023-04-14T21:12:38Z INFO  neolink::rtsp] Join Pause
[2023-04-14T21:12:38Z WARN  neolink::rtsp] ingresso: Retryable error: ingresso: Error while streaming

    Caused by:
        deadline has elapsed
[2023-04-14T21:12:40Z INFO  neolink_core::bc_protocol] ingresso: Local discovery success 95270002NFXXXXX4 at 192.168.2.101:59469
[2023-04-14T21:12:41Z INFO  neolink::rtsp::states::loggedin] ingresso: Camera time is already set: 2023-04-14 23:12:40 +1
[2023-04-14T21:12:42Z INFO  neolink::rtsp::states::loggedin] ingresso: Camera reports firmware version v3.0.0.907_22042201
[2023-04-14T21:12:42Z INFO  neolink::rtsp::states::streaming] ingresso: Starting video stream Sub Stream (Fluent)
[2023-04-14T21:12:42Z INFO  neolink::rtsp::states::streaming] ingresso: Starting video stream Main Stream (Clear)
2:01:26.786095073  4857 0x7f563c0175e0 WARN               rtspmedia rtsp-media.c:4935:gst_rtsp_media_set_state: media 0x7f5638052790 was not prepared
2:01:26.788309750  4857 0x7f56380104c0 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<audsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
2:01:26.788327092  4857 0x7f563c017400 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<vidsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
2:01:26.799820132  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
2:01:26.800648410  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
2:01:26.800666753  4857 0x7f563c0175e0 WARN               rtspmedia rtsp-media.c:4623:gst_rtsp_media_suspend: media 0x7f563803f5b0 was not prepared
2:01:26.803036848  4857 0x7f563c0175e0 FIXME              rtspmedia rtsp-media.c:2884:gst_rtsp_media_seek_trickmode:<GstRTSPMedia@0x7f563803f5b0> Handle going back to 0 for none live not seekable streams.
QuantumEntangledAndy commented 1 year ago

I think I have mostly addressed this deadline has elapsed in the latest neolink. I haven't had it in awhile since the new buffer model but perhaps you can confirm?

Veuchez commented 1 year ago

Actually I still see errors, it disconnects, the video stream is better but still jerky. In the video stream I have the date and time with the seconds, sometimes the seconds go backwards, sometimes they jump forward several seconds... Then I launch a test with GST_DEBUG=3 and I place the result.

Veuchez commented 1 year ago
roberto@neolink:~/neolink_linux_x86_64_ubuntu$ GST_DEBUG=3 ./neolink rtsp --config=neolink.toml
[2023-04-20T19:23:12Z INFO  neolink] Neolink 42ff221b0735bf55fbf9ba2508329ef1fdb540e9 release
[2023-04-20T19:23:12Z INFO  neolink::rtsp] Starting RTSP Server at 0.0.0.0:8554
[2023-04-20T19:23:12Z INFO  neolink_core::bc_protocol] ingresso: Local discovery success 95270002NXXXXX4 at 192.168.2.101:40916
[2023-04-20T19:23:13Z INFO  neolink::rtsp::states::loggedin] ingresso: Camera time is already set: 2023-04-20 21:23:19.0 -01:00:00
[2023-04-20T19:23:13Z INFO  neolink::rtsp::states::loggedin] ingresso: Camera reports firmware version v3.0.0.907_22042201
[2023-04-20T19:23:13Z INFO  neolink::rtsp::states::streaming] ingresso: Starting video stream Sub Stream (Fluent)
[2023-04-20T19:23:13Z INFO  neolink::rtsp::states::streaming] ingresso: Starting video stream Main Stream (Clear)
0:00:08.049379469  1416 0x7f112c00d700 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<audsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:00:08.050022947  1416 0x7f112c010240 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<vidsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:00:08.054101298  1416 0x7f113c005920 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
0:00:08.054873773  1416 0x7f113c005920 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
0:00:08.054892775  1416 0x7f113c005920 WARN               rtspmedia rtsp-media.c:4623:gst_rtsp_media_suspend: media 0x7f11400831b0 was not prepared
0:00:08.056595794  1416 0x7f113c005920 FIXME              rtspmedia rtsp-media.c:2884:gst_rtsp_media_seek_trickmode:<GstRTSPMedia@0x7f11400831b0> Handle going back to 0 for none live not seekable streams.
0:00:08.056802715  1416 0x7f112c00d700 WARN                 basesrc gstbasesrc.c:3127:gst_base_src_loop:<audsrc> error: Internal data stream error.
0:00:08.056818391  1416 0x7f112c00d700 WARN                 basesrc gstbasesrc.c:3127:gst_base_src_loop:<audsrc> error: streaming stopped, reason not-linked (-1)
0:00:08.056852210  1416 0x7f112c00d700 WARN                   queue gstqueue.c:992:gst_queue_handle_sink_event:<audqueue> error: Internal data stream error.
0:00:08.056864270  1416 0x7f112c00d700 WARN                   queue gstqueue.c:992:gst_queue_handle_sink_event:<audqueue> error: streaming stopped, reason not-linked (-1)
0:00:08.056886635  1416 0x7f11400031e0 WARN               rtspmedia rtsp-media.c:3268:default_handle_message: 0x7f11400831b0: got error Internal data stream error. (../libs/gst/base/gstbasesrc.c(3127): gst_base_src_loop (): /GstPipeline:media-pipeline/GstBin:bin0/GstAppSrc:audsrc:
streaming stopped, reason not-linked (-1))
0:00:08.056921672  1416 0x7f11400031e0 WARN               rtspmedia rtsp-media.c:3268:default_handle_message: 0x7f11400831b0: got error Internal data stream error. (../plugins/elements/gstqueue.c(992): gst_queue_handle_sink_event (): /GstPipeline:media-pipeline/GstBin:bin0/GstQueue:audqueue:
streaming stopped, reason not-linked (-1))
0:00:14.625050515  1416 0x7f1134066640 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<audsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:00:14.625550289  1416 0x7f1114023cc0 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<vidsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:00:14.627805893  1416 0x7f113c005920 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
0:00:14.637104875  1416 0x7f113c005920 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
0:00:14.637122102  1416 0x7f113c005920 WARN               rtspmedia rtsp-media.c:4623:gst_rtsp_media_suspend: media 0x7f11400833a0 was not prepared
0:00:14.654459414  1416 0x7f113c005920 FIXME              rtspmedia rtsp-media.c:2884:gst_rtsp_media_seek_trickmode:<GstRTSPMedia@0x7f11400833a0> Handle going back to 0 for none live not seekable streams.
0:00:24.320356271  1416 0x7f113c005920 WARN               rtspmedia rtsp-media.c:4935:gst_rtsp_media_set_state: media 0x7f11400833a0 was not prepared
Veuchez commented 1 year ago
[2023-04-20T19:25:11Z INFO  neolink::rtsp::states::loggedin] ingresso: Camera time is already set: 2023-04-20 21:25:18.0 -01:00:00
[2023-04-20T19:25:12Z INFO  neolink::rtsp::states::loggedin] ingresso: Camera reports firmware version v3.0.0.907_22042201
[2023-04-20T19:25:12Z INFO  neolink::rtsp::states::streaming] ingresso: Starting video stream Sub Stream (Fluent)
[2023-04-20T19:25:12Z INFO  neolink::rtsp::states::streaming] ingresso: Starting video stream Main Stream (Clear)
0:00:07.788299606  1529 0x7fd13400d6a0 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<audsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:00:07.789426699  1529 0x7fd134010240 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<vidsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:00:07.793607076  1529 0x7fd144005920 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
0:00:07.794921674  1529 0x7fd144005920 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
0:00:07.794965629  1529 0x7fd144005920 WARN               rtspmedia rtsp-media.c:4623:gst_rtsp_media_suspend: media 0x7fd13c0841b0 was not prepared
0:00:07.797823668  1529 0x7fd144005920 FIXME              rtspmedia rtsp-media.c:2884:gst_rtsp_media_seek_trickmode:<GstRTSPMedia@0x7fd13c0841b0> Handle going back to 0 for none live not seekable streams.
0:00:07.856133529  1529 0x7fd13400d6a0 WARN                 basesrc gstbasesrc.c:3127:gst_base_src_loop:<audsrc> error: Internal data stream error.
0:00:07.856181325  1529 0x7fd13400d6a0 WARN                 basesrc gstbasesrc.c:3127:gst_base_src_loop:<audsrc> error: streaming stopped, reason not-linked (-1)
0:00:07.856268667  1529 0x7fd13400d6a0 WARN                   queue gstqueue.c:992:gst_queue_handle_sink_event:<audqueue> error: Internal data stream error.
0:00:07.856280889  1529 0x7fd13400d6a0 WARN                   queue gstqueue.c:992:gst_queue_handle_sink_event:<audqueue> error: streaming stopped, reason not-linked (-1)
0:00:07.856295930  1529 0x7fd13c0031e0 WARN               rtspmedia rtsp-media.c:3268:default_handle_message: 0x7fd13c0841b0: got error Internal data stream error. (../libs/gst/base/gstbasesrc.c(3127): gst_base_src_loop (): /GstPipeline:media-pipeline/GstBin:bin0/GstAppSrc:audsrc:
streaming stopped, reason not-linked (-1))
0:00:07.856326893  1529 0x7fd13c0031e0 WARN               rtspmedia rtsp-media.c:3268:default_handle_message: 0x7fd13c0841b0: got error Internal data stream error. (../plugins/elements/gstqueue.c(992): gst_queue_handle_sink_event (): /GstPipeline:media-pipeline/GstBin:bin0/GstQueue:audqueue:
streaming stopped, reason not-linked (-1))
0:02:02.592107011  1529 0x7fd11001cd20 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<audsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:02:02.592596537  1529 0x7fd11001cf00 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<vidsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:02:02.594957384  1529 0x7fd144005920 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
0:02:02.642985537  1529 0x7fd144005920 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
0:02:02.643139983  1529 0x7fd144005920 WARN               rtspmedia rtsp-media.c:4623:gst_rtsp_media_suspend: media 0x7fd13c0843a0 was not prepared
0:02:02.656638159  1529 0x7fd144005920 FIXME              rtspmedia rtsp-media.c:2884:gst_rtsp_media_seek_trickmode:<GstRTSPMedia@0x7fd13c0843a0> Handle going back to 0 for none live not seekable streams.
0:02:02.663084609  1529 0x7fd11001ccc0 WARN            audiodecoder gstaudiodecoder.c:1790:gst_audio_decoder_drain:<faad1> still 1 frames left after draining
0:02:02.663171575  1529 0x7fd11001ccc0 WARN            audiodecoder gstaudiodecoder.c:1790:gst_audio_decoder_drain:<faad1> still 1 frames left after draining
0:02:26.555418915  1529 0x7fd144005920 WARN               rtspmedia rtsp-media.c:4935:gst_rtsp_media_set_state: media 0x7fd13c0843a0 was not prepared
0:30:12.732491822  1529 0x7fd144005920 WARN               rtspmedia rtsp-media.c:4941:gst_rtsp_media_set_state: media 0x7fd13c0841b0 in error status while changing to state 1
0:30:12.732570904  1529 0x7fd144005920 WARN               rtspmedia rtsp-media.c:4941:gst_rtsp_media_set_state: media 0x7fd13c0841b0 in error status while changing to state 1
0:30:12.746887510  1529 0x7fd13400d6a0 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<audsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:30:12.747162010  1529 0x7fd134010240 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<vidsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:30:12.748985235  1529 0x7fd144005920 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
0:30:12.750201408  1529 0x7fd144005920 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
0:30:12.750216894  1529 0x7fd144005920 WARN               rtspmedia rtsp-media.c:4623:gst_rtsp_media_suspend: media 0x7fd13c084590 was not prepared
0:30:12.751388933  1529 0x7fd144005920 FIXME              rtspmedia rtsp-media.c:2884:gst_rtsp_media_seek_trickmode:<GstRTSPMedia@0x7fd13c084590> Handle going back to 0 for none live not seekable streams.
0:30:12.751556657  1529 0x7fd13400d6a0 WARN                 basesrc gstbasesrc.c:3127:gst_base_src_loop:<audsrc> error: Internal data stream error.
0:30:12.751569286  1529 0x7fd13400d6a0 WARN                 basesrc gstbasesrc.c:3127:gst_base_src_loop:<audsrc> error: streaming stopped, reason not-linked (-1)
0:30:12.751594114  1529 0x7fd13400d6a0 WARN                   queue gstqueue.c:992:gst_queue_handle_sink_event:<audqueue> error: Internal data stream error.
0:30:12.751600840  1529 0x7fd13400d6a0 WARN                   queue gstqueue.c:992:gst_queue_handle_sink_event:<audqueue> error: streaming stopped, reason not-linked (-1)
0:30:12.751688103  1529 0x7fd13c0031e0 WARN               rtspmedia rtsp-media.c:3268:default_handle_message: 0x7fd13c084590: got error Internal data stream error. (../libs/gst/base/gstbasesrc.c(3127): gst_base_src_loop (): /GstPipeline:media-pipeline/GstBin:bin2/GstAppSrc:audsrc:
streaming stopped, reason not-linked (-1))
0:30:12.751773595  1529 0x7fd13c0031e0 WARN               rtspmedia rtsp-media.c:3268:default_handle_message: 0x7fd13c084590: got error Internal data stream error. (../plugins/elements/gstqueue.c(992): gst_queue_handle_sink_event (): /GstPipeline:media-pipeline/GstBin:bin2/GstQueue:audqueue:
streaming stopped, reason not-linked (-1))
[2023-04-20T19:55:25Z INFO  neolink::rtsp] Join Pause
[2023-04-20T19:55:25Z WARN  neolink::rtsp] ingresso: Retryable error: ingresso: Error while streaming

    Caused by:
        deadline has elapsed
[2023-04-20T19:55:27Z INFO  neolink_core::bc_protocol] ingresso: Local discovery success 95270002NXXXXX4 at 192.168.2.101:40916
[2023-04-20T19:55:28Z INFO  neolink::rtsp::states::loggedin] ingresso: Camera time is already set: 2023-04-20 21:55:35.0 -01:00:00
[2023-04-20T19:55:29Z INFO  neolink::rtsp::states::loggedin] ingresso: Camera reports firmware version v3.0.0.907_22042201
[2023-04-20T19:55:29Z INFO  neolink::rtsp::states::streaming] ingresso: Starting video stream Sub Stream (Fluent)
[2023-04-20T19:55:29Z INFO  neolink::rtsp::states::streaming] ingresso: Starting video stream Main Stream (Clear)
0:30:33.709873665  1529 0x7fd144005920 WARN               rtspmedia rtsp-media.c:4941:gst_rtsp_media_set_state: media 0x7fd13c084590 in error status while changing to state 1
0:30:33.710008199  1529 0x7fd144005920 WARN               rtspmedia rtsp-media.c:4941:gst_rtsp_media_set_state: media 0x7fd13c084590 in error status while changing to state 1
0:30:33.736266880  1529 0x7fd13400d6a0 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<audsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:30:33.736850316  1529 0x7fd134010240 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<vidsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:30:33.742431544  1529 0x7fd144005920 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
0:30:33.745266159  1529 0x7fd144005920 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
0:30:33.745343502  1529 0x7fd144005920 WARN               rtspmedia rtsp-media.c:4623:gst_rtsp_media_suspend: media 0x7fd13c084780 was not prepared
0:30:33.749219038  1529 0x7fd144005920 FIXME              rtspmedia rtsp-media.c:2884:gst_rtsp_media_seek_trickmode:<GstRTSPMedia@0x7fd13c084780> Handle going back to 0 for none live not seekable streams.
0:30:33.749744247  1529 0x7fd13400d6a0 WARN                 basesrc gstbasesrc.c:3127:gst_base_src_loop:<audsrc> error: Internal data stream error.
0:30:33.749792043  1529 0x7fd13400d6a0 WARN                 basesrc gstbasesrc.c:3127:gst_base_src_loop:<audsrc> error: streaming stopped, reason not-linked (-1)
0:30:33.749862230  1529 0x7fd13400d6a0 WARN                   queue gstqueue.c:992:gst_queue_handle_sink_event:<audqueue> error: Internal data stream error.
0:30:33.749921282  1529 0x7fd13400d6a0 WARN                   queue gstqueue.c:992:gst_queue_handle_sink_event:<audqueue> error: streaming stopped, reason not-linked (-1)
0:30:33.751093534  1529 0x7fd13c0031e0 WARN               rtspmedia rtsp-media.c:3268:default_handle_message: 0x7fd13c084780: got error Internal data stream error. (../libs/gst/base/gstbasesrc.c(3127): gst_base_src_loop (): /GstPipeline:media-pipeline/GstBin:bin3/GstAppSrc:audsrc:
streaming stopped, reason not-linked (-1))
0:30:33.751174945  1529 0x7fd13c0031e0 WARN               rtspmedia rtsp-media.c:3268:default_handle_message: 0x7fd13c084780: got error Internal data stream error. (../plugins/elements/gstqueue.c(992): gst_queue_handle_sink_event (): /GstPipeline:media-pipeline/GstBin:bin3/GstQueue:audqueue:
streaming stopped, reason not-linked (-1))
1:00:29.787099059  1529 0x7fd144005920 WARN               rtspmedia rtsp-media.c:4941:gst_rtsp_media_set_state: media 0x7fd13c084780 in error status while changing to state 1
1:00:29.787163873  1529 0x7fd144005920 WARN               rtspmedia rtsp-media.c:4941:gst_rtsp_media_set_state: media 0x7fd13c084780 in error status while changing to state 1
1:00:29.807105161  1529 0x7fd13400d6a0 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<audsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
1:00:29.807832745  1529 0x7fd134010240 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<vidsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
1:00:29.810055211  1529 0x7fd144005920 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
1:00:29.812239063  1529 0x7fd144005920 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
1:00:29.812260218  1529 0x7fd144005920 WARN               rtspmedia rtsp-media.c:4623:gst_rtsp_media_suspend: media 0x7fd13c084780 was not prepared
1:00:29.813992921  1529 0x7fd144005920 FIXME              rtspmedia rtsp-media.c:2884:gst_rtsp_media_seek_trickmode:<GstRTSPMedia@0x7fd13c084780> Handle going back to 0 for none live not seekable streams.
1:00:29.814209097  1529 0x7fd13400d6a0 WARN                 basesrc gstbasesrc.c:3127:gst_base_src_loop:<audsrc> error: Internal data stream error.
1:00:29.814229850  1529 0x7fd13400d6a0 WARN                 basesrc gstbasesrc.c:3127:gst_base_src_loop:<audsrc> error: streaming stopped, reason not-linked (-1)
1:00:29.814264837  1529 0x7fd13400d6a0 WARN                   queue gstqueue.c:992:gst_queue_handle_sink_event:<audqueue> error: Internal data stream error.
1:00:29.814275683  1529 0x7fd13400d6a0 WARN                   queue gstqueue.c:992:gst_queue_handle_sink_event:<audqueue> error: streaming stopped, reason not-linked (-1)
1:00:29.814611781  1529 0x7fd13c0031e0 WARN               rtspmedia rtsp-media.c:3268:default_handle_message: 0x7fd13c084780: got error Internal data stream error. (../libs/gst/base/gstbasesrc.c(3127): gst_base_src_loop (): /GstPipeline:media-pipeline/GstBin:bin4/GstAppSrc:audsrc:
streaming stopped, reason not-linked (-1))
1:00:29.814637013  1529 0x7fd13c0031e0 WARN               rtspmedia rtsp-media.c:3268:default_handle_message: 0x7fd13c084780: got error Internal data stream error. (../plugins/elements/gstqueue.c(992): gst_queue_handle_sink_event (): /GstPipeline:media-pipeline/GstBin:bin4/GstQueue:audqueue:
streaming stopped, reason not-linked (-1))
[2023-04-20T20:25:42Z INFO  neolink::rtsp] Join Pause
[2023-04-20T20:25:42Z WARN  neolink::rtsp] ingresso: Retryable error: ingresso: Error while streaming

    Caused by:
        deadline has elapsed
[2023-04-20T20:25:47Z WARN  neolink::rtsp] ingresso: Retryable error: ingresso: Could not connect to camera

    Caused by:
        0: Failed to connect to camera ingresso at Address: 192.168.2.101, UID: 95270002NXXXXX4 on channel 0
        1: Cannot contact camera at given address
[2023-04-20T20:25:51Z INFO  neolink_core::bc_protocol] ingresso: Local discovery success 95270002NXXXXX4 at 192.168.2.101:40916
[2023-04-20T20:25:53Z INFO  neolink::rtsp::states::loggedin] ingresso: Camera time is already set: 2023-04-20 22:26:01.0 -01:00:00
[2023-04-20T20:25:54Z INFO  neolink::rtsp::states::loggedin] ingresso: Camera reports firmware version v3.0.0.907_22042201
[2023-04-20T20:25:54Z INFO  neolink::rtsp::states::streaming] ingresso: Starting video stream Sub Stream (Fluent)
[2023-04-20T20:25:54Z INFO  neolink::rtsp::states::streaming] ingresso: Starting video stream Main Stream (Clear)
1:00:50.759996056  1529 0x7fd144005920 WARN               rtspmedia rtsp-media.c:4941:gst_rtsp_media_set_state: media 0x7fd13c084780 in error status while changing to state 1
1:00:50.760119503  1529 0x7fd144005920 WARN               rtspmedia rtsp-media.c:4941:gst_rtsp_media_set_state: media 0x7fd13c084780 in error status while changing to state 1
1:00:50.766910600  1529 0x7fd13400d6a0 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<audsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
1:00:50.766995013  1529 0x7fd134010240 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<vidsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
1:00:50.768494836  1529 0x7fd144005920 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
1:00:50.769252809  1529 0x7fd144005920 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
1:00:50.769268647  1529 0x7fd144005920 WARN               rtspmedia rtsp-media.c:4623:gst_rtsp_media_suspend: media 0x7fd13c084590 was not prepared
1:00:50.770750398  1529 0x7fd144005920 FIXME              rtspmedia rtsp-media.c:2884:gst_rtsp_media_seek_trickmode:<GstRTSPMedia@0x7fd13c084590> Handle going back to 0 for none live not seekable streams.
1:00:50.771123380  1529 0x7fd13400d6a0 WARN                 basesrc gstbasesrc.c:3127:gst_base_src_loop:<audsrc> error: Internal data stream error.
1:00:50.771156103  1529 0x7fd13400d6a0 WARN                 basesrc gstbasesrc.c:3127:gst_base_src_loop:<audsrc> error: streaming stopped, reason not-linked (-1)
1:00:50.771211864  1529 0x7fd13400d6a0 WARN                   queue gstqueue.c:992:gst_queue_handle_sink_event:<audqueue> error: Internal data stream error.
1:00:50.771217175  1529 0x7fd13c0031e0 WARN               rtspmedia rtsp-media.c:3268:default_handle_message: 0x7fd13c084590: got error Internal data stream error. (../libs/gst/base/gstbasesrc.c(3127): gst_base_src_loop (): /GstPipeline:media-pipeline/GstBin:bin5/GstAppSrc:audsrc:
streaming stopped, reason not-linked (-1))
1:00:50.771239956  1529 0x7fd13400d6a0 WARN                   queue gstqueue.c:992:gst_queue_handle_sink_event:<audqueue> error: streaming stopped, reason not-linked (-1)
1:00:50.771353097  1529 0x7fd13c0031e0 WARN               rtspmedia rtsp-media.c:3268:default_handle_message: 0x7fd13c084590: got error Internal data stream error. (../plugins/elements/gstqueue.c(992): gst_queue_handle_sink_event (): /GstPipeline:media-pipeline/GstBin:bin5/GstQueue:audqueue:
streaming stopped, reason not-linked (-1))
1:01:11.759712102  1529 0x7fd144005920 WARN               rtspmedia rtsp-media.c:4941:gst_rtsp_media_set_state: media 0x7fd13c084590 in error status while changing to state 1
1:01:11.759783568  1529 0x7fd144005920 WARN               rtspmedia rtsp-media.c:4941:gst_rtsp_media_set_state: media 0x7fd13c084590 in error status while changing to state 1
1:01:11.764368284  1529 0x7fd13400d6a0 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<audsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
1:01:11.764433785  1529 0x7fd134010240 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<vidsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
1:01:11.765896582  1529 0x7fd144005920 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
1:01:11.766631073  1529 0x7fd144005920 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
1:01:11.766645856  1529 0x7fd144005920 WARN               rtspmedia rtsp-media.c:4623:gst_rtsp_media_suspend: media 0x7fd13c0841b0 was not prepared
1:01:11.768195791  1529 0x7fd144005920 FIXME              rtspmedia rtsp-media.c:2884:gst_rtsp_media_seek_trickmode:<GstRTSPMedia@0x7fd13c0841b0> Handle going back to 0 for none live not seekable streams.
1:01:11.768394102  1529 0x7fd13400d6a0 WARN                 basesrc gstbasesrc.c:3127:gst_base_src_loop:<audsrc> error: Internal data stream error.
1:01:11.768418084  1529 0x7fd13400d6a0 WARN                 basesrc gstbasesrc.c:3127:gst_base_src_loop:<audsrc> error: streaming stopped, reason not-linked (-1)
1:01:11.768450030  1529 0x7fd13400d6a0 WARN                   queue gstqueue.c:992:gst_queue_handle_sink_event:<audqueue> error: Internal data stream error.
1:01:11.768463865  1529 0x7fd13400d6a0 WARN                   queue gstqueue.c:992:gst_queue_handle_sink_event:<audqueue> error: streaming stopped, reason not-linked (-1)
1:01:11.768464531  1529 0x7fd13c0031e0 WARN               rtspmedia rtsp-media.c:3268:default_handle_message: 0x7fd13c0841b0: got error Internal data stream error. (../libs/gst/base/gstbasesrc.c(3127): gst_base_src_loop (): /GstPipeline:media-pipeline/GstBin:bin6/GstAppSrc:audsrc:
streaming stopped, reason not-linked (-1))
1:01:11.768499363  1529 0x7fd13c0031e0 WARN               rtspmedia rtsp-media.c:3268:default_handle_message: 0x7fd13c0841b0: got error Internal data stream error. (../plugins/elements/gstqueue.c(992): gst_queue_handle_sink_event (): /GstPipeline:media-pipeline/GstBin:bin6/GstQueue:audqueue:
streaming stopped, reason not-linked (-1))
1:10:46.253678884  1529 0x7fd134029d80 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<audsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
1:10:46.254005678  1529 0x7fd1380344c0 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<vidsrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
1:10:46.255965823  1529 0x7fd144005920 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
1:10:46.261283878  1529 0x7fd144005920 FIXME              rtspmedia rtsp-media.c:4584:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
1:10:46.261302751  1529 0x7fd144005920 WARN               rtspmedia rtsp-media.c:4623:gst_rtsp_media_suspend: media 0x7fd13c0843a0 was not prepared
1:10:46.276188270  1529 0x7fd144005920 FIXME              rtspmedia rtsp-media.c:2884:gst_rtsp_media_seek_trickmode:<GstRTSPMedia@0x7fd13c0843a0> Handle going back to 0 for none live not seekable streams.
1:10:46.319846957  1529 0x7fd114004f60 WARN            audiodecoder gstaudiodecoder.c:1790:gst_audio_decoder_drain:<faad7> still 1 frames left after draining
1:10:46.319912536  1529 0x7fd114004f60 WARN            audiodecoder gstaudiodecoder.c:1790:gst_audio_decoder_drain:<faad7> still 1 frames left after draining
1:11:04.050490054  1529 0x7fd144005920 WARN               rtspmedia rtsp-media.c:4935:gst_rtsp_media_set_state: media 0x7fd13c0843a0 was not prepared
QuantumEntangledAndy commented 1 year ago

Is this VLC jerkeyness? In vlc with time jumping please see #68. Still working on that bit

QuantumEntangledAndy commented 1 year ago

@dkerr64 could you test with the latest build? I've reworked the buffer code to help imporve the stream and i think your original issue with the unrecognized bcmedia has been partially addressed even if the underlying issue still needs some work. If you don't get back within a week I'll probably close this issue

dkerr64 commented 1 year ago

@QuantumEntangledAndy sorry I have not commented earlier on your more recent updates. I've been using the image capture feature you added for me and it is working really well.

For the RTSP stream, I just synced and built the code as it exists today in master. Things have hugely improved, I especially like that the video stream starts almost instantly. But I still see issues. I am testing with a Reolink Argus 3 pro and VLC as the client (which I am running on macOS).

Without pause... If I start the stream before the "Buffers prepared" message then VLC starts up but shows "stream not ready" on a background of old fashioned video noise. It does not seem to recover even after the buffers prepared message.

If I wait for "buffers prepared" then things are pretty good, but choppy. Definitely not as smooth as the native iOS client. And after a few moments a stream of error messages starts to log (but the VLC is still showing live video)... as I write this it has been running for 20 minutes which it would never do before.

[2023-05-14T15:17:17Z INFO  neolink_core::bc_protocol] driveway: Trying TCP discovery
[2023-05-14T15:17:17Z INFO  neolink_core::bc_protocol] driveway: Trying local discovery
[2023-05-14T15:17:17Z INFO  neolink_core::bc_protocol] driveway: Local discovery success <redacted> at 192.168.12.177:36696
[2023-05-14T15:17:18Z INFO  neolink::rtsp::states::loggedin] driveway: Camera time is already set: 2023-05-14 11:17:21.0 +05:00:00
[2023-05-14T15:17:19Z INFO  neolink::rtsp::states::loggedin] driveway: Camera reports firmware version v3.0.0.973_22051000
[2023-05-14T15:17:19Z INFO  neolink::rtsp::states::streaming] driveway: Starting video stream Main Stream (Clear)
[2023-05-14T15:17:29Z INFO  neolink::rtsp::gst::server] Removing Session Media
[2023-05-14T15:17:29Z INFO  neolink::rtsp] driveway: Buffers prepared
[2023-05-14T15:17:58Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:17:58Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:17:58Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:17:58Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:17:58Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:17:58Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:17:58Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:17:58Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:17:58Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:17:58Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.

With pause... Setting this in my config file...

  [cameras.pause]
  on_motion = true
  on_client = true

Similar pattern to above. I let Neolink startup and go into pause state then open up the VLC stream. It starts up very quickly and again I get the error messages. But in this case it stops streaming after about 10-15 seconds with no message from Neolink, but the VLC window just closes....

[2023-05-14T15:43:48Z INFO  neolink] Neolink 0.5.10 (unknown commit) release
[2023-05-14T15:43:48Z INFO  neolink::rtsp] Starting RTSP Server at 0.0.0.0:8554
[2023-05-14T15:43:48Z INFO  neolink_core::bc_protocol] driveway: Trying TCP discovery
[2023-05-14T15:43:49Z INFO  neolink_core::bc_protocol] driveway: Trying local discovery
[2023-05-14T15:43:51Z INFO  neolink_core::bc_protocol] driveway: Local discovery success <redacted> at 192.168.12.177:36696
[2023-05-14T15:43:52Z INFO  neolink::rtsp::states::loggedin] driveway: Camera time is already set: 2023-05-14 11:43:54.0 +05:00:00
[2023-05-14T15:43:52Z INFO  neolink::rtsp::states::loggedin] driveway: Camera reports firmware version v3.0.0.973_22051000
[2023-05-14T15:43:52Z INFO  neolink::rtsp::states::streaming] driveway: Starting video stream Main Stream (Clear)
[2023-05-14T15:44:02Z INFO  neolink::rtsp] driveway: Buffers prepared
[2023-05-14T15:44:02Z INFO  neolink::rtsp] Client Pause
[2023-05-14T15:44:23Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:44:23Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:44:23Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:44:23Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:44:23Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:44:23Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:44:23Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
.
.
.
[2023-05-14T15:44:35Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:44:35Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:44:35Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:44:35Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
<and it ends>
<8 minutes later I started VLC streaming again>
[2023-05-14T15:52:22Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:52:22Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:52:22Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:52:22Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
.
.
.
[2023-05-14T15:52:34Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:52:34Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:52:34Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:52:34Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:52:34Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera
<which worked for all of 12 seconds>

Neolink does not print out any message that it is pausing the stream. But if I look at the network traffic it does seem to have stoped (below you can see the ~20 minute long first test, a short break, then "pause" test which is very brief.

image
dkerr64 commented 1 year ago

@QuantumEntangledAndy if I run the tests with a hardwired camera then without pause it is working well, no error messages...

[2023-05-14T15:55:42Z INFO  neolink] Neolink 0.5.10 (unknown commit) release
[2023-05-14T15:55:42Z INFO  neolink::rtsp] Starting RTSP Server at 0.0.0.0:8554
[2023-05-14T15:55:42Z INFO  neolink_core::bc_protocol] driveway: Trying TCP discovery
[2023-05-14T15:55:45Z INFO  neolink_core::bc_protocol] driveway: TCP Discovery success at 192.168.21.35:9000
[2023-05-14T15:55:47Z INFO  neolink::rtsp::states::loggedin] driveway: Camera time is already set: 2023-05-14 11:55:47.0 +05:00:00
[2023-05-14T15:55:47Z INFO  neolink::rtsp::states::loggedin] driveway: Camera reports firmware version v3.0.0.136_20121112
[2023-05-14T15:55:47Z INFO  neolink::rtsp::states::streaming] driveway: Starting video stream Main Stream (Clear)
[2023-05-14T15:55:53Z INFO  neolink::rtsp] driveway: Buffers prepared

(neolink:8055): GStreamer-CRITICAL **: 11:56:50.251: gst_mini_object_unref: assertion 'mini_object != NULL' failed

That last error came when I shutdown the VLC client.

But with pause, I have the same problem as the WiFi attached Argus 3 pro... error messages and then the VLC window closes down.

[2023-05-14T15:57:12Z INFO  neolink] Neolink 0.5.10 (unknown commit) release
[2023-05-14T15:57:12Z INFO  neolink::rtsp] Starting RTSP Server at 0.0.0.0:8554
[2023-05-14T15:57:12Z INFO  neolink_core::bc_protocol] driveway: Trying TCP discovery
[2023-05-14T15:57:12Z INFO  neolink_core::bc_protocol] driveway: TCP Discovery success at 192.168.21.35:9000
[2023-05-14T15:57:12Z INFO  neolink::rtsp::states::loggedin] driveway: Camera time is already set: 2023-05-14 11:57:12.0 +05:00:00
[2023-05-14T15:57:12Z INFO  neolink::rtsp::states::loggedin] driveway: Camera reports firmware version v3.0.0.136_20121112
[2023-05-14T15:57:12Z INFO  neolink::rtsp::states::streaming] driveway: Starting video stream Main Stream (Clear)
[2023-05-14T15:57:18Z INFO  neolink::rtsp] driveway: Buffers prepared
[2023-05-14T15:57:18Z INFO  neolink::rtsp] Client Pause
[2023-05-14T15:57:28Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:57:28Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:57:28Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:57:28Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:57:28Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
[2023-05-14T15:57:28Z ERROR neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera.
QuantumEntangledAndy commented 1 year ago

Oh yes we added that message while testing for buffer issues where it would drain.

After pause the buffer should drain out since no new frames are comming in

I'll see if I can add some way to handle that situation better

QuantumEntangledAndy commented 1 year ago

Ok this is being addressed in #82

QuantumEntangledAndy commented 1 year ago

Ok test ready could you see if this build helps for you? Docker is here if you use that docker pull quantumentangledandy/neolink:fix-pause_buffers

QuantumEntangledAndy commented 1 year ago

I recommend you test without motion pause first just client. Also be aware that during a pause VLC is likely to drop the connection for inactivity, use ffplay or something to handle that better

dkerr64 commented 1 year ago

@QuantumEntangledAndy Looking good. Not perfect, but a lot better... two runs here, first using the wired camera, second with the WiFi attached Argus 3 pro...

david@neolink:~/github/neolink$ ~/github/neolink/target/release/neolink rtsp --config=/home/david/neolink.toml
[2023-05-15T23:57:10Z INFO  neolink] Neolink 0.5.10 (unknown commit) release
[2023-05-15T23:57:10Z INFO  neolink::rtsp] Starting RTSP Server at 0.0.0.0:8554
[2023-05-15T23:57:10Z INFO  neolink_core::bc_protocol] driveway: Trying TCP discovery
[2023-05-15T23:57:11Z INFO  neolink_core::bc_protocol] driveway: TCP Discovery success at 192.168.21.35:9000
[2023-05-15T23:57:13Z INFO  neolink::rtsp::states::loggedin] driveway: Camera time is already set: 2023-05-15 19:57:13.0 +05:00:00
[2023-05-15T23:57:13Z INFO  neolink::rtsp::states::loggedin] driveway: Camera reports firmware version v3.0.0.136_20121112
[2023-05-15T23:57:13Z INFO  neolink::rtsp::states::streaming] driveway: Starting video stream Main Stream (Clear)
[2023-05-15T23:57:19Z INFO  neolink::rtsp] driveway: Buffers prepared
[2023-05-15T23:57:19Z INFO  neolink::rtsp] Client Pause
[2023-05-15T23:57:24Z INFO  neolink::rtsp] Client Resume
[2023-05-15T23:57:24Z INFO  neolink::rtsp::states::streaming] driveway: Starting video stream Main Stream (Clear)
[2023-05-15T23:57:24Z WARN  neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera. Pausing RTSP until refilled.
[2023-05-15T23:57:58Z INFO  neolink::rtsp] Client Pause
[2023-05-15T23:58:10Z INFO  neolink::rtsp] Client Resume
[2023-05-15T23:58:10Z INFO  neolink::rtsp::states::streaming] driveway: Starting video stream Main Stream (Clear)
[2023-05-15T23:58:11Z WARN  neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera. Pausing RTSP until refilled.

(neolink:17867): GStreamer-CRITICAL **: 19:58:49.821: gst_mini_object_unref: assertion 'mini_object != NULL' failed
[2023-05-15T23:58:49Z INFO  neolink::rtsp] Client Pause
^C
david@neolink:~/github/neolink$ nano /home/david/neolink.toml
david@neolink:~/github/neolink$
david@neolink:~/github/neolink$ ~/github/neolink/target/release/neolink rtsp --config=/home/david/neolink.toml
[2023-05-15T23:59:13Z INFO  neolink] Neolink 0.5.10 (unknown commit) release
[2023-05-15T23:59:13Z INFO  neolink::rtsp] Starting RTSP Server at 0.0.0.0:8554
[2023-05-15T23:59:13Z INFO  neolink_core::bc_protocol] driveway: Trying TCP discovery
[2023-05-15T23:59:14Z INFO  neolink_core::bc_protocol] driveway: Trying local discovery
[2023-05-15T23:59:15Z INFO  neolink_core::bc_protocol] driveway: Local discovery success <redacted> at 192.168.12.177:13455
[2023-05-15T23:59:17Z WARN  neolink::rtsp] driveway: Retryable error: driveway: Could not login to camera

    Caused by:
        Communication error
[2023-05-15T23:59:17Z INFO  neolink_core::bc_protocol] driveway: Trying TCP discovery
[2023-05-15T23:59:17Z INFO  neolink_core::bc_protocol] driveway: Trying local discovery
[2023-05-15T23:59:17Z INFO  neolink_core::bc_protocol] driveway: Local discovery success <redacted> at 192.168.12.177:13455
[2023-05-15T23:59:18Z WARN  neolink::rtsp] driveway: Retryable error: driveway: Could not login to camera

    Caused by:
        Communication error
[2023-05-15T23:59:18Z INFO  neolink_core::bc_protocol] driveway: Trying TCP discovery
[2023-05-15T23:59:18Z INFO  neolink_core::bc_protocol] driveway: Trying local discovery
[2023-05-15T23:59:18Z INFO  neolink_core::bc_protocol] driveway: Local discovery success <redacted> at 192.168.12.177:13455
[2023-05-15T23:59:19Z WARN  neolink::rtsp] driveway: Retryable error: driveway: Could not login to camera

    Caused by:
        Communication error
[2023-05-15T23:59:19Z INFO  neolink_core::bc_protocol] driveway: Trying TCP discovery
[2023-05-15T23:59:19Z INFO  neolink_core::bc_protocol] driveway: Trying local discovery
[2023-05-15T23:59:19Z INFO  neolink_core::bc_protocol] driveway: Local discovery success <redacted> at 192.168.12.177:13455
[2023-05-15T23:59:20Z WARN  neolink::rtsp] driveway: Retryable error: driveway: Could not login to camera

    Caused by:
        Communication error
[2023-05-15T23:59:20Z INFO  neolink_core::bc_protocol] driveway: Trying TCP discovery
[2023-05-15T23:59:20Z INFO  neolink_core::bc_protocol] driveway: Trying local discovery
[2023-05-15T23:59:20Z INFO  neolink_core::bc_protocol] driveway: Local discovery success <redacted> at 192.168.12.177:13455
[2023-05-15T23:59:21Z WARN  neolink::rtsp] driveway: Retryable error: driveway: Could not login to camera

    Caused by:
        Communication error
[2023-05-15T23:59:21Z INFO  neolink_core::bc_protocol] driveway: Trying TCP discovery
[2023-05-15T23:59:21Z INFO  neolink_core::bc_protocol] driveway: Trying local discovery
[2023-05-15T23:59:21Z INFO  neolink_core::bc_protocol] driveway: Local discovery success <redacted> at 192.168.12.177:13455
[2023-05-15T23:59:22Z INFO  neolink::rtsp::states::loggedin] driveway: Camera time is already set: 2023-05-15 19:59:28.0 +05:00:00
[2023-05-15T23:59:23Z INFO  neolink::rtsp::states::loggedin] driveway: Camera reports firmware version v3.0.0.973_22051000
[2023-05-15T23:59:23Z INFO  neolink::rtsp::states::streaming] driveway: Starting video stream Main Stream (Clear)
[2023-05-15T23:59:31Z INFO  neolink::rtsp] driveway: Buffers prepared
[2023-05-15T23:59:31Z INFO  neolink::rtsp] Client Pause
[2023-05-15T23:59:42Z INFO  neolink::rtsp] Client Resume
[2023-05-15T23:59:42Z INFO  neolink::rtsp::states::streaming] driveway: Starting video stream Main Stream (Clear)
[2023-05-15T23:59:43Z WARN  neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera. Pausing RTSP until refilled.
[2023-05-16T00:00:24Z WARN  neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera. Pausing RTSP until refilled.
[2023-05-16T00:01:08Z WARN  neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera. Pausing RTSP until refilled.
[2023-05-16T00:01:30Z WARN  neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera. Pausing RTSP until refilled.
[2023-05-16T00:02:03Z WARN  neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera. Pausing RTSP until refilled.
[2023-05-16T00:02:44Z WARN  neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera. Pausing RTSP until refilled.
[2023-05-16T00:03:14Z WARN  neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera. Pausing RTSP until refilled.
[2023-05-16T00:03:19Z WARN  neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera. Pausing RTSP until refilled.
[2023-05-16T00:03:33Z INFO  neolink::rtsp] Client Pause
^C
david@neolink:~/github/neolink$

So you can see that I get Buffer exhausted messages, more so with the WiFi attached. But the VLC client stays up the whole time, it does not shut down. When it resumes the audio seems to start first, then video follows. It takes about 10 seconds to fully recover (video starts) from buffer exhausted. I also noticed at one point where video seemed to play faster than realtime (that is the seconds counter on the video ticks up faster than every second). Possibly because the video is trying to catch up with the audio which started playing before the video did.

You can see it takes a few attempts to login to the camera, but that is not new. I see this a lot but have not bothered to try and track down why.

The Gstreamer error seems to be a side affect of me closing the VLC client, but it does not seem to impact the server, can reconnect.

David.

QuantumEntangledAndy commented 1 year ago

I see, I am trying to track down why the buffer is being exhausted and what can be done with it. From my tests the camera is only sending 46s of video in a real time period of 63s. Theres just not enough frames comming in to be live

QuantumEntangledAndy commented 1 year ago

Oddly the buffer is ok when I connect via relay but it drains quickly over TCP. TCP should be the more stable direct connection... It's really weird

QuantumEntangledAndy commented 1 year ago

Can you try again with the latest build in that PR. Found a possible bug in the last one related to the time re-stamping after a pause that might cause the buffer to empty.

dkerr64 commented 1 year ago

@QuantumEntangledAndy looking good. Occasional buffer exhausted message but in general is running well, I only saw it once in a 5 minute run. I only tried with the Argus 3 pro as it is late here.

I notice that the playback is delayed by almost 20 seconds from real time, maybe due to the buffering. The native client has under 2 second delay.

But congrats on getting it to work way more reliably.

QuantumEntangledAndy commented 1 year ago

I can try reducing the size of the buffer that should reduce the latency, just can be difficult to find the middle ground between latency and enough buffer for camera issues. I might try some std dev caluclations to find out the variance in the buffer size under normal operations

dkerr64 commented 1 year ago

The log…

david@neolink:~/github/neolink$ ~/github/neolink/target/release/neolink rtsp --config=/home/david/neolink.toml
[2023-05-16T02:53:12Z INFO  neolink] Neolink 0.5.10 (unknown commit) release
[2023-05-16T02:53:12Z INFO  neolink::rtsp] Starting RTSP Server at 0.0.0.0:8554
[2023-05-16T02:53:12Z INFO  neolink_core::bc_protocol] driveway: Trying TCP discovery
[2023-05-16T02:53:12Z INFO  neolink_core::bc_protocol] driveway: Trying local discovery
[2023-05-16T02:53:14Z INFO  neolink_core::bc_protocol] driveway: Local discovery success xxxxxx at 192.168.12.177:13455
[2023-05-16T02:53:15Z INFO  neolink::rtsp::states::loggedin] driveway: Camera time is already set: 2023-05-15 22:53:21.0 +05:00:00
[2023-05-16T02:53:16Z INFO  neolink::rtsp::states::loggedin] driveway: Camera reports firmware version v3.0.0.973_22051000
[2023-05-16T02:53:16Z INFO  neolink::rtsp::states::streaming] driveway: Starting video stream Main Stream (Clear)
[2023-05-16T02:53:24Z INFO  neolink::rtsp] driveway: Buffers prepared
[2023-05-16T02:53:24Z INFO  neolink::rtsp] Client Pause
[2023-05-16T02:54:00Z INFO  neolink::rtsp] Client Resume
[2023-05-16T02:54:00Z INFO  neolink::rtsp::states::streaming] driveway: Starting video stream Main Stream (Clear)
[2023-05-16T02:54:11Z WARN  neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera. Pausing RTSP until refilled.
[2023-05-16T02:56:59Z WARN  neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera. Pausing RTSP until refilled.
[2023-05-16T02:57:25Z INFO  neolink::rtsp] Client Pause
[2023-05-16T02:57:53Z INFO  neolink::rtsp] Client Resume
[2023-05-16T02:57:53Z INFO  neolink::rtsp::states::streaming] driveway: Starting video stream Main Stream (Clear)
[2023-05-16T03:00:09Z WARN  neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera. Pausing RTSP until refilled.
[2023-05-16T03:01:55Z INFO  neolink::rtsp] Client Pause
dkerr64 commented 1 year ago

I can try reducing the size of the buffer that should reduce the latency, just can be difficult to find the middle ground between latency and enough buffer for camera issues. I might try some std dev caluclations to find out the variance in the buffer size under normal operations

Definitely worth trying and maybe consider making it a config setting… some cameras may be better than others and work well with a smaller buffer.

QuantumEntangledAndy commented 1 year ago

Still buffer exhausted. Can you run with debug logging?

RUST_LOG=neolink=debug ~/github/neolink/target/release/neolink rtsp --config=/home/david/neolink.toml
QuantumEntangledAndy commented 1 year ago

In the debug logs you can also see Minimum Latency time which is a hint for the delay that the buffer time introduces

dkerr64 commented 1 year ago

Doing this all from an iPad so my Terminal scroll back is not very big, but I captured this. I see an over full message, perhaps the buffer is not emptying fast enough?

[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Buffer size: 257
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Buffer size: 257
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Got frame at 41.377644s
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Got frame at 41.460977s
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Buffer size: 262
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Buffer size: 262
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Buffer size: 262
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Got frame at 41.544309s
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Got frame at 41.627646s
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Buffer size: 267
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Buffer size: 267
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Got frame at 41.710976s
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Got frame at 41.79431s
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Buffer size: 272
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Buffer size: 272
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Buffer size: 272
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Buffer size: 272
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Buffer size: 272
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Got frame at 41.877644s
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Got frame at 41.960978s
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Buffer size: 275
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Buffer size: 275
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Buffer size: 275
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Buffer size: 276
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Buffer size: 276
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Buffer size: 276
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Buffer size: 276
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Buffer size: 276
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Buffer size: 276
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Buffer size: 276
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Buffer size: 276
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Buffer size: 276
[2023-05-16T03:17:53Z DEBUG neolink::rtsp::gst::sender] Buffer size: 276
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Buffer size: 276
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Buffer size: 276
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Buffer size: 276
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Buffer size: 276
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Got frame at 42.044314s
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Buffer size: 278
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Buffer size: 278
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Got frame at 42.127645s
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Got frame at 42.210978s
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Buffer size: 283
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Buffer size: 283
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Buffer size: 283
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Got frame at 42.294312s
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Got frame at 42.377647s
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Buffer size: 288
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Buffer size: 288
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Buffer size: 288
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Got frame at 42.460976s
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Got frame at 42.54431s
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Buffer size: 293
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Buffer size: 293
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Buffer size: 293
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Buffer size: 293
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Got frame at 42.627643s
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Buffer size: 294
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Buffer size: 294
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Got frame at 42.710978s
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Got frame at 42.79431s
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Buffer overfull
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Minimum Latency: 0ns
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Jumped to live: New start time: 32.808139s
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Buffer size: 298
[2023-05-16T03:17:54Z WARN  neolink::rtsp::gst::sender] Buffer exhausted. Not enough data from Camera. Pausing RTSP until refilled.
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Refilling: 0/200 (0.00%)
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Refilling: 0/200 (0.00%)
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Got frame at 42.877644s
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Got frame at 42.960976s
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Refilling: 5/200 (2.50%)
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Refilling: 5/200 (2.50%)
[2023-05-16T03:17:54Z DEBUG neolink::rtsp::gst::sender] Refilling: 5/200 (2.50%)
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Refilling: 5/200 (2.50%)
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Refilling: 5/200 (2.50%)
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Got frame at 43.044313s
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Got frame at 43.127645s
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Refilling: 10/200 (5.00%)
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Refilling: 10/200 (5.00%)
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Refilling: 10/200 (5.00%)
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Got frame at 43.210979s
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Got frame at 43.29431s
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Refilling: 15/200 (7.50%)
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Refilling: 15/200 (7.50%)
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Refilling: 15/200 (7.50%)
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Got frame at 43.377646s
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Got frame at 43.460977s
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Refilling: 20/200 (10.00%)
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Refilling: 20/200 (10.00%)
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Refilling: 20/200 (10.00%)
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Got frame at 43.54431s
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Got frame at 43.627644s
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Refilling: 25/200 (12.50%)
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Refilling: 25/200 (12.50%)
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Got frame at 43.710976s
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Refilling: 26/200 (13.00%)
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Refilling: 26/200 (13.00%)
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Got frame at 43.794312s
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Got frame at 43.877644s
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Refilling: 30/200 (15.00%)
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Refilling: 30/200 (15.00%)
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Refilling: 31/200 (15.50%)
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Refilling: 31/200 (15.50%)
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Got frame at 43.960979s
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Refilling: 33/200 (16.50%)
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Refilling: 33/200 (16.50%)
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Refilling: 33/200 (16.50%)
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Refilling: 33/200 (16.50%)
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Refilling: 33/200 (16.50%)
[2023-05-16T03:17:55Z DEBUG neolink::rtsp::gst::sender] Refilling: 33/200 (16.50%)
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Refilling: 33/200 (16.50%)
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Refilling: 33/200 (16.50%)
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Refilling: 33/200 (16.50%)
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Refilling: 33/200 (16.50%)
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Refilling: 33/200 (16.50%)
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Refilling: 33/200 (16.50%)
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Refilling: 33/200 (16.50%)
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Got frame at 44.04431s
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Refilling: 35/200 (17.50%)
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Refilling: 35/200 (17.50%)
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Got frame at 44.127641s
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Got frame at 44.210976s
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Refilling: 40/200 (20.00%)
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Refilling: 40/200 (20.00%)
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Refilling: 40/200 (20.00%)
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Got frame at 44.29431s
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Got frame at 44.377645s
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Refilling: 45/200 (22.50%)
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Refilling: 45/200 (22.50%)
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Refilling: 45/200 (22.50%)
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Refilling: 45/200 (22.50%)
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Refilling: 45/200 (22.50%)
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Refilling: 45/200 (22.50%)
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Got frame at 44.460978s
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Got frame at 44.544312s
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Refilling: 50/200 (25.00%)
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Refilling: 50/200 (25.00%)
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Got frame at 44.627643s
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Refilling: 51/200 (25.50%)
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Refilling: 51/200 (25.50%)
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Refilling: 51/200 (25.50%)
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Got frame at 44.710977s
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Got frame at 44.794311s
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Refilling: 55/200 (27.50%)
[2023-05-16T03:17:56Z DEBUG neolink::rtsp::gst::sender] Refilling: 55/200 (27.50%)
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Refilling: 55/200 (27.50%)
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Got frame at 44.877647s
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Got frame at 44.960978s
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Refilling: 60/200 (30.00%)
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Refilling: 60/200 (30.00%)
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Got frame at 45.044309s
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Got frame at 45.127643s
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Refilling: 65/200 (32.50%)
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Refilling: 66/200 (33.00%)
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Refilling: 66/200 (33.00%)
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Got frame at 45.210977s
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Got frame at 45.294311s
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Refilling: 70/200 (35.00%)
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Refilling: 71/200 (35.50%)
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Refilling: 71/200 (35.50%)
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Got frame at 45.377642s
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Got frame at 45.460977s
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Refilling: 75/200 (37.50%)
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Got frame at 45.54431s
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Refilling: 78/200 (39.00%)
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Refilling: 78/200 (39.00%)
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Refilling: 78/200 (39.00%)
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Got frame at 45.627642s
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Got frame at 45.710976s
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Refilling: 80/200 (40.00%)
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Refilling: 80/200 (40.00%)
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Refilling: 81/200 (40.50%)
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Refilling: 81/200 (40.50%)
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Got frame at 45.794312s
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Got frame at 45.877643s
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Refilling: 85/200 (42.50%)
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Refilling: 85/200 (42.50%)
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Got frame at 45.96098s
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Refilling: 89/200 (44.50%)
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Refilling: 89/200 (44.50%)
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Refilling: 89/200 (44.50%)
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Refilling: 89/200 (44.50%)
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Refilling: 89/200 (44.50%)
[2023-05-16T03:17:57Z DEBUG neolink::rtsp::gst::sender] Refilling: 89/200 (44.50%)
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Refilling: 89/200 (44.50%)
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Refilling: 89/200 (44.50%)
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Refilling: 89/200 (44.50%)
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Refilling: 89/200 (44.50%)
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Refilling: 89/200 (44.50%)
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Refilling: 89/200 (44.50%)
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Refilling: 89/200 (44.50%)
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Refilling: 89/200 (44.50%)
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Got frame at 46.04431s
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Refilling: 90/200 (45.00%)
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Refilling: 90/200 (45.00%)
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Got frame at 46.127644s
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Got frame at 46.210979s
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Refilling: 95/200 (47.50%)
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Refilling: 96/200 (48.00%)
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Refilling: 96/200 (48.00%)
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Got frame at 46.294311s
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Got frame at 46.377645s
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Refilling: 100/200 (50.00%)
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Refilling: 101/200 (50.50%)
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Refilling: 101/200 (50.50%)
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Got frame at 46.460981s
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Got frame at 46.544311s
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Refilling: 105/200 (52.50%)
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Refilling: 105/200 (52.50%)
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Refilling: 105/200 (52.50%)
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Refilling: 105/200 (52.50%)
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Refilling: 105/200 (52.50%)
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Got frame at 46.627646s
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Got frame at 46.710977s
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Got frame at 46.79431s
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Refilling: 110/200 (55.00%)
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Refilling: 110/200 (55.00%)
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Refilling: 110/200 (55.00%)
[2023-05-16T03:17:58Z DEBUG neolink::rtsp::gst::sender] Refilling: 111/200 (55.50%)
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Refilling: 111/200 (55.50%)
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Refilling: 111/200 (55.50%)
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Got frame at 46.877644s
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Got frame at 46.960979s
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Refilling: 115/200 (57.50%)
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Refilling: 115/200 (57.50%)
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Refilling: 115/200 (57.50%)
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Refilling: 116/200 (58.00%)
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Refilling: 116/200 (58.00%)
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Got frame at 47.044311s
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Got frame at 47.127644s
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Refilling: 120/200 (60.00%)
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Refilling: 121/200 (60.50%)
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Refilling: 121/200 (60.50%)
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Got frame at 47.210976s
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Got frame at 47.29431s
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Refilling: 125/200 (62.50%)
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Refilling: 126/200 (63.00%)
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Refilling: 126/200 (63.00%)
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Got frame at 47.377645s
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Got frame at 47.460978s
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Refilling: 130/200 (65.00%)
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Refilling: 130/200 (65.00%)
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Refilling: 130/200 (65.00%)
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Got frame at 47.54431s
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Got frame at 47.627645s
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Refilling: 135/200 (67.50%)
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Refilling: 135/200 (67.50%)
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Refilling: 135/200 (67.50%)
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Refilling: 135/200 (67.50%)
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Got frame at 47.710979s
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Got frame at 47.794311s
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Refilling: 140/200 (70.00%)
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Refilling: 140/200 (70.00%)
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Refilling: 140/200 (70.00%)
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Refilling: 140/200 (70.00%)
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Refilling: 140/200 (70.00%)
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Got frame at 47.877643s
[2023-05-16T03:17:59Z DEBUG neolink::rtsp::gst::sender] Got frame at 47.960976s
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Refilling: 144/200 (72.00%)
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Refilling: 144/200 (72.00%)
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Refilling: 144/200 (72.00%)
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Refilling: 144/200 (72.00%)
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Refilling: 144/200 (72.00%)
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Refilling: 144/200 (72.00%)
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Refilling: 144/200 (72.00%)
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Refilling: 144/200 (72.00%)
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Refilling: 144/200 (72.00%)
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Refilling: 144/200 (72.00%)
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Refilling: 144/200 (72.00%)
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Refilling: 144/200 (72.00%)
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Refilling: 144/200 (72.00%)
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Got frame at 48.044311s
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Refilling: 145/200 (72.50%)
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Refilling: 145/200 (72.50%)
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Refilling: 145/200 (72.50%)
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Got frame at 48.127644s
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Got frame at 48.210977s
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Refilling: 150/200 (75.00%)
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Refilling: 151/200 (75.50%)
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Refilling: 151/200 (75.50%)
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Got frame at 48.29431s
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Got frame at 48.377647s
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Refilling: 155/200 (77.50%)
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Refilling: 155/200 (77.50%)
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Refilling: 155/200 (77.50%)
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Got frame at 48.460977s
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Got frame at 48.54431s
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Refilling: 160/200 (80.00%)
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Refilling: 160/200 (80.00%)
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Refilling: 160/200 (80.00%)
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Got frame at 48.627646s
[2023-05-16T03:18:00Z DEBUG neolink::rtsp::gst::sender] Got frame at 48.710977s
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Refilling: 165/200 (82.50%)
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Refilling: 165/200 (82.50%)
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Got frame at 48.79431s
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Got frame at 48.877644s
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Refilling: 170/200 (85.00%)
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Refilling: 170/200 (85.00%)
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Refilling: 170/200 (85.00%)
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Refilling: 170/200 (85.00%)
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Refilling: 170/200 (85.00%)
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Got frame at 48.960979s
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Refilling: 171/200 (85.50%)
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Refilling: 171/200 (85.50%)
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Got frame at 49.044311s
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Got frame at 49.127644s
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Refilling: 175/200 (87.50%)
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Refilling: 175/200 (87.50%)
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Refilling: 176/200 (88.00%)
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Refilling: 176/200 (88.00%)
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Got frame at 49.210977s
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Got frame at 49.294309s
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Refilling: 180/200 (90.00%)
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Refilling: 181/200 (90.50%)
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Got frame at 49.377643s
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Got frame at 49.460977s
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Refilling: 185/200 (92.50%)
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Refilling: 185/200 (92.50%)
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Refilling: 185/200 (92.50%)
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Got frame at 49.54431s
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Got frame at 49.627643s
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Refilling: 190/200 (95.00%)
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Refilling: 190/200 (95.00%)
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Refilling: 190/200 (95.00%)
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Got frame at 49.710976s
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Got frame at 49.79431s
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Refilling: 195/200 (97.50%)
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Refilling: 195/200 (97.50%)
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Refilling: 195/200 (97.50%)
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Got frame at 49.877644s
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Got frame at 49.960977s
[2023-05-16T03:18:01Z DEBUG neolink::rtsp::gst::sender] Refilling: 200/200 (100.00%)
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Refilling: 200/200 (100.00%)
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Refilling: 200/200 (100.00%)
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Refilling: 200/200 (100.00%)
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Refilling: 200/200 (100.00%)
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Refilling: 200/200 (100.00%)
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Refilling: 200/200 (100.00%)
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Refilling: 200/200 (100.00%)
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Refilling: 200/200 (100.00%)
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Refilling: 200/200 (100.00%)
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Refilling: 200/200 (100.00%)
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Refilling: 200/200 (100.00%)
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Refilling: 200/200 (100.00%)
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Refilling: 200/200 (100.00%)
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Refilling: 200/200 (100.00%)
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Refilling: 200/200 (100.00%)
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Refilling: 200/200 (100.00%)
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Refilling: 200/200 (100.00%)
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Got frame at 50.04431s
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Got frame at 50.127645s
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Got frame at 50.210977s
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Minimum Latency: 8.240741s
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Jumped to live: New start time: 21.809981s
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Buffer size: 206
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Buffer size: 206
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Got frame at 50.294311s
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Got frame at 50.377642s
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Buffer size: 211
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Buffer size: 211
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Got frame at 50.460978s
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Got frame at 50.54431s
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Buffer size: 215
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Buffer size: 215
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Buffer size: 215
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Got frame at 50.627643s
[2023-05-16T03:18:02Z DEBUG neolink::rtsp::gst::sender] Got frame at 50.710975s
[2023-05-16T03:18:03Z DEBUG neolink::rtsp::gst::sender] Buffer size: 220
[2023-05-16T03:18:03Z DEBUG neolink::rtsp::gst::sender] Buffer size: 220
[2023-05-16T03:18:03Z DEBUG neolink::rtsp::gst::sender] Buffer size: 220
[2023-05-16T03:18:03Z DEBUG neolink::rtsp::gst::sender] Got frame at 50.794309s
[2023-05-16T03:18:03Z DEBUG neolink::rtsp::gst::sender] Got frame at 50.877644s
[2023-05-16T03:18:03Z DEBUG neolink::rtsp::gst::sender] Buffer size: 225
[2023-05-16T03:18:03Z DEBUG neolink::rtsp::gst::sender] Buffer size: 225
[2023-05-16T03:18:03Z DEBUG neolink::rtsp::gst::sender] Buffer size: 225
[2023-05-16T03:18:03Z DEBUG neolink::rtsp::gst::sender] Buffer size: 225
[2023-05-16T03:18:03Z DEBUG neolink::rtsp::gst::sender] Buffer size: 225
[2023-05-16T03:18:03Z DEBUG neolink::rtsp::gst::sender] Got frame at 50.960978s
[2023-05-16T03:18:03Z DEBUG neolink::rtsp::gst::sender] Got frame at 51.044312s
[2023-05-16T03:18:03Z DEBUG neolink::rtsp::gst::sender] Got frame at 51.127644s
QuantumEntangledAndy commented 1 year ago

Hmm something is up it goes from overfull to nothing too quickly. I'll check my code again

dkerr64 commented 1 year ago

Hmm something is up it goes from overfull to nothing too quickly. I'll check my code again

👍 I won’t be able to test again tonight. I’ll check in again tomorrow.

QuantumEntangledAndy commented 1 year ago

Could you check with latest master? Hopefully I got everything sorted out now

dkerr64 commented 1 year ago

@QuantumEntangledAndy looking good. I do get the buffer exhausted message and then a new message that says to try and reduce maximum bitrate. So I did that, from 3Kbps to 2Kbps and at the lower rate things are working well. It feels odd that 3Kbps bitrate would saturate neolink's buffer... it seems to be restricted to the Argus 3 pro as my wired camera RLC-520 is also set to 3Kbps and does not have this problem.

dkerr64 commented 1 year ago

Also... while in pause state, what handshaking, if any, is taking place between neolink and the camera? The other day after I had finished testing, I noticed sometime later that the battery was draining at a much higher pace than normal. This was even after shutting down neolink. Now it may be completely unrelated and coincidental (I notice higher drain when there is a lot of movement in the frame triggering the cameras motion detection) but I wanted to ask and check what power optimizations you have in place. Thanks.

dkerr64 commented 1 year ago

Maybe we need another issue to open for this, but during pause there is still an awful lot of traffic which is definitely draining the camera's battery... 177 is the camera, 185 is the host running neolink.

root@neolink:~# tcpdump -i eth2 host 192.168.12.177
tcpdump: verbose output suppressed, use -v[v]... for full protocol decode
listening on eth2, link-type EN10MB (Ethernet), snapshot length 262144 bytes
14:51:25.584370 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:25.584535 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.584558 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.606978 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:25.607103 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.607127 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.626338 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:25.626501 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.626525 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.664751 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:25.664832 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.664852 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.664860 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.664909 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.684781 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:25.684899 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.684926 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.704440 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:25.704574 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.704595 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.727833 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:25.728010 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.728036 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.764273 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:25.764437 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.764461 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.764475 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.764481 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.784659 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:25.784845 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.784870 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.804635 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:25.804707 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.804746 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.825599 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:25.825641 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.825658 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.865445 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:25.865484 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.865505 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.865514 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.865520 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.884565 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:25.884601 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.884619 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.904323 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:25.904347 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.904364 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.926779 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:25.926806 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.926823 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.976854 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:25.976889 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.976904 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.976915 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.976921 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.976927 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:25.985141 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:25.985168 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.004178 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.004241 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.004260 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.025632 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.025698 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.025723 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.055172 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.055238 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.055256 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.055268 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.076423 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.076462 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.076477 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.114944 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.115006 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.115024 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.115032 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.115038 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.154114 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.154167 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.154185 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.154196 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.154204 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.174619 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.174704 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.174730 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.194261 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.194324 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.194363 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.214812 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.214868 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.214886 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.254303 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.254417 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.254444 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.254458 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.254468 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.275295 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.275389 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.275411 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.296242 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.296340 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.296362 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.334205 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.334365 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.334389 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.334402 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.334409 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.366099 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.366242 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.366268 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.366282 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.377489 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.377611 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.415618 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.415746 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.415768 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.415782 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.415789 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.430469 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.430499 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.434709 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.455271 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.455404 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.455425 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.487201 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.487351 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.487373 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.487382 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.514682 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.514863 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.514889 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.514897 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.534521 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 44
14:51:26.534654 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.534692 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.534697 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.534721 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 44
14:51:26.535518 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.557740 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.557900 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.557926 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.575012 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.575153 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.575175 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.616757 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.616952 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.616988 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.616999 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.617006 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.654452 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.654567 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.654586 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.654595 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.654601 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.675603 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.675748 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.675782 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.704059 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.704202 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.704226 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.704234 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.725912 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.726064 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.726087 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.745245 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.745376 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.745408 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.787295 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.787425 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.787449 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.787462 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.787467 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.804486 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.804636 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.804664 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.827853 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.828025 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.828051 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.864918 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.865091 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.865115 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.865130 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.865136 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.884343 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.884464 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.884484 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.906863 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.907033 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.907064 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.926355 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.926413 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.926431 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.967316 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.967372 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.967390 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.967399 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.967405 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.984262 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:26.984286 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:26.984298 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.006261 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.006293 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.006306 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.025422 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.025446 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.025458 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.054717 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.054780 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.054797 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.054806 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.075778 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.075809 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.075823 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.114155 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.114188 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.114203 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.114211 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.114217 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.124568 ARP, Request who-has 192.168.12.177 (Broadcast) tell 192.168.12.1, length 46
14:51:27.134084 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.134136 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.134156 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.154327 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.154371 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.154388 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.174490 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.174573 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.174593 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.196695 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.196773 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.196795 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.234109 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.234147 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.234167 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.234175 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.234182 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.256635 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.256694 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.256718 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.294260 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.294345 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.294371 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.294384 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.294393 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.316335 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.316402 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.316434 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.334614 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.334693 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.334716 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.359899 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.359982 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.360004 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.360016 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.376871 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.376939 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.418731 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.418802 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.418825 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.418839 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.418846 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.430287 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.430306 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.434235 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.454173 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.454237 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.454258 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.481513 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.481606 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.481643 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.481660 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.514555 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.514794 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.514857 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.514868 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.534317 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.534441 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.534462 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.554363 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.554532 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.554556 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.577383 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.577719 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.577870 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.614535 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.614677 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.614699 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.614714 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.614721 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.634664 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.634755 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.634774 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.654514 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.654680 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.654705 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.675618 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.675779 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.675806 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.694892 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.695069 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.695107 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.733912 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.734071 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.734096 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.734109 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.734116 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.754606 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.754787 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.754816 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.774812 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.774985 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.775009 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.795640 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.795822 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.795886 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.829365 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.829475 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.829509 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.829522 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.864350 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.864484 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.864514 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.864522 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.864529 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.884148 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.884223 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.884259 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.904196 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.904252 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.904273 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.930012 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.930036 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.930044 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.931174 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.964267 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.964329 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.964344 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.964353 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.984201 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
14:51:27.984270 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:27.984283 IP 192.168.12.185.53681 > 192.168.12.177.21046: UDP, length 28
14:51:28.004446 IP 192.168.12.177.21046 > 192.168.12.185.53681: UDP, length 28
QuantumEntangledAndy commented 1 year ago

I'm aware. Pause is not a real pause because of the need to stay connected to monitor the motion alerts.

There's a PR open to address this atm #85 and I'm working on something for client pause too.

dkerr64 commented 1 year ago

I'm aware. Pause is not a real pause because of the need to stay connected to monitor the motion alerts.

There's a PR open to address this atm #85 and I'm working on something for client pause too.

This does seem consistent with the native iOS client. Pause streaming from the camera, or go to settings, or even go to view another camera and those 28-byte length UDP packets just keep coming. The only way to stop them is to send the iOS client to background. But then bringing it back to foreground requires reconnecting (not sure if it has to re-discover as well, probably does). Reconnecting slows down resumption of streaming, but not by a whole lot.

Looks like there is a trade off here for the pause mode... keep the connection alive so streaming resumes promptly at expense of power drain, or disconnect and re-connect at expense of few seconds delay in resuming the stream. Opportunity for yet-another user config option!

dkerr64 commented 1 year ago

The macOS official client does, however, stop the 28-byte handshakes if you switch to another camera, and then reconnects on returning to the Argus 3 pro. Delay does not seem to bad. Might be worth sniffing the traffic to see if it really logs out or not. It does something cleanly, because network traffic is very different when you just close down the client mid-streaming... lots of port unreachable until the camera figures out no one is listening anymore.

QuantumEntangledAndy commented 1 year ago

Yes I'm going to see if I can work something up for it when I have time to cycle into this not pause for a certain time then a full disconnect after some time period. Maybe can check battery too.

QuantumEntangledAndy commented 1 year ago

Will close this as the original issue seems to be addressed.

If you want to discuss the pause thing please open a new issue. It gets hard to keep track of issues if they are all grouped so seperate issues are preffered