Segmentation fault when using secondary classifier

dangraf · March 21, 2021, 8:17pm

• Hardware Platform (Jetson / GPU)
Jetson AGX Xavier
• DeepStream Version
5.1
• JetPack Version (valid for Jetson only)
5.1
• Issue Type( questions, new requirements, bugs)
Question or bug
• How to reproduce the issue ? (This is for bugs. Including which sample app is using, the configuration files content, the command line used and other details for reproducing)

I’m trying to do face-recognition using this project as a base: (GitHub - riotu-lab/deepstream-facenet: Demo app on using Deepstream 5.0 with Facenet) but get segmentation fault and need help how to understand why to be able to fix the problem.

The object-detection is working fine and also object tracking but as soon as a face is identified, the program crashes because of a segmentation fault.

I first suspected the facenet.onnx model but I’ve sucessfully been able to use the generated .engine file together with tensorrt and use the model in a jupyter notebook. The model seems to work just fine.

I then expected the configuration file to be faulty and tested different settings.
The model works just fine if I set “process mode” to 1 (sending the whole frame to the facenet-model. But as soon as I set process-mode=2 I get the segmentation fault.

This is the configuration file that generates the segmentation fault:
[property]
gpu-id=0
net-scale-factor=0.0039215697906911373
onnx-file=facenet.onnx
model-engine-file=facenet.onnx_b1_gpu0_fp16.engine
batch-size=1
force-implicit-batch-dim=1
#uff-input-blob-name=input_1:0
#output-blob-names=Bottleneck_BatchNorm/batchnorm_1/add_1:0
infer-dims=3;160;160
input-object-min-width=80
input-object-min-height=80

gie-unique-id=2
network-mode=2
network-type=1
output-tensor-meta=1

process-mode=2
operate-on-gie-id=1
operate-on-class-ids=0
classifier-async-mode=1
is-classifier=1

I then turned on debugging to better understand why I get the errors but it seems like it’s working as expected. But I’m pretty new to this framework and might be missing something. (GST_DEBUG=5)

OSType)GST_QOS_TYPE_OVERFLOW, 0:00:17.671893453 3817 0:00:17.671927534 3817 0:00:17.671978001 3817 0:00:17.672074998 3817 0:00:17.672111480 3817 0:00:17.672150906 3817 0:00:17.672201245 3817 0:00:17.672246335 3817 0:00:17.672274401 3817 0:00:17.672318467 3817 0:00:17.672411304 3817 0:00:17.672446954 3817 0:00:17.672492492 3817 0:00:17.672550799 3817 0:00:17.672585489 3817 0:00:17.672633331 3817 0:00:17.672660597 3817 0:00:17.672682838 3817 0:00:17.672712760 3817 0:00:17.672741177 3817 0:00:17.672763674 3817 0:00:17.672785339 3817 0:00:17.672805660 3817 0:00:17.672827646 3817 0:00:17.672870144 3817 0:00:17.672902369 3817 0:00:17.672933091 3817 0:00:17.672960260 3817 Segmentation fault (core dumped) proportion=(double)0.57686659740759627, diff=(gint64)-11373606, timestamp=(guint64)10066666566;
0x29cc2ed0 DEBUG GST_QOS gstbasetransform.c:2645:gst_base_transform_update_qos: qos: proportion: 0.576867, diff -11373606, timestamp 0:00:10.066666566
0x29cc2ed0 DEBUG GST_PADS gstpad.c:3810:do_probe_callbacks:onscreendisplay:sink data is passed
0x29cc2ed0 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked:convertor:src have event type qos event: 0x7f4c1132b0, time 99:99:99.999999999, seq-num 567, GstEventQOS, type=(GstQOSType)GST_QOS_TYPE_OVERFLOW, proportion=(double)0.57686659740759627, diff=(gint64)-11373606, timestamp=(guint64)10066666566;
0x29cc2ed0 DEBUG basetransform gstbasetransform.c:1941:gst_base_transform_src_eventfunc: handling event 0x7f4c1132b0 qos event: 0x7f4c1132b0, time 99:99:99.999999999, seq-num 567, GstEventQOS, type=(GstQOSType)GST_QOS_TYPE_OVERFLOW, proportion=(double)0.57686659740759627, diff=(gint64)-11373606, timestamp=(guint64)10066666566;
0x29cc2ed0 DEBUG GST_QOS gstbasetransform.c:2645:gst_base_transform_update_qos: qos: proportion: 0.576867, diff -11373606, timestamp 0:00:10.066666566
0x29cc2ed0 DEBUG GST_PADS gstpad.c:3810:do_probe_callbacks:convertor:sink data is passed
0x29cc2ed0 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked: have event type qos event: 0x7f4c1132b0, time 99:99:99.999999999, seq-num 567, GstEventQOS, type=(GstQOSType)GST_QOS_TYPE_OVERFLOW, proportion=(double)0.57686659740759627, diff=(gint64)-11373606, timestamp=(guint64)10066666566;
0x29cc2ed0 DEBUG basetransform gstbasetransform.c:1941:gst_base_transform_src_eventfunc: handling event 0x7f4c1132b0 qos event: 0x7f4c1132b0, time 99:99:99.999999999, seq-num 567, GstEventQOS, type=(GstQOSType)GST_QOS_TYPE_OVERFLOW, proportion=(double)0.57686659740759627, diff=(gint64)-11373606, timestamp=(guint64)10066666566;
0x29cc2ed0 DEBUG GST_QOS gstbasetransform.c:2645:gst_base_transform_update_qos: qos: proportion: 0.576867, diff -11373606, timestamp 0:00:10.066666566
0x29cc2ed0 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked:tracker:src have event type qos event: 0x7f4c1132b0, time 99:99:99.999999999, seq-num 567, GstEventQOS, type=(GstQOSType)GST_QOS_TYPE_OVERFLOW, proportion=(double)0.57686659740759627, diff=(gint64)-11373606, timestamp=(guint64)10066666566;
0x29cc2ed0 DEBUG basetransform gstbasetransform.c:1941:gst_base_transform_src_eventfunc: handling event 0x7f4c1132b0 qos event: 0x7f4c1132b0, time 99:99:99.999999999, seq-num 567, GstEventQOS, type=(GstQOSType)GST_QOS_TYPE_OVERFLOW, proportion=(double)0.57686659740759627, diff=(gint64)-11373606, timestamp=(guint64)10066666566;
0x29cc2ed0 DEBUG GST_QOS gstbasetransform.c:2645:gst_base_transform_update_qos: qos: proportion: 0.576867, diff -11373606, timestamp 0:00:10.066666566
0x29cc2ed0 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked: have event type qos event: 0x7f4c1132b0, time 99:99:99.999999999, seq-num 567, GstEventQOS, type=(GstQOSType)GST_QOS_TYPE_OVERFLOW, proportion=(double)0.57686659740759627, diff=(gint64)-11373606, timestamp=(guint64)10066666566;
0x29cc2ed0 DEBUG basetransform gstbasetransform.c:1941:gst_base_transform_src_eventfunc: handling event 0x7f4c1132b0 qos event: 0x7f4c1132b0, time 99:99:99.999999999, seq-num 567, GstEventQOS, type=(GstQOSType)GST_QOS_TYPE_OVERFLOW, proportion=(double)0.57686659740759627, diff=(gint64)-11373606, timestamp=(guint64)10066666566;
0x29cc2ed0 DEBUG GST_QOS gstbasetransform.c:2645:gst_base_transform_update_qos: qos: proportion: 0.576867, diff -11373606, timestamp 0:00:10.066666566
0x29cc2ed0 DEBUG GST_EVENT gstpad.c:5693:gst_pad_send_event_unchecked:Stream-muxer:src have event type qos event: 0x7f4c1132b0, time 99:99:99.999999999, seq-num 567, GstEventQOS, type=(GstQOSType)GST_QOS_TYPE_OVERFLOW, proportion=(double)0.57686659740759627, diff=(gint64)-11373606, timestamp=(guint64)10066666566;
0x29cc2ed0 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked:Stream-muxer:src sent event, ret ok
0x29cc2ed0 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked: sent event, ret ok
0x29cc2ed0 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked:tracker:src sent event, ret ok
0x29cc2ed0 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked: sent event, ret ok
0x29cc2ed0 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked:convertor:src sent event, ret ok
0x29cc2ed0 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked:onscreendisplay:src sent event, ret ok
0x29cc2ed0 DEBUG GST_PADS gstpad.c:5776:gst_pad_send_event_unchecked:nvegl-transform:src sent event, ret ok
0x29cc2ed0 DEBUG basesink gstbasesink.c:3577:gst_base_sink_chain_unlocked: object unref after render 0x7f400a0d40
0x29cc2ed0 DEBUG GST_SCHEDULING gstpad.c:4326:gst_pad_chain_data_unchecked:nvvideo-renderer:sink called chainfunction &gst_base_sink_chain with buffer 0x7f400a0d40, returned ok
0x29cc2ed0 DEBUG GST_SCHEDULING gstpad.c:4326:gst_pad_chain_data_unchecked:nvegl-transform:sink called chainfunction &gst_base_transform_chain with buffer 0x7f400b1510, returned ok
0x29cc2ed0 DEBUG GST_SCHEDULING gstpad.c:4326:gst_pad_chain_data_unchecked:onscreendisplay:sink called chainfunction &gst_base_transform_chain with buffer 0x7f400b1510, returned ok
0x29cc2ed0 DEBUG GST_SCHEDULING gstpad.c:4326:gst_pad_chain_data_unchecked:convertor:sink called chainfunction &gst_base_transform_chain with buffer 0x58e94eb0, returned ok

Any suggestions of how to proceed with the debugging?

Fiona.Chen · March 22, 2021, 3:02am

Nvidia has provided sample of face detection FaceDetect-IR — Transfer Learning Toolkit 3.0 documentation (nvidia.com) model. And the deepstream sample is in /opt/nvidia/deepstream/deepstream/samples/configs/tlt_pretrained_models/

For the project you mentioned, it is 3rd party sample, please contact the owner of the project.

dangraf · March 22, 2021, 7:17am

Hello.
No, I will not contact the owner of the project. Deepstream SDK is supposed to be a tool to be able to deploy any model (note, SDK stands for software development kit) , not only models you have provided as examples.
In this case I get segmentation fault and I don’t know how to debug it and what is causing it. The one who can explain what is happening is Nvidia who have developed this tool.
How do I track down the bug that is causing this?

dangraf · March 22, 2021, 7:48am

Hello again. I’m trying your advice to look at the example FaceDetect-IR
(https://ngc.nvidia.com/catalog/models/nvidia:tlt_facedetectir)
and they are refering to a file “deepstream_app_source1_faceirnet.txt” that should be located at " /opt/nvidia/deepstream/deepstream-5.1/samples/configs/tlt_pretrained_models"

It does not exist on my jetson with jetpack as specified above.
There are some other files named eg. “deepstream_app_source1_facedetectir.txt” but no …faceirnet.txt or in any other subfolders.

Could you please copy and paste the config-file in this thread if you have it or point me to where it’s located?

Fiona.Chen · March 22, 2021, 9:46am

deepstream_app_source1_facedetectir.txt is the correct file. You don’t need ***faceirnet.txt

Fiona.Chen · March 22, 2021, 9:49am

The error is related to the implementation. Deepstream can support many kinds of models, but it should be configured correctly. The project is not provided by Nvidia. We do not have responsibility to guarantee it can work.

dangraf · March 22, 2021, 9:51am

implementation of the model or implementation of the deepstream app? I understand that you can’t give any guarantees, i just want to know what is happening.

Fiona.Chen · March 22, 2021, 9:58am

Yes. The implementation of the app matters. The wrong implementation and configuration will cause error.

dangraf · March 22, 2021, 12:56pm

I still don’t know if the problem is in the configuration file or the application.

The setup I’m using now is:
use deepstream_test_1.py

with the following modifications:
…
face_classifier = Gst.ElementFactory.make(“nvinfer”, “secondary-inference face_classifier”)
face_classifier.set_property(‘config-file-path’, “facenet/facenet_config.txt”)
…
pipeline.add(face_classifier)
…
pgie.link(face_classifier)
face_classifier.link(nvvidconv)
…

my configuration file looks like:
[property]
gpu-id=0
net-scale-factor=0.0039215697906911373
onnx-file=facenet.onnx
model-engine-file=facenet.onnx_b1_gpu0_fp16.engine
batch-size=1
force-implicit-batch-dim=1
#input-object-min-width=80
#input-object-min-height=80

gie-unique-id=2
network-mode=2
network-type=1
output-tensor-meta=1

classifier-async-mode=1
process-mode=2
operate-on-gie-id=1
operate-on-class-ids=0
is-classifier=1

[class-attrs-all]
pre-cluster-threshold=0.2
group-threshold=1
eps=0.2

Fiona.Chen · March 23, 2021, 1:24am

Can the original deepstream_test_1.py work? If it can. The error is caused by your modification. We don’t know anything about the model you use, so we can not tell you whether the configuration and the usage is correct.

dangraf · March 23, 2021, 8:21am

Ok. The facenet is developed by google 2015 and one of the most known face-recognition libraries. The exact model I’m using is David Sandbegs implementation(GitHub - davidsandberg/facenet: Face recognition using Tensorflow). It takes an image as input and outputs an array of eg 128, 256 or 512 values (in this case 128) that can be seen as a vector of float values.
My onnx-model can be accessed here(https://github.com/dangraf/facenet)

As mentioned before, the model seems to be fine, i’ve tested in in tensorrt, created an engine for an onnx file and tested that engine file. If I set it as a primary classifier, the model works and do not crash the system. When using it as a secondary and looking in the log files I can see the message that the secondary classifier returns OK.

There is no good settings for this output so I chose classifier since it’s ok to do softmax on the output looking at it as a 128 class problem. Since it works as a primary classifier, i’m guessing the post-processing as a classifier works fine.

If I choose segmentation (network-type=2) this works better but crashes after about 300 frames.

Fiona.Chen · March 23, 2021, 9:33am

Do you know the input layer dimensions? Is there customized post-processing? If you use it as primary model, is there any output from the model with deepstream-test1? Can you show the error log with deepstream-test1?

dangraf · March 23, 2021, 9:46am

Input layer dimentions for the tensor is [1 (bs), 160,160, 3]
No customized post-processing.
when using it as a primary model I just see that it processes the frames. I have still not figured out how to dump the output to eg a file.
You mention an error log, do you mean the gstreamer log set to dump error messages (Basic tutorial 11: Debugging tools) eg GST_DEBUG=1?

Fiona.Chen · March 23, 2021, 9:49am

There is print log in deepstream_python_apps/deepstream_test_1.py at master · NVIDIA-AI-IOT/deepstream_python_apps · GitHub. And it is better to enable “GST_DEBUG=3”

Fiona.Chen · March 23, 2021, 9:50am

Is the model NHWC input?

dangraf · March 23, 2021, 9:55am

Yes it is. I had this problem before because I needed to convert it form the original facnet.pb → facnet.onnx and it had the old tensorflow input format. The switch in the configuration file does not work on onnx-models so I had to add a parameter to the pasrser to fix it to NHWC.

I need some time to re-run the test1 to give you the logs, because I have been messing around a lot to figure out what’s wrong.

Fiona.Chen · March 23, 2021, 9:56am

Deepstream does not support NHWC input onnx model now. Please make sure the model is NCHW

dangraf · March 23, 2021, 10:01am

You are correct. This is the command I uses for the converter:
python3 -m tf2onnx.convert --input facenet.pb --inputs input:0[1,160,160,3] --inputs-as-nchw input:0 --outputs Bottleneck/BatchNorm/batchnorm/add_1:0 --output facenet.onnx
so it’s nchw

dangraf · March 23, 2021, 11:03am

Test1: facenet as primary classifier:

The model is working in parallel with the detector.

Configuration file:

[property]
gpu-id=0
net-scale-factor=0.0039215697906911373
onnx-file=facenet.onnx
model-engine-file=facenet.onnx_b1_gpu0_fp16.engine
batch-size=1
force-implicit-batch-dim=1
input-object-min-width=10
input-object-min-height=10
gie-unique-id=2
network-mode=2
network-type=1
output-tensor-meta=1
classifier-async-mode=0
process-mode=1

Run Command:

GST_DEBUG=3 python3 deepstream_test_1.py …/…/…/streams/sample_720p.h264

Debug output, level 3:

Creating Pipeline

Creating Source

Creating H264Parser

Creating Decoder

Creating EGLSink

Playing file …/…/…/streams/sample_720p.h264
Adding elements to Pipeline

Linking elements in the Pipeline

Starting pipeline

Using winsys: x11
Opening in BLOCKING MODE
Opening in BLOCKING MODE
0:00:00.495017446 63 0x29b07cf0 WARN v4l2 gstv4l2object.c:4434:gst_v4l2_object_probe_caps:nvv4l2-decoder:src Failed to probe pixel aspect ratio with VIDIOC_CROPCAP: Unknown error -1
0:00:00.495230996 63 0x29b07cf0 WARN v4l2 gstv4l2object.c:2375:gst_v4l2_object_add_interlace_mode:0x291ae880 Failed to determine interlace mode
0:00:00.495343259 63 0x29b07cf0 WARN v4l2 gstv4l2object.c:2375:gst_v4l2_object_add_interlace_mode:0x291ae880 Failed to determine interlace mode
0:00:05.389970635 63 0x29b07cf0 INFO nvinfer gstnvinfer.cpp:619:gst_nvinfer_logger: NvDsInferContext[UID 2]: Info from NvDsInferContextImpl::deserializeEngineAndBackend() <nvdsinfer_context_impl.cpp:1702> [UID = 2]: deserialized trt engine from :/opt/nvidia/deepstream/deepstream-5.1/samples/deepstream_python_apps/apps/deepstream-test1/facenet/facenet.onnx_b1_gpu0_fp16.engine
INFO: [Implicit Engine Info]: layers num: 2
0 INPUT kFLOAT input:0 3x160x160
1 OUTPUT kFLOAT Bottleneck/BatchNorm/batchnorm/add_1:0 128

0:00:05.390374821 63 0x29b07cf0 INFO nvinfer gstnvinfer.cpp:619:gst_nvinfer_logger: NvDsInferContext[UID 2]: Info from NvDsInferContextImpl::generateBackendContext() <nvdsinfer_context_impl.cpp:1806> [UID = 2]: Use deserialized engine model: /opt/nvidia/deepstream/deepstream-5.1/samples/deepstream_python_apps/apps/deepstream-test1/facenet/facenet.onnx_b1_gpu0_fp16.engine
0:00:05.400257584 63 0x29b07cf0 INFO nvinfer gstnvinfer_impl.cpp:313:notifyLoadModelStatus: [UID 2]: Load new model:facenet/facenet_config.txt sucessfully
0:00:05.457880395 63 0x29b07cf0 INFO nvinfer gstnvinfer.cpp:619:gst_nvinfer_logger: NvDsInferContext[UID 1]: Info from NvDsInferContextImpl::deserializeEngineAndBackend() <nvdsinfer_context_impl.cpp:1702> [UID = 1]: deserialized trt engine from :/opt/nvidia/deepstream/deepstream-5.1/samples/models/Primary_Detector/resnet10.caffemodel_b1_gpu0_int8.engine
INFO: [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:05.458500881 63 0x29b07cf0 INFO nvinfer gstnvinfer.cpp:619:gst_nvinfer_logger: NvDsInferContext[UID 1]: Info from NvDsInferContextImpl::generateBackendContext() <nvdsinfer_context_impl.cpp:1806> [UID = 1]: Use deserialized engine model: /opt/nvidia/deepstream/deepstream-5.1/samples/models/Primary_Detector/resnet10.caffemodel_b1_gpu0_int8.engine
0:00:05.462259165 63 0x29b07cf0 INFO nvinfer gstnvinfer_impl.cpp:313:notifyLoadModelStatus: [UID 1]: Load new model:dstest1_pgie_config.txt sucessfully
0:00:05.463267196 63 0x29b07cf0 WARN basesrc gstbasesrc.c:3583:gst_base_src_start_complete: pad not activated yet
NvMMLiteOpen : Block : BlockType = 261
NVMEDIA: Reading vendor.tegra.display-size : status: 6
NvMMLiteBlockCreate : Block : BlockType = 261
0:00:05.584447238 63 0x28e6ca80 WARN v4l2 gstv4l2object.c:4434:gst_v4l2_object_probe_caps:nvv4l2-decoder:src Failed to probe pixel aspect ratio with VIDIOC_CROPCAP: Unknown error -1
0:00:05.584622129 63 0x28e6ca80 WARN v4l2 gstv4l2object.c:2375:gst_v4l2_object_add_interlace_mode:0x291ae880 Failed to determine interlace mode
0:00:05.584742296 63 0x28e6ca80 WARN v4l2 gstv4l2object.c:2375:gst_v4l2_object_add_interlace_mode:0x291ae880 Failed to determine interlace mode
0:00:05.658114382 63 0x28e6ca80 WARN v4l2videodec gstv4l2videodec.c:1673:gst_v4l2_video_dec_decide_allocation: Duration invalid, not setting latency
0:00:05.658628430 63 0x28e6ca80 WARN v4l2bufferpool gstv4l2bufferpool.c:1065:gst_v4l2_buffer_pool_start:nvv4l2-decoder:pool:src Uncertain or not enough buffers, enabling copy threshold
0:00:05.675219262 63 0x28e6e2d0 WARN v4l2bufferpool gstv4l2bufferpool.c:1512:gst_v4l2_buffer_pool_dqbuf:nvv4l2-decoder:pool:src Driver should never set v4l2_buffer.field to ANY
Frame Number=0 Number of Objects=5 Vehicle_count=3 Person_count=2
Frame Number=1 Number of Objects=5 Vehicle_count=3 Person_count=2
Frame Number=2 Number of Objects=5 Vehicle_count=3 Person_count=2
Frame Number=3 Number of Objects=7 Vehicle_count=4 Person_count=3
Frame Number=4 Number of Objects=6 Vehicle_count=4 Person_count=2
Frame Number=5 Number of Objects=5 Vehicle_count=3 Person_count=2
Frame Number=6 Number of Objects=5 Vehicle_count=3 Person_count=2
Frame Number=7 Number of Objects=6 Vehicle_count=4 Person_count=2
Frame Number=8 Number of Objects=6 Vehicle_count=4 Person_count=2
Frame Number=9 Number of Objects=6 Vehicle_count=4 Person_count=2
Frame Number=10 Number of Objects=6 Vehicle_count=4 Person_count=2
Frame Number=11 Number of Objects=7 Vehicle_count=5 Person_count=2
Frame Number=12 Number of Objects=6 Vehicle_count=4 Person_count=2
Frame Number=13 Number of Objects=6 Vehicle_count=4 Person_count=2
Frame Number=14 Number of Objects=7 Vehicle_count=5 Person_count=2
Frame Number=15 Number of Objects=7 Vehicle_count=5 Person_count=2
Frame Number=16 Number of Objects=6 Vehicle_count=4 Person_count=2
Frame Number=17 Number of Objects=7 Vehicle_count=5 Person_count=2
Frame Number=18 Number of Objects=6 Vehicle_count=4 Person_count=2
Frame Number=19 Number of Objects=6 Vehicle_count=4 Person_count=2
Frame Number=20 Number of Objects=7 Vehicle_count=5 Person_count=2
Frame Number=21 Number of Objects=7 Vehicle_count=5 Person_count=2
Frame Number=22 Number of Objects=7 Vehicle_count=5 Person_count=2
Frame Number=23 Number of Objects=8 Vehicle_count=6 Person_count=2
Frame Number=24 Number of Objects=8 Vehicle_count=6 Person_count=2
Frame Number=25 Number of Objects=8 Vehicle_count=6 Person_count=2
Frame Number=26 Number of Objects=8 Vehicle_count=6 Person_count=2
Frame Number=27 Number of Objects=8 Vehicle_count=6 Person_count=2
Frame Number=28 Number of Objects=8 Vehicle_count=6 Person_count=2
Frame Number=29 Number of Objects=8 Vehicle_count=6 Person_count=2
Frame Number=30 Number of Objects=9 Vehicle_count=7 Person_count=2
Frame Number=31 Number of Objects=9 Vehicle_count=7 Person_count=2
Frame Number=32 Number of Objects=8 Vehicle_count=6 Person_count=2
Frame Number=33 Number of Objects=7 Vehicle_count=5 Person_count=2
Frame Number=34 Number of Objects=9 Vehicle_count=6 Person_count=3
Frame Number=35 Number of Objects=8 Vehicle_count=6 Person_count=2
Frame Number=36 Number of Objects=9 Vehicle_count=7 Person_count=2
Frame Number=37 Number of Objects=13 Vehicle_count=9 Person_count=4
Frame Number=38 Number of Objects=11 Vehicle_count=8 Person_count=3
Frame Number=39 Number of Objects=11 Vehicle_count=8 Person_count=3
Frame Number=40 Number of Objects=9 Vehicle_count=7 Person_count=2
Frame Number=41 Number of Objects=10 Vehicle_count=8 Person_count=2
Frame Number=42 Number of Objects=10 Vehicle_count=8 Person_count=2

Debug level 4 for face-classifier:

I don’t know if the the model performs outputs but the log looks almost the same for the “secondary classifier” (that is actually put in primary processing mode) as for the pgie.

0:00:08.302356827 88 0x363d5b20 INFO GST_REFCOUNTING gstelement.c:3251:gst_element_dispose: 0x36a990b0 dispose
0:00:08.302385852 88 0x363d5b20 INFO GST_ELEMENT_PADS gstelement.c:789:gst_element_remove_pad: removing pad ‘sink’
0:00:08.302423934 88 0x363d5b20 INFO GST_ELEMENT_PADS gstelement.c:789:gst_element_remove_pad: removing pad ‘src’
0:00:08.302461120 88 0x363d5b20 INFO GST_REFCOUNTING gstelement.c:3297:gst_element_dispose: 0x36a990b0 parent class dispose
0:00:08.302502562 88 0x363d5b20 INFO GST_REFCOUNTING gstelement.c:3328:gst_element_finalize: 0x36a990b0 finalize
0:00:08.302523395 88 0x363d5b20 INFO GST_REFCOUNTING gstelement.c:3334:gst_element_finalize: 0x36a990b0 finalize parent
0:00:08.302554469 88 0x363d5b20 INFO GST_REFCOUNTING gstelement.c:3251:gst_element_dispose: 0x36a99d70 dispose
0:00:08.302574598 88 0x363d5b20 INFO GST_ELEMENT_PADS gstelement.c:789:gst_element_remove_pad: removing pad ‘sink’
0:00:08.302603912 88 0x363d5b20 INFO GST_ELEMENT_PADS gstelement.c:789:gst_element_remove_pad: removing pad ‘src’
0:00:08.302634409 88 0x363d5b20 INFO GST_REFCOUNTING gstelement.c:3297:gst_element_dispose: 0x36a99d70 parent class dispose
0:00:08.302662667 88 0x363d5b20 INFO GST_REFCOUNTING gstelement.c:3328:gst_element_finalize: 0x36a99d70 finalize
0:00:08.302682188 88 0x363d5b20 INFO GST_REFCOUNTING gstelement.c:3334:gst_element_finalize: 0x36a99d70 finalize parent
0:00:08.302711917 88 0x363d5b20 INFO GST_REFCOUNTING gstelement.c:3251:gst_element_dispose: 0x36ac32b0 dispose
0:00:08.302734478 88 0x363d5b20 INFO GST_ELEMENT_PADS gstelement.c:789:gst_element_remove_pad: removing pad ‘sink’
0:00:08.302769520 88 0x363d5b20 INFO GST_ELEMENT_PADS gstelement.c:789:gst_element_remove_pad: removing pad ‘src’
0:00:08.3028041

Note: it seems like the copy command from the terminal does not actually copy what is displayed eg “element_dispose: 0x36ac32b0 dispose” looks like “element_dispose: dispose” in my terminal.

dangraf · March 23, 2021, 11:15am

Test2 facenet as secondary classifier

When running this test, it just halts after some frames. I don’t get the segmentation fault anymore.

Configuration file:

[property]
gpu-id=0
net-scale-factor=0.0039215697906911373
onnx-file=facenet.onnx
model-engine-file=facenet.onnx_b1_gpu0_fp16.engine
batch-size=1
force-implicit-batch-dim=1
input-object-min-width=10
input-object-min-height=10
gie-unique-id=2
network-mode=2
network-type=1
output-tensor-meta=1
classifier-async-mode=0
process-mode=2
operate-on-gie-id=1
operate-on-class-ids=0

Run Command:

GST_DEBUG=3 python3 deepstream_test_1.py …/…/…/streams/sample_720p.h264

Debug output, level4:

Debug level3 does not give any hints. the program just stops and does not respond to either closing the window of the video or ctrl+c in the terminal:

root@bounty-desktop:/opt/nvidia/deepstream/deepstream-5.1/samples/deepstream_python_apps/apps/deepstream-test1# GST_DEBUG=4 python3 deepstream_test_1.py …/…/…/streams/sample_720p.h264
0:00:00.000390226 114 0xe562b20 INFO GST_INIT gst.c:586:init_pre: Initializing GStreamer Core Library version 1.14.5
0:00:00.000588220 114 0xe562b20 INFO GST_INIT gst.c:587:init_pre: Using library installed in /usr/lib/aarch64-linux-gnu
0:00:00.000679168 114 0xe562b20 INFO GST_INIT gst.c:607:init_pre: Linux bounty-desktop 4.9.201-tegra #1 SMP PREEMPT Fri Feb 19 08:42:04 PST 2021 aarch64
0:00:00.001428323 114 0xe562b20 INFO GST_INIT gstmessage.c:127:_priv_gst_message_initialize: init messages
0:00:00.002346573 114 0xe562b20 INFO GST_INIT gstcontext.c:84:_priv_gst_context_initialize: init contexts
0:00:00.003206261 114 0xe562b20 INFO GST_PLUGIN_LOADING gstplugin.c:317:_priv_gst_plugin_initialize: registering 0 static plugins
0:00:00.003624328 114 0xe562b20 INFO GST_PLUGIN_LOADING gstplugin.c:225:gst_plugin_register_static: registered static plugin “staticelements”
0:00:00.003689995 114 0xe562b20 INFO GST_PLUGIN_LOADING gstplugin.c:227:gst_plugin_register_static: added static plugin “staticelements”, result: 1
0:00:00.003769711 114 0xe562b20 INFO GST_REGISTRY gstregistry.c:1727:ensure_current_registry: reading registry cache: /root/.cache/gstreamer-1.0/registry.aarch64.bin
0:00:00.088734674 114 0xe562b20 INFO GST_REGISTRY gstregistrybinary.c:621:priv_gst_registry_binary_read_cache: loaded /root/.cache/gstreamer-1.0/registry.aarch64.bin in 0.084890 seconds
0:00:00.089047168 114 0xe562b20 INFO GST_REGISTRY gstregistry.c:1583:scan_and_update_registry: Validating plugins from registry cache: /root/.cache/gstreamer-1.0/registry.aarch64.bin
0:00:00.094749225 114 0xe562b20 INFO GST_REGISTRY gstregistry.c:1685:scan_and_update_registry: Registry cache has not changed
0:00:00.094848237 114 0xe562b20 INFO GST_REGISTRY gstregistry.c:1762:ensure_current_registry: registry reading and updating done, result = 1
0:00:00.094902352 114 0xe562b20 INFO GST_INIT gst.c:807:init_post: GLib runtime version: 2.56.4
0:00:00.094982259 114 0xe562b20 INFO GST_INIT gst.c:809:init_post: GLib headers version: 2.56.4
0:00:00.095001524 114 0xe562b20 INFO GST_INIT gst.c:810:init_post: initialized GStreamer successfully
Creating Pipeline

Creating Source

0:00:00.099152597 114 0xe562b20 INFO GST_PLUGIN_LOADING gstplugin.c:901:_priv_gst_plugin_load_file_for_registry: plugin “/usr/lib/aarch64-linux-gnu/gstreamer-1.0/libgstcoreelements.so” loaded
0:00:00.099289851 114 0xe562b20 INFO GST_ELEMENT_FACTORY gstelementfactory.c:359:gst_element_factory_create: creating element “filesrc” named “file-source”
0:00:00.100102049 114 0xe562b20 INFO GST_ELEMENT_PADS gstelement.c:670:gst_element_add_pad:GstBaseSrc@0xe9763f0 adding pad ‘src’
Creating H264Parser

0:00:00.107240812 114 0xe562b20 INFO GST_PLUGIN_LOADING gstplugin.c:901:_priv_gst_plugin_load_file_for_registry: plugin “/usr/lib/aarch64-linux-gnu/gstreamer-1.0/libgstvideoparsersbad.so” loaded
0:00:00.107329904 114 0xe562b20 INFO GST_ELEMENT_FACTORY gstelementfactory.c:359:gst_element_factory_create: creating element “h264parse” named “h264-parser”
0:00:00.108014575 114 0xe562b20 INFO GST_ELEMENT_PADS gstelement.c:670:gst_element_add_pad:GstBaseParse@0xe986120 adding pad ‘sink’
0:00:00.108153046 114 0xe562b20 INFO GST_ELEMENT_PADS gstelement.c:670:gst_element_add_pad:GstBaseParse@0xe986120 adding pad ‘src’
0:00:00.108331486 114 0xe562b20 INFO baseparse gstbaseparse.c:3961:gst_base_parse_set_pts_interpolation:GstH264Parse@0xe986120 PTS interpolation: no
Creating Decoder

0:00:00.156591355 114 0xe562b20 INFO GST_PLUGIN_LOADING gstplugin.c:901:_priv_gst_plugin_load_file_for_registry: plugin “/usr/lib/aarch64-linux-gnu/gstreamer-1.0/libgstnvvideo4linux2.so” loaded
0:00:00.156694432 114 0xe562b20 INFO GST_ELEMENT_FACTORY gstelementfactory.c:359:gst_element_factory_create: creating element “nvv4l2decoder” named “nvv4l2-decoder”
0:00:00.157655021 114 0xe562b20 INFO GST_ELEMENT_PADS gstelement.c:670:gst_element_add_pad:GstVideoDecoder@0xe9dc2b0 adding pad ‘sink’
0:00:00.157756593 114 0xe562b20 INFO GST_ELEMENT_PADS gstelement.c:670:gst_element_add_pad:GstVideoDecoder@0xe9dc2b0 adding pad ‘src’
0:00:00.176947915 114 0xe562b20 INFO GST_PLUGIN_LOADING gstplugin.c:901:_priv_gst_plugin_load_file_for_registry: plugin “/usr/lib/aarch64-linux-gnu/gstreamer-1.0/deepstream/libnvdsgst_multistream.so” loaded
0:00:00.177114419 114 0xe562b20 INFO GST_ELEMENT_FACTORY gstelementfactory.c:359:gst_element_factory_create: creating element “nvstreammux” named “Stream-muxer”
0:00:00.178020157 114 0xe562b20 INFO GST_ELEMENT_PADS gstelement.c:670:gst_element_add_pad:GstNvStreamMux@0xea4c250 adding pad ‘src’
0:00:00.280910686 114 0xe562b20 INFO GST_PLUGIN_LOADING gstplugin.c:901:_priv_gst_plugin_load_file_for_registry: plugin “/usr/lib/aarch64-linux-gnu/gstreamer-1.0/deepstream/libnvdsgst_infer.so” loaded
0:00:00.281020611 114 0xe562b20 INFO GST_ELEMENT_FACTORY gstelementfactory.c:359:gst_element_factory_create: creating element “nvinfer” named “primary-inference”
0:00:00.281864170 114 0xe562b20 INFO GST_ELEMENT_PADS gstelement.c:670:gst_element_add_pad:GstBaseTransform@0xec264d0 adding pad ‘sink’
0:00:00.281956879 114 0xe562b20 INFO GST_ELEMENT_PADS gstelement.c:670:gst_element_add_pad:GstBaseTransform@0xec264d0 adding pad ‘src’
0:00:00.283613755 114 0xe562b20 INFO GST_ELEMENT_FACTORY gstelementfactory.c:359:gst_element_factory_create: creating element “nvinfer” named “secondary-nference faceclassifier”
0:00:00.283740769 114 0xe562b20 INFO GST_ELEMENT_PADS gstelement.c:670:gst_element_add_pad:GstBaseTransform@0xec26da0 adding pad ‘sink’
0:00:00.283818085 114 0xe562b20 INFO GST_ELEMENT_PADS gstelement.c:670:gst_element_add_pad:GstBaseTransform@0xec26da0 adding pad ‘src’
0:00:00.288381656 114 0xe562b20 INFO GST_PLUGIN_LOADING gstplugin.c:901:_priv_gst_plugin_load_file_for_registry: plugin “/usr/lib/aarch64-linux-gnu/gstreamer-1.0/deepstream/libgstnvvideoconvert.so” loaded
0:00:00.288452956 114 0xe562b20 INFO GST_ELEMENT_FACTORY gstelementfactory.c:359:gst_element_factory_create: creating element “nvvideoconvert” named “convertor”
0:00:00.289200990 114 0xe562b20 INFO GST_ELEMENT_PADS gstelement.c:670:gst_element_add_pad:GstBaseTransform@0xec50270 adding pad ‘sink’
0:00:00.289329636 114 0xe562b20 INFO GST_ELEMENT_PADS gstelement.c:670:gst_element_add_pad:GstBaseTransform@0xec50270 adding pad ‘src’
0:00:00.302168632 114 0xe562b20 INFO GST_PLUGIN_LOADING gstplugin.c:901:_priv_gst_plugin_load_file_for_registry: plugin “/usr/lib/aarch64-linux-gnu/gstreamer-1.0/deepstream/libnvdsgst_osd.so” loaded
0:00:00.302270620 114 0xe562b20 INFO GST_ELEMENT_FACTORY gstelementfactory.c:359:gst_element_factory_create: creating element “nvdsosd” named “onscreendisplay”
0:00:00.303162406 114 0xe562b20 INFO GST_ELEMENT_PADS gstelement.c:670:gst_element_add_pad:GstBaseTransform@0xec61fd0 adding pad ‘sink’
0:00:00.303256266 114 0xe562b20 INFO GST_ELEMENT_PADS gstelement.c:670:gst_element_add_pad:GstBaseTransform@0xec61fd0 adding pad ‘src’
0:00:00.323991115 114 0xe562b20 INFO GST_PLUGIN_LOADING gstplugin.c:901:_priv_gst_plugin_load_file_for_registry: plugin “/usr/lib/aarch64-linux-gnu/gstreamer-1.0/libgstnvegltransform.so” loaded
0:00:00.324172948 114 0xe562b20 INFO GST_ELEMENT_FACTORY gstelementfactory.c:359:gst_element_factory_create: creating element “nvegltransform” named “nvegl-transform”
0:00:00.324877908 114 0xe562b20 INFO GST_ELEMENT_PADS gstelement.c:670:gst_element_add_pad:GstBaseTransform@0xed18150 adding pad ‘sink’
0:00:00.325022203 114 0xe562b20 INFO GST_ELEMENT_PADS gstelement.c:670:gst_element_add_pad:GstBaseTransform@0xed18150 adding pad ‘src’
Creating EGLSink

0:00:00.330312976 114 0xe562b20 INFO GST_PLUGIN_LOADING gstplugin.c:901:_priv_gst_plugin_load_file_for_registry: plugin “/usr/lib/aarch64-linux-gnu/gstreamer-1.0/libgstnveglglessink.so” loaded
0:00:00.330429078 114 0xe562b20 INFO GST_ELEMENT_FACTORY gstelementfactory.c:359:gst_element_factory_create: creating element “nveglglessink” named “nvvideo-renderer”
0:00:00.331588491 114 0xe562b20 INFO GST_ELEMENT_PADS gstelement.c:670:gst_element_add_pad:GstBaseSink@0xed1f760 adding pad ‘sink’
Playing file …/…/…/streams/sample_720p.h264
0:00:00.334967720 114 0xe562b20 INFO filesrc gstfilesrc.c:261:gst_file_src_set_location: filename : …/…/…/streams/sample_720p.h264
0:00:00.335025291 114 0xe562b20 INFO filesrc gstfilesrc.c:262:gst_file_src_set_location: uri : file:///opt/nvidia/deepstream/deepstream-5.1/samples/streams/sample_720p.h264
Adding elements to Pipeline

Linking elements in the Pipeline

0:00:00.338378694 114 0xe562b20 INFO GST_ELEMENT_PADS gstutils.c:1774:gst_element_link_pads_full: trying to link element file-source:(any) to element h264-parser:(any)
0:00:00.338695221 114 0xe562b20 INFO GST_PADS gstutils.c:1035:gst_pad_check_link: trying to link file-source:src and h264-parser:sink
0:00:00.338869181 114 0xe562b20 INFO GST_PADS gstpad.c:4232:gst_pad_peer_query:h264-parser:src pad has no peer
0:00:00.338961185 114 0xe562b20 INFO GST_PADS gstutils.c:1588:prepare_link_maybe_ghosting: file-source and h264-parser in same bin, no need for ghost pads
0:00:00.339042629 114 0xe562b20 INFO GST_PADS gstpad.c:2378:gst_pad_link_prepare: trying to link file-source:src and h264-parser:sink
0:00:00.339144458 114 0xe562b20 INFO GST_PADS gstpad.c:4232:gst_pad_peer_query:h264-parser:src pad has no peer
0:00:00.339265615 114 0xe562b20 INFO GST_PADS gstpad.c:2586:gst_pad_link_full: linked file-source:src and h264-parser:sink, successful
0:00:00.339294737 114 0xe562b20 INFO GST_EVENT gstevent.c:1517:gst_event_new_reconfigure: creating reconfigure event
0:00:00.339323602 114 0xe562b20 INFO GST_EVENT gstpad.c:5808:gst_pad_send_event_unchecked:file-source:src Received event on flushing pad. Discarding
0:00:00.339501210 114 0xe562b20 INFO GST_ELEMENT_PADS gstutils.c:1774:gst_element_link_pads_full: trying to link element h264-parser:(any) to element nvv4l2-decoder:(any)
0:00:00.339608383 114 0xe562b20 INFO GST_PADS gstutils.c:1035:gst_pad_check_link: trying to link h264-parser:src and nvv4l2-decoder:sink
0:00:00.339811560 114 0xe562b20 INFO GST_PADS gstpad.c:4232:gst_pad_peer_query:nvv4l2-decoder:src pad has no peer
0:00:00.340488040 114 0xe562b20 INFO GST_PADS gstutils.c:1588:prepare_link_maybe_ghosting: h264-parser and nvv4l2-decoder in same bin, no need for ghost pads
0:00:00.340588140 114 0xe562b20 INFO GST_PADS gstpad.c:2378:gst_pad_link_prepare: trying to link h264-parser:src and nvv4l2-decoder:sink
0:00:00.340644879 114 0xe562b20 INFO GST_PADS gstpad.c:4232:gst_pad_peer_query:nvv4l2-decoder:src pad has no peer
0:00:00.340916188 114 0xe562b20 INFO GST_PADS gstpad.c:2586:gst_pad_link_full: linked h264-parser:src and nvv4l2-decoder:sink, successful
0:00:00.340947453 114 0xe562b20 INFO GST_EVENT gstevent.c:1517:gst_event_new_reconfigure: creating reconfigure event
0:00:00.341003136 114 0xe562b20 INFO GST_EVENT gstpad.c:5808:gst_pad_send_event_unchecked:h264-parser:src Received event on flushing pad. Discarding
0:00:00.341404178 114 0xe562b20 INFO GST_ELEMENT_PADS gstelement.c:917:gst_element_get_static_pad: no such pad ‘sink_0’ in element “Stream-muxer”
0:00:00.341687039 114 0xe562b20 INFO GST_ELEMENT_PADS gstelement.c:670:gst_element_add_pad: adding pad ‘sink_0’
0:00:00.342675757 114 0xe562b20 INFO GST_ELEMENT_PADS gstelement.c:920:gst_element_get_static_pad: found pad nvv4l2-decoder:src
0:00:00.343001660 114 0xe562b20 INFO GST_PADS gstpad.c:2378:gst_pad_link_prepare: trying to link nvv4l2-decoder:src and Stream-muxer:sink_0
0:00:00.343214726 114 0xe562b20 INFO GST_PADS gstpad.c:4232:gst_pad_peer_query:Stream-muxer:src pad has no peer
0:00:00.343498195 114 0xe562b20 INFO GST_PADS gstpad.c:2586:gst_pad_link_full: linked nvv4l2-decoder:src and Stream-muxer:sink_0, successful
0:00:00.343533141 114 0xe562b20 INFO GST_EVENT gstevent.c:1517:gst_event_new_reconfigure: creating reconfigure event
0:00:00.343560438 114 0xe562b20 INFO GST_EVENT gstpad.c:5808:gst_pad_send_event_unchecked:nvv4l2-decoder:src Received event on flushing pad. Discarding
0:00:00.343695261 114 0xe562b20 INFO GST_ELEMENT_PADS gstutils.c:1774:gst_element_link_pads_full: trying to link element Stream-muxer:(any) to element primary-inference:(any)
0:00:00.343742911 114 0xe562b20 INFO GST_PADS gstutils.c:1035:gst_pad_check_link: trying to link Stream-muxer:src and primary-inference:sink
0:00:00.343795969 114 0xe562b20 INFO GST_PADS gstpad.c:4232:gst_pad_peer_query:primary-inference:src pad has no peer
0:00:00.343967177 114 0xe562b20 INFO GST_PADS gstutils.c:1588:prepare_link_maybe_ghosting: Stream-muxer and primary-inference in same bin, no need for ghost pads
0:00:00.344036140 114 0xe562b20 INFO GST_PADS gstpad.c:2378:gst_pad_link_prepare: trying to link Stream-muxer:src and primary-inference:sink
0:00:00.344078414 114 0xe562b20 INFO GST_PADS gstpad.c:4232:gst_pad_peer_query:primary-inference:src pad has no peer
0:00:00.344185235 114 0xe562b20 INFO GST_PADS gstpad.c:2586:gst_pad_link_full: linked Stream-muxer:src and primary-inference:sink, successful
0:00:00.344210068 114 0xe562b20 INFO GST_EVENT gstevent.c:1517:gst_event_new_reconfigure: creating reconfigure event
0:00:00.344234485 114 0xe562b20 INFO GST_EVENT gstpad.c:5808:gst_pad_send_event_unchecked:Stream-muxer:src Received event on flushing pad. Discarding
0:00:00.344328442 114 0xe562b20 INFO GST_ELEMENT_PADS gstutils.c:1774:gst_element_link_pads_full: trying to link element primary-inference:(any) to element secondary-nference faceclassifier:(any)
0:00:00.344369852 114 0xe562b20 INFO GST_PADS gstutils.c:1035:gst_pad_check_link: trying to link primary-inference:src and secondary-nference faceclassifier:sink
0:00:00.344551844 114 0xe562b20 INFO GST_PADS gstpad.c:4232:gst_pad_peer_query: pad has no peer
0:00:00.344641896 114 0xe562b20 INFO GST_PADS gstutils.c:1588:prepare_link_maybe_ghosting: primary-inference and secondary-nference faceclassifier in same bin, no need for ghost pads
0:00:00.344692459 114 0xe562b20 INFO GST_PADS gstpad.c:2378:gst_pad_link_prepare: trying to link primary-inference:src and secondary-nference faceclassifier:sink
0:00:00.344811920 114 0xe562b20 INFO GST_PADS gstpad.c:4232:gst_pad_peer_query: pad has no peer
0:00:00.344902036 114 0xe562b20 INFO GST_PADS gstpad.c:2586:gst_pad_link_full: linked primary-inference:src and secondary-nference faceclassifier:sink, successful
0:00:00.344924405 114 0xe562b20 INFO GST_EVENT gstevent.c:1517:gst_event_new_reconfigure: creating reconfigure event
0:00:00.344947639 114 0xe562b20 INFO GST_EVENT gstpad.c:5808:gst_pad_send_event_unchecked:primary-inference:src Received event on flushing pad. Discarding
0:00:00.345023706 114 0xe562b20 INFO GST_ELEMENT_PADS gstutils.c:1774:gst_element_link_pads_full: trying to link element secondary-nference faceclassifier:(any) to element convertor:(any)
0:00:00.345061788 114 0xe562b20 INFO GST_PADS gstutils.c:1035:gst_pad_check_link: trying to link secondary-nference faceclassifier:src and convertor:sink
0:00:00.345250341 114 0xe562b20 INFO GST_PADS gstpad.c:4232:gst_pad_peer_query:convertor:src pad has no peer
0:00:00.345572276 114 0xe562b20 INFO GST_PADS gstutils.c:1588:prepare_link_maybe_ghosting: secondary-nference faceclassifier and convertor in same bin, no need for ghost pads
0:00:00.345662936 114 0xe562b20 INFO GST_PADS gstpad.c:2378:gst_pad_link_prepare: trying to link secondary-nference faceclassifier:src and convertor:sink
0:00:00.345840864 114 0xe562b20 INFO GST_PADS gstpad.c:4232:gst_pad_peer_query:convertor:src pad has no peer
0:00:00.347136956 114 0xe562b20 INFO GST_PADS gstpad.c:2586:gst_pad_link_full: linked secondary-nference faceclassifier:src and convertor:sink, successful
0:00:00.347204799 114 0xe562b20 INFO GST_EVENT gstevent.c:1517:gst_event_new_reconfigure: creating reconfigure event
0:00:00.347237761 114 0xe562b20 INFO GST_EVENT gstpad.c:5808:gst_pad_send_event_unchecked: Received event on flushing pad. Discarding
0:00:00.347378663 114 0xe562b20 INFO GST_ELEMENT_PADS gstutils.c:1774:gst_element_link_pads_full: trying to link element convertor:(any) to element onscreendisplay:(any)
0:00:00.347432362 114 0xe562b20 INFO GST_PADS gstutils.c:1035:gst_pad_check_link: trying to link convertor:src and onscreendisplay:sink
0:00:00.348448217 114 0xe562b20 INFO GST_PADS gstpad.c:4232:gst_pad_peer_query:onscreendisplay:src pad has no peer
0:00:00.348588351 114 0xe562b20 INFO GST_PADS gstutils.c:1588:prepare_link_maybe_ghosting: convertor and onscreendisplay in same bin, no need for ghost pads
0:00:00.348663139 114 0xe562b20 INFO GST_PADS gstpad.c:2378:gst_pad_link_prepare: trying to link convertor:src and onscreendisplay:sink
0:00:00.349718772 114 0xe562b20 INFO GST_PADS gstpad.c:4232:gst_pad_peer_query:onscreendisplay:src pad has no peer
0:00:00.349849018 114 0xe562b20 INFO GST_PADS gstpad.c:2586:gst_pad_link_full: linked convertor:src and onscreendisplay:sink, successful
0:00:00.349896604 114 0xe562b20 INFO GST_EVENT gstevent.c:1517:gst_event_new_reconfigure: creating reconfigure event
0:00:00.349993344 114 0xe562b20 INFO GST_EVENT gstpad.c:5808:gst_pad_send_event_unchecked:convertor:src Received event on flushing pad. Discarding
0:00:00.350136615 114 0xe562b20 INFO GST_ELEMENT_PADS gstutils.c:1774:gst_element_link_pads_full: trying to link element onscreendisplay:(any) to element nvegl-transform:(any)
0:00:00.350187689 114 0xe562b20 INFO GST_PADS gstutils.c:1035:gst_pad_check_link: trying to link onscreendisplay:src and nvegl-transform:sink
0:00:00.350682592 114 0xe562b20 INFO GST_PADS gstpad.c:4232:gst_pad_peer_query:nvegl-transform:src pad has no peer
0:00:00.350916715 114 0xe562b20 INFO GST_PADS gstutils.c:1588:prepare_link_maybe_ghosting: onscreendisplay and nvegl-transform in same bin, no need for ghost pads
0:00:00.350974510 114 0xe562b20 INFO GST_PADS gstpad.c:2378:gst_pad_link_prepare: trying to link onscreendisplay:src and nvegl-transform:sink
0:00:00.351982397 114 0xe562b20 INFO GST_PADS gstpad.c:4232:gst_pad_peer_query:nvegl-transform:src pad has no peer
0:00:00.352182374 114 0xe562b20 INFO GST_PADS gstpad.c:2586:gst_pad_link_full: linked onscreendisplay:src and nvegl-transform:sink, successful
0:00:00.352269418 114 0xe562b20 INFO GST_EVENT gstevent.c:1517:gst_event_new_reconfigure: creating reconfigure event
0:00:00.352307852 114 0xe562b20 INFO GST_EVENT gstpad.c:5808:gst_pad_send_event_unchecked:onscreendisplay:src Received event on flushing pad. Discarding
0:00:00.352474355 114 0xe562b20 INFO GST_ELEMENT_PADS gstutils.c:1774:gst_element_link_pads_full: trying to link element nvegl-transform:(any) to element nvvideo-renderer:(any)
0:00:00.352522550 114 0xe562b20 INFO GST_PADS gstutils.c:1035:gst_pad_check_link: trying to link nvegl-transform:src and nvvideo-renderer:sink
0:00:00.354389740 114 0xe562b20 INFO GST_PADS gstutils.c:1588:prepare_link_maybe_ghosting: nvegl-transform and nvvideo-renderer in same bin, no need for ghost pads
0:00:00.354455087 114 0xe562b20 INFO GST_PADS gstpad.c:2378:gst_pad_link_prepare: trying to link nvegl-transform:src and nvvideo-renderer:sink
0:00:00.355149359 114 0xe562b20 INFO GST_PADS gstpad.c:2586:gst_pad_link_full: linked nvegl-transform:src and nvvideo-renderer:sink, successful
0:00:00.355186929 114 0xe562b20 INFO GST_EVENT gstevent.c:1517:gst_event_new_reconfigure: creating reconfigure event
0:00:00.355221331 114 0xe562b20 INFO GST_EVENT gstpad.c:5808:gst_pad_send_event_unchecked:nvegl-transform:src Received event on flushing pad. Discarding
0:00:00.360973949 114 0xe562b20 INFO GST_ELEMENT_PADS gstelement.c:920:gst_element_get_static_pad: found pad onscreendisplay:sink
Starting pipeline

0:00:00.362010702 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current NULL pending VOID_PENDING, desired next READY

Using winsys: x11
0:00:00.362545286 114 0xe562b20 INFO egladaption gstegladaptation_egl.c:186:gst_egl_adaptation_init_display: System reports supported EGL version v1.5
0:00:00.363440240 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to READY
0:00:00.363487986 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.363552149 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘nvvideo-renderer’ changed state to 2(READY) successfully
0:00:00.363607768 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current NULL pending VOID_PENDING, desired next READY
0:00:00.363640985 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to READY
0:00:00.363679931 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.363740158 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘nvegl-transform’ changed state to 2(READY) successfully
0:00:00.363802561 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current NULL pending VOID_PENDING, desired next READY
0:00:00.363837090 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to READY
0:00:00.363870404 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.364030187 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘onscreendisplay’ changed state to 2(READY) successfully
0:00:00.364077069 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current NULL pending VOID_PENDING, desired next READY
0:00:00.364122863 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to READY
0:00:00.364168882 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.364230708 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘convertor’ changed state to 2(READY) successfully
0:00:00.364278231 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current NULL pending VOID_PENDING, desired next READY
0:00:00.364313144 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to READY
0:00:00.364344890 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.364389532 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘secondary-nference faceclassifier’ changed state to 2(READY) successfully
0:00:00.364532002 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current NULL pending VOID_PENDING, desired next READY
0:00:00.364571620 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to READY
0:00:00.364597829 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.364653192 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘primary-inference’ changed state to 2(READY) successfully
0:00:00.364709867 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current NULL pending VOID_PENDING, desired next READY
0:00:00.468811716 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to READY
0:00:00.468914601 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.469049167 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘Stream-muxer’ changed state to 2(READY) successfully
0:00:00.469122451 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current NULL pending VOID_PENDING, desired next READY
Opening in BLOCKING MODE
Opening in BLOCKING MODE
0:00:00.529394813 114 0xe562b20 INFO v4l2 v4l2_calls.c:619:gst_v4l2_open:nvv4l2-decoder:sink Opened device ‘’ (/dev/nvhost-nvdec) successfully
0:00:00.529502818 114 0xe562b20 INFO v4l2 v4l2_calls.c:720:gst_v4l2_dup:nvv4l2-decoder:src Cloned device ‘’ (/dev/nvhost-nvdec) successfully
0:00:00.529621351 114 0xe562b20 INFO v4l2 gstv4l2object.c:1284:gst_v4l2_object_fill_format_list:nvv4l2-decoder:sink got 13 format(s):
0:00:00.529654633 114 0xe562b20 INFO v4l2 gstv4l2object.c:1290:gst_v4l2_object_fill_format_list:nvv4l2-decoder:sink JPEG
0:00:00.529681802 114 0xe562b20 INFO v4l2 gstv4l2object.c:1290:gst_v4l2_object_fill_format_list:nvv4l2-decoder:sink MJPG
0:00:00.529707243 114 0xe562b20 INFO v4l2 gstv4l2object.c:1290:gst_v4l2_object_fill_format_list:nvv4l2-decoder:sink DVX5
0:00:00.529731404 114 0xe562b20 INFO v4l2 gstv4l2object.c:1290:gst_v4l2_object_fill_format_list:nvv4l2-decoder:sink DVX4
0:00:00.529756109 114 0xe562b20 INFO v4l2 gstv4l2object.c:1290:gst_v4l2_object_fill_format_list:nvv4l2-decoder:sink MPG4
0:00:00.529781199 114 0xe562b20 INFO v4l2 gstv4l2object.c:1290:gst_v4l2_object_fill_format_list:nvv4l2-decoder:sink MPG2
0:00:00.529874995 114 0xe562b20 INFO v4l2 gstv4l2object.c:1290:gst_v4l2_object_fill_format_list:nvv4l2-decoder:sink MPG1
0:00:00.529906132 114 0xe562b20 INFO v4l2 gstv4l2object.c:1290:gst_v4l2_object_fill_format_list:nvv4l2-decoder:sink VP90
0:00:00.529929941 114 0xe562b20 INFO v4l2 gstv4l2object.c:1290:gst_v4l2_object_fill_format_list:nvv4l2-decoder:sink VP80
0:00:00.529954071 114 0xe562b20 INFO v4l2 gstv4l2object.c:1290:gst_v4l2_object_fill_format_list:nvv4l2-decoder:sink H265
0:00:00.530016025 114 0xe562b20 INFO v4l2 gstv4l2object.c:1290:gst_v4l2_object_fill_format_list:nvv4l2-decoder:sink H264
0:00:00.530041275 114 0xe562b20 INFO v4l2 gstv4l2object.c:1290:gst_v4l2_object_fill_format_list:nvv4l2-decoder:sink VP8F
0:00:00.530064028 114 0xe562b20 INFO v4l2 gstv4l2object.c:1290:gst_v4l2_object_fill_format_list:nvv4l2-decoder:sink S264
0:00:00.530914371 114 0xe562b20 INFO v4l2 gstv4l2object.c:4490:gst_v4l2_object_probe_caps:nvv4l2-decoder:sink probed caps: image/jpeg, width=(int)[ 48, 7680 ], height=(int)[ 48, 7680 ], parsed=(boolean)true; video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, width=(int)[ 48, 4096 ], height=(int)[ 48, 4096 ], parsed=(boolean)true; video/x-h265, stream-format=(string)byte-stream, alignment=(string)au, width=(int)[ 180, 7680 ], height=(int)[ 180, 7680 ], parsed=(boolean)true; video/mpeg, mpegversion=(int)4, systemstream=(boolean)false, parsed=(boolean)true, width=(int)[ 48, 1920 ], height=(int)[ 48, 1920 ]; video/mpeg, mpegversion=(int)2, systemstream=(boolean)false, parsed=(boolean)true, width=(int)[ 48, 4096 ], height=(int)[ 48, 4096 ]; video/mpeg, mpegversion=(int)1, systemstream=(boolean)false, parsed=(boolean)true, width=(int)[ 48, 4096 ], height=(int)[ 48, 4096 ]; video/x-divx, divxversion=(int)5, width=(int)[ 48, 1920 ], height=(int)[ 48, 1920 ], systemstream=(boolean)false; video/x-divx, divxversion=(int)4, width=(int)[ 48, 1920 ], height=(int)[ 48, 1920 ], systemstream=(boolean)false; video/x-vp8, width=(int)[ 48, 4096 ], height=(int)[ 48, 4096 ]; video/x-vp9, width=(int)[ 48, 4096 ], height=(int)[ 48, 4096 ]
0:00:00.534211644 114 0xe562b20 INFO v4l2 gstv4l2object.c:1284:gst_v4l2_object_fill_format_list:nvv4l2-decoder:src got 2 format(s):
0:00:00.534310241 114 0xe562b20 INFO v4l2 gstv4l2object.c:1290:gst_v4l2_object_fill_format_list:nvv4l2-decoder:src NM12
0:00:00.534491433 114 0xe562b20 INFO v4l2 gstv4l2object.c:1290:gst_v4l2_object_fill_format_list:nvv4l2-decoder:src YM16
0:00:00.534723444 114 0xe562b20 WARN v4l2 gstv4l2object.c:4434:gst_v4l2_object_probe_caps:nvv4l2-decoder:src Failed to probe pixel aspect ratio with VIDIOC_CROPCAP: Unknown error -1
0:00:00.534842233 114 0xe562b20 WARN v4l2 gstv4l2object.c:2375:gst_v4l2_object_add_interlace_mode:0xe9deac0 Failed to determine interlace mode
0:00:00.534925597 114 0xe562b20 WARN v4l2 gstv4l2object.c:2375:gst_v4l2_object_add_interlace_mode:0xe9deac0 Failed to determine interlace mode
0:00:00.535128614 114 0xe562b20 INFO v4l2 gstv4l2object.c:4490:gst_v4l2_object_probe_caps:nvv4l2-decoder:src probed caps: video/x-raw, format=(string)NV12, width=(int)[ 48, 4096 ], height=(int)[ 48, 4096 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-raw, format=(string)Y42B, width=(int)[ 48, 4096 ], height=(int)[ 48, 4096 ], framerate=(fraction)[ 0/1, 2147483647/1 ]
0:00:00.535171752 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to READY
0:00:00.535203402 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.535300590 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘nvv4l2-decoder’ changed state to 2(READY) successfully
0:00:00.535428372 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current NULL pending VOID_PENDING, desired next READY
0:00:00.535464630 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to READY
0:00:00.535489527 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.535525753 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘h264-parser’ changed state to 2(READY) successfully
0:00:00.535560442 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current NULL pending VOID_PENDING, desired next READY
0:00:00.535586972 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to READY
0:00:00.535612285 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.535644350 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘file-source’ changed state to 2(READY) successfully
0:00:00.535719682 114 0xe562b20 INFO GST_STATES gstelement.c:2651:gst_element_continue_state: committing state from NULL to READY, pending PLAYING, next PAUSED
0:00:00.535759012 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed NULL to READY (PLAYING pending)
0:00:00.535793285 114 0xe562b20 INFO GST_STATES gstelement.c:2658:gst_element_continue_state: continue state change READY to PAUSED, final PLAYING
0:00:00.535998479 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current READY pending VOID_PENDING, desired next PAUSED
0:00:00.537748800 114 0xe562b20 INFO GST_STATES gstbin.c:2960:gst_bin_change_state_func: child ‘nvvideo-renderer’ is changing state asynchronously to PAUSED
0:00:00.537835140 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current READY pending VOID_PENDING, desired next PAUSED
0:00:00.538134610 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PAUSED
0:00:00.538177716 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.538233046 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘nvegl-transform’ changed state to 3(PAUSED) successfully
0:00:00.538283865 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current READY pending VOID_PENDING, desired next PAUSED
0:00:00.552371590 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PAUSED
0:00:00.552475403 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.552577999 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘onscreendisplay’ changed state to 3(PAUSED) successfully
0:00:00.552662291 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current READY pending VOID_PENDING, desired next PAUSED
0:00:00.552753111 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PAUSED
0:00:00.552833627 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.553014564 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘convertor’ changed state to 3(PAUSED) successfully
0:00:00.553088903 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current READY pending VOID_PENDING, desired next PAUSED
0:00:05.485528751 114 0xe562b20 INFO nvinfer gstnvinfer.cpp:619:gst_nvinfer_logger: NvDsInferContext[UID 2]: Info from NvDsInferContextImpl::deserializeEngineAndBackend() <nvdsinfer_context_impl.cpp:1702> [UID = 2]: deserialized trt engine from :/opt/nvidia/deepstream/deepstream-5.1/samples/deepstream_python_apps/apps/deepstream-test1/facenet/facenet.onnx_b1_gpu0_fp16.engine
INFO: [Implicit Engine Info]: layers num: 2
0 INPUT kFLOAT input:0 3x160x160
1 OUTPUT kFLOAT Bottleneck/BatchNorm/batchnorm/add_1:0 128

0:00:05.485828445 114 0xe562b20 INFO nvinfer gstnvinfer.cpp:619:gst_nvinfer_logger: NvDsInferContext[UID 2]: Info from NvDsInferContextImpl::generateBackendContext() <nvdsinfer_context_impl.cpp:1806> [UID = 2]: Use deserialized engine model: /opt/nvidia/deepstream/deepstream-5.1/samples/deepstream_python_apps/apps/deepstream-test1/facenet/facenet.onnx_b1_gpu0_fp16.engine
0:00:05.495734087 114 0xe562b20 INFO nvinfer gstnvinfer_impl.cpp:313:notifyLoadModelStatus: [UID 2]: Load new model:facenet/facenet_config.txt sucessfully
0:00:05.495856493 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PAUSED
0:00:05.496129530 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:05.496213117 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘secondary-nference faceclassifier’ changed state to 3(PAUSED) successfully
0:00:05.496286081 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current READY pending VOID_PENDING, desired next PAUSED
0:00:05.556417665 114 0xe562b20 INFO nvinfer gstnvinfer.cpp:619:gst_nvinfer_logger: NvDsInferContext[UID 1]: Info from NvDsInferContextImpl::deserializeEngineAndBackend() <nvdsinfer_context_impl.cpp:1702> [UID = 1]: deserialized trt engine from :/opt/nvidia/deepstream/deepstream-5.1/samples/models/Primary_Detector/resnet10.caffemodel_b1_gpu0_int8.engine
INFO: [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:05.556712175 114 0xe562b20 INFO nvinfer gstnvinfer.cpp:619:gst_nvinfer_logger: NvDsInferContext[UID 1]: Info from NvDsInferContextImpl::generateBackendContext() <nvdsinfer_context_impl.cpp:1806> [UID = 1]: Use deserialized engine model: /opt/nvidia/deepstream/deepstream-5.1/samples/models/Primary_Detector/resnet10.caffemodel_b1_gpu0_int8.engine
0:00:05.559835872 114 0xe562b20 INFO nvinfer gstnvinfer_impl.cpp:313:notifyLoadModelStatus: [UID 1]: Load new model:dstest1_pgie_config.txt sucessfully
0:00:05.560109900 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PAUSED
0:00:05.560210257 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:05.560352920 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘primary-inference’ changed state to 3(PAUSED) successfully
0:00:05.560462685 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current READY pending VOID_PENDING, desired next PAUSED
0:00:05.560614020 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PAUSED
0:00:05.560664774 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:05.560730889 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘Stream-muxer’ changed state to 3(PAUSED) successfully
0:00:05.560804524 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current READY pending VOID_PENDING, desired next PAUSED
0:00:05.560886448 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PAUSED
0:00:05.560941331 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:05.561047992 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘nvv4l2-decoder’ changed state to 3(PAUSED) successfully
0:00:05.561136668 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current READY pending VOID_PENDING, desired next PAUSED
0:00:05.561522862 114 0xe562b20 INFO filesrc gstfilesrc.c:468:gst_file_src_start: opening file …/…/…/streams/sample_720p.h264
0:00:05.561657716 114 0xe562b20 WARN basesrc gstbasesrc.c:3583:gst_base_src_start_complete: pad not activated yet
0:00:05.561748504 114 0xe562b20 INFO filesrc gstfilesrc.c:468:gst_file_src_start: opening file …/…/…/streams/sample_720p.h264
0:00:05.562003460 114 0xe562b20 INFO GST_EVENT gstevent.c:895:gst_event_new_segment: creating segment event time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=99:99:99.999999999, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.000000000, duration 99:99:99.999999999
0:00:05.562100456 114 0xe562b20 INFO task gsttask.c:457:gst_task_set_lock: setting stream lock 0xe978390 on task 0xe988710
0:00:05.562130986 114 0xe562b20 INFO GST_PADS gstpad.c:6154:gst_pad_start_task:h264-parser:sink created task 0xe988710
0:00:05.562766695 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PAUSED
0:00:05.562879725 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:05.562945040 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘h264-parser’ changed state to 3(PAUSED) successfully
0:00:05.563002546 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current READY pending VOID_PENDING, desired next PAUSED
0:00:05.563053269 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PAUSED
0:00:05.563085750 114 0xe69ca80 INFO GST_ELEMENT_PADS gstelement.c:920:gst_element_get_static_pad: found pad h264-parser:sink
0:00:05.563224220 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:05.563366499 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘file-source’ changed state to 3(PAUSED) successfully
0:00:05.567615240 114 0xe69ca80 INFO h264parse gsth264parse.c:1793:gst_h264_parse_update_src_caps: resolution changed 1280x720
0:00:05.567731981 114 0xe69ca80 INFO baseparse gstbaseparse.c:4004:gst_base_parse_set_latency: min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:05.569913106 114 0xe69ca80 INFO GST_EVENT gstevent.c:814:gst_event_new_caps: creating caps event video/x-h264, width=(int)1280, height=(int)720, framerate=(fraction)30/1, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, parsed=(boolean)true, stream-format=(string)byte-stream, alignment=(string)au, profile=(string)high, level=(string)3.1
NvMMLiteOpen : Block : BlockType = 261
NVMEDIA: Reading vendor.tegra.display-size : status: 6
NvMMLiteBlockCreate : Block : BlockType = 261
0:00:05.580702790 114 0xe69ca80 INFO v4l2 gstv4l2object.c:3152:gst_v4l2_object_setup_pool:nvv4l2-decoder:sink accessing buffers via mode 3
0:00:05.581359972 114 0xe69ca80 INFO v4l2bufferpool gstv4l2bufferpool.c:825:gst_v4l2_buffer_pool_set_config:nvv4l2-decoder:pool:sink increasing minimum buffers to 2
0:00:05.581411239 114 0xe69ca80 INFO v4l2bufferpool gstv4l2bufferpool.c:832:gst_v4l2_buffer_pool_set_config:nvv4l2-decoder:pool:sink increasing minimum buffers to 10
0:00:05.581454825 114 0xe69ca80 INFO v4l2bufferpool gstv4l2bufferpool.c:838:gst_v4l2_buffer_pool_set_config:nvv4l2-decoder:pool:sink reducing maximum buffers to 32
0:00:05.581489386 114 0xe69ca80 INFO v4l2bufferpool gstv4l2bufferpool.c:849:gst_v4l2_buffer_pool_set_config:nvv4l2-decoder:pool:sink can’t allocate, setting maximum to minimum
0:00:05.582161737 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”;
0:00:05.683448092 114 0xe69ca80 WARN v4l2 gstv4l2object.c:4434:gst_v4l2_object_probe_caps:nvv4l2-decoder:src Failed to probe pixel aspect ratio with VIDIOC_CROPCAP: Unknown error -1
0:00:05.683587490 114 0xe69ca80 WARN v4l2 gstv4l2object.c:2375:gst_v4l2_object_add_interlace_mode:0xe9deac0 Failed to determine interlace mode
0:00:05.683672102 114 0xe69ca80 WARN v4l2 gstv4l2object.c:2375:gst_v4l2_object_add_interlace_mode:0xe9deac0 Failed to determine interlace mode
0:00:05.683846606 114 0xe69ca80 INFO v4l2 gstv4l2object.c:4490:gst_v4l2_object_probe_caps:nvv4l2-decoder:src probed caps: video/x-raw, format=(string)NV12, width=(int)[ 48, 4096 ], height=(int)[ 48, 4096 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-raw, format=(string)Y42B, width=(int)[ 48, 4096 ], height=(int)[ 48, 4096 ], framerate=(fraction)[ 0/1, 2147483647/1 ]
0:00:05.685938863 114 0xe69ca80 INFO GST_EVENT gstevent.c:814:gst_event_new_caps: creating caps event video/x-raw(memory:NVMM), format=(string)NV12, width=(int)1280, height=(int)720, interlace-mode=(string)progressive, multiview-mode=(string)mono, multiview-flags=(GstVideoMultiviewFlagsSet)0:ffffffff:/right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono, pixel-aspect-ratio=(fraction)1/1, chroma-site=(string)mpeg2, colorimetry=(string)bt709, framerate=(fraction)30/1
0:00:05.686597390 114 0xe69ca80 INFO GST_EVENT gstevent.c:814:gst_event_new_caps: creating caps event video/x-raw(memory:NVMM), format=(string)NV12, width=(int)1920, height=(int)1080, multiview-mode=(string)mono, multiview-flags=(GstVideoMultiviewFlagsSet)0:ffffffff:/right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono, framerate=(fraction)30/1, batch-size=(int)1, num-surfaces-per-frame=(int)1
0:00:05.687015969 114 0xe69ca80 INFO basetransform gstbasetransform.c:1308:gst_base_transform_setcaps: reuse caps
0:00:05.687101157 114 0xe69ca80 INFO GST_EVENT gstevent.c:814:gst_event_new_caps: creating caps event video/x-raw(memory:NVMM), format=(string)NV12, width=(int)1920, height=(int)1080, multiview-mode=(string)mono, multiview-flags=(GstVideoMultiviewFlagsSet)0:ffffffff:/right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono, framerate=(fraction)30/1, batch-size=(int)1, num-surfaces-per-frame=(int)1
0:00:05.687431924 114 0xe69ca80 INFO basetransform gstbasetransform.c:1308:gst_base_transform_setcaps: reuse caps
0:00:05.687675583 114 0xe69ca80 INFO GST_EVENT gstevent.c:814:gst_event_new_caps: creating caps event video/x-raw(memory:NVMM), format=(string)NV12, width=(int)1920, height=(int)1080, multiview-mode=(string)mono, multiview-flags=(GstVideoMultiviewFlagsSet)0:ffffffff:/right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono, framerate=(fraction)30/1, batch-size=(int)1, num-surfaces-per-frame=(int)1
0:00:05.689394927 114 0xe69ca80 INFO GST_EVENT gstevent.c:814:gst_event_new_caps: creating caps event video/x-raw(memory:NVMM), width=(int)1920, height=(int)1080, multiview-mode=(string)mono, multiview-flags=(GstVideoMultiviewFlagsSet)0:ffffffff:/right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono, framerate=(fraction)30/1, batch-size=(int)1, num-surfaces-per-frame=(int)1, format=(string)RGBA
0:00:05.689592984 114 0xe69ca80 INFO basetransform gstbasetransform.c:1308:gst_base_transform_setcaps: reuse caps
0:00:05.690651177 114 0xe69ca80 INFO GST_EVENT gstevent.c:814:gst_event_new_caps: creating caps event video/x-raw(memory:NVMM), width=(int)1920, height=(int)1080, multiview-mode=(string)mono, multiview-flags=(GstVideoMultiviewFlagsSet)0:ffffffff:/right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono, framerate=(fraction)30/1, batch-size=(int)1, num-surfaces-per-frame=(int)1, format=(string)RGBA
0:00:05.691133088 114 0xe69ca80 INFO GST_EVENT gstevent.c:814:gst_event_new_caps: creating caps event video/x-raw(memory:EGLImage), width=(int)1920, height=(int)1080, multiview-mode=(string)mono, multiview-flags=(GstVideoMultiviewFlagsSet)0:ffffffff:/right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono, framerate=(fraction)30/1, batch-size=(int)1, num-surfaces-per-frame=(int)1, format=(string)RGBA
0:00:05.700021371 114 0xe69ccf0 INFO nveglglessink gsteglglessink.c:2685:gst_eglglessink_configure_caps: No window. Will attempt internal window creation
0:00:05.700114879 114 0xe69ccf0 INFO nveglglessink gsteglglessink.c:937:gst_eglglessink_create_window: Attempting internal window creation
0:00:05.761376980 114 0xe69ccf0 INFO egladaption gstegladaptation_egl.c:254:gst_egl_adaptation_update_surface_dimensions: Got surface of 1920x1080 pixels
0:00:05.764840917 114 0xe69ccf0 INFO egladaption gstegladaptation.c:611:gst_egl_adaptation_init_surface: Performing initial texture setup
0:00:05.765020637 114 0xe69ccf0 INFO nveglglessink gsteglglessink.c:2726:gst_eglglessink_configure_caps: Configured caps successfully
0:00:05.766856818 114 0xe69ca80 INFO task gsttask.c:457:gst_task_set_lock: setting stream lock 0xe978cd0 on task 0xe988cb0
0:00:05.766918005 114 0xe69ca80 INFO GST_PADS gstpad.c:6154:gst_pad_start_task:Stream-muxer:src created task 0xe988cb0
0:00:05.767697849 114 0xe69ca80 INFO nvstreammux gstnvstreammux.c:1129:gst_nvstreammux_sink_event: Conversion enabled for source 0
0:00:05.767993895 114 0xe69ca80 INFO v4l2 gstv4l2object.c:3152:gst_v4l2_object_setup_pool:nvv4l2-decoder:src accessing buffers via mode 2
0:00:05.768249683 114 0xe69ca80 INFO v4l2bufferpool gstv4l2bufferpool.c:825:gst_v4l2_buffer_pool_set_config:nvv4l2-decoder:pool:src increasing minimum buffers to 2
0:00:05.768286516 114 0xe69ca80 INFO v4l2bufferpool gstv4l2bufferpool.c:832:gst_v4l2_buffer_pool_set_config:nvv4l2-decoder:pool:src increasing minimum buffers to 9
0:00:05.768310037 114 0xe69ca80 INFO v4l2bufferpool gstv4l2bufferpool.c:838:gst_v4l2_buffer_pool_set_config:nvv4l2-decoder:pool:src reducing maximum buffers to 32
0:00:05.768328246 114 0xe69ca80 INFO v4l2bufferpool gstv4l2bufferpool.c:849:gst_v4l2_buffer_pool_set_config:nvv4l2-decoder:pool:src can’t allocate, setting maximum to minimum
0:00:05.768345207 114 0xe69ca80 INFO v4l2bufferpool gstv4l2bufferpool.c:854:gst_v4l2_buffer_pool_set_config:nvv4l2-decoder:pool:src adding needed video meta
0:00:05.768401914 114 0xe69e140 INFO GST_EVENT gstevent.c:895:gst_event_new_segment: creating segment event time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=99:99:99.999999999, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.000000000, duration 99:99:99.999999999
0:00:05.768482557 114 0xe69ca80 INFO v4l2bufferpool gstv4l2bufferpool.c:838:gst_v4l2_buffer_pool_set_config:nvv4l2-decoder:pool:src reducing maximum buffers to 32
0:00:05.768513055 114 0xe69ca80 INFO v4l2bufferpool gstv4l2bufferpool.c:849:gst_v4l2_buffer_pool_set_config:nvv4l2-decoder:pool:src can’t allocate, setting maximum to minimum
0:00:05.768784523 114 0xe69ca80 WARN v4l2videodec gstv4l2videodec.c:1673:gst_v4l2_video_dec_decide_allocation: Duration invalid, not setting latency
0:00:05.769386375 114 0xe69ca80 WARN v4l2bufferpool gstv4l2bufferpool.c:1065:gst_v4l2_buffer_pool_start:nvv4l2-decoder:pool:src Uncertain or not enough buffers, enabling copy threshold
0:00:05.770007460 114 0xe69ca80 INFO task gsttask.c:457:gst_task_set_lock: setting stream lock 0xe978a80 on task 0xe988dd0
0:00:05.770052646 114 0xe69ca80 INFO GST_PADS gstpad.c:6154:gst_pad_start_task:nvv4l2-decoder:src created task 0xe988dd0
0:00:05.772843463 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)7063680, maximum-bitrate=(uint)7063680, bitrate=(uint)5270448;
0:00:05.779154700 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)3431520, maximum-bitrate=(uint)7063680, bitrate=(uint)5103272;
0:00:05.783771521 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)2376240, maximum-bitrate=(uint)7063680, bitrate=(uint)4876020;
0:00:05.783796291 114 0xe69e2d0 WARN v4l2bufferpool gstv4l2bufferpool.c:1512:gst_v4l2_buffer_pool_dqbuf:nvv4l2-decoder:pool:src Driver should never set v4l2_buffer.field to ANY
0:00:05.784342428 114 0xe69e2d0 INFO videodecoder gstvideodecoder.c:3201:gst_video_decoder_clip_and_push_buf: First buffer since flush took 0:00:00.223462828 to produce
Frame Number=0 Number of Objects=5 Vehicle_count=3 Person_count=2
0:00:06.004193984 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)2376240, maximum-bitrate=(uint)7063680, bitrate=(uint)4689378;
0:00:06.062762295 114 0xe69ccf0 INFO nveglglessink gsteglglessink.c:980:gst_eglglessink_setup_vbo: VBO setup. have_vbo:0
0:00:06.067676251 114 0xe69cd40 INFO GST_STATES gstbin.c:3424:bin_handle_async_done: committing state from READY to PAUSED, old pending PLAYING
0:00:06.067735262 114 0xe69cd40 INFO GST_STATES gstbin.c:3453:bin_handle_async_done: continue state change, pending PLAYING
0:00:06.067771647 114 0xe69cd40 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed READY to PAUSED (PLAYING pending)
0:00:06.068300632 114 0xe69f590 INFO GST_STATES gstbin.c:3250:gst_bin_continue_func: continue state change PAUSED to PLAYING, final PLAYING
0:00:06.069076540 114 0xe69f590 INFO GST_EVENT gstevent.c:1388:gst_event_new_latency: creating latency event 0:00:00.000000000
0:00:06.069313223 114 0xe69f590 INFO bin gstbin.c:2783:gst_bin_do_latency_func: configured latency of 0:00:00.000000000
0:00:06.069446701 114 0xe69f590 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:06.069510256 114 0xe69f590 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PLAYING
0:00:06.069566098 114 0xe69f590 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:06.070085194 114 0xe69f590 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘nvvideo-renderer’ changed state to 4(PLAYING) successfully
0:00:06.071225663 114 0xe69f590 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:06.071280930 114 0xe69f590 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PLAYING
0:00:06.071319972 114 0xe69f590 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:06.071717398 114 0xe69f590 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘nvegl-transform’ changed state to 4(PLAYING) successfully
0:00:06.071775225 114 0xe69f590 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:06.071846108 114 0xe69f590 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PLAYING
0:00:06.071950305 114 0xe69f590 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:06.072231470 114 0xe69f590 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘onscreendisplay’ changed state to 4(PLAYING) successfully
0:00:06.072292689 114 0xe69f590 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:06.072340019 114 0xe69f590 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PLAYING
0:00:06.072370932 114 0xe69f590 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:06.072650657 114 0xe69f590 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘convertor’ changed state to 4(PLAYING) successfully
0:00:06.072707396 114 0xe69f590 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:06.072742565 114 0xe69f590 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PLAYING
0:00:06.072773415 114 0xe69f590 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:06.072843594 114 0xe69f590 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘secondary-nference faceclassifier’ changed state to 4(PLAYING) successfully
0:00:06.072892460 114 0xe69f590 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:06.072922222 114 0xe69f590 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PLAYING
0:00:06.072983025 114 0xe69f590 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:06.073077493 114 0xe69f590 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘primary-inference’ changed state to 4(PLAYING) successfully
0:00:06.073123191 114 0xe69f590 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:06.073295647 114 0xe69f590 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PLAYING
0:00:06.073338209 114 0xe69f590 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:06.073411908 114 0xe69f590 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘Stream-muxer’ changed state to 4(PLAYING) successfully
0:00:06.073459335 114 0xe69f590 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:06.073495528 114 0xe69f590 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PLAYING
0:00:06.073530858 114 0xe69f590 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:06.073581164 114 0xe69f590 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘nvv4l2-decoder’ changed state to 4(PLAYING) successfully
0:00:06.073625742 114 0xe69f590 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:06.073667120 114 0xe69f590 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PLAYING
0:00:06.073698066 114 0xe69f590 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:06.073746804 114 0xe69f590 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘h264-parser’ changed state to 4(PLAYING) successfully
0:00:06.073788790 114 0xe69f590 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:06.073821815 114 0xe69f590 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PLAYING
0:00:06.073854169 114 0xe69f590 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:06.073901947 114 0xe69f590 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘file-source’ changed state to 4(PLAYING) successfully
0:00:06.073960478 114 0xe69f590 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PLAYING
0:00:06.073992095 114 0xe69f590 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
Frame Number=1 Number of Objects=5 Vehicle_count=3 Person_count=2
0:00:06.088627109 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)2376240, maximum-bitrate=(uint)7063680, bitrate=(uint)4819457;
0:00:06.089269091 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)2376240, maximum-bitrate=(uint)7063680, bitrate=(uint)4707984;
0:00:06.089583857 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)2376240, maximum-bitrate=(uint)7063680, bitrate=(uint)4568250;
0:00:06.089877855 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)2337600, maximum-bitrate=(uint)7063680, bitrate=(uint)4437035;
0:00:06.090254928 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)2337600, maximum-bitrate=(uint)7063680, bitrate=(uint)4564560;
0:00:06.090928943 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)2262960, maximum-bitrate=(uint)7063680, bitrate=(uint)4379400;
Frame Number=2 Number of Objects=5 Vehicle_count=3 Person_count=2
0:00:06.114181188 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)2195040, maximum-bitrate=(uint)7063680, bitrate=(uint)4275382;
0:00:06.114711869 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)2165520, maximum-bitrate=(uint)7063680, bitrate=(uint)4179480;
Frame Number=3 Number of Objects=7 Vehicle_count=4 Person_count=3
0:00:06.147869500 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)1884960, maximum-bitrate=(uint)7063680, bitrate=(uint)4079718;
Frame Number=4 Number of Objects=6 Vehicle_count=4 Person_count=2
0:00:06.180385213 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)1809840, maximum-bitrate=(uint)7063680, bitrate=(uint)3985140;
0:00:06.181100254 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)1108080, maximum-bitrate=(uint)7063680, bitrate=(uint)3870057;
Frame Number=5 Number of Objects=5 Vehicle_count=3 Person_count=2
Frame Number=6 Number of Objects=5 Vehicle_count=3 Person_count=2
Frame Number=7 Number of Objects=6 Vehicle_count=4 Person_count=2
0:00:06.309122086 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)1108080, maximum-bitrate=(uint)7063680, bitrate=(uint)3758773;
Frame Number=8 Number of Objects=6 Vehicle_count=4 Person_count=2
Frame Number=9 Number of Objects=6 Vehicle_count=4 Person_count=2
Frame Number=10 Number of Objects=6 Vehicle_count=4 Person_count=2
0:00:06.383485369 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)836400, maximum-bitrate=(uint)7063680, bitrate=(uint)3654402;
Frame Number=11 Number of Objects=7 Vehicle_count=5 Person_count=2
Frame Number=12 Number of Objects=6 Vehicle_count=4 Person_count=2
Frame Number=13 Number of Objects=6 Vehicle_count=4 Person_count=2
0:00:06.508634843 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)836400, maximum-bitrate=(uint)7063680, bitrate=(uint)3559746;
Frame Number=14 Number of Objects=7 Vehicle_count=5 Person_count=2
Frame Number=15 Number of Objects=7 Vehicle_count=5 Person_count=2
0:00:06.566039005 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)836400, maximum-bitrate=(uint)8223360, bitrate=(uint)3715200;
Frame Number=16 Number of Objects=6 Vehicle_count=4 Person_count=2
Frame Number=17 Number of Objects=7 Vehicle_count=5 Person_count=2
Frame Number=18 Number of Objects=6 Vehicle_count=4 Person_count=2
0:00:06.662790908 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)836400, maximum-bitrate=(uint)8223360, bitrate=(uint)3559207;
0:00:06.663245937 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)836400, maximum-bitrate=(uint)8223360, bitrate=(uint)3476756;
0:00:06.663667365 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)836400, maximum-bitrate=(uint)8223360, bitrate=(uint)3613235;
Frame Number=19 Number of Objects=6 Vehicle_count=4 Person_count=2
0:00:06.711793977 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)836400, maximum-bitrate=(uint)8223360, bitrate=(uint)3536386;
Frame Number=20 Number of Objects=7 Vehicle_count=5 Person_count=2
0:00:06.749619344 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)497520, maximum-bitrate=(uint)8223360, bitrate=(uint)3451973;
Frame Number=21 Number of Objects=7 Vehicle_count=5 Person_count=2
Frame Number=22 Number of Objects=7 Vehicle_count=5 Person_count=2
Frame Number=23 Number of Objects=8 Vehicle_count=6 Person_count=2
Frame Number=24 Number of Objects=8 Vehicle_count=6 Person_count=2
Frame Number=25 Number of Objects=8 Vehicle_count=6 Person_count=2
Frame Number=26 Number of Objects=8 Vehicle_count=6 Person_count=2
Frame Number=27 Number of Objects=8 Vehicle_count=6 Person_count=2
0:00:07.032204522 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)430800, maximum-bitrate=(uint)8223360, bitrate=(uint)3322535;
Frame Number=28 Number of Objects=8 Vehicle_count=6 Person_count=2
Frame Number=29 Number of Objects=8 Vehicle_count=6 Person_count=2
0:00:07.118601418 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)430800, maximum-bitrate=(uint)8223360, bitrate=(uint)3250788;
0:00:07.119276745 114 0xe69ca80 INFO videodecoder gstvideodecoder.c:1330:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)“H.264\ (High\ Profile)”, minimum-bitrate=(uint)430800, maximum-bitrate=(uint)8223360, bitrate=(uint)3166788;
^C0:05:41.758234127 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PLAYING pending VOID_PENDING, desired next PAUSED
0:05:41.758356534 114 0xe562b20 INFO GST_STATES gstbin.c:2960:gst_bin_change_state_func: child ‘nvvideo-renderer’ is changing state asynchronously to PAUSED
0:05:41.758430682 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PLAYING pending VOID_PENDING, desired next PAUSED
0:05:41.758526624 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PAUSED
0:05:41.758629413 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:05:41.758782350 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘nvegl-transform’ changed state to 3(PAUSED) successfully
0:05:41.758840466 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PLAYING pending VOID_PENDING, desired next PAUSED
0:05:41.758876564 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PAUSED
0:05:41.758912566 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:05:41.758963577 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘onscreendisplay’ changed state to 3(PAUSED) successfully
0:05:41.759012699 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PLAYING pending VOID_PENDING, desired next PAUSED
0:05:41.759051550 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PAUSED
0:05:41.759110177 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:05:41.759158660 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘convertor’ changed state to 3(PAUSED) successfully
0:05:41.759240201 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PLAYING pending VOID_PENDING, desired next PAUSED
0:05:41.759272394 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PAUSED
0:05:41.759305708 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:05:41.759354447 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘secondary-nference faceclassifier’ changed state to 3(PAUSED) successfully
0:05:41.759443188 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PLAYING pending VOID_PENDING, desired next PAUSED
0:05:41.759473782 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PAUSED
0:05:41.759508024 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:05:41.759558427 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘primary-inference’ changed state to 3(PAUSED) successfully
0:05:41.759603613 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PLAYING pending VOID_PENDING, desired next PAUSED
0:05:41.759839691 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PAUSED
0:05:41.760001460 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:05:41.760060344 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘Stream-muxer’ changed state to 3(PAUSED) successfully
0:05:41.760116315 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PLAYING pending VOID_PENDING, desired next PAUSED
0:05:41.760153565 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PAUSED
0:05:41.760197312 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:05:41.760243650 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘nvv4l2-decoder’ changed state to 3(PAUSED) successfully
0:05:41.760288389 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PLAYING pending VOID_PENDING, desired next PAUSED
0:05:41.760363529 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PAUSED
0:05:41.760393419 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:05:41.760518354 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘h264-parser’ changed state to 3(PAUSED) successfully
0:05:41.760565653 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PLAYING pending VOID_PENDING, desired next PAUSED
0:05:41.760598487 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to PAUSED
0:05:41.760672283 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:05:41.760721502 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘file-source’ changed state to 3(PAUSED) successfully
0:05:41.760774785 114 0xe562b20 INFO GST_STATES gstelement.c:2981:gst_element_change_state: forcing commit state NULL <= READY
0:05:41.760804994 114 0xe562b20 INFO GST_STATES gstelement.c:2651:gst_element_continue_state: committing state from PLAYING to PAUSED, pending NULL, next READY
0:05:41.760829636 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PLAYING to PAUSED (NULL pending)
0:05:41.760866758 114 0xe562b20 INFO GST_STATES gstelement.c:2658:gst_element_continue_state: continue state change PAUSED to READY, final NULL
0:05:41.760971660 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PLAYING pending PAUSED, desired next READY
0:05:41.761223803 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to READY
0:05:41.761257660 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PLAYING to READY (VOID_PENDING pending)
0:05:41.761298175 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘nvvideo-renderer’ changed state to 2(READY) successfully
0:05:41.761339233 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PAUSED pending VOID_PENDING, desired next READY
0:05:41.780427946 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to READY
0:05:41.780532816 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:05:41.780627957 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘nvegl-transform’ changed state to 2(READY) successfully
0:05:41.780816800 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PAUSED pending VOID_PENDING, desired next READY
0:05:41.784733761 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to READY
0:05:41.784895115 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:05:41.784990704 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘onscreendisplay’ changed state to 2(READY) successfully
0:05:41.785065076 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PAUSED pending VOID_PENDING, desired next READY
0:05:41.790291265 114 0xe562b20 INFO GST_STATES gstelement.c:2676:gst_element_continue_state: completed state change to READY
0:05:41.790391686 114 0xe562b20 INFO GST_STATES gstelement.c:2579:_priv_gst_element_state_changed: notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:05:41.790489164 114 0xe562b20 INFO GST_STATES gstbin.c:2954:gst_bin_change_state_func: child ‘convertor’ changed state to 2(READY) successfully
0:05:41.790570257 114 0xe562b20 INFO GST_STATES gstbin.c:2506:gst_bin_element_set_state: current PAUSED pending VOID_PENDING, desired next READY
^C^C^C^C^C^C^C^C^C