Bus error while running deepstream refrerence app

Accelerated Computing Intelligent Video Analytics DeepStream SDK
1

Hi,
I did a complete installation according to the deepstream installation guide, however when I run the reference app, I am getting the following error.

//
myDS:/opt/nvidia/deepstream/deepstream-5.0/samples$ deepstream-app -c configs/deepstream-app/source30_1080p_dec_infer-resnet_tiled_display_int8.txt

(gst-plugin-scanner:23649): GStreamer-WARNING **: 07:20:20.152: 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
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.915972517 23648 0x560d8953f070 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.916011616 23648 0x560d8953f070 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.916026116 23648 0x560d8953f070 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:07.516771844 23648 0x560d8953f070 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:07.524027159 23648 0x560d8953f070 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

** INFO: <bus_callback:167>: Pipeline running

Bus error (core dumped)
//

Since I am using a virtual machine in Azure, I just changed the sink from eglsink to file and also disabled tiled display in the config files I tested.

I also tried by removing cache but got the same error and then I checked for blacklisted plugins and got the following response.

Blacklisted files:
libnvdsgst_inferserver.so

Total count: 1 blacklisted file

I am not sure what is causing this error and what I should do. It would be great if I can get some help.

• Hardware Platform (Jetson / GPU) - Tesla M60
• DeepStream Version - DeepStream 5.0.0
• TensorRT Version - TensorRT - 7.0.0.11
• NVIDIA GPU Driver Version (valid for GPU only) - 440.33.01
• Operating System - Ubuntu 18.04

(I got the same error while trying with deepstream 4.0.2 as well)

1 Like
3

May I know which platform you running on?

4

I am not sure what you mean by platform.
Hardware platform : Tesla M60
CUDA version : 10.2
cuDNN : 7.6.5
TensorRT : 7.0.0.11
DeepStream : 5.0
OS : Ubuntu 18.04
Installed everything using debian packages.

5

oh, see it, thanks.
Can you run success using TensorRT with same model as deepstream used
/usr/src/tensorrt/bin/trtexec --deploy=/opt/nvidia/deepstream/deepstream/samples/models/Primary_Detector/resnet10.prototxt --output=conv2d_bbox --output=conv2d_cov/Sigmoid

6

Yes, it says that the test is PASSED. Given below is the full output I got,

myDS:~$ /usr/src/tensorrt/bin/trtexec --deploy=/opt/nvidia/deepstream/deepstream/samples/models/Primary_Detector/resnet10.prototxt --output=conv2d_bbox --output=conv2d_cov/Sigmoid
&&&& RUNNING TensorRT.trtexec # /usr/src/tensorrt/bin/trtexec --deploy=/opt/nvidia/deepstream/deepstream/samples/models/Primary_Detector/resnet10.prototxt --output=conv2d_bbox --output=conv2d_cov/Sigmoid
[06/01/2020-04:49:12] [I] === Model Options ===
[06/01/2020-04:49:12] [I] Format: Caffe
[06/01/2020-04:49:12] [I] Model:
[06/01/2020-04:49:12] [I] Prototxt: /opt/nvidia/deepstream/deepstream/samples/models/Primary_Detector/resnet10.prototxt
[06/01/2020-04:49:12] [I] Output: conv2d_cov/Sigmoid conv2d_bbox
[06/01/2020-04:49:12] [I] === Build Options ===
[06/01/2020-04:49:12] [I] Max batch: 1
[06/01/2020-04:49:12] [I] Workspace: 16 MB
[06/01/2020-04:49:12] [I] minTiming: 1
[06/01/2020-04:49:12] [I] avgTiming: 8
[06/01/2020-04:49:12] [I] Precision: FP32
[06/01/2020-04:49:12] [I] Calibration:
[06/01/2020-04:49:12] [I] Safe mode: Disabled
[06/01/2020-04:49:12] [I] Save engine:
[06/01/2020-04:49:12] [I] Load engine:
[06/01/2020-04:49:12] [I] Inputs format: fp32:CHW
[06/01/2020-04:49:12] [I] Outputs format: fp32:CHW
[06/01/2020-04:49:12] [I] Input build shapes: model
[06/01/2020-04:49:12] [I] === System Options ===
[06/01/2020-04:49:12] [I] Device: 0
[06/01/2020-04:49:12] [I] DLACore:
[06/01/2020-04:49:12] [I] Plugins:
[06/01/2020-04:49:12] [I] === Inference Options ===
[06/01/2020-04:49:12] [I] Batch: 1
[06/01/2020-04:49:12] [I] Iterations: 10
[06/01/2020-04:49:12] [I] Duration: 3s (+ 200ms warm up)
[06/01/2020-04:49:12] [I] Sleep time: 0ms
[06/01/2020-04:49:12] [I] Streams: 1
[06/01/2020-04:49:12] [I] ExposeDMA: Disabled
[06/01/2020-04:49:12] [I] Spin-wait: Disabled
[06/01/2020-04:49:12] [I] Multithreading: Disabled
[06/01/2020-04:49:12] [I] CUDA Graph: Disabled
[06/01/2020-04:49:12] [I] Skip inference: Disabled
[06/01/2020-04:49:12] [I] Input inference shapes: model
[06/01/2020-04:49:12] [I] Inputs:
[06/01/2020-04:49:12] [I] === Reporting Options ===
[06/01/2020-04:49:12] [I] Verbose: Disabled
[06/01/2020-04:49:12] [I] Averages: 10 inferences
[06/01/2020-04:49:12] [I] Percentile: 99
[06/01/2020-04:49:12] [I] Dump output: Disabled
[06/01/2020-04:49:12] [I] Profile: Disabled
[06/01/2020-04:49:12] [I] Export timing to JSON file:
[06/01/2020-04:49:12] [I] Export output to JSON file:
[06/01/2020-04:49:12] [I] Export profile to JSON file:
[06/01/2020-04:49:12] [I]
[06/01/2020-04:49:32] [I] [TRT] Some tactics do not have sufficient workspace memory to run. Increasing workspace size may increase performance, please check verbose output.
[06/01/2020-04:49:35] [I] [TRT] Detected 1 inputs and 2 output network tensors.
[06/01/2020-04:49:35] [W] [TRT] Current optimization profile is: 0. Please ensure there are no enqueued operations pending in this context prior to switching profiles
[06/01/2020-04:49:38] [I] Warmup completed 109 queries over 200 ms
[06/01/2020-04:49:38] [I] Timing trace has 1312 queries over 2.98336 s
[06/01/2020-04:49:38] [I] Trace averages of 10 runs:
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79453 ms - Host latency: 2.27952 ms (end to end 3.36315 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79125 ms - Host latency: 2.27555 ms (end to end 3.43923 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79221 ms - Host latency: 2.276 ms (end to end 3.46455 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79078 ms - Host latency: 2.27497 ms (end to end 3.46853 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79557 ms - Host latency: 2.27817 ms (end to end 3.49038 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.80199 ms - Host latency: 2.28799 ms (end to end 3.3869 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82372 ms - Host latency: 2.30707 ms (end to end 3.33017 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82476 ms - Host latency: 2.30969 ms (end to end 3.54552 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.80534 ms - Host latency: 2.29057 ms (end to end 3.40559 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79631 ms - Host latency: 2.2813 ms (end to end 3.4655 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79129 ms - Host latency: 2.27547 ms (end to end 3.35747 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79593 ms - Host latency: 2.28088 ms (end to end 3.47937 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79481 ms - Host latency: 2.2789 ms (end to end 3.46024 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79518 ms - Host latency: 2.27909 ms (end to end 3.45103 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.78865 ms - Host latency: 2.27257 ms (end to end 3.43324 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.792 ms - Host latency: 2.27679 ms (end to end 3.42866 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.80627 ms - Host latency: 2.2918 ms (end to end 3.35085 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82083 ms - Host latency: 2.30772 ms (end to end 3.33702 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82205 ms - Host latency: 2.30701 ms (end to end 3.47365 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.8194 ms - Host latency: 2.30389 ms (end to end 3.50549 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82229 ms - Host latency: 2.30814 ms (end to end 3.5145 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82985 ms - Host latency: 2.31568 ms (end to end 3.43237 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82439 ms - Host latency: 2.30981 ms (end to end 3.5155 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 2.07258 ms - Host latency: 2.55909 ms (end to end 3.57319 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.78987 ms - Host latency: 2.27427 ms (end to end 3.4512 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79293 ms - Host latency: 2.27838 ms (end to end 3.473 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 2.05402 ms - Host latency: 2.53876 ms (end to end 3.74263 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82462 ms - Host latency: 2.3082 ms (end to end 3.43343 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82018 ms - Host latency: 2.30994 ms (end to end 3.52888 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.9627 ms - Host latency: 2.44543 ms (end to end 3.65713 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79323 ms - Host latency: 2.27733 ms (end to end 3.37315 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.78964 ms - Host latency: 2.27467 ms (end to end 3.46013 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79507 ms - Host latency: 2.27976 ms (end to end 3.43893 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.7994 ms - Host latency: 2.28382 ms (end to end 3.48812 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.8319 ms - Host latency: 2.31909 ms (end to end 3.22645 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82246 ms - Host latency: 2.30784 ms (end to end 3.5417 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.9973 ms - Host latency: 2.48157 ms (end to end 3.60016 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.78444 ms - Host latency: 2.26952 ms (end to end 3.45166 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.7889 ms - Host latency: 2.27415 ms (end to end 3.47271 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79087 ms - Host latency: 2.27583 ms (end to end 3.44485 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79303 ms - Host latency: 2.27814 ms (end to end 3.49078 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82751 ms - Host latency: 2.31201 ms (end to end 3.31179 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 2.00154 ms - Host latency: 2.48533 ms (end to end 3.56461 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79215 ms - Host latency: 2.27654 ms (end to end 3.48303 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79109 ms - Host latency: 2.2793 ms (end to end 3.46503 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.78801 ms - Host latency: 2.27312 ms (end to end 3.45579 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.78878 ms - Host latency: 2.27244 ms (end to end 3.46509 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79696 ms - Host latency: 2.28127 ms (end to end 3.4817 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.78947 ms - Host latency: 2.27401 ms (end to end 3.47068 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.78601 ms - Host latency: 2.27013 ms (end to end 3.46414 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.787 ms - Host latency: 2.27158 ms (end to end 3.442 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.96643 ms - Host latency: 2.45106 ms (end to end 3.6736 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.81852 ms - Host latency: 2.30118 ms (end to end 3.32072 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.81997 ms - Host latency: 2.30276 ms (end to end 3.54564 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.8244 ms - Host latency: 2.3067 ms (end to end 3.52996 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.81654 ms - Host latency: 2.29935 ms (end to end 3.52488 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.81755 ms - Host latency: 2.30366 ms (end to end 3.52377 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82637 ms - Host latency: 2.31034 ms (end to end 3.4079 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82213 ms - Host latency: 2.31016 ms (end to end 3.42383 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82722 ms - Host latency: 2.31179 ms (end to end 3.5425 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82 ms - Host latency: 2.30262 ms (end to end 3.51748 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.81798 ms - Host latency: 2.3022 ms (end to end 3.40033 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.81912 ms - Host latency: 2.30337 ms (end to end 3.52847 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82269 ms - Host latency: 2.30613 ms (end to end 3.50857 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82144 ms - Host latency: 2.30616 ms (end to end 3.5266 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.81644 ms - Host latency: 2.30112 ms (end to end 3.41259 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.81865 ms - Host latency: 2.3021 ms (end to end 3.51362 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 2.07179 ms - Host latency: 2.55741 ms (end to end 3.65748 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.78702 ms - Host latency: 2.27125 ms (end to end 3.44387 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.78989 ms - Host latency: 2.27279 ms (end to end 3.47568 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79094 ms - Host latency: 2.27479 ms (end to end 3.45773 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79475 ms - Host latency: 2.27921 ms (end to end 3.34714 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79215 ms - Host latency: 2.27694 ms (end to end 3.4679 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 2.05609 ms - Host latency: 2.53951 ms (end to end 3.53693 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79087 ms - Host latency: 2.2744 ms (end to end 3.3697 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.78678 ms - Host latency: 2.26891 ms (end to end 3.47501 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.78844 ms - Host latency: 2.27146 ms (end to end 3.4679 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.81584 ms - Host latency: 2.29882 ms (end to end 3.29277 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82111 ms - Host latency: 2.30502 ms (end to end 3.50586 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.81931 ms - Host latency: 2.30356 ms (end to end 3.52998 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 2.06104 ms - Host latency: 2.54539 ms (end to end 3.69739 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79102 ms - Host latency: 2.27468 ms (end to end 3.46584 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.78755 ms - Host latency: 2.27541 ms (end to end 3.44812 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79177 ms - Host latency: 2.27424 ms (end to end 3.46589 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79543 ms - Host latency: 2.27861 ms (end to end 3.48447 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79578 ms - Host latency: 2.28022 ms (end to end 3.42917 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.78586 ms - Host latency: 2.27034 ms (end to end 3.45754 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 2.02615 ms - Host latency: 2.51072 ms (end to end 3.58545 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 2.09639 ms - Host latency: 2.58127 ms (end to end 3.84377 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.81946 ms - Host latency: 2.30227 ms (end to end 3.52979 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.81851 ms - Host latency: 2.30327 ms (end to end 3.46848 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.8197 ms - Host latency: 2.30664 ms (end to end 3.51855 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82686 ms - Host latency: 2.31086 ms (end to end 3.47214 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82192 ms - Host latency: 2.30452 ms (end to end 3.44248 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.81892 ms - Host latency: 2.3012 ms (end to end 3.42634 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.87971 ms - Host latency: 2.36311 ms (end to end 3.59114 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 2.00967 ms - Host latency: 2.49331 ms (end to end 3.64153 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.78923 ms - Host latency: 2.27244 ms (end to end 3.47061 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.78853 ms - Host latency: 2.2728 ms (end to end 3.47292 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.78728 ms - Host latency: 2.27104 ms (end to end 3.48098 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.78975 ms - Host latency: 2.27441 ms (end to end 3.47727 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.78735 ms - Host latency: 2.27109 ms (end to end 3.46948 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79031 ms - Host latency: 2.27356 ms (end to end 3.48201 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82563 ms - Host latency: 2.31006 ms (end to end 3.32429 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.81697 ms - Host latency: 2.29946 ms (end to end 3.53884 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82078 ms - Host latency: 2.30532 ms (end to end 3.53652 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82234 ms - Host latency: 2.30764 ms (end to end 3.50171 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82324 ms - Host latency: 2.30854 ms (end to end 3.54038 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.8146 ms - Host latency: 2.30122 ms (end to end 3.5271 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.8248 ms - Host latency: 2.3083 ms (end to end 3.55015 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82522 ms - Host latency: 2.30911 ms (end to end 3.55842 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82742 ms - Host latency: 2.31169 ms (end to end 3.44458 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.8156 ms - Host latency: 2.298 ms (end to end 3.43159 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82437 ms - Host latency: 2.30872 ms (end to end 3.41919 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82192 ms - Host latency: 2.30647 ms (end to end 3.55156 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.81741 ms - Host latency: 2.30325 ms (end to end 3.53347 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82397 ms - Host latency: 2.31274 ms (end to end 3.43665 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.81765 ms - Host latency: 2.301 ms (end to end 3.43052 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.81912 ms - Host latency: 2.302 ms (end to end 3.53818 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.82576 ms - Host latency: 2.30898 ms (end to end 3.54897 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.98821 ms - Host latency: 2.47139 ms (end to end 3.58528 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.7844 ms - Host latency: 2.2679 ms (end to end 3.46619 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.78569 ms - Host latency: 2.26887 ms (end to end 3.47539 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.79385 ms - Host latency: 2.27781 ms (end to end 3.47483 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.81213 ms - Host latency: 2.29666 ms (end to end 3.28799 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.81982 ms - Host latency: 2.30562 ms (end to end 3.54319 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 2.05364 ms - Host latency: 2.53828 ms (end to end 3.71938 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.78901 ms - Host latency: 2.27307 ms (end to end 3.48289 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.78491 ms - Host latency: 2.26838 ms (end to end 3.472 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.78406 ms - Host latency: 2.26753 ms (end to end 3.46042 ms)
[06/01/2020-04:49:38] [I] Average on 10 runs - GPU latency: 1.78452 ms - Host latency: 2.26956 ms (end to end 3.45339 ms)
[06/01/2020-04:49:38] [I] Host latency
[06/01/2020-04:49:38] [I] min: 2.25488 ms (end to end 2.28717 ms)
[06/01/2020-04:49:38] [I] max: 5.0249 ms (end to end 8.70044 ms)
[06/01/2020-04:49:38] [I] mean: 2.31472 ms (end to end 3.48176 ms)
[06/01/2020-04:49:38] [I] median: 2.29272 ms (end to end 3.49304 ms)
[06/01/2020-04:49:38] [I] percentile: 3.66125 ms at 99% (end to end 4.63062 ms at 99%)
[06/01/2020-04:49:38] [I] throughput: 439.773 qps
[06/01/2020-04:49:38] [I] walltime: 2.98336 s
[06/01/2020-04:49:38] [I] GPU Compute
[06/01/2020-04:49:38] [I] min: 1.77173 ms
[06/01/2020-04:49:38] [I] max: 4.53198 ms
[06/01/2020-04:49:38] [I] mean: 1.83032 ms
[06/01/2020-04:49:38] [I] median: 1.80835 ms
[06/01/2020-04:49:38] [I] percentile: 3.17761 ms at 99%
[06/01/2020-04:49:38] [I] total compute time: 2.40138 s
&&&& PASSED TensorRT.trtexec # /usr/src/tensorrt/bin/trtexec --deploy=/opt/nvidia/deepstream/deepstream/samples/models/Primary_Detector/resnet10.prototxt --output=conv2d_bbox --output=conv2d_cov/Sigmoid

7

for remove elements from blacklists, please run rm ~/.gstreamer-0.10/registry**
and can you update TensorRT to 7.1+ and try again?
I see you use TRT 7.0

8

There is no such folder starting with .gstreamer-xxx inside ~/ directory. I can see such folder only inside the .cache folder in ~/ directory.

9

sorry, forget cache,
it’s ~/.cache/gstreamer-1.0/registry***

10

So I ran the command " rm ~/.cache/gstreamer-1.0/registry*** " and then tried to run the app again but still I am getting the same error. I don’t see a difference.

Also I couldn’t find TensorRT 7.1 in the official download page. In the below link it is mentioned the latest version is 7.0 and 7.1 is yet to be released on summer.
https://developer.nvidia.com/tensorrt#tensorrt-whats-new

11

Oh, Sorry about TensorRT version, seems readme needs update.
How about rm ~/.gstreamer-0.10/registry***
then run gst-inspect-1.0 and look for warnings/criticals from the plugin that was blacklisted.
Does blacklist issue resolved?
It would be better you run with “GST_DEBUG=nvinfer:5 app” and “GST_DEBUG=5 app” and provide the logs here.

13

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.

14

Please provide complete log, you can attach it as attachment,
besides, you can refer to this get core dump file, do debug, and get the back trace for analysis.

15

Hi,
This is an issue our dev team is working upon, one workaround is you can use software encoder - there is one option in sink1 ground in config, for sink0 group, you can set sink component as fakesink.