Taking a chosen program (a + v) from multiprogram MPEG TS freezes

Andrey Utkin andrey.krieger.utkin at gmail.com
Fri Oct 4 13:58:58 PDT 2013


You know, there's such thing as multiprogram MPEG TS. As sample media
file you can look at
https://dl.dropboxusercontent.com/u/43104344/mpts_out.ts
There are two programs in it.

So i try to view (or save to another file) particular program by choice.
I figured out that tsdemux gives src pads named {video,audio}_[pid].
So i made up such pipelines:
gst-launch-1.0 --gst-debug-level 4 filesrc location=mpts_out.ts !
tsdemux name=demux   demux.video_0022 ! queue  max-size-bytes=0
max-size-buffers=0 max-size-time=5000000000 ! decodebin ! videoconvert
! x264enc ! queue ! mux.    demux.audio_0025 ! queue max-size-bytes=0
max-size-buffers=0 max-size-time=5000000000 ! audioconvert ! avenc_mp2
! queue ! mpegtsmux name=mux mux. ! filesink location=mpts_remux.ts

gst-launch-1.0 --gst-debug-level 4 filesrc
location=/home/krieger/work/employers/netsol/gstreamer_test/mpts_out.ts
! tsdemux name=demux   demux.video_0022 ! queue  max-size-bytes=0
max-size-buffers=0 max-size-time=5000000000 ! decodebin ! videoconvert
! xvimagesink    demux.audio_0025 ! queue max-size-bytes=0
max-size-buffers=0 max-size-time=5000000000 ! audioconvert ! alsasink

The problem: both of them freeze immediately after start.

I use gst stuff 1.2.0.
The output of the second program, with gst-debug-level 4:

0:00:00.000570933 10143      0x2225c70 INFO                GST_INIT
gstmessage.c:123:_priv_gst_message_initialize: init messages
0:00:00.000661450 10143      0x2225c70 INFO                GST_INIT
gstcontext.c:77:_priv_gst_context_initialize: init contexts
0:00:00.000776301 10143      0x2225c70 INFO      GST_PLUGIN_LOADING
gstplugin.c:317:_priv_gst_plugin_initialize: registering 0 static
plugins
0:00:00.000870043 10143      0x2225c70 INFO      GST_PLUGIN_LOADING
gstplugin.c:225:gst_plugin_register_static: registered static plugin
"staticelements"
0:00:00.000886154 10143      0x2225c70 INFO      GST_PLUGIN_LOADING
gstplugin.c:227:gst_plugin_register_static: added static plugin
"staticelements", result: 1
0:00:00.001153872 10143      0x2225c70 INFO            GST_REGISTRY
gstregistry.c:1680:ensure_current_registry: reading registry cache:
/home/krieger/.cache/gstreamer-1.0/registry.x86_64.bin
0:00:00.013687423 10143      0x2225c70 INFO            GST_REGISTRY
gstregistrybinary.c:617:priv_gst_registry_binary_read_cache: loaded
/home/krieger/.cache/gstreamer-1.0/registry.x86_64.bin in 0.012512
seconds
0:00:00.013746530 10143      0x2225c70 INFO            GST_REGISTRY
gstregistry.c:1539:scan_and_update_registry: Validating plugins from
registry cache: /home/krieger/.cache/gstreamer-1.0/registry.x86_64.bin
0:00:00.014201766 10143      0x2225c70 INFO            GST_REGISTRY
gstregistry.c:1638:scan_and_update_registry: Registry cache has not
changed
0:00:00.014215954 10143      0x2225c70 INFO            GST_REGISTRY
gstregistry.c:1715:ensure_current_registry: registry reading and
updating done, result = 1
0:00:00.014228078 10143      0x2225c70 INFO                GST_INIT
gst.c:707:init_post: GLib runtime version: 2.32.4
0:00:00.014248994 10143      0x2225c70 INFO                GST_INIT
gst.c:709:init_post: GLib headers version: 2.32.4
0:00:00.014277475 10143      0x2225c70 INFO            GST_PIPELINE
gstparse.c:322:gst_parse_launch_full: parsing pipeline description
'filesrc location=/home/krieger/work/employers/netsol/gstreamer_test/mpts_out.ts
! tsdemux name=demux demux.video_0022 ! queue max-size-bytes=0
max-size-buffers=0 max-size-time=5000000000 ! decodebin ! videoconvert
! xvimagesink demux.audio_0025 ! queue max-size-bytes=0
max-size-buffers=0 max-size-time=5000000000 ! audioconvert ! alsasink
'
0:00:00.014708981 10143      0x2225c70 INFO      GST_PLUGIN_LOADING
gstplugin.c:830:gst_plugin_load_file: plugin
"/usr/lib64/gstreamer-1.0/libgstcoreelements.so" loaded
0:00:00.014727921 10143      0x2225c70 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:365:gst_element_factory_create: creating element
"filesrc"
0:00:00.014828840 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<GstBaseSrc at 0x2380010> adding pad
'src'
0:00:00.014863639 10143      0x2225c70 INFO                 filesrc
gstfilesrc.c:253:gst_file_src_set_location: filename :
/home/krieger/work/employers/netsol/gstreamer_test/mpts_out.ts
0:00:00.014875254 10143      0x2225c70 INFO                 filesrc
gstfilesrc.c:254:gst_file_src_set_location: uri      :
file:///home/krieger/work/employers/netsol/gstreamer_test/mpts_out.ts
0:00:00.015628185 10143      0x2225c70 INFO      GST_PLUGIN_LOADING
gstplugin.c:830:gst_plugin_load_file: plugin
"/usr/lib64/gstreamer-1.0/libgstmpegtsdemux.so" loaded
0:00:00.015645499 10143      0x2225c70 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:365:gst_element_factory_create: creating element
"tsdemux"
0:00:00.015779976 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<MpegTSBase at 0x238a170> adding pad
'sink'
0:00:00.015854100 10143      0x2225c70 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:365:gst_element_factory_create: creating element
"queue"
0:00:00.015945222 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<GstQueue at 0x23a2250> adding pad
'sink'
0:00:00.015974724 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<GstQueue at 0x23a2250> adding pad
'src'
0:00:00.016220585 10143      0x2225c70 INFO      GST_PLUGIN_LOADING
gstplugin.c:830:gst_plugin_load_file: plugin
"/usr/lib64/gstreamer-1.0/libgstplayback.so" loaded
0:00:00.016240807 10143      0x2225c70 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:365:gst_element_factory_create: creating element
"decodebin"
0:00:00.016344911 10143      0x2225c70 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:363:gst_element_factory_create: creating element
"typefind" named "typefind"
0:00:00.016412172 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<GstTypeFindElement at 0x23ab100>
adding pad 'sink'
0:00:00.016444151 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<GstTypeFindElement at 0x23ab100>
adding pad 'src'
0:00:00.016481512 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad typefind:sink
0:00:00.016530222 10143      0x2225c70 INFO                GST_PADS
gstpad.c:2083:gst_pad_link_prepare: trying to link sink:proxypad0 and
typefind:sink
0:00:00.016546059 10143      0x2225c70 INFO                GST_PADS
gstpad.c:2285:gst_pad_link_full: linked sink:proxypad0 and
typefind:sink, successful
0:00:00.016557320 10143      0x2225c70 INFO               GST_EVENT
gstevent.c:1313:gst_event_new_reconfigure: creating reconfigure event
0:00:00.016579418 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<GstDecodeBin at 0x23a80e0> adding
pad 'sink'
0:00:00.016694605 10143      0x2225c70 INFO      GST_PLUGIN_LOADING
gstplugin.c:830:gst_plugin_load_file: plugin
"/usr/lib64/gstreamer-1.0/libgstvideoconvert.so" loaded
0:00:00.016709630 10143      0x2225c70 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:365:gst_element_factory_create: creating element
"videoconvert"
0:00:00.016874961 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<GstBaseTransform at 0x23b67c0>
adding pad 'sink'
0:00:00.016903412 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<GstBaseTransform at 0x23b67c0>
adding pad 'src'
0:00:00.017602225 10143      0x2225c70 INFO      GST_PLUGIN_LOADING
gstplugin.c:830:gst_plugin_load_file: plugin
"/usr/lib64/gstreamer-1.0/libgstxvimagesink.so" loaded
0:00:00.017619340 10143      0x2225c70 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:365:gst_element_factory_create: creating element
"xvimagesink"
0:00:00.017738143 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<GstBaseSink at 0x23bc000> adding
pad 'sink'
0:00:00.017763023 10143      0x2225c70 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:365:gst_element_factory_create: creating element
"queue"
0:00:00.017792739 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<GstQueue at 0x23a2540> adding pad
'sink'
0:00:00.017819500 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<GstQueue at 0x23a2540> adding pad
'src'
0:00:00.017944168 10143      0x2225c70 INFO      GST_PLUGIN_LOADING
gstplugin.c:830:gst_plugin_load_file: plugin
"/usr/lib64/gstreamer-1.0/libgstaudioconvert.so" loaded
0:00:00.017959232 10143      0x2225c70 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:365:gst_element_factory_create: creating element
"audioconvert"
0:00:00.018046693 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<GstBaseTransform at 0x23c02b0>
adding pad 'sink'
0:00:00.018077062 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<GstBaseTransform at 0x23c02b0>
adding pad 'src'
0:00:00.018598614 10143      0x2225c70 INFO      GST_PLUGIN_LOADING
gstplugin.c:830:gst_plugin_load_file: plugin
"/usr/lib64/gstreamer-1.0/libgstalsa.so" loaded
0:00:00.018614809 10143      0x2225c70 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:365:gst_element_factory_create: creating element
"alsasink"
0:00:00.018730289 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<GstBaseSink at 0x23c8070> adding
pad 'sink'
0:00:00.018788472 10143      0x2225c70 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:365:gst_element_factory_create: creating element
"pipeline"
0:00:00.018884695 10143      0x2225c70 INFO            GST_PIPELINE
./grammar.y:578:gst_parse_perform_link: linking filesrc0:(any) to
demux:(any) (0/0) with caps "(NULL)"
0:00:00.018904334 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstutils.c:1543:gst_element_link_pads_full: trying to link element
filesrc0:(any) to element demux:(any)
0:00:00.018923721 10143      0x2225c70 INFO                GST_PADS
gstutils.c:936:gst_pad_check_link: trying to link filesrc0:src and
demux:sink
0:00:00.018951453 10143      0x2225c70 INFO                GST_PADS
gstutils.c:1443:prepare_link_maybe_ghosting: filesrc0 and demux in
same bin, no need for ghost pads
0:00:00.018969244 10143      0x2225c70 INFO                GST_PADS
gstpad.c:2083:gst_pad_link_prepare: trying to link filesrc0:src and
demux:sink
0:00:00.018987432 10143      0x2225c70 INFO                GST_PADS
gstpad.c:2285:gst_pad_link_full: linked filesrc0:src and demux:sink,
successful
0:00:00.018998982 10143      0x2225c70 INFO               GST_EVENT
gstevent.c:1313:gst_event_new_reconfigure: creating reconfigure event
0:00:00.019008893 10143      0x2225c70 INFO               GST_EVENT
gstpad.c:5033:gst_pad_send_event_unchecked:<filesrc0:src> Received
event on flushing pad. Discarding
0:00:00.019025139 10143      0x2225c70 INFO           GST_PARENTAGE
gstbin.c:4131:gst_bin_get_by_name: [pipeline0]: looking up child
element demux
0:00:00.019047791 10143      0x2225c70 INFO            GST_PIPELINE
./grammar.y:578:gst_parse_perform_link: linking demux:demux to
queue0:(any) (1/0) with caps "(NULL)"
0:00:00.019061200 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstutils.c:1543:gst_element_link_pads_full: trying to link element
demux:video_0022 to element queue0:(any)
0:00:00.019072418 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstelement.c:894:gst_element_get_static_pad: no such pad 'video_0022'
in element "demux"
0:00:00.019083147 10143      0x2225c70 INFO                 default
gstutils.c:1889:gst_element_link_pads_filtered: Could not link pads:
demux:video_0022 - queue0:(null)
0:00:00.019100312 10143      0x2225c70 INFO            GST_PIPELINE
./grammar.y:578:gst_parse_perform_link: linking queue0:(any) to
decodebin0:(any) (0/0) with caps "(NULL)"
0:00:00.019113051 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstutils.c:1543:gst_element_link_pads_full: trying to link element
queue0:(any) to element decodebin0:(any)
0:00:00.019125881 10143      0x2225c70 INFO                GST_PADS
gstutils.c:936:gst_pad_check_link: trying to link queue0:src and
decodebin0:sink
0:00:00.019142866 10143      0x2225c70 INFO                GST_PADS
gstpad.c:3626:gst_pad_peer_query:<queue0:sink> pad has no peer
0:00:00.019161653 10143      0x2225c70 INFO                GST_PADS
gstutils.c:1443:prepare_link_maybe_ghosting: queue0 and decodebin0 in
same bin, no need for ghost pads
0:00:00.019178909 10143      0x2225c70 INFO                GST_PADS
gstpad.c:2083:gst_pad_link_prepare: trying to link queue0:src and
decodebin0:sink
0:00:00.019193509 10143      0x2225c70 INFO                GST_PADS
gstpad.c:3626:gst_pad_peer_query:<queue0:sink> pad has no peer
0:00:00.019209647 10143      0x2225c70 INFO                GST_PADS
gstpad.c:2285:gst_pad_link_full: linked queue0:src and
decodebin0:sink, successful
0:00:00.019221155 10143      0x2225c70 INFO               GST_EVENT
gstevent.c:1313:gst_event_new_reconfigure: creating reconfigure event
0:00:00.019230450 10143      0x2225c70 INFO               GST_EVENT
gstpad.c:5033:gst_pad_send_event_unchecked:<queue0:src> Received event
on flushing pad. Discarding
0:00:00.019248881 10143      0x2225c70 INFO            GST_PIPELINE
./grammar.y:578:gst_parse_perform_link: linking decodebin0:(any) to
videoconvert0:(any) (0/0) with caps "(NULL)"
0:00:00.019262371 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstutils.c:1543:gst_element_link_pads_full: trying to link element
decodebin0:(any) to element videoconvert0:(any)
0:00:00.019280514 10143      0x2225c70 INFO                GST_PADS
gstpad.c:3626:gst_pad_peer_query:<videoconvert0:src> pad has no peer
0:00:00.020323185 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstelement.c:894:gst_element_get_static_pad: no such pad 'src_%u' in
element "decodebin0"
0:00:00.020349278 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstutils.c:1123:gst_element_get_compatible_pad:<decodebin0> Could not
find a compatible pad to link to videoconvert0:sink
0:00:00.020361533 10143      0x2225c70 INFO                 default
gstutils.c:1889:gst_element_link_pads_filtered: Could not link pads:
decodebin0:(null) - videoconvert0:(null)
0:00:00.020374999 10143      0x2225c70 INFO            GST_PIPELINE
./grammar.y:578:gst_parse_perform_link: linking videoconvert0:(any) to
xvimagesink0:(any) (0/0) with caps "(NULL)"
0:00:00.020389896 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstutils.c:1543:gst_element_link_pads_full: trying to link element
videoconvert0:(any) to element xvimagesink0:(any)
0:00:00.020403187 10143      0x2225c70 INFO                GST_PADS
gstutils.c:936:gst_pad_check_link: trying to link videoconvert0:src
and xvimagesink0:sink
0:00:00.020418154 10143      0x2225c70 INFO                GST_PADS
gstpad.c:3626:gst_pad_peer_query:<videoconvert0:sink> pad has no peer
0:00:00.021410979 10143      0x2225c70 INFO                GST_PADS
gstutils.c:1443:prepare_link_maybe_ghosting: videoconvert0 and
xvimagesink0 in same bin, no need for ghost pads
0:00:00.021430271 10143      0x2225c70 INFO                GST_PADS
gstpad.c:2083:gst_pad_link_prepare: trying to link videoconvert0:src
and xvimagesink0:sink
0:00:00.021445179 10143      0x2225c70 INFO                GST_PADS
gstpad.c:3626:gst_pad_peer_query:<videoconvert0:sink> pad has no peer
0:00:00.022416626 10143      0x2225c70 INFO                GST_PADS
gstpad.c:2285:gst_pad_link_full: linked videoconvert0:src and
xvimagesink0:sink, successful
0:00:00.022431368 10143      0x2225c70 INFO               GST_EVENT
gstevent.c:1313:gst_event_new_reconfigure: creating reconfigure event
0:00:00.022441172 10143      0x2225c70 INFO               GST_EVENT
gstpad.c:5033:gst_pad_send_event_unchecked:<videoconvert0:src>
Received event on flushing pad. Discarding
0:00:00.022455778 10143      0x2225c70 INFO           GST_PARENTAGE
gstbin.c:4131:gst_bin_get_by_name: [pipeline0]: looking up child
element demux
0:00:00.022476756 10143      0x2225c70 INFO            GST_PIPELINE
./grammar.y:578:gst_parse_perform_link: linking demux:demux to
queue1:(any) (1/0) with caps "(NULL)"
0:00:00.022489845 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstutils.c:1543:gst_element_link_pads_full: trying to link element
demux:audio_0025 to element queue1:(any)
0:00:00.022500879 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstelement.c:894:gst_element_get_static_pad: no such pad 'audio_0025'
in element "demux"
0:00:00.022511143 10143      0x2225c70 INFO                 default
gstutils.c:1889:gst_element_link_pads_filtered: Could not link pads:
demux:audio_0025 - queue1:(null)
0:00:00.022523645 10143      0x2225c70 INFO            GST_PIPELINE
./grammar.y:578:gst_parse_perform_link: linking queue1:(any) to
audioconvert0:(any) (0/0) with caps "(NULL)"
0:00:00.022535467 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstutils.c:1543:gst_element_link_pads_full: trying to link element
queue1:(any) to element audioconvert0:(any)
0:00:00.022547955 10143      0x2225c70 INFO                GST_PADS
gstutils.c:936:gst_pad_check_link: trying to link queue1:src and
audioconvert0:sink
0:00:00.022562374 10143      0x2225c70 INFO                GST_PADS
gstpad.c:3626:gst_pad_peer_query:<queue1:sink> pad has no peer
0:00:00.022575990 10143      0x2225c70 INFO                GST_PADS
gstpad.c:3626:gst_pad_peer_query:<audioconvert0:src> pad has no peer
0:00:00.022600871 10143      0x2225c70 INFO               structure
gststructure.c:2853:gst_structure_get_valist: Expected field
'channel-mask' in structure: audio/x-raw, rate=(int)[ 1, 2147483647 ],
channels=(int)[ 1, 2147483647 ], layout=(string)interleaved;
0:00:00.022647199 10143      0x2225c70 INFO                GST_PADS
gstutils.c:1443:prepare_link_maybe_ghosting: queue1 and audioconvert0
in same bin, no need for ghost pads
0:00:00.022662934 10143      0x2225c70 INFO                GST_PADS
gstpad.c:2083:gst_pad_link_prepare: trying to link queue1:src and
audioconvert0:sink
0:00:00.022678356 10143      0x2225c70 INFO                GST_PADS
gstpad.c:3626:gst_pad_peer_query:<queue1:sink> pad has no peer
0:00:00.022691850 10143      0x2225c70 INFO                GST_PADS
gstpad.c:3626:gst_pad_peer_query:<audioconvert0:src> pad has no peer
0:00:00.022711888 10143      0x2225c70 INFO               structure
gststructure.c:2853:gst_structure_get_valist: Expected field
'channel-mask' in structure: audio/x-raw, rate=(int)[ 1, 2147483647 ],
channels=(int)[ 1, 2147483647 ], layout=(string)interleaved;
0:00:00.022753581 10143      0x2225c70 INFO                GST_PADS
gstpad.c:2285:gst_pad_link_full: linked queue1:src and
audioconvert0:sink, successful
0:00:00.022765657 10143      0x2225c70 INFO               GST_EVENT
gstevent.c:1313:gst_event_new_reconfigure: creating reconfigure event
0:00:00.022775075 10143      0x2225c70 INFO               GST_EVENT
gstpad.c:5033:gst_pad_send_event_unchecked:<queue1:src> Received event
on flushing pad. Discarding
0:00:00.022788880 10143      0x2225c70 INFO            GST_PIPELINE
./grammar.y:578:gst_parse_perform_link: linking audioconvert0:(any) to
alsasink0:(any) (0/0) with caps "(NULL)"
0:00:00.022801885 10143      0x2225c70 INFO        GST_ELEMENT_PADS
gstutils.c:1543:gst_element_link_pads_full: trying to link element
audioconvert0:(any) to element alsasink0:(any)
0:00:00.022814191 10143      0x2225c70 INFO                GST_PADS
gstutils.c:936:gst_pad_check_link: trying to link audioconvert0:src
and alsasink0:sink
0:00:00.022829415 10143      0x2225c70 INFO                GST_PADS
gstpad.c:3626:gst_pad_peer_query:<queue1:sink> pad has no peer
0:00:00.022851373 10143      0x2225c70 INFO               structure
gststructure.c:2853:gst_structure_get_valist: Expected field
'channel-mask' in structure: audio/x-raw, rate=(int)[ 1, 2147483647 ],
channels=(int)[ 1, 2147483647 ], layout=(string)interleaved;
0:00:00.022898599 10143      0x2225c70 INFO                GST_PADS
gstutils.c:1443:prepare_link_maybe_ghosting: audioconvert0 and
alsasink0 in same bin, no need for ghost pads
0:00:00.022914181 10143      0x2225c70 INFO                GST_PADS
gstpad.c:2083:gst_pad_link_prepare: trying to link audioconvert0:src
and alsasink0:sink
0:00:00.022929828 10143      0x2225c70 INFO                GST_PADS
gstpad.c:3626:gst_pad_peer_query:<queue1:sink> pad has no peer
0:00:00.022951772 10143      0x2225c70 INFO               structure
gststructure.c:2853:gst_structure_get_valist: Expected field
'channel-mask' in structure: audio/x-raw, rate=(int)[ 1, 2147483647 ],
channels=(int)[ 1, 2147483647 ], layout=(string)interleaved;
0:00:00.022997804 10143      0x2225c70 INFO                GST_PADS
gstpad.c:2285:gst_pad_link_full: linked audioconvert0:src and
alsasink0:sink, successful
0:00:00.023010402 10143      0x2225c70 INFO               GST_EVENT
gstevent.c:1313:gst_event_new_reconfigure: creating reconfigure event
0:00:00.023019753 10143      0x2225c70 INFO               GST_EVENT
gstpad.c:5033:gst_pad_send_event_unchecked:<audioconvert0:src>
Received event on flushing pad. Discarding
Setting pipeline to PAUSED ...
0:00:00.023084804 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2227:gst_bin_element_set_state:<alsasink0> current NULL
pending VOID_PENDING, desired next READY
0:00:00.024451193 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2328:gst_element_continue_state:<alsasink0> completed
state change to READY
0:00:00.024470170 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2233:_priv_gst_element_state_changed:<alsasink0>
notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.024497146 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2656:gst_bin_change_state_func:<pipeline0> child 'alsasink0'
changed state to 2(READY) successfully
0:00:00.024514589 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2227:gst_bin_element_set_state:<xvimagesink0> current NULL
pending VOID_PENDING, desired next READY
0:00:00.025877386 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2328:gst_element_continue_state:<xvimagesink0> completed
state change to READY
0:00:00.025900663 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2233:_priv_gst_element_state_changed:<xvimagesink0>
notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.025919082 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2656:gst_bin_change_state_func:<pipeline0> child
'xvimagesink0' changed state to 2(READY) successfully
0:00:00.025936146 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2227:gst_bin_element_set_state:<audioconvert0> current NULL
pending VOID_PENDING, desired next READY
0:00:00.025948645 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2328:gst_element_continue_state:<audioconvert0> completed
state change to READY
0:00:00.025958668 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2233:_priv_gst_element_state_changed:<audioconvert0>
notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.025972112 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2656:gst_bin_change_state_func:<pipeline0> child
'audioconvert0' changed state to 2(READY) successfully
0:00:00.025985731 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2227:gst_bin_element_set_state:<videoconvert0> current NULL
pending VOID_PENDING, desired next READY
0:00:00.025997357 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2328:gst_element_continue_state:<videoconvert0> completed
state change to READY
0:00:00.026007237 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2233:_priv_gst_element_state_changed:<videoconvert0>
notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.026020281 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2656:gst_bin_change_state_func:<pipeline0> child
'videoconvert0' changed state to 2(READY) successfully
0:00:00.026032982 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2227:gst_bin_element_set_state:<queue1> current NULL pending
VOID_PENDING, desired next READY
0:00:00.026044364 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2328:gst_element_continue_state:<queue1> completed state
change to READY
0:00:00.026054088 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2233:_priv_gst_element_state_changed:<queue1> notifying
about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.026067066 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2656:gst_bin_change_state_func:<pipeline0> child 'queue1'
changed state to 2(READY) successfully
0:00:00.026080866 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2227:gst_bin_element_set_state:<decodebin0> current NULL
pending VOID_PENDING, desired next READY
0:00:00.026097148 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2227:gst_bin_element_set_state:<typefind> current NULL
pending VOID_PENDING, desired next READY
0:00:00.026109004 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2328:gst_element_continue_state:<typefind> completed
state change to READY
0:00:00.026118686 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2233:_priv_gst_element_state_changed:<typefind> notifying
about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.026132624 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2656:gst_bin_change_state_func:<decodebin0> child 'typefind'
changed state to 2(READY) successfully
0:00:00.026146658 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2328:gst_element_continue_state:<decodebin0> completed
state change to READY
0:00:00.026156845 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2233:_priv_gst_element_state_changed:<decodebin0>
notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.026169960 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2656:gst_bin_change_state_func:<pipeline0> child 'decodebin0'
changed state to 2(READY) successfully
0:00:00.026182749 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2227:gst_bin_element_set_state:<queue0> current NULL pending
VOID_PENDING, desired next READY
0:00:00.026193973 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2328:gst_element_continue_state:<queue0> completed state
change to READY
0:00:00.026203778 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2233:_priv_gst_element_state_changed:<queue0> notifying
about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.026219049 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2656:gst_bin_change_state_func:<pipeline0> child 'queue0'
changed state to 2(READY) successfully
0:00:00.026229164 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2227:gst_bin_element_set_state:<demux> current NULL pending
VOID_PENDING, desired next READY
0:00:00.026241170 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2328:gst_element_continue_state:<demux> completed state
change to READY
0:00:00.026247658 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2233:_priv_gst_element_state_changed:<demux> notifying
about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.026256989 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2656:gst_bin_change_state_func:<pipeline0> child 'demux'
changed state to 2(READY) successfully
0:00:00.026265455 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2227:gst_bin_element_set_state:<filesrc0> current NULL
pending VOID_PENDING, desired next READY
0:00:00.026273260 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2328:gst_element_continue_state:<filesrc0> completed
state change to READY
0:00:00.026279244 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2233:_priv_gst_element_state_changed:<filesrc0> notifying
about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.026288145 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2656:gst_bin_change_state_func:<pipeline0> child 'filesrc0'
changed state to 2(READY) successfully
0:00:00.026297695 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2303:gst_element_continue_state:<pipeline0> committing
state from NULL to READY, pending PAUSED, next PAUSED
0:00:00.026307610 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2233:_priv_gst_element_state_changed:<pipeline0>
notifying about state-changed NULL to READY (PAUSED pending)
0:00:00.026328587 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2310:gst_element_continue_state:<pipeline0> continue
state change READY to PAUSED, final PAUSED
0:00:00.026346985 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2227:gst_bin_element_set_state:<alsasink0> current READY
pending VOID_PENDING, desired next PAUSED
0:00:00.026374192 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2662:gst_bin_change_state_func:<pipeline0> child 'alsasink0'
is changing state asynchronously to PAUSED
0:00:00.026384957 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2227:gst_bin_element_set_state:<xvimagesink0> current READY
pending VOID_PENDING, desired next PAUSED
0:00:00.026396814 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2662:gst_bin_change_state_func:<pipeline0> child
'xvimagesink0' is changing state asynchronously to PAUSED
0:00:00.026406125 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2227:gst_bin_element_set_state:<audioconvert0> current READY
pending VOID_PENDING, desired next PAUSED
0:00:00.026419171 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2328:gst_element_continue_state:<audioconvert0> completed
state change to PAUSED
0:00:00.026425864 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2233:_priv_gst_element_state_changed:<audioconvert0>
notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.026435594 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2656:gst_bin_change_state_func:<pipeline0> child
'audioconvert0' changed state to 3(PAUSED) successfully
0:00:00.026444527 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2227:gst_bin_element_set_state:<videoconvert0> current READY
pending VOID_PENDING, desired next PAUSED
0:00:00.026455219 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2328:gst_element_continue_state:<videoconvert0> completed
state change to PAUSED
0:00:00.026461491 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2233:_priv_gst_element_state_changed:<videoconvert0>
notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.026470899 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2656:gst_bin_change_state_func:<pipeline0> child
'videoconvert0' changed state to 3(PAUSED) successfully
0:00:00.026479889 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2227:gst_bin_element_set_state:<queue1> current READY pending
VOID_PENDING, desired next PAUSED
0:00:00.026501561 10143      0x2225c70 INFO                    task
gsttask.c:431:gst_task_set_lock: setting stream lock 0x2383690 on task
0x23f8000
0:00:00.026510583 10143      0x2225c70 INFO                GST_PADS
gstpad.c:5365:gst_pad_start_task:<queue1:src> created task 0x23f8000
0:00:00.026543189 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2328:gst_element_continue_state:<queue1> completed state
change to PAUSED
0:00:00.026551460 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2233:_priv_gst_element_state_changed:<queue1> notifying
about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.026561861 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2656:gst_bin_change_state_func:<pipeline0> child 'queue1'
changed state to 3(PAUSED) successfully
0:00:00.026572183 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2227:gst_bin_element_set_state:<decodebin0> current READY
pending VOID_PENDING, desired next PAUSED
0:00:00.027062287 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2227:gst_bin_element_set_state:<typefind> current READY
pending VOID_PENDING, desired next PAUSED
0:00:00.027092864 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2328:gst_element_continue_state:<typefind> completed
state change to PAUSED
0:00:00.027100281 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2233:_priv_gst_element_state_changed:<typefind> notifying
about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.027110740 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2656:gst_bin_change_state_func:<decodebin0> child 'typefind'
changed state to 3(PAUSED) successfully
0:00:00.027121739 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2662:gst_bin_change_state_func:<pipeline0> child 'decodebin0'
is changing state asynchronously to PAUSED
0:00:00.027130246 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2227:gst_bin_element_set_state:<queue0> current READY pending
VOID_PENDING, desired next PAUSED
0:00:00.027145398 10143      0x2225c70 INFO                    task
gsttask.c:431:gst_task_set_lock: setting stream lock 0x2382740 on task
0x23f8120
0:00:00.027152861 10143      0x2225c70 INFO                GST_PADS
gstpad.c:5365:gst_pad_start_task:<queue0:src> created task 0x23f8120
0:00:00.027180461 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2328:gst_element_continue_state:<queue0> completed state
change to PAUSED
0:00:00.027188127 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2233:_priv_gst_element_state_changed:<queue0> notifying
about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.027198366 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2656:gst_bin_change_state_func:<pipeline0> child 'queue0'
changed state to 3(PAUSED) successfully
0:00:00.027213736 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2227:gst_bin_element_set_state:<demux> current READY pending
VOID_PENDING, desired next PAUSED
0:00:00.027244585 10143      0x2225c70 INFO                 filesrc
gstfilesrc.c:450:gst_file_src_start:<filesrc0> opening file
/home/krieger/work/employers/netsol/gstreamer_test/mpts_out.ts
0:00:00.027275171 10143      0x2225c70 INFO                 filesrc
gstfilesrc.c:450:gst_file_src_start:<filesrc0> opening file
/home/krieger/work/employers/netsol/gstreamer_test/mpts_out.ts
0:00:00.027294332 10143      0x2225c70 INFO                    task
gsttask.c:431:gst_task_set_lock: setting stream lock 0x23822e0 on task
0x23f8240
0:00:00.027301959 10143      0x2225c70 INFO                GST_PADS
gstpad.c:5365:gst_pad_start_task:<demux:sink> created task 0x23f8240
0:00:00.027327655 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2328:gst_element_continue_state:<demux> completed state
change to PAUSED
0:00:00.027336933 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2233:_priv_gst_element_state_changed:<demux> notifying
about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.027524216 10143      0x23c4ed0 INFO              mpegtsbase
mpegtsbase.c:726:mpegts_base_apply_pat:<demux> PAT
0:00:00.027947793 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2656:gst_bin_change_state_func:<pipeline0> child 'demux'
changed state to 3(PAUSED) successfully
0:00:00.027965490 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2227:gst_bin_element_set_state:<filesrc0> current READY
pending VOID_PENDING, desired next PAUSED
0:00:00.027979156 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2328:gst_element_continue_state:<filesrc0> completed
state change to PAUSED
0:00:00.027985722 10143      0x2225c70 INFO              GST_STATES
gstelement.c:2233:_priv_gst_element_state_changed:<filesrc0> notifying
about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.027995858 10143      0x2225c70 INFO              GST_STATES
gstbin.c:2656:gst_bin_change_state_func:<pipeline0> child 'filesrc0'
changed state to 3(PAUSED) successfully
Pipeline is PREROLLING ...
0:00:00.028018947 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad demux:sink
0:00:00.028067822 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event video/x-h264,
stream-format=(string)byte-stream, alignment=(string)nal
0:00:00.028135498 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad demux:sink
0:00:00.028154597 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event audio/x-ac3
0:00:00.028192502 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad demux:sink
0:00:00.028209714 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event audio/x-ac3
0:00:00.028247766 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad demux:sink
0:00:00.028265246 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event audio/x-ac3
0:00:00.028302067 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad demux:sink
0:00:00.028318907 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event audio/x-ac3
0:00:00.028355147 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad demux:sink
0:00:00.028371400 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event
subpicture/x-dvb
0:00:00.028408022 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad demux:sink
0:00:00.028424094 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event
subpicture/x-dvb
0:00:00.028863175 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad demux:sink
0:00:00.028881396 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event
subpicture/x-dvb
0:00:00.028916486 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad demux:sink
0:00:00.028932671 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event
subpicture/x-dvb
0:00:00.028970311 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad demux:sink
0:00:00.028988240 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event
subpicture/x-dvb
0:00:00.029022941 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad demux:sink
0:00:00.029039077 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event
subpicture/x-dvb
0:00:00.029095317 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<demux> adding pad 'video_0022'
0:00:00.029115476 10143      0x23c4ed0 INFO            GST_PIPELINE
./grammar.y:506:gst_parse_found_pad: trying delayed linking
demux:video_0022 to queue0:(NULL)
0:00:00.029126668 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstutils.c:1543:gst_element_link_pads_full: trying to link element
demux:video_0022 to element queue0:(any)
0:00:00.029135363 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad
demux:video_0022
0:00:00.029144339 10143      0x23c4ed0 INFO                GST_PADS
gstutils.c:936:gst_pad_check_link: trying to link demux:video_0022 and
queue0:sink
0:00:00.029166096 10143      0x23c4ed0 INFO                GST_PADS
gstutils.c:1443:prepare_link_maybe_ghosting: demux and queue0 in same
bin, no need for ghost pads
0:00:00.029178317 10143      0x23c4ed0 INFO                GST_PADS
gstpad.c:2083:gst_pad_link_prepare: trying to link demux:video_0022
and queue0:sink
0:00:00.029196352 10143      0x23c4ed0 INFO                GST_PADS
gstpad.c:2285:gst_pad_link_full: linked demux:video_0022 and
queue0:sink, successful
0:00:00.029204387 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:1313:gst_event_new_reconfigure: creating reconfigure event
0:00:00.029219368 10143      0x23c4ed0 INFO            GST_PIPELINE
./grammar.y:506:gst_parse_found_pad: trying delayed linking
demux:audio_0025 to queue1:(NULL)
0:00:00.029227243 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstutils.c:1543:gst_element_link_pads_full: trying to link element
demux:audio_0025 to element queue1:(any)
0:00:00.029234743 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:894:gst_element_get_static_pad: no such pad 'audio_0025'
in element "demux"
0:00:00.029735374 10143      0x23c4ed0 INFO                 default
gstutils.c:1889:gst_element_link_pads_filtered: Could not link pads:
demux:audio_0025 - queue1:(null)
0:00:00.029759528 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:709:gst_event_new_segment: creating segment event time
segment start=0:00:00.059981629, stop=99:99:99.999999999,
rate=1.000000, applied_rate=1.000000, flags=0x00,
time=0:00:00.059981629, base=0:00:00.000000000, position
0:00:00.059981629, duration 99:99:99.999999999
0:00:00.029889857 10143      0x23c5000 INFO                typefind
gsttypefindelement.c:182:gst_type_find_element_have_type:<typefind>
found caps video/x-h264, stream-format=(string)byte-stream,
alignment=(string)nal, probability=100
0:00:00.029915809 10143      0x23c5000 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event video/x-h264,
stream-format=(string)byte-stream, alignment=(string)nal
0:00:00.029937055 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad typefind:src
0:00:00.029947987 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad typefind:sink
0:00:00.029981268 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<demux> adding pad 'audio_0024'
0:00:00.029994521 10143      0x23c4ed0 INFO            GST_PIPELINE
./grammar.y:506:gst_parse_found_pad: trying delayed linking
demux:audio_0025 to queue1:(NULL)
0:00:00.030004022 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstutils.c:1543:gst_element_link_pads_full: trying to link element
demux:audio_0025 to element queue1:(any)
0:00:00.030012481 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:894:gst_element_get_static_pad: no such pad 'audio_0025'
in element "demux"
0:00:00.030012450 10143      0x23c5000 INFO                GST_PADS
gstpad.c:2083:gst_pad_link_prepare: trying to link typefind:src and
decodepad0:proxypad1
0:00:00.030019643 10143      0x23c4ed0 INFO                 default
gstutils.c:1889:gst_element_link_pads_filtered: Could not link pads:
demux:audio_0025 - queue1:(null)
0:00:00.030023632 10143      0x23c5000 INFO                GST_PADS
gstpad.c:2285:gst_pad_link_full: linked typefind:src and
decodepad0:proxypad1, successful
0:00:00.030031444 10143      0x23c5000 INFO               GST_EVENT
gstevent.c:1313:gst_event_new_reconfigure: creating reconfigure event
0:00:00.030078377 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<demux> adding pad 'audio_0023'
0:00:00.030088425 10143      0x23c4ed0 INFO            GST_PIPELINE
./grammar.y:506:gst_parse_found_pad: trying delayed linking
demux:audio_0025 to queue1:(NULL)
0:00:00.030096865 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstutils.c:1543:gst_element_link_pads_full: trying to link element
demux:audio_0025 to element queue1:(any)
0:00:00.030104588 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:894:gst_element_get_static_pad: no such pad 'audio_0025'
in element "demux"
0:00:00.030111252 10143      0x23c4ed0 INFO                 default
gstutils.c:1889:gst_element_link_pads_filtered: Could not link pads:
demux:audio_0025 - queue1:(null)
0:00:00.030204728 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad demux:sink
0:00:00.030228582 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event video/x-h264,
stream-format=(string)byte-stream, alignment=(string)nal
0:00:00.030284446 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad demux:sink
0:00:00.030305827 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event audio/x-ac3
0:00:00.030342286 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad demux:sink
0:00:00.030361304 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event audio/x-ac3
0:00:00.030397022 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad demux:sink
0:00:00.030415553 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event audio/mpeg,
mpegversion=(int)1
0:00:00.030435300 10143      0x23c4ed0 WARN                 default
descriptions.c:611:format_info_get_desc: Unexpected MPEG-1 layer in
audio/mpeg, mpegversion=(int)1
0:00:00.030470079 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad demux:sink
0:00:00.030489995 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event
subpicture/x-dvb
0:00:00.030525538 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad demux:sink
0:00:00.030543987 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event audio/x-lpcm
0:00:00.031576945 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstpad.c:1840:gst_pad_unlink: unlinking typefind:src(0x2382b30) and
decodepad0:proxypad1(0x23ae910)
0:00:00.031700799 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstpad.c:1894:gst_pad_unlink: unlinked typefind:src and
decodepad0:proxypad1
0:00:00.031717146 10143      0x23c5000 INFO                GST_PADS
gstpad.c:2083:gst_pad_link_prepare: trying to link typefind:src and
decodepad0:proxypad1
0:00:00.031723174 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad demux:sink
0:00:00.031725644 10143      0x23c5000 INFO                GST_PADS
gstpad.c:2285:gst_pad_link_full: linked typefind:src and
decodepad0:proxypad1, successful
0:00:00.031743538 10143      0x23c5000 INFO               GST_EVENT
gstevent.c:1313:gst_event_new_reconfigure: creating reconfigure event
0:00:00.031753134 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event audio/x-lpcm
0:00:00.031763919 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstpad.c:1840:gst_pad_unlink: unlinking typefind:src(0x2382b30) and
decodepad0:proxypad1(0x23ae910)
0:00:00.031777142 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstpad.c:1894:gst_pad_unlink: unlinked typefind:src and
decodepad0:proxypad1
0:00:00.031787503 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad demux:sink
0:00:00.031805808 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event audio/x-lpcm
0:00:00.031840425 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad demux:sink
0:00:00.031858684 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event audio/x-lpcm
0:00:00.031896758 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad demux:sink
0:00:00.031915832 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event audio/x-lpcm
0:00:00.031951768 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad demux:sink
0:00:00.031970140 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event audio/x-lpcm
0:00:00.032006846 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad demux:sink
0:00:00.032024584 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event audio/x-lpcm
0:00:00.032100300 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad demux:sink
0:00:00.032097160 10143      0x23c5000 INFO      GST_PLUGIN_LOADING
gstplugin.c:830:gst_plugin_load_file: plugin
"/usr/lib64/gstreamer-1.0/libgstvideoparsersbad.so" loaded
0:00:00.032124923 10143      0x23c5000 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:365:gst_element_factory_create: creating element
"h264parse"
0:00:00.032128509 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event audio/x-lpcm
0:00:00.032180211 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad demux:sink
0:00:00.032205067 10143      0x23c4ed0 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event audio/x-lpcm
0:00:00.032225127 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<GstBaseParse at 0x7fae5c045020>
adding pad 'sink'
0:00:00.032262124 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<GstBaseParse at 0x7fae5c045020>
adding pad 'src'
0:00:00.032284049 10143      0x23c5000 INFO               baseparse
gstbaseparse.c:3539:gst_base_parse_set_pts_interpolation:<GstH264Parse at 0x7fae5c045020>
PTS interpolation: no
0:00:00.032314091 10143      0x23c5000 INFO                GST_PADS
gstpad.c:2083:gst_pad_link_prepare: trying to link typefind:src and
h264parse0:sink
0:00:00.032324871 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<demux> adding pad 'audio_0026'
0:00:00.032331809 10143      0x23c5000 INFO                GST_PADS
gstpad.c:3626:gst_pad_peer_query:<h264parse0:src> pad has no peer
0:00:00.032336337 10143      0x23c4ed0 INFO            GST_PIPELINE
./grammar.y:506:gst_parse_found_pad: trying delayed linking
demux:audio_0025 to queue1:(NULL)
0:00:00.033452209 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstutils.c:1543:gst_element_link_pads_full: trying to link element
demux:audio_0025 to element queue1:(any)
0:00:00.032350535 10143      0x23c5000 INFO                GST_PADS
gstpad.c:2285:gst_pad_link_full: linked typefind:src and
h264parse0:sink, successful
0:00:00.033469682 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:894:gst_element_get_static_pad: no such pad 'audio_0025'
in element "demux"
0:00:00.033484151 10143      0x23c5000 INFO               GST_EVENT
gstevent.c:1313:gst_event_new_reconfigure: creating reconfigure event
0:00:00.033487211 10143      0x23c4ed0 INFO                 default
gstutils.c:1889:gst_element_link_pads_filtered: Could not link pads:
demux:audio_0025 - queue1:(null)
0:00:00.033512399 10143      0x23c5000 INFO              GST_STATES
gstelement.c:2328:gst_element_continue_state:<h264parse0> completed
state change to READY
0:00:00.033516798 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<demux> adding pad 'audio_0025'
0:00:00.033528720 10143      0x23c4ed0 INFO            GST_PIPELINE
./grammar.y:506:gst_parse_found_pad: trying delayed linking
demux:audio_0025 to queue1:(NULL)
0:00:00.033541147 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstutils.c:1543:gst_element_link_pads_full: trying to link element
demux:audio_0025 to element queue1:(any)
0:00:00.033552743 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad
demux:audio_0025
0:00:00.033547281 10143      0x23c5000 INFO              GST_STATES
gstelement.c:2233:_priv_gst_element_state_changed:<h264parse0>
notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.033565994 10143      0x23c4ed0 INFO                GST_PADS
gstutils.c:936:gst_pad_check_link: trying to link demux:audio_0025 and
queue1:sink
0:00:00.033592536 10143      0x23c5000 INFO                GST_PADS
gstpad.c:3626:gst_pad_peer_query:<h264parse0:src> pad has no peer
0:00:00.033619159 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad h264parse0:src
0:00:00.033639852 10143      0x23c5000 INFO                GST_PADS
gstpad.c:2083:gst_pad_link_prepare: trying to link h264parse0:src and
decodepad0:proxypad1
0:00:00.033649779 10143      0x23c5000 INFO                GST_PADS
gstpad.c:2285:gst_pad_link_full: linked h264parse0:src and
decodepad0:proxypad1, successful
0:00:00.033657070 10143      0x23c5000 INFO               GST_EVENT
gstevent.c:1313:gst_event_new_reconfigure: creating reconfigure event
0:00:00.033663090 10143      0x23c5000 INFO               GST_EVENT
gstpad.c:5033:gst_pad_send_event_unchecked:<h264parse0:src> Received
event on flushing pad. Discarding
0:00:00.033749376 10143      0x23c4ed0 WARN                    alsa
conf.c:4692:snd_config_expand: alsalib error: Unknown parameters {AES0
0x02 AES1 0x82 AES2 0x00 AES3 0x02}
0:00:00.033761894 10143      0x23c4ed0 WARN                    alsa
pcm.c:2217:snd_pcm_open_noupdate: alsalib error: Unknown PCM
default:{AES0 0x02 AES1 0x82 AES2 0x00 AES3 0x02}
0:00:00.033767070 10143      0x23c5000 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:365:gst_element_factory_create: creating element
"capsfilter"
0:00:00.033770435 10143      0x23c4ed0 INFO                    alsa
gstalsasink.c:318:gst_alsasink_getcaps:<alsasink0> returning caps
audio/x-raw, format=(string){ S16LE, U16LE, S24_32LE, U24_32LE, S32LE,
U32LE, S24LE, U24LE }, layout=(string)interleaved, rate=(int)[ 4000,
2147483647 ], channels=(int)2,
channel-mask=(bitmask)0x0000000000000003; audio/x-raw,
format=(string){ S16LE, U16LE, S24_32LE, U24_32LE, S32LE, U32LE,
S24LE, U24LE }, layout=(string)interleaved, rate=(int)[ 4000,
2147483647 ], channels=(int)1; audio/x-raw, format=(string){ S16LE,
U16LE, S24_32LE, U24_32LE, S32LE, U32LE, S24LE, U24LE },
layout=(string)interleaved, rate=(int)[ 4000, 2147483647 ],
channels=(int)3, channel-mask=(bitmask)0x000000000000000b;
audio/x-raw, format=(string){ S16LE, U16LE, S24_32LE, U24_32LE, S32LE,
U32LE, S24LE, U24LE }, layout=(string)interleaved, rate=(int)[ 4000,
2147483647 ], channels=(int)4,
channel-mask=(bitmask)0x0000000000000033; audio/x-raw,
format=(string){ S16LE, U16LE, S24_32LE, U24_32LE, S32LE, U32LE,
S24LE, U24LE }, layout=(string)interleaved, rate=(int)[ 4000,
2147483647 ], channels=(int)6,
channel-mask=(bitmask)0x000000000000003f; audio/x-raw,
format=(string){ S16LE, U16LE, S24_32LE, U24_32LE, S32LE, U32LE,
S24LE, U24LE }, layout=(string)interleaved, rate=(int)[ 4000,
2147483647 ], channels=(int)8,
channel-mask=(bitmask)0x0000000000000c3f
0:00:00.033831455 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<GstBaseTransform at 0x7fae5c04c020>
adding pad 'sink'
0:00:00.033849756 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<GstBaseTransform at 0x7fae5c04c020>
adding pad 'src'
0:00:00.033864361 10143      0x23c5000 INFO               GST_EVENT
gstevent.c:1313:gst_event_new_reconfigure: creating reconfigure event
0:00:00.033873827 10143      0x23c5000 INFO              GST_STATES
gstelement.c:2303:gst_element_continue_state:<capsfilter0> committing
state from NULL to READY, pending PAUSED, next PAUSED
0:00:00.033882395 10143      0x23c5000 INFO              GST_STATES
gstelement.c:2233:_priv_gst_element_state_changed:<capsfilter0>
notifying about state-changed NULL to READY (PAUSED pending)
0:00:00.033892361 10143      0x23c5000 INFO              GST_STATES
gstelement.c:2310:gst_element_continue_state:<capsfilter0> continue
state change READY to PAUSED, final PAUSED
0:00:00.033904284 10143      0x23c5000 INFO              GST_STATES
gstelement.c:2328:gst_element_continue_state:<capsfilter0> completed
state change to PAUSED
0:00:00.033910513 10143      0x23c5000 INFO              GST_STATES
gstelement.c:2233:_priv_gst_element_state_changed:<capsfilter0>
notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.033926170 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstpad.c:1840:gst_pad_unlink: unlinking h264parse0:src(0x7fae58086750)
and decodepad0:proxypad1(0x23ae910)
0:00:00.033939547 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstpad.c:1894:gst_pad_unlink: unlinked h264parse0:src and
decodepad0:proxypad1
0:00:00.033951279 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad
capsfilter0:sink
0:00:00.033960673 10143      0x23c5000 INFO                GST_PADS
gstpad.c:2083:gst_pad_link_prepare: trying to link h264parse0:src and
capsfilter0:sink
0:00:00.033968479 10143      0x23c5000 INFO                GST_PADS
gstpad.c:2285:gst_pad_link_full: linked h264parse0:src and
capsfilter0:sink, successful
0:00:00.033975381 10143      0x23c5000 INFO               GST_EVENT
gstevent.c:1313:gst_event_new_reconfigure: creating reconfigure event
0:00:00.033986654 10143      0x23c5000 INFO               GST_EVENT
gstpad.c:5033:gst_pad_send_event_unchecked:<h264parse0:src> Received
event on flushing pad. Discarding
0:00:00.033996377 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad capsfilter0:src
0:00:00.034347416 10143      0x23c5000 INFO                GST_PADS
gstpad.c:2083:gst_pad_link_prepare: trying to link capsfilter0:src and
decodepad0:proxypad1
0:00:00.034364140 10143      0x23c5000 INFO                GST_PADS
gstpad.c:2285:gst_pad_link_full: linked capsfilter0:src and
decodepad0:proxypad1, successful
0:00:00.034374340 10143      0x23c5000 INFO               GST_EVENT
gstevent.c:1313:gst_event_new_reconfigure: creating reconfigure event
0:00:00.034385611 10143      0x23c5000 INFO               GST_EVENT
gstpad.c:5033:gst_pad_send_event_unchecked:<h264parse0:src> Received
event on flushing pad. Discarding
0:00:00.034433482 10143      0x23c4ed0 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad queue1:sink
0:00:00.034445442 10143      0x23c4ed0 INFO                GST_PADS
gstutils.c:1443:prepare_link_maybe_ghosting: demux and queue1 in same
bin, no need for ghost pads
0:00:00.034457729 10143      0x23c4ed0 INFO                GST_PADS
gstpad.c:2083:gst_pad_link_prepare: trying to link demux:audio_0025
and queue1:sink
0:00:00.034530505 10143      0x23c5000 INFO              GST_STATES
gstelement.c:2328:gst_element_continue_state:<h264parse0> completed
state change to PAUSED
0:00:00.034540267 10143      0x23c5000 INFO              GST_STATES
gstelement.c:2233:_priv_gst_element_state_changed:<h264parse0>
notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.034603330 10143      0x23c5000 INFO                GST_PADS
gstpad.c:3626:gst_pad_peer_query:<'':decodepad0> pad has no peer
0:00:00.034664167 10143      0x23c5000 INFO                GST_PADS
gstpad.c:3626:gst_pad_peer_query:<'':decodepad0> pad has no peer
0:00:00.034754937 10143      0x23c5000 WARN               h264parse
gsth264parse.c:926:gst_h264_parse_handle_frame:<h264parse0> no SPS/PPS
yet, nal Type: 9 AU delimiter, Size: 2 will be dropped
0:00:00.034772049 10143      0x23c5000 WARN               h264parse
gsth264parse.c:926:gst_h264_parse_handle_frame:<h264parse0> no SPS/PPS
yet, nal Type: 6 SEI, Size: 5 will be dropped
0:00:00.034826584 10143      0x23c5000 WARN               h264parse
gsth264parse.c:926:gst_h264_parse_handle_frame:<h264parse0> no SPS/PPS
yet, nal Type: 1 Slice, Size: 15944 will be dropped
0:00:00.034841374 10143      0x23c5000 WARN               h264parse
gsth264parse.c:926:gst_h264_parse_handle_frame:<h264parse0> no SPS/PPS
yet, nal Type: 9 AU delimiter, Size: 2 will be dropped
0:00:00.034852631 10143      0x23c5000 WARN               h264parse
gsth264parse.c:926:gst_h264_parse_handle_frame:<h264parse0> no SPS/PPS
yet, nal Type: 6 SEI, Size: 5 will be dropped
0:00:00.034877077 10143      0x23c5000 WARN               h264parse
gsth264parse.c:926:gst_h264_parse_handle_frame:<h264parse0> no SPS/PPS
yet, nal Type: 1 Slice, Size: 5148 will be dropped
0:00:00.034893849 10143      0x23c5000 WARN               h264parse
gsth264parse.c:926:gst_h264_parse_handle_frame:<h264parse0> no SPS/PPS
yet, nal Type: 9 AU delimiter, Size: 2 will be dropped
0:00:00.034905027 10143      0x23c5000 WARN               h264parse
gsth264parse.c:926:gst_h264_parse_handle_frame:<h264parse0> no SPS/PPS
yet, nal Type: 6 SEI, Size: 5 will be dropped
0:00:00.034929713 10143      0x23c5000 WARN               h264parse
gsth264parse.c:926:gst_h264_parse_handle_frame:<h264parse0> no SPS/PPS
yet, nal Type: 1 Slice, Size: 4885 will be dropped
0:00:00.035045385 10143      0x23c4ed0 INFO                GST_PADS
gstpad.c:2139:gst_pad_link_prepare: caps are incompatible
0:00:00.035214941 10143      0x23c4ed0 INFO                 default
gstutils.c:1889:gst_element_link_pads_filtered: Could not link pads:
demux:audio_0025 - queue1:(null)
0:00:00.035214136 10143      0x23c5000 WARN               h264parse
gsth264parse.c:926:gst_h264_parse_handle_frame:<h264parse0> no SPS/PPS
yet, nal Type: 9 AU delimiter, Size: 2 will be dropped
0:00:00.035252794 10143      0x23c5000 WARN               h264parse
gsth264parse.c:926:gst_h264_parse_handle_frame:<h264parse0> no SPS/PPS
yet, nal Type: 6 SEI, Size: 5 will be dropped
0:00:00.035303851 10143      0x23c5000 WARN               h264parse
gsth264parse.c:926:gst_h264_parse_handle_frame:<h264parse0> no SPS/PPS
yet, nal Type: 1 Slice, Size: 5935 will be dropped
0:00:00.035325359 10143      0x23c5000 WARN               h264parse
gsth264parse.c:926:gst_h264_parse_handle_frame:<h264parse0> no SPS/PPS
yet, nal Type: 9 AU delimiter, Size: 2 will be dropped
0:00:00.035340806 10143      0x23c5000 WARN               h264parse
gsth264parse.c:926:gst_h264_parse_handle_frame:<h264parse0> no SPS/PPS
yet, nal Type: 6 SEI, Size: 5 will be dropped
0:00:00.035424118 10143      0x23c5000 WARN               h264parse
gsth264parse.c:926:gst_h264_parse_handle_frame:<h264parse0> no SPS/PPS
yet, nal Type: 1 Slice, Size: 33056 will be dropped
0:00:00.035444081 10143      0x23c5000 WARN               h264parse
gsth264parse.c:926:gst_h264_parse_handle_frame:<h264parse0> no SPS/PPS
yet, nal Type: 9 AU delimiter, Size: 2 will be dropped
0:00:00.035459150 10143      0x23c5000 WARN               h264parse
gsth264parse.c:926:gst_h264_parse_handle_frame:<h264parse0> no SPS/PPS
yet, nal Type: 6 SEI, Size: 5 will be dropped
0:00:00.035493822 10143      0x23c5000 WARN               h264parse
gsth264parse.c:926:gst_h264_parse_handle_frame:<h264parse0> no SPS/PPS
yet, nal Type: 1 Slice, Size: 9037 will be dropped
0:00:00.035510865 10143      0x23c5000 WARN               h264parse
gsth264parse.c:926:gst_h264_parse_handle_frame:<h264parse0> no SPS/PPS
yet, nal Type: 9 AU delimiter, Size: 2 will be dropped
0:00:00.035525828 10143      0x23c5000 WARN               h264parse
gsth264parse.c:926:gst_h264_parse_handle_frame:<h264parse0> no SPS/PPS
yet, nal Type: 6 SEI, Size: 5 will be dropped
0:00:00.035670458 10143      0x23c5000 WARN               h264parse
gsth264parse.c:926:gst_h264_parse_handle_frame:<h264parse0> no SPS/PPS
yet, nal Type: 1 Slice, Size: 4263 will be dropped
0:00:00.035711649 10143      0x23c5000 WARN               h264parse
gsth264parse.c:926:gst_h264_parse_handle_frame:<h264parse0> no SPS/PPS
yet, nal Type: 9 AU delimiter, Size: 2 will be dropped
0:00:00.036368090 10143      0x23c5000 INFO               h264parse
gsth264parse.c:1223:gst_h264_parse_update_src_caps:<h264parse0>
resolution changed 1920x1080
0:00:00.036383452 10143      0x23c5000 INFO               h264parse
gsth264parse.c:1247:gst_h264_parse_update_src_caps:<h264parse0> aspect
ratio idc changed 1
0:00:00.036404786 10143      0x23c5000 INFO               h264parse
gsth264parse.c:1315:gst_h264_parse_update_src_caps:<h264parse0> PAR
1/1
0:00:00.036423078 10143      0x23c5000 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event video/x-h264,
stream-format=(string)avc, alignment=(string)au, width=(int)1920,
height=(int)1080, parsed=(boolean)true,
pixel-aspect-ratio=(fraction)1/1,
codec_data=(buffer)014d4028ffe10017674d4028ab280f0089fbc04f000003000100000300329401000468ff3c80
0:00:00.036480594 10143      0x23c5000 INFO                GST_PADS
gstpad.c:3626:gst_pad_peer_query:<'':decodepad0> pad has no peer
0:00:00.036525590 10143      0x23c5000 INFO                GST_PADS
gstpad.c:3626:gst_pad_peer_query:<'':decodepad0> pad has no peer
0:00:00.036543911 10143      0x23c5000 INFO           basetransform
gstbasetransform.c:1335:gst_base_transform_setcaps:<capsfilter0> reuse
caps
0:00:00.036557266 10143      0x23c5000 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event video/x-h264,
stream-format=(string)avc, alignment=(string)au, width=(int)1920,
height=(int)1080, parsed=(boolean)true,
pixel-aspect-ratio=(fraction)1/1,
codec_data=(buffer)014d4028ffe10017674d4028ab280f0089fbc04f000003000100000300329401000468ff3c80
0:00:00.036593892 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstpad.c:1840:gst_pad_unlink: unlinking
capsfilter0:src(0x7fae58086bb0) and decodepad0:proxypad1(0x23ae910)
0:00:00.036617655 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstpad.c:1894:gst_pad_unlink: unlinked capsfilter0:src and
decodepad0:proxypad1
0:00:00.036638331 10143      0x23c5000 INFO                GST_PADS
gstpad.c:2083:gst_pad_link_prepare: trying to link capsfilter0:src and
decodepad0:proxypad1
0:00:00.036651065 10143      0x23c5000 INFO                GST_PADS
gstpad.c:2285:gst_pad_link_full: linked capsfilter0:src and
decodepad0:proxypad1, successful
0:00:00.036662166 10143      0x23c5000 INFO               GST_EVENT
gstevent.c:1313:gst_event_new_reconfigure: creating reconfigure event
0:00:00.036798271 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstpad.c:1840:gst_pad_unlink: unlinking
capsfilter0:src(0x7fae58086bb0) and decodepad0:proxypad1(0x23ae910)
0:00:00.036818951 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstpad.c:1894:gst_pad_unlink: unlinked capsfilter0:src and
decodepad0:proxypad1
0:00:00.036836691 10143      0x23c5000 INFO                GST_PADS
gstpad.c:2083:gst_pad_link_prepare: trying to link capsfilter0:src and
decodepad0:proxypad1
0:00:00.036848514 10143      0x23c5000 INFO                GST_PADS
gstpad.c:2285:gst_pad_link_full: linked capsfilter0:src and
decodepad0:proxypad1, successful
0:00:00.036859496 10143      0x23c5000 INFO               GST_EVENT
gstevent.c:1313:gst_event_new_reconfigure: creating reconfigure event
0:00:00.036883460 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstpad.c:1840:gst_pad_unlink: unlinking
capsfilter0:src(0x7fae58086bb0) and decodepad0:proxypad1(0x23ae910)
0:00:00.036902398 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstpad.c:1894:gst_pad_unlink: unlinked capsfilter0:src and
decodepad0:proxypad1
0:00:00.036919472 10143      0x23c5000 INFO                GST_PADS
gstpad.c:2083:gst_pad_link_prepare: trying to link capsfilter0:src and
decodepad0:proxypad1
0:00:00.036931154 10143      0x23c5000 INFO                GST_PADS
gstpad.c:2285:gst_pad_link_full: linked capsfilter0:src and
decodepad0:proxypad1, successful
0:00:00.036941702 10143      0x23c5000 INFO               GST_EVENT
gstevent.c:1313:gst_event_new_reconfigure: creating reconfigure event
0:00:00.036969709 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstpad.c:1840:gst_pad_unlink: unlinking
capsfilter0:src(0x7fae58086bb0) and decodepad0:proxypad1(0x23ae910)
0:00:00.036986894 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstpad.c:1894:gst_pad_unlink: unlinked capsfilter0:src and
decodepad0:proxypad1
0:00:00.040090327 10143      0x23c5000 INFO            GST_TYPEFIND
gsttypefind.c:73:gst_type_find_register: registering typefind function
for avtype_avs
0:00:00.040166362 10143      0x23c5000 INFO            GST_TYPEFIND
gsttypefind.c:73:gst_type_find_register: registering typefind function
for avtype_daud
0:00:00.040199092 10143      0x23c5000 INFO            GST_TYPEFIND
gsttypefind.c:73:gst_type_find_register: registering typefind function
for avtype_ea
0:00:00.040224023 10143      0x23c5000 INFO            GST_TYPEFIND
gsttypefind.c:73:gst_type_find_register: registering typefind function
for avtype_ffm
0:00:00.040254314 10143      0x23c5000 INFO            GST_TYPEFIND
gsttypefind.c:73:gst_type_find_register: registering typefind function
for avtype_4xm
0:00:00.040279251 10143      0x23c5000 INFO            GST_TYPEFIND
gsttypefind.c:73:gst_type_find_register: registering typefind function
for avtype_gxf
0:00:00.040303446 10143      0x23c5000 INFO            GST_TYPEFIND
gsttypefind.c:73:gst_type_find_register: registering typefind function
for avtype_idcin
0:00:00.040333683 10143      0x23c5000 INFO            GST_TYPEFIND
gsttypefind.c:73:gst_type_find_register: registering typefind function
for avtype_ipmovie
0:00:00.040366989 10143      0x23c5000 INFO            GST_TYPEFIND
gsttypefind.c:73:gst_type_find_register: registering typefind function
for avtype_mm
0:00:00.040403869 10143      0x23c5000 INFO            GST_TYPEFIND
gsttypefind.c:73:gst_type_find_register: registering typefind function
for avtype_mmf
0:00:00.040448109 10143      0x23c5000 INFO            GST_TYPEFIND
gsttypefind.c:73:gst_type_find_register: registering typefind function
for avtype_nsv
0:00:00.040474226 10143      0x23c5000 INFO            GST_TYPEFIND
gsttypefind.c:73:gst_type_find_register: registering typefind function
for avtype_nut
0:00:00.040524887 10143      0x23c5000 INFO            GST_TYPEFIND
gsttypefind.c:73:gst_type_find_register: registering typefind function
for avtype_film_cpk
0:00:00.040549324 10143      0x23c5000 INFO            GST_TYPEFIND
gsttypefind.c:73:gst_type_find_register: registering typefind function
for avtype_smk
0:00:00.040573018 10143      0x23c5000 INFO            GST_TYPEFIND
gsttypefind.c:73:gst_type_find_register: registering typefind function
for avtype_sol
0:00:00.040597789 10143      0x23c5000 INFO            GST_TYPEFIND
gsttypefind.c:73:gst_type_find_register: registering typefind function
for avtype_psxstr
0:00:00.040636003 10143      0x23c5000 INFO            GST_TYPEFIND
gsttypefind.c:73:gst_type_find_register: registering typefind function
for avtype_vmd
0:00:00.040664433 10143      0x23c5000 INFO            GST_TYPEFIND
gsttypefind.c:73:gst_type_find_register: registering typefind function
for avtype_wc3movie
0:00:00.040686489 10143      0x23c5000 INFO            GST_TYPEFIND
gsttypefind.c:73:gst_type_find_register: registering typefind function
for avtype_wsaud
0:00:00.040707994 10143      0x23c5000 INFO            GST_TYPEFIND
gsttypefind.c:73:gst_type_find_register: registering typefind function
for avtype_wsvqa
0:00:00.040734631 10143      0x23c5000 INFO            GST_TYPEFIND
gsttypefind.c:73:gst_type_find_register: registering typefind function
for avtype_yuv4mpegpipe
0:00:00.040986847 10143      0x23c5000 INFO      GST_PLUGIN_LOADING
gstplugin.c:830:gst_plugin_load_file: plugin
"/usr/lib64/gstreamer-1.0/libgstlibav.so" loaded
0:00:00.041001555 10143      0x23c5000 INFO     GST_ELEMENT_FACTORY
gstelementfactory.c:365:gst_element_factory_create: creating element
"avdec_h264"
0:00:00.041143696 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<GstVideoDecoder at 0x7fae5c0b8b70>
adding pad 'sink'
0:00:00.041169047 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<GstVideoDecoder at 0x7fae5c0b8b70>
adding pad 'src'
0:00:00.041652459 10143      0x23c5000 INFO                GST_PADS
gstpad.c:2083:gst_pad_link_prepare: trying to link capsfilter0:src and
avdec_h264-0:sink
0:00:00.041714886 10143      0x23c5000 INFO                GST_PADS
gstpad.c:2285:gst_pad_link_full: linked capsfilter0:src and
avdec_h264-0:sink, successful
0:00:00.041729595 10143      0x23c5000 INFO               GST_EVENT
gstevent.c:1313:gst_event_new_reconfigure: creating reconfigure event
0:00:00.041767218 10143      0x23c5000 INFO              GST_STATES
gstelement.c:2328:gst_element_continue_state:<avdec_h264-0> completed
state change to READY
0:00:00.041779311 10143      0x23c5000 INFO              GST_STATES
gstelement.c:2233:_priv_gst_element_state_changed:<avdec_h264-0>
notifying about state-changed NULL to READY (VOID_PENDING pending)
0:00:00.041823383 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstelement.c:897:gst_element_get_static_pad: found pad
avdec_h264-0:src
0:00:00.041845587 10143      0x23c5000 INFO                GST_PADS
gstpad.c:2083:gst_pad_link_prepare: trying to link avdec_h264-0:src
and decodepad0:proxypad1
0:00:00.041858767 10143      0x23c5000 INFO                GST_PADS
gstpad.c:2285:gst_pad_link_full: linked avdec_h264-0:src and
decodepad0:proxypad1, successful
0:00:00.041869708 10143      0x23c5000 INFO               GST_EVENT
gstevent.c:1313:gst_event_new_reconfigure: creating reconfigure event
0:00:00.041879262 10143      0x23c5000 INFO               GST_EVENT
gstpad.c:5033:gst_pad_send_event_unchecked:<avdec_h264-0:src> Received
event on flushing pad. Discarding
0:00:00.042316876 10143      0x23c5000 INFO              GST_STATES
gstelement.c:2328:gst_element_continue_state:<avdec_h264-0> completed
state change to PAUSED
0:00:00.042332446 10143      0x23c5000 INFO              GST_STATES
gstelement.c:2233:_priv_gst_element_state_changed:<avdec_h264-0>
notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.042575797 10143      0x23c5000 INFO           basetransform
gstbasetransform.c:1335:gst_base_transform_setcaps:<capsfilter0> reuse
caps
0:00:00.043746135 10143      0x23c5000 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event video/x-raw,
format=(string)I420, width=(int)1920, height=(int)1080,
pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)progressive,
colorimetry=(string)bt709, framerate=(fraction)0/1
0:00:00.043795991 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstelement.c:646:gst_element_add_pad:<decodebin0> adding pad 'src_0'
0:00:00.043813406 10143      0x23c5000 INFO            GST_PIPELINE
./grammar.y:506:gst_parse_found_pad: trying delayed linking
decodebin0:(NULL) to videoconvert0:(NULL)
0:00:00.043826980 10143      0x23c5000 INFO        GST_ELEMENT_PADS
gstutils.c:1543:gst_element_link_pads_full: trying to link element
decodebin0:(any) to element videoconvert0:(any)
0:00:00.043842042 10143      0x23c5000 INFO                GST_PADS
gstutils.c:936:gst_pad_check_link: trying to link decodebin0:src_0 and
videoconvert0:sink
0:00:00.044070104 10143      0x23c5000 INFO                GST_PADS
gstutils.c:1443:prepare_link_maybe_ghosting: decodebin0 and
videoconvert0 in same bin, no need for ghost pads
0:00:00.044090583 10143      0x23c5000 INFO                GST_PADS
gstpad.c:2083:gst_pad_link_prepare: trying to link decodebin0:src_0
and videoconvert0:sink
0:00:00.044305264 10143      0x23c5000 INFO                GST_PADS
gstpad.c:2285:gst_pad_link_full: linked decodebin0:src_0 and
videoconvert0:sink, successful
0:00:00.044321122 10143      0x23c5000 INFO               GST_EVENT
gstevent.c:1313:gst_event_new_reconfigure: creating reconfigure event
0:00:00.044353964 10143      0x23c5000 INFO               decodebin
gstdecodebin2.c:3966:gst_decode_bin_expose:<decodebin0:src_0> added
new decoded pad
0:00:00.044371326 10143      0x23c5000 INFO              GST_STATES
gstbin.c:3112:bin_handle_async_done:<decodebin0> committing state from
READY to PAUSED, old pending PAUSED
0:00:00.044383735 10143      0x23c5000 INFO              GST_STATES
gstbin.c:3132:bin_handle_async_done:<decodebin0> completed state
change, pending VOID
0:00:00.044392763 10143      0x23c5000 INFO              GST_STATES
gstelement.c:2233:_priv_gst_element_state_changed:<decodebin0>
notifying about state-changed READY to PAUSED (VOID_PENDING pending)
0:00:00.045218392 10143      0x23c5000 INFO           basetransform
gstbasetransform.c:1335:gst_base_transform_setcaps:<videoconvert0>
reuse caps
0:00:00.045254621 10143      0x23c5000 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event video/x-raw,
format=(string)I420, width=(int)1920, height=(int)1080,
pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)progressive,
colorimetry=(string)bt709, framerate=(fraction)0/1
0:00:00.046079828 10143      0x23c5000 WARN              bufferpool
gstbufferpool.c:632:gst_buffer_pool_set_config:<xvimagebufferpool0>
can't change config, we are active
0:00:00.046114059 10143      0x23c5000 WARN              bufferpool
gstbufferpool.c:632:gst_buffer_pool_set_config:<xvimagebufferpool0>
can't change config, we are active
0:00:00.046755902 10143      0x23c5000 WARN                   libav
gstavviddec.c:688:gst_ffmpegviddec_get_buffer:<avdec_h264-0> already
alloc'ed output buffer for frame
0:00:00.046779004 10143 0x7fae5c0506c0 ERROR                  libav
:0:: get_buffer() failed (-1 0 (nil))
0:00:00.046808927 10143 0x7fae5c0506c0 ERROR                  libav
:0:: decode_slice_header error
0:00:00.046964234 10143 0x7fae5c0506c0 INFO                   libav
:0:: concealing 8160 DC, 8160 AC, 8160 MV errors
0:00:00.082851475 10143      0x23c5000 INFO           basetransform
gstbasetransform.c:1335:gst_base_transform_setcaps:<capsfilter0> reuse
caps
0:00:00.088034053 10143 0x7fae5c0506c0 ERROR                  libav
:0:: reference picture missing during reorder
0:00:00.088071109 10143 0x7fae5c0506c0 ERROR                  libav
:0:: Missing reference picture
0:00:00.102760675 10143      0x23c5000 WARN                   libav
gstavviddec.c:1215:gst_ffmpegviddec_video_frame: Change in interlacing
! picture:1, recorded:0
0:00:00.102839958 10143      0x23c5000 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event video/x-raw,
format=(string)I420, width=(int)1920, height=(int)1080,
pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)mixed,
colorimetry=(string)bt709, framerate=(fraction)0/1
0:00:00.103743000 10143      0x23c5000 INFO           basetransform
gstbasetransform.c:1335:gst_base_transform_setcaps:<videoconvert0>
reuse caps
0:00:00.103780250 10143      0x23c5000 INFO               GST_EVENT
gstevent.c:628:gst_event_new_caps: creating caps event video/x-raw,
format=(string)I420, width=(int)1920, height=(int)1080,
pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)mixed,
colorimetry=(string)bt709, framerate=(fraction)0/1


Any suggestions?

-- 
Andrey Utkin


More information about the gstreamer-devel mailing list