gistfile1.txt

0:00:00.000052440 12852 0x55e10a226e00 INFO                GST_INIT gst.c:586:init_pre: Initializing GStreamer Core Library version 1.16.3
0:00:00.000085294 12852 0x55e10a226e00 INFO                GST_INIT gst.c:587:init_pre: Using library installed in /usr/lib/x86_64-linux-gnu
0:00:00.000108159 12852 0x55e10a226e00 INFO                GST_INIT gst.c:605:init_pre: Linux sauron 5.15.0-78-generic #85~20.04.1-Ubuntu SMP Mon Jul 17 09:42:39 UTC 2023 x86_64
0:00:00.000316345 12852 0x55e10a226e00 INFO                GST_INIT gstmessage.c:128:_priv_gst_message_initialize: init messages
0:00:00.000705625 12852 0x55e10a226e00 INFO                GST_INIT gstcontext.c:84:_priv_gst_context_initialize: init contexts
0:00:00.000877629 12852 0x55e10a226e00 INFO      GST_PLUGIN_LOADING gstplugin.c:318:_priv_gst_plugin_initialize: registering 0 static plugins
0:00:00.000964456 12852 0x55e10a226e00 INFO      GST_PLUGIN_LOADING gstplugin.c:226:gst_plugin_register_static: registered static plugin "staticelements"
0:00:00.000978737 12852 0x55e10a226e00 INFO      GST_PLUGIN_LOADING gstplugin.c:228:gst_plugin_register_static: added static plugin "staticelements", result: 1
0:00:00.001002771 12852 0x55e10a226e00 INFO            GST_REGISTRY gstregistry.c:1733:ensure_current_registry: reading registry cache: /home/chris/.cache/gstreamer-1.0/registry.x86_64.bin
0:00:00.014961931 12852 0x55e10a226e00 INFO            GST_REGISTRY gstregistrybinary.c:621:priv_gst_registry_binary_read_cache: loaded /home/chris/.cache/gstreamer-1.0/registry.x86_64.bin in 0.013929 seconds
0:00:00.015010440 12852 0x55e10a226e00 INFO            GST_REGISTRY gstregistry.c:1592:scan_and_update_registry: Validating plugins from registry cache: /home/chris/.cache/gstreamer-1.0/registry.x86_64.bin
0:00:00.015537933 12852 0x55e10a226e00 INFO      GST_PLUGIN_LOADING gstplugin.c:1524:gst_plugin_ext_dep_extract_env_vars_paths:<plugin247> ignoring environment variable content '': either not an absolute path or not a path at all
0:00:00.015548616 12852 0x55e10a226e00 INFO      GST_PLUGIN_LOADING gstplugin.c:1524:gst_plugin_ext_dep_extract_env_vars_paths:<plugin247> ignoring environment variable content '1': either not an absolute path or not a path at all
0:00:00.015931302 12852 0x55e10a226e00 INFO            GST_REGISTRY gstregistry.c:1691:scan_and_update_registry: Registry cache has not changed
0:00:00.015940405 12852 0x55e10a226e00 INFO            GST_REGISTRY gstregistry.c:1768:ensure_current_registry: registry reading and updating done, result = 1
0:00:00.015950673 12852 0x55e10a226e00 INFO                GST_INIT gst.c:806:init_post: GLib runtime version: 2.64.6
0:00:00.015959409 12852 0x55e10a226e00 INFO                GST_INIT gst.c:808:init_post: GLib headers version: 2.64.6
0:00:00.015965653 12852 0x55e10a226e00 INFO                GST_INIT gst.c:810:init_post: initialized GStreamer successfully
0:00:00.016115692 12852 0x55e10a226e00 INFO         rtspmountpoints rtsp-mount-points.c:333:gst_rtsp_mount_points_add_factory: adding media factory 0x55e10a42da70 for path /test
stream ready at rtsp://127.0.0.1:8554/test
0:00:04.587380464 12852 0x55e10a226e00 INFO              rtspclient rtsp-client.c:4157:gst_rtsp_client_set_connection: client 0x55e10a45e230 connected to server ip 127.0.0.1, ipv6 = 0
0:00:04.587395285 12852 0x55e10a226e00 INFO              rtspclient rtsp-client.c:4161:gst_rtsp_client_set_connection: added new client 0x55e10a45e230 ip 127.0.0.1:55064
0:00:04.587495435 12852 0x55e10a462060 INFO          rtspthreadpool rtsp-thread-pool.c:329:do_loop: enter mainloop of thread 0x55e10a46b800
0:00:04.587498690 12852 0x55e10a226e00 INFO              rtspclient rtsp-client.c:4763:gst_rtsp_client_attach: client 0x55e10a45e230: attaching to context 0x55e10a46ec80
0:00:04.588071960 12852 0x55e10a462060 INFO              rtspclient rtsp-client.c:3533:handle_request: client 0x55e10a45e230: received a request OPTIONS rtsp://127.0.0.1:8554/test 1.0
0:00:04.588705054 12852 0x55e10a462060 INFO              rtspclient rtsp-client.c:3533:handle_request: client 0x55e10a45e230: received a request DESCRIBE rtsp://127.0.0.1:8554/test 1.0
0:00:04.588757399 12852 0x55e10a462060 INFO         rtspmountpoints rtsp-mount-points.c:300:gst_rtsp_mount_points_match: found media factory 0x55e10a42da70 for path /test
0:00:04.588783630 12852 0x55e10a462060 INFO            GST_PIPELINE gstparse.c:336:gst_parse_launch_full: parsing pipeline description '( appsrc name=videosrc ! h264parse ! rtph264pay name=pay0 pt=96 )'
0:00:04.589110629 12852 0x55e10a462060 INFO      GST_PLUGIN_LOADING gstplugin.c:902:_priv_gst_plugin_load_file_for_registry: plugin "/usr/lib/x86_64-linux-gnu/gstreamer-1.0/libgstapp.so" loaded
0:00:04.589137451 12852 0x55e10a462060 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "appsrc"
0:00:04.589379781 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstBaseSrc@0x7f0bcc00c370> adding pad 'src'
0:00:04.590966608 12852 0x55e10a462060 INFO      GST_PLUGIN_LOADING gstplugin.c:902:_priv_gst_plugin_load_file_for_registry: plugin "/usr/lib/x86_64-linux-gnu/gstreamer-1.0/libgstvideoparsersbad.so" loaded
0:00:04.590987490 12852 0x55e10a462060 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "h264parse"
0:00:04.591112968 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstBaseParse@0x7f0bcc013b80> adding pad 'sink'
0:00:04.591146301 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstBaseParse@0x7f0bcc013b80> adding pad 'src'
0:00:04.591217679 12852 0x55e10a462060 INFO               baseparse gstbaseparse.c:4026:gst_base_parse_set_pts_interpolation:<GstH264Parse@0x7f0bcc013b80> PTS interpolation: no
0:00:04.592060961 12852 0x55e10a462060 INFO      GST_PLUGIN_LOADING gstplugin.c:902:_priv_gst_plugin_load_file_for_registry: plugin "/usr/lib/x86_64-linux-gnu/gstreamer-1.0/libgstrtp.so" loaded
0:00:04.592081609 12852 0x55e10a462060 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "rtph264pay"
0:00:04.592250535 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstRTPBasePayload@0x7f0bcc024190> adding pad 'src'
0:00:04.592284344 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstRTPBasePayload@0x7f0bcc024190> adding pad 'sink'
0:00:04.592337172 12852 0x55e10a462060 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "bin"
0:00:04.592415868 12852 0x55e10a462060 INFO            GST_PIPELINE grammar.y:648:gst_parse_perform_link: linking some pad of GstAppSrc named videosrc to some pad of GstH264Parse named h264parse0 (0/0) with caps "(NULL)"
0:00:04.592439244 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstutils.c:1771:gst_element_link_pads_full: trying to link element videosrc:(any) to element h264parse0:(any)
0:00:04.592457527 12852 0x55e10a462060 INFO                GST_PADS gstutils.c:1034:gst_pad_check_link: trying to link videosrc:src and h264parse0:sink
0:00:04.592486020 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:4237:gst_pad_peer_query:<h264parse0:src> pad has no peer
0:00:04.592524984 12852 0x55e10a462060 INFO                GST_PADS gstutils.c:1587:prepare_link_maybe_ghosting: videosrc and h264parse0 in same bin, no need for ghost pads
0:00:04.592555305 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:2377:gst_pad_link_prepare: trying to link videosrc:src and h264parse0:sink
0:00:04.592576328 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:4237:gst_pad_peer_query:<h264parse0:src> pad has no peer
0:00:04.592599269 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:2585:gst_pad_link_full: linked videosrc:src and h264parse0:sink, successful
0:00:04.592609966 12852 0x55e10a462060 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:04.592624732 12852 0x55e10a462060 INFO               GST_EVENT gstpad.c:5812:gst_pad_send_event_unchecked:<videosrc:src> Received event on flushing pad. Discarding
0:00:04.592652169 12852 0x55e10a462060 INFO            GST_PIPELINE grammar.y:648:gst_parse_perform_link: linking some pad of GstH264Parse named h264parse0 to some pad of GstRtpH264Pay named pay0 (0/0) with caps "(NULL)"
0:00:04.592674346 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstutils.c:1771:gst_element_link_pads_full: trying to link element h264parse0:(any) to element pay0:(any)
0:00:04.592691771 12852 0x55e10a462060 INFO                GST_PADS gstutils.c:1034:gst_pad_check_link: trying to link h264parse0:src and pay0:sink
0:00:04.592717651 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:4237:gst_pad_peer_query:<pay0:src> pad has no peer
0:00:04.592739121 12852 0x55e10a462060 INFO                GST_PADS gstutils.c:1587:prepare_link_maybe_ghosting: h264parse0 and pay0 in same bin, no need for ghost pads
0:00:04.592753932 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:2377:gst_pad_link_prepare: trying to link h264parse0:src and pay0:sink
0:00:04.592766593 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:4237:gst_pad_peer_query:<pay0:src> pad has no peer
0:00:04.592780942 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:2585:gst_pad_link_full: linked h264parse0:src and pay0:sink, successful
0:00:04.592789337 12852 0x55e10a462060 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:04.592798146 12852 0x55e10a462060 INFO               GST_EVENT gstpad.c:5812:gst_pad_send_event_unchecked:<h264parse0:src> Received event on flushing pad. Discarding
0:00:04.592900036 12852 0x55e10a462060 INFO           GST_PARENTAGE gstbin.c:4478:gst_bin_get_by_name: [bin0]: looking up child element pay0
0:00:04.592917490 12852 0x55e10a462060 INFO               rtspmedia rtsp-media.c:2066:gst_rtsp_media_collect_streams: found stream 0 with payloader 0x7f0bcc024190
0:00:04.592928728 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:928:gst_element_get_static_pad: found pad pay0:src
0:00:04.593002736 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:2377:gst_pad_link_prepare: trying to link pay0:src and src_0:proxypad0
0:00:04.593016605 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:2585:gst_pad_link_full: linked pay0:src and src_0:proxypad0, successful
0:00:04.593029901 12852 0x55e10a462060 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:04.593044588 12852 0x55e10a462060 INFO               GST_EVENT gstpad.c:5812:gst_pad_send_event_unchecked:<pay0:src> Received event on flushing pad. Discarding
0:00:04.593067359 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<bin0> adding pad 'src_0'
0:00:04.593124429 12852 0x55e10a462060 INFO           GST_PARENTAGE gstbin.c:4478:gst_bin_get_by_name: [bin0]: looking up child element dynpay0
0:00:04.593140979 12852 0x55e10a462060 INFO           GST_PARENTAGE gstbin.c:4478:gst_bin_get_by_name: [bin0]: looking up child element depay0
0:00:04.593153370 12852 0x55e10a462060 INFO           GST_PARENTAGE gstbin.c:4478:gst_bin_get_by_name: [bin0]: looking up child element pay1
0:00:04.593164944 12852 0x55e10a462060 INFO           GST_PARENTAGE gstbin.c:4478:gst_bin_get_by_name: [bin0]: looking up child element dynpay1
0:00:04.593176468 12852 0x55e10a462060 INFO           GST_PARENTAGE gstbin.c:4478:gst_bin_get_by_name: [bin0]: looking up child element depay1
0:00:04.593190831 12852 0x55e10a462060 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:360:gst_element_factory_create: creating element "pipeline" named "media-pipeline"
0:00:04.593275749 12852 0x55e10a462060 INFO            GST_PIPELINE gstparse.c:336:gst_parse_launch_full: parsing pipeline description 'filesrc name=vin location=video00.mp4 : qtdemux : h264parse : appsink name=vid max-buffers=3 drop=false'
0:00:04.593732954 12852 0x55e10a462060 INFO      GST_PLUGIN_LOADING gstplugin.c:902:_priv_gst_plugin_load_file_for_registry: plugin "/usr/lib/x86_64-linux-gnu/gstreamer-1.0/libgstcoreelements.so" loaded
0:00:04.593749111 12852 0x55e10a462060 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "filesrc"
0:00:04.593820274 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstBaseSrc@0x7f0bcc03e210> adding pad 'src'
0:00:04.593860291 12852 0x55e10a462060 INFO                 filesrc gstfilesrc.c:261:gst_file_src_set_location: filename : video00.mp4
0:00:04.593870049 12852 0x55e10a462060 INFO                 filesrc gstfilesrc.c:262:gst_file_src_set_location: uri      : file:///data/lytentec/zf/VCU/video-stream-exterior/video00.mp4
0:00:04.594744220 12852 0x55e10a462060 INFO      GST_PLUGIN_LOADING gstplugin.c:902:_priv_gst_plugin_load_file_for_registry: plugin "/usr/lib/x86_64-linux-gnu/gstreamer-1.0/libgstisomp4.so" loaded
0:00:04.594760987 12852 0x55e10a462060 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "qtdemux"
0:00:04.594915436 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstQTDemux@0x7f0bcc04c270> adding pad 'sink'
0:00:04.594967021 12852 0x55e10a462060 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "h264parse"
0:00:04.594991640 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstBaseParse@0x7f0bcc04e260> adding pad 'sink'
0:00:04.595008749 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstBaseParse@0x7f0bcc04e260> adding pad 'src'
0:00:04.595037314 12852 0x55e10a462060 INFO               baseparse gstbaseparse.c:4026:gst_base_parse_set_pts_interpolation:<GstH264Parse@0x7f0bcc04e260> PTS interpolation: no
0:00:04.595054483 12852 0x55e10a462060 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "appsink"
0:00:04.595164472 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstBaseSink@0x7f0bcc053b90> adding pad 'sink'
0:00:04.595210668 12852 0x55e10a462060 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "pipeline"
0:00:04.595272685 12852 0x55e10a462060 INFO            GST_PIPELINE grammar.y:648:gst_parse_perform_link: linking some pad of GstFileSrc named vin to some pad of GstQTDemux named qtdemux0 (0/0) with caps "(NULL)"
0:00:04.595286392 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstutils.c:1771:gst_element_link_pads_full: trying to link element vin:(any) to element qtdemux0:(any)
0:00:04.595298359 12852 0x55e10a462060 INFO                GST_PADS gstutils.c:1034:gst_pad_check_link: trying to link vin:src and qtdemux0:sink
0:00:04.595316926 12852 0x55e10a462060 INFO                GST_PADS gstutils.c:1587:prepare_link_maybe_ghosting: vin and qtdemux0 in same bin, no need for ghost pads
0:00:04.595331914 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:2377:gst_pad_link_prepare: trying to link vin:src and qtdemux0:sink
0:00:04.595346859 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:2585:gst_pad_link_full: linked vin:src and qtdemux0:sink, successful
0:00:04.595355363 12852 0x55e10a462060 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:04.595364331 12852 0x55e10a462060 INFO               GST_EVENT gstpad.c:5812:gst_pad_send_event_unchecked:<vin:src> Received event on flushing pad. Discarding
0:00:04.595379315 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstutils.c:1771:gst_element_link_pads_full: trying to link element vin:(any) to element qtdemux0:(any)
0:00:04.595391084 12852 0x55e10a462060 INFO                 default gstutils.c:2161:gst_element_link_pads_filtered: Could not link pads: vin:(null) - qtdemux0:(null)
0:00:04.595414463 12852 0x55e10a462060 INFO            GST_PIPELINE grammar.y:648:gst_parse_perform_link: linking some pad of GstQTDemux named qtdemux0 to some pad of GstH264Parse named h264parse1 (0/0) with caps "(NULL)"
0:00:04.595425973 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstutils.c:1771:gst_element_link_pads_full: trying to link element qtdemux0:(any) to element h264parse1:(any)
0:00:04.595440955 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:4237:gst_pad_peer_query:<h264parse1:src> pad has no peer
0:00:04.595473042 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:925:gst_element_get_static_pad: no such pad 'video_%u' in element "qtdemux0"
0:00:04.595488816 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstutils.c:1225:gst_element_get_compatible_pad:<qtdemux0> Could not find a compatible pad to link to h264parse1:sink
0:00:04.595499999 12852 0x55e10a462060 INFO                 default gstutils.c:2161:gst_element_link_pads_filtered: Could not link pads: qtdemux0:(null) - h264parse1:(null)
0:00:04.595519854 12852 0x55e10a462060 INFO            GST_PIPELINE grammar.y:648:gst_parse_perform_link: linking some pad of GstH264Parse named h264parse1 to some pad of GstAppSink named vid (0/0) with caps "(NULL)"
0:00:04.595530928 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstutils.c:1771:gst_element_link_pads_full: trying to link element h264parse1:(any) to element vid:(any)
0:00:04.595542585 12852 0x55e10a462060 INFO                GST_PADS gstutils.c:1034:gst_pad_check_link: trying to link h264parse1:src and vid:sink
0:00:04.595558537 12852 0x55e10a462060 INFO                GST_PADS gstutils.c:1587:prepare_link_maybe_ghosting: h264parse1 and vid in same bin, no need for ghost pads
0:00:04.595572226 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:2377:gst_pad_link_prepare: trying to link h264parse1:src and vid:sink
0:00:04.595586841 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:2585:gst_pad_link_full: linked h264parse1:src and vid:sink, successful
0:00:04.595595254 12852 0x55e10a462060 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:04.595604041 12852 0x55e10a462060 INFO               GST_EVENT gstpad.c:5812:gst_pad_send_event_unchecked:<h264parse1:src> Received event on flushing pad. Discarding
0:00:04.595627771 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstutils.c:1771:gst_element_link_pads_full: trying to link element h264parse1:(any) to element vid:(any)
0:00:04.595645467 12852 0x55e10a462060 INFO                 default gstutils.c:2161:gst_element_link_pads_filtered: Could not link pads: h264parse1:(null) - vid:(null)
0:00:04.595667850 12852 0x55e10a462060 INFO           GST_PARENTAGE gstbin.c:4478:gst_bin_get_by_name: [bin0]: looking up child element videosrc
0:00:04.595685850 12852 0x55e10a462060 INFO           GST_PARENTAGE gstbin.c:4478:gst_bin_get_by_name: [pipeline0]: looking up child element vid
0:00:04.595755851 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<vid> current NULL pending VOID_PENDING, desired next READY
0:00:04.595778834 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<vid> completed state change to READY
0:00:04.595793266 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<vid> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:04.595823514 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'vid' changed state to 2(READY) successfully
0:00:04.595841214 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<h264parse1> current NULL pending VOID_PENDING, desired next READY
0:00:04.595856790 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<h264parse1> completed state change to READY
0:00:04.595870029 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<h264parse1> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:04.595926160 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'h264parse1' changed state to 2(READY) successfully
0:00:04.595948409 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<qtdemux0> current NULL pending VOID_PENDING, desired next READY
0:00:04.595968213 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<qtdemux0> completed state change to READY
0:00:04.595988175 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<qtdemux0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:04.596004131 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'qtdemux0' changed state to 2(READY) successfully
0:00:04.596016110 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<vin> current NULL pending VOID_PENDING, desired next READY
0:00:04.596025901 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<vin> completed state change to READY
0:00:04.596034748 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<vin> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:04.596050006 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'vin' changed state to 2(READY) successfully
0:00:04.596068251 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2660:gst_element_continue_state:<pipeline0> committing state from NULL to READY, pending PLAYING, next PAUSED
0:00:04.596078058 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<pipeline0> notifying about state-changed NULL to READY (PLAYING pending)
0:00:04.596093444 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2668:gst_element_continue_state:<pipeline0> continue state change READY to PAUSED, final PLAYING
0:00:04.596108668 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<vid> current READY pending VOID_PENDING, desired next PAUSED
0:00:04.596126574 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2959:gst_bin_change_state_func:<pipeline0> child 'vid' is changing state asynchronously to PAUSED
0:00:04.596137904 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<h264parse1> current READY pending VOID_PENDING, desired next PAUSED
0:00:04.596191434 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:4237:gst_pad_peer_query:<h264parse1:sink> pad has no peer
0:00:04.596315585 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<h264parse1> completed state change to PAUSED
0:00:04.596326069 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<h264parse1> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:04.596343482 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'h264parse1' changed state to 3(PAUSED) successfully
0:00:04.596355467 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<qtdemux0> current READY pending VOID_PENDING, desired next PAUSED
0:00:04.596371671 12852 0x55e10a462060 INFO                 filesrc gstfilesrc.c:468:gst_file_src_start:<vin> opening file video00.mp4
0:00:04.596398404 12852 0x55e10a462060 WARN                 basesrc gstbasesrc.c:3600:gst_base_src_start_complete:<vin> pad not activated yet
0:00:04.596419885 12852 0x55e10a462060 INFO                 filesrc gstfilesrc.c:468:gst_file_src_start:<vin> opening file video00.mp4
0:00:04.596447246 12852 0x55e10a462060 INFO                    task gsttask.c:460:gst_task_set_lock: setting stream lock 0x7f0bcc00efd0 on task 0x7f0bcc015710
0:00:04.596456100 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:6159:gst_pad_start_task:<qtdemux0:sink> created task 0x7f0bcc015710
0:00:04.596520805 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<qtdemux0> completed state change to PAUSED
got message stream-status
0:00:04.596531849 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<qtdemux0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:04.596549036 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'qtdemux0' changed state to 3(PAUSED) successfully
0:00:04.596559857 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<vin> current READY pending VOID_PENDING, desired next PAUSED
got message stream-status
0:00:04.596571880 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<vin> completed state change to PAUSED
0:00:04.596581336 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<vin> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:04.596597857 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'vin' changed state to 3(PAUSED) successfully
0:00:04.596619922 12852 0x55e10a462060 INFO        rtspmediafactory rtsp-media-factory.c:1356:gst_rtsp_media_factory_construct: constructed media 0x7f0bcc02d1f0 for url /test
0:00:04.596616661 12852 0x7f0bcc003ea0 WARN                 qtdemux qtdemux_types.c:239:qtdemux_type_get: unknown QuickTime node type pasp
0:00:04.596661118 12852 0x7f0bcc003ea0 INFO                 qtdemux qtdemux.c:14202:qtdemux_parse_tree:<qtdemux0> timescale: 3000
0:00:04.596669177 12852 0x7f0bcc003ea0 INFO                 qtdemux qtdemux.c:14203:qtdemux_parse_tree:<qtdemux0> duration: 15000
0:00:04.596673209 12852 0x55e10a462060 INFO               rtspmedia rtsp-media.c:3571:gst_rtsp_media_prepare: preparing media 0x7f0bcc02d1f0
0:00:04.596690422 12852 0x7f0bcc003ea0 WARN                 qtdemux qtdemux.c:3250:qtdemux_parse_trex:<qtdemux0> failed to find fragment defaults for stream 1
0:00:04.596697648 12852 0x7f0bcc003f60 INFO          rtspthreadpool rtsp-thread-pool.c:329:do_loop: enter mainloop of thread 0x7f0bcc052500
0:00:04.596728154 12852 0x7f0bcc003ea0 INFO                 qtdemux qtdemux.c:11664:qtdemux_parse_trak:<qtdemux0> type avc1 caps video/x-h264, stream-format=(string)avc, alignment=(string)au, level=(string)5.1, profile=(string)high, codec_data=(buffer)01640033ffe1001f67640033acd9402d00ebf9f016e04040a8000003000800000301e478c18cb001000568ebecb22c
0:00:04.596756828 12852 0x7f0bcc003ea0 INFO          GST_SCHEDULING gstpad.c:4898:gst_pad_get_range_unchecked:<vin:src> getrange failed, flow: eos
0:00:04.596764850 12852 0x7f0bcc003ea0 INFO          GST_SCHEDULING gstpad.c:5113:gst_pad_pull_range:<qtdemux0:sink> pullrange failed, flow: eos
0:00:04.596832646 12852 0x7f0bcc003ea0 INFO               GST_EVENT gstevent.c:820:gst_event_new_caps: creating caps event video/x-h264, stream-format=(string)avc, alignment=(string)au, level=(string)5.1, profile=(string)high, codec_data=(buffer)01640033ffe1001f67640033acd9402d00ebf9f016e04040a8000003000800000301e478c18cb001000568ebecb22c, width=(int)2880, height=(int)1860, framerate=(fraction)30/1, pixel-aspect-ratio=(fraction)1/1, colorimetry=(string)2:4:0:0
0:00:04.596845300 12852 0x7f0bcc003ea0 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<qtdemux0> adding pad 'video_0'
0:00:04.596858486 12852 0x7f0bcc003ea0 INFO            GST_PIPELINE grammar.y:520:gst_parse_found_pad: trying delayed linking all pads some pad of GstQTDemux named qtdemux0 to some pad of GstH264Parse named h264parse1
0:00:04.596866240 12852 0x7f0bcc003ea0 INFO        GST_ELEMENT_PADS gstutils.c:1771:gst_element_link_pads_full: trying to link element qtdemux0:(any) to element h264parse1:(any)
0:00:04.596877405 12852 0x7f0bcc003ea0 INFO                GST_PADS gstutils.c:1034:gst_pad_check_link: trying to link qtdemux0:video_0 and h264parse1:sink
0:00:04.596904113 12852 0x7f0bcc003ea0 INFO                GST_PADS gstutils.c:1587:prepare_link_maybe_ghosting: qtdemux0 and h264parse1 in same bin, no need for ghost pads
0:00:04.596914945 12852 0x7f0bcc003ea0 INFO                GST_PADS gstpad.c:2377:gst_pad_link_prepare: trying to link qtdemux0:video_0 and h264parse1:sink
0:00:04.596934959 12852 0x7f0bcc003ea0 INFO                GST_PADS gstpad.c:2585:gst_pad_link_full: linked qtdemux0:video_0 and h264parse1:sink, successful
0:00:04.596941102 12852 0x7f0bcc003ea0 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:04.596954943 12852 0x55e10a462060 INFO      GST_PLUGIN_LOADING gstplugin.c:902:_priv_gst_plugin_load_file_for_registry: plugin "/usr/lib/x86_64-linux-gnu/gstreamer-1.0/libgstrtpmanager.so" loaded
0:00:04.596964694 12852 0x55e10a462060 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "rtpbin"
0:00:04.596982140 12852 0x7f0bcc003ea0 INFO               GST_EVENT gstevent.c:900:gst_event_new_segment: creating segment event time segment start=0:00:00.066666666, offset=0:00:00.000000000, stop=0:00:05.066666666, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.066666666, duration 99:99:99.999999999
got message stream-start
0:00:04.597056191 12852 0x7f0bcc003ea0 INFO               baseparse gstbaseparse.c:4843:gst_base_parse_set_upstream_tags:<h264parse1> upstream tags: taglist, video-codec=(string)"H.264\ /\ AVC", maximum-bitrate=(uint)2097152, bitrate=(uint)1257948, encoder=(string)x264;
0:00:04.597111553 12852 0x7f0bcc003ea0 INFO               h264parse gsth264parse.c:897:gst_h264_parse_process_nal:<h264parse1> have SPS/PPS in stream
0:00:04.597144527 12852 0x7f0bcc003ea0 INFO               h264parse gsth264parse.c:1938:gst_h264_parse_update_src_caps:<h264parse1> pixel aspect ratio has been changed 1/1
0:00:04.597162567 12852 0x7f0bcc003ea0 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse1> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:04.597203574 12852 0x7f0bcc003ea0 INFO               GST_EVENT gstevent.c:820:gst_event_new_caps: creating caps event video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, level=(string)5.1, profile=(string)high, width=(int)2880, height=(int)1860, framerate=(fraction)30/1, pixel-aspect-ratio=(fraction)1/1, colorimetry=(string)2:4:0:0, interlace-mode=(string)progressive, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, parsed=(boolean)true
0:00:04.597202653 12852 0x7f0bcc003f60 INFO              rtspstream rtsp-stream.c:3691:gst_rtsp_stream_join_bin: stream 0x7f0bcc038310 joining bin as session 0
0:00:04.597239700 12852 0x7f0bcc003ea0 INFO               GST_EVENT gstevent.c:820:gst_event_new_caps: creating caps event video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, level=(string)5.1, profile=(string)high, width=(int)2880, height=(int)1860, framerate=(fraction)30/1, pixel-aspect-ratio=(fraction)1/1, colorimetry=(string)2:4:0:0, interlace-mode=(string)progressive, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, parsed=(boolean)true
0:00:04.597247308 12852 0x7f0bcc003f60 INFO        GST_ELEMENT_PADS gstelement.c:925:gst_element_get_static_pad: no such pad 'send_rtp_sink_0' in element "rtpbin0"
0:00:04.597266657 12852 0x7f0bcc003f60 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "rtpsession"
got message duration-changed
got message tag
got message tag
got message tag
0:00:04.597458704 12852 0x7f0bcc003ea0 INFO              GST_STATES gstbin.c:3421:bin_handle_async_done:<pipeline0> committing state from READY to PAUSED, old pending PLAYING
0:00:04.597472654 12852 0x7f0bcc003ea0 INFO              GST_STATES gstbin.c:3452:bin_handle_async_done:<pipeline0> continue state change, pending PLAYING
0:00:04.597483744 12852 0x7f0bcc003ea0 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<pipeline0> notifying about state-changed READY to PAUSED (PLAYING pending)
got message async-done
0:00:04.597615557 12852 0x7f0bcc0044c0 INFO              GST_STATES gstbin.c:3248:gst_bin_continue_func:<pipeline0> continue state change PAUSED to PLAYING, final PLAYING
got message new-clock
0:00:04.597701195 12852 0x7f0bcc003f60 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "rtpssrcdemux"
0:00:04.597808590 12852 0x7f0bcc0044c0 INFO               GST_EVENT gstevent.c:1449:gst_event_new_latency: creating latency event 0:00:00.000000000
0:00:04.597821672 12852 0x7f0bcc003f60 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstRtpSsrcDemux@0x55e10a45e990> adding pad 'sink'
0:00:04.597842847 12852 0x7f0bcc003f60 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstRtpSsrcDemux@0x55e10a45e990> adding pad 'rtcp_sink'
0:00:04.597854428 12852 0x7f0bcc0044c0 INFO                     bin gstbin.c:2783:gst_bin_do_latency_func:<pipeline0> configured latency of 0:00:00.000000000
0:00:04.597858887 12852 0x7f0bcc003f60 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "rtpstorage"
0:00:04.597898321 12852 0x7f0bcc0044c0 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<vid> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:04.597916501 12852 0x7f0bcc0044c0 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<vid> completed state change to PLAYING
0:00:04.597935601 12852 0x7f0bcc003f60 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstRtpStorage@0x7f0bc4003c60> adding pad 'src'
0:00:04.597935892 12852 0x7f0bcc0044c0 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<vid> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:04.597950317 12852 0x7f0bcc003f60 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstRtpStorage@0x7f0bc4003c60> adding pad 'sink'
0:00:04.597987059 12852 0x7f0bcc0044c0 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'vid' changed state to 4(PLAYING) successfully
0:00:04.598004470 12852 0x7f0bcc0044c0 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<h264parse1> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:04.598026644 12852 0x7f0bcc0044c0 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<h264parse1> completed state change to PLAYING
0:00:04.598046997 12852 0x7f0bcc0044c0 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<h264parse1> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:04.598066973 12852 0x7f0bcc0044c0 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'h264parse1' changed state to 4(PLAYING) successfully
0:00:04.598080815 12852 0x7f0bcc0044c0 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<qtdemux0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:04.598091233 12852 0x7f0bcc0044c0 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<qtdemux0> completed state change to PLAYING
0:00:04.598091269 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<rtpssrcdemux0> completed state change to NULL
0:00:04.598117635 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<rtpsession0> completed state change to NULL
0:00:04.598130858 12852 0x7f0bcc0044c0 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<qtdemux0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:04.598137325 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<rtpstorage0> completed state change to NULL
0:00:04.598156612 12852 0x7f0bcc003f60 INFO        GST_ELEMENT_PADS gstelement.c:925:gst_element_get_static_pad: no such pad 'send_rtp_sink' in element "rtpsession0"
0:00:04.598157882 12852 0x7f0bcc0044c0 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'qtdemux0' changed state to 4(PLAYING) successfully
0:00:04.598184710 12852 0x7f0bcc0044c0 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<vin> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:04.598187068 12852 0x7f0bcc003f60 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<rtpsession0> adding pad 'send_rtp_sink'
0:00:04.598199776 12852 0x7f0bcc0044c0 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<vin> completed state change to PLAYING
0:00:04.598218582 12852 0x7f0bcc0044c0 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<vin> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:04.598222985 12852 0x7f0bcc003f60 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<rtpsession0> adding pad 'send_rtp_src'
0:00:04.598235832 12852 0x7f0bcc003f60 INFO        GST_ELEMENT_PADS gstelement.c:928:gst_element_get_static_pad: found pad rtpsession0:send_rtp_src
0:00:04.598238106 12852 0x7f0bcc0044c0 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'vin' changed state to 4(PLAYING) successfully
0:00:04.598258140 12852 0x7f0bcc0044c0 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<pipeline0> completed state change to PLAYING
0:00:04.598268849 12852 0x7f0bcc0044c0 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<pipeline0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:04.598275289 12852 0x7f0bcc003f60 INFO                GST_PADS gstpad.c:2377:gst_pad_link_prepare: trying to link rtpsession0:send_rtp_src and send_rtp_src_0:proxypad1
0:00:04.598292296 12852 0x7f0bcc003f60 INFO                GST_PADS gstpad.c:2585:gst_pad_link_full: linked rtpsession0:send_rtp_src and send_rtp_src_0:proxypad1, successful
0:00:04.598300961 12852 0x7f0bcc003f60 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:04.598324221 12852 0x7f0bcc003f60 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<rtpbin0> adding pad 'send_rtp_src_0'
0:00:04.598351644 12852 0x7f0bcc003f60 INFO                GST_PADS gstpad.c:2377:gst_pad_link_prepare: trying to link send_rtp_sink_0:proxypad2 and rtpsession0:send_rtp_sink
0:00:04.598362929 12852 0x7f0bcc003f60 INFO                GST_PADS gstpad.c:2585:gst_pad_link_full: linked send_rtp_sink_0:proxypad2 and rtpsession0:send_rtp_sink, successful
0:00:04.598370811 12852 0x7f0bcc003f60 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:04.598388215 12852 0x7f0bcc003f60 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<rtpbin0> adding pad 'send_rtp_sink_0'
0:00:04.598412566 12852 0x7f0bcc003f60 INFO                GST_PADS gstpad.c:2377:gst_pad_link_prepare: trying to link bin0:src_0 and rtpbin0:send_rtp_sink_0
0:00:04.598439900 12852 0x7f0bcc003f60 INFO                GST_PADS gstpad.c:2585:gst_pad_link_full: linked bin0:src_0 and rtpbin0:send_rtp_sink_0, successful
0:00:04.598448702 12852 0x7f0bcc003f60 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:04.598462568 12852 0x7f0bcc003f60 INFO               GST_EVENT gstpad.c:5812:gst_pad_send_event_unchecked:<pay0:src> Received event on flushing pad. Discarding
0:00:04.598478926 12852 0x7f0bcc003f60 INFO        GST_ELEMENT_PADS gstelement.c:928:gst_element_get_static_pad: found pad rtpbin0:send_rtp_src_0
0:00:04.598490731 12852 0x7f0bcc003f60 INFO        GST_ELEMENT_PADS gstelement.c:925:gst_element_get_static_pad: no such pad 'send_rtcp_src_0' in element "rtpbin0"
0:00:04.598503459 12852 0x7f0bcc003f60 INFO        GST_ELEMENT_PADS gstelement.c:925:gst_element_get_static_pad: no such pad 'send_rtcp_src' in element "rtpsession0"
0:00:04.598529135 12852 0x7f0bcc003f60 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<rtpsession0> adding pad 'send_rtcp_src'
0:00:04.598562406 12852 0x7f0bcc003f60 INFO                GST_PADS gstpad.c:2377:gst_pad_link_prepare: trying to link rtpsession0:send_rtcp_src and send_rtcp_src_0:proxypad3
0:00:04.598574094 12852 0x7f0bcc003f60 INFO                GST_PADS gstpad.c:2585:gst_pad_link_full: linked rtpsession0:send_rtcp_src and send_rtcp_src_0:proxypad3, successful
0:00:04.598581915 12852 0x7f0bcc003f60 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:04.598598866 12852 0x7f0bcc003f60 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<rtpbin0> adding pad 'send_rtcp_src_0'
0:00:04.598611890 12852 0x7f0bcc003f60 INFO        GST_ELEMENT_PADS gstelement.c:925:gst_element_get_static_pad: no such pad 'recv_rtcp_sink_0' in element "rtpbin0"
0:00:04.598624207 12852 0x7f0bcc003f60 INFO        GST_ELEMENT_PADS gstelement.c:925:gst_element_get_static_pad: no such pad 'recv_rtcp_sink' in element "rtpsession0"
0:00:04.598648045 12852 0x7f0bcc003f60 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<rtpsession0> adding pad 'recv_rtcp_sink'
0:00:04.598683469 12852 0x7f0bcc003f60 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<rtpsession0> adding pad 'sync_src'
0:00:04.598703251 12852 0x7f0bcc003f60 INFO        GST_ELEMENT_PADS gstelement.c:928:gst_element_get_static_pad: found pad rtpsession0:sync_src
0:00:04.598713817 12852 0x7f0bcc003f60 INFO        GST_ELEMENT_PADS gstelement.c:928:gst_element_get_static_pad: found pad rtpssrcdemux0:rtcp_sink
0:00:04.598727761 12852 0x7f0bcc003f60 INFO                GST_PADS gstpad.c:2377:gst_pad_link_prepare: trying to link rtpsession0:sync_src and rtpssrcdemux0:rtcp_sink
0:00:04.598738656 12852 0x7f0bcc003f60 INFO                GST_PADS gstpad.c:2585:gst_pad_link_full: linked rtpsession0:sync_src and rtpssrcdemux0:rtcp_sink, successful
0:00:04.598746466 12852 0x7f0bcc003f60 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:04.598778126 12852 0x7f0bcc003f60 INFO                GST_PADS gstpad.c:2377:gst_pad_link_prepare: trying to link recv_rtcp_sink_0:proxypad4 and rtpsession0:recv_rtcp_sink
0:00:04.598796247 12852 0x7f0bcc003f60 INFO                GST_PADS gstpad.c:2585:gst_pad_link_full: linked recv_rtcp_sink_0:proxypad4 and rtpsession0:recv_rtcp_sink, successful
0:00:04.598808910 12852 0x7f0bcc003f60 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:04.598835880 12852 0x7f0bcc003f60 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<rtpbin0> adding pad 'recv_rtcp_sink_0'
0:00:04.598877316 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3218:start_preroll: setting pipeline to PAUSED for media 0x7f0bcc02d1f0
0:00:04.598896009 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:2904:set_target_state: set target state to PAUSED for media 0x7f0bcc02d1f0
0:00:04.598906640 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:2891:set_state: set state to PAUSED for media 0x7f0bcc02d1f0
0:00:04.598927910 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<rtpbin0> current NULL pending VOID_PENDING, desired next READY
0:00:04.598955001 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<rtpstorage0> current NULL pending VOID_PENDING, desired next READY
0:00:04.598966659 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<rtpstorage0> completed state change to READY
0:00:04.598977182 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<rtpstorage0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:04.598998893 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<rtpbin0> child 'rtpstorage0' changed state to 2(READY) successfully
0:00:04.599011987 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<rtpssrcdemux0> current NULL pending VOID_PENDING, desired next READY
0:00:04.599022770 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<rtpssrcdemux0> completed state change to READY
0:00:04.599032709 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<rtpssrcdemux0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:04.599046186 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<rtpbin0> child 'rtpssrcdemux0' changed state to 2(READY) successfully
0:00:04.599058127 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<rtpsession0> current NULL pending VOID_PENDING, desired next READY
0:00:04.599068695 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<rtpsession0> completed state change to READY
0:00:04.599078473 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<rtpsession0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:04.599091657 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<rtpbin0> child 'rtpsession0' changed state to 2(READY) successfully
0:00:04.599102788 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<rtpbin0> completed state change to READY
0:00:04.599112395 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<rtpbin0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:04.599125960 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<media-pipeline> child 'rtpbin0' changed state to 2(READY) successfully
0:00:04.599137013 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<bin0> current NULL pending VOID_PENDING, desired next READY
0:00:04.599158529 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<pay0> current NULL pending VOID_PENDING, desired next READY
0:00:04.599170110 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<pay0> completed state change to READY
0:00:04.599180190 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<pay0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:04.599194202 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<bin0> child 'pay0' changed state to 2(READY) successfully
0:00:04.599206393 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<h264parse0> current NULL pending VOID_PENDING, desired next READY
0:00:04.599217888 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<h264parse0> completed state change to READY
0:00:04.599234600 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<h264parse0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:04.599257478 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<bin0> child 'h264parse0' changed state to 2(READY) successfully
0:00:04.599277919 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<videosrc> current NULL pending VOID_PENDING, desired next READY
0:00:04.599290547 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<videosrc> completed state change to READY
0:00:04.599300314 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<videosrc> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:04.599314441 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<bin0> child 'videosrc' changed state to 2(READY) successfully
0:00:04.599325677 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<bin0> completed state change to READY
0:00:04.599335413 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<bin0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:04.599349122 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<media-pipeline> child 'bin0' changed state to 2(READY) successfully
0:00:04.599361071 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2660:gst_element_continue_state:<media-pipeline> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:04.599371128 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<media-pipeline> notifying about state-changed NULL to READY (PAUSED pending)
0:00:04.599382963 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2668:gst_element_continue_state:<media-pipeline> continue state change READY to PAUSED, final PAUSED
0:00:04.599398713 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<rtpbin0> current READY pending VOID_PENDING, desired next PAUSED
0:00:04.599419788 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<rtpstorage0> current READY pending VOID_PENDING, desired next PAUSED
0:00:04.599434193 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<rtpstorage0> completed state change to PAUSED
0:00:04.599444281 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<rtpstorage0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:04.599458515 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<rtpbin0> child 'rtpstorage0' changed state to 3(PAUSED) successfully
0:00:04.599470606 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<rtpssrcdemux0> current READY pending VOID_PENDING, desired next PAUSED
0:00:04.599484918 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<rtpssrcdemux0> completed state change to PAUSED
0:00:04.599494851 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<rtpssrcdemux0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:04.599508591 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<rtpbin0> child 'rtpssrcdemux0' changed state to 3(PAUSED) successfully
0:00:04.599520394 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<rtpsession0> current READY pending VOID_PENDING, desired next PAUSED
0:00:04.599533632 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<rtpsession0> completed state change to PAUSED
0:00:04.599543411 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<rtpsession0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:04.599557659 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<rtpbin0> child 'rtpsession0' changed state to 3(PAUSED) successfully
0:00:04.599570347 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<rtpbin0> completed state change to PAUSED
0:00:04.599580381 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<rtpbin0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:04.599593782 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<media-pipeline> child 'rtpbin0' changed state to 3(PAUSED) successfully
0:00:04.599609752 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<bin0> current READY pending VOID_PENDING, desired next PAUSED
0:00:04.599628884 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<pay0> current READY pending VOID_PENDING, desired next PAUSED
0:00:04.599649922 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<pay0> completed state change to PAUSED
0:00:04.599660063 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<pay0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:04.599673905 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<bin0> child 'pay0' changed state to 3(PAUSED) successfully
0:00:04.599686135 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<h264parse0> current READY pending VOID_PENDING, desired next PAUSED
0:00:04.599935893 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<h264parse0> completed state change to PAUSED
0:00:04.599958423 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<h264parse0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:04.599969370 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<bin0> child 'h264parse0' changed state to 3(PAUSED) successfully
0:00:04.599977591 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<videosrc> current READY pending VOID_PENDING, desired next PAUSED
0:00:04.599995127 12852 0x7f0bcc003f60 INFO                 basesrc gstbasesrc.c:1373:gst_base_src_do_seek:<videosrc> 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:04.600009366 12852 0x7f0bcc003f60 INFO                    task gsttask.c:460:gst_task_set_lock: setting stream lock 0x7f0bcc00e1f0 on task 0x7f0bc4003dd0
0:00:04.600016092 12852 0x7f0bcc003f60 INFO                GST_PADS gstpad.c:6159:gst_pad_start_task:<videosrc:src> created task 0x7f0bc4003dd0
0:00:04.600064502 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<videosrc> completed state change to PAUSED
0:00:04.600073363 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<videosrc> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:04.600083619 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<bin0> child 'videosrc' changed state to 3(PAUSED) successfully
0:00:04.600092603 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<bin0> completed state change to PAUSED
0:00:04.600100763 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<bin0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:04.600110274 12852 0x7f0bcc003f60 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<media-pipeline> child 'bin0' changed state to 3(PAUSED) successfully
0:00:04.600118112 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<media-pipeline> completed state change to PAUSED
0:00:04.600112775 12852 0x7f0bc800d860 INFO        GST_ELEMENT_PADS gstelement.c:925:gst_element_get_static_pad: no such pad 'sink' in element "videosrc"
0:00:04.600126042 12852 0x7f0bcc003f60 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<media-pipeline> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:04.600139037 12852 0x7f0bc800d860 FIXME                default gstutils.c:3980:gst_pad_create_stream_id_internal:<videosrc:src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id
0:00:04.600146753 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3226:start_preroll: SUCCESS state change for media 0x7f0bcc02d1f0
0:00:04.600188859 12852 0x7f0bc800d860 INFO               GST_EVENT gstevent.c:820:gst_event_new_caps: creating caps event video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, width=(int)2880, height=(int)1860, framerate=(fraction)30/1
0:00:04.600261497 12852 0x7f0bc800d860 INFO               GST_EVENT gstevent.c:900:gst_event_new_segment: creating segment event time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=99:99:99.999999999, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.000000000, duration 99:99:99.999999999
0:00:04.600275995 12852 0x7f0bc800d860 INFO                 basesrc gstbasesrc.c:2962:gst_base_src_loop:<videosrc> marking pending DISCONT
0:00:04.600336989 12852 0x7f0bc800d860 INFO               h264parse gsth264parse.c:1938:gst_h264_parse_update_src_caps:<h264parse0> pixel aspect ratio has been changed 1/1
0:00:04.600347563 12852 0x7f0bc800d860 INFO               h264parse gsth264parse.c:1975:gst_h264_parse_update_src_caps:<h264parse0> PAR 1/1
0:00:04.600359313 12852 0x7f0bc800d860 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:04.600405714 12852 0x7f0bc800d860 INFO               GST_EVENT gstevent.c:820:gst_event_new_caps: creating caps event video/x-h264, stream-format=(string)avc, alignment=(string)au, width=(int)2880, height=(int)1860, framerate=(fraction)30/1, pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)progressive, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, parsed=(boolean)true, profile=(string)high, level=(string)5.1, codec_data=(buffer)01640033ffe1001f67640033acd9402d00ebf9f016e04040a8000003000800000301e478c18cb001000568ebecb22c
0:00:04.600499803 12852 0x7f0bc800d860 INFO               GST_EVENT gstevent.c:820:gst_event_new_caps: creating caps event application/x-rtp, media=(string)video, clock-rate=(int)90000, encoding-name=(string)H264, packetization-mode=(string)1, profile-level-id=(string)640033, sprop-parameter-sets=(string)"Z2QAM6zZQC0A6/nwFuBAQKgAAAMACAAAAwHkeMGMsA\=\=\,aOvssiw\=", payload=(int)96, ssrc=(uint)27775101, timestamp-offset=(uint)2892889663, seqnum-offset=(uint)30153, a-framerate=(string)30
0:00:04.600520684 12852 0x7f0bc800d860 INFO                GST_PADS gstpad.c:4237:gst_pad_peer_query:<rtpbin0:send_rtp_src_0> pad has no peer
0:00:04.600535543 12852 0x7f0bc800d860 INFO                GST_PADS gstpad.c:4237:gst_pad_peer_query:<rtpbin0:send_rtp_src_0> pad has no peer
0:00:04.600547477 12852 0x7f0bc800d860 INFO                GST_PADS gstpad.c:4237:gst_pad_peer_query:<rtpbin0:send_rtp_src_0> pad has no peer
0:00:04.600602805 12852 0x7f0bc800d860 INFO              rtspstream rtsp-stream.c:2413:on_new_sender_ssrc: 0x7f0bcc038310: new sender source 0x7f0bc0016110
0:00:04.600632529 12852 0x7f0bc800d860 INFO              rtspstream rtsp-stream.c:2247:dump_structure: structure: application/x-rtp-source-stats, ssrc=(uint)27775101, internal=(boolean)true, validated=(boolean)true, received-bye=(boolean)false, is-csrc=(boolean)false, is-sender=(boolean)false, seqnum-base=(int)30153, clock-rate=(int)90000, octets-sent=(guint64)0, packets-sent=(guint64)0, octets-received=(guint64)0, packets-received=(guint64)0, bitrate=(guint64)0, packets-lost=(int)0, jitter=(uint)0, sent-pli-count=(uint)0, recv-pli-count=(uint)0, sent-fir-count=(uint)0, recv-fir-count=(uint)0, sent-nack-count=(uint)0, recv-nack-count=(uint)0, have-sr=(boolean)false, sr-ntptime=(guint64)0, sr-rtptime=(uint)0, sr-octet-count=(uint)0, sr-packet-count=(uint)0;
0:00:04.600648333 12852 0x7f0bc800d860 INFO                GST_PADS gstpad.c:4237:gst_pad_peer_query:<rtpbin0:send_rtp_src_0> pad has no peer
0:00:04.600671761 12852 0x7f0bc800d860 INFO              rtspstream rtsp-stream.c:2229:caps_notify: stream 0x7f0bcc038310 received caps 0x7f0bc0003d40, application/x-rtp, media=(string)video, clock-rate=(int)90000, encoding-name=(string)H264, packetization-mode=(string)1, profile-level-id=(string)640033, sprop-parameter-sets=(string)"Z2QAM6zZQC0A6/nwFuBAQKgAAAMACAAAAwHkeMGMsA\=\=\,aOvssiw\=", payload=(int)96, ssrc=(uint)27775101, timestamp-offset=(uint)2892889663, seqnum-offset=(uint)30153, a-framerate=(string)30
0:00:04.600798961 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:841:collect_media_stats: collect media stats
0:00:04.600820629 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:658:do_query_position:<GstRTSPStream@0x7f0bcc038310> media position: 0:00:00.000000000
0:00:04.600831914 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:876:collect_media_stats: stats: position 0:00:00.000000000, stop 99:99:99.999999999
0:00:04.600866456 12852 0x55e10a462060 INFO               rtspmedia rtsp-media.c:3598:gst_rtsp_media_prepare: object 0x7f0bcc02d1f0 is prerolled
0:00:04.600903932 12852 0x55e10a462060 INFO               rtspmedia rtsp-media.c:841:collect_media_stats: collect media stats
0:00:04.600915045 12852 0x55e10a462060 INFO               rtspmedia rtsp-media.c:658:do_query_position:<GstRTSPStream@0x7f0bcc038310> media position: 0:00:00.000000000
0:00:04.600923262 12852 0x55e10a462060 INFO               rtspmedia rtsp-media.c:876:collect_media_stats: stats: position 0:00:00.000000000, stop 99:99:99.999999999
0:00:04.600979870 12852 0x55e10a462060 INFO                 default gstmikey.c:2358:gst_mikey_message_new_from_caps: No srtp key
0:00:04.601005060 12852 0x55e10a462060 FIXME              rtspmedia rtsp-media.c:4201:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
0:00:04.601018253 12852 0x55e10a462060 INFO              rtspclient rtsp-client.c:2920:handle_describe_request: adding content-base: rtsp://127.0.0.1:8554/test/
0:00:04.603099281 12852 0x55e10a462060 INFO              rtspclient rtsp-client.c:3533:handle_request: client 0x55e10a45e230: received a request SETUP rtsp://127.0.0.1:8554/test/stream=0 1.0
0:00:04.603118368 12852 0x55e10a462060 INFO         rtspmountpoints rtsp-mount-points.c:300:gst_rtsp_mount_points_match: found media factory 0x55e10a42da70 for path /test/stream=0
0:00:04.603128155 12852 0x55e10a462060 INFO              rtspclient rtsp-client.c:1022:find_media: reusing cached media 0x7f0bcc02d1f0 for path /test
0:00:04.603137414 12852 0x55e10a462060 FIXME              rtspmedia rtsp-media.c:4201:gst_rtsp_media_suspend: suspend for dynamic pipelines needs fixing
0:00:04.603146479 12852 0x55e10a462060 WARN               rtspmedia rtsp-media.c:4227:gst_rtsp_media_suspend: media 0x7f0bcc02d1f0 was not prepared
0:00:04.603187043 12852 0x55e10a462060 INFO             rtspsession rtsp-session.c:132:gst_rtsp_session_init: init session 0x7f0bc001feb0
0:00:04.603204617 12852 0x55e10a462060 INFO              rtspclient rtsp-client.c:661:client_watch_session: watching session 0x7f0bc001feb0
0:00:04.603228377 12852 0x55e10a462060 INFO              rtspclient rtsp-client.c:1983:parse_transport: found valid transport RTP/AVP;unicast;client_port=32914-32915
0:00:04.603246189 12852 0x55e10a462060 INFO             rtspsession rtsp-session.c:247:gst_rtsp_session_manage_media: manage new media 0x7f0bcc02d1f0 in session 0x7f0bcc05d5b0
0:00:04.606550624 12852 0x55e10a462060 INFO              rtspclient rtsp-client.c:3533:handle_request: client 0x55e10a45e230: received a request PLAY rtsp://127.0.0.1:8554/test/ 1.0
0:00:04.606575096 12852 0x55e10a462060 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "funnel"
0:00:04.606619831 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstFunnel@0x7f0bcc0157e0> adding pad 'src'
0:00:04.606644925 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:928:gst_element_get_static_pad: found pad funnel0:src
0:00:04.606659721 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:2377:gst_pad_link_prepare: trying to link funnel0:src and rtpbin0:recv_rtcp_sink_0
0:00:04.606680739 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:2585:gst_pad_link_full: linked funnel0:src and rtpbin0:recv_rtcp_sink_0, successful
0:00:04.606690200 12852 0x55e10a462060 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:04.606699860 12852 0x55e10a462060 INFO               GST_EVENT gstpad.c:5812:gst_pad_send_event_unchecked:<funnel0:src> Received event on flushing pad. Discarding
0:00:04.607030969 12852 0x55e10a462060 INFO      GST_PLUGIN_LOADING gstplugin.c:902:_priv_gst_plugin_load_file_for_registry: plugin "/usr/lib/x86_64-linux-gnu/gstreamer-1.0/libgstudp.so" loaded
0:00:04.607047664 12852 0x55e10a462060 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "udpsrc"
0:00:04.607159812 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstBaseSrc@0x7f0bcc067910> adding pad 'src'
0:00:04.607220705 12852 0x55e10a462060 INFO                  udpsrc gstudpsrc.c:1459:gst_udpsrc_open:<udpsrc0> have udp buffer of 106496 bytes
0:00:04.607252116 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<udpsrc0> completed state change to READY
0:00:04.607281697 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<udpsrc0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:04.607313729 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:928:gst_element_get_static_pad: found pad udpsrc0:src
0:00:04.607346969 12852 0x55e10a462060 INFO                 basesrc gstbasesrc.c:1373:gst_base_src_do_seek:<udpsrc0> 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:04.607370696 12852 0x55e10a462060 INFO                    task gsttask.c:460:gst_task_set_lock: setting stream lock 0x7f0bcc00fb60 on task 0x7f0bcc015950
0:00:04.607383418 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:6159:gst_pad_start_task:<udpsrc0:src> created task 0x7f0bcc015950
0:00:04.607498469 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2660:gst_element_continue_state:<udpsrc0> committing state from READY to PAUSED, pending PLAYING, next PLAYING
0:00:04.607523681 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<udpsrc0> notifying about state-changed READY to PAUSED (PLAYING pending)
0:00:04.607545363 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2668:gst_element_continue_state:<udpsrc0> continue state change PAUSED to PLAYING, final PLAYING
0:00:04.607557999 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<udpsrc0> completed state change to PLAYING
0:00:04.607568728 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<udpsrc0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:04.607577282 12852 0x7f0bcc004400 INFO        GST_ELEMENT_PADS gstelement.c:925:gst_element_get_static_pad: no such pad 'sink' in element "udpsrc0"
0:00:04.607636664 12852 0x7f0bcc004400 INFO                GST_PADS gstpad.c:4237:gst_pad_peer_query:<udpsrc0:src> pad has no peer
0:00:04.607641345 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<funnel0> adding pad 'funnelpad0'
0:00:04.607673584 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:2377:gst_pad_link_prepare: trying to link udpsrc0:src and funnel0:funnelpad0
0:00:04.607702685 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:2585:gst_pad_link_full: linked udpsrc0:src and funnel0:funnelpad0, successful
0:00:04.607710802 12852 0x55e10a462060 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:04.607730289 12852 0x55e10a462060 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "udpsrc"
0:00:04.607760224 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstBaseSrc@0x7f0bcc069ae0> adding pad 'src'
0:00:04.607803147 12852 0x55e10a462060 INFO                  udpsrc gstudpsrc.c:1459:gst_udpsrc_open:<udpsrc1> have udp buffer of 106496 bytes
0:00:04.607823688 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<udpsrc1> completed state change to READY
0:00:04.607834355 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<udpsrc1> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:04.607855578 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:928:gst_element_get_static_pad: found pad udpsrc1:src
0:00:04.607884672 12852 0x55e10a462060 INFO                 basesrc gstbasesrc.c:1373:gst_base_src_do_seek:<udpsrc1> 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:04.607935137 12852 0x55e10a462060 INFO                    task gsttask.c:460:gst_task_set_lock: setting stream lock 0x7f0bc801a910 on task 0x7f0bcc015a70
0:00:04.607944855 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:6159:gst_pad_start_task:<udpsrc1:src> created task 0x7f0bcc015a70
0:00:04.608029573 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2660:gst_element_continue_state:<udpsrc1> committing state from READY to PAUSED, pending PLAYING, next PLAYING
0:00:04.608044375 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<udpsrc1> notifying about state-changed READY to PAUSED (PLAYING pending)
0:00:04.608082586 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2668:gst_element_continue_state:<udpsrc1> continue state change PAUSED to PLAYING, final PLAYING
0:00:04.608096357 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<udpsrc1> completed state change to PLAYING
0:00:04.608112150 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<udpsrc1> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:04.608147376 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<funnel0> adding pad 'funnelpad1'
0:00:04.608162566 12852 0x7f0bcc003d20 INFO        GST_ELEMENT_PADS gstelement.c:925:gst_element_get_static_pad: no such pad 'sink' in element "udpsrc1"
0:00:04.608195711 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:2377:gst_pad_link_prepare: trying to link udpsrc1:src and funnel0:funnelpad1
0:00:04.608218046 12852 0x7f0bcc003d20 INFO                GST_PADS gstpad.c:4237:gst_pad_peer_query:<udpsrc1:src> pad has no peer
0:00:04.608242829 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:2585:gst_pad_link_full: linked udpsrc1:src and funnel0:funnelpad1, successful
0:00:04.608252639 12852 0x55e10a462060 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:04.608273561 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2660:gst_element_continue_state:<funnel0> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:04.608283294 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<funnel0> notifying about state-changed NULL to READY (PAUSED pending)
0:00:04.608300102 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2668:gst_element_continue_state:<funnel0> continue state change READY to PAUSED, final PAUSED
0:00:04.608312830 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<funnel0> completed state change to PAUSED
0:00:04.608321683 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<funnel0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:04.608337678 12852 0x55e10a462060 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "tee"
0:00:04.608426647 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstTee@0x55e10a46aa80> adding pad 'sink'
0:00:04.608461336 12852 0x55e10a462060 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "multiudpsink"
0:00:04.608543938 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstBaseSink@0x7f0bcc06cae0> adding pad 'sink'
0:00:04.608619812 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<tee0> adding pad 'src_0'
0:00:04.608637426 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:928:gst_element_get_static_pad: found pad multiudpsink0:sink
0:00:04.608659105 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:2377:gst_pad_link_prepare: trying to link tee0:src_0 and multiudpsink0:sink
0:00:04.608676426 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:4237:gst_pad_peer_query:<tee0:sink> pad has no peer
0:00:04.608698756 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:2585:gst_pad_link_full: linked tee0:src_0 and multiudpsink0:sink, successful
0:00:04.608707995 12852 0x55e10a462060 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:04.608717309 12852 0x55e10a462060 INFO               GST_EVENT gstpad.c:5812:gst_pad_send_event_unchecked:<tee0:src_0> Received event on flushing pad. Discarding
0:00:04.608757433 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2660:gst_element_continue_state:<multiudpsink0> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:04.608770113 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<multiudpsink0> notifying about state-changed NULL to READY (PAUSED pending)
0:00:04.608788490 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2668:gst_element_continue_state:<multiudpsink0> continue state change READY to PAUSED, final PAUSED
0:00:04.608800911 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<media-pipeline> notifying about state-changed PAUSED to PAUSED (PAUSED pending)
0:00:04.608833652 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2660:gst_element_continue_state:<tee0> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:04.608843866 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<tee0> notifying about state-changed NULL to READY (PAUSED pending)
0:00:04.608868999 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2668:gst_element_continue_state:<tee0> continue state change READY to PAUSED, final PAUSED
0:00:04.608883464 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<tee0> completed state change to PAUSED
0:00:04.608893581 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<tee0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:04.608906055 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:928:gst_element_get_static_pad: found pad tee0:sink
0:00:04.608920048 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:2377:gst_pad_link_prepare: trying to link rtpbin0:send_rtp_src_0 and tee0:sink
0:00:04.608962988 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:2585:gst_pad_link_full: linked rtpbin0:send_rtp_src_0 and tee0:sink, successful
0:00:04.608972211 12852 0x55e10a462060 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:04.608998477 12852 0x55e10a462060 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "tee"
0:00:04.609025255 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstTee@0x55e10a46abd0> adding pad 'sink'
0:00:04.609061436 12852 0x55e10a462060 INFO     GST_ELEMENT_FACTORY gstelementfactory.c:363:gst_element_factory_create: creating element "multiudpsink"
0:00:04.609081423 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<GstBaseSink@0x7f0bcc06f5f0> adding pad 'sink'
0:00:04.609132191 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<tee1> adding pad 'src_0'
0:00:04.609152878 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:928:gst_element_get_static_pad: found pad multiudpsink1:sink
0:00:04.609173626 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:2377:gst_pad_link_prepare: trying to link tee1:src_0 and multiudpsink1:sink
0:00:04.609190467 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:4237:gst_pad_peer_query:<tee1:sink> pad has no peer
0:00:04.609211444 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:2585:gst_pad_link_full: linked tee1:src_0 and multiudpsink1:sink, successful
0:00:04.609221446 12852 0x55e10a462060 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:04.609236267 12852 0x55e10a462060 INFO               GST_EVENT gstpad.c:5812:gst_pad_send_event_unchecked:<tee1:src_0> Received event on flushing pad. Discarding
0:00:04.609267675 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2660:gst_element_continue_state:<multiudpsink1> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:04.609282233 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<multiudpsink1> notifying about state-changed NULL to READY (PAUSED pending)
0:00:04.609300254 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2668:gst_element_continue_state:<multiudpsink1> continue state change READY to PAUSED, final PAUSED
0:00:04.609318892 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<multiudpsink1> completed state change to PAUSED
0:00:04.609331967 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<multiudpsink1> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:04.609352815 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2660:gst_element_continue_state:<tee1> committing state from NULL to READY, pending PAUSED, next PAUSED
0:00:04.609367791 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<tee1> notifying about state-changed NULL to READY (PAUSED pending)
0:00:04.609385152 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2668:gst_element_continue_state:<tee1> continue state change READY to PAUSED, final PAUSED
0:00:04.609403471 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<tee1> completed state change to PAUSED
0:00:04.609416137 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<tee1> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:04.609433762 12852 0x55e10a462060 INFO        GST_ELEMENT_PADS gstelement.c:928:gst_element_get_static_pad: found pad tee1:sink
0:00:04.609451653 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:2377:gst_pad_link_prepare: trying to link rtpbin0:send_rtcp_src_0 and tee1:sink
0:00:04.609478895 12852 0x55e10a462060 INFO                GST_PADS gstpad.c:2585:gst_pad_link_full: linked rtpbin0:send_rtcp_src_0 and tee1:sink, successful
0:00:04.609489090 12852 0x55e10a462060 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event
0:00:04.609623406 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:04.609626485 12852 0x7f0bc800d860 INFO              GST_STATES gstbin.c:3421:bin_handle_async_done:<media-pipeline> committing state from PAUSED to PAUSED, old pending PAUSED
0:00:04.609647928 12852 0x7f0bc800d860 INFO              GST_STATES gstbin.c:3444:bin_handle_async_done:<media-pipeline> completed state change, pending VOID
0:00:04.609658053 12852 0x7f0bc800d860 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<media-pipeline> notifying about state-changed PAUSED to PAUSED (VOID_PENDING pending)
0:00:04.609738609 12852 0x55e10a462060 FIXME             rtspclient rtsp-client.c:1818:handle_play_request:<GstRTSPClient@0x55e10a45e230> Add support for seek style (null)
0:00:04.609778074 12852 0x55e10a462060 FIXME              rtspmedia rtsp-media.c:2711:gst_rtsp_media_seek_full:<GstRTSPMedia@0x7f0bcc02d1f0> Handle going back to 0 for none live not seekable streams.
0:00:04.609787298 12852 0x55e10a462060 INFO               rtspmedia rtsp-media.c:2827:gst_rtsp_media_seek_full: pipeline is not seekable
0:00:04.609832259 12852 0x55e10a462060 INFO               rtspmedia rtsp-media.c:841:collect_media_stats: collect media stats
0:00:04.609853285 12852 0x55e10a462060 INFO               rtspmedia rtsp-media.c:658:do_query_position:<GstRTSPStream@0x7f0bcc038310> media position: 0:00:00.000000000
0:00:04.609879808 12852 0x55e10a462060 INFO               rtspmedia rtsp-media.c:876:collect_media_stats: stats: position 0:00:00.000000000, stop 99:99:99.999999999
0:00:04.609957706 12852 0x55e10a462060 INFO               rtspmedia rtsp-media.c:4441:gst_rtsp_media_set_state: going to state PLAYING media 0x7f0bcc02d1f0, target state PAUSED
0:00:04.609970752 12852 0x55e10a462060 INFO              rtspstream rtsp-stream.c:4373:update_transport: adding 127.0.0.1:32914-32915
0:00:04.610019476 12852 0x55e10a462060 INFO               rtspmedia rtsp-media.c:4495:gst_rtsp_media_set_state: state 4 active 1 media 0x7f0bcc02d1f0 do_state 1
0:00:04.610028655 12852 0x55e10a462060 INFO               rtspmedia rtsp-media.c:4357:media_set_pipeline_state_locked: state PLAYING media 0x7f0bcc02d1f0
0:00:04.610036307 12852 0x55e10a462060 INFO               rtspmedia rtsp-media.c:2904:set_target_state: set target state to PLAYING for media 0x7f0bcc02d1f0
0:00:04.610045503 12852 0x55e10a462060 INFO               rtspmedia rtsp-media.c:2891:set_state: set state to PLAYING for media 0x7f0bcc02d1f0
0:00:04.610126447 12852 0x55e10a462060 INFO               GST_EVENT gstevent.c:1449:gst_event_new_latency: creating latency event 0:00:00.000000000
0:00:04.610157342 12852 0x55e10a462060 INFO                     bin gstbin.c:2783:gst_bin_do_latency_func:<media-pipeline> configured latency of 0:00:00.000000000
0:00:04.610179472 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<multiudpsink1> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:04.610190617 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<multiudpsink1> completed state change to PLAYING
0:00:04.610199637 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<multiudpsink1> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:04.610216545 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<media-pipeline> child 'multiudpsink1' changed state to 4(PLAYING) successfully
0:00:04.610228017 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<multiudpsink0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:04.610240467 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<multiudpsink0> completed state change to PLAYING
0:00:04.610257240 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<multiudpsink0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:04.610278384 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<media-pipeline> child 'multiudpsink0' changed state to 4(PLAYING) successfully
0:00:04.610298459 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<tee1> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:04.610313869 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<tee1> completed state change to PLAYING
0:00:04.610328541 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<tee1> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:04.610350267 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<media-pipeline> child 'tee1' changed state to 4(PLAYING) successfully
0:00:04.610369845 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<tee0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:04.610394827 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<tee0> completed state change to PLAYING
0:00:04.610424268 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<tee0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:04.610447402 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<media-pipeline> child 'tee0' changed state to 4(PLAYING) successfully
0:00:04.610468284 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<rtpbin0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:04.610494227 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<rtpstorage0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:04.610515458 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<rtpstorage0> completed state change to PLAYING
0:00:04.610525780 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<rtpstorage0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:04.610541066 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<rtpbin0> child 'rtpstorage0' changed state to 4(PLAYING) successfully
0:00:04.610556005 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<rtpssrcdemux0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:04.610569642 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<rtpssrcdemux0> completed state change to PLAYING
0:00:04.610581959 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<rtpssrcdemux0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:04.610611420 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<rtpbin0> child 'rtpssrcdemux0' changed state to 4(PLAYING) successfully
0:00:04.610631344 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<rtpsession0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:04.610702736 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<rtpsession0> completed state change to PLAYING
0:00:04.610747972 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<rtpsession0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:04.610771408 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<rtpbin0> child 'rtpsession0' changed state to 4(PLAYING) successfully
0:00:04.610788395 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<rtpbin0> completed state change to PLAYING
0:00:04.610802433 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<rtpbin0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:04.610822328 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<media-pipeline> child 'rtpbin0' changed state to 4(PLAYING) successfully
0:00:04.610840745 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<bin0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:04.610864010 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<pay0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:04.610890674 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<pay0> completed state change to PLAYING
0:00:04.610906938 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<pay0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:04.610929581 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<bin0> child 'pay0' changed state to 4(PLAYING) successfully
0:00:04.610942944 12852 0x7f0bc800d860 INFO               GST_EVENT gstevent.c:820:gst_event_new_caps: creating caps event application/x-rtp, media=(string)video, clock-rate=(int)90000, encoding-name=(string)H264, packetization-mode=(string)1, profile-level-id=(string)640033, sprop-parameter-sets=(string)"Z2QAM6zZQC0A6/nwFuBAQKgAAAMACAAAAwHkeMGMsA\=\=\,aOvssiw\=", payload=(int)96, seqnum-offset=(uint)30153, timestamp-offset=(uint)2892889663, ssrc=(uint)27775101, a-framerate=(string)30
0:00:04.610949683 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<h264parse0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:04.610984619 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<h264parse0> completed state change to PLAYING
0:00:04.611002682 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<h264parse0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:04.611041950 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<bin0> child 'h264parse0' changed state to 4(PLAYING) successfully
0:00:04.611062447 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<videosrc> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:04.611077799 12852 0x7f0bc800d860 INFO              rtspstream rtsp-stream.c:2229:caps_notify: stream 0x7f0bcc038310 received caps 0x7f0bc0003e30, application/x-rtp, media=(string)video, clock-rate=(int)90000, encoding-name=(string)H264, packetization-mode=(string)1, profile-level-id=(string)640033, sprop-parameter-sets=(string)"Z2QAM6zZQC0A6/nwFuBAQKgAAAMACAAAAwHkeMGMsA\=\=\,aOvssiw\=", payload=(int)96, seqnum-offset=(uint)30153, timestamp-offset=(uint)2892889663, ssrc=(uint)27775101, a-framerate=(string)30
0:00:04.611080826 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<videosrc> completed state change to PLAYING
0:00:04.611121972 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<videosrc> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:04.611143291 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<bin0> child 'videosrc' changed state to 4(PLAYING) successfully
0:00:04.611172085 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<bin0> completed state change to PLAYING
0:00:04.611190244 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<bin0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:04.611208152 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<media-pipeline> child 'bin0' changed state to 4(PLAYING) successfully
0:00:04.611222497 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<funnel0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:04.611233729 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<funnel0> completed state change to PLAYING
0:00:04.611243659 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<funnel0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:04.611257081 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<media-pipeline> child 'funnel0' changed state to 4(PLAYING) successfully
0:00:04.611268538 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<media-pipeline> child 'udpsrc0' changed state to 4(PLAYING) successfully
0:00:04.611279515 12852 0x55e10a462060 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<media-pipeline> child 'udpsrc1' changed state to 4(PLAYING) successfully
0:00:04.611291316 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<media-pipeline> completed state change to PLAYING
0:00:04.611301100 12852 0x55e10a462060 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<media-pipeline> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:04.611313965 12852 0x55e10a462060 INFO               rtspmedia rtsp-media.c:841:collect_media_stats: collect media stats
0:00:04.611334138 12852 0x55e10a462060 INFO               rtspmedia rtsp-media.c:658:do_query_position:<GstRTSPStream@0x7f0bcc038310> media position: 0:00:00.001272193
0:00:04.611359932 12852 0x55e10a462060 INFO               rtspmedia rtsp-media.c:876:collect_media_stats: stats: position 0:00:00.001272193, stop 99:99:99.999999999
0:00:04.611402644 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 2048 (new-clock)
got message tag
0:00:04.831244169 12852 0x7f0bcc003ea0 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse1> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:04.831345883 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:04.864645626 12852 0x7f0bc800d860 INFO               h264parse gsth264parse.c:1975:gst_h264_parse_update_src_caps:<h264parse0> PAR 1/1
0:00:04.864690023 12852 0x7f0bc800d860 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:04.864887708 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:04.897858146 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:04.931241301 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
got message tag
0:00:04.964672253 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:04.997967043 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.031216529 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.064553246 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.097965950 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.131299975 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.164509824 12852 0x7f0bcc003ea0 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse1> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:05.164563024 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.197882200 12852 0x7f0bc800d860 INFO               h264parse gsth264parse.c:1975:gst_h264_parse_update_src_caps:<h264parse0> PAR 1/1
0:00:05.197919880 12852 0x7f0bc800d860 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:05.198090644 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.231304583 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.264654533 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.297873352 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.331182225 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.364529239 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.397959158 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.431191111 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.464636571 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.497906268 12852 0x7f0bcc003ea0 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse1> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:05.498010888 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.531219746 12852 0x7f0bc800d860 INFO               h264parse gsth264parse.c:1975:gst_h264_parse_update_src_caps:<h264parse0> PAR 1/1
0:00:05.531247730 12852 0x7f0bc800d860 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:05.531378437 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.564595261 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.597924166 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.631261260 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.664618800 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.731302436 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.764554638 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.797867391 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.831144941 12852 0x7f0bcc003ea0 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse1> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:05.831174332 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.864476021 12852 0x7f0bc800d860 INFO               h264parse gsth264parse.c:1975:gst_h264_parse_update_src_caps:<h264parse0> PAR 1/1
0:00:05.864498866 12852 0x7f0bc800d860 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:05.864655626 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.897951048 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.931282997 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.964507684 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:05.992316049 12852 0x7f0bcc06ecc0 INFO               GST_EVENT gstevent.c:820:gst_event_new_caps: creating caps event application/x-rtcp
0:00:05.992381000 12852 0x7f0bcc06ecc0 INFO               GST_EVENT gstevent.c:900:gst_event_new_segment: creating segment event time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=99:99:99.999999999, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.000000000, duration 99:99:99.999999999
0:00:05.992485974 12852 0x7f0bcc06ecc0 INFO              rtspstream rtsp-stream.c:2247:dump_structure: structure: application/x-rtp-source-stats, ssrc=(uint)27775101, internal=(boolean)true, validated=(boolean)true, received-bye=(boolean)false, is-csrc=(boolean)false, is-sender=(boolean)true, seqnum-base=(int)30153, clock-rate=(int)90000, octets-sent=(guint64)181875, packets-sent=(guint64)214, octets-received=(guint64)181875, packets-received=(guint64)214, bitrate=(guint64)0, packets-lost=(int)-214, jitter=(uint)0, sent-pli-count=(uint)0, recv-pli-count=(uint)0, sent-fir-count=(uint)0, recv-fir-count=(uint)0, sent-nack-count=(uint)0, recv-nack-count=(uint)0, have-sr=(boolean)true, sr-ntptime=(guint64)16754578658375731368, sr-rtptime=(uint)2893014056, sr-octet-count=(uint)181875, sr-packet-count=(uint)214;
0:00:05.997943886 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:06.064640554 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:06.131320868 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:06.164501719 12852 0x7f0bcc003ea0 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse1> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:06.197865902 12852 0x7f0bc800d860 INFO               h264parse gsth264parse.c:1975:gst_h264_parse_update_src_caps:<h264parse0> PAR 1/1
0:00:06.197901113 12852 0x7f0bc800d860 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:06.198074394 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:06.264621649 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:06.331284243 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:06.398017969 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:06.464654957 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:06.471322992 12852 0x7f0bcc004400 INFO               GST_EVENT gstevent.c:900:gst_event_new_segment: creating segment event time segment start=0:00:00.000000000, offset=0:00:00.000000000, stop=99:99:99.999999999, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.000000000, duration 99:99:99.999999999
0:00:06.471356177 12852 0x7f0bcc004400 INFO                 basesrc gstbasesrc.c:2962:gst_base_src_loop:<udpsrc0> marking pending DISCONT
0:00:06.471388388 12852 0x7f0bcc004400 INFO               GST_EVENT gstevent.c:820:gst_event_new_caps: creating caps event application/x-rtcp
0:00:06.471473503 12852 0x7f0bcc004400 INFO              rtspstream rtsp-stream.c:2341:on_new_ssrc: 0x7f0bcc038310: new source 0x7f0bc0017710
0:00:06.471574828 12852 0x7f0bcc004400 INFO              rtspstream rtsp-stream.c:2247:dump_structure: structure: application/x-rtp-source-stats, ssrc=(uint)3839457562, internal=(boolean)false, validated=(boolean)false, received-bye=(boolean)false, is-csrc=(boolean)false, is-sender=(boolean)false, seqnum-base=(int)-1, clock-rate=(int)-1, rtcp-from=(string)127.0.0.1:32915, octets-sent=(guint64)0, packets-sent=(guint64)0, octets-received=(guint64)0, packets-received=(guint64)0, bitrate=(guint64)0, packets-lost=(int)0, jitter=(uint)0, sent-pli-count=(uint)0, recv-pli-count=(uint)0, sent-fir-count=(uint)0, recv-fir-count=(uint)0, sent-nack-count=(uint)0, recv-nack-count=(uint)0, have-sr=(boolean)false, sr-ntptime=(guint64)0, sr-rtptime=(uint)0, sr-octet-count=(uint)0, sr-packet-count=(uint)0, sent-rb=(boolean)false, sent-rb-fractionlost=(uint)0, sent-rb-packetslost=(int)0, sent-rb-exthighestseq=(uint)0, sent-rb-jitter=(uint)0, sent-rb-lsr=(uint)0, sent-rb-dlsr=(uint)0, have-rb=(boolean)false, rb-fractionlost=(uint)0, rb-packetslost=(int)0, rb-exthighestseq=(uint)0, rb-jitter=(uint)0, rb-lsr=(uint)0, rb-dlsr=(uint)0, rb-round-trip=(uint)0;
0:00:06.471610623 12852 0x7f0bcc004400 INFO              rtspstream rtsp-stream.c:2272:find_transport: finding 127.0.0.1:32915 in 1 transports
0:00:06.471629576 12852 0x7f0bcc004400 INFO              rtspstream rtsp-stream.c:2324:check_transport: 0x7f0bcc038310: found transport 0x55e10a45f4a0 for source  0x7f0bc0017710
0:00:06.471646828 12852 0x7f0bcc004400 INFO              rtspstream rtsp-stream.c:2346:on_new_ssrc: 0x7f0bcc038310: source 0x7f0bc0017710 for transport 0x55e10a45f4a0
0:00:06.471675347 12852 0x7f0bcc004400 INFO              rtspstream rtsp-stream.c:2363:on_ssrc_active: 0x7f0bcc038310: source 0x7f0bc0017710 in transport 0x55e10a45f4a0 is active
0:00:06.471685282 12852 0x7f0bcc004400 INFO              rtspclient rtsp-client.c:1944:do_keepalive: keep session 0x7f0bc001feb0 alive
0:00:06.471732842 12852 0x7f0bcc004400 INFO              rtspstream rtsp-stream.c:2352:on_ssrc_sdes: 0x7f0bcc038310: new SDES 0x7f0bc0017710
0:00:06.497928351 12852 0x7f0bcc003ea0 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse1> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:06.531246339 12852 0x7f0bc800d860 INFO               h264parse gsth264parse.c:1975:gst_h264_parse_update_src_caps:<h264parse0> PAR 1/1
0:00:06.531293722 12852 0x7f0bc800d860 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:06.531552075 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:06.597990673 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:06.664646718 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:06.731352447 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:06.797895136 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:06.831196589 12852 0x7f0bcc003ea0 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse1> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:06.864492135 12852 0x7f0bc800d860 INFO               h264parse gsth264parse.c:1975:gst_h264_parse_update_src_caps:<h264parse0> PAR 1/1
0:00:06.864528592 12852 0x7f0bc800d860 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:06.864674765 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:06.931268695 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:06.997939833 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:07.097979430 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:07.164521727 12852 0x7f0bcc003ea0 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse1> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:07.197825129 12852 0x7f0bc800d860 INFO               h264parse gsth264parse.c:1975:gst_h264_parse_update_src_caps:<h264parse0> PAR 1/1
0:00:07.197856545 12852 0x7f0bc800d860 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:07.198018921 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:07.331371374 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:07.464657510 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:07.497843721 12852 0x7f0bcc003ea0 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse1> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:07.531248629 12852 0x7f0bc800d860 INFO               h264parse gsth264parse.c:1975:gst_h264_parse_update_src_caps:<h264parse0> PAR 1/1
0:00:07.531297233 12852 0x7f0bc800d860 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:07.531472576 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:07.731262823 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:07.831259512 12852 0x7f0bcc003ea0 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse1> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:07.864575781 12852 0x7f0bc800d860 INFO               h264parse gsth264parse.c:1975:gst_h264_parse_update_src_caps:<h264parse0> PAR 1/1
0:00:07.864628430 12852 0x7f0bc800d860 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:07.864849812 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:07.997953881 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:08.131251009 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:08.164583788 12852 0x7f0bcc003ea0 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse1> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:08.197857038 12852 0x7f0bc800d860 INFO               h264parse gsth264parse.c:1975:gst_h264_parse_update_src_caps:<h264parse0> PAR 1/1
0:00:08.197903741 12852 0x7f0bc800d860 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:08.198072517 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:08.331260244 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:08.464588801 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:08.497944185 12852 0x7f0bcc003ea0 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse1> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:08.531176183 12852 0x7f0bc800d860 INFO               h264parse gsth264parse.c:1975:gst_h264_parse_update_src_caps:<h264parse0> PAR 1/1
0:00:08.531215769 12852 0x7f0bc800d860 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:08.531443761 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:08.664603491 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:08.732114767 12852 0x7f0bcc06ecc0 INFO              rtspstream rtsp-stream.c:2247:dump_structure: structure: application/x-rtp-source-stats, ssrc=(uint)27775101, internal=(boolean)true, validated=(boolean)true, received-bye=(boolean)false, is-csrc=(boolean)false, is-sender=(boolean)true, seqnum-base=(int)30153, clock-rate=(int)90000, octets-sent=(guint64)642694, packets-sent=(guint64)692, octets-received=(guint64)642694, packets-received=(guint64)692, bitrate=(guint64)419100, packets-lost=(int)-692, jitter=(uint)0, sent-pli-count=(uint)0, recv-pli-count=(uint)0, sent-fir-count=(uint)0, recv-fir-count=(uint)0, sent-nack-count=(uint)0, recv-nack-count=(uint)0, have-sr=(boolean)true, sr-ntptime=(guint64)16754578670142747758, sr-rtptime=(uint)2893260631, sr-octet-count=(uint)642694, sr-packet-count=(uint)692;
0:00:08.764725123 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:08.831223095 12852 0x7f0bcc003ea0 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse1> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:08.864509103 12852 0x7f0bc800d860 INFO               h264parse gsth264parse.c:1975:gst_h264_parse_update_src_caps:<h264parse0> PAR 1/1
0:00:08.864546436 12852 0x7f0bc800d860 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:08.864703218 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:08.998058982 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:09.131189491 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:09.164504672 12852 0x7f0bcc003ea0 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse1> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:09.197925742 12852 0x7f0bc800d860 INFO               h264parse gsth264parse.c:1975:gst_h264_parse_update_src_caps:<h264parse0> PAR 1/1
0:00:09.197984542 12852 0x7f0bc800d860 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse0> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:09.198270270 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:09.331316224 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:09.464666815 12852 0x7f0bcc003f60 INFO               rtspmedia rtsp-media.c:3060:default_handle_message: 0x7f0bcc02d1f0: got message type 16 (tag)
0:00:09.531130068 12852 0x7f0bcc003ea0 INFO                    task gsttask.c:312:gst_task_func:<qtdemux0:sink> Task going to paused
GST_MESSAGE_EOS: eos
0:00:09.531201659 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<vid> current PLAYING pending VOID_PENDING, desired next PAUSED
0:00:09.531221376 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<vid> completed state change to PAUSED
0:00:09.531231000 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<vid> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:09.531252980 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'vid' changed state to 3(PAUSED) successfully
0:00:09.531264102 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<h264parse1> current PLAYING pending VOID_PENDING, desired next PAUSED
0:00:09.531272840 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<h264parse1> completed state change to PAUSED
0:00:09.531280539 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<h264parse1> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:09.531291587 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'h264parse1' changed state to 3(PAUSED) successfully
0:00:09.531301072 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<qtdemux0> current PLAYING pending VOID_PENDING, desired next PAUSED
0:00:09.531309456 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<qtdemux0> completed state change to PAUSED
0:00:09.531316906 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<qtdemux0> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:09.531327076 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'qtdemux0' changed state to 3(PAUSED) successfully
0:00:09.531336040 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<vin> current PLAYING pending VOID_PENDING, desired next PAUSED
0:00:09.531344799 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<vin> completed state change to PAUSED
0:00:09.531352082 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<vin> notifying about state-changed PLAYING to PAUSED (VOID_PENDING pending)
0:00:09.531361976 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'vin' changed state to 3(PAUSED) successfully
0:00:09.531373102 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2660:gst_element_continue_state:<pipeline0> committing state from PLAYING to PAUSED, pending NULL, next READY
0:00:09.531381168 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<pipeline0> notifying about state-changed PLAYING to PAUSED (NULL pending)
0:00:09.531391149 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2668:gst_element_continue_state:<pipeline0> continue state change PAUSED to READY, final NULL
0:00:09.531404858 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<vid> current PAUSED pending VOID_PENDING, desired next READY
0:00:09.531431367 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<vid> completed state change to READY
0:00:09.531439941 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<vid> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:09.531450566 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'vid' changed state to 2(READY) successfully
0:00:09.531459905 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<h264parse1> current PAUSED pending VOID_PENDING, desired next READY
0:00:09.531539805 12852 0x55e10a226e00 INFO               baseparse gstbaseparse.c:4843:gst_base_parse_set_upstream_tags:<h264parse1> upstream tags: (NULL)
0:00:09.531551677 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<h264parse1> completed state change to READY
0:00:09.531559613 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<h264parse1> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:09.531570773 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'h264parse1' changed state to 2(READY) successfully
0:00:09.531580448 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<qtdemux0> current PAUSED pending VOID_PENDING, desired next READY
0:00:09.531625556 12852 0x7f0bcc003ea0 INFO                    task gsttask.c:314:gst_task_func:<qtdemux0:sink> Task resume from paused
0:00:09.531679962 12852 0x55e10a226e00 INFO        GST_ELEMENT_PADS gstelement.c:787:gst_element_remove_pad:<qtdemux0> removing pad 'video_0'
0:00:09.531700191 12852 0x55e10a226e00 INFO        GST_ELEMENT_PADS gstpad.c:2132:gst_pad_unlink: unlinking qtdemux0:video_0(0x7f0bcc00fd40) and h264parse1:sink(0x7f0bcc00f1b0)
0:00:09.531723579 12852 0x55e10a226e00 INFO        GST_ELEMENT_PADS gstpad.c:2187:gst_pad_unlink: unlinked qtdemux0:video_0 and h264parse1:sink
0:00:09.531756198 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<qtdemux0> completed state change to READY
0:00:09.531765436 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<qtdemux0> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:09.531776641 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'qtdemux0' changed state to 2(READY) successfully
0:00:09.531788176 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<vid> current READY pending VOID_PENDING, desired next READY
0:00:09.531795707 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2621:gst_bin_element_set_state:<vid> skipping transition from READY to  READY
0:00:09.531803143 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'vid' changed state to 2(READY) successfully
0:00:09.531811280 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<h264parse1> current READY pending VOID_PENDING, desired next READY
0:00:09.531818460 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2621:gst_bin_element_set_state:<h264parse1> skipping transition from READY to  READY
0:00:09.531826000 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'h264parse1' changed state to 2(READY) successfully
0:00:09.531834762 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<qtdemux0> current READY pending VOID_PENDING, desired next READY
0:00:09.531842071 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2621:gst_bin_element_set_state:<qtdemux0> skipping transition from READY to  READY
0:00:09.531849557 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'qtdemux0' changed state to 2(READY) successfully
0:00:09.531857703 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<vin> current PAUSED pending VOID_PENDING, desired next READY
0:00:09.531867690 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<vin> completed state change to READY
0:00:09.531875278 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<vin> notifying about state-changed PAUSED to READY (VOID_PENDING pending)
0:00:09.531885518 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'vin' changed state to 2(READY) successfully
0:00:09.531914657 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2660:gst_element_continue_state:<pipeline0> committing state from PAUSED to READY, pending NULL, next NULL
0:00:09.531923520 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<pipeline0> notifying about state-changed PAUSED to READY (NULL pending)
0:00:09.531933445 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2668:gst_element_continue_state:<pipeline0> continue state change READY to NULL, final NULL
0:00:09.531949995 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<vid> current READY pending VOID_PENDING, desired next NULL
0:00:09.531961824 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<vid> completed state change to NULL
0:00:09.531969531 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<vid> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:00:09.531980232 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'vid' changed state to 1(NULL) successfully
0:00:09.531988934 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<h264parse1> current READY pending VOID_PENDING, desired next NULL
0:00:09.531998396 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<h264parse1> completed state change to NULL
0:00:09.532005946 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<h264parse1> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:00:09.532017130 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'h264parse1' changed state to 1(NULL) successfully
0:00:09.532033281 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<qtdemux0> current READY pending VOID_PENDING, desired next NULL
0:00:09.532049069 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<qtdemux0> completed state change to NULL
0:00:09.532062460 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<qtdemux0> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:00:09.532081670 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'qtdemux0' changed state to 1(NULL) successfully
0:00:09.532091597 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<vin> current READY pending VOID_PENDING, desired next NULL
0:00:09.532101218 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<vin> completed state change to NULL
0:00:09.532108600 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<vin> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:00:09.532119041 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'vin' changed state to 1(NULL) successfully
0:00:09.532139595 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<pipeline0> completed state change to NULL
0:00:09.532148038 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<pipeline0> notifying about state-changed READY to NULL (VOID_PENDING pending)
0:00:09.632276778 12852 0x55e10a226e00 INFO           GST_PARENTAGE gstbin.c:4478:gst_bin_get_by_name: [pipeline0]: looking up child element vin
vin: 0x7f0bcc03e210
0:00:09.632381398 12852 0x55e10a226e00 INFO                 filesrc gstfilesrc.c:261:gst_file_src_set_location: filename : video02.mp4
0:00:09.632392531 12852 0x55e10a226e00 INFO                 filesrc gstfilesrc.c:262:gst_file_src_set_location: uri      : file:///data/lytentec/zf/VCU/video-stream-exterior/video02.mp4
0:00:09.632434709 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<vid> current NULL pending VOID_PENDING, desired next READY
0:00:09.632458597 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<vid> completed state change to READY
0:00:09.632470183 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<vid> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:09.632506883 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'vid' changed state to 2(READY) successfully
0:00:09.632519907 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<h264parse1> current NULL pending VOID_PENDING, desired next READY
0:00:09.632530841 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<h264parse1> completed state change to READY
0:00:09.632540732 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<h264parse1> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:09.632560101 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'h264parse1' changed state to 2(READY) successfully
0:00:09.632583155 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<qtdemux0> current NULL pending VOID_PENDING, desired next READY
0:00:09.632601552 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<qtdemux0> completed state change to READY
0:00:09.632618933 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<qtdemux0> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:09.632643362 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'qtdemux0' changed state to 2(READY) successfully
0:00:09.632664759 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<vin> current NULL pending VOID_PENDING, desired next READY
0:00:09.632680828 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<vin> completed state change to READY
0:00:09.632695795 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<vin> notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:09.632722334 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'vin' changed state to 2(READY) successfully
0:00:09.632746022 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2660:gst_element_continue_state:<pipeline0> committing state from NULL to READY, pending PLAYING, next PAUSED
0:00:09.632757358 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<pipeline0> notifying about state-changed NULL to READY (PLAYING pending)
0:00:09.632771262 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2668:gst_element_continue_state:<pipeline0> continue state change READY to PAUSED, final PLAYING
0:00:09.632790101 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<vid> current READY pending VOID_PENDING, desired next PAUSED
0:00:09.632811070 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2959:gst_bin_change_state_func:<pipeline0> child 'vid' is changing state asynchronously to PAUSED
0:00:09.632822595 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<h264parse1> current READY pending VOID_PENDING, desired next PAUSED
0:00:09.632917169 12852 0x55e10a226e00 INFO                GST_PADS gstpad.c:4237:gst_pad_peer_query:<h264parse1:sink> pad has no peer
0:00:09.633112807 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<h264parse1> completed state change to PAUSED
0:00:09.633126767 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<h264parse1> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:09.633143995 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'h264parse1' changed state to 3(PAUSED) successfully
0:00:09.633158808 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<qtdemux0> current READY pending VOID_PENDING, desired next PAUSED
0:00:09.633181362 12852 0x55e10a226e00 INFO                 filesrc gstfilesrc.c:468:gst_file_src_start:<vin> opening file video02.mp4
0:00:09.633222981 12852 0x55e10a226e00 WARN                 basesrc gstbasesrc.c:3600:gst_base_src_start_complete:<vin> pad not activated yet
0:00:09.633247030 12852 0x55e10a226e00 INFO                 filesrc gstfilesrc.c:468:gst_file_src_start:<vin> opening file video02.mp4
0:00:09.633294556 12852 0x55e10a226e00 INFO                    task gsttask.c:460:gst_task_set_lock: setting stream lock 0x7f0bcc00efd0 on task 0x7f0bcc015710
0:00:09.633310590 12852 0x55e10a226e00 INFO                GST_PADS gstpad.c:6159:gst_pad_start_task:<qtdemux0:sink> created task 0x7f0bcc015710
0:00:09.633335071 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<qtdemux0> completed state change to PAUSED
0:00:09.633346163 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<qtdemux0> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:09.633360621 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'qtdemux0' changed state to 3(PAUSED) successfully
0:00:09.633372227 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<vin> current READY pending VOID_PENDING, desired next PAUSED
0:00:09.633386151 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<vin> completed state change to PAUSED
0:00:09.633396999 12852 0x55e10a226e00 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<vin> notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:09.633411712 12852 0x55e10a226e00 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'vin' changed state to 3(PAUSED) successfully
0:00:09.633502168 12852 0x7f0bcc003ea0 WARN                 qtdemux qtdemux_types.c:239:qtdemux_type_get: unknown QuickTime node type pasp
got message stream-status
got message stream-status
0:00:09.633543619 12852 0x7f0bcc003ea0 INFO                 qtdemux qtdemux.c:14202:qtdemux_parse_tree:<qtdemux0> timescale: 3000
0:00:09.633564585 12852 0x7f0bcc003ea0 INFO                 qtdemux qtdemux.c:14203:qtdemux_parse_tree:<qtdemux0> duration: 15000
0:00:09.633607672 12852 0x7f0bcc003ea0 WARN                 qtdemux qtdemux.c:3250:qtdemux_parse_trex:<qtdemux0> failed to find fragment defaults for stream 1
0:00:09.633685981 12852 0x7f0bcc003ea0 INFO                 qtdemux qtdemux.c:11664:qtdemux_parse_trak:<qtdemux0> type avc1 caps video/x-h264, stream-format=(string)avc, alignment=(string)au, level=(string)5.1, profile=(string)high, codec_data=(buffer)01640033ffe1001f67640033acd9402d00ebf9f016e04040a8000003000800000301e478c18cb001000568ebecb22c
0:00:09.633737535 12852 0x7f0bcc003ea0 INFO          GST_SCHEDULING gstpad.c:4898:gst_pad_get_range_unchecked:<vin:src> getrange failed, flow: eos
0:00:09.633756783 12852 0x7f0bcc003ea0 INFO          GST_SCHEDULING gstpad.c:5113:gst_pad_pull_range:<qtdemux0:sink> pullrange failed, flow: eos
0:00:09.633871694 12852 0x7f0bcc003ea0 INFO               GST_EVENT gstevent.c:820:gst_event_new_caps: creating caps event video/x-h264, stream-format=(string)avc, alignment=(string)au, level=(string)5.1, profile=(string)high, codec_data=(buffer)01640033ffe1001f67640033acd9402d00ebf9f016e04040a8000003000800000301e478c18cb001000568ebecb22c, width=(int)2880, height=(int)1860, framerate=(fraction)30/1, pixel-aspect-ratio=(fraction)1/1, colorimetry=(string)2:4:0:0
0:00:09.633900779 12852 0x7f0bcc003ea0 INFO        GST_ELEMENT_PADS gstelement.c:671:gst_element_add_pad:<qtdemux0> adding pad 'video_0'
0:00:09.633937518 12852 0x7f0bcc003ea0 INFO            GST_PIPELINE grammar.y:520:gst_parse_found_pad: trying delayed linking all pads some pad of GstQTDemux named qtdemux0 to some pad of GstH264Parse named h264parse1
0:00:09.633959633 12852 0x7f0bcc003ea0 INFO        GST_ELEMENT_PADS gstutils.c:1771:gst_element_link_pads_full: trying to link element qtdemux0:(any) to element h264parse1:(any)
0:00:09.633983171 12852 0x7f0bcc003ea0 INFO                GST_PADS gstutils.c:1034:gst_pad_check_link: trying to link qtdemux0:video_0 and h264parse1:sink
0:00:09.634046455 12852 0x7f0bcc003ea0 INFO                GST_PADS gstutils.c:1587:prepare_link_maybe_ghosting: qtdemux0 and h264parse1 in same bin, no need for ghost pads
0:00:09.634075145 12852 0x7f0bcc003ea0 INFO                GST_PADS gstpad.c:2377:gst_pad_link_prepare: trying to link qtdemux0:video_0 and h264parse1:sink
0:00:09.634127189 12852 0x7f0bcc003ea0 INFO                GST_PADS gstpad.c:2585:gst_pad_link_full: linked qtdemux0:video_0 and h264parse1:sink, successful
0:00:09.634143972 12852 0x7f0bcc003ea0 INFO               GST_EVENT gstevent.c:1579:gst_event_new_reconfigure: creating reconfigure event

(video-stream-exterior:12852): GLib-GObject-WARNING **: 07:22:16.821: ../../../gobject/gsignal.c:2736: instance '0x7f0bcc04c270' has no handler with id '5'
0:00:09.634283292 12852 0x7f0bcc003ea0 INFO               GST_EVENT gstevent.c:900:gst_event_new_segment: creating segment event time segment start=0:00:00.066666666, offset=0:00:00.000000000, stop=0:00:05.066666666, rate=1.000000, applied_rate=1.000000, flags=0x00, time=0:00:00.000000000, base=0:00:00.000000000, position 0:00:00.066666666, duration 99:99:99.999999999
got message stream-start
0:00:09.634425735 12852 0x7f0bcc003ea0 INFO               baseparse gstbaseparse.c:4843:gst_base_parse_set_upstream_tags:<h264parse1> upstream tags: taglist, video-codec=(string)"H.264\ /\ AVC", maximum-bitrate=(uint)2097152, bitrate=(uint)1233236, encoder=(string)x264;
0:00:09.634522394 12852 0x7f0bcc003ea0 INFO               h264parse gsth264parse.c:897:gst_h264_parse_process_nal:<h264parse1> have SPS/PPS in stream
0:00:09.634563531 12852 0x7f0bcc003ea0 INFO               h264parse gsth264parse.c:1938:gst_h264_parse_update_src_caps:<h264parse1> pixel aspect ratio has been changed 1/1
0:00:09.634600511 12852 0x7f0bcc003ea0 INFO               baseparse gstbaseparse.c:4068:gst_base_parse_set_latency:<h264parse1> min/max latency 0:00:00.033333333, 0:00:00.033333333
0:00:09.634666342 12852 0x7f0bcc003ea0 INFO               GST_EVENT gstevent.c:820:gst_event_new_caps: creating caps event video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, level=(string)5.1, profile=(string)high, width=(int)2880, height=(int)1860, framerate=(fraction)30/1, pixel-aspect-ratio=(fraction)1/1, colorimetry=(string)2:4:0:0, interlace-mode=(string)progressive, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, parsed=(boolean)true
0:00:09.634720906 12852 0x7f0bcc003ea0 INFO               GST_EVENT gstevent.c:820:gst_event_new_caps: creating caps event video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, level=(string)5.1, profile=(string)high, width=(int)2880, height=(int)1860, framerate=(fraction)30/1, pixel-aspect-ratio=(fraction)1/1, colorimetry=(string)2:4:0:0, interlace-mode=(string)progressive, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, parsed=(boolean)true
got message duration-changed
got message tag
got message tag
got message tag
0:00:09.635058824 12852 0x7f0bcc003ea0 INFO              GST_STATES gstbin.c:3421:bin_handle_async_done:<pipeline0> committing state from READY to PAUSED, old pending PLAYING
0:00:09.635080143 12852 0x7f0bcc003ea0 INFO              GST_STATES gstbin.c:3452:bin_handle_async_done:<pipeline0> continue state change, pending PLAYING
0:00:09.635100516 12852 0x7f0bcc003ea0 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<pipeline0> notifying about state-changed READY to PAUSED (PLAYING pending)
0:00:09.635161596 12852 0x7f0bcc0044c0 INFO              GST_STATES gstbin.c:3248:gst_bin_continue_func:<pipeline0> continue state change PAUSED to PLAYING, final PLAYING
got message async-done
0:00:09.635234922 12852 0x7f0bcc0044c0 INFO               GST_EVENT gstevent.c:1449:gst_event_new_latency: creating latency event 0:00:00.000000000
0:00:09.635291502 12852 0x7f0bcc0044c0 INFO                     bin gstbin.c:2783:gst_bin_do_latency_func:<pipeline0> configured latency of 0:00:00.000000000
0:00:09.635328407 12852 0x7f0bcc0044c0 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<vid> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:09.635353705 12852 0x7f0bcc0044c0 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<vid> completed state change to PLAYING
0:00:09.635376509 12852 0x7f0bcc0044c0 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<vid> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:09.635414438 12852 0x7f0bcc0044c0 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'vid' changed state to 4(PLAYING) successfully
0:00:09.635438466 12852 0x7f0bcc0044c0 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<h264parse1> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:09.635457356 12852 0x7f0bcc0044c0 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<h264parse1> completed state change to PLAYING
0:00:09.635474527 12852 0x7f0bcc0044c0 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<h264parse1> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:09.635504866 12852 0x7f0bcc0044c0 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'h264parse1' changed state to 4(PLAYING) successfully
0:00:09.635531428 12852 0x7f0bcc0044c0 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<qtdemux0> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:09.635551287 12852 0x7f0bcc0044c0 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<qtdemux0> completed state change to PLAYING
0:00:09.635569447 12852 0x7f0bcc0044c0 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<qtdemux0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:09.635602241 12852 0x7f0bcc0044c0 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'qtdemux0' changed state to 4(PLAYING) successfully
0:00:09.635623813 12852 0x7f0bcc0044c0 INFO              GST_STATES gstbin.c:2503:gst_bin_element_set_state:<vin> current PAUSED pending VOID_PENDING, desired next PLAYING
0:00:09.635642801 12852 0x7f0bcc0044c0 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<vin> completed state change to PLAYING
0:00:09.635660789 12852 0x7f0bcc0044c0 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<vin> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:09.635689402 12852 0x7f0bcc0044c0 INFO              GST_STATES gstbin.c:2952:gst_bin_change_state_func:<pipeline0> child 'vin' changed state to 4(PLAYING) successfully
0:00:09.635709660 12852 0x7f0bcc0044c0 INFO              GST_STATES gstelement.c:2688:gst_element_continue_state:<pipeline0> completed state change to PLAYING
0:00:09.635726682 12852 0x7f0bcc0044c0 INFO              GST_STATES gstelement.c:2588:_priv_gst_element_state_changed:<pipeline0> notifying about state-changed PAUSED to PLAYING (VOID_PENDING pending)
0:00:10.286014025 12852 0x7f0bcc004400 INFO              rtspstream rtsp-stream.c:2363:on_ssrc_active: 0x7f0bcc038310: source 0x7f0bc0017710 in transport 0x55e10a45f4a0 is active
0:00:10.286048602 12852 0x7f0bcc004400 INFO              rtspclient rtsp-client.c:1944:do_keepalive: keep session 0x7f0bc001feb0 alive