Nvcompositor/appsink pipeline error

orin nx 16g,
L4T 36.2.0
JETPACK 6.0
This is the pipeline I built:


It has two V4L2 cameras, which are pushed to the RTSP appsrc by appsink1 after being merged together using nvcompositor.
appsink2 and appsink3 send the images to two mp4 appsrc pipelines.
The problem is that I have successfully initialised the pipeline, and it doesn’t say link error at GST_DEBUG=4, but at runtime it doesn’t receive any “new-sample” images from appsink1, as if the pipeline is blocked.
Here is how I debugged it:
1, cancelled the red box component, the video plays normally.
2、As long as gst_bin_add_many added appsink2/appsink3, no subsequent operation on appsink2/appsink3, will also be a problem.
3, do not use nvcompositor, just use a V4L2 camera, directly connected to appsink1,also connected to appsink2, like the top half of the picture, one side of the pipeline is normal.

0:00:00.000144964  9259 0xaaaade61f400 INFO                GST_INIT gst.c:592:init_pre: Initializing GStreamer Core Library version 1.20.3
0:00:00.000211110  9259 0xaaaade61f400 INFO                GST_INIT gst.c:593:init_pre: Using library installed in /usr/lib/aarch64-linux-gnu
0:00:00.000232903  9259 0xaaaade61f400 INFO                GST_INIT gst.c:611:init_pre: Linux ubuntu 5.15.122-tegra #1 SMP PREEMPT Thu Nov 30 10:30:37 PST 2023 aarch64
0:00:00.000626867  9259 0xaaaade61f400 INFO                GST_INIT gstmessage.c:129:_priv_gst_message_initialize: init messages
0:00:00.001248934  9259 0xaaaade61f400 INFO                GST_INIT gstcontext.c:86:_priv_gst_context_initialize: init contexts
0:00:00.001952571  9259 0xaaaade61f400 INFO      GST_PLUGIN_LOADING gstplugin.c:324:_priv_gst_plugin_initialize: registering 0 static plugins
0:00:00.002153793  9259 0xaaaade61f400 INFO      GST_PLUGIN_LOADING gstplugin.c:232:gst_plugin_register_static: registered static plugin "staticelements"
0:00:00.002184898  9259 0xaaaade61f400 INFO      GST_PLUGIN_LOADING gstplugin.c:234:gst_plugin_register_static: added static plugin "staticelements", result: 1
0:00:00.002267524  9259 0xaaaade61f400 INFO            GST_REGISTRY gstregistry.c:1827:ensure_current_registry: reading registry cache: /root/.cache/gstreamer-1.0/registry.aarch64.bin
0:00:00.036318789  9259 0xaaaade61f400 INFO            GST_REGISTRY gstregistrybinary.c:683:priv_gst_registry_binary_read_cache: loaded /root/.cache/gstreamer-1.0/registry.aarch64.bin in 0.033984 seconds
0:00:00.036559277  9259 0xaaaade61f400 INFO            GST_REGISTRY gstregistry.c:1693:scan_and_update_registry: Validating plugins from registry cache: /root/.cache/gstreamer-1.0/registry.aarch64.bin
0:00:00.041073396  9259 0xaaaade61f400 INFO            GST_REGISTRY gstregistry.c:1785:scan_and_update_registry: Registry cache has not changed
0:00:00.041109973  9259 0xaaaade61f400 INFO            GST_REGISTRY gstregistry.c:1862:ensure_current_registry: registry reading and updating done, result = 1
0:00:00.041135350  9259 0xaaaade61f400 INFO                GST_INIT gst.c:833:init_post: GLib runtime version: 2.72.4
0:00:00.041155575  9259 0xaaaade61f400 INFO                GST_INIT gst.c:835:init_post: GLib headers version: 2.72.1
0:00:00.041170967  9259 0xaaaade61f400 INFO                GST_INIT gst.c:837:init_post: initialized GStreamer successfully
start rtsp pipe init
double rtsp init 
width : 1920
height : 1080
framerate : 30
0:00:00.041791562  9259 0xaaaade61f400 INFO         rtspmountpoints rtsp-mount-points.c:360:gst_rtsp_mount_points_add_factory: adding media factory 0xaaaade8afae0 for path /test
stream ready at rtsp://IP:8554/test
main main init 
width : 1920
height : 1080
framerate : 30
0:00:00.043077329  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "pipeline"
0:00:00.053371718  9259 0xaaaade61f400 INFO      GST_PLUGIN_LOADING gstplugin.c:987:_priv_gst_plugin_load_file_for_registry: plugin "/usr/lib/aarch64-linux-gnu/gstreamer-1.0/libgstnvv4l2camerasrc.so" loaded
0:00:00.053664687  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "nvv4l2camerasrc"
0:00:00.053802995  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseSrc@0xaaaade8d9f10> adding pad 'src'
0:00:00.055456005  9259 0xaaaade61f400 INFO      GST_PLUGIN_LOADING gstplugin.c:987:_priv_gst_plugin_load_file_for_registry: plugin "/usr/lib/aarch64-linux-gnu/gstreamer-1.0/libgstcoreelements.so" loaded
0:00:00.055655147  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "capsfilter"
0:00:00.055717773  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade8e4180> adding pad 'sink'
0:00:00.055756110  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade8e4180> adding pad 'src'
0:00:00.083206504  9259 0xaaaade61f400 INFO      GST_PLUGIN_LOADING gstplugin.c:987:_priv_gst_plugin_load_file_for_registry: plugin "/usr/lib/aarch64-linux-gnu/gstreamer-1.0/libgstnvvidconv.so" loaded
0:00:00.083514065  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "nvvidconv"
0:00:00.083575315  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade965dd0> adding pad 'sink'
0:00:00.083607604  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade965dd0> adding pad 'src'
0:00:00.083647989  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "capsfilter"
0:00:00.083673526  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade8e44c0> adding pad 'sink'
0:00:00.083692631  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade8e44c0> adding pad 'src'
0:00:00.083721048  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "capsfilter"
0:00:00.083762841  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade8e4800> adding pad 'sink'
0:00:00.083785850  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade8e4800> adding pad 'src'
0:00:00.083899453  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "queue"
0:00:00.083954495  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstQueue@0xaaaade9680a0> adding pad 'sink'
0:00:00.083981663  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstQueue@0xaaaade9680a0> adding pad 'src'
0:00:00.084015840  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "queue"
0:00:00.084044577  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstQueue@0xaaaade9683a0> adding pad 'sink'
0:00:00.084088707  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstQueue@0xaaaade9683a0> adding pad 'src'
0:00:00.084114627  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "nvv4l2camerasrc"
0:00:00.084138404  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseSrc@0xaaaade96c870> adding pad 'src'
0:00:00.084160133  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "capsfilter"
0:00:00.084180005  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade8e4b40> adding pad 'sink'
0:00:00.084198150  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade8e4b40> adding pad 'src'
0:00:00.084217063  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "nvvidconv"
0:00:00.084239079  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade96d340> adding pad 'sink'
0:00:00.084256168  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade96d340> adding pad 'src'
0:00:00.084275240  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "capsfilter"
0:00:00.084297417  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade8e4e80> adding pad 'sink'
0:00:00.084315561  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade8e4e80> adding pad 'src'
0:00:00.084341098  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "capsfilter"
0:00:00.084363403  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade8e51c0> adding pad 'sink'
0:00:00.084381963  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade8e51c0> adding pad 'src'
0:00:00.085153923  9259 0xaaaade61f400 INFO      GST_PLUGIN_LOADING gstplugin.c:987:_priv_gst_plugin_load_file_for_registry: plugin "/usr/lib/aarch64-linux-gnu/gstreamer-1.0/libgstnvcompositor.so" loaded
0:00:00.085419755  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "nvcompositor"
0:00:00.085464460  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstAggregator@0xaaaade976a00> adding pad 'src'
0:00:00.085690675  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "capsfilter"
0:00:00.085733748  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade8e5500> adding pad 'sink'
0:00:00.085757525  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade8e5500> adding pad 'src'
0:00:00.085776757  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "nvvidconv"
0:00:00.085813719  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade978800> adding pad 'sink'
0:00:00.085834295  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade978800> adding pad 'src'
0:00:00.085854328  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "capsfilter"
0:00:00.085874840  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade8e5840> adding pad 'sink'
0:00:00.085893593  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade8e5840> adding pad 'src'
0:00:00.103301317  9259 0xaaaade61f400 INFO      GST_PLUGIN_LOADING gstplugin.c:987:_priv_gst_plugin_load_file_for_registry: plugin "/usr/lib/aarch64-linux-gnu/gstreamer-1.0/libgstnvvideo4linux2.so" loaded
0:00:00.103744178  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "nvv4l2h264enc"
0:00:00.103823284  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstVideoEncoder@0xaaaade985c50> adding pad 'sink'
0:00:00.103855221  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstVideoEncoder@0xaaaade985c50> adding pad 'src'
0:00:00.105092987  9259 0xaaaade61f400 INFO      GST_PLUGIN_LOADING gstplugin.c:987:_priv_gst_plugin_load_file_for_registry: plugin "/usr/lib/aarch64-linux-gnu/gstreamer-1.0/libgstvideoparsersbad.so" loaded
0:00:00.105433445  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "h264parse"
0:00:00.105491911  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseParse@0xaaaade989960> adding pad 'sink'
0:00:00.105526728  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseParse@0xaaaade989960> adding pad 'src'
0:00:00.105599818  9259 0xaaaade61f400 INFO               baseparse gstbaseparse.c:4046:gst_base_parse_set_pts_interpolation:<GstH264Parse@0xaaaade989960> PTS interpolation: no
0:00:00.105618186  9259 0xaaaade61f400 INFO               baseparse gstbaseparse.c:4064:gst_base_parse_set_infer_ts:<GstH264Parse@0xaaaade989960> TS inferring: no
0:00:00.106016566  9259 0xaaaade61f400 INFO      GST_PLUGIN_LOADING gstplugin.c:987:_priv_gst_plugin_load_file_for_registry: plugin "/usr/lib/aarch64-linux-gnu/gstreamer-1.0/libgstapp.so" loaded
0:00:00.106239869  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "appsink"
0:00:00.106285759  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseSink@0xaaaade990bd0> adding pad 'sink'
0:00:00.106408802  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "tee"
0:00:00.106462180  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstTee@0xaaaade8bb150> adding pad 'sink'
0:00:00.106563271  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "capsfilter"
0:00:00.106619753  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade8e5b80> adding pad 'sink'
0:00:00.106647273  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade8e5b80> adding pad 'src'
0:00:00.106671338  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "nvvidconv"
0:00:00.106711627  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade9920f0> adding pad 'sink'
0:00:00.106735052  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade9920f0> adding pad 'src'
0:00:00.106770541  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "capsfilter"
0:00:00.106794158  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade996130> adding pad 'sink'
0:00:00.106814478  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade996130> adding pad 'src'
0:00:00.106834319  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "nvv4l2h264enc"
0:00:00.106864528  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstVideoEncoder@0xaaaade994f10> adding pad 'sink'
0:00:00.106887665  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstVideoEncoder@0xaaaade994f10> adding pad 'src'
0:00:00.106913873  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "h264parse"
0:00:00.106952339  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseParse@0xaaaade99a260> adding pad 'sink'
0:00:00.106975667  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseParse@0xaaaade99a260> adding pad 'src'
0:00:00.107003444  9259 0xaaaade61f400 INFO               baseparse gstbaseparse.c:4046:gst_base_parse_set_pts_interpolation:<GstH264Parse@0xaaaade99a260> PTS interpolation: no
0:00:00.107016885  9259 0xaaaade61f400 INFO               baseparse gstbaseparse.c:4064:gst_base_parse_set_infer_ts:<GstH264Parse@0xaaaade99a260> TS inferring: no
0:00:00.107035733  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "appsink"
0:00:00.107071222  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseSink@0xaaaade99e020> adding pad 'sink'
0:00:00.107094679  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "tee"
0:00:00.107127480  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstTee@0xaaaade8bb2a0> adding pad 'sink'
0:00:00.107151641  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "capsfilter"
0:00:00.107174905  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade996470> adding pad 'sink'
0:00:00.107196026  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade996470> adding pad 'src'
0:00:00.107215451  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "nvvidconv"
0:00:00.107250460  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade99eb30> adding pad 'sink'
0:00:00.107277244  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade99eb30> adding pad 'src'
0:00:00.107297885  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "capsfilter"
0:00:00.107320286  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade9967b0> adding pad 'sink'
0:00:00.107340510  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseTransform@0xaaaade9967b0> adding pad 'src'
0:00:00.107358463  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "nvv4l2h264enc"
0:00:00.107396224  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstVideoEncoder@0xaaaade99f7b0> adding pad 'sink'
0:00:00.107419041  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstVideoEncoder@0xaaaade99f7b0> adding pad 'src'
0:00:00.107450274  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "h264parse"
0:00:00.107475970  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseParse@0xaaaade9a2960> adding pad 'sink'
0:00:00.107496867  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseParse@0xaaaade9a2960> adding pad 'src'
0:00:00.107532516  9259 0xaaaade61f400 INFO               baseparse gstbaseparse.c:4046:gst_base_parse_set_pts_interpolation:<GstH264Parse@0xaaaade9a2960> PTS interpolation: no
0:00:00.107546949  9259 0xaaaade61f400 INFO               baseparse gstbaseparse.c:4064:gst_base_parse_set_infer_ts:<GstH264Parse@0xaaaade9a2960> TS inferring: no
0:00:00.107566597  9259 0xaaaade61f400 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:489:gst_element_factory_create_with_properties: creating element "appsink"
0:00:00.107596198  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<GstBaseSink@0xaaaade9a5f30> adding pad 'sink'
0:00:00.107652136  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.107691561  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.107719274  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.107746155  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.107769707  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.107791596  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.107813325  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.107834093  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.107863182  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.107884943  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.107907471  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.107929200  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.108290587  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstutils.c:1816:gst_element_link_pads_full: trying to link element main_src1:(any) to element main_caps1:(any)
0:00:00.108319420  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1079:gst_pad_check_link: trying to link main_src1:src and main_caps1:sink
0:00:00.108363069  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_caps1:src> pad has no peer
0:00:00.108412799  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1632:prepare_link_maybe_ghosting: main_src1 and main_caps1 in same bin, no need for ghost pads
0:00:00.108446272  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2382:gst_pad_link_prepare: trying to link main_src1:src and main_caps1:sink
0:00:00.108466304  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_caps1:src> pad has no peer
0:00:00.108491329  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2590:gst_pad_link_full: linked main_src1:src and main_caps1:sink, successful
0:00:00.108502881  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.108516546  9259 0xaaaade61f400 INFO               GST_EVENT gstpad.c:5946:gst_pad_send_event_unchecked:<main_src1:src> Received event on flushing pad. Discarding
0:00:00.108542818  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstutils.c:1816:gst_element_link_pads_full: trying to link element main_caps1:(any) to element main_conv1:(any)
0:00:00.108560035  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1079:gst_pad_check_link: trying to link main_caps1:src and main_conv1:sink
0:00:00.108591428  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_conv1:src> pad has no peer
0:00:00.108726568  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1632:prepare_link_maybe_ghosting: main_caps1 and main_conv1 in same bin, no need for ghost pads
0:00:00.108752553  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2382:gst_pad_link_prepare: trying to link main_caps1:src and main_conv1:sink
0:00:00.108780810  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_conv1:src> pad has no peer
0:00:00.108876237  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2590:gst_pad_link_full: linked main_caps1:src and main_conv1:sink, successful
0:00:00.108891629  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.108904621  9259 0xaaaade61f400 INFO               GST_EVENT gstpad.c:5946:gst_pad_send_event_unchecked:<main_caps1:src> Received event on flushing pad. Discarding
0:00:00.108924750  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstutils.c:1816:gst_element_link_pads_full: trying to link element main_conv1:(any) to element main_caps2:(any)
0:00:00.108941934  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1079:gst_pad_check_link: trying to link main_conv1:src and main_caps2:sink
0:00:00.109021393  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_caps2:src> pad has no peer
0:00:00.109052146  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1632:prepare_link_maybe_ghosting: main_conv1 and main_caps2 in same bin, no need for ghost pads
0:00:00.109072338  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2382:gst_pad_link_prepare: trying to link main_conv1:src and main_caps2:sink
0:00:00.109143349  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_caps2:src> pad has no peer
0:00:00.109173941  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2590:gst_pad_link_full: linked main_conv1:src and main_caps2:sink, successful
0:00:00.109193110  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.109210935  9259 0xaaaade61f400 INFO               GST_EVENT gstpad.c:5946:gst_pad_send_event_unchecked:<main_conv1:src> Received event on flushing pad. Discarding
0:00:00.109233367  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstutils.c:1816:gst_element_link_pads_full: trying to link element main_caps2:(any) to element main_sony_mp4_tee:(any)
0:00:00.109255704  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1079:gst_pad_check_link: trying to link main_caps2:src and main_sony_mp4_tee:sink
0:00:00.109352539  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1632:prepare_link_maybe_ghosting: main_caps2 and main_sony_mp4_tee in same bin, no need for ghost pads
0:00:00.109375356  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2382:gst_pad_link_prepare: trying to link main_caps2:src and main_sony_mp4_tee:sink
0:00:00.109464062  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2590:gst_pad_link_full: linked main_caps2:src and main_sony_mp4_tee:sink, successful
0:00:00.109478815  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.109494015  9259 0xaaaade61f400 INFO               GST_EVENT gstpad.c:5946:gst_pad_send_event_unchecked:<main_caps2:src> Received event on flushing pad. Discarding
0:00:00.109515872  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstutils.c:1816:gst_element_link_pads_full: trying to link element main_sony_mp4_tee:(any) to element main_caps7:(any)
0:00:00.109536160  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_caps7:src> pad has no peer
0:00:00.109626467  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<main_sony_mp4_tee> adding pad 'src_0'
0:00:00.109660612  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1632:prepare_link_maybe_ghosting: main_sony_mp4_tee and main_caps7 in same bin, no need for ghost pads
0:00:00.109684101  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2382:gst_pad_link_prepare: trying to link main_sony_mp4_tee:src_0 and main_caps7:sink
0:00:00.109779400  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_caps7:src> pad has no peer
0:00:00.109809705  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2590:gst_pad_link_full: linked main_sony_mp4_tee:src_0 and main_caps7:sink, successful
0:00:00.109823689  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.109837353  9259 0xaaaade61f400 INFO               GST_EVENT gstpad.c:5946:gst_pad_send_event_unchecked:<main_sony_mp4_tee:src_0> Received event on flushing pad. Discarding
0:00:00.109860554  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstutils.c:1816:gst_element_link_pads_full: trying to link element main_src2:(any) to element main_caps3:(any)
0:00:00.109878891  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1079:gst_pad_check_link: trying to link main_src2:src and main_caps3:sink
0:00:00.109898091  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_caps3:src> pad has no peer
0:00:00.109918764  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1632:prepare_link_maybe_ghosting: main_src2 and main_caps3 in same bin, no need for ghost pads
0:00:00.109938220  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2382:gst_pad_link_prepare: trying to link main_src2:src and main_caps3:sink
0:00:00.109955757  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_caps3:src> pad has no peer
0:00:00.109976846  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2590:gst_pad_link_full: linked main_src2:src and main_caps3:sink, successful
0:00:00.109989518  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.110001934  9259 0xaaaade61f400 INFO               GST_EVENT gstpad.c:5946:gst_pad_send_event_unchecked:<main_src2:src> Received event on flushing pad. Discarding
0:00:00.110021103  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstutils.c:1816:gst_element_link_pads_full: trying to link element main_caps3:(any) to element main_conv2:(any)
0:00:00.110038927  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1079:gst_pad_check_link: trying to link main_caps3:src and main_conv2:sink
0:00:00.110064912  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_conv2:src> pad has no peer
0:00:00.110147411  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1632:prepare_link_maybe_ghosting: main_caps3 and main_conv2 in same bin, no need for ghost pads
0:00:00.110170451  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2382:gst_pad_link_prepare: trying to link main_caps3:src and main_conv2:sink
0:00:00.110197396  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_conv2:src> pad has no peer
0:00:00.110276279  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2590:gst_pad_link_full: linked main_caps3:src and main_conv2:sink, successful
0:00:00.110291095  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.110303799  9259 0xaaaade61f400 INFO               GST_EVENT gstpad.c:5946:gst_pad_send_event_unchecked:<main_caps3:src> Received event on flushing pad. Discarding
0:00:00.110324504  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstutils.c:1816:gst_element_link_pads_full: trying to link element main_conv2:(any) to element main_caps4:(any)
0:00:00.110342105  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1079:gst_pad_check_link: trying to link main_conv2:src and main_caps4:sink
0:00:00.110410523  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_caps4:src> pad has no peer
0:00:00.110440604  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1632:prepare_link_maybe_ghosting: main_conv2 and main_caps4 in same bin, no need for ghost pads
0:00:00.110459068  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2382:gst_pad_link_prepare: trying to link main_conv2:src and main_caps4:sink
0:00:00.110558943  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_caps4:src> pad has no peer
0:00:00.110596800  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2590:gst_pad_link_full: linked main_conv2:src and main_caps4:sink, successful
0:00:00.110612737  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.110625953  9259 0xaaaade61f400 INFO               GST_EVENT gstpad.c:5946:gst_pad_send_event_unchecked:<main_conv2:src> Received event on flushing pad. Discarding
0:00:00.110647170  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstutils.c:1816:gst_element_link_pads_full: trying to link element main_caps4:(any) to element main_iray_mp4_tee:(any)
0:00:00.110665858  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1079:gst_pad_check_link: trying to link main_caps4:src and main_iray_mp4_tee:sink
0:00:00.110746917  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1632:prepare_link_maybe_ghosting: main_caps4 and main_iray_mp4_tee in same bin, no need for ghost pads
0:00:00.110769701  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2382:gst_pad_link_prepare: trying to link main_caps4:src and main_iray_mp4_tee:sink
0:00:00.110852040  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2590:gst_pad_link_full: linked main_caps4:src and main_iray_mp4_tee:sink, successful
0:00:00.110867144  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.110880041  9259 0xaaaade61f400 INFO               GST_EVENT gstpad.c:5946:gst_pad_send_event_unchecked:<main_caps4:src> Received event on flushing pad. Discarding
0:00:00.110899689  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstutils.c:1816:gst_element_link_pads_full: trying to link element main_iray_mp4_tee:(any) to element main_caps8:(any)
0:00:00.110918282  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_caps8:src> pad has no peer
0:00:00.110969707  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<main_iray_mp4_tee> adding pad 'src_0'
0:00:00.110996588  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1632:prepare_link_maybe_ghosting: main_iray_mp4_tee and main_caps8 in same bin, no need for ghost pads
0:00:00.111017421  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2382:gst_pad_link_prepare: trying to link main_iray_mp4_tee:src_0 and main_caps8:sink
0:00:00.111365399  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_caps8:src> pad has no peer
0:00:00.111396728  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2590:gst_pad_link_full: linked main_iray_mp4_tee:src_0 and main_caps8:sink, successful
0:00:00.111411737  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.111424089  9259 0xaaaade61f400 INFO               GST_EVENT gstpad.c:5946:gst_pad_send_event_unchecked:<main_iray_mp4_tee:src_0> Received event on flushing pad. Discarding
0:00:00.111445882  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstutils.c:1816:gst_element_link_pads_full: trying to link element main_compositor1:(any) to element main_caps5:(any)
0:00:00.111464506  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1079:gst_pad_check_link: trying to link main_compositor1:src and main_caps5:sink
0:00:00.111486075  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_caps5:src> pad has no peer
0:00:00.111508444  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1632:prepare_link_maybe_ghosting: main_compositor1 and main_caps5 in same bin, no need for ghost pads
0:00:00.111527004  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2382:gst_pad_link_prepare: trying to link main_compositor1:src and main_caps5:sink
0:00:00.111544125  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_caps5:src> pad has no peer
0:00:00.111564157  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2590:gst_pad_link_full: linked main_compositor1:src and main_caps5:sink, successful
0:00:00.111573438  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.111584862  9259 0xaaaade61f400 INFO               GST_EVENT gstpad.c:5946:gst_pad_send_event_unchecked:<main_compositor1:src> Received event on flushing pad. Discarding
0:00:00.111637696  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstutils.c:1816:gst_element_link_pads_full: trying to link element main_caps5:(any) to element main_conv3:(any)
0:00:00.111657792  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1079:gst_pad_check_link: trying to link main_caps5:src and main_conv3:sink
0:00:00.111684001  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_conv3:src> pad has no peer
0:00:00.111765507  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1632:prepare_link_maybe_ghosting: main_caps5 and main_conv3 in same bin, no need for ghost pads
0:00:00.111786212  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2382:gst_pad_link_prepare: trying to link main_caps5:src and main_conv3:sink
0:00:00.111810757  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_conv3:src> pad has no peer
0:00:00.111885607  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2590:gst_pad_link_full: linked main_caps5:src and main_conv3:sink, successful
0:00:00.111897959  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.111910568  9259 0xaaaade61f400 INFO               GST_EVENT gstpad.c:5946:gst_pad_send_event_unchecked:<main_caps5:src> Received event on flushing pad. Discarding
0:00:00.111930216  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstutils.c:1816:gst_element_link_pads_full: trying to link element main_conv3:(any) to element main_caps6:(any)
0:00:00.111946953  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1079:gst_pad_check_link: trying to link main_conv3:src and main_caps6:sink
0:00:00.112002603  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_caps6:src> pad has no peer
0:00:00.112026699  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1632:prepare_link_maybe_ghosting: main_conv3 and main_caps6 in same bin, no need for ghost pads
0:00:00.112043788  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2382:gst_pad_link_prepare: trying to link main_conv3:src and main_caps6:sink
0:00:00.112096461  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_caps6:src> pad has no peer
0:00:00.112120750  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2590:gst_pad_link_full: linked main_conv3:src and main_caps6:sink, successful
0:00:00.112131598  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.112143695  9259 0xaaaade61f400 INFO               GST_EVENT gstpad.c:5946:gst_pad_send_event_unchecked:<main_conv3:src> Received event on flushing pad. Discarding
0:00:00.112162383  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstutils.c:1816:gst_element_link_pads_full: trying to link element main_caps6:(any) to element main_enc1:(any)
0:00:00.112177680  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1079:gst_pad_check_link: trying to link main_caps6:src and main_enc1:sink
0:00:00.112277043  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_enc1:src> pad has no peer
0:00:00.112340405  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1632:prepare_link_maybe_ghosting: main_caps6 and main_enc1 in same bin, no need for ghost pads
0:00:00.112360693  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2382:gst_pad_link_prepare: trying to link main_caps6:src and main_enc1:sink
0:00:00.112424599  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_enc1:src> pad has no peer
0:00:00.112456568  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2590:gst_pad_link_full: linked main_caps6:src and main_enc1:sink, successful
0:00:00.112469881  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.112478521  9259 0xaaaade61f400 INFO               GST_EVENT gstpad.c:5946:gst_pad_send_event_unchecked:<main_caps6:src> Received event on flushing pad. Discarding
0:00:00.112499482  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstutils.c:1816:gst_element_link_pads_full: trying to link element main_enc1:(any) to element main_parse1:(any)
0:00:00.112514074  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1079:gst_pad_check_link: trying to link main_enc1:src and main_parse1:sink
0:00:00.112538587  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_parse1:src> pad has no peer
0:00:00.112558139  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1632:prepare_link_maybe_ghosting: main_enc1 and main_parse1 in same bin, no need for ghost pads
0:00:00.112574972  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2382:gst_pad_link_prepare: trying to link main_enc1:src and main_parse1:sink
0:00:00.112592060  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_parse1:src> pad has no peer
0:00:00.112612733  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2590:gst_pad_link_full: linked main_enc1:src and main_parse1:sink, successful
0:00:00.112623773  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.112634910  9259 0xaaaade61f400 INFO               GST_EVENT gstpad.c:5946:gst_pad_send_event_unchecked:<main_enc1:src> Received event on flushing pad. Discarding
0:00:00.112652926  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstutils.c:1816:gst_element_link_pads_full: trying to link element main_parse1:(any) to element main_sink1:(any)
0:00:00.112666751  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1079:gst_pad_check_link: trying to link main_parse1:src and main_sink1:sink
0:00:00.112691807  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1632:prepare_link_maybe_ghosting: main_parse1 and main_sink1 in same bin, no need for ghost pads
0:00:00.112710112  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2382:gst_pad_link_prepare: trying to link main_parse1:src and main_sink1:sink
0:00:00.112729632  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2590:gst_pad_link_full: linked main_parse1:src and main_sink1:sink, successful
0:00:00.112741089  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.112752417  9259 0xaaaade61f400 INFO               GST_EVENT gstpad.c:5946:gst_pad_send_event_unchecked:<main_parse1:src> Received event on flushing pad. Discarding
0:00:00.112770306  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstutils.c:1816:gst_element_link_pads_full: trying to link element main_sony_mp4_caps2:(any) to element main_sony_mp4_conv1:(any)
0:00:00.112786754  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1079:gst_pad_check_link: trying to link main_sony_mp4_caps2:src and main_sony_mp4_conv1:sink
0:00:00.112804515  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_sony_mp4_caps2:sink> pad has no peer
0:00:00.112821219  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_sony_mp4_conv1:src> pad has no peer
0:00:00.112900934  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1632:prepare_link_maybe_ghosting: main_sony_mp4_caps2 and main_sony_mp4_conv1 in same bin, no need for ghost pads
0:00:00.112920966  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2382:gst_pad_link_prepare: trying to link main_sony_mp4_caps2:src and main_sony_mp4_conv1:sink
0:00:00.112937319  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_sony_mp4_caps2:sink> pad has no peer
0:00:00.112953703  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_sony_mp4_conv1:src> pad has no peer
0:00:00.113025577  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2590:gst_pad_link_full: linked main_sony_mp4_caps2:src and main_sony_mp4_conv1:sink, successful
0:00:00.113037482  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.113048106  9259 0xaaaade61f400 INFO               GST_EVENT gstpad.c:5946:gst_pad_send_event_unchecked:<main_sony_mp4_caps2:src> Received event on flushing pad. Discarding
0:00:00.113065195  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstutils.c:1816:gst_element_link_pads_full: trying to link element main_sony_mp4_conv1:(any) to element main_sony_mp4_caps1:(any)
0:00:00.113080331  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1079:gst_pad_check_link: trying to link main_sony_mp4_conv1:src and main_sony_mp4_caps1:sink
0:00:00.113093483  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_sony_mp4_caps2:sink> pad has no peer
0:00:00.113139469  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_sony_mp4_caps1:src> pad has no peer
0:00:00.113428245  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1632:prepare_link_maybe_ghosting: main_sony_mp4_conv1 and main_sony_mp4_caps1 in same bin, no need for ghost pads
0:00:00.113458262  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2382:gst_pad_link_prepare: trying to link main_sony_mp4_conv1:src and main_sony_mp4_caps1:sink
0:00:00.113478295  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_sony_mp4_caps2:sink> pad has no peer
0:00:00.113538777  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_sony_mp4_caps1:src> pad has no peer
0:00:00.113569114  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2590:gst_pad_link_full: linked main_sony_mp4_conv1:src and main_sony_mp4_caps1:sink, successful
0:00:00.113582938  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.113595483  9259 0xaaaade61f400 INFO               GST_EVENT gstpad.c:5946:gst_pad_send_event_unchecked:<main_sony_mp4_conv1:src> Received event on flushing pad. Discarding
0:00:00.113616571  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstutils.c:1816:gst_element_link_pads_full: trying to link element main_sony_mp4_caps1:(any) to element main_sony_mp4_enc1:(any)
0:00:00.113637212  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1079:gst_pad_check_link: trying to link main_sony_mp4_caps1:src and main_sony_mp4_enc1:sink
0:00:00.113690557  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_sony_mp4_caps2:sink> pad has no peer
0:00:00.113765344  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_sony_mp4_enc1:src> pad has no peer
0:00:00.113806177  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1632:prepare_link_maybe_ghosting: main_sony_mp4_caps1 and main_sony_mp4_enc1 in same bin, no need for ghost pads
0:00:00.113833794  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2382:gst_pad_link_prepare: trying to link main_sony_mp4_caps1:src and main_sony_mp4_enc1:sink
0:00:00.113852578  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_sony_mp4_caps2:sink> pad has no peer
0:00:00.113917572  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_sony_mp4_enc1:src> pad has no peer
0:00:00.113954693  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2590:gst_pad_link_full: linked main_sony_mp4_caps1:src and main_sony_mp4_enc1:sink, successful
0:00:00.113969958  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.113982054  9259 0xaaaade61f400 INFO               GST_EVENT gstpad.c:5946:gst_pad_send_event_unchecked:<main_sony_mp4_caps1:src> Received event on flushing pad. Discarding
0:00:00.114004615  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstutils.c:1816:gst_element_link_pads_full: trying to link element main_sony_mp4_enc1:(any) to element main_sony_mp4_parse1:(any)
0:00:00.114026247  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1079:gst_pad_check_link: trying to link main_sony_mp4_enc1:src and main_sony_mp4_parse1:sink
0:00:00.114047560  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_sony_mp4_parse1:src> pad has no peer
0:00:00.114068713  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1632:prepare_link_maybe_ghosting: main_sony_mp4_enc1 and main_sony_mp4_parse1 in same bin, no need for ghost pads
0:00:00.114089513  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2382:gst_pad_link_prepare: trying to link main_sony_mp4_enc1:src and main_sony_mp4_parse1:sink
0:00:00.114107562  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:4357:gst_pad_peer_query:<main_sony_mp4_parse1:src> pad has no peer
0:00:00.114127915  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2590:gst_pad_link_full: linked main_sony_mp4_enc1:src and main_sony_mp4_parse1:sink, successful
0:00:00.114140171  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.114156107  9259 0xaaaade61f400 INFO               GST_EVENT gstpad.c:5946:gst_pad_send_event_unchecked:<main_sony_mp4_enc1:src> Received event on flushing pad. Discarding
0:00:00.114176812  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstutils.c:1816:gst_element_link_pads_full: trying to link element main_sony_mp4_parse1:(any) to element main_sony_mp4_sink1:(any)
0:00:00.114195437  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1079:gst_pad_check_link: trying to link main_sony_mp4_parse1:src and main_sony_mp4_sink1:sink
0:00:00.114219725  9259 0xaaaade61f400 INFO                GST_PADS gstutils.c:1632:prepare_link_maybe_ghosting: main_sony_mp4_parse1 and main_sony_mp4_sink1 in same bin, no need for ghost pads
0:00:00.114239246  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2382:gst_pad_link_prepare: trying to link main_sony_mp4_parse1:src and main_sony_mp4_sink1:sink
0:00:00.114260143  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2590:gst_pad_link_full: linked main_sony_mp4_parse1:src and main_sony_mp4_sink1:sink, successful
0:00:00.114272111  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.114284271  9259 0xaaaade61f400 INFO               GST_EVENT gstpad.c:5946:gst_pad_send_event_unchecked:<main_sony_mp4_parse1:src> Received event on flushing pad. Discarding
0:00:00.114440084  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<main_compositor1> adding pad 'sink_0'
0:00:00.114533271  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<main_compositor1> adding pad 'sink_1'
0:00:00.114590296  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:1016:gst_element_get_static_pad: found pad main_caps7:src
0:00:00.114611801  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:1016:gst_element_get_static_pad: found pad main_caps8:src
0:00:00.114650298  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:759:gst_element_add_pad:<main_sony_mp4_tee> adding pad 'src_1'
0:00:00.114673883  9259 0xaaaade61f400 INFO        GST_ELEMENT_PADS gstelement.c:1016:gst_element_get_static_pad: found pad main_sony_mp4_caps2:sink
0:00:00.114747325  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2382:gst_pad_link_prepare: trying to link main_sony_mp4_tee:src_1 and main_sony_mp4_caps2:sink
0:00:00.114963428  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2590:gst_pad_link_full: linked main_sony_mp4_tee:src_1 and main_sony_mp4_caps2:sink, successful
0:00:00.114988612  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.115030054  9259 0xaaaade61f400 INFO               GST_EVENT gstpad.c:5946:gst_pad_send_event_unchecked:<main_sony_mp4_tee:src_1> Received event on flushing pad. Discarding
0:00:00.115063975  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2382:gst_pad_link_prepare: trying to link main_caps7:src and main_compositor1:sink_0
0:00:00.115393009  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2590:gst_pad_link_full: linked main_caps7:src and main_compositor1:sink_0, successful
0:00:00.115420689  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.115441458  9259 0xaaaade61f400 INFO               GST_EVENT gstpad.c:5946:gst_pad_send_event_unchecked:<main_caps7:src> Received event on flushing pad. Discarding
0:00:00.115473459  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2382:gst_pad_link_prepare: trying to link main_caps8:src and main_compositor1:sink_1
0:00:00.115758140  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:2590:gst_pad_link_full: linked main_caps8:src and main_compositor1:sink_1, successful
0:00:00.115779516  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1660:gst_event_new_reconfigure: creating reconfigure event
0:00:00.115794685  9259 0xaaaade61f400 INFO               GST_EVENT gstpad.c:5946:gst_pad_send_event_unchecked:<main_caps8:src> Received event on flushing pad. Discarding
main link ok
0:00:00.116222506  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_sony_mp4_sink1> current NULL pending VOID_PENDING, desired next READY
0:00:00.116293804  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_sony_mp4_sink1> completed state change to READY
0:00:00.116330701  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_sony_mp4_sink1> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.116422608  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_sony_mp4_sink1' changed state to 2(READY) successfully
0:00:00.116450768  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_sink1> current NULL pending VOID_PENDING, desired next READY
0:00:00.116471825  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_sink1> completed state change to READY
0:00:00.116488146  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_sink1> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.116511410  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_sink1' changed state to 2(READY) successfully
0:00:00.116530195  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_sony_mp4_parse1> current NULL pending VOID_PENDING, desired next READY
0:00:00.116548083  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_sony_mp4_parse1> completed state change to READY
0:00:00.116562964  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_sony_mp4_parse1> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.116585300  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_sony_mp4_parse1' changed state to 2(READY) successfully
0:00:00.116605045  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_parse1> current NULL pending VOID_PENDING, desired next READY
0:00:00.116621430  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_parse1> completed state change to READY
0:00:00.116634422  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_parse1> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.116654359  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_parse1' changed state to 2(READY) successfully
0:00:00.116672407  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_sony_mp4_enc1> current NULL pending VOID_PENDING, desired next READY
Opening in BLOCKING MODE 
0:00:00.176261048  9259 0xaaaade61f400 INFO                    v4l2 v4l2_calls.c:679:gst_v4l2_open:<main_sony_mp4_enc1:sink> Opened device '' (/dev/v4l2-nvenc) successfully
0:00:00.176302937  9259 0xaaaade61f400 INFO                    v4l2 v4l2_calls.c:780:gst_v4l2_dup:<main_sony_mp4_enc1:src> Cloned device '' (/dev/v4l2-nvenc) successfully
0:00:00.177084945  9259 0xaaaade61f400 INFO                    v4l2 gstv4l2object.c:1302:gst_v4l2_object_fill_format_list:<main_sony_mp4_enc1:sink> got 6 format(s):
0:00:00.177108466  9259 0xaaaade61f400 INFO                    v4l2 gstv4l2object.c:1306:gst_v4l2_object_fill_format_list:<main_sony_mp4_enc1:sink>   YM12
0:00:00.177122898  9259 0xaaaade61f400 INFO                    v4l2 gstv4l2object.c:1306:gst_v4l2_object_fill_format_list:<main_sony_mp4_enc1:sink>   PM10
0:00:00.177134386  9259 0xaaaade61f400 INFO                    v4l2 gstv4l2object.c:1306:gst_v4l2_object_fill_format_list:<main_sony_mp4_enc1:sink>   NM24
0:00:00.177145587  9259 0xaaaade61f400 INFO                    v4l2 gstv4l2object.c:1306:gst_v4l2_object_fill_format_list:<main_sony_mp4_enc1:sink>   NM12
0:00:00.177155859  9259 0xaaaade61f400 INFO                    v4l2 gstv4l2object.c:1306:gst_v4l2_object_fill_format_list:<main_sony_mp4_enc1:sink>   NV10
0:00:00.177164307  9259 0xaaaade61f400 INFO                    v4l2 gstv4l2object.c:1306:gst_v4l2_object_fill_format_list:<main_sony_mp4_enc1:sink>   YM24
0:00:00.177293527  9259 0xaaaade61f400 INFO                    v4l2 gstv4l2object.c:4692:gst_v4l2_object_probe_caps:<main_sony_mp4_enc1:sink> probed caps: video/x-raw, format=(string)I420, width=(int)[ 48, 4096 ], height=(int)[ 48, 4096 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-raw, format=(string)P010_10LE, width=(int)[ 48, 4096 ], height=(int)[ 48, 4096 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-raw, format=(string)NV24, width=(int)[ 48, 4096 ], height=(int)[ 48, 4096 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-raw, format=(string)NV12, width=(int)[ 48, 4096 ], height=(int)[ 48, 4096 ], framerate=(fraction)[ 0/1, 2147483647/1 ]
0:00:00.177319576  9259 0xaaaade61f400 INFO                    v4l2 gstv4l2object.c:1302:gst_v4l2_object_fill_format_list:<main_sony_mp4_enc1:src> got 3 format(s):
0:00:00.177333400  9259 0xaaaade61f400 INFO                    v4l2 gstv4l2object.c:1306:gst_v4l2_object_fill_format_list:<main_sony_mp4_enc1:src>   AV10
0:00:00.177344857  9259 0xaaaade61f400 INFO                    v4l2 gstv4l2object.c:1306:gst_v4l2_object_fill_format_list:<main_sony_mp4_enc1:src>   H265
0:00:00.177354265  9259 0xaaaade61f400 INFO                    v4l2 gstv4l2object.c:1306:gst_v4l2_object_fill_format_list:<main_sony_mp4_enc1:src>   H264
0:00:00.177399674  9259 0xaaaade61f400 WARN                    v4l2 gstv4l2object.c:4634:gst_v4l2_object_probe_caps:<main_sony_mp4_enc1:src> Failed to probe pixel aspect ratio with VIDIOC_CROPCAP: Unknown error -1
0:00:00.177453052  9259 0xaaaade61f400 INFO                    v4l2 gstv4l2object.c:4692:gst_v4l2_object_probe_caps:<main_sony_mp4_enc1:src> probed caps: video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, width=(int)[ 48, 4096 ], height=(int)[ 48, 4096 ], framerate=(fraction)[ 0/1, 2147483647/1 ]
0:00:00.177476285  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_sony_mp4_enc1> completed state change to READY
0:00:00.177492989  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_sony_mp4_enc1> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.177531870  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_sony_mp4_enc1' changed state to 2(READY) successfully
0:00:00.177562879  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_enc1> current NULL pending VOID_PENDING, desired next READY
Opening in BLOCKING MODE 
0:00:00.213668894  9259 0xaaaade61f400 INFO                    v4l2 v4l2_calls.c:679:gst_v4l2_open:<main_enc1:sink> Opened device '' (/dev/v4l2-nvenc) successfully
0:00:00.213708863  9259 0xaaaade61f400 INFO                    v4l2 v4l2_calls.c:780:gst_v4l2_dup:<main_enc1:src> Cloned device '' (/dev/v4l2-nvenc) successfully
0:00:00.213741696  9259 0xaaaade61f400 INFO                    v4l2 gstv4l2object.c:1302:gst_v4l2_object_fill_format_list:<main_enc1:sink> got 6 format(s):
0:00:00.213762592  9259 0xaaaade61f400 INFO                    v4l2 gstv4l2object.c:1306:gst_v4l2_object_fill_format_list:<main_enc1:sink>   YM12
0:00:00.213774561  9259 0xaaaade61f400 INFO                    v4l2 gstv4l2object.c:1306:gst_v4l2_object_fill_format_list:<main_enc1:sink>   PM10
0:00:00.213784257  9259 0xaaaade61f400 INFO                    v4l2 gstv4l2object.c:1306:gst_v4l2_object_fill_format_list:<main_enc1:sink>   NM24
0:00:00.213793601  9259 0xaaaade61f400 INFO                    v4l2 gstv4l2object.c:1306:gst_v4l2_object_fill_format_list:<main_enc1:sink>   NM12
0:00:00.213802882  9259 0xaaaade61f400 INFO                    v4l2 gstv4l2object.c:1306:gst_v4l2_object_fill_format_list:<main_enc1:sink>   NV10
0:00:00.213812258  9259 0xaaaade61f400 INFO                    v4l2 gstv4l2object.c:1306:gst_v4l2_object_fill_format_list:<main_enc1:sink>   YM24
0:00:00.213979175  9259 0xaaaade61f400 INFO                    v4l2 gstv4l2object.c:4692:gst_v4l2_object_probe_caps:<main_enc1:sink> probed caps: video/x-raw, format=(string)I420, width=(int)[ 48, 4096 ], height=(int)[ 48, 4096 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-raw, format=(string)P010_10LE, width=(int)[ 48, 4096 ], height=(int)[ 48, 4096 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-raw, format=(string)NV24, width=(int)[ 48, 4096 ], height=(int)[ 48, 4096 ], framerate=(fraction)[ 0/1, 2147483647/1 ]; video/x-raw, format=(string)NV12, width=(int)[ 48, 4096 ], height=(int)[ 48, 4096 ], framerate=(fraction)[ 0/1, 2147483647/1 ]
0:00:00.214005960  9259 0xaaaade61f400 INFO                    v4l2 gstv4l2object.c:1302:gst_v4l2_object_fill_format_list:<main_enc1:src> got 3 format(s):
0:00:00.214019304  9259 0xaaaade61f400 INFO                    v4l2 gstv4l2object.c:1306:gst_v4l2_object_fill_format_list:<main_enc1:src>   AV10
0:00:00.214030857  9259 0xaaaade61f400 INFO                    v4l2 gstv4l2object.c:1306:gst_v4l2_object_fill_format_list:<main_enc1:src>   H265
0:00:00.214040937  9259 0xaaaade61f400 INFO                    v4l2 gstv4l2object.c:1306:gst_v4l2_object_fill_format_list:<main_enc1:src>   H264
0:00:00.214053641  9259 0xaaaade61f400 WARN                    v4l2 gstv4l2object.c:4634:gst_v4l2_object_probe_caps:<main_enc1:src> Failed to probe pixel aspect ratio with VIDIOC_CROPCAP: Unknown error -1
0:00:00.214101803  9259 0xaaaade61f400 INFO                    v4l2 gstv4l2object.c:4692:gst_v4l2_object_probe_caps:<main_enc1:src> probed caps: video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, width=(int)[ 48, 4096 ], height=(int)[ 48, 4096 ], framerate=(fraction)[ 0/1, 2147483647/1 ]
0:00:00.214124555  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_enc1> completed state change to READY
0:00:00.214140364  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_enc1> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.214181581  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_enc1' changed state to 2(READY) successfully
0:00:00.214213550  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_sony_mp4_caps1> current NULL pending VOID_PENDING, desired next READY
0:00:00.214230735  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_sony_mp4_caps1> completed state change to READY
0:00:00.214241999  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_sony_mp4_caps1> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.214257295  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_sony_mp4_caps1' changed state to 2(READY) successfully
0:00:00.214273360  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_caps6> current NULL pending VOID_PENDING, desired next READY
0:00:00.214285136  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_caps6> completed state change to READY
0:00:00.214295217  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_caps6> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.214308145  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_caps6' changed state to 2(READY) successfully
0:00:00.214326513  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_sony_mp4_conv1> current NULL pending VOID_PENDING, desired next READY
0:00:00.214342098  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_sony_mp4_conv1> completed state change to READY
0:00:00.214353138  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_sony_mp4_conv1> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.214367699  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_sony_mp4_conv1' changed state to 2(READY) successfully
0:00:00.214381331  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_conv3> current NULL pending VOID_PENDING, desired next READY
0:00:00.214392243  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_conv3> completed state change to READY
0:00:00.214402420  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_conv3> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.214415892  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_conv3' changed state to 2(READY) successfully
0:00:00.214433909  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_sony_mp4_caps2> current NULL pending VOID_PENDING, desired next READY
0:00:00.214444917  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_sony_mp4_caps2> completed state change to READY
0:00:00.214455317  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_sony_mp4_caps2> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.214468278  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_sony_mp4_caps2' changed state to 2(READY) successfully
0:00:00.214483414  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_caps5> current NULL pending VOID_PENDING, desired next READY
0:00:00.214517303  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_caps5> completed state change to READY
0:00:00.214528248  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_caps5> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.214542488  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_caps5' changed state to 2(READY) successfully
0:00:00.214560280  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_compositor1> current NULL pending VOID_PENDING, desired next READY
0:00:00.214573657  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_compositor1> completed state change to READY
0:00:00.214583801  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_compositor1> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.214597498  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_compositor1' changed state to 2(READY) successfully
0:00:00.214611802  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_caps8> current NULL pending VOID_PENDING, desired next READY
0:00:00.214622618  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_caps8> completed state change to READY
0:00:00.214632475  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_caps8> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.214646075  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_caps8' changed state to 2(READY) successfully
0:00:00.214658651  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_caps7> current NULL pending VOID_PENDING, desired next READY
0:00:00.214671804  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_caps7> completed state change to READY
0:00:00.214682524  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_caps7> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.214695773  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_caps7' changed state to 2(READY) successfully
0:00:00.214709245  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_iray_mp4_tee> current NULL pending VOID_PENDING, desired next READY
0:00:00.214720541  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_iray_mp4_tee> completed state change to READY
0:00:00.214730846  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_iray_mp4_tee> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.214743166  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_iray_mp4_tee' changed state to 2(READY) successfully
0:00:00.214756478  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_sony_mp4_tee> current NULL pending VOID_PENDING, desired next READY
0:00:00.214767391  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_sony_mp4_tee> completed state change to READY
0:00:00.214777439  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_sony_mp4_tee> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.214790591  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_sony_mp4_tee' changed state to 2(READY) successfully
0:00:00.214803936  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_caps4> current NULL pending VOID_PENDING, desired next READY
0:00:00.214814336  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_caps4> completed state change to READY
0:00:00.214824160  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_caps4> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.214843617  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_caps4' changed state to 2(READY) successfully
0:00:00.214858977  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_caps2> current NULL pending VOID_PENDING, desired next READY
0:00:00.214869474  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_caps2> completed state change to READY
0:00:00.214879746  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_caps2> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.214892130  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_caps2' changed state to 2(READY) successfully
0:00:00.214907875  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_conv2> current NULL pending VOID_PENDING, desired next READY
0:00:00.214919971  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_conv2> completed state change to READY
0:00:00.214929924  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_conv2> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.214942244  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_conv2' changed state to 2(READY) successfully
0:00:00.214957828  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_conv1> current NULL pending VOID_PENDING, desired next READY
0:00:00.214969413  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_conv1> completed state change to READY
0:00:00.214979205  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_conv1> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.214992485  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_conv1' changed state to 2(READY) successfully
0:00:00.215007206  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_caps3> current NULL pending VOID_PENDING, desired next READY
0:00:00.215017702  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_caps3> completed state change to READY
0:00:00.215027399  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_caps3> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.215039783  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_caps3' changed state to 2(READY) successfully
0:00:00.215052807  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_caps1> current NULL pending VOID_PENDING, desired next READY
0:00:00.215064968  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_caps1> completed state change to READY
0:00:00.215075688  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_caps1> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.215089192  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_caps1' changed state to 2(READY) successfully
0:00:00.215100617  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_src2> current NULL pending VOID_PENDING, desired next READY
0:00:00.215114089  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_src2> completed state change to READY
0:00:00.215125033  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_src2> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.215138730  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_src2' changed state to 2(READY) successfully
0:00:00.215152778  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_src1> current NULL pending VOID_PENDING, desired next READY
0:00:00.215164011  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_src1> completed state change to READY
0:00:00.215173835  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_src1> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.215186699  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_src1' changed state to 2(READY) successfully
0:00:00.215204556  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_queue2> current NULL pending VOID_PENDING, desired next READY
0:00:00.215217548  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_queue2> completed state change to READY
0:00:00.215228557  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_queue2> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.215241357  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_queue2' changed state to 2(READY) successfully
0:00:00.215255149  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_queue1> current NULL pending VOID_PENDING, desired next READY
0:00:00.215265582  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_queue1> completed state change to READY
0:00:00.215275758  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_queue1> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.215288366  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_queue1' changed state to 2(READY) successfully
0:00:00.215312911  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2778:gst_element_continue_state:<main_pipe1> committing state from NULL to READY, pending PLAYING, next PAUSED
0:00:00.215324591  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_pipe1> notifying about state-changed NULL to READY (PLAYING pending)
0:00:00.215338448  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2786:gst_element_continue_state:<main_pipe1> continue state change READY to PAUSED, final PLAYING
0:00:00.215398706  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_sony_mp4_sink1> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.215450899  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2935:gst_bin_change_state_func:<main_pipe1> child 'main_sony_mp4_sink1' is changing state asynchronously to PAUSED
0:00:00.215467732  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_sink1> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.215490292  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2935:gst_bin_change_state_func:<main_pipe1> child 'main_sink1' is changing state asynchronously to PAUSED
0:00:00.215504469  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_sony_mp4_parse1> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.215830623  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_sony_mp4_parse1> completed state change to PAUSED
0:00:00.215851615  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_sony_mp4_parse1> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.215870496  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_sony_mp4_parse1' changed state to 3(PAUSED) successfully
0:00:00.215888800  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_parse1> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.216081222  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_parse1> completed state change to PAUSED
0:00:00.216099335  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_parse1> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.216117479  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_parse1' changed state to 3(PAUSED) successfully
0:00:00.216134536  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_sony_mp4_enc1> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.216161449  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_sony_mp4_enc1> completed state change to PAUSED
0:00:00.216174153  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_sony_mp4_enc1> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.216190122  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_sony_mp4_enc1' changed state to 3(PAUSED) successfully
0:00:00.216203530  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_enc1> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.216223275  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_enc1> completed state change to PAUSED
0:00:00.216234571  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_enc1> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.216248843  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_enc1' changed state to 3(PAUSED) successfully
0:00:00.216266316  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_sony_mp4_caps1> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.216289901  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_sony_mp4_caps1> completed state change to PAUSED
0:00:00.216301421  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_sony_mp4_caps1> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.216316173  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_sony_mp4_caps1' changed state to 3(PAUSED) successfully
0:00:00.216329390  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_caps6> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.216346510  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_caps6> completed state change to PAUSED
0:00:00.216357167  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_caps6> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.216370607  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_caps6' changed state to 3(PAUSED) successfully
0:00:00.216385551  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_sony_mp4_conv1> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.216404976  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_sony_mp4_conv1> completed state change to PAUSED
0:00:00.216416112  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_sony_mp4_conv1> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.216430257  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_sony_mp4_conv1' changed state to 3(PAUSED) successfully
0:00:00.216443185  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_conv3> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.216459794  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_conv3> completed state change to PAUSED
0:00:00.216470834  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_conv3> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.216483762  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_conv3' changed state to 3(PAUSED) successfully
0:00:00.216498995  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_sony_mp4_caps2> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.216517267  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_sony_mp4_caps2> completed state change to PAUSED
0:00:00.216529396  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_sony_mp4_caps2> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.216548404  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_sony_mp4_caps2' changed state to 3(PAUSED) successfully
0:00:00.216562549  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_caps5> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.216579317  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_caps5> completed state change to PAUSED
0:00:00.216590230  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_caps5> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.216603798  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_caps5' changed state to 3(PAUSED) successfully
0:00:00.216619478  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_compositor1> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.216639895  9259 0xaaaade61f400 INFO              aggregator gstaggregator.c:2488:gst_aggregator_src_pad_activate_mode_func:<main_compositor1:src> Activating pad!
0:00:00.216650199  9259 0xaaaade61f400 INFO              aggregator gstaggregator.c:1511:gst_aggregator_start_srcpad_task:<main_compositor1> Starting srcpad task
0:00:00.216681464  9259 0xaaaade61f400 INFO                    task gsttask.c:516:gst_task_set_lock: setting stream lock 0xaaaade6328d0 on task 0xaaaade98b710
0:00:00.216694713  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:6291:gst_pad_start_task:<main_compositor1:src> created task 0xaaaade98b710
0:00:00.216841533  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_compositor1> completed state change to PAUSED
0:00:00.216867838  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_compositor1> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.216886174  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_compositor1' changed state to 3(PAUSED) successfully
0:00:00.216929312  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_caps8> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.216954241  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_caps8> completed state change to PAUSED
0:00:00.216967105  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_caps8> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.216982145  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_caps8' changed state to 3(PAUSED) successfully
0:00:00.216999138  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_caps7> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.217020227  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_caps7> completed state change to PAUSED
0:00:00.217033315  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_caps7> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.217049891  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_caps7' changed state to 3(PAUSED) successfully
0:00:00.217065892  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_iray_mp4_tee> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.217083940  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_iray_mp4_tee> completed state change to PAUSED
0:00:00.217095621  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_iray_mp4_tee> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.217125094  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_iray_mp4_tee' changed state to 3(PAUSED) successfully
0:00:00.217141926  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_sony_mp4_tee> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.217160807  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_sony_mp4_tee> completed state change to PAUSED
0:00:00.217172039  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_sony_mp4_tee> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.217186024  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_sony_mp4_tee' changed state to 3(PAUSED) successfully
0:00:00.217199432  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_caps4> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.217215912  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_caps4> completed state change to PAUSED
0:00:00.217226185  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_caps4> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.217239113  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_caps4' changed state to 3(PAUSED) successfully
0:00:00.217251657  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_caps2> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.217265834  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_caps2> completed state change to PAUSED
0:00:00.217277034  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_caps2> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.217290475  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_caps2' changed state to 3(PAUSED) successfully
0:00:00.217303563  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_conv2> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.217321644  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_conv2> completed state change to PAUSED
0:00:00.217343788  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_conv2> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.217359533  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_conv2' changed state to 3(PAUSED) successfully
0:00:00.217374285  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_conv1> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.217391374  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_conv1> completed state change to PAUSED
0:00:00.217401294  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_conv1> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.217416078  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_conv1' changed state to 3(PAUSED) successfully
0:00:00.217440623  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_caps3> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.217458256  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_caps3> completed state change to PAUSED
0:00:00.217469296  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_caps3> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.217482416  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_caps3' changed state to 3(PAUSED) successfully
0:00:00.217496241  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_caps1> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.217513969  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_caps1> completed state change to PAUSED
0:00:00.217525586  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_caps1> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.217538642  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_caps1' changed state to 3(PAUSED) successfully
0:00:00.217552499  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_src2> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.217689335  9259 0xaaaade61f400 INFO                 basesrc gstbasesrc.c:1430:gst_base_src_do_seek:<main_src2> seeking: 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:00.217717784  9259 0xaaaade61f400 INFO                    task gsttask.c:516:gst_task_set_lock: setting stream lock 0xaaaade96e180 on task 0xaaaade98b830
0:00:00.217733368  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:6291:gst_pad_start_task:<main_src2:src> created task 0xaaaade98b830
0:00:00.217843547  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_src2> completed state change to PAUSED
0:00:00.217861500  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_src2> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.217879580  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2971:gst_bin_change_state_func:<main_pipe1> child 'main_src2' changed state to 3(PAUSED) successfully without preroll
0:00:00.217895229  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_src1> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.217938398  9259 0xaaaade8b3d80 INFO        GST_ELEMENT_PADS gstelement.c:1013:gst_element_get_static_pad: no such pad 'sink' in element "main_src2"
0:00:00.217973631  9259 0xaaaade61f400 INFO                 basesrc gstbasesrc.c:1430:gst_base_src_do_seek:<main_src1> seeking: 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:00.217985728  9259 0xaaaade8b3d80 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<main_src2:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:00:00.217999936  9259 0xaaaade61f400 INFO                    task gsttask.c:516:gst_task_set_lock: setting stream lock 0xaaaade8dc0d0 on task 0xaaaade98b950
0:00:00.218013216  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:6291:gst_pad_start_task:<main_src1:src> created task 0xaaaade98b950
0:00:00.218128644  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_src1> completed state change to PAUSED
0:00:00.218149028  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_src1> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.218187206  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2971:gst_bin_change_state_func:<main_pipe1> child 'main_src1' changed state to 3(PAUSED) successfully without preroll
0:00:00.218202310  9259 0xaaaade8b3de0 INFO        GST_ELEMENT_PADS gstelement.c:1013:gst_element_get_static_pad: no such pad 'sink' in element "main_src1"
0:00:00.218247719  9259 0xaaaade8b3de0 FIXME                default gstutils.c:4025:gst_pad_create_stream_id_internal:<main_src1:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:00:00.218274184  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_queue2> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.218306601  9259 0xaaaade61f400 INFO                    task gsttask.c:516:gst_task_set_lock: setting stream lock 0xaaaade8ddc90 on task 0xaaaade98ba70
0:00:00.218329802  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:6291:gst_pad_start_task:<main_queue2:src> created task 0xaaaade98ba70
0:00:00.218438989  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_queue2> completed state change to PAUSED
0:00:00.218458830  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_queue2> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.218517104  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_queue2' changed state to 3(PAUSED) successfully
0:00:00.218540656  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_queue1> current READY pending VOID_PENDING, desired next PAUSED
0:00:00.218552849  9259 0xaaaade8b3d80 WARN         nvv4l2camerasrc gstnvv4l2camerasrc.cpp:684:gst_nv_v4l2_camera_fixate:<main_src2> Unable to fixate caps structure
0:00:00.218573073  9259 0xaaaade61f400 INFO                    task gsttask.c:516:gst_task_set_lock: setting stream lock 0xaaaade8dd7f0 on task 0xaaaade98bb90
0:00:00.218607186  9259 0xaaaade61f400 INFO                GST_PADS gstpad.c:6291:gst_pad_start_task:<main_queue1:src> created task 0xaaaade98bb90
0:00:00.218733334  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_queue1> completed state change to PAUSED
0:00:00.218754455  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_queue1> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.218777239  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_queue1' changed state to 3(PAUSED) successfully
0:00:00.218799096  9259 0xaaaade61f400 INFO                pipeline gstpipeline.c:533:gst_pipeline_change_state:<main_pipe1> pipeline is live
0:00:00.218814489  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2778:gst_element_continue_state:<main_pipe1> committing state from READY to PAUSED, pending PLAYING, next PLAYING
0:00:00.218827833  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_pipe1> notifying about state-changed READY to PAUSED (PLAYING pending)
0:00:00.218843065  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2786:gst_element_continue_state:<main_pipe1> continue state change PAUSED to PLAYING, final PLAYING
0:00:00.218869338  9259 0xaaaade8b3de0 WARN         nvv4l2camerasrc gstnvv4l2camerasrc.cpp:684:gst_nv_v4l2_camera_fixate:<main_src1> Unable to fixate caps structure
0:00:00.218970173  9259 0xaaaade61f400 INFO               GST_EVENT gstevent.c:1530:gst_event_new_latency: creating latency event 0:00:00.000000000
0:00:00.219017567  9259 0xaaaade61f400 INFO                     bin gstbin.c:2759:gst_bin_do_latency_func:<main_pipe1> configured latency of 0:00:00.000000000
0:00:00.219068128  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_sony_mp4_sink1> current READY pending PAUSED, desired next PLAYING
0:00:00.219082113  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2935:gst_bin_change_state_func:<main_pipe1> child 'main_sony_mp4_sink1' is changing state asynchronously to PLAYING
0:00:00.219096321  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_sink1> current READY pending PAUSED, desired next PLAYING
0:00:00.219106721  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2935:gst_bin_change_state_func:<main_pipe1> child 'main_sink1' is changing state asynchronously to PLAYING
0:00:00.219119490  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_sony_mp4_parse1> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.219130946  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_sony_mp4_parse1> completed state change to PLAYING
0:00:00.219141762  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_sony_mp4_parse1> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.219156867  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_sony_mp4_parse1' changed state to 4(PLAYING) successfully
0:00:00.219170275  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_parse1> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.219180292  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_parse1> completed state change to PLAYING
0:00:00.219190628  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_parse1> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.219203940  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_parse1' changed state to 4(PLAYING) successfully
0:00:00.219217029  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_sony_mp4_enc1> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.219229893  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_sony_mp4_enc1> completed state change to PLAYING
0:00:00.219240005  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_sony_mp4_enc1> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.219254342  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_sony_mp4_enc1' changed state to 4(PLAYING) successfully
0:00:00.219267110  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_enc1> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.219277638  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_enc1> completed state change to PLAYING
0:00:00.219307239  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_enc1> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.219323048  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_enc1' changed state to 4(PLAYING) successfully
0:00:00.219336616  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_sony_mp4_caps1> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.219346953  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_sony_mp4_caps1> completed state change to PLAYING
0:00:00.219356233  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_sony_mp4_caps1> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.219369161  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_sony_mp4_caps1' changed state to 4(PLAYING) successfully
0:00:00.219384970  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_caps6> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.219397034  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_caps6> completed state change to PLAYING
0:00:00.219407274  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_caps6> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.219420299  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_caps6' changed state to 4(PLAYING) successfully
0:00:00.219432971  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_sony_mp4_conv1> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.219444747  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_sony_mp4_conv1> completed state change to PLAYING
0:00:00.219455084  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_sony_mp4_conv1> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.219468140  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_sony_mp4_conv1' changed state to 4(PLAYING) successfully
0:00:00.219480525  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_conv3> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.219491149  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_conv3> completed state change to PLAYING
0:00:00.219500941  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_conv3> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.219513582  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_conv3' changed state to 4(PLAYING) successfully
0:00:00.219526542  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_sony_mp4_caps2> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.219537198  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_sony_mp4_caps2> completed state change to PLAYING
0:00:00.219546991  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_sony_mp4_caps2> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.219559663  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_sony_mp4_caps2' changed state to 4(PLAYING) successfully
0:00:00.219572207  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_caps5> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.219582576  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_caps5> completed state change to PLAYING
0:00:00.219592432  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_caps5> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.219605328  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_caps5' changed state to 4(PLAYING) successfully
0:00:00.219618577  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_compositor1> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.219635217  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_compositor1> completed state change to PLAYING
0:00:00.219650194  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_compositor1> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.219688595  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_compositor1' changed state to 4(PLAYING) successfully
0:00:00.219705651  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_caps8> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.219718420  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_caps8> completed state change to PLAYING
0:00:00.219741492  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_caps8> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.219758037  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_caps8' changed state to 4(PLAYING) successfully
0:00:00.219771413  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_caps7> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.219783766  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_caps7> completed state change to PLAYING
0:00:00.219794678  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_caps7> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.219809078  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_caps7' changed state to 4(PLAYING) successfully
0:00:00.219821527  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_iray_mp4_tee> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.219833975  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_iray_mp4_tee> completed state change to PLAYING
0:00:00.219845240  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_iray_mp4_tee> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.219860408  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_iray_mp4_tee' changed state to 4(PLAYING) successfully
0:00:00.219874424  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_sony_mp4_tee> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.219887193  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_sony_mp4_tee> completed state change to PLAYING
0:00:00.219898553  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_sony_mp4_tee> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.219913658  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_sony_mp4_tee' changed state to 4(PLAYING) successfully
0:00:00.219906905  9259 0xaaaade8b38c0 WARN         videoaggregator gstvideoaggregator.c:1140:gst_video_aggregator_find_best_format:<main_compositor1> Nothing compatible with video/x-raw(memory:NVMM), format=(string)RGBA, width=(int)1920, height=(int)1080, framerate=(fraction)30/1
0:00:00.219933690  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_caps4> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.219989596  9259 0xaaaade8b38c0 WARN            nvcompositor gstnvcompositor.c:776:gst_nvcompositor_fixate_caps:<main_compositor1> This pad is invalid
0:00:00.220008828  9259 0xaaaade8b38c0 WARN            nvcompositor gstnvcompositor.c:776:gst_nvcompositor_fixate_caps:<main_compositor1> This pad is invalid
0:00:00.220047518  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_caps4> completed state change to PLAYING
0:00:00.220080767  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_caps4> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.220107487  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_caps4' changed state to 4(PLAYING) successfully
0:00:00.220045918  9259 0xaaaade8b38c0 INFO         videoaggregator gstvideoaggregator.c:1301:gst_video_aggregator_default_negotiated_src_caps:<main_compositor1:src> set src caps: video/x-raw(memory:NVMM), format=(string)RGBA, width=(int)1920, height=(int)1080, framerate=(fraction)30/1, colorimetry=(string)sRGB
0:00:00.220127136  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_caps2> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.220163073  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_caps2> completed state change to PLAYING
0:00:00.220168225  9259 0xaaaade8b38c0 INFO              aggregator gstaggregator.c:610:gst_aggregator_push_mandatory_events:<main_compositor1> pushing stream start
0:00:00.220175809  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_caps2> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.220222275  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_caps2' changed state to 4(PLAYING) successfully
0:00:00.220243876  9259 0xaaaade8b38c0 INFO              aggregator gstaggregator.c:621:gst_aggregator_push_mandatory_events:<main_compositor1> pushing caps: video/x-raw(memory:NVMM), format=(string)RGBA, width=(int)1920, height=(int)1080, framerate=(fraction)30/1, colorimetry=(string)sRGB
0:00:00.220254116  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_conv2> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.220277125  9259 0xaaaade8b38c0 INFO               GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event video/x-raw(memory:NVMM), format=(string)RGBA, width=(int)1920, height=(int)1080, framerate=(fraction)30/1, colorimetry=(string)sRGB
0:00:00.220318502  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_conv2> completed state change to PLAYING
0:00:00.220336518  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_conv2> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.220349383  9259 0xaaaade8b38c0 INFO           basetransform gstbasetransform.c:1325:gst_base_transform_setcaps:<main_caps5> reuse caps
0:00:00.220371143  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_conv2' changed state to 4(PLAYING) successfully
0:00:00.220373703  9259 0xaaaade8b38c0 INFO               GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event video/x-raw(memory:NVMM), format=(string)RGBA, width=(int)1920, height=(int)1080, framerate=(fraction)30/1, colorimetry=(string)sRGB
0:00:00.220395784  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_conv1> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.220439433  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_conv1> completed state change to PLAYING
0:00:00.220460330  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_conv1> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.220482059  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_conv1' changed state to 4(PLAYING) successfully
0:00:00.220505259  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_caps3> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.220526028  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_caps3> completed state change to PLAYING
0:00:00.220545037  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_caps3> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.220569741  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_caps3' changed state to 4(PLAYING) successfully
0:00:00.220595246  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_caps1> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.220615471  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_caps1> completed state change to PLAYING
0:00:00.220631887  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_caps1> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.220650992  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_caps1' changed state to 4(PLAYING) successfully
0:00:00.220674352  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_src2> completed state change to PLAYING
0:00:00.220696561  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_src2> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.220717394  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_src2' changed state to 4(PLAYING) successfully
0:00:00.220737906  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_src1> completed state change to PLAYING
0:00:00.220754835  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_src1> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.220752339  9259 0xaaaade8b38c0 INFO               GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event video/x-raw(memory:NVMM), width=(int)1920, height=(int)1080, framerate=(fraction)30/1, format=(string)NV12
0:00:00.220897943  9259 0xaaaade8b38c0 INFO           basetransform gstbasetransform.c:1325:gst_base_transform_setcaps:<main_caps6> reuse caps
0:00:00.220925624  9259 0xaaaade8b38c0 INFO               GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event video/x-raw(memory:NVMM), width=(int)1920, height=(int)1080, framerate=(fraction)30/1, format=(string)NV12
0:00:00.221096797  9259 0xaaaade8b38c0 FIXME               basesink gstbasesink.c:3395:gst_base_sink_default_event:<main_sink1> stream-start event without group-id. Consider implementing group-id handling in the upstream elements
0:00:00.221168735  9259 0xaaaade8b38c0 INFO               GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, profile=(string)NULL, level=(string)NULL, width=(int)1920, height=(int)1080, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)30/1, interlace-mode=(string)progressive, colorimetry=(string)bt709, chroma-site=(string)mpeg2
0:00:00.221248642  9259 0xaaaade8b38c0 INFO               GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, profile=(string)NULL, level=(string)NULL, width=(int)1920, height=(int)1080, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)30/1, interlace-mode=(string)progressive, colorimetry=(string)bt709, chroma-site=(string)mpeg2, parsed=(boolean)true
0:00:00.221290915  9259 0xaaaade8b38c0 INFO               GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, profile=(string)NULL, level=(string)NULL, width=(int)1920, height=(int)1080, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)30/1, interlace-mode=(string)progressive, colorimetry=(string)bt709, chroma-site=(string)mpeg2, parsed=(boolean)true
0:00:00.221362309  9259 0xaaaade8b38c0 INFO                    v4l2 gstv4l2object.c:3211:gst_v4l2_object_setup_pool:<main_enc1:src> accessing buffers via mode 2
0:00:00.221535850  9259 0xaaaade8b38c0 INFO          v4l2bufferpool gstv4l2bufferpool.c:827:gst_v4l2_buffer_pool_set_config:<main_enc1:pool:src> increasing minimum buffers to 2
0:00:00.221556395  9259 0xaaaade8b38c0 INFO          v4l2bufferpool gstv4l2bufferpool.c:841:gst_v4l2_buffer_pool_set_config:<main_enc1:pool:src> reducing maximum buffers to 64
0:00:00.221567691  9259 0xaaaade8b38c0 INFO          v4l2bufferpool gstv4l2bufferpool.c:859:gst_v4l2_buffer_pool_set_config:<main_enc1:pool:src> can't allocate, setting maximum to minimum
0:00:00.221638285  9259 0xaaaade8b38c0 INFO          v4l2bufferpool gstv4l2bufferpool.c:841:gst_v4l2_buffer_pool_set_config:<main_enc1:pool:src> reducing maximum buffers to 64
0:00:00.221652046  9259 0xaaaade8b38c0 INFO          v4l2bufferpool gstv4l2bufferpool.c:859:gst_v4l2_buffer_pool_set_config:<main_enc1:pool:src> can't allocate, setting maximum to minimum
NvMMLiteOpen : Block : BlockType = 4 
===== NvVideo: NVENC =====
NvMMLiteBlockCreate : Block : BlockType = 4 
0:00:00.223107930  9259 0xaaaade8b38c0 INFO                    v4l2 gstv4l2object.c:4185:gst_v4l2_object_set_format_full:<main_enc1:sink> Set output framerate to 30/1
0:00:00.223146171  9259 0xaaaade8b38c0 INFO                    v4l2 gstv4l2object.c:3211:gst_v4l2_object_setup_pool:<main_enc1:sink> accessing buffers via mode 5
0:00:00.223279839  9259 0xaaaade8b38c0 INFO          v4l2bufferpool gstv4l2bufferpool.c:827:gst_v4l2_buffer_pool_set_config:<main_enc1:pool:sink> increasing minimum buffers to 2
0:00:00.223299391  9259 0xaaaade8b38c0 INFO          v4l2bufferpool gstv4l2bufferpool.c:834:gst_v4l2_buffer_pool_set_config:<main_enc1:pool:sink> increasing minimum buffers to 4
0:00:00.223309696  9259 0xaaaade8b38c0 INFO          v4l2bufferpool gstv4l2bufferpool.c:841:gst_v4l2_buffer_pool_set_config:<main_enc1:pool:sink> reducing maximum buffers to 64
0:00:00.223319136  9259 0xaaaade8b38c0 INFO          v4l2bufferpool gstv4l2bufferpool.c:859:gst_v4l2_buffer_pool_set_config:<main_enc1:pool:sink> can't allocate, setting maximum to minimum
0:00:00.223328416  9259 0xaaaade8b38c0 INFO          v4l2bufferpool gstv4l2bufferpool.c:864:gst_v4l2_buffer_pool_set_config:<main_enc1:pool:sink> adding needed video meta
0:00:00.220789588  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_src1' changed state to 4(PLAYING) successfully
0:00:00.223592808  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_queue2> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.223644746  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_queue2> completed state change to PLAYING
0:00:00.223996212  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_queue2> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.224072151  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_queue2' changed state to 4(PLAYING) successfully
0:00:00.224117208  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2479:gst_bin_element_set_state:<main_queue1> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:00.224144729  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2806:gst_element_continue_state:<main_queue1> completed state change to PLAYING
0:00:00.224163609  9259 0xaaaade61f400 INFO              GST_STATES gstelement.c:2706:_priv_gst_element_state_changed:<main_queue1> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:00.224188634  9259 0xaaaade61f400 INFO              GST_STATES gstbin.c:2928:gst_bin_change_state_func:<main_pipe1> child 'main_queue1' changed state to 4(PLAYING) successfully
start main_pipe
0:00:00.225550659  9259 0xaaaade8b38c0 WARN          v4l2bufferpool gstv4l2bufferpool.c:1114:gst_v4l2_buffer_pool_start:<main_enc1:pool:src> Uncertain or not enough buffers, enabling copy threshold
0:00:00.232365104  9259 0xaaaade8b38c0 INFO               GST_EVENT gstevent.c:972: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 99:99:99.999999999, duration 99:99:99.999999999
usb is run in the system.
usb start open ok.
0:00:00.235174693  9259 0xaaaade8b38c0 INFO                    task gsttask.c:516:gst_task_set_lock: setting stream lock 0xaaaade97a920 on task 0xffff5c021cb0
0:00:00.235218982  9259 0xaaaade8b38c0 INFO                GST_PADS gstpad.c:6291:gst_pad_start_task:<main_enc1:src> created task 0xffff5c021cb0
H264: Profile = 66, Level = 0 
NVMEDIA: Need to set EMC bandwidth : 846000 
0:00:00.257063959  9259 0xaaaadea18cc0 WARN          v4l2bufferpool gstv4l2bufferpool.c:1565:gst_v4l2_buffer_pool_dqbuf:<main_enc1:pool:src> Driver should never set v4l2_buffer.field to ANY
0:00:00.257311071  9259 0xaaaadea18cc0 INFO               GST_EVENT gstevent.c:972: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 99:99:99.999999999, duration 99:99:99.999999999
0:00:00.257579783  9259 0xaaaadea18cc0 INFO               baseparse gstbaseparse.c:4088:gst_base_parse_set_latency:<main_parse1> min/max latency 0:00:00.000000000, 0:00:00.000000000
0:00:00.257691626  9259 0xaaaadea18cc0 INFO               GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, profile=(string)constrained-baseline, level=(string)5, width=(int)1920, height=(int)1080, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)30/1, interlace-mode=(string)progressive, colorimetry=(string)bt709, chroma-site=(string)mpeg2, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, parsed=(boolean)true
0:00:00.257769005  9259 0xaaaadea18cc0 INFO               GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, profile=(string)constrained-baseline, level=(string)5, width=(int)1920, height=(int)1080, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)30/1, interlace-mode=(string)progressive, colorimetry=(string)bt709, chroma-site=(string)mpeg2, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, parsed=(boolean)true
0:00:00.258107543  9259 0xaaaadea18cc0 INFO               videometa gstvideometa.c:1072:gst_video_time_code_meta_api_get_type: registering
FOUND TAG      : found by element main_sink1
     video codec: H.264 (Constrained Baseline Profile)
0:00:00.497276627  9259 0xaaaade8b3d80 INFO               GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event video/x-raw(memory:NVMM), width=(int)640, height=(int)360, format=(string)YUY2, interlace-mode=(string)progressive, framerate=(fraction)30/1
0:00:00.497456825  9259 0xaaaade8b3d80 INFO           basetransform gstbasetransform.c:1325:gst_base_transform_setcaps:<main_caps3> reuse caps
0:00:00.497504698  9259 0xaaaade8b3d80 INFO               GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event video/x-raw(memory:NVMM), width=(int)640, height=(int)360, format=(string)YUY2, interlace-mode=(string)progressive, framerate=(fraction)30/1
0:00:00.498056267  9259 0xaaaade8b3d80 INFO               GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event video/x-raw(memory:NVMM), width=(int)1920, height=(int)1080, interlace-mode=(string)progressive, framerate=(fraction)30/1, format=(string)RGBA
0:00:00.498117453  9259 0xaaaade8b3d80 INFO           basetransform gstbasetransform.c:1325:gst_base_transform_setcaps:<main_caps4> reuse caps
0:00:00.498145165  9259 0xaaaade8b3d80 INFO               GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event video/x-raw(memory:NVMM), width=(int)1920, height=(int)1080, interlace-mode=(string)progressive, framerate=(fraction)30/1, format=(string)RGBA
0:00:00.498214224  9259 0xaaaade8b3d80 INFO           basetransform gstbasetransform.c:1325:gst_base_transform_setcaps:<main_caps8> reuse caps
0:00:00.498246961  9259 0xaaaade8b3d80 INFO               GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event video/x-raw(memory:NVMM), width=(int)1920, height=(int)1080, interlace-mode=(string)progressive, framerate=(fraction)30/1, format=(string)RGBA
0:00:00.709628297  9259 0xaaaade8b3de0 INFO               GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event video/x-raw(memory:NVMM), width=(int)640, height=(int)360, format=(string)YUY2, interlace-mode=(string)progressive, framerate=(fraction)30/1
0:00:00.709825615  9259 0xaaaade8b3de0 INFO           basetransform gstbasetransform.c:1325:gst_base_transform_setcaps:<main_caps1> reuse caps
0:00:00.709903569  9259 0xaaaade8b3de0 INFO               GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event video/x-raw(memory:NVMM), width=(int)640, height=(int)360, format=(string)YUY2, interlace-mode=(string)progressive, framerate=(fraction)30/1
0:00:00.710836493  9259 0xaaaade8b3de0 INFO               GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event video/x-raw(memory:NVMM), width=(int)1920, height=(int)1080, interlace-mode=(string)progressive, framerate=(fraction)30/1, format=(string)RGBA
0:00:00.710917840  9259 0xaaaade8b3de0 INFO           basetransform gstbasetransform.c:1325:gst_base_transform_setcaps:<main_caps2> reuse caps
0:00:00.710953201  9259 0xaaaade8b3de0 INFO               GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event video/x-raw(memory:NVMM), width=(int)1920, height=(int)1080, interlace-mode=(string)progressive, framerate=(fraction)30/1, format=(string)RGBA
0:00:00.711035827  9259 0xaaaade8b3de0 INFO           basetransform gstbasetransform.c:1325:gst_base_transform_setcaps:<main_caps7> reuse caps
0:00:00.711078133  9259 0xaaaade8b3de0 INFO               GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event video/x-raw(memory:NVMM), width=(int)1920, height=(int)1080, interlace-mode=(string)progressive, framerate=(fraction)30/1, format=(string)RGBA
0:00:00.711158839  9259 0xaaaade8b3de0 INFO           basetransform gstbasetransform.c:1325:gst_base_transform_setcaps:<main_sony_mp4_caps2> reuse caps
0:00:00.711188568  9259 0xaaaade8b3de0 INFO               GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event video/x-raw(memory:NVMM), width=(int)1920, height=(int)1080, interlace-mode=(string)progressive, framerate=(fraction)30/1, format=(string)RGBA
0:00:00.711489633  9259 0xaaaade8b3de0 INFO               GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event video/x-raw(memory:NVMM), width=(int)1920, height=(int)1080, interlace-mode=(string)progressive, framerate=(fraction)30/1, format=(string)NV12
0:00:00.711604580  9259 0xaaaade8b3de0 INFO           basetransform gstbasetransform.c:1325:gst_base_transform_setcaps:<main_sony_mp4_caps1> reuse caps
0:00:00.711639429  9259 0xaaaade8b3de0 INFO               GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event video/x-raw(memory:NVMM), width=(int)1920, height=(int)1080, interlace-mode=(string)progressive, framerate=(fraction)30/1, format=(string)NV12
0:00:00.711907566  9259 0xaaaade8b3de0 INFO               GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, profile=(string)NULL, level=(string)NULL, width=(int)1920, height=(int)1080, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)30/1, interlace-mode=(string)progressive, colorimetry=(string)bt709, chroma-site=(string)mpeg2
0:00:00.712014449  9259 0xaaaade8b3de0 INFO               GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, profile=(string)NULL, level=(string)NULL, width=(int)1920, height=(int)1080, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)30/1, interlace-mode=(string)progressive, colorimetry=(string)bt709, chroma-site=(string)mpeg2, parsed=(boolean)true
0:00:00.712057202  9259 0xaaaade8b3de0 INFO               GST_EVENT gstevent.c:892:gst_event_new_caps: creating caps event video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, profile=(string)NULL, level=(string)NULL, width=(int)1920, height=(int)1080, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)30/1, interlace-mode=(string)progressive, colorimetry=(string)bt709, chroma-site=(string)mpeg2, parsed=(boolean)true
0:00:00.712135924  9259 0xaaaade8b3de0 INFO                    v4l2 gstv4l2object.c:3211:gst_v4l2_object_setup_pool:<main_sony_mp4_enc1:src> accessing buffers via mode 2
0:00:00.712246168  9259 0xaaaade8b3de0 INFO          v4l2bufferpool gstv4l2bufferpool.c:827:gst_v4l2_buffer_pool_set_config:<main_sony_mp4_enc1:pool:src> increasing minimum buffers to 2
0:00:00.712264184  9259 0xaaaade8b3de0 INFO          v4l2bufferpool gstv4l2bufferpool.c:841:gst_v4l2_buffer_pool_set_config:<main_sony_mp4_enc1:pool:src> reducing maximum buffers to 64
0:00:00.712276121  9259 0xaaaade8b3de0 INFO          v4l2bufferpool gstv4l2bufferpool.c:859:gst_v4l2_buffer_pool_set_config:<main_sony_mp4_enc1:pool:src> can't allocate, setting maximum to minimum
0:00:00.712311002  9259 0xaaaade8b3de0 INFO          v4l2bufferpool gstv4l2bufferpool.c:841:gst_v4l2_buffer_pool_set_config:<main_sony_mp4_enc1:pool:src> reducing maximum buffers to 64
0:00:00.712322682  9259 0xaaaade8b3de0 INFO          v4l2bufferpool gstv4l2bufferpool.c:859:gst_v4l2_buffer_pool_set_config:<main_sony_mp4_enc1:pool:src> can't allocate, setting maximum to minimum
NvMMLiteOpen : Block : BlockType = 4 
===== NvVideo: NVENC =====
NvMMLiteBlockCreate : Block : BlockType = 4 
0:00:00.713723940  9259 0xaaaade8b3de0 INFO                    v4l2 gstv4l2object.c:4185:gst_v4l2_object_set_format_full:<main_sony_mp4_enc1:sink> Set output framerate to 30/1
0:00:00.713754853  9259 0xaaaade8b3de0 INFO                    v4l2 gstv4l2object.c:3211:gst_v4l2_object_setup_pool:<main_sony_mp4_enc1:sink> accessing buffers via mode 5
0:00:00.713860392  9259 0xaaaade8b3de0 INFO          v4l2bufferpool gstv4l2bufferpool.c:827:gst_v4l2_buffer_pool_set_config:<main_sony_mp4_enc1:pool:sink> increasing minimum buffers to 2
0:00:00.713878345  9259 0xaaaade8b3de0 INFO          v4l2bufferpool gstv4l2bufferpool.c:834:gst_v4l2_buffer_pool_set_config:<main_sony_mp4_enc1:pool:sink> increasing minimum buffers to 4
0:00:00.713889641  9259 0xaaaade8b3de0 INFO          v4l2bufferpool gstv4l2bufferpool.c:841:gst_v4l2_buffer_pool_set_config:<main_sony_mp4_enc1:pool:sink> reducing maximum buffers to 64
0:00:00.713899113  9259 0xaaaade8b3de0 INFO          v4l2bufferpool gstv4l2bufferpool.c:859:gst_v4l2_buffer_pool_set_config:<main_sony_mp4_enc1:pool:sink> can't allocate, setting maximum to minimum
0:00:00.713908426  9259 0xaaaade8b3de0 INFO          v4l2bufferpool gstv4l2bufferpool.c:864:gst_v4l2_buffer_pool_set_config:<main_sony_mp4_enc1:pool:sink> adding needed video meta
0:00:00.717318448  9259 0xaaaade8b3de0 WARN          v4l2bufferpool gstv4l2bufferpool.c:1114:gst_v4l2_buffer_pool_start:<main_sony_mp4_enc1:pool:src> Uncertain or not enough buffers, enabling copy threshold

Hi,
Please upgrade to Jetpack 6.0GA(r36.3). r36.2 is developer preview and not stable. Please use production release.


I updated to 36.3 and still have the problem.
I now suspect that it is a problem with the pad’s property settings, the image is formatted as 1920*1080 after the tee, I requested two sink pads from nvcompositor via gst_element_request_pad_simple, and the properties for these two pads I set to:

	g_object_set (G_OBJECT (s_main_pipe_obj->compositor_sink1_pad), "xpos", 960, "ypos", 270, "width", 960, "height", 540, "zorder", 1, "repeat-after-eos", 1, NULL);
	g_object_set (G_OBJECT (s_main_pipe_obj->compositor_sink2_pad), "xpos", 0, "ypos", 270, "width", 960, "height", 540,  "zorder", 0, "repeat-after-eos", 1, NULL);

Could it be that the 19201080 after the tee is not compatible with the 960540 of the nvcompositor pad.
The 960*540 is meant to display two images

Hi,
Please construct the pipeline in gst-launch-1.0 command with fakesink. To ensure it works in gst-launch-1.0 first, and then apple to C code by replacing fakesink with appsink.

Here is a command with two nvarguscamerasrc for reference:
Using `nvcompositor` with `nvarguscamerasrc` leads to segfault in `nvcompositor` - #5 by DaneLLL

I solved the problem by linking the mp4 pipeline after appsink received the first frame of video.

1 Like

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