gstreamer: Plipeline from decodebin2 stops

Jayanth K.P kp.jayanth at gmail.com
Mon Jan 23 07:15:28 PST 2012


Hi,
   I have constructed a pipeline using decodebin2 to demux flv(h.264)
, mux it to MPEG2 TS and sink to a file. The source is http link.

 I am seeing a strange issue whenever stream goes down and comes up
many times when the pipeline is PLAYING, at some point pipeline stops.

I have enable debug in gst (export GST_DEBUG="*:5") . I see these logs
. Plz suggest what is going wrong .

I have gstreamer version : 0.10.32
          plugin base         : 0.10.32

I am attaching the LOG ..



Regards,
Jayanth


0:20:14.301614000 26390       0xbc05a0 LOG           GST_SCHEDULING
gstpad.c:4663:gst_pad_push:<mpegts mux:src> calling chainfunction
&gst_base_sink_chain
0:20:14.301630000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:3519:gst_base_sink_chain_unlocked:<ts-filesink> got
times start: 0:02:11.120000000, end: 99:99:99.999999999
0:20:14.301646000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:1928:gst_base_sink_get_sync_times:<ts-filesink> got
times start: 0:02:11.120000000, stop: 99:99:99.999999999, do_sync 0
0:20:14.301662000 26390       0xbc05a0 LOG                 basesink
gstbasesink.c:1939:gst_base_sink_get_sync_times:<ts-filesink> not time
format, don't clip
0:20:14.301676000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:2860:gst_base_sink_render_object:<ts-filesink> rendering
object 0x2aaaac5302c0
0:20:14.301690000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:980:gst_base_sink_set_last_buffer_unlocked:<ts-filesink>
setting last buffer to 0x2aaaac5302c0
0:20:14.301703000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:195:gst_buffer_finalize: finalize 0x13bff20
0:20:14.301721000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:2974:gst_base_sink_render_object:<ts-filesink> object
unref after render 0x2aaaac5302c0
0:20:14.301737000 26390       0xbc05a0 LOG           GST_SCHEDULING
gstpad.c:4670:gst_pad_push:<mpegts mux:src> called chainfunction
&gst_base_sink_chain, returned ok
0:20:14.301949000 26390       0xbc05a0 LOG                mpegtsmux
mpegtsmux.c:918:new_packet_cb:<mpegts mux> Outputting a packet of
length 188
0:20:14.301967000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:311:gst_buffer_init: init 0x2aaaac592ac0
0:20:14.301982000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:336:gst_buffer_new: new 0x2aaaac592ac0
0:20:14.301995000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:385:gst_buffer_new_and_alloc: new 0x2aaaac592ac0 of size
188
0:20:14.302011000 26390       0xbc05a0 LOG                mpegtsmux
mpegtsmux.c:945:new_packet_cb:<mpegts mux> marking as delta unit
0:20:14.302026000 26390       0xbc05a0 LOG           GST_SCHEDULING
gstpad.c:4663:gst_pad_push:<mpegts mux:src> calling chainfunction
&gst_base_sink_chain
0:20:14.302043000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:3519:gst_base_sink_chain_unlocked:<ts-filesink> got
times start: 0:02:11.120000000, end: 99:99:99.999999999
0:20:14.302060000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:1928:gst_base_sink_get_sync_times:<ts-filesink> got
times start: 0:02:11.120000000, stop: 99:99:99.999999999, do_sync 0
0:20:14.302077000 26390       0xbc05a0 LOG                 basesink
gstbasesink.c:1939:gst_base_sink_get_sync_times:<ts-filesink> not time
format, don't clip
0:20:14.302093000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:2860:gst_base_sink_render_object:<ts-filesink> rendering
object 0x2aaaac592ac0
0:20:14.302107000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:980:gst_base_sink_set_last_buffer_unlocked:<ts-filesink>
setting last buffer to 0x2aaaac592ac0
0:20:14.302121000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:195:gst_buffer_finalize: finalize 0x2aaaac5302c0
0:20:14.302140000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:2974:gst_base_sink_render_object:<ts-filesink> object
unref after render 0x2aaaac592ac0
0:20:14.302156000 26390       0xbc05a0 LOG           GST_SCHEDULING
gstpad.c:4670:gst_pad_push:<mpegts mux:src> called chainfunction
&gst_base_sink_chain, returned ok
0:20:14.302174000 26390       0xbc05a0 LOG                mpegtsmux
mpegtsmux.c:918:new_packet_cb:<mpegts mux> Outputting a packet of
length 188
0:20:14.302188000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:311:gst_buffer_init: init 0x2aaaac577030
0:20:14.302202000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:336:gst_buffer_new: new 0x2aaaac577030
0:20:14.302215000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:385:gst_buffer_new_and_alloc: new 0x2aaaac577030 of size
188
0:20:14.302230000 26390       0xbc05a0 LOG                mpegtsmux
mpegtsmux.c:945:new_packet_cb:<mpegts mux> marking as delta unit
0:20:14.302245000 26390       0xbc05a0 LOG           GST_SCHEDULING
gstpad.c:4663:gst_pad_push:<mpegts mux:src> calling chainfunction
&gst_base_sink_chain
0:20:14.302260000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:3519:gst_base_sink_chain_unlocked:<ts-filesink> got
times start: 0:02:11.120000000, end: 99:99:99.999999999
0:20:14.302453000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:1928:gst_base_sink_get_sync_times:<ts-filesink> got
times start: 0:02:11.120000000, stop: 99:99:99.999999999, do_sync 0
0:20:14.302473000 26390       0xbc05a0 LOG                 basesink
gstbasesink.c:1939:gst_base_sink_get_sync_times:<ts-filesink> not time
format, don't clip
0:20:14.302489000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:2860:gst_base_sink_render_object:<ts-filesink> rendering
object 0x2aaaac577030
0:20:14.302503000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:980:gst_base_sink_set_last_buffer_unlocked:<ts-filesink>
setting last buffer to 0x2aaaac577030
0:20:14.302518000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:195:gst_buffer_finalize: finalize 0x2aaaac592ac0
0:20:14.302539000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:2974:gst_base_sink_render_object:<ts-filesink> object
unref after render 0x2aaaac577030
0:20:14.302556000 26390       0xbc05a0 LOG           GST_SCHEDULING
gstpad.c:4670:gst_pad_push:<mpegts mux:src> called chainfunction
&gst_base_sink_chain, returned ok
0:20:14.302575000 26390       0xbc05a0 LOG                mpegtsmux
mpegtsmux.c:918:new_packet_cb:<mpegts mux> Outputting a packet of
length 188
0:20:14.302589000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:311:gst_buffer_init: init 0x2aaaac55d730
0:20:14.302603000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:336:gst_buffer_new: new 0x2aaaac55d730
0:20:14.302616000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:385:gst_buffer_new_and_alloc: new 0x2aaaac55d730 of size
188
0:20:14.302631000 26390       0xbc05a0 LOG                mpegtsmux
mpegtsmux.c:945:new_packet_cb:<mpegts mux> marking as delta unit
0:20:14.302647000 26390       0xbc05a0 LOG           GST_SCHEDULING
gstpad.c:4663:gst_pad_push:<mpegts mux:src> calling chainfunction
&gst_base_sink_chain
0:20:14.302662000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:3519:gst_base_sink_chain_unlocked:<ts-filesink> got
times start: 0:02:11.120000000, end: 99:99:99.999999999
0:20:14.302678000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:1928:gst_base_sink_get_sync_times:<ts-filesink> got
times start: 0:02:11.120000000, stop: 99:99:99.999999999, do_sync 0
0:20:14.302694000 26390       0xbc05a0 LOG                 basesink
gstbasesink.c:1939:gst_base_sink_get_sync_times:<ts-filesink> not time
format, don't clip
0:20:14.302708000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:2860:gst_base_sink_render_object:<ts-filesink> rendering
object 0x2aaaac55d730
0:20:14.302722000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:980:gst_base_sink_set_last_buffer_unlocked:<ts-filesink>
setting last buffer to 0x2aaaac55d730
0:20:14.302735000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:195:gst_buffer_finalize: finalize 0x2aaaac577030
0:20:14.302753000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:2974:gst_base_sink_render_object:<ts-filesink> object
unref after render 0x2aaaac55d730
0:20:14.302768000 26390       0xbc05a0 LOG           GST_SCHEDULING
gstpad.c:4670:gst_pad_push:<mpegts mux:src> called chainfunction
&gst_base_sink_chain, returned ok
0:20:14.302786000 26390       0xbc05a0 LOG                mpegtsmux
mpegtsmux.c:918:new_packet_cb:<mpegts mux> Outputting a packet of
length 188
0:20:14.302970000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:311:gst_buffer_init: init 0x2aaaac5303c0
0:20:14.302987000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:336:gst_buffer_new: new 0x2aaaac5303c0
0:20:14.303000000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:385:gst_buffer_new_and_alloc: new 0x2aaaac5303c0 of size
188
0:20:14.303017000 26390       0xbc05a0 LOG                mpegtsmux
mpegtsmux.c:945:new_packet_cb:<mpegts mux> marking as delta unit
0:20:14.303032000 26390       0xbc05a0 LOG           GST_SCHEDULING
gstpad.c:4663:gst_pad_push:<mpegts mux:src> calling chainfunction
&gst_base_sink_chain
0:20:14.303049000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:3519:gst_base_sink_chain_unlocked:<ts-filesink> got
times start: 0:02:11.120000000, end: 99:99:99.999999999
0:20:14.303066000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:1928:gst_base_sink_get_sync_times:<ts-filesink> got
times start: 0:02:11.120000000, stop: 99:99:99.999999999, do_sync 0
0:20:14.303083000 26390       0xbc05a0 LOG                 basesink
gstbasesink.c:1939:gst_base_sink_get_sync_times:<ts-filesink> not time
format, don't clip
0:20:14.303098000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:2860:gst_base_sink_render_object:<ts-filesink> rendering
object 0x2aaaac5303c0
0:20:14.303112000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:980:gst_base_sink_set_last_buffer_unlocked:<ts-filesink>
setting last buffer to 0x2aaaac5303c0
0:20:14.303126000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:195:gst_buffer_finalize: finalize 0x2aaaac55d730
0:20:14.303145000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:2974:gst_base_sink_render_object:<ts-filesink> object
unref after render 0x2aaaac5303c0
0:20:14.303161000 26390       0xbc05a0 LOG           GST_SCHEDULING
gstpad.c:4670:gst_pad_push:<mpegts mux:src> called chainfunction
&gst_base_sink_chain, returned ok
0:20:14.303179000 26390       0xbc05a0 LOG                mpegtsmux
mpegtsmux.c:918:new_packet_cb:<mpegts mux> Outputting a packet of
length 188
0:20:14.303194000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:311:gst_buffer_init: init 0x2aaaac588cc0
0:20:14.303207000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:336:gst_buffer_new: new 0x2aaaac588cc0
0:20:14.303220000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:385:gst_buffer_new_and_alloc: new 0x2aaaac588cc0 of size
188
0:20:14.303235000 26390       0xbc05a0 LOG                mpegtsmux
mpegtsmux.c:945:new_packet_cb:<mpegts mux> marking as delta unit
0:20:14.303250000 26390       0xbc05a0 LOG           GST_SCHEDULING
gstpad.c:4663:gst_pad_push:<mpegts mux:src> calling chainfunction
&gst_base_sink_chain
0:20:14.303265000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:3519:gst_base_sink_chain_unlocked:<ts-filesink> got
times start: 0:02:11.120000000, end: 99:99:99.999999999
0:20:14.303282000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:1928:gst_base_sink_get_sync_times:<ts-filesink> got
times start: 0:02:11.120000000, stop: 99:99:99.999999999, do_sync 0
0:20:14.303464000 26390       0xbc05a0 LOG                 basesink
gstbasesink.c:1939:gst_base_sink_get_sync_times:<ts-filesink> not time
format, don't clip
0:20:14.303483000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:2860:gst_base_sink_render_object:<ts-filesink> rendering
object 0x2aaaac588cc0
0:20:14.303497000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:980:gst_base_sink_set_last_buffer_unlocked:<ts-filesink>
setting last buffer to 0x2aaaac588cc0
0:20:14.303511000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:195:gst_buffer_finalize: finalize 0x2aaaac5303c0
0:20:14.303530000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:2974:gst_base_sink_render_object:<ts-filesink> object
unref after render 0x2aaaac588cc0
0:20:14.303547000 26390       0xbc05a0 LOG           GST_SCHEDULING
gstpad.c:4670:gst_pad_push:<mpegts mux:src> called chainfunction
&gst_base_sink_chain, returned ok
0:20:14.303566000 26390       0xbc05a0 LOG                mpegtsmux
mpegtsmux.c:918:new_packet_cb:<mpegts mux> Outputting a packet of
length 188
0:20:14.303581000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:311:gst_buffer_init: init 0x2aaaac55d4b0
0:20:14.303594000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:336:gst_buffer_new: new 0x2aaaac55d4b0
0:20:14.303607000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:385:gst_buffer_new_and_alloc: new 0x2aaaac55d4b0 of size
188
0:20:14.303622000 26390       0xbc05a0 LOG                mpegtsmux
mpegtsmux.c:945:new_packet_cb:<mpegts mux> marking as delta unit
0:20:14.303637000 26390       0xbc05a0 LOG           GST_SCHEDULING
gstpad.c:4663:gst_pad_push:<mpegts mux:src> calling chainfunction
&gst_base_sink_chain
0:20:14.303653000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:3519:gst_base_sink_chain_unlocked:<ts-filesink> got
times start: 0:02:11.120000000, end: 99:99:99.999999999
0:20:14.303670000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:1928:gst_base_sink_get_sync_times:<ts-filesink> got
times start: 0:02:11.120000000, stop: 99:99:99.999999999, do_sync 0
0:20:14.303685000 26390       0xbc05a0 LOG                 basesink
gstbasesink.c:1939:gst_base_sink_get_sync_times:<ts-filesink> not time
format, don't clip
0:20:14.303700000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:2860:gst_base_sink_render_object:<ts-filesink> rendering
object 0x2aaaac55d4b0
0:20:14.303713000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:980:gst_base_sink_set_last_buffer_unlocked:<ts-filesink>
setting last buffer to 0x2aaaac55d4b0
0:20:14.303726000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:195:gst_buffer_finalize: finalize 0x2aaaac588cc0
0:20:14.303744000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:2974:gst_base_sink_render_object:<ts-filesink> object
unref after render 0x2aaaac55d4b0
0:20:14.303760000 26390       0xbc05a0 LOG           GST_SCHEDULING
gstpad.c:4670:gst_pad_push:<mpegts mux:src> called chainfunction
&gst_base_sink_chain, returned ok
0:20:14.303777000 26390       0xbc05a0 LOG                mpegtsmux
mpegtsmux.c:918:new_packet_cb:<mpegts mux> Outputting a packet of
length 188
0:20:14.303792000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:311:gst_buffer_init: init 0x2aaaac5545b0
0:20:14.303970000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:336:gst_buffer_new: new 0x2aaaac5545b0
0:20:14.303988000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:385:gst_buffer_new_and_alloc: new 0x2aaaac5545b0 of size
188
0:20:14.304005000 26390       0xbc05a0 LOG                mpegtsmux
mpegtsmux.c:945:new_packet_cb:<mpegts mux> marking as delta unit
0:20:14.304020000 26390       0xbc05a0 LOG           GST_SCHEDULING
gstpad.c:4663:gst_pad_push:<mpegts mux:src> calling chainfunction
&gst_base_sink_chain
0:20:14.304037000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:3519:gst_base_sink_chain_unlocked:<ts-filesink> got
times start: 0:02:11.120000000, end: 99:99:99.999999999
0:20:14.304055000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:1928:gst_base_sink_get_sync_times:<ts-filesink> got
times start: 0:02:11.120000000, stop: 99:99:99.999999999, do_sync 0
0:20:14.304071000 26390       0xbc05a0 LOG                 basesink
gstbasesink.c:1939:gst_base_sink_get_sync_times:<ts-filesink> not time
format, don't clip
0:20:14.304087000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:2860:gst_base_sink_render_object:<ts-filesink> rendering
object 0x2aaaac5545b0
0:20:14.304100000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:980:gst_base_sink_set_last_buffer_unlocked:<ts-filesink>
setting last buffer to 0x2aaaac5545b0
0:20:14.304120000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:195:gst_buffer_finalize: finalize 0x2aaaac55d4b0
0:20:14.304140000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:2974:gst_base_sink_render_object:<ts-filesink> object
unref after render 0x2aaaac5545b0
0:20:14.304156000 26390       0xbc05a0 LOG           GST_SCHEDULING
gstpad.c:4670:gst_pad_push:<mpegts mux:src> called chainfunction
&gst_base_sink_chain, returned ok
0:20:14.304173000 26390       0xbc05a0 LOG                mpegtsmux
mpegtsmux.c:918:new_packet_cb:<mpegts mux> Outputting a packet of
length 188
0:20:14.304188000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:311:gst_buffer_init: init 0x2aaaac588cc0
0:20:14.304201000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:336:gst_buffer_new: new 0x2aaaac588cc0
0:20:14.304214000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:385:gst_buffer_new_and_alloc: new 0x2aaaac588cc0 of size
188
0:20:14.304229000 26390       0xbc05a0 LOG                mpegtsmux
mpegtsmux.c:945:new_packet_cb:<mpegts mux> marking as delta unit
0:20:14.304244000 26390       0xbc05a0 LOG           GST_SCHEDULING
gstpad.c:4663:gst_pad_push:<mpegts mux:src> calling chainfunction
&gst_base_sink_chain
0:20:14.304259000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:3519:gst_base_sink_chain_unlocked:<ts-filesink> got
times start: 0:02:11.120000000, end: 99:99:99.999999999
0:20:14.304275000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:1928:gst_base_sink_get_sync_times:<ts-filesink> got
times start: 0:02:11.120000000, stop: 99:99:99.999999999, do_sync 0
0:20:14.304291000 26390       0xbc05a0 LOG                 basesink
gstbasesink.c:1939:gst_base_sink_get_sync_times:<ts-filesink> not time
format, don't clip
0:20:14.304305000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:2860:gst_base_sink_render_object:<ts-filesink> rendering
object 0x2aaaac588cc0
0:20:14.304485000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:980:gst_base_sink_set_last_buffer_unlocked:<ts-filesink>
setting last buffer to 0x2aaaac588cc0
0:20:14.304502000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:195:gst_buffer_finalize: finalize 0x2aaaac5545b0
0:20:14.304523000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:2974:gst_base_sink_render_object:<ts-filesink> object
unref after render 0x2aaaac588cc0
0:20:14.304539000 26390       0xbc05a0 LOG           GST_SCHEDULING
gstpad.c:4670:gst_pad_push:<mpegts mux:src> called chainfunction
&gst_base_sink_chain, returned ok
0:20:14.304558000 26390       0xbc05a0 LOG                mpegtsmux
mpegtsmux.c:918:new_packet_cb:<mpegts mux> Outputting a packet of
length 188
0:20:14.304573000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:311:gst_buffer_init: init 0x2aaaac5303c0
0:20:14.304587000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:336:gst_buffer_new: new 0x2aaaac5303c0
0:20:14.304600000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:385:gst_buffer_new_and_alloc: new 0x2aaaac5303c0 of size
188
0:20:14.304616000 26390       0xbc05a0 LOG                mpegtsmux
mpegtsmux.c:945:new_packet_cb:<mpegts mux> marking as delta unit
0:20:14.304630000 26390       0xbc05a0 LOG           GST_SCHEDULING
gstpad.c:4663:gst_pad_push:<mpegts mux:src> calling chainfunction
&gst_base_sink_chain
0:20:14.304646000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:3519:gst_base_sink_chain_unlocked:<ts-filesink> got
times start: 0:02:11.120000000, end: 99:99:99.999999999
0:20:14.304663000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:1928:gst_base_sink_get_sync_times:<ts-filesink> got
times start: 0:02:11.120000000, stop: 99:99:99.999999999, do_sync 0
0:20:14.304678000 26390       0xbc05a0 LOG                 basesink
gstbasesink.c:1939:gst_base_sink_get_sync_times:<ts-filesink> not time
format, don't clip
0:20:14.304693000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:2860:gst_base_sink_render_object:<ts-filesink> rendering
object 0x2aaaac5303c0
0:20:14.304707000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:980:gst_base_sink_set_last_buffer_unlocked:<ts-filesink>
setting last buffer to 0x2aaaac5303c0
0:20:14.304720000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:195:gst_buffer_finalize: finalize 0x2aaaac588cc0
0:20:14.304738000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:2974:gst_base_sink_render_object:<ts-filesink> object
unref after render 0x2aaaac5303c0
0:20:14.304754000 26390       0xbc05a0 LOG           GST_SCHEDULING
gstpad.c:4670:gst_pad_push:<mpegts mux:src> called chainfunction
&gst_base_sink_chain, returned ok
0:20:14.304771000 26390       0xbc05a0 LOG                mpegtsmux
mpegtsmux.c:918:new_packet_cb:<mpegts mux> Outputting a packet of
length 188
0:20:14.304793000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:311:gst_buffer_init: init 0x2aaaac55d730
0:20:14.304806000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:336:gst_buffer_new: new 0x2aaaac55d730
0:20:14.304819000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:385:gst_buffer_new_and_alloc: new 0x2aaaac55d730 of size
188
0:20:14.305009000 26390       0xbc05a0 LOG                mpegtsmux
mpegtsmux.c:945:new_packet_cb:<mpegts mux> marking as delta unit
0:20:14.305027000 26390       0xbc05a0 LOG           GST_SCHEDULING
gstpad.c:4663:gst_pad_push:<mpegts mux:src> calling chainfunction
&gst_base_sink_chain
0:20:14.305044000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:3519:gst_base_sink_chain_unlocked:<ts-filesink> got
times start: 0:02:11.120000000, end: 99:99:99.999999999
0:20:14.305061000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:1928:gst_base_sink_get_sync_times:<ts-filesink> got
times start: 0:02:11.120000000, stop: 99:99:99.999999999, do_sync 0
0:20:14.305078000 26390       0xbc05a0 LOG                 basesink
gstbasesink.c:1939:gst_base_sink_get_sync_times:<ts-filesink> not time
format, don't clip
0:20:14.305093000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:2860:gst_base_sink_render_object:<ts-filesink> rendering
object 0x2aaaac55d730
0:20:14.305107000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:980:gst_base_sink_set_last_buffer_unlocked:<ts-filesink>
setting last buffer to 0x2aaaac55d730
0:20:14.305121000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:195:gst_buffer_finalize: finalize 0x2aaaac5303c0
0:20:14.305141000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:2974:gst_base_sink_render_object:<ts-filesink> object
unref after render 0x2aaaac55d730
0:20:14.305156000 26390       0xbc05a0 LOG           GST_SCHEDULING
gstpad.c:4670:gst_pad_push:<mpegts mux:src> called chainfunction
&gst_base_sink_chain, returned ok
0:20:14.305175000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:195:gst_buffer_finalize: finalize 0x2aaaac531e20
0:20:14.305194000 26390       0xbc05a0 LOG                mpegtsmux
mpegtsmux.c:918:new_packet_cb:<mpegts mux> Outputting a packet of
length 188
0:20:14.305208000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:311:gst_buffer_init: init 0x2aaaac577030
0:20:14.305221000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:336:gst_buffer_new: new 0x2aaaac577030
0:20:14.305234000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:385:gst_buffer_new_and_alloc: new 0x2aaaac577030 of size
188
0:20:14.305250000 26390       0xbc05a0 LOG                mpegtsmux
mpegtsmux.c:945:new_packet_cb:<mpegts mux> marking as delta unit
0:20:14.305264000 26390       0xbc05a0 LOG           GST_SCHEDULING
gstpad.c:4663:gst_pad_push:<mpegts mux:src> calling chainfunction
&gst_base_sink_chain
0:20:14.305280000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:3519:gst_base_sink_chain_unlocked:<ts-filesink> got
times start: 0:02:11.120000000, end: 99:99:99.999999999
0:20:14.305296000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:1928:gst_base_sink_get_sync_times:<ts-filesink> got
times start: 0:02:11.120000000, stop: 99:99:99.999999999, do_sync 0
0:20:14.305312000 26390       0xbc05a0 LOG                 basesink
gstbasesink.c:1939:gst_base_sink_get_sync_times:<ts-filesink> not time
format, don't clip
0:20:14.305326000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:2860:gst_base_sink_render_object:<ts-filesink> rendering
object 0x2aaaac577030
0:20:14.305507000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:980:gst_base_sink_set_last_buffer_unlocked:<ts-filesink>
setting last buffer to 0x2aaaac577030
0:20:14.305524000 26390       0xbc05a0 LOG               GST_BUFFER
gstbuffer.c:195:gst_buffer_finalize: finalize 0x2aaaac55d730
0:20:14.305544000 26390       0xbc05a0 DEBUG               basesink
gstbasesink.c:2974:gst_base_sink_render_object:<ts-filesink> object
unref after render 0x2aaaac577030
0:20:14.305561000 26390       0xbc05a0 LOG           GST_SCHEDULING
gstpad.c:4670:gst_pad_push:<mpegts mux:src> called chainfunction
&gst_base_sink_chain, returned ok
0:20:14.305578000 26390       0xbc05a0 DEBUG            collectpads
gstcollectpads.c:1411:gst_collect_pads_chain: Pad mpegts mux:sink_64
done
0:20:14.305601000 26390       0xbc05a0 LOG           GST_SCHEDULING
gstpad.c:4670:gst_pad_push:<decodebin20:src0> called chainfunction
&gst_collect_pads_chain, returned ok
0:20:14.305619000 26390       0xbc05a0 LOG           GST_SCHEDULING
gstpad.c:4670:gst_pad_push:<multiqueue0:src0> called chainfunction
&gst_proxy_pad_do_chain, returned ok
0:20:14.305637000 26390       0xbc05a0 LOG               multiqueue
gstmultiqueue.c:1109:gst_multi_queue_loop:<multiqueue0> AFTER PUSHING
sq->srcresult: ok
0:20:14.305652000 26390       0xbc05a0 DEBUG             multiqueue
gstmultiqueue.c:1026:gst_multi_queue_loop:<multiqueue0> SingleQueue 0
: trying to pop an object
0:20:14.305666000 26390       0xbc05a0 LOG      data_queue_dataflow
gstdataqueue.c:487:gst_data_queue_pop: locking qlock from thread
0xbc05a0
0:20:14.305679000 26390       0xbc05a0 LOG      data_queue_dataflow
gstdataqueue.c:487:gst_data_queue_pop: locked qlock from thread
0xbc05a0
0:20:14.305691000 26390       0xbc05a0 LOG      data_queue_dataflow
gstdataqueue.c:489:gst_data_queue_pop: queue:0xb74740 before popping:
0 visible items, 0 bytes, 0 ns, 0 elements
0:20:14.305704000 26390       0xbc05a0 LOG      data_queue_dataflow
gstdataqueue.c:492:gst_data_queue_pop: unlocking qlock from thread
0xbc05a0
0:20:14.305718000 26390       0xbc05a0 LOG               multiqueue
gstmultiqueue.c:1570:single_queue_underrun_cb:<multiqueue0> Single
Queue 0 is empty, Checking other single queues
0:20:14.305731000 26390       0xbc05a0 LOG      data_queue_dataflow
gstdataqueue.c:356:gst_data_queue_is_full: locking qlock from thread
0xbc05a0
0:20:14.305744000 26390       0xbc05a0 LOG      data_queue_dataflow
gstdataqueue.c:356:gst_data_queue_is_full: locked qlock from thread
0xbc05a0
0:20:14.305757000 26390       0xbc05a0 DEBUG             multiqueue
gstmultiqueue.c:1609:single_queue_check_full:<multiqueue0> queue 0:
visible 0/5, bytes 0/2097152, time 0/0
0:20:14.305772000 26390       0xbc05a0 LOG      data_queue_dataflow
gstdataqueue.c:358:gst_data_queue_is_full: unlocking qlock from thread
0xbc05a0
0:20:14.305784000 26390       0xbc05a0 LOG      data_queue_dataflow
gstdataqueue.c:332:gst_data_queue_is_empty: locking qlock from thread
0xbc05a0
0:20:14.305795000 26390       0xbc05a0 LOG      data_queue_dataflow
gstdataqueue.c:332:gst_data_queue_is_empty: locked qlock from thread
0xbc05a0
0:20:14.305806000 26390       0xbc05a0 LOG      data_queue_dataflow
gstdataqueue.c:334:gst_data_queue_is_empty: unlocking qlock from
thread 0xbc05a0
0:20:14.305818000 26390       0xbc05a0 LOG      data_queue_dataflow
gstdataqueue.c:356:gst_data_queue_is_full: locking qlock from thread
0xbc05a0
0:20:14.306016000 26390       0xbc05a0 LOG      data_queue_dataflow
gstdataqueue.c:356:gst_data_queue_is_full: locked qlock from thread
0xbc05a0
0:20:14.306035000 26390       0xbc05a0 DEBUG             multiqueue
gstmultiqueue.c:1609:single_queue_check_full:<multiqueue0> queue 1:
visible 5461/5461, bytes 2097024/2097152, time 0/0
0:20:14.306050000 26390       0xbc05a0 LOG      data_queue_dataflow
gstdataqueue.c:358:gst_data_queue_is_full: unlocking qlock from thread
0xbc05a0
0:20:14.306065000 26390       0xbc05a0 DEBUG             multiqueue
gstmultiqueue.c:1584:single_queue_underrun_cb:<multiqueue0> queue 1 is
filled, bumping its max visible to 5462
0:20:14.306079000 26390       0xbc05a0 LOG      data_queue_dataflow
gstdataqueue.c:602:gst_data_queue_limits_changed: locking qlock from
thread 0xbc05a0
0:20:14.306091000 26390       0xbc05a0 LOG      data_queue_dataflow
gstdataqueue.c:602:gst_data_queue_limits_changed: locked qlock from
thread 0xbc05a0
0:20:14.306103000 26390       0xbc05a0 DEBUG              dataqueue
gstdataqueue.c:604:gst_data_queue_limits_changed: signal del
0:20:14.306119000 26390       0xbc05a0 LOG      data_queue_dataflow
gstdataqueue.c:607:gst_data_queue_limits_changed: unlocking qlock from
thread 0xbc05a0
0:20:14.306131000 26390       0xbc05a0 LOG      data_queue_dataflow
gstdataqueue.c:332:gst_data_queue_is_empty: locking qlock from thread
0xbc05a0
0:20:14.306142000 26390       0xbc05a0 LOG      data_queue_dataflow
gstdataqueue.c:332:gst_data_queue_is_empty: locked qlock from thread
0xbc05a0
0:20:14.306154000 26390       0xbc05a0 LOG      data_queue_dataflow
gstdataqueue.c:334:gst_data_queue_is_empty: unlocking qlock from
thread 0xbc05a0
0:20:14.306165000 26390       0xbc05a0 LOG      data_queue_dataflow
gstdataqueue.c:497:gst_data_queue_pop: locking qlock from thread
0xbc05a0
0:20:14.306177000 26390       0xbc05a0 LOG      data_queue_dataflow
gstdataqueue.c:497:gst_data_queue_pop: locked qlock from thread
0xbc05a0
0:20:14.305832000 26390       0xbc1570 DEBUG            collectpads
gstcollectpads.c:1399:gst_collect_pads_chain: Pad mpegts mux:sink_65
resuming
0:20:14.306198000 26390       0xbc1570 DEBUG            collectpads
gstcollectpads.c:1368:gst_collect_pads_chain: Pad mpegts mux:sink_65
checking
0:20:14.306213000 26390       0xbc1570 DEBUG            collectpads
gstcollectpads.c:1062:gst_collect_pads_check_pads_unlocked: stored
cookie : 3, used_cookie:3
0:20:14.306227000 26390       0xbc1570 DEBUG            collectpads
gstcollectpads.c:1155:gst_collect_pads_check_collected: Not all active
pads (2) have data, continuing
0:20:14.306240000 26390       0xbc1570 DEBUG            collectpads
gstcollectpads.c:1390:gst_collect_pads_chain: Pad mpegts mux:sink_65
has a buffer queued, waiting
0:20:14.306260000 26390       0xb7b3c0 DEBUG             multiqueue
gstmultiqueue.c:1609:single_queue_check_full:<multiqueue0> queue 1:
visible 5461/5462, bytes 2097024/2097152, time 0/0
0:20:14.306277000 26390       0xb7b3c0 LOG      data_queue_dataflow
gstdataqueue.c:450:gst_data_queue_push: queue:0xb746c0 after pushing:
5462 visible items, 2097408 bytes, 0 ns, 5462 elements
0:20:14.306291000 26390       0xb7b3c0 LOG      data_queue_dataflow
gstdataqueue.c:454:gst_data_queue_push: unlocking qlock from thread
0xb7b3c0
0:20:14.306470000 26390       0xb7b3c0 DEBUG             multiqueue
gstmultiqueue.c:876:apply_buffer:<multiqueue0> queue 1, last_stop
updated to 8:48:35.168000000
0:20:14.306491000 26390       0xb7b3c0 DEBUG             multiqueue
gstmultiqueue.c:799:update_time_level:<multiqueue0> queue 1, sink
0:02:11.168000000, src 0:02:15.704000000
0:20:14.306510000 26390       0xb7b3c0 LOG           GST_SCHEDULING
gstpad.c:4670:gst_pad_push:<flvdemux0:audio> called chainfunction
&gst_multi_queue_chain, returned ok
0:20:14.306530000 26390       0xb7b3c0 LOG               GST_BUFFER
gstbuffer.c:195:gst_buffer_finalize: finalize 0x2aaaac591cc0
0:20:14.306554000 26390       0xb7b3c0 LOG                  adapter
gstadapter.c:669:gst_adapter_take_buffer:<GstAdapter at 0xb74540> taking
buffer of 4 bytes
0:20:14.306570000 26390       0xb7b3c0 LOG                  adapter
gstadapter.c:689:gst_adapter_take_buffer:<GstAdapter at 0xb74540>
providing buffer of 4 bytes via sub-buffer
0:20:14.306586000 26390       0xb7b3c0 LOG               GST_BUFFER
gstbuffer.c:311:gst_buffer_init: init 0x2aaaac577430
0:20:14.306601000 26390       0xb7b3c0 LOG               GST_BUFFER
gstbuffer.c:336:gst_buffer_new: new 0x2aaaac577430
0:20:14.306613000 26390       0xb7b3c0 LOG               GST_BUFFER
gstbuffer.c:597:gst_buffer_create_sub: new subbuffer 0x2aaaac577430
(parent 0x2aaaac577bc0)
0:20:14.306630000 26390       0xb7b3c0 LOG                  adapter
gstadapter.c:504:gst_adapter_flush_unchecked:<GstAdapter at 0xb74540>
flushing 4 bytes
0:20:14.306645000 26390       0xb7b3c0 LOG                 flvdemux
gstflvparse.c:1431:gst_flv_parse_tag_type:<flvdemux0> tag data size is
385
0:20:14.306659000 26390       0xb7b3c0 LOG               GST_BUFFER
gstbuffer.c:195:gst_buffer_finalize: finalize 0x2aaaac577430
0:20:14.306676000 26390       0xb7b3c0 LOG                  adapter
gstadapter.c:669:gst_adapter_take_buffer:<GstAdapter at 0xb74540> taking
buffer of 396 bytes
0:20:14.306691000 26390       0xb7b3c0 LOG                  adapter
gstadapter.c:689:gst_adapter_take_buffer:<GstAdapter at 0xb74540>
providing buffer of 396 bytes via sub-buffer
0:20:14.306705000 26390       0xb7b3c0 LOG               GST_BUFFER
gstbuffer.c:311:gst_buffer_init: init 0x2aaaac588ec0
0:20:14.306718000 26390       0xb7b3c0 LOG               GST_BUFFER
gstbuffer.c:336:gst_buffer_new: new 0x2aaaac588ec0
0:20:14.306731000 26390       0xb7b3c0 LOG               GST_BUFFER
gstbuffer.c:597:gst_buffer_create_sub: new subbuffer 0x2aaaac588ec0
(parent 0x2aaaac577bc0)
0:20:14.306746000 26390       0xb7b3c0 LOG                  adapter
gstadapter.c:504:gst_adapter_flush_unchecked:<GstAdapter at 0xb74540>
flushing 396 bytes
0:20:14.306760000 26390       0xb7b3c0 LOG                 flvdemux
gstflvparse.c:562:gst_flv_parse_tag_audio:<flvdemux0> parsing an audio
tag
0:20:14.306774000 26390       0xb7b3c0 LOG                 flvdemux
gstflvparse.c:583:gst_flv_parse_tag_audio:<flvdemux0> pts bytes E3 EF
78 01
0:20:14.306788000 26390       0xb7b3c0 LOG                 flvdemux
gstflvparse.c:680:gst_flv_parse_tag_audio:<flvdemux0> audio tag with 2
channels, 44100Hz sampling rate, 16 bits width, codec tag 2 (flags 2F)
0:20:14.306804000 26390       0xb7b3c0 LOG               GST_BUFFER
gstbuffer.c:311:gst_buffer_init: init 0x2aaaac577430
0:20:14.306818000 26390       0xb7b3c0 LOG               GST_BUFFER
gstbuffer.c:336:gst_buffer_new: new 0x2aaaac577430
0:20:14.306995000 26390       0xb7b3c0 LOG               GST_BUFFER
gstbuffer.c:597:gst_buffer_create_sub: new subbuffer 0x2aaaac577430
(parent 0x2aaaac577bc0)
0:20:14.307018000 26390       0xb7b3c0 LOG                 flvdemux
gstflvparse.c:856:gst_flv_parse_tag_audio:<flvdemux0> pushing 384
bytes buffer at pts 8:48:35.192000000 with duration
99:99:99.999999999, offset 11974
0:20:14.307038000 26390       0xb7b3c0 LOG           GST_SCHEDULING
gstpad.c:4663:gst_pad_push:<flvdemux0:audio> calling chainfunction
&gst_multi_queue_chain
0:20:14.307054000 26390       0xb7b3c0 LOG               multiqueue
gstmultiqueue.c:1165:gst_multi_queue_chain:<multiqueue0> SingleQueue 1
: a
bout to enqueue buffer 0x2aaaac577430 with id 19163
0:20:14.307069000 26390       0xb7b3c0 LOG      data_queue_dataflow
gstdataqueue.c:420:gst_data_queue_push: locking qlock from thread
0xb7b3c0
0:20:14.307082000 26390       0xb7b3c0 LOG      data_queue_dataflow
gstdataqueue.c:420:gst_data_queue_push: locked qlock from thread
0xb7b3c0
0:20:14.307094000 26390       0xb7b3c0 LOG      data_queue_dataflow
gstdataqueue.c:422:gst_data_queue_push: queue:0xb746c0 before pushing:
5462 visible items, 2097408 bytes, 0 ns, 5462 elements
0:20:14.307109000 26390       0xb7b3c0 DEBUG             multiqueue
gstmultiqueue.c:1609:single_queue_check_full:<multiqueue0> queue 1:
visible 5462/5462, bytes 2097408/2097152, time 0/0
0:20:14.307124000 26390       0xb7b3c0 LOG      data_queue_dataflow
gstdataqueue.c:426:gst_data_queue_push: unlocking qlock from thread
0xb7b3c0
0:20:14.307137000 26390       0xb7b3c0 LOG               multiqueue
gstmultiqueue.c:1506:single_queue_overrun_cb:<multiqueue0> Single
Queue 1 is full
0:20:14.307151000 26390       0xb7b3c0 LOG               multiqueue
gstmultiqueue.c:1513:single_queue_overrun_cb:<multiqueue0> Checking
Queue 0
0:20:14.307163000 26390       0xb7b3c0 LOG      data_queue_dataflow
gstdataqueue.c:332:gst_data_queue_is_empty: locking qlock from thread
0xb7b3c0
0:20:14.307175000 26390       0xb7b3c0 LOG      data_queue_dataflow
gstdataqueue.c:332:gst_data_queue_is_empty: locked qlock from thread
0xb7b3c0
0:20:14.307187000 26390       0xb7b3c0 LOG      data_queue_dataflow
gstdataqueue.c:334:gst_data_queue_is_empty: unlocking qlock from
thread 0xb7b3c0
0:20:14.307199000 26390       0xb7b3c0 LOG               multiqueue
gstmultiqueue.c:1516:single_queue_overrun_cb:<multiqueue0> Queue 0 is
empty
0:20:14.307212000 26390       0xb7b3c0 DEBUG             multiqueue
gstmultiqueue.c:1521:single_queue_overrun_cb:<multiqueue0> Another
queue is empty, bumping single queue 1 max visible to 5463
0:20:14.307225000 26390       0xb7b3c0 LOG      data_queue_dataflow
gstdataqueue.c:431:gst_data_queue_push: locking qlock from thread
0xb7b3c0
0:20:14.307237000 26390       0xb7b3c0 LOG      data_queue_dataflow
gstdataqueue.c:431:gst_data_queue_push: locked qlock from thread
0xb7b3c0
0:20:14.307250000 26390       0xb7b3c0 DEBUG             multiqueue
gstmultiqueue.c:1609:single_queue_check_full:<multiqueue0> queue 1:
visible 5462/5463, bytes 2097408/2097152, time 0/0


More information about the gstreamer-devel mailing list