You mentioned to run rm ~/.gstreamer-0.10/registry***, however I am doing rm ~/.cache/gstreamer-1.0/registry*** since that is what I have in my machine. Even after doing this it showing that plugin as blacklisted with warning. Here is the blacklists output,
(gst-plugin-scanner:4763): GStreamer-WARNING **: 14:20:20.655: Failed to load plugin ‘/usr/lib/x86_64-linux-gnu/gstreamer-1.0/deepstream/libnvdsgst_inferserver.so’: libtrtserver.so: cannot open shared object file: No such file or directory
Blacklisted files:
libnvdsgst_inferserver.so
(In the readme file provided with sample app, it is mentioned that even if we get this warning for libnvdsgst_inferserver.so plugin it is harmless while running the app first time)
Here are the logs while running with GST_DEBUG=nvinfer:5,
//start
myDS:/opt/nvidia/deepstream/deepstream-5.0/samples$ GST_DEBUG=nvinfer:5 deepstream-app -c configs/deepstream-app/source30_1080p_dec_infer-resnet_tiled_display_int8.txt
ERROR: …/nvdsinfer/nvdsinfer_model_builder.cpp:1408 Deserialize engine failed because file path: /opt/nvidia/deepstream/deepstream-5.0/samples/configs/deepstream-app/…/…/models/Primary_Detector/resnet10.caffemodel_b30_gpu0_int8.engine open error
0:00:00.493577627 4268 0x561364139a70 WARN nvinfer gstnvinfer.cpp:599:gst_nvinfer_logger:<primary_gie> NvDsInferContext[UID 1]: Warning from NvDsInferContextImpl::deserializeEngineAndBackend() <nvdsinfer_context_impl.cpp:1566> [UID = 1]: deserialize engine from file :/opt/nvidia/deepstream/deepstream-5.0/samples/configs/deepstream-app/…/…/models/Primary_Detector/resnet10.caffemodel_b30_gpu0_int8.engine failed
0:00:00.493615027 4268 0x561364139a70 WARN nvinfer gstnvinfer.cpp:599:gst_nvinfer_logger:<primary_gie> NvDsInferContext[UID 1]: Warning from NvDsInferContextImpl::generateBackendContext() <nvdsinfer_context_impl.cpp:1673> [UID = 1]: deserialize backend context from engine from file :/opt/nvidia/deepstream/deepstream-5.0/samples/configs/deepstream-app/…/…/models/Primary_Detector/resnet10.caffemodel_b30_gpu0_int8.engine failed, try rebuild
0:00:00.493629527 4268 0x561364139a70 INFO nvinfer gstnvinfer.cpp:602:gst_nvinfer_logger:<primary_gie> NvDsInferContext[UID 1]: Info from NvDsInferContextImpl::buildModel() <nvdsinfer_context_impl.cpp:1591> [UID = 1]: Trying to create engine from model files
WARNING: …/nvdsinfer/nvdsinfer_model_builder.cpp:1163 INT8 not supported by platform. Trying FP16 mode.
WARNING: …/nvdsinfer/nvdsinfer_model_builder.cpp:1177 FP16 not supported by platform. Using FP32 mode.
INFO: …/nvdsinfer/nvdsinfer_func_utils.cpp:37 [TRT]: Some tactics do not have sufficient workspace memory to run. Increasing workspace size may increase performance, please check verbose output.
INFO: …/nvdsinfer/nvdsinfer_func_utils.cpp:37 [TRT]: Detected 1 inputs and 2 output network tensors.
0:00:06.881915892 4268 0x561364139a70 INFO nvinfer gstnvinfer.cpp:602:gst_nvinfer_logger:<primary_gie> NvDsInferContext[UID 1]: Info from NvDsInferContextImpl::buildModel() <nvdsinfer_context_impl.cpp:1624> [UID = 1]: serialize cuda engine to file: /opt/nvidia/deepstream/deepstream-5.0/samples/models/Primary_Detector/resnet10.caffemodel_b30_gpu0_fp32.engine successfully
WARNING: …/nvdsinfer/nvdsinfer_func_utils.cpp:34 [TRT]: Current optimization profile is: 0. Please ensure there are no enqueued operations pending in this context prior to switching profiles
INFO: …/nvdsinfer/nvdsinfer_model_builder.cpp:685 [Implicit Engine Info]: layers num: 3
0 INPUT kFLOAT input_1 3x368x640
1 OUTPUT kFLOAT conv2d_bbox 16x23x40
2 OUTPUT kFLOAT conv2d_cov/Sigmoid 4x23x40
0:00:06.889358256 4268 0x561364139a70 INFO nvinfer gstnvinfer_impl.cpp:311:notifyLoadModelStatus:<primary_gie> [UID 1]: Load new model:/opt/nvidia/deepstream/deepstream-5.0/samples/configs/deepstream-app/config_infer_primary.txt sucessfully
Runtime commands:
h: Print this help
q: Quit
p: Pause
r: Resume
**PERF: FPS 0 (Avg) FPS 1 (Avg) FPS 2 (Avg) FPS 3 (Avg) FPS 4 (Avg) FPS 5 (Avg) FPS 6 (Avg) FPS 7 (Avg) FPS 8 (Avg) FPS 9 (Avg) FPS 10 (Avg) FPS 11 (Avg) FPS 12 (Avg) FPS 13 (Avg) FPS 14 (Avg) FPS 15 (Avg) FPS 16 (Avg) FPS 17 (Avg) FPS 18 (Avg) FPS 19 (Avg) FPS 20 (Avg) FPS 21 (Avg) FPS 22 (Avg) FPS 23 (Avg) FPS 24 (Avg) FPS 25 (Avg) FPS 26 (Avg) FPS 27 (Avg) FPS 28 (Avg) FPS 29 (Avg)
**PERF: 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00) 0.00 (0.00)
** INFO: <bus_callback:181>: Pipeline ready
Bus error (core dumped)
//end
Here while running with GST_DEBUG=5 I have only provided the last few lines of the log since the whole log is extremely large.
//start
GST_SCHEDULING gstpad.c:4320:gst_pad_chain_data_unchecked:multiqueue0:sink_0 calling chainfunction &gst_multi_queue_chain with buffer buffer: 0x7fad6813e1b0, pts 0:00:00.666666666, dts 0:00:00.600000000, dur 0:00:00.033333333, size 33878, offset none, offset_end none, flags 0x2000
0:01:19.929627162 3223 0x7fad9801bf70 DEBUG multiqueue gstmultiqueue.c:2864:single_queue_check_full: queue 0: visible 1/5, bytes 28133/2097152, time 33333334/0
0:01:19.929637461 3223 0x7fad9801bf70 DEBUG multiqueue gstmultiqueue.c:1510:apply_buffer: queue 0, sink position updated to 0:00:00.633333333
0:01:19.929647861 3223 0x7fad9801bf70 DEBUG multiqueue gstmultiqueue.c:1383:update_time_level: queue 0 sink_segment.position:0:00:00.633333333, sink_time:+0:00:00.566666667
0:01:19.929662561 3223 0x7fad9801bf70 DEBUG multiqueue gstmultiqueue.c:1433:update_time_level: queue 0, sink +0:00:00.566666667, src +0:00:00.500000000
0:01:19.929675261 3223 0x7fad9801bf70 DEBUG GST_SCHEDULING gstpad.c:4326:gst_pad_chain_data_unchecked:multiqueue0:sink_0 called chainfunction &gst_multi_queue_chain with buffer 0x7fad6813e1b0, returned ok
0:01:19.929364063 3223 0x7fad34007ad0 DEBUG GST_PADS gstpad.c:3910:push_sticky:decodebin2:src_0 event tag was already received
0:01:19.929703961 3223 0x7fad74006f20 DEBUG multiqueue gstmultiqueue.c:1865:gst_multi_queue_loop: groupid 0 high_time +99:99:99.999999999 next_time +0:00:00.655333333
0:01:19.929719261 3223 0x7fad74006f20 DEBUG multiqueue gstmultiqueue.c:1510:apply_buffer: queue 1, src position updated to 0:00:00.426666666
0:01:19.929726761 3223 0x7fad74006ed0 DEBUG GST_PADS gstpad.c:3910:push_sticky:h264parse0:src event caps was already received
0:01:19.929012565 3223 0x7fad880390a0 DEBUG multiqueue gstmultiqueue.c:1433:update_time_level: queue 1, sink +0:00:00.426666666, src +0:00:00.320000000
0:01:19.929745361 3223 0x7fad74006f20 DEBUG multiqueue gstmultiqueue.c:1433:update_time_level: queue 1, sink +0:00:00.554666666, src +0:00:00.426666666
0:01:19.929231264 3223 0x55e10b62a000 DEBUG GST_BUS gstbus.c:546:gst_bus_timed_pop_filtered: got message 0x7fada4002930, tag from src_fakesink, type mask is 4294967295
0:01:19.929393163 3223 0x55e10ba77140 DEBUG GST_PADS gstpad.c:3910:push_sticky:<primary_gie_conv:src> event stream-start was already received
0:01:19.929649661 3223 0x55e10ba666d0 DEBUG GST_BUFFER gstbuffer.c:2384:gst_buffer_foreach_meta: remove metadata 0x7fadc8035368 (NvDsMeta)
0:01:19.929651261 3223 0x55e10ba66ad0 DEBUG GST_SCHEDULING gstpad.c:4320:gst_pad_chain_data_unchecked:src:proxypad34 calling chainfunction &gst_proxy_pad_chain_default with buffer buffer: 0x7fad343457e0, pts 0:00:03.999999960, dts 99:99:99.999999999, dur 99:99:99.999999999, size 64, offset none, offset_end none, flags 0x0
0:01:19.929834560 3223 0x55e10ba666d0 DEBUG nvdspool gstnvdsbufferpool.c:492:gst_nvds_buffer_pool_release_buffer: release_buffer
0:01:19.929707261 3223 0x7fad34007ad0 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked:<src_0:proxypad52> sent event, ret ok
0:01:19.929737261 3223 0x7fad74006ed0 DEBUG GST_PADS gstpad.c:3910:push_sticky:h264parse0:src event segment was already received
0:01:19.929868460 3223 0x55e10ba66ad0 DEBUG GST_SCHEDULING gstpad.c:4320:gst_pad_chain_data_unchecked:<sink_bin:sink> calling chainfunction &gst_proxy_pad_chain_default with buffer buffer: 0x7fad343457e0, pts 0:00:03.999999960, dts 99:99:99.999999999, dur 99:99:99.999999999, size 64, offset none, offset_end none, flags 0x0
0:01:19.929884760 3223 0x55e10ba66ad0 DEBUG GST_SCHEDULING gstpad.c:4320:gst_pad_chain_data_unchecked:<sink_bin_queue:sink> calling chainfunction &gst_queue_chain with buffer buffer: 0x7fad343457e0, pts 0:00:03.999999960, dts 99:99:99.999999999, dur 99:99:99.999999999, size 64, offset none, offset_end none, flags 0x0
0:01:19.929320863 3223 0x7fad68018940 DEBUG GST_PADS gstpad.c:3910:push_sticky:aacparse3:src event caps was already received
0:01:19.929801661 3223 0x55e10ba77140 DEBUG GST_PADS gstpad.c:3910:push_sticky:<primary_gie_conv:src> event caps was already received
0:01:19.929927560 3223 0x55e10ba77140 DEBUG GST_PADS gstpad.c:3910:push_sticky:<primary_gie_conv:src> event segment was already received
0:01:19.929689661 3223 0x7fad9801bf70 DEBUG qtdemux qtdemux.c:8600:qtdemux_parse_samples: parsing up to sample 19
0:01:19.929857760 3223 0x7fad34007ad0 DEBUG GST_PADS gstpad.c:3932:push_sticky:nvv4l2decoder1:src event tag marked received
0:01:19.929979660 3223 0x7fad34007ad0 DEBUG GST_PADS gstpad.c:3910:push_sticky:nvv4l2decoder1:src event tag was already received
0:01:19.929886260 3223 0x7fad74006ed0 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked:capsfilter0:sink have event type tag event: 0x7fad5400f580, time 99:99:99.999999999, seq-num 8227, GstTagList-stream, taglist=(taglist)“taglist,\ video-codec=(string)“H.264\ (High\ Profile)”,\ language-code=(string)en,\ bitrate=(uint)5675753,\ minimum-bitrate=(uint)7129680,\ maximum-bitrate=(uint)7752000;”;
0:01:19.930013159 3223 0x7fad74006ed0 DEBUG GST_PADS gstpad.c:3980:check_sticky:capsfilter0:src pushing all sticky events
0:01:19.929895860 3223 0x7fad74006f20 DEBUG multiqueue gstmultiqueue.c:1638:gst_single_queue_push_one: SingleQueue 1 : Pushing buffer 0x7fad543293f0 with ts 0:00:00.405333333
0:01:19.930027559 3223 0x55e10ba668a0 DEBUG queue_dataflow gstqueue.c:1528:gst_queue_loop:<sink_bin_queue> queue is not empty
0:01:19.929896660 3223 0x7fad9c01b680 DEBUG GST_PADS gstpad.c:3910:push_sticky:<multi_src_bin:src> event stream-start was already received
0:01:19.929788361 3223 0x55e10b62a000 DEBUG GST_BUS gstbus.c:839:gst_bus_source_dispatch: source 0x55e10b85da50 calling dispatch with tag message: 0x7fada4002930, time 99:99:99.999999999, seq-num 8224, element ‘src_fakesink’, GstMessageTag, taglist=(taglist)“taglist,\ video-codec=(string)“H.264\ (High\ Profile)”,\ language-code=(string)en,\ bitrate=(uint)5675753,\ minimum-bitrate=(uint)7129680,\ maximum-bitrate=(uint)7486320;”;
0:01:19.930067259 3223 0x55e10b62a000 DEBUG NVDS_APP deepstream_app.c:76:bus_callback: Received message on bus: source src_fakesink, msg_type tag
0:01:19.929061765 3223 0x7fadd83426d0 DEBUG GST_BUS gstbus.c:357:gst_bus_post: [msg 0x55e114b8ff10] pushing on async queue
0:01:19.929838460 3223 0x7fad58009940 DEBUG GST_PADS gstpad.c:3910:push_sticky:<src_elem:src_0> event tag was already received
0:01:19.929949560 3223 0x55e10ba77140 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked:<primary_gie:sink> have event type tag event: 0x7facf00116d0, time 99:99:99.999999999, seq-num 8097, GstTagList-stream, taglist=(taglist)“taglist,\ video-codec=(string)“H.264\ (High\ Profile)”,\ language-code=(string)en,\ bitrate=(uint)5675753,\ minimum-bitrate=(uint)7129680,\ maximum-bitrate=(uint)7486320;”;
0:01:19.929949460 3223 0x55e10ba666d0 DEBUG GST_BUFFER gstbuffer.c:1375:gst_buffer_is_memory_range_writable: idx 0, length -1
0:01:19.929966360 3223 0x7fad9801bf70 DEBUG qtdemux qtdemux.c:8812:qtdemux_parse_samples: sample 19: index 19, timestamp 0:00:00.633333333
0:01:19.930155759 3223 0x55e10ba666d0 DEBUG GST_SCHEDULING gstpad.c:4320:gst_pad_chain_data_unchecked:<sink_sub_bin_cap_filter1:sink> calling chainfunction &gst_base_transform_chain with buffer buffer: 0x7fadcc0db4b0, pts 0:00:03.899999961, dts 99:99:99.999999999, dur 99:99:99.999999999, size 64, offset none, offset_end none, flags 0x0
0:01:19.929996160 3223 0x7fad34007ad0 DEBUG videodecoder gstvideodecoder.c:2667:gst_video_decoder_prepare_finish_frame: sync timestamp 0:00:00.400000000 diff -0:00:00.333333334
0:01:19.930022959 3223 0x7fad74006ed0 DEBUG GST_PADS gstpad.c:3910:push_sticky:capsfilter0:src event stream-start was already received
0:01:19.930200758 3223 0x7fad74006ed0 DEBUG GST_PADS gstpad.c:3910:push_sticky:capsfilter0:src event caps was already received
0:01:19.930211058 3223 0x7fad74006f20 DEBUG GST_SCHEDULING gstpad.c:4320:gst_pad_chain_data_unchecked:aacparse0:sink calling chainfunction &gst_base_parse_chain with buffer buffer: 0x7fad543293f0, pts 0:00:00.405333333, dts 0:00:00.405333333, dur 0:00:00.021333333, size 365, offset none, offset_end none, flags 0x0
0:01:19.930224358 3223 0x7fad74006f20 DEBUG baseparse gstbaseparse.c:3029:gst_base_parse_chain: chain
0:01:19.930244758 3223 0x7fad74006f20 DEBUG GST_CAPS gstpad.c:2705:gst_pad_has_current_caps:aacparse0:src check current pad caps audio/mpeg, mpegversion=(int)4, framed=(boolean)true, stream-format=(string)raw, level=(string)2, base-profile=(string)lc, profile=(string)lc, codec_data=(buffer)119056e500, rate=(int)48000, channels=(int)2
0:01:19.930256258 3223 0x7fad80007f20 DEBUG GST_BUFFER gstbuffer.c:1148:gst_buffer_get_memory_range: idx 0, length 1
0:01:19.930081759 3223 0x55e10b62a000 DEBUG GST_BUS gstbus.c:844:gst_bus_source_dispatch: source 0x55e10b85da50 handler returns 1
0:01:19.930100359 3223 0x7fadd83426d0 DEBUG GST_BUS gstbus.c:360:gst_bus_post: [msg 0x55e114b8ff10] pushed on async queue
0:01:19.930301758 3223 0x55e10b62a000 DEBUG GST_BUS gstbus.c:546:gst_bus_timed_pop_filtered: got message 0x55e114b8ff10, state-changed from aacparse3, type mask is 4294967295
0:01:19.930112359 3223 0x7fad58009940 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked:<src_0:proxypad78> sent event, ret ok
0:01:19.930128859 3223 0x55e10ba77140 DEBUG GST_PADS gstpad.c:3980:check_sticky:<primary_gie:src> pushing all sticky events
0:01:19.930156659 3223 0x55e10ba77320 DEBUG basetransform gstbasetransform.c:1745:default_copy_metadata:<osd_conv> copying metadata
0:01:19.929751461 3223 0x7fad880390a0 DEBUG GST_SCHEDULING gstpad.c:4326:gst_pad_chain_data_unchecked:multiqueue2:sink_1 called chainfunction &gst_multi_queue_chain with buffer 0x7fadbc011840, returned not-linked
0:01:19.930374158 3223 0x7fad880390a0 DEBUG flowcombiner gstflowcombiner.c:217:gst_flow_combiner_get_flow: Combining flow returns
0:01:19.930379857 3223 0x55e10ba77140 DEBUG GST_PADS gstpad.c:3910:push_sticky:<primary_gie:src> event stream-start was already received
0:01:19.930396057 3223 0x55e10ba77140 DEBUG GST_PADS gstpad.c:3910:push_sticky:<primary_gie:src> event caps was already received
0:01:19.930408457 3223 0x55e10ba77140 DEBUG GST_PADS gstpad.c:3910:push_sticky:<primary_gie:src> event segment was already received
0:01:19.930053359 3223 0x55e10ba66ad0 DEBUG GST_SCHEDULING gstpad.c:4326:gst_pad_chain_data_unchecked:<sink_bin_queue:sink> called chainfunction &gst_queue_chain with buffer 0x7fad343457e0, returned ok
0:01:19.930432157 3223 0x55e10ba77140 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked:src:proxypad36 have event type tag event: 0x7facf00116d0, time 99:99:99.999999999, seq-num 8097, GstTagList-stream, taglist=(taglist)“taglist,\ video-codec=(string)“H.264\ (High\ Profile)”,\ language-code=(string)en,\ bitrate=(uint)5675753,\ minimum-bitrate=(uint)7129680,\ maximum-bitrate=(uint)7486320;”;
0:01:19.930257158 3223 0x7fad74006f20 DEBUG GST_SCHEDULING gstpad.c:4320:gst_pad_chain_data_unchecked:<avdec_aac1:sink> calling chainfunction &gst_audio_decoder_chain with buffer buffer: 0x7fad543293f0, pts 0:00:00.405333333, dts 0:00:00.405333333, dur 0:00:00.021333333, size 365, offset none, offset_end none, flags 0x0
0:01:19.930277258 3223 0x7fad80007f20 DEBUG GST_BUFFER gstbuffer.c:1148:gst_buffer_get_memory_range: idx 0, length 1
0:01:19.929913260 3223 0x7fad68018940 DEBUG GST_PADS gstpad.c:3910:push_sticky:aacparse3:src event segment was already received
0:01:19.930520557 3223 0x7fad68018940 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked:<avdec_aac3:sink> have event type tag event: 0x7fad0400d090, time 99:99:99.999999999, seq-num 8228, GstTagList-stream, taglist=(taglist)“taglist,\ audio-codec=(string)“MPEG-4\ AAC”,\ maximum-bitrate=(uint)128882,\ bitrate=(uint)128825,\ language-code=(string)en,\ minimum-bitrate=(uint)140999;”;
0:01:19.930535757 3223 0x7fad68018940 DEBUG audiodecoder gstaudiodecoder.c:2340:gst_audio_decoder_sink_event:<avdec_aac3> received event 20510, tag
0:01:19.930551757 3223 0x7fad68018940 INFO audiodecoder gstaudiodecoder.c:2294:gst_audio_decoder_sink_eventfunc:<avdec_aac3> upstream stream tags: taglist, audio-codec=(string)“MPEG-4\ AAC”, maximum-bitrate=(uint)128882, bitrate=(uint)128825, language-code=(string)en, minimum-bitrate=(uint)140999;
0:01:19.930361958 3223 0x55e10ba77320 DEBUG basetransform gstbasetransform.c:1717:foreach_metadata:<osd_conv> transformed metadata NvDsMetaAPI: copy: 0
0:01:19.930566356 3223 0x7fad9801bf70 DEBUG qtdemux qtdemux.c:5826:gst_qtdemux_loop_state_movie: pushing from stream 1, empty 0 offset 597978, size 377, dts=0:00:00.554666666, pts=0:00:00.554666666, duration 0:00:00.021333334
0:01:19.930582156 3223 0x7fad9801bf70 DEBUG basesrc gstbasesrc.c:2399:gst_base_src_update_length: reading offset 597978, length 377, size 34952625, segment.stop -1, maxsize 34952625
0:01:19.930593956 3223 0x55e10ba666d0 DEBUG basetransform gstbasetransform.c:1990:default_submit_input_buffer:<sink_sub_bin_cap_filter1> handling buffer 0x7fadcc0db4b0 of size 64, PTS 0:00:03.899999961 and offset NONE
0:01:19.930607956 3223 0x55e10ba666d0 DEBUG basetransform gstbasetransform.c:2102:default_generate_output:<sink_sub_bin_cap_filter1> calling prepare buffer
0:01:19.930620356 3223 0x55e10ba666d0 DEBUG basetransform gstbasetransform.c:2109:default_generate_output:<sink_sub_bin_cap_filter1> using allocated buffer in 0x7fadcc0db4b0, out 0x7fadcc0db4b0
0:01:19.930631356 3223 0x55e10ba666d0 DEBUG basetransform gstbasetransform.c:2126:default_generate_output:<sink_sub_bin_cap_filter1> doing inplace transform
0:01:19.930646456 3223 0x55e10ba666d0 DEBUG GST_SCHEDULING gstpad.c:4320:gst_pad_chain_data_unchecked:<sink_sub_bin_encoder1:sink> calling chainfunction &gst_video_encoder_chain with buffer buffer: 0x7fadcc0db4b0, pts 0:00:03.899999961, dts 99:99:99.999999999, dur 99:99:99.999999999, size 64, offset none, offset_end none, flags 0x0
0:01:19.930523957 3223 0x7fadd83426d0 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x55e114b8ff10] dropped
0:01:19.930668356 3223 0x7fadd83426d0 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x55e114b8ff10] dropped
0:01:19.930677356 3223 0x7fadd83426d0 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x55e114b8ff10] dropped
0:01:19.930698156 3223 0x7fadd83426d0 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x55e114b8ff10] dropped
0:01:19.930710456 3223 0x7fadd83426d0 DEBUG GST_BUS gstbus.c:353:gst_bus_post: [msg 0x55e114b8ff10] dropped
0:01:19.930722456 3223 0x7fadd83426d0 DEBUG GST_STATES gstelement.c:2911:gst_element_set_state_func: returned SUCCESS
0:01:19.930736556 3223 0x7fadd83426d0 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘aacparse3’ changed state to 4(PLAYING) successfully
0:01:19.930750955 3223 0x7fadd83426d0 DEBUG bin gstbin.c:2372:gst_bin_sort_iterator_next: queue head gives multiqueue2
0:01:19.930763655 3223 0x7fadd83426d0 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000
0:01:19.930774955 3223 0x7fadd83426d0 DEBUG bin gstbin.c:892:find_message: structure-change
0:01:19.930450657 3223 0x55e10ba77140 DEBUG GST_PADS gstpad.c:3980:check_sticky:<primary_gie_bin:src> pushing all sticky events
0:01:19.930803455 3223 0x55e10ba77140 DEBUG GST_PADS gstpad.c:3910:push_sticky:<primary_gie_bin:src> event stream-start was already received
0:01:19.930816355 3223 0x55e10ba77140 DEBUG GST_PADS gstpad.c:3910:push_sticky:<primary_gie_bin:src> event caps was already received
0:01:19.930828355 3223 0x55e10ba77140 DEBUG GST_PADS gstpad.c:3910:push_sticky:<primary_gie_bin:src> event segment was already received
0:01:19.930836655 3223 0x55e10ba77140 DEBUG GST_PADS gstpad.c:3810:do_probe_callbacks:<primary_gie_bin:src> data is passed
0:01:19.930661156 3223 0x55e10b62a000 DEBUG GST_BUS gstbus.c:839:gst_bus_source_dispatch: source 0x55e10b85da50 calling dispatch with state-changed message: 0x55e114b8ff10, time 99:99:99.999999999, seq-num 8223, element ‘aacparse3’, GstMessageStateChanged, old-state=(GstState)GST_STATE_PAUSED, new-state=(GstState)GST_STATE_PLAYING, pending-state=(GstState)GST_STATE_VOID_PENDING;
0:01:19.930677756 3223 0x7fad58009940 DEBUG GST_PADS gstpad.c:3932:push_sticky:decodebin0:src_0 event tag marked received
0:01:19.930181259 3223 0x7fad34007ad0 DEBUG default gstvideoutils.c:40:_gst_video_codec_frame_free: free frame 0x7fad3435c220
0:01:19.930690056 3223 0x7fad68018940 DEBUG audiodecoder gstaudiodecoder.c:567:gst_audio_decoder_create_merged_tags_event:<avdec_aac3> merged : taglist, audio-codec=(string)“MPEG-4\ AAC”, maximum-bitrate=(uint)128882, bitrate=(uint)128825, language-code=(string)en, minimum-bitrate=(uint)140999;
0:01:19.930914555 3223 0x7fad34007ad0 DEBUG videodecoder gstvideodecoder.c:3188:gst_video_decoder_clip_and_push_buf: pushing buffer 0x7fad60155be0 of size 64, PTS 0:00:00.400000000, dur 0:00:00.033333333
0:01:19.930571356 3223 0x55e10ba77320 DEBUG basetransform gstbasetransform.c:2109:default_generate_output:<osd_conv> using allocated buffer in 0x7fad6c14b270, out 0x7fad343458f0
0:01:19.930945854 3223 0x55e10ba77320 DEBUG basetransform gstbasetransform.c:2129:default_generate_output:<osd_conv> doing non-inplace transform
0:01:19.930607956 3223 0x7fad74006ed0 DEBUG GST_PADS gstpad.c:3910:push_sticky:capsfilter0:src event segment was already received
0:01:19.930989154 3223 0x7fad9c01b680 DEBUG GST_PADS gstpad.c:3910:push_sticky:<multi_src_bin:src> event caps was already received
0:01:19.930996054 3223 0x55e10ba77320 DEBUG GST_BUFFER gstbuffer.c:2202:gst_buffer_add_meta: alloc metadata 0x7fadc8035308 (NvDsMeta) of size 72
0:01:19.930465657 3223 0x7fad74006f20 DEBUG audiodecoder gstaudiodecoder.c:1466:gst_audio_decoder_push_buffers:<avdec_aac1> available: 365
0:01:19.930657656 3223 0x55e10ba666d0 DEBUG v4l2videoenc gstv4l2videoenc.c:1117:gst_v4l2_video_enc_handle_frame:<sink_sub_bin_encoder1> Handling frame 6
0:01:19.930853155 3223 0x55e10ba77140 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked:<common_analytics_tee:sink> have event type tag event: 0x7facf00116d0, time 99:99:99.999999999, seq-num 8097, GstTagList-stream, taglist=(taglist)“taglist,\ video-codec=(string)“H.264\ (High\ Profile)”,\ language-code=(string)en,\ bitrate=(uint)5675753,\ minimum-bitrate=(uint)7129680,\ maximum-bitrate=(uint)7486320;”;
0:01:19.931062054 3223 0x55e10ba666d0 DEBUG v4l2bufferpool gstv4l2bufferpool.c:2075:gst_v4l2_buffer_pool_process:<sink_sub_bin_encoder1:pool:sink> process buffer 0x7fadcc109538
0:01:19.931079354 3223 0x55e10ba666d0 DEBUG v4l2bufferpool gstv4l2bufferpool.c:1642:gst_v4l2_buffer_pool_acquire_buffer:<sink_sub_bin_encoder1:pool:sink> acquire
0:01:19.930891755 3223 0x7fad58009940 DEBUG GST_PADS gstpad.c:3910:push_sticky:decodebin0:src_0 event tag was already received
0:01:19.931102654 3223 0x7fad58009940 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked:<src_0:proxypad48> sent event, ret ok
0:01:19.931112754 3223 0x7fad58009940 DEBUG GST_PADS gstpad.c:3932:push_sticky:nvv4l2decoder3:src event tag marked received
0:01:19.931121154 3223 0x7fad880390a0 DEBUG flowcombiner gstflowcombiner.c:240:gst_flow_combiner_get_flow: Combined flow return: ok (0)
0:01:19.930969754 3223 0x7fad9801bf70 DEBUG basesrc gstbasesrc.c:2506:gst_base_src_get_range: calling create offset 597978 length 377, time 0
0:01:19.930992354 3223 0x7fad74006ed0 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked:nvv4l2decoder2:sink have event type tag event: 0x7fad5400f580, time 99:99:99.999999999, seq-num 8227, GstTagList-stream, taglist=(taglist)“taglist,\ video-codec=(string)“H.264\ (High\ Profile)”,\ language-code=(string)en,\ bitrate=(uint)5675753,\ minimum-bitrate=(uint)7129680,\ maximum-bitrate=(uint)7752000;”;
0:01:19.931174553 3223 0x7fad9c01b680 DEBUG GST_PADS gstpad.c:3910:push_sticky:<multi_src_bin:src> event segment was already received
0:01:19.930788255 3223 0x7fadd83426d0 DEBUG bin gstbin.c:2282:update_degree: change element qtdemux0, degree 2->1, linked to multiqueue2
0:01:19.931064954 3223 0x55e10ba77140 DEBUG GST_PADS gstpad.c:2897:gst_pad_iterate_internal_links_default:<common_analytics_tee:sink> Making iterator
0:01:19.931034654 3223 0x7fad74006f20 DEBUG libav gstavauddec.c:472:gst_ffmpegauddec_audio_frame:<avdec_aac1> size: 365
0:01:19.931216853 3223 0x7fad880390a0 DEBUG qtdemux qtdemux.c:8600:qtdemux_parse_samples: parsing up to sample 20
0:01:19.930933355 3223 0x7fad34007ad0 DEBUG GST_SCHEDULING gstpad.c:4320:gst_pad_chain_data_unchecked:<src_0:proxypad52> calling chainfunction &gst_proxy_pad_chain_default with buffer buffer: 0x7fad60155be0, pts 0:00:00.400000000, dts 99:99:99.999999999, dur 0:00:00.033333333, size 64, offset none, offset_end none, flags 0x0
0:01:19.931123553 3223 0x7fad58009940 DEBUG GST_PADS gstpad.c:3910:push_sticky:nvv4l2decoder3:src event tag was already received
0:01:19.931155453 3223 0x7fad9801bf70 DEBUG GST_MEMORY gstmemory.c:139:gst_memory_init: new memory 0x7fad6c448aa0, maxsize:384 offset:0 size:377
0:01:19.931174853 3223 0x7fad74006ed0 DEBUG videodecoder gstvideodecoder.c:1384:gst_video_decoder_sink_event: received event 20510, tag
0:01:19.931321352 3223 0x7fad74006ed0 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, language-code=(string)en, bitrate=(uint)5675753, minimum-bitrate=(uint)7129680, maximum-bitrate=(uint)7752000;
0:01:19.931337752 3223 0x7fad74006ed0 DEBUG videodecoder gstvideodecoder.c:813:gst_video_decoder_create_merged_tags_event: merged : taglist, video-codec=(string)“H.264\ (High\ Profile)”, language-code=(string)en, bitrate=(uint)5675753, minimum-bitrate=(uint)7129680, maximum-bitrate=(uint)7752000;
0:01:19.931429952 3223 0x7fad74006ed0 DEBUG GST_EVENT gstevent.c:306:gst_event_new_custom: creating new event 0x7fad5400f740 tag 20510
0:01:19.931442752 3223 0x7fad74006ed0 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked:nvv4l2decoder2:sink sent event, ret ok
0:01:19.931457052 3223 0x7fad74006ed0 DEBUG GST_PADS gstpad.c:3932:push_sticky:capsfilter0:src event tag marked received
0:01:19.931470252 3223 0x7fad74006ed0 DEBUG GST_PADS gstpad.c:3910:push_sticky:capsfilter0:src event tag was already received
0:01:19.931482952 3223 0x7fad74006ed0 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked:capsfilter0:sink sent event, ret ok
0:01:19.931219653 3223 0x55e10ba66ad0 DEBUG GST_SCHEDULING gstpad.c:4326:gst_pad_chain_data_unchecked:<sink_bin:sink> called chainfunction &gst_proxy_pad_chain_default with buffer 0x7fad343457e0, returned ok
0:01:19.931113854 3223 0x7fad68018940 DEBUG GST_EVENT gstevent.c:306:gst_event_new_custom: creating new event 0x7fad0400d020 tag 20510
0:01:19.931195753 3223 0x7fad9c01b680 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked:<primary_gie_bin:sink> have event type tag event: 0x7fad3400d2c0, time 99:99:99.999999999, seq-num 8185, GstTagList-stream, taglist=(taglist)“taglist,\ video-codec=(string)“H.264\ (High\ Profile)”,\ language-code=(string)en,\ bitrate=(uint)5675753,\ minimum-bitrate=(uint)7255680,\ maximum-bitrate=(uint)7486320;”;
0:01:19.931251453 3223 0x7fad34007ad0 DEBUG GST_PADS gstpad.c:3810:do_probe_callbacks:decodebin2:src_0 data is passed
0:01:19.931282953 3223 0x55e10ba668a0 DEBUG GST_SCHEDULING gstpad.c:4320:gst_pad_chain_data_unchecked:<sink_bin_tee:sink> calling chainfunction &gst_tee_chain with buffer buffer: 0x7fad343457e0, pts 0:00:03.999999960, dts 99:99:99.999999999, dur 99:99:99.999999999, size 64, offset none, offset_end none, flags 0x0
0:01:19.931573551 3223 0x55e10ba668a0 DEBUG tee gsttee.c:1020:gst_tee_chain:<sink_bin_tee> received buffer 0x7fad343457e0
0:01:19.931589651 3223 0x55e10ba668a0 DEBUG GST_SCHEDULING gstpad.c:4320:gst_pad_chain_data_unchecked:<sink_sub_bin1:sink> calling chainfunction &gst_proxy_pad_chain_default with buffer buffer: 0x7fad343457e0, pts 0:00:03.999999960, dts 99:99:99.999999999, dur 99:99:99.999999999, size 64, offset none, offset_end none, flags 0x0
0:01:19.931600851 3223 0x55e10ba668a0 DEBUG GST_SCHEDULING gstpad.c:4320:gst_pad_chain_data_unchecked:<sink_sub_bin_queue1:sink> calling chainfunction &gst_queue_chain with buffer buffer: 0x7fad343457e0, pts 0:00:03.999999960, dts 99:99:99.999999999, dur 99:99:99.999999999, size 64, offset none, offset_end none, flags 0x0
0:01:19.931609551 3223 0x55e10ba668a0 DEBUG GST_SCHEDULING gstpad.c:4326:gst_pad_chain_data_unchecked:<sink_sub_bin_queue1:sink> called chainfunction &gst_queue_chain with buffer 0x7fad343457e0, returned ok
0:01:19.931618551 3223 0x55e10ba668a0 DEBUG GST_SCHEDULING gstpad.c:4326:gst_pad_chain_data_unchecked:<sink_sub_bin1:sink> called chainfunction &gst_proxy_pad_chain_default with buffer 0x7fad343457e0, returned ok
0:01:19.931625551 3223 0x55e10ba668a0 DEBUG tee gsttee.c:1024:gst_tee_chain:<sink_bin_tee> handled buffer ok
0:01:19.931633951 3223 0x55e10ba668a0 DEBUG GST_SCHEDULING gstpad.c:4326:gst_pad_chain_data_unchecked:<sink_bin_tee:sink> called chainfunction &gst_tee_chain with buffer 0x7fad343457e0, returned ok
0:01:19.931641551 3223 0x55e10ba668a0 DEBUG queue_dataflow gstqueue.c:1516:gst_queue_loop:<sink_bin_queue> queue is empty
0:01:19.931353552 3223 0x55e10ba666d0 DEBUG v4l2bufferpool gstv4l2bufferpool.c:1720:gst_v4l2_buffer_pool_release_buffer:<sink_sub_bin_encoder1:pool:sink> release buffer 0x7fad583375a0
0:01:19.931299653 3223 0x7fad9801bf70 DEBUG basesrc gstbasesrc.c:2360:gst_base_src_do_sync: no sync needed
0:01:19.931681150 3223 0x7fad9801bf70 DEBUG basesrc gstbasesrc.c:2597:gst_base_src_get_range: buffer ok
0:01:19.931702550 3223 0x7fad9801bf70 DEBUG GST_PADS gstpad.c:3810:do_probe_callbacks:qtdemux2:audio_0 data is passed
0:01:19.931711450 3223 0x7fad9801bf70 DEBUG GST_PADS gstpad.c:3810:do_probe_callbacks:multiqueue0:sink_1 data is passed
0:01:19.931724650 3223 0x7fad9801bf70 DEBUG GST_SCHEDULING gstpad.c:4320:gst_pad_chain_data_unchecked:multiqueue0:sink_1 calling chainfunction &gst_multi_queue_chain with buffer buffer: 0x7fad601559c0, pts 0:00:00.554666666, dts 0:00:00.554666666, dur 0:00:00.021333334, size 377, offset none, offset_end none, flags 0x0
0:01:19.931737050 3223 0x7fad9801bf70 DEBUG multiqueue gstmultiqueue.c:2864:single_queue_check_full: queue 1: visible 6/6, bytes 2120/2097152, time 128000000/0
0:01:19.931745550 3223 0x7fad9801bf70 DEBUG multiqueue gstmultiqueue.c:2807:single_queue_overrun_cb: Queue 1 is filled, signalling overrun
0:01:19.931756350 3223 0x7fad9801bf70 DEBUG multiqueue gstmultiqueue.c:2864:single_queue_check_full: queue 1: visible 6/6, bytes 2120/2097152, time 128000000/0
0:01:19.930873955 3223 0x55e10b62a000 DEBUG NVDS_APP deepstream_app.c:76:bus_callback: Received message on bus: source aacparse3, msg_type state-changed
0:01:19.931215453 3223 0x7fadd83426d0 DEBUG bin gstbin.c:885:find_message: no message found matching types 00001000
0:01:19.931795250 3223 0x7fadd83426d0 DEBUG bin gstbin.c:892:find_message: structure-change
0:01:19.931495651 3223 0x7fad74006ed0 DEBUG GST_PADS gstpad.c:3932:push_sticky:h264parse0:src event tag marked received
0:01:19.931515051 3223 0x55e10ba66ad0 DEBUG GST_SCHEDULING gstpad.c:4326:gst_pad_chain_data_unchecked:src:proxypad34 called chainfunction &gst_proxy_pad_chain_default with buffer 0x7fad343457e0, returned ok
0:01:19.931236853 3223 0x7fad880390a0 DEBUG qtdemux qtdemux.c:8812:qtdemux_parse_samples: sample 20: index 20, timestamp 0:00:00.426666666
0:01:19.931858950 3223 0x7fad880390a0 DEBUG qtdemux qtdemux.c:8914:qtdemux_parse_samples: setting all keyframes
0:01:19.931881749 3223 0x7fad880390a0 DEBUG qtdemux qtdemux.c:5826:gst_qtdemux_loop_state_movie: pushing from stream 1, empty 0 offset 471572, size 367, dts=0:00:00.426666666, pts=0:00:00.426666666, duration 0:00:00.021333334
0:01:19.931888549 3223 0x55e10ba77140 DEBUG GST_PADS gstpad.c:3980:check_sticky:<common_analytics_tee:src_0> pushing all sticky events
0:01:19.931896849 3223 0x7fad880390a0 DEBUG basesrc gstbasesrc.c:2399:gst_base_src_update_length: reading offset 471572, length 367, size 34952625, segment.stop -1, maxsize 34952625
0:01:19.931910749 3223 0x7fad880390a0 DEBUG basesrc gstbasesrc.c:2506:gst_base_src_get_range: calling create offset 471572 length 367, time 0
0:01:19.931442352 3223 0x55e10ba77320 DEBUG GST_MEMORY gstmemory.c:88:_gst_memory_free: free memory 0x7fadb400d970
0:01:19.931283453 3223 0x7fad58009940 DEBUG videodecoder gstvideodecoder.c:2667:gst_video_decoder_prepare_finish_frame: sync timestamp 0:00:00.533333333 diff -0:00:00.466666667
0:01:19.931961849 3223 0x7fad58009940 DEBUG default gstvideoutils.c:40:_gst_video_codec_frame_free: free frame 0x7fadcc109940
0:01:19.931981049 3223 0x7fadd83426d0 DEBUG bin gstbin.c:2282:update_degree: change element qtdemux0, degree 1->0, linked to multiqueue2
0:01:19.931995149 3223 0x7fadd83426d0 DEBUG bin gstbin.c:2165:add_to_queue: adding ‘qtdemux0’ to queue
0:01:19.931848750 3223 0x7fad74006f20 DEBUG libav gstavauddec.c:480:gst_ffmpegauddec_audio_frame:<avdec_aac1> Decode audio: len=365, have_data=1
0:01:19.931848350 3223 0x55e10ba66ad0 DEBUG GST_SCHEDULING gstpad.c:4326:gst_pad_chain_data_unchecked:nvosd0:sink called chainfunction &gst_base_transform_chain with buffer 0x7fad343457e0, returned ok
0:01:19.932040449 3223 0x55e10ba66ad0 DEBUG queue_dataflow gstqueue.c:1516:gst_queue_loop:<osd_conv_queue> queue is empty
0:01:19.931561851 3223 0x7fad34007ad0 DEBUG GST_SCHEDULING gstpad.c:4320:gst_pad_chain_data_unchecked:<src_0:proxypad70> calling chainfunction &gst_proxy_pad_chain_default with buffer buffer: 0x7fad60155be0, pts 0:00:00.400000000, dts 99:99:99.999999999, dur 0:00:00.033333333, size 64, offset none, offset_end none, flags 0x0
0:01:19.931898849 3223 0x55e10ba77140 DEBUG GST_PADS gstpad.c:3910:push_sticky:<common_analytics_tee:src_0> event stream-start was already received
0:01:19.931641151 3223 0x7facf001e370 DEBUG v4l2bufferpool gstv4l2bufferpool.c:175:gst_v4l2_buffer_pool_copy_buffer:<sink_sub_bin_encoder1:pool:src> copy raw bytes
0:01:19.931939549 3223 0x7fad880390a0 DEBUG GST_MEMORY gstmemory.c:139:gst_memory_init: new memory 0x7fad60152ec0, maxsize:374 offset:0 size:367
0:01:19.932121848 3223 0x7fad880390a0 DEBUG basesrc gstbasesrc.c:2360:gst_base_src_do_sync: no sync needed
0:01:19.932130548 3223 0x7fad880390a0 DEBUG basesrc gstbasesrc.c:2597:gst_base_src_get_range: buffer ok
0:01:19.932140448 3223 0x7fad880390a0 DEBUG GST_PADS gstpad.c:3810:do_probe_callbacks:qtdemux0:audio_0 data is passed
0:01:19.932148948 3223 0x7fad880390a0 DEBUG GST_PADS gstpad.c:3810:do_probe_callbacks:multiqueue2:sink_1 data is passed
0:01:19.932157648 3223 0x7fad880390a0 DEBUG GST_SCHEDULING gstpad.c:4320:gst_pad_chain_data_unchecked:multiqueue2:sink_1 calling chainfunction &gst_multi_queue_chain with buffer buffer: 0x7fad6c14b8d0, pts 0:00:00.426666666, dts 0:00:00.426666666, dur 0:00:00.021333334, size 367, offset none, offset_end none, flags 0x0
Bus error (core dumped)
//end
If needed I can give additional parts of the logs as well.