NvStreammux plugin seems to cause problem in a decoded h264 video stream

Accelerated Computing Intelligent Video Analytics DeepStream SDK
1

Please provide complete information as applicable to your setup.

• Hardware Platform (Jetson / GPU) Jetson Orin AGX
• DeepStream Version 6.2
• JetPack Version (valid for Jetson only) 5.1.1
• TensorRT Version 5.1.1
• NVIDIA GPU Driver Version (valid for GPU only)
• Issue Type( questions, new requirements, bugs)
• How to reproduce the issue ? (This is for bugs. Including which sample app is using, the configuration files content, the command line used and other details for reproducing)
• Requirement details( This is for new requirement. Including the module name-for which plugin or for which sample application, the function description)

Hello,

I am using deepstream sdk on a jetson Orin to retrieve the video stream of a camera, decode it if needed, make some image processing, and finally, i want to encode it to h264 and stream in in rtsp. I am using mediamtx as a server to listen to the rtsp stream and send it in WebRTC.

I tested with 2 different cameras :

  • one that gives me a raw video stream that I read with the v4l2src plugin
  • And an IP camera with an h264 encoded rtsp video stream, that I can read with the uridecodebin plugin

For exemple with the fisrt camera, I would use the following pipeline to do what I need :

gst-launch-1.0 v4l2src device=/dev/video1 ! "video/x-raw" ! nvvideoconvert ! "video/x-raw(memory:NVMM),format=RGBA" ! m.sink_0 nvstreammux name=m batch-size=1 width=1920 height=1080 live-source=true ! identity ! nvvideoconvert ! "video/x-raw(memory:NVMM),format=I420" ! nvv4l2h264enc ! "video/x-h264" ! h264parse ! rtspclientsink location="rtsp://localhost:8554/stream"

It works perfectly and I can replace the identity plugin with different custom video processing plugin, inference and tracker without problem. Mediamtx can read the rtsp stream and i can stream in WebRTC perfectly fine.

But when I try the same with the IP camera, for exemple with this pipeline :

gst-launch-1.0 uridecodebin uri="rtsp://<user>:<password>@<cam_address>" caps="video/x-raw(memory:NVMM)" ! nvvideoconvert ! "video/x-raw(memory:NVMM),format=RGBA" ! m.sink_0 nvstreammux name=m batch-size=1 width=1920 height=1080 live-source=true ! identity ! nvvideoconvert ! "video/x-raw(memory:NVMM),format=I420" ! nvv4l2h264enc ! "video/x-h264" ! h264parse ! rtspclientsink location="rtsp://localhost:8554/stream"

Everything seems to be fine until it reach the WebRTC server where it fail to publish the video stream because it supposedly have B-frames in it (which is not compatible with WebRTC).

Dec 14 10:39:57 tegra-ubuntu mediamtx[964]: 2023/12/14 10:39:57 INF [WebRTC] [session 9e4452f9] is reading from path 'stream', 1 track (H264)
Dec 14 10:39:57 tegra-ubuntu mediamtx[964]: 2023/12/14 10:39:57 INF [WebRTC] [session 9e4452f9] closed: WebRTC doesn't support H264 streams with B-frames

I don’t think the nvv4l2h264enc plugin is at fault here since it works fine with the first camera. And the weird part is that it actually works if I remove the nvstreammux plugin (just with a lot of delay, but I guess that’s an other problem),
for example, I have no problem with this pipeline :

gst-launch-1.0 uridecodebin uri="rtsp://<user>:<password>@<cam_address>" caps="video/x-raw(memory:NVMM)" ! nvvideoconvert ! "video/x-raw(memory:NVMM),format=RGBA" ! identity ! nvvideoconvert ! "video/x-raw(memory:NVMM),format=I420" ! nvv4l2h264enc ! "video/x-h264" ! h264parse ! rtspclientsink location="rtsp://localhost:8554/vsheen"

The thing is from what I understand, I absolutely need the nvstreammux plugin to use with the vninfer and nvtracker so I can’t get rid of it.

So I have no idea what is happening and don’t even know where to start looking. I tried playing with the nvstreammux property with no luck, same for the h264 encoder.

EDIT : apparently, swithing from “live-source=true” to false also solve the issue. But why ? And most importantly, isn’t this property necessary when reading an rtsp stream ?

3

  1. The default profile of nvv4l2h264enc is baseline, so it will not encode B-frame. Have you changed the profile?

  2. live-source is necessary for rtsp source. nvstreammux will discard the frame when timeout to ensure the normal operation of the pipeline. I don’t understand why the live-source property affects encoding

  3. Can you change rtspclientsink to filesink to determine whether there are b-frames?

  4. Help me to dump log use the following command line.thanks

GST_DEBUG_NO_COLOR=1 GST_DEBUG=3 your-app > webrtc.log 2>&1
4

Another question, if you also set live-source to true when use a v4l2 camera, what will be the result?

5

Hello junshengy, thank you for your answer.
To answer your first question, I did not change the encoding profile (in fact in my application I specify the property to be baseline). I replaced the rtcpclient plugin with a filesink and used this tool to analyse the h264 stream : MP4 H264 Analyzer (lulebo.github.io). As expected, there was no B-frames. Yet the WebRTC server still gives me the same error message. So something else in the video stream is going wrong. In fact, I can’t seem to even be able to get back the rtsp stream with VLC (only with the IP camera, again I don’t notice any problem with my v4l2 camera), i don’t get any error message or anything, just a black screen.

Here is the log obtained with your command :

Setting pipeline to PAUSED ...
0:00:00.389611347  4259 0xaaaac4ed90f0 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xaaaac4d68e00 Failed to determine interlace mode
0:00:00.389688243  4259 0xaaaac4ed90f0 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xaaaac4d68e00 Failed to determine interlace mode
0:00:00.389709364  4259 0xaaaac4ed90f0 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xaaaac4d68e00 Failed to determine interlace mode
0:00:00.389725396  4259 0xaaaac4ed90f0 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xaaaac4d68e00 Failed to determine interlace mode
0:00:00.389772084  4259 0xaaaac4ed90f0 WARN                    v4l2 gstv4l2object.c:4512:gst_v4l2_object_probe_caps:<nvv4l2h264enc0:src> Failed to probe pixel aspect ratio with VIDIOC_CROPCAP: Unknown error -1
Opening in BLOCKING MODE 
Pipeline is live and does not need PREROLL ...
Progress: (open) Opening Stream
Progress: (connect) Connecting to rtsp://localhost:8554/vsheen
Progress: (open) Opening Stream
Progress: (connect) Connecting to rtsp://<user>:<password>@192.168.79.20
Progress: (open) Retrieving server options
Progress: (open) Opened Stream
Setting pipeline to PLAYING ...
New clock: GstSystemClock
Progress: (open) Retrieving server options
Progress: (request) Sending RECORD request
Progress: (request) Sending PLAY request
Progress: (open) Retrieving media info
0:00:00.535164652  4259 0xaaaac4fea520 WARN                  udpsrc gstudpsrc.c:1445:gst_udpsrc_open:<udpsrc0> warning: Could not create a buffer of requested 524288 bytes (Operation not permitted). Need net.admin privilege?
0:00:00.535285964  4259 0xaaaac4fea520 WARN                  udpsrc gstudpsrc.c:1455:gst_udpsrc_open:<udpsrc0> have udp buffer of 212992 bytes while 524288 were requested
Progress: (request) SETUP stream 0
Progress: (open) Opened Stream
Progress: (request) Sending PLAY request
0:00:00.556180908  4259 0xffff48003300 FIXME                default gstutils.c:3980:gst_pad_create_stream_id_internal:<fakesrc0:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
Progress: (request) Sent PLAY request
0:00:00.677876248  4259 0xffff4c006b60 WARN                    v4l2 gstv4l2object.c:4512:gst_v4l2_object_probe_caps:<nvv4l2decoder0:src> Failed to probe pixel aspect ratio with VIDIOC_CROPCAP: Unknown error -1
0:00:00.677976536  4259 0xffff4c006b60 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xffff200fcbe0 Failed to determine interlace mode
0:00:00.678046168  4259 0xffff4c006b60 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xffff200fcbe0 Failed to determine interlace mode
0:00:00.678064024  4259 0xffff4c006b60 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xffff200fcbe0 Failed to determine interlace mode
0:00:00.678081304  4259 0xffff4c006b60 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xffff200fcbe0 Failed to determine interlace mode
NvMMLiteOpen : Block : BlockType = 261 
NVMEDIA: Reading vendor.tegra.display-size : status: 6 
NvMMLiteBlockCreate : Block : BlockType = 261 
0:00:00.783864092  4259 0xffff4c006b60 WARN                    v4l2 gstv4l2object.c:4512:gst_v4l2_object_probe_caps:<nvv4l2decoder0:src> Failed to probe pixel aspect ratio with VIDIOC_CROPCAP: Unknown error -1
0:00:00.783949692  4259 0xffff4c006b60 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xffff200fcbe0 Failed to determine interlace mode
0:00:00.784003036  4259 0xffff4c006b60 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xffff200fcbe0 Failed to determine interlace mode
0:00:00.784025820  4259 0xffff4c006b60 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xffff200fcbe0 Failed to determine interlace mode
0:00:00.784040156  4259 0xffff4c006b60 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xffff200fcbe0 Failed to determine interlace mode
NvMMLiteOpen : Block : BlockType = 4 
Opening in BLOCKING MODE 
Redistribute latency...
===== NVMEDIA: NVENC =====
NvMMLiteBlockCreate : Block : BlockType = 4 
0:00:00.789334133  4259 0xffff4c006b60 WARN          v4l2bufferpool gstv4l2bufferpool.c:1114:gst_v4l2_buffer_pool_start:<nvv4l2h264enc0:pool:src> Uncertain or not enough buffers, enabling copy threshold
0:00:00.789954999  4259 0xffff4c006b60 WARN            v4l2videodec gstv4l2videodec.c:1880:gst_v4l2_video_dec_decide_allocation:<nvv4l2decoder0> Duration invalid, not setting latency
0:00:00.790201016  4259 0xffff4c006b60 WARN          v4l2bufferpool gstv4l2bufferpool.c:1114:gst_v4l2_buffer_pool_start:<nvv4l2decoder0:pool:src> Uncertain or not enough buffers, enabling copy threshold
0:00:00.804460634  4259 0xffff2000d860 WARN          v4l2bufferpool gstv4l2bufferpool.c:1565:gst_v4l2_buffer_pool_dqbuf:<nvv4l2decoder0:pool:src> Driver should never set v4l2_buffer.field to ANY
H264: Profile = 66, Level = 0 
NVMEDIA: Need to set EMC bandwidth : 705000 
NVMEDIA_ENC: bBlitMode is set to TRUE 
0:00:00.871198122  4259 0xffff0c012000 WARN          v4l2bufferpool gstv4l2bufferpool.c:1565:gst_v4l2_buffer_pool_dqbuf:<nvv4l2h264enc0:pool:src> Driver should never set v4l2_buffer.field to ANY
Progress: (record) Sending server stream info
Progress: (request) SETUP stream 0
0:00:00.874784891  4259 0xffff0c012000 FIXME               basesink gstbasesink.c:3246:gst_base_sink_default_event:<multiudpsink0> stream-start event without group-id. Consider implementing group-id handling in the upstream elements
Progress: (request) Sending RECORD request
Progress: (record) Starting recording
handling interrupt.
Interrupt: Stopping pipeline ...
Execution ended after 0:00:49.434664899
Setting pipeline to NULL ...
0:00:49.888837629  4259 0xaaaac4fea520 WARN                 rtspsrc gstrtspsrc.c:6585:gst_rtspsrc_send:<source> error: Unhandled error
0:00:49.888911678  4259 0xaaaac4fea520 WARN                 rtspsrc gstrtspsrc.c:6585:gst_rtspsrc_send:<source> error: Method Not Valid in This State (455)
0:00:49.888982654  4259 0xaaaac4fea520 WARN                 rtspsrc gstrtspsrc.c:8669:gst_rtspsrc_pause:<source> error: Could not send message. (Generic error)
Freeing pipeline ...

For reference, here is the log when using the v4l2 camera (so with the fisrt pipeline in my original post) :

Setting pipeline to PAUSED ...
0:00:00.373954833  4578 0xaaaab4956c90 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xaaaab4958000 Failed to determine interlace mode
0:00:00.374033329  4578 0xaaaab4956c90 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xaaaab4958000 Failed to determine interlace mode
0:00:00.374054961  4578 0xaaaab4956c90 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xaaaab4958000 Failed to determine interlace mode
0:00:00.374070609  4578 0xaaaab4956c90 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xaaaab4958000 Failed to determine interlace mode
0:00:00.374116018  4578 0xaaaab4956c90 WARN                    v4l2 gstv4l2object.c:4512:gst_v4l2_object_probe_caps:<nvv4l2h264enc0:src> Failed to probe pixel aspect ratio with VIDIOC_CROPCAP: Unknown error -1
Opening in BLOCKING MODE 
Pipeline is live and does not need PREROLL ...
Progress: (open) Opening Stream
Progress: (connect) Connecting to rtsp://localhost:8554/stream
Progress: (open) Retrieving server options
Progress: (open) Opened Stream
Setting pipeline to PLAYING ...
New clock: GstSystemClock
Progress: (request) Sending RECORD request
Redistribute latency...
NvMMLiteOpen : Block : BlockType = 4 
===== NVMEDIA: NVENC =====
NvMMLiteBlockCreate : Block : BlockType = 4 
0:00:00.436644977  4578 0xaaaab4b380c0 WARN          v4l2bufferpool gstv4l2bufferpool.c:1114:gst_v4l2_buffer_pool_start:<nvv4l2h264enc0:pool:src> Uncertain or not enough buffers, enabling copy threshold
0:00:00.479445720  4578 0xaaaab4b380c0 WARN          v4l2bufferpool gstv4l2bufferpool.c:809:gst_v4l2_buffer_pool_start:<v4l2src0:pool:src> Uncertain or not enough buffers, enabling copy threshold
H264: Profile = 66, Level = 0 
NVMEDIA: Need to set EMC bandwidth : 1692000 
NVMEDIA_ENC: bBlitMode is set to TRUE 
0:00:00.760158462  4578 0xffff80194360 WARN          v4l2bufferpool gstv4l2bufferpool.c:1565:gst_v4l2_buffer_pool_dqbuf:<nvv4l2h264enc0:pool:src> Driver should never set v4l2_buffer.field to ANY
Progress: (record) Sending server stream info
Progress: (request) SETUP stream 0
Progress: (request) Sending RECORD request
Progress: (record) Starting recording
handling interrupt.
Interrupt: Stopping pipeline ...
Execution ended after 0:00:20.294896492
Setting pipeline to NULL ...
Freeing pipeline ...

To answer your second comment, the “live-source” was already set to true in the pipeline with the v4l2 camera, but I did try to set it as false without noticing any major defferences (it still works fine).

6

I guess this line is reporting an error.

This may be due to some issues with your rtsp source timestamp.

You can try setting the attach-sys-ts property of nvstreammux to true.

7

As a supplement, you can refer to this link.

https://docs.nvidia.com/metropolis/deepstream/dev-guide/text/DS_NTP_Timestamp.html?highlight=configure_source_for_ntp_sync#ntp-timestamp-in-deepstream

8

I agree that an issue with timestamps would explain some things. Unfortunately, I don’t see any improvement after setting the attach-sys-ts property to true.

9

So to recap my problem :

  • I have a camera that stream video in h264.
  • I can read this stream either with vlc or with uridecodebin without apparent problems.
  • If I immediately re-encode this video stream and try to stream it via RTP (with rtspclientsink plugin), it seems to work fine
  • If I use a nvstreammux before re-encoding and streaming, I get an error message by mediamtx saying it detect B-frames in my h264 stream (which I already checked, shouldn’t be the case)
10

I tried using the internal rtsp stream to push to mediamtx and it worked fine.

I have several suggestions for comparative analysis

  1. Change a media server
    GitHub - deepch/RTSPtoWebRTC: RTSP to WebRTC use Pion WebRTC

  2. Try using the new nvstreammux

export USE_NEW_NVSTREAMMUX=yes

3.Use the following command to dump nvstreammux logs

GST_DEBUG_NO_COLOR=1 GST_DEBUG=3,nvstreammux:5 your-app > log.log  2>&1
11

Hi junshengy, thank you again for your reply.
I tested the same pipeline but this time using the new streammux, and it worked ! No B-frames error.
here is the log with the new streammux :

0:00:00.142075802  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<GstNvStreamMux@0xaaaadbb7c170> [BatchPolicy 111]BatchPolicy insert priority 0 source 0 max_fps 60.000000

0:00:00.142197531  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<GstNvStreamMux@0xaaaadbb7c170> [BatchPolicy 132]BatchPolicy batch_size 0 config batch size 1

0:00:00.142258459  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<GstNvStreamMux@0xaaaadbb7c170> [BatchPolicy 137]BatchPolicy max_fps_dur=8333.333333 min_fps_dur=200000.000000 overall_max_fps=120/1 overall_min_fps=5/1

0:00:00.157021809  5970 0xaaaadbb72ef0 DEBUG            nvstreammux gstnvstreammux.cpp:1212:gst_nvstreammux_request_new_pad:<m> Requesting new sink pad
0:00:00.157139889  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [add_pad 463]add_pad 463 id=0

max_fps_dur 8.33333e+06 min_fps_dur 2e+08
Setting pipeline to PAUSED ...
0:00:00.200876720  5970 0xaaaadbb72ef0 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xaaaadbb90480 Failed to determine interlace mode
0:00:00.200942480  5970 0xaaaadbb72ef0 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xaaaadbb90480 Failed to determine interlace mode
0:00:00.200961808  5970 0xaaaadbb72ef0 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xaaaadbb90480 Failed to determine interlace mode
0:00:00.200977040  5970 0xaaaadbb72ef0 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xaaaadbb90480 Failed to determine interlace mode
0:00:00.201019888  5970 0xaaaadbb72ef0 WARN                    v4l2 gstv4l2object.c:4512:gst_v4l2_object_probe_caps:<nvv4l2h264enc0:src> Failed to probe pixel aspect ratio with VIDIOC_CROPCAP: Unknown error -1
Opening in BLOCKING MODE 
Pipeline is live and does not need PREROLL ...
Progress: (open) Opening Stream
Progress: (connect) Connecting to rtsp://localhost:8554/vsheen
Progress: (open) Opening Stream
Progress: (connect) Connecting to rtsp://<user>:<password>@192.168.79.20
Progress: (open) Retrieving server options
Progress: (open) Opened Stream
Setting pipeline to PLAYING ...
New clock: GstSystemClock
Progress: (request) Sending RECORD request
Progress: (request) Sending PLAY request
Progress: (open) Retrieving server options
Progress: (open) Retrieving media info
0:00:00.284437269  5970 0xaaaadbb7f520 WARN                  udpsrc gstudpsrc.c:1445:gst_udpsrc_open:<udpsrc0> warning: Could not create a buffer of requested 524288 bytes (Operation not permitted). Need net.admin privilege?
0:00:00.284528118  5970 0xaaaadbb7f520 WARN                  udpsrc gstudpsrc.c:1455:gst_udpsrc_open:<udpsrc0> have udp buffer of 212992 bytes while 524288 were requested
Progress: (request) SETUP stream 0
Progress: (open) Opened Stream
Progress: (request) Sending PLAY request
0:00:00.307268378  5970 0xffff70003980 FIXME                default gstutils.c:3980:gst_pad_create_stream_id_internal:<fakesrc0:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
Progress: (request) Sent PLAY request
0:00:00.431494764  5970 0xffff7801c760 WARN                    v4l2 gstv4l2object.c:4512:gst_v4l2_object_probe_caps:<nvv4l2decoder0:src> Failed to probe pixel aspect ratio with VIDIOC_CROPCAP: Unknown error -1
0:00:00.431590285  5970 0xffff7801c760 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xffff40097550 Failed to determine interlace mode
0:00:00.431621069  5970 0xffff7801c760 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xffff40097550 Failed to determine interlace mode
0:00:00.431638829  5970 0xffff7801c760 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xffff40097550 Failed to determine interlace mode
0:00:00.431656077  5970 0xffff7801c760 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xffff40097550 Failed to determine interlace mode
NvMMLiteOpen : Block : BlockType = 261 
NVMEDIA: Reading vendor.tegra.display-size : status: 6 
NvMMLiteBlockCreate : Block : BlockType = 261 
0:00:00.542418321  5970 0xffff7801c760 WARN                    v4l2 gstv4l2object.c:4512:gst_v4l2_object_probe_caps:<nvv4l2decoder0:src> Failed to probe pixel aspect ratio with VIDIOC_CROPCAP: Unknown error -1
0:00:00.542501617  5970 0xffff7801c760 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xffff40097550 Failed to determine interlace mode
0:00:00.542556466  5970 0xffff7801c760 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xffff40097550 Failed to determine interlace mode
0:00:00.542591090  5970 0xffff7801c760 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xffff40097550 Failed to determine interlace mode
0:00:00.542621426  5970 0xffff7801c760 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xffff40097550 Failed to determine interlace mode
0:00:00.543666776  5970 0xffff7801c760 DEBUG            nvstreammux :0::<m> [push_events 84]pad[0] event_type=2

0:00:00.543727897  5970 0xffff7801c760 DEBUG            nvstreammux :0::<m> [get_available 113]number of buffers available in queue of pad (0) = 0

0:00:00.543745177  5970 0xffff7801c760 DEBUG            nvstreammux :0::<m> [push_events 103][push_events] available buffers=0 q.size=0 event_indices.size=0

0:00:00.543755737  5970 0xffff7801c760 DEBUG            nvstreammux :0::<m> [push_events 49]push_events 49 num_sources_idle=1

0:00:00.543766265  5970 0xffff7801c760 DEBUG            nvstreammux :0::<m> [push_events 71]sink=0 get_switched_to_idle()=0 queue.size()=0 get_eos()=0

0:00:00.543775705  5970 0xffff7801c760 DEBUG            nvstreammux :0::<m> [push_events 78]push_events num_sources_idle=1 pads_got_eos_and_empty_in_q=0(1)

0:00:00.543784633  5970 0xffff7801c760 DEBUG            nvstreammux :0::<m> [push_events 80]DEBUGME push_events 80 size=1 pads_got_eos_and_empty_in_q=0

0:00:00.543793017  5970 0xffff7801c760 DEBUG            nvstreammux :0::<m> [update_idle_sources 339]update_idle_sources 339 idle_sources=1

0:00:00.544891967  5970 0xffff7801c760 DEBUG            nvstreammux :0::<m> [BatchPolicy 111]BatchPolicy insert priority 0 source 0 max_fps 60.000000

0:00:00.544917888  5970 0xffff7801c760 DEBUG            nvstreammux :0::<m> [BatchPolicy 132]BatchPolicy batch_size 1 config batch size 1

0:00:00.544953664  5970 0xffff7801c760 DEBUG            nvstreammux :0::<m> [BatchPolicy 137]BatchPolicy max_fps_dur=8333.333333 min_fps_dur=200000.000000 overall_max_fps=120/1 overall_min_fps=5/1

0:00:00.544969760  5970 0xffff7801c760 WARN             nvstreammux gstnvstreammux.cpp:1051:configure_module:<m> No config-file provided; falling back to default streammux config 1

0:00:00.545001280  5970 0xffff7801c760 DEBUG            nvstreammux gstnvstreammux.cpp:792:handle_caps:<m> *** MUX -- > input caps; feature memory:NVMM 1; pad_id=0 caps=[video/x-raw(memory:NVMM), width=(int)1920, height=(int)1080, interlace-mode=(string)progressive, multiview-mode=(string)mono, multiview-flags=(GstVideoMultiviewFlagsSet)0:ffffffff:/right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)25/1, nvbuf-memory-type=(string)nvbuf-mem-surface-array, gpu-id=(int)0, format=(string)RGBA, block-linear=(boolean)false] mimetype=[video/x-raw]

NvMMLiteOpen : Block : BlockType = 4 
Opening in BLOCKING MODE 
max_fps_dur 8.33333e+06 min_fps_dur 2e+08
Redistribute latency...
===== NVMEDIA: NVENC =====
NvMMLiteBlockCreate : Block : BlockType = 4 
0:00:00.547976817  5970 0xffff7801c760 WARN          v4l2bufferpool gstv4l2bufferpool.c:1114:gst_v4l2_buffer_pool_start:<nvv4l2h264enc0:pool:src> Uncertain or not enough buffers, enabling copy threshold
0:00:00.548570069  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [get_batch_size 316]get_batch_size inputs->size=1 num_sources_eos=0 num_surfaces_per_frame=1 config=0 adaptive_batching=1

0:00:00.548595413  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [get_batch_size 316]get_batch_size inputs->size=1 num_sources_eos=0 num_surfaces_per_frame=1 config=0 adaptive_batching=1

0:00:00.548737718  5970 0xffff7801c760 WARN            v4l2videodec gstv4l2videodec.c:1880:gst_v4l2_video_dec_decide_allocation:<nvv4l2decoder0> Duration invalid, not setting latency
0:00:00.548742646  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [push_loop 282]push_loop 282 0xffff2c0020a0

0:00:00.548841814  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [push_loop 294]DEBUGME what

0:00:00.548870454  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [get_available 113]number of buffers available in queue of pad (0) = 0

0:00:00.548892183  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [push_events 103][push_events] available buffers=0 q.size=0 event_indices.size=0

0:00:00.548918935  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [push_events 71]sink=0 get_switched_to_idle()=0 queue.size()=0 get_eos()=0

0:00:00.548937751  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [push_events 78]push_events num_sources_idle=1 pads_got_eos_and_empty_in_q=0(1)

0:00:00.548955159  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [push_events 80]DEBUGME push_events 80 size=1 pads_got_eos_and_empty_in_q=0

0:00:00.548972887  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [update_idle_sources 339]update_idle_sources 339 idle_sources=1

0:00:00.548991991  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [form_batch 458]sources_to_process=1

0:00:00.549006071  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [form_batch 475]update_with_source sid=0

0:00:00.549031671  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [get_batch_size 316]get_batch_size inputs->size=1 num_sources_eos=0 num_surfaces_per_frame=1 config=0 adaptive_batching=1

0:00:00.549048056  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [update_with_source 375]update_with_source sid=0

0:00:00.549064536  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [get_available 113]number of buffers available in queue of pad (0) = 0

0:00:00.549081144  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [update_with_source 380]num_avail=0 sid=0

0:00:00.549107736  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [get_batch_size 316]get_batch_size inputs->size=1 num_sources_eos=0 num_surfaces_per_frame=1 config=0 adaptive_batching=1

0:00:00.549125464  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [update_with_source 387]allowed_repeats=1 allowed_repeats_source=1 bs=1

0:00:00.549140280  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [update_with_source 389]num_avail=0 sid=0

0:00:00.549155960  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [update_with_source 395]synch_buffer=(nil) num_to_insert=0

0:00:00.549171480  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [get_available 113]number of buffers available in queue of pad (0) = 0

0:00:00.549188984  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [update_with_source 404]update_with_source insert 0 acc_batch 0 source_id 0 available 0 max repeats 1

0:00:00.549207992  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [form_batch 482]form_batch 482

0:00:00.549224825  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [push_loop 315]wait_not_past_max=1 acc_batch=0

0:00:00.549238585  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [push_loop 318]DEBUGME

0:00:00.549296281  5970 0xffff7801c760 WARN          v4l2bufferpool gstv4l2bufferpool.c:1114:gst_v4l2_buffer_pool_start:<nvv4l2decoder0:pool:src> Uncertain or not enough buffers, enabling copy threshold
0:00:00.562930952  5970 0xffff4000ec60 WARN          v4l2bufferpool gstv4l2bufferpool.c:1565:gst_v4l2_buffer_pool_dqbuf:<nvv4l2decoder0:pool:src> Driver should never set v4l2_buffer.field to ANY
0:00:00.563029001  5970 0xffff4000ec60 DEBUG            nvstreammux :0::<m> [push_events 84]pad[0] event_type=2

0:00:00.563073257  5970 0xffff4000ec60 DEBUG            nvstreammux :0::<m> [get_available 113]number of buffers available in queue of pad (0) = 0

0:00:00.563103881  5970 0xffff4000ec60 DEBUG            nvstreammux :0::<m> [push_events 103][push_events] available buffers=0 q.size=0 event_indices.size=0

0:00:00.563112553  5970 0xffff4000ec60 DEBUG            nvstreammux :0::<m> [push_events 71]sink=0 get_switched_to_idle()=0 queue.size()=0 get_eos()=0

0:00:00.563119465  5970 0xffff4000ec60 DEBUG            nvstreammux :0::<m> [push_events 78]push_events num_sources_idle=1 pads_got_eos_and_empty_in_q=0(1)

0:00:00.563125737  5970 0xffff4000ec60 DEBUG            nvstreammux :0::<m> [push_events 80]DEBUGME push_events 80 size=1 pads_got_eos_and_empty_in_q=0

0:00:00.563131561  5970 0xffff4000ec60 DEBUG            nvstreammux :0::<m> [update_idle_sources 339]update_idle_sources 339 idle_sources=1

0:00:00.563152458  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [get_available 113]number of buffers available in queue of pad (0) = 0

0:00:00.563177450  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [push_events 103][push_events] available buffers=0 q.size=0 event_indices.size=0

0:00:00.563186474  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [push_events 71]sink=0 get_switched_to_idle()=0 queue.size()=0 get_eos()=0

0:00:00.563192618  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [push_events 78]push_events num_sources_idle=1 pads_got_eos_and_empty_in_q=0(1)

0:00:00.563198346  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [push_events 80]DEBUGME push_events 80 size=1 pads_got_eos_and_empty_in_q=0

0:00:00.563203754  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [update_idle_sources 339]update_idle_sources 339 idle_sources=1

0:00:00.563212394  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [form_batch 458]sources_to_process=1

0:00:00.563217386  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [form_batch 475]update_with_source sid=0

0:00:00.563224874  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [get_batch_size 316]get_batch_size inputs->size=1 num_sources_eos=0 num_surfaces_per_frame=1 config=0 adaptive_batching=1

0:00:00.563230698  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [update_with_source 375]update_with_source sid=0

0:00:00.563236074  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [get_available 113]number of buffers available in queue of pad (0) = 0

0:00:00.563241034  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [update_with_source 380]num_avail=0 sid=0

0:00:00.563247658  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [get_batch_size 316]get_batch_size inputs->size=1 num_sources_eos=0 num_surfaces_per_frame=1 config=0 adaptive_batching=1

0:00:00.563253226  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [update_with_source 387]allowed_repeats=1 allowed_repeats_source=1 bs=1

0:00:00.563257898  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [update_with_source 389]num_avail=0 sid=0

0:00:00.563263146  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [update_with_source 395]synch_buffer=(nil) num_to_insert=0

0:00:00.563268266  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [get_available 113]number of buffers available in queue of pad (0) = 0

0:00:00.563273866  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [update_with_source 404]update_with_source insert 0 acc_batch 0 source_id 0 available 0 max repeats 1

0:00:00.563278730  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [form_batch 482]form_batch 482

0:00:00.563284298  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [push_loop 315]wait_not_past_max=1 acc_batch=0

0:00:00.563288906  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [push_loop 318]DEBUGME

0:00:00.598483927  5970 0xffff4000ec60 DEBUG            nvstreammux :0::<m> [add_buffer 472]id=0

0:00:00.598526327  5970 0xffff4000ec60 DEBUG            nvstreammux :0::<m> [get_batch_size 316]get_batch_size inputs->size=1 num_sources_eos=0 num_surfaces_per_frame=1 config=0 adaptive_batching=1

...

0:00:20.847695363  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [push_events 103][push_events] available buffers=0 q.size=0 event_indices.size=0

0:00:20.847707299  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [push_events 71]sink=0 get_switched_to_idle()=0 queue.size()=0 get_eos()=0

0:00:20.847712899  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [push_events 78]push_events num_sources_idle=0 pads_got_eos_and_empty_in_q=0(1)

0:00:20.847718243  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [push_events 80]DEBUGME push_events 80 size=1 pads_got_eos_and_empty_in_q=0

0:00:20.847723427  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [update_idle_sources 339]update_idle_sources 339 idle_sources=0

0:00:20.856170389  5970 0xffff4000ec00 DEBUG            nvstreammux :0::<m> [push_loop 388]push_loop 388

[ERROR push 333] push failed [-2]
[ERROR push 333] push failed [-2]
[ERROR push 333] push failed [-2]
Freeing pipeline ...
0:00:20.941969607  5970 0xaaaadbb72ef0 DEBUG            nvstreammux gstnvstreammux.cpp:1288:gst_nvstreammux_release_pad:<m> Pad deleted 0

0:00:20.942053192  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 412]remove_pad 412 id=0

0:00:20.942088200  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.943185422  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.944274933  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.945365019  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.946447745  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.947530120  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.948613614  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.949705364  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.950786491  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.951928001  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.953017608  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.954099886  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.955180884  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.956262714  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.957347265  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.958429031  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.959529549  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.960658292  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.961772794  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.962862369  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.963948775  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.965034157  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.966115764  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.967197594  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.968279136  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.969363911  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.970445005  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.971527443  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.972610169  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.973694688  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.974776646  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.975859852  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.976943891  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.978024793  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.979105791  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.980187237  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.981270380  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.982384786  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.983469689  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.984552575  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.985636229  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.986719083  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.987804658  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.988888344  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.989970526  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.991052645  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.992136907  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.993222481  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.994308952  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.995391710  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.996474340  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.997558602  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.998640497  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:20.999727511  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.000808957  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.001888996  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.002970346  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.004052912  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.005135830  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.006216445  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.007297411  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.008378793  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.009461456  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.010541654  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.011648348  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.012735011  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.013819689  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.014901071  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.015982678  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.017066140  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.018145442  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.019224584  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.020305935  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.021388213  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.022468475  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.023550306  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.024632424  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.025716814  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.026797492  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.027878779  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.028962017  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.030042439  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.031123406  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.032221268  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.033356571  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.034464641  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.035557703  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.036661102  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.037799732  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.038908027  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.040001857  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.041120008  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.042205038  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.043293876  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.044379803  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.045465601  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.046548679  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.047629934  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.048712756  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.049797146  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 427]App sequence error release pad called before push events

0:00:21.050882176  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 435]100ms timeout exhausted. App sequence error release pad called before push events

0:00:21.050921249  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [update_eos_sources 345]update_eos_sources 345 eos_sources=-1

0:00:21.050942433  5970 0xaaaadbb72ef0 DEBUG            nvstreammux :0::<m> [remove_pad 452]DEBUGME remove_pad 452 size=0

And here is the one with the old streammux :

0:00:00.336976615  6166 0xaaaae9075980 DEBUG            nvstreammux gstnvstreammux.c:1478:gst_nvstreammux_request_new_pad:<m> Requesting new sink pad
Setting pipeline to PAUSED ...
0:00:00.382052877  6166 0xaaaae9075980 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xaaaae8f6d000 Failed to determine interlace mode
0:00:00.382125005  6166 0xaaaae9075980 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xaaaae8f6d000 Failed to determine interlace mode
0:00:00.382145421  6166 0xaaaae9075980 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xaaaae8f6d000 Failed to determine interlace mode
0:00:00.382160717  6166 0xaaaae9075980 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xaaaae8f6d000 Failed to determine interlace mode
0:00:00.382207342  6166 0xaaaae9075980 WARN                    v4l2 gstv4l2object.c:4512:gst_v4l2_object_probe_caps:<nvv4l2h264enc0:src> Failed to probe pixel aspect ratio with VIDIOC_CROPCAP: Unknown error -1
Opening in BLOCKING MODE 
Pipeline is live and does not need PREROLL ...
Progress: (open) Opening Stream
Progress: (connect) Connecting to rtsp://localhost:8554/vsheen
Progress: (open) Opening Stream
Progress: (connect) Connecting to rtsp://admin:Exavision30@192.168.79.20
Progress: (open) Retrieving server options
Progress: (open) Opened Stream
Setting pipeline to PLAYING ...
New clock: GstSystemClock
Progress: (request) Sending RECORD request
Progress: (open) Retrieving server options
Progress: (request) Sending PLAY request
Progress: (open) Retrieving media info
0:00:00.522602462  6166 0xaaaae91dc520 WARN                  udpsrc gstudpsrc.c:1445:gst_udpsrc_open:<udpsrc0> warning: Could not create a buffer of requested 524288 bytes (Operation not permitted). Need net.admin privilege?
0:00:00.522684638  6166 0xaaaae91dc520 WARN                  udpsrc gstudpsrc.c:1455:gst_udpsrc_open:<udpsrc0> have udp buffer of 212992 bytes while 524288 were requested
Progress: (request) SETUP stream 0
Progress: (open) Opened Stream
Progress: (request) Sending PLAY request
0:00:00.544236763  6166 0xffff58003300 FIXME                default gstutils.c:3980:gst_pad_create_stream_id_internal:<fakesrc0:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
Progress: (request) Sent PLAY request
0:00:00.671218142  6166 0xffff5c006b60 WARN                    v4l2 gstv4l2object.c:4512:gst_v4l2_object_probe_caps:<nvv4l2decoder0:src> Failed to probe pixel aspect ratio with VIDIOC_CROPCAP: Unknown error -1
0:00:00.671331518  6166 0xffff5c006b60 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xffff300c7e00 Failed to determine interlace mode
0:00:00.671365278  6166 0xffff5c006b60 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xffff300c7e00 Failed to determine interlace mode
0:00:00.671386623  6166 0xffff5c006b60 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xffff300c7e00 Failed to determine interlace mode
0:00:00.671406879  6166 0xffff5c006b60 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xffff300c7e00 Failed to determine interlace mode
NvMMLiteOpen : Block : BlockType = 261 
NVMEDIA: Reading vendor.tegra.display-size : status: 6 
NvMMLiteBlockCreate : Block : BlockType = 261 
0:00:00.787873060  6166 0xffff5c006b60 WARN                    v4l2 gstv4l2object.c:4512:gst_v4l2_object_probe_caps:<nvv4l2decoder0:src> Failed to probe pixel aspect ratio with VIDIOC_CROPCAP: Unknown error -1
0:00:00.787950244  6166 0xffff5c006b60 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xffff300c7e00 Failed to determine interlace mode
0:00:00.787992932  6166 0xffff5c006b60 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xffff300c7e00 Failed to determine interlace mode
0:00:00.788021445  6166 0xffff5c006b60 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xffff300c7e00 Failed to determine interlace mode
0:00:00.788076645  6166 0xffff5c006b60 WARN                    v4l2 gstv4l2object.c:2398:gst_v4l2_object_add_interlace_mode:0xffff300c7e00 Failed to determine interlace mode
0:00:00.791045878  6166 0xffff5c006b60 DEBUG            nvstreammux gstnvstreammux.c:1232:gst_nvstreammux_sink_event:<m> parse video info from caps video/x-raw(memory:NVMM), width=(int)1920, height=(int)1080, interlace-mode=(string)progressive, multiview-mode=(string)mono, multiview-flags=(GstVideoMultiviewFlagsSet)0:ffffffff:/right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)25/1, nvbuf-memory-type=(string)nvbuf-mem-surface-array, gpu-id=(int)0, format=(string)RGBA, block-linear=(boolean)false
Opening in BLOCKING MODE 
Redistribute latency...
NvMMLiteOpen : Block : BlockType = 4 
===== NVMEDIA: NVENC =====
NvMMLiteBlockCreate : Block : BlockType = 4 
0:00:00.794579915  6166 0xffff5c006b60 WARN          v4l2bufferpool gstv4l2bufferpool.c:1114:gst_v4l2_buffer_pool_start:<nvv4l2h264enc0:pool:src> Uncertain or not enough buffers, enabling copy threshold
0:00:00.795486448  6166 0xffff5c006b60 WARN            v4l2videodec gstv4l2videodec.c:1880:gst_v4l2_video_dec_decide_allocation:<nvv4l2decoder0> Duration invalid, not setting latency
0:00:00.795875634  6166 0xffff5c006b60 WARN          v4l2bufferpool gstv4l2bufferpool.c:1114:gst_v4l2_buffer_pool_start:<nvv4l2decoder0:pool:src> Uncertain or not enough buffers, enabling copy threshold
0:00:00.810106021  6166 0xffff3000c860 WARN          v4l2bufferpool gstv4l2bufferpool.c:1565:gst_v4l2_buffer_pool_dqbuf:<nvv4l2decoder0:pool:src> Driver should never set v4l2_buffer.field to ANY
0:00:00.810207014  6166 0xffff3000c860 INFO             nvstreammux gstnvstreammux.c:1406:gst_nvstreammux_sink_event:<m> mux got segment from src 0 time segment start=0:00:00.166729225, offset=0:00:00.000000000, stop=99:99:99.999999999, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.166729225, position 0:00:00.166729225, duration 99:99:99.999999999
0:00:00.845266929  6166 0xffff3000c860 DEBUG            nvstreammux gstnvstreammux.c:518:gst_nvstreammux_chain:<m> Got buffer 0xffff300c9d80 from source 0 pts = 0:00:00.166729225
0:00:00.845543987  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2313:gst_nvstreammux_src_collect_buffers:<m> Pad added event sent 0

0:00:00.845612083  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2814:gst_nvstreammux_src_push_loop:<m> Pushing buffer 0xffff300d7c60, batch size 1, PTS 0:00:00.405811255
0:00:00.845625331  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2835:gst_nvstreammux_src_push_loop:<m> STREAMMUX OUT BUFFER attached timestamp 0:00:00.405811255
0:00:00.850483248  6166 0xffff3000c860 DEBUG            nvstreammux gstnvstreammux.c:518:gst_nvstreammux_chain:<m> Got buffer 0xffff4403eea0 from source 0 pts = 0:00:00.206725080
0:00:00.855678510  6166 0xffff3000c860 DEBUG            nvstreammux gstnvstreammux.c:518:gst_nvstreammux_chain:<m> Got buffer 0xffff4403ed80 from source 0 pts = 0:00:00.246708504
H264: Profile = 66, Level = 0 
0:00:00.861348207  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2814:gst_nvstreammux_src_push_loop:<m> Pushing buffer 0xffff300de120, batch size 1, PTS 0:00:00.421545395
0:00:00.861370639  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2835:gst_nvstreammux_src_push_loop:<m> STREAMMUX OUT BUFFER attached timestamp 0:00:00.421545395
NVMEDIA: Need to set EMC bandwidth : 705000 
NVMEDIA_ENC: bBlitMode is set to TRUE 
0:00:00.866508141  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2814:gst_nvstreammux_src_push_loop:<m> Pushing buffer 0xffff4403eb40, batch size 1, PTS 0:00:00.426704753
0:00:00.866528813  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2835:gst_nvstreammux_src_push_loop:<m> STREAMMUX OUT BUFFER attached timestamp 0:00:00.426704753
0:00:00.875406305  6166 0xffff1c012c00 WARN          v4l2bufferpool gstv4l2bufferpool.c:1565:gst_v4l2_buffer_pool_dqbuf:<nvv4l2h264enc0:pool:src> Driver should never set v4l2_buffer.field to ANY
Progress: (record) Sending server stream info
Progress: (request) SETUP stream 0
0:00:00.878885301  6166 0xffff1c012c00 FIXME               basesink gstbasesink.c:3246:gst_base_sink_default_event:<multiudpsink0> stream-start event without group-id. Consider implementing group-id handling in the upstream elements
0:00:00.879215159  6166 0xffff3801a360 DEBUG            nvstreammux gstnvstreammux.c:1167:gst_nvstreammux_src_event:<m> latency 0:00:02.100000000
Progress: (request) Sending RECORD request
0:00:00.886020702  6166 0xffff3000c860 DEBUG            nvstreammux gstnvstreammux.c:518:gst_nvstreammux_chain:<m> Got buffer 0xffff4403e6c0 from source 0 pts = 0:00:00.286671266
0:00:00.886075231  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2814:gst_nvstreammux_src_push_loop:<m> Pushing buffer 0xffff4403e900, batch size 1, PTS 5124095:34:32.055824610
0:00:00.886093791  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2835:gst_nvstreammux_src_push_loop:<m> STREAMMUX OUT BUFFER attached timestamp 5124095:34:32.055824610
0:00:00.922530162  6166 0xffff3000c860 DEBUG            nvstreammux gstnvstreammux.c:518:gst_nvstreammux_chain:<m> Got buffer 0xffff300c9d80 from source 0 pts = 0:00:00.326605303
0:00:00.922614419  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2814:gst_nvstreammux_src_push_loop:<m> Pushing buffer 0xffff300d7c60, batch size 1, PTS 5124095:34:32.092353303
0:00:00.922656531  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2835:gst_nvstreammux_src_push_loop:<m> STREAMMUX OUT BUFFER attached timestamp 5124095:34:32.092353303
0:00:00.963045374  6166 0xffff3000c860 DEBUG            nvstreammux gstnvstreammux.c:518:gst_nvstreammux_chain:<m> Got buffer 0xffff4403eea0 from source 0 pts = 0:00:00.366502896
0:00:00.963323936  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2814:gst_nvstreammux_src_push_loop:<m> Pushing buffer 0xffff300de120, batch size 1, PTS 5124095:34:32.133059779
0:00:00.963372352  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2835:gst_nvstreammux_src_push_loop:<m> STREAMMUX OUT BUFFER attached timestamp 5124095:34:32.133059779
0:00:01.002969382  6166 0xffff3000c860 DEBUG            nvstreammux gstnvstreammux.c:518:gst_nvstreammux_chain:<m> Got buffer 0xffff4403ed80 from source 0 pts = 0:00:00.406356904
0:00:01.043145552  6166 0xffff3000c860 DEBUG            nvstreammux gstnvstreammux.c:518:gst_nvstreammux_chain:<m> Got buffer 0xffff4403e6c0 from source 0 pts = 0:00:00.446161054
0:00:01.083430490  6166 0xffff3000c860 DEBUG            nvstreammux gstnvstreammux.c:518:gst_nvstreammux_chain:<m> Got buffer 0xffff300c9d80 from source 0 pts = 0:00:00.485910264
Progress: (record) Starting recording
0:00:02.957228902  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2814:gst_nvstreammux_src_push_loop:<m> Pushing buffer 0xffff4403eb40, batch size 1, PTS 0:00:00.417394538
0:00:02.957369287  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2835:gst_nvstreammux_src_push_loop:<m> STREAMMUX OUT BUFFER attached timestamp 0:00:00.417394538
0:00:02.967431618  6166 0xffff3000c860 DEBUG            nvstreammux gstnvstreammux.c:518:gst_nvstreammux_chain:<m> Got buffer 0xffff4403eea0 from source 0 pts = 0:00:00.525600983
0:00:02.972302046  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2814:gst_nvstreammux_src_push_loop:<m> Pushing buffer 0xffff4403e900, batch size 1, PTS 0:00:00.432483042
0:00:02.972370270  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2835:gst_nvstreammux_src_push_loop:<m> STREAMMUX OUT BUFFER attached timestamp 0:00:00.432483042
0:00:03.022489282  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2814:gst_nvstreammux_src_push_loop:<m> Pushing buffer 0xffff300d7c60, batch size 1, PTS 0:00:00.482665221
0:00:03.022632802  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2835:gst_nvstreammux_src_push_loop:<m> STREAMMUX OUT BUFFER attached timestamp 0:00:00.482665221
0:00:03.032438395  6166 0xffff3000c860 DEBUG            nvstreammux gstnvstreammux.c:518:gst_nvstreammux_chain:<m> Got buffer 0xffff4403ed80 from source 0 pts = 0:00:00.565231526
0:00:03.037717370  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2814:gst_nvstreammux_src_push_loop:<m> Pushing buffer 0xffff300de120, batch size 1, PTS 0:00:00.497895870
0:00:03.037797019  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2835:gst_nvstreammux_src_push_loop:<m> STREAMMUX OUT BUFFER attached timestamp 0:00:00.497895870
0:00:03.047635540  6166 0xffff3000c860 DEBUG            nvstreammux gstnvstreammux.c:518:gst_nvstreammux_chain:<m> Got buffer 0xffff4403e6c0 from source 0 pts = 0:00:00.604802364
0:00:03.052977779  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2814:gst_nvstreammux_src_push_loop:<m> Pushing buffer 0xffff4403eb40, batch size 1, PTS 0:00:00.513157239
0:00:03.053042163  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2835:gst_nvstreammux_src_push_loop:<m> STREAMMUX OUT BUFFER attached timestamp 0:00:00.513157239
0:00:03.058098545  6166 0xffff3000c860 DEBUG            nvstreammux gstnvstreammux.c:518:gst_nvstreammux_chain:<m> Got buffer 0xffff300c9d80 from source 0 pts = 0:00:00.644316345
0:00:03.063298127  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2814:gst_nvstreammux_src_push_loop:<m> Pushing buffer 0xffff4403e900, batch size 1, PTS 0:00:00.523482483
0:00:03.063354863  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2835:gst_nvstreammux_src_push_loop:<m> STREAMMUX OUT BUFFER attached timestamp 0:00:00.523482483
0:00:03.072837286  6166 0xffff3000c860 DEBUG            nvstreammux gstnvstreammux.c:518:gst_nvstreammux_chain:<m> Got buffer 0xffff4403eea0 from source 0 pts = 0:00:00.683778803
0:00:03.078068581  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2814:gst_nvstreammux_src_push_loop:<m> Pushing buffer 0xffff300d7c60, batch size 1, PTS 0:00:00.538252937
0:00:03.078126181  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2835:gst_nvstreammux_src_push_loop:<m> STREAMMUX OUT BUFFER attached timestamp 0:00:00.538252937
0:00:03.087068697  6166 0xffff3000c860 DEBUG            nvstreammux gstnvstreammux.c:518:gst_nvstreammux_chain:<m> Got buffer 0xffff4403ed80 from source 0 pts = 0:00:00.723197525
0:00:03.091218833  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2814:gst_nvstreammux_src_push_loop:<m> Pushing buffer 0xffff300de120, batch size 1, PTS 0:00:00.551400181

...

0:00:09.403612647  6166 0xffff3000c860 DEBUG            nvstreammux gstnvstreammux.c:518:gst_nvstreammux_chain:<m> Got buffer 0xffff300c9d80 from source 0 pts = 0:00:08.792553807
0:00:09.403979689  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2814:gst_nvstreammux_src_push_loop:<m> Pushing buffer 0xffff300d7c60, batch size 1, PTS 0:00:06.864148717
0:00:09.404044137  6166 0xffff3000c800 DEBUG            nvstreammux gstnvstreammux.c:2835:gst_nvstreammux_src_push_loop:<m> STREAMMUX OUT BUFFER attached timestamp 0:00:06.864148717
handling interrupt.
Interrupt: Stopping pipeline ...
Execution ended after 0:00:08.977912605
Setting pipeline to NULL ...
0:00:09.437831630  6166 0xaaaae91dc520 WARN                 rtspsrc gstrtspsrc.c:6585:gst_rtspsrc_send:<source> error: Unhandled error
0:00:09.437950063  6166 0xaaaae91dc520 WARN                 rtspsrc gstrtspsrc.c:6585:gst_rtspsrc_send:<source> error: Method Not Valid in This State (455)
0:00:09.438065071  6166 0xaaaae91dc520 WARN                 rtspsrc gstrtspsrc.c:8669:gst_rtspsrc_pause:<source> error: Could not send message. (Generic error)
Freeing pipeline ...
0:00:09.513268036  6166 0xaaaae9075980 DEBUG            nvstreammux gstnvstreammux.c:1589:gst_nvstreammux_release_pad:<m> Pad deleted 0

I still don’t know what is going wrong, but I also could reproduce the problem without using nvstreammux.
For that, on one pc (actually an other jetson) I have a simple pipeline running and publishing a h264 video stream in rtp

gst-launch-1.0 videotestsrc ! nvvideoconvert ! "video/x-raw(memory:NVMM),format=I420" ! nvv4l2h264enc ! h264parse ! rtspclientsink location="rtsp://192.168.79.31:8553/stream"

and on the other I decode and restream with this pipeline :

gst-launch-1.0 uridecodebin uri="rtsp://192.168.79.31:8553/stream" caps="video/x-raw(memory:NVMM)" ! nvvideoconvert ! "video/x-raw(memory:NVMM),format=I420" ! nvv4l2h264enc ! h264parse ! rtspclientsink location="rtsp://localhost:8554/stream"

While there is no problem with the first stream, the second one get me the same “B-frame” error when I try to publish in WebRTC.
What did you mean by the internal rtsp stream ?

I will try to use a different server as you suggested and see if I can replicate the problem.

12

There is no update from you for a period, assuming this is not an issue anymore. Hence we are closing this topic. If need further support, please open a new one. Thanks

This is a sony rtsp camera we used for testing

As I mentioned above, the problem is caused by your rtsp camera timestamp being out of order, so when you use videotestsrc or v4l2 camera, the problem will not occur.

The following is the error log

Pushing buffer 0xffff300d7c60, batch size 1, PTS 0:00:00.405811255
Pushing buffer 0xffff300de120, batch size 1, PTS 0:00:00.421545395
Pushing buffer 0xffff4403eb40, batch size 1, PTS 0:00:00.426704753
Pushing buffer 0xffff4403e900, batch size 1, PTS 5124095:34:32.055
Pushing buffer 0xffff300d7c60, batch size 1, PTS 5124095:34:32.092
Pushing buffer 0xffff300de120, batch size 1, PTS 5124095:34:32.133
Pushing buffer 0xffff4403eb40, batch size 1, PTS 0:00:00.417394538
Pushing buffer 0xffff4403e900, batch size 1, PTS 0:00:00.432483042
Pushing buffer 0xffff300d7c60, batch size 1, PTS 0:00:00.482665221
Pushing buffer 0xffff300de120, batch size 1, PTS 0:00:00.497895870
Pushing buffer 0xffff4403eb40, batch size 1, PTS 0:00:00.513157239
Pushing buffer 0xffff4403e900, batch size 1, PTS 0:00:00.523482483
Pushing buffer 0xffff300d7c60, batch size 1, PTS 0:00:00.538252937
Pushing buffer 0xffff300de120, batch size 1, PTS 0:00:00.551400181
Pushing buffer 0xffff300d7c60, batch size 1, PTS 0:00:06.864148717

The gst-launch-1.0 command line may not solve this problem.

The workaround is to modify the timestamp with the current clock time after encoding.

13

This topic was automatically closed 14 days after the last reply. New replies are no longer allowed.