Hi,
Here I met some problems when I play *.mp4 files by using gstreamer on DM365, my mp4 file is recored by some digital camera.
I think this problem is same with the following URL
And my gstreamer trunk version is 919, boundled with DVSDK 4.03.
Any help is appreciated!
Steps:
1.export DMAI_DEBUG=2
2.gst-launch -v --gst-debug=TI*:5 filesrc location=/vfat/media/video/sample.mp4 ! qtdemux name=demux demux.audio_00 ! queue max-size-buffers=1200 max-size-time=0 max-size-bytes=0 ! TIAuddec1 codecName=aacdec engineName=codecServer ! volume volume=0.5 ! alsasink demux.video_00 ! TIViddec2 engineName=codecServer codecName=h264dec ! queue max-size-buffers=1200 max-size-time=0 max-size-bytes=0 ! TIDmaiVideoSink displayStd=v4l2 displayDevice=/dev/video2 videoStd=AUTO videoOutput=LCD sync=true
Log:
/usr/app $ gst-launch -v --gst-debug=TI*:5 filesrc location=/vfat/media/video/sample.mp4 ! qtdemux name=demux demux.audio_00 ! queue max-size-buffers=1200 max-size-time=0 max-size-bytes=0 ! TIAuddec1 codecName=aacdec engineName=codecServer ! volume volume=0.5 ! alsasink demux.video_00 ! TIViddec2 engineName=codecServer codecName=h264dec ! queue max-size-buffers=1200 max-size-time=0 max-size-bytes=0 ! TIDmaiVideoSink displayStd=v4l2 displayDevice=/dev/video2 videoStd=AUTO videoOutput=LCD sync=true
(gst-launch-0.10:678): GLib-WARNING **: getpwuid_r(): failed due to unknown user id (0)
@0x000960ef:[T:0x4001fe50] ti.sdo.dmai - [Dmai] Dmai log level set to '2'. Note that calling CERuntime_init after this point may cause unexpected change to DMAI tracing behavior.
0:00:00.274304583 678 0x15088 LOG TIVidenc1 gsttividenc1.c:205:gst_tividenc1_get_type: initialized get_type
0:00:00.276727125 678 0x15088 LOG TIViddec2 gsttividdec2.c:226:gst_tividdec2_get_type: initialized get_type
0:00:00.278255125 678 0x15088 LOG TIImgenc1 gsttiimgenc1.c:222:gst_tiimgenc1_get_type: initialized get_type
0:00:00.279747042 678 0x15088 LOG TIImgdec1 gsttiimgdec1.c:183:gst_tiimgdec1_get_type: initialized get_type
0:00:00.281293667 678 0x15088 LOG TIAuddec1 gsttiauddec1.c:194:gst_tiauddec1_get_type: initialized get_type
0:00:00.282831833 678 0x15088 LOG TIAudenc1 gsttiaudenc1.c:191:gst_tiaudenc1_get_type: initialized get_type
0:00:00.285120542 678 0x15088 LOG TIVidResize gsttividresize.c:187:gst_tividresize_get_type: initialized get_type
0:00:00.286589958 678 0x15088 LOG TIPrepEncBuf gsttiprepencbuf.c:166:gst_tiprepencbuf_get_type: initialized get_type
0:00:00.301391833 678 0x15088 LOG TIAuddec1 gsttiauddec1.c:303:gst_tiauddec1_init_env: gst_tiauddec1_init_env - begin
0:00:00.302493083 678 0x15088 LOG TIAuddec1 gsttiauddec1.c:342:gst_tiauddec1_init_env: gst_tiauddec1_init_env - end
0:00:00.304404625 678 0x15088 LOG TIAuddec1 gsttiauddec1.c:446:gst_tiauddec1_set_property: begin set_property
0:00:00.305384541 678 0x15088 LOG TIAuddec1 gsttiauddec1.c:465:gst_tiauddec1_set_property: setting "codecName" to "aacdec"
0:00:00.306463250 678 0x15088 LOG TIAuddec1 gsttiauddec1.c:497:gst_tiauddec1_set_property: end set_property
0:00:00.310306875 678 0x15088 LOG TIAuddec1 gsttiauddec1.c:446:gst_tiauddec1_set_property: begin set_property
0:00:00.311354208 678 0x15088 LOG TIAuddec1 gsttiauddec1.c:456:gst_tiauddec1_set_property: setting "engineName" to "codecServer"
0:00:00.312452000 678 0x15088 LOG TIAuddec1 gsttiauddec1.c:497:gst_tiauddec1_set_property: end set_property
0:00:00.482659625 678 0x15088 LOG TIViddec2 gsttividdec2.c:340:gst_tividdec2_init_env: gst_tividdec2_init_env - begin
0:00:00.483984250 678 0x15088 LOG TIViddec2 gsttividdec2.c:403:gst_tividdec2_init_env: gst_tividdec2_init_env - end
0:00:00.485969833 678 0x15088 LOG TIViddec2 gsttividdec2.c:520:gst_tividdec2_set_property: begin set_property
0:00:00.487113667 678 0x15088 LOG TIViddec2 gsttividdec2.c:530:gst_tividdec2_set_property: setting "engineName" to "codecServer"
0:00:00.488164625 678 0x15088 LOG TIViddec2 gsttividdec2.c:579:gst_tividdec2_set_property: end set_property
0:00:00.489734000 678 0x15088 LOG TIViddec2 gsttividdec2.c:520:gst_tividdec2_set_property: begin set_property
0:00:00.490819625 678 0x15088 LOG TIViddec2 gsttividdec2.c:539:gst_tividdec2_set_property: setting "codecName" to "h264dec"
0:00:00.491927333 678 0x15088 LOG TIViddec2 gsttividdec2.c:579:gst_tividdec2_set_property: end set_property
0:00:00.510849375 678 0x15088 LOG TIDmaiVideoSink gsttidmaivideosink.c:400:gst_tidmaivideosink_init_env: gst_tidmaivideosink_init_env - begin
0:00:00.512060833 678 0x15088 LOG TIDmaiVideoSink gsttidmaivideosink.c:453:gst_tidmaivideosink_init_env: gst_tidmaivideosink_init_env - end
0:00:00.567305875 678 0x15088 DEBUG TIDmaiVideoSink gsttidmaivideosink.c:2006:gst_tidmaivideosink_get_caps: Begin
0:00:00.568511500 678 0x15088 DEBUG TIDmaiVideoSink gsttidmaivideosink.c:2010:gst_tidmaivideosink_get_caps: Finish
0:00:00.573884791 678 0x15088 DEBUG TIDmaiVideoSink gsttidmaivideosink.c:2006:gst_tidmaivideosink_get_caps: Begin
0:00:00.574993208 678 0x15088 DEBUG TIDmaiVideoSink gsttidmaivideosink.c:2010:gst_tidmaivideosink_get_caps: Finish
Setting pipeline to PAUSED ...
0:00:00.582937458 678 0x15088 DEBUG TIDmaiVideoSink gsttidmaivideosink.c:1951:gst_tidmaivideosink_change_state: Begin
0:00:00.584140833 678 0x15088 DEBUG TIDmaiVideoSink gsttidmaivideosink.c:1987:gst_tidmaivideosink_change_state: Finish
0:00:00.656967291 678 0x15088 LOG TIViddec2 gsttividdec2.c:1217:gst_tividdec2_change_state: begin change_state (10)
0:00:00.658156166 678 0x15088 LOG TIViddec2 gsttividdec2.c:1254:gst_tividdec2_change_state: end change_state
0:00:00.659914250 678 0x15088 LOG TIAuddec1 gsttiauddec1.c:1008:gst_tiauddec1_change_state: begin change_state (10)
0:00:00.661058875 678 0x15088 LOG TIAuddec1 gsttiauddec1.c:1045:gst_tiauddec1_change_state: end change_state
0:00:00.665950541 678 0x15088 DEBUG TIDmaiVideoSink gsttidmaivideosink.c:1951:gst_tidmaivideosink_change_state: Begin
0:00:00.668144125 678 0x15088 DEBUG TIDmaiVideoSink gsttidmaivideosink.c:1987:gst_tidmaivideosink_change_state: Finish
0:00:00.675797458 678 0x15088 LOG TIViddec2 gsttividdec2.c:1217:gst_tividdec2_change_state: begin change_state (19)
0:00:00.677461917 678 0x15088 LOG TIViddec2 gsttividdec2.c:1254:gst_tividdec2_change_state: end change_state
0:00:00.679225583 678 0x15088 LOG TIAuddec1 gsttiauddec1.c:1008:gst_tiauddec1_change_state: begin change_state (19)
0:00:00.680642208 678 0x15088 LOG TIAuddec1 gsttiauddec1.c:1045:gst_tiauddec1_change_state: end change_state
Pipeline is PREROLLING ...
0:00:00.748091958 678 0x6d710 DEBUG TIViddec2 gsttividdec2.c:833:gst_tividdec2_sink_event: pad "sink" received: newsegment
0:00:00.749853833 678 0x6d710 LOG TICommonUtils gstticommonutils.c:393:gst_ti_parse_newsegment: Got NEWSEGMENT event in GST_FORMAT_TIME (0:00:00.000000000 - 0:01:55.115000000)
0:00:00.753034375 678 0x6d710 DEBUG TIViddec2 gsttividdec2.c:833:gst_tividdec2_sink_event: pad "sink" received: tag
0:00:00.755598250 678 0x6d710 DEBUG TIViddec2 gsttividdec2.c:833:gst_tividdec2_sink_event: pad "sink" received: tag
0:00:00.761040417 678 0x6d710 INFO TIViddec2 gsttividdec2.c:627:gst_tividdec2_set_sink_caps: requested sink caps: video/mpeg, mpegversion=(int)4, systemstream=(boolean)false, codec_data=(buffer)000001b0f5000001b509000001000000012000845d4c28a021e0a31f, width=(int)640, height=(int)480, framerate=(fraction)30000/1001, pixel-aspect-ratio=(fraction)1/1
0:00:00.762427750 678 0x6d710 LOG TIViddec2 gsttividdec2.c:1141:gst_tividdec2_exit_video: begin exit_video
0:00:00.763518958 678 0x6d710 LOG TIViddec2 gsttividdec2.c:1198:gst_tividdec2_exit_video: end exit_video
0:00:00.764584125 678 0x6d710 LOG TIViddec2 gsttividdec2.c:731:gst_tividdec2_set_sink_caps: sink caps negotiation successful
/GstPipeline:pipeline0/GstTIViddec2:tividdec20.GstPad:sink: caps = video/mpeg, mpegversion=(int)4, systemstream=(boolean)false, codec_data=(buffer)000001b0f5000001b509000001000000012000845d4c28a021e0a31f, width=(int)640, height=(int)480, framerate=(fraction)30000/1001, pixel-aspect-ratio=(fraction)1/1
0:00:00.776278750 678 0x6d710 LOG TIViddec2 gsttividdec2.c:1031:gst_tividdec2_init_video: begin init_video
0:00:00.778271416 678 0x70788 LOG TIViddec2 gsttividdec2.c:1535:gst_tividdec2_decode_thread: init video decode_thread
0:00:00.779795833 678 0x70788 LOG TIViddec2 gsttividdec2.c:1313:gst_tividdec2_codec_start: opening codec engine "codecServer"
0:00:00.782271416 678 0x70788 LOG TIViddec2 gsttividdec2.c:1395:gst_tividdec2_codec_start: opening video decoder "h264dec"
@0x0012eefb:[T:0x42a52490] ti.sdo.dmai - [Vdec2] Video decoder instance created
@0x0012f051:[T:0x42a52490] ti.sdo.dmai - [Vdec2] Made XDM_SETPARAMS control call
@0x0012f169:[T:0x42a52490] ti.sdo.dmai - [Vdec2] Made XDM_GETBUFINFO control call
0:00:00.900576208 678 0x70788 LOG TICircBuffer gstticircbuffer.c:99:gst_ticircbuffer_get_type: initialized get_type
0:00:00.901660500 678 0x70788 LOG TICircBuffer gstticircbuffer.c:115:gst_ticircbuffer_class_init: begin class_init
0:00:00.902803458 678 0x70788 LOG TICircBuffer gstticircbuffer.c:121:gst_ticircbuffer_class_init: end class_init
0:00:00.904107083 678 0x70788 LOG TICircBuffer gstticircbuffer.c:159:gst_ticircbuffer_init: begin init
0:00:00.905485541 678 0x70788 LOG TICircBuffer gstticircbuffer.c:179:gst_ticircbuffer_init: end init
0:00:00.906737292 678 0x70788 INFO TICircBuffer gstticircbuffer.c:200:gst_ticircbuffer_new: requested windowSize: 461312
0:00:00.907900125 678 0x70788 INFO TICircBuffer gstticircbuffer.c:203:gst_ticircbuffer_new: fixed block size is OFF
0:00:00.909026208 678 0x70788 LOG TICircBuffer gstticircbuffer.c:220:gst_ticircbuffer_new: creating circular input buffer of size 1614592
@0x00182ae4:[T:0x42a52490] ti.sdo.dmai - [Buffer] Alloc Buffer of size 1614592 at 0x43d69000 (0x86b9c000 phys)
0:00:01.243816167 678 0x70788 LOG TIViddec2 gsttividdec2.c:809:gst_tividdec2_set_source_caps_base: setting source caps to: video/x-raw-yuv, format=(fourcc)NV12, framerate=(fraction)30000/1001, width=(int)640, height=(int)480
/GstPipeline:pipeline0/GstTIViddec2:tividdec20.GstPad:src: caps = video/x-raw-yuv, format=(fourcc)NV12, framerate=(fraction)30000/1001, width=(int)640, height=(int)480
0:00:01.248088917 678 0x70788 LOG TIViddec2 gsttividdec2.c:1474:gst_tividdec2_codec_start: creating output buffer table
(gst-launch-0.10:678): GStreamer-CRITICAL **: gst_debug_log_valist: assertion `category != NULL' failed
@0x00184bd9:[T:0x42a52490] ti.sdo.dmai - [BufTab] Allocating BufTab for 4 buffers
@0x00184e26:[T:0x42a52490] ti.sdo.dmai - [Buffer] Alloc Buffer of size 608256 at 0x44207000 (0x85bee000 phys)
@0x00185038:[T:0x42a52490] ti.sdo.dmai - [Buffer] Alloc Buffer of size 608256 at 0x442e6000 (0x85b0f000 phys)
@0x00185251:[T:0x42a52490] ti.sdo.dmai - [Buffer] Alloc Buffer of size 608256 at 0x443c5000 (0x85a30000 phys)
@0x0018546e:[T:0x42a52490] ti.sdo.dmai - [Buffer] Alloc Buffer of size 608256 at 0x444a4000 (0x85951000 phys)
0:00:01.253334417 678 0x70788 LOG TICircBuffer gstticircbuffer.c:777:gst_ticircbuffer_window_available: data is not available: 0 < 461312
0:00:01.254541417 678 0x70788 LOG TICircBuffer gstticircbuffer.c:516:gst_ticircbuffer_get_data: blocking output until a full window is available
0:00:01.255721375 678 0x6d710 LOG TIViddec2 gsttividdec2.c:1127:gst_tividdec2_init_video: end init_video
0:00:01.257035916 678 0x6d710 LOG TIViddec2 gsttividdec2.c:901:gst_tividdec2_populate_codec_header: Parsing codec data to get SPS, PPS and NAL headers
0:00:01.258361250 678 0x6d710 LOG TIViddec2 gsttividdec2.c:999:gst_tividdec2_chain: Found extra header information for h264dec
0:00:01.259432750 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:01.260494416 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 0 bytes of data
0:00:01.261622750 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:01.262714625 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
0:00:01.263906083 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:01.264932333 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 0 bytes of data
0:00:01.266109416 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:01.267374833 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
0:00:01.268538958 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:01.269580292 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 438 bytes of data
0:00:01.270737750 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:01.603638083 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
(gst-launch-0.10:678): GStreamer-CRITICAL **: gst_buffer_create_sub: assertion `buffer->size >= offset + size' failed
/GstPipeline:pipeline0/GstQueue:queue0.GstPad:sink: caps = audio/mpeg, mpegversion=(int)4, framed=(boolean)true, stream-format=(string)raw, codec_data=(buffer)1308, rate=(int)24000, channels=(int)1
0:00:01.612126583 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:01.613253792 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 0 bytes of data
0:00:01.614343750 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:01.615465958 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
0:00:01.616817333 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:01.617948666 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 0 bytes of data
0:00:01.619153083 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:01.620231708 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
0:00:01.621370541 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:01.622448458 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 438 bytes of data
0:00:01.623689750 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:01.624795750 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
(gst-launch-0.10:678): GStreamer-CRITICAL **: gst_buffer_create_sub: assertion `buffer->size >= offset + size' failed
0:00:01.628673583 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:01.629823625 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 0 bytes of data
0:00:01.630923750 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:01.632049125 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
0:00:01.633265958 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:01.634386333 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 0 bytes of data
0:00:01.635564833 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:01.968348958 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
0:00:01.969601833 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:01.970689208 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 438 bytes of data
0:00:01.971916542 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:01.973002958 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
(gst-launch-0.10:678): GStreamer-CRITICAL **: gst_buffer_create_sub: assertion `buffer->size >= offset + size' failed
0:00:01.637088417 678 0x61218 DEBUG TIAuddec1 gsttiauddec1.c:698:gst_tiauddec1_sink_event: pad "sink" received: newsegment
0:00:01.977029875 678 0x61218 LOG TICommonUtils gstticommonutils.c:393:gst_ti_parse_newsegment: Got NEWSEGMENT event in GST_FORMAT_TIME (0:00:00.000000000 - 0:01:55.114666666)
0:00:01.979120000 678 0x61218 DEBUG TIAuddec1 gsttiauddec1.c:698:gst_tiauddec1_sink_event: pad "sink" received: tag
0:00:01.981033583 678 0x61218 DEBUG TIAuddec1 gsttiauddec1.c:698:gst_tiauddec1_sink_event: pad "sink" received: tag
/GstPipeline:pipeline0/GstQueue:queue0.GstPad:src: caps = audio/mpeg, mpegversion=(int)4, framed=(boolean)true, stream-format=(string)raw, codec_data=(buffer)1308, rate=(int)24000, channels=(int)1
0:00:01.986991333 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:01.988133667 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 0 bytes of data
0:00:01.989263625 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:01.990378167 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
0:00:01.991573458 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:01.992718125 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 0 bytes of data
0:00:01.993961250 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:01.995111083 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
0:00:01.996343333 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:01.998187125 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 438 bytes of data
0:00:01.999457292 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:02.000611458 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
(gst-launch-0.10:678): GStreamer-CRITICAL **: gst_buffer_create_sub: assertion `buffer->size >= offset + size' failed
0:00:02.002924375 678 0x61218 INFO TIAuddec1 gsttiauddec1.c:544:gst_tiauddec1_set_sink_caps: requested sink caps: audio/mpeg, mpegversion=(int)4, framed=(boolean)true, stream-format=(string)raw, codec_data=(buffer)1308, rate=(int)24000, channels=(int)1
0:00:02.336201708 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:02.337775708 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 0 bytes of data
0:00:02.338979166 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:02.340086000 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
0:00:02.341283875 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:02.342350375 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 0 bytes of data
0:00:02.343614458 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:02.344760875 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
0:00:02.346871958 678 0x61218 LOG TIAuddec1 gsttiauddec1.c:949:gst_tiauddec1_exit_audio: begin exit_audio
0:00:02.347975583 678 0x61218 LOG TIAuddec1 gsttiauddec1.c:989:gst_tiauddec1_exit_audio: end exit_audio
0:00:02.349371167 678 0x61218 LOG TIAuddec1 gsttiauddec1.c:644:gst_tiauddec1_set_sink_caps: sink caps negotiation successful
/GstPipeline:pipeline0/GstTIAuddec1:tiauddec10.GstPad:sink: caps = audio/mpeg, mpegversion=(int)4, framed=(boolean)true, stream-format=(string)raw, codec_data=(buffer)1308, rate=(int)24000, channels=(int)1
0:00:02.353444333 678 0x61218 LOG TIAuddec1 gsttiauddec1.c:838:gst_tiauddec1_init_audio: begin init_audio
0:00:02.355323833 678 0x741a0 LOG TIAuddec1 gsttiauddec1.c:1202:gst_tiauddec1_decode_thread: starting auddec decode thread
0:00:02.356865375 678 0x741a0 LOG TIAuddec1 gsttiauddec1.c:1098:gst_tiauddec1_codec_start: opening codec engine "codecServer"
0:00:02.358044000 678 0x741a0 LOG TIAuddec1 gsttiauddec1.c:1118:gst_tiauddec1_codec_start: opening audio decoder "aacdec"
@0x0029412f:[T:0x44eff490] ti.sdo.dmai - [Adec1] Codec requires buffer sizes in 1536 and out 4096
0:00:02.362555667 678 0x741a0 LOG TICircBuffer gstticircbuffer.c:159:gst_ticircbuffer_init: begin init
0:00:02.363685750 678 0x741a0 LOG TICircBuffer gstticircbuffer.c:179:gst_ticircbuffer_init: end init
0:00:02.364778708 678 0x741a0 INFO TICircBuffer gstticircbuffer.c:200:gst_ticircbuffer_new: requested windowSize: 1536
0:00:02.365879250 678 0x741a0 INFO TICircBuffer gstticircbuffer.c:203:gst_ticircbuffer_new: fixed block size is OFF
0:00:02.367350791 678 0x741a0 LOG TICircBuffer gstticircbuffer.c:220:gst_ticircbuffer_new: creating circular input buffer of size 46848
@0x00295b3e:[T:0x44eff490] ti.sdo.dmai - [Buffer] Alloc Buffer of size 46848 at 0x44590000 (0x854db000 phys)
0:00:02.369110333 678 0x741a0 LOG TIAuddec1 gsttiauddec1.c:1162:gst_tiauddec1_codec_start: creating output buffers
@0x002e7122:[T:0x44eff490] ti.sdo.dmai - [BufTab] Allocating BufTab for 2 buffers
@0x002e731c:[T:0x44eff490] ti.sdo.dmai - [Buffer] Alloc Buffer of size 4096 at 0x445a1000 (0x8546b000 phys)
@0x002e750e:[T:0x44eff490] ti.sdo.dmai - [Buffer] Alloc Buffer of size 4096 at 0x445a2000 (0x8546c000 phys)
0:00:02.703463208 678 0x741a0 LOG TICircBuffer gstticircbuffer.c:777:gst_ticircbuffer_window_available: data is not available: 0 < 1536
0:00:02.704682083 678 0x741a0 LOG TICircBuffer gstticircbuffer.c:516:gst_ticircbuffer_get_data: blocking output until a full window is available
0:00:02.346011750 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:02.706805458 678 0x61218 LOG TIAuddec1 gsttiauddec1.c:935:gst_tiauddec1_init_audio: end init_audio
0:00:02.708028583 678 0x61218 LOG TIAuddec1 gsttiauddec1.c:795:gst_tiauddec1_chain: Adding auto-generated ADIF header.
0:00:02.709133750 678 0x61218 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:02.710258167 678 0x61218 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 20 bytes of data
0:00:02.711413792 678 0x61218 LOG TICircBuffer gstticircbuffer.c:357:gst_ticircbuffer_queue_data: buffer received: timestamp: 0, duration: 42666666
0:00:02.712701167 678 0x61218 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 475 bytes of data
0:00:02.714179083 678 0x61218 LOG TICircBuffer gstticircbuffer.c:357:gst_ticircbuffer_queue_data: buffer received: timestamp: 42666666, duration: 42666667
0:00:02.715407875 678 0x61218 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 434 bytes of data
0:00:02.706033583 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 438 bytes of data
0:00:02.717562458 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:02.718715000 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
(gst-launch-0.10:678): GStreamer-CRITICAL **: gst_buffer_create_sub: assertion `buffer->size >= offset + size' failed
0:00:02.721821500 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:02.723061500 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 0 bytes of data
0:00:02.724204375 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:02.725315500 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
0:00:02.727039291 678 0x61218 LOG TICircBuffer gstticircbuffer.c:357:gst_ticircbuffer_queue_data: buffer received: timestamp: 85333333, duration: 42666667
0:00:02.728324125 678 0x61218 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 390 bytes of data
0:00:03.061336000 678 0x61218 LOG TICircBuffer gstticircbuffer.c:357:gst_ticircbuffer_queue_data: buffer received: timestamp: 128000000, duration: 42666666
0:00:03.062651375 678 0x61218 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 389 bytes of data
0:00:02.726501958 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:03.064242291 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 0 bytes of data
0:00:03.065518333 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:03.066789417 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
0:00:03.068028667 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:03.069168208 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 438 bytes of data
0:00:03.070372750 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:03.071519792 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
0:00:03.072768458 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:03.073993833 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 3344 bytes of data
0:00:03.075241458 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:03.076339125 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
(gst-launch-0.10:678): GStreamer-CRITICAL **: gst_buffer_create_sub: assertion `buffer->size >= offset + size' failed
0:00:03.080390042 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:03.081517000 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 0 bytes of data
0:00:03.082680708 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:03.083807500 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
0:00:03.085046500 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:03.087068541 678 0x61218 LOG TICircBuffer gstticircbuffer.c:357:gst_ticircbuffer_queue_data: buffer received: timestamp: 170666666, duration: 42666667
0:00:03.088334250 678 0x61218 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 374 bytes of data
0:00:03.089487916 678 0x61218 LOG TICircBuffer gstticircbuffer.c:572:gst_ticircbuffer_broadcast_producer: broadcast_producer: output unblocked
0:00:03.423101000 678 0x741a0 LOG TICircBuffer gstticircbuffer.c:518:gst_ticircbuffer_get_data: unblocking output
0:00:03.424229375 678 0x741a0 LOG TICircBuffer gstticircbuffer.c:777:gst_ticircbuffer_window_available: data is available: 2082 >= 1536
@0x00397a92:[T:0x44eff490] ti.sdo.dmai - [Buffer] Set user pointer 0x44590000 (physical 0x854db000)
0:00:03.425675500 678 0x741a0 LOG TICircBuffer gstticircbuffer.c:547:gst_ticircbuffer_get_data: returning data at offset 0
0:00:03.427420458 678 0x741a0 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:101:gst_tidmaibuffertransport_class_init: begin class_init
0:00:03.428592667 678 0x741a0 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:111:gst_tidmaibuffertransport_class_init: end class_init
0:00:03.429831250 678 0x741a0 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:85:gst_tidmaibuffertransport_init: begin init
0:00:03.430861708 678 0x741a0 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:90:gst_tidmaibuffertransport_init: end init
0:00:03.431941375 678 0x741a0 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:215:gst_tidmaibuffertransport_new: end new
0:00:03.433061625 678 0x741a0 LOG TIAuddec1 gsttiauddec1.c:1243:gst_tiauddec1_decode_thread: Invoking the audio decoder at 0x44590000 with 1536 bytes
@0x00399e50:[T:0x44eff490] ti.sdo.dmai - [Adec1] AUDDEC1_process() ret 0, consumed 20, created 0 samples
0:00:03.434849625 678 0x741a0 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:123:gst_tidmaibuffertransport_finalize: begin finalize
0:00:03.435964208 678 0x741a0 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:142:gst_tidmaibuffertransport_finalize: calling Buffer_delete()
0:00:03.437229000 678 0x741a0 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:169:gst_tidmaibuffertransport_finalize: end finalize
0:00:03.438351875 678 0x741a0 LOG TICircBuffer gstticircbuffer.c:445:gst_ticircbuffer_data_consumed: 20 bytes consumed
0:00:03.439472875 678 0x741a0 LOG TICircBuffer gstticircbuffer.c:613:gst_ticircbuffer_broadcast_consumer: broadcast_consumer: input unblocked
0:00:03.440536833 678 0x741a0 LOG TICircBuffer gstticircbuffer.c:777:gst_ticircbuffer_window_available: data is available: 2062 >= 1536
@0x0039ba63:[T:0x44eff490] ti.sdo.dmai - [Buffer] Set user pointer 0x44590014 (physical 0x854db014)
0:00:03.441963792 678 0x741a0 LOG TICircBuffer gstticircbuffer.c:547:gst_ticircbuffer_get_data: returning data at offset 20
0:00:03.443119000 678 0x741a0 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:85:gst_tidmaibuffertransport_init: begin init
0:00:03.444170500 678 0x741a0 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:90:gst_tidmaibuffertransport_init: end init
0:00:03.445269250 678 0x741a0 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:215:gst_tidmaibuffertransport_new: end new
0:00:03.446370542 678 0x741a0 LOG TIAuddec1 gsttiauddec1.c:1243:gst_tiauddec1_decode_thread: Invoking the audio decoder at 0x44590014 with 1536 bytes
@0x0039e3ca:[T:0x44eff490] ti.sdo.dmai - [Adec1] AUDDEC1_process() ret 0, consumed 0, created 0 samples
0:00:03.452639500 678 0x741a0 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:123:gst_tidmaibuffertransport_finalize: begin finalize
0:00:03.7862863h timestamp : 0:00:00.000000000, duration: 0:00:00.085333333
/GstPipeline:pipeline0/GstVolume:volume0.GstPad:src: caps = audio/x-raw-int, endianness=(int)1234, signed=(boolean)true, width=(int)16, depth=(int)16, rate=(int)24000, channels=(int)1
/GstPipeline:pipeline0/GstVolume:volume0.GstPad:sink: caps = audio/x-raw-int, endianness=(int)1234, signed=(boolean)true, width=(int)16, depth=(int)16, rate=(int)24000, channels=(int)1
channels=2; rate=48000; format=2
0:00:03.086176625 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 0 bytes of data
0:00:04.013694875 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
Caught interrupt -- handling interrupt.
0:00:04.015970625 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
0:00:04.021475166 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:04.023700625 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 438 bytes of data
Interrupt: Stopping pipeline ...
ERROR: pipeline doesn't want to preroll.
Setting pipeline to NULL ...
0:00:04.034108208 678 0x15088 DEBUG TIDmaiVideoSink gsttidmaivideosink.c:1951:gst_tidmaivideosink_change_state: Begin
0:00:04.026041125 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:04.038540875 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
/GstPipeline:pipeline0/GstAlsaSink:alsasink0.GstPad:sink: caps = audio/x-raw-int, endianness=(int)1234, signed=(boolean)true, width=(int)16, depth=(int)16, rate=(int)24000, channels=(int)1
(gst-launch-0.10:678): GStreamer-CRITICAL **: gst_buffer_create_sub: assertion `buffer->size >= offset + size' failed
0:00:04.053008583 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:04.054380875 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 0 bytes of data
0:00:04.055548833 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:04.057903083 678 0x15088 DEBUG TIDmaiVideoSink gsttidmaivideosink.c:1987:gst_tidmaivideosink_change_state: Finish
0:00:04.060085500 678 0x741a0 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:123:gst_tidmaibuffertransport_finalize: begin finalize
0:00:04.061286500 678 0x741a0 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:138:gst_tidmaibuffertransport_finalize: clearing GStreamer useMask bit
0:00:04.062539958 678 0x741a0 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:169:gst_tidmaibuffertransport_finalize: end finalize
0:00:04.063851583 678 0x741a0 DEBUG TIAuddec1 gsttiauddec1.c:1340:gst_tiauddec1_decode_thread: push to source pad failed
0:00:04.067351125 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
0:00:04.068578875 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:04.069706500 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 0 bytes of data
0:00:04.070913875 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:04.072030458 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
0:00:04.073283083 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:04.074419625 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 438 bytes of data
0:00:04.075655250 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:04.077403583 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
0:00:04.411389208 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:04.412598083 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 1574 bytes of data
0:00:04.413815375 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:04.414955666 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
/GstPipeline:pipeline0/GstAlsaSink:alsasink0.GstPad:sink: caps = NULL
(gst-launch-0.10:678): GStreamer-CRITICAL **: gst_buffer_create_sub: assertion `buffer->size >= offset + size' failed
0:00:04.421088708 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:04.422226583 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 0 bytes of data
0:00:04.423314417 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:04.424406083 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
0:00:04.425669375 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:04.428770542 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 0 bytes of data
0:00:04.430083583 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:04.431251375 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
0:00:04.432530667 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:04.433697667 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 438 bytes of data
0:00:04.434943375 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:04.436098750 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
(gst-launch-0.10:678): GStreamer-CRITICAL **: gst_buffer_create_sub: assertion `buffer->size >= offset + size' failed
0:00:04.439522166 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:04.440717583 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 0 bytes of data
0:00:04.441881833 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:04.443029291 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
0:00:04.444234458 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:04.445393458 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 0 bytes of data
0:00:04.778870000 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:04.780030250 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
0:00:04.781242542 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:04.782399833 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 438 bytes of data
0:00:04.783664417 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:04.784824542 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
/GstPipeline:pipeline0/GstVolume:volume0.GstPad:src: caps = NULL
/GstPipeline:pipeline0/GstVolume:volume0.GstPad:sink: caps = NULL
0:00:04.789698583 678 0x15088 LOG TIViddec2 gsttividdec2.c:1217:gst_tividdec2_change_state: begin change_state (26)
0:00:04.786079625 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:04.791825417 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 1411 bytes of data
0:00:04.793122917 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:360:gst_ticircbuffer_queue_data: buffer received: no timestamp available
0:00:04.794298958 678 0x6d710 LOG TICircBuffer gstticircbuffer.c:378:gst_ticircbuffer_queue_data: queued 4 bytes of data
(gst-launch-0.10:678): GStreamer-CRITICAL **: gst_buffer_create_sub: assertion `buffer->size >= offset + size' failed
/GstPipeline:pipeline0/GstTIViddec2:tividdec20.GstPad:src: caps = NULL
/GstPipeline:pipeline0/GstTIViddec2:tividdec20.GstPad:sink: caps = NULL
0:00:04.801162750 678 0x15088 LOG TIViddec2 gsttividdec2.c:1254:gst_tividdec2_change_state: end change_state
0:00:04.802876458 678 0x15088 LOG TIAuddec1 gsttiauddec1.c:1008:gst_tiauddec1_change_state: begin change_state (26)
/GstPipeline:pipeline0/GstTIAuddec1:tiauddec10.GstPad:src: caps = NULL
/GstPipeline:pipeline0/GstTIAuddec1:tiauddec10.GstPad:sink: caps = NULL
0:00:04.811263875 678 0x15088 LOG TIAuddec1 gsttiauddec1.c:1045:gst_tiauddec1_change_state: end change_state
/GstPipeline:pipeline0/GstQueue:queue0.GstPad:src: caps = NULL
/GstPipeline:pipeline0/GstQueue:queue0.GstPad:sink: caps = NULL
/GstPipeline:pipeline0/GstQTDemux:demux.GstPad:audio_00: caps = NULL
/GstPipeline:pipeline0/GstQTDemux:demux.GstPad:video_00: caps = NULL
0:00:04.834291583 678 0x15088 DEBUG TIDmaiVideoSink gsttidmaivideosink.c:1951:gst_tidmaivideosink_change_state: Begin
0:00:04.835821041 678 0x15088 DEBUG TIDmaiVideoSink gsttidmaivideosink.c:1376:gst_tidmaivideosink_exit_display: Begin
0:00:04.837011125 678 0x15088 DEBUG TIDmaiVideoSink gsttidmaivideosink.c:1416:gst_tidmaivideosink_exit_display: Finish
0:00:04.838009083 678 0x15088 DEBUG TIDmaiVideoSink gsttidmaivideosink.c:1987:gst_tidmaivideosink_change_state: Finish
0:00:04.844370542 678 0x15088 LOG TIViddec2 gsttividdec2.c:1217:gst_tividdec2_change_state: begin change_state (17)
0:00:04.845871542 678 0x15088 LOG TIViddec2 gsttividdec2.c:1141:gst_tividdec2_exit_video: begin exit_video
0:00:04.847179667 678 0x15088 LOG TICircBuffer gstticircbuffer.c:572:gst_ticircbuffer_broadcast_producer: broadcast_producer: output unblocked
0:00:04.848419833 678 0x70788 LOG TICircBuffer gstticircbuffer.c:518:gst_ticircbuffer_get_data: unblocking output
@0x00544988:[T:0x42a52490] ti.sdo.dmai - [Buffer] Set user pointer 0x43d69000 (physical 0x86b9c000)
0:00:05.182570333 678 0x70788 LOG TICircBuffer gstticircbuffer.c:547:gst_ticircbuffer_get_data: returning data at offset 0
0:00:05.183899250 678 0x70788 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:85:gst_tidmaibuffertransport_init: begin init
0:00:05.185019833 678 0x70788 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:90:gst_tidmaibuffertransport_init: end init
0:00:05.186135750 678 0x70788 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:215:gst_tidmaibuffertransport_new: end new
0:00:05.187439041 678 0x70788 LOG TIViddec2 gsttividdec2.c:1633:gst_tividdec2_decode_thread: invoking the video decoder
@0x005476de:[T:0x42a52490] ti.sdo.dmai - [Vdec2] VIDDEC2_process() ret -1 inId 0 inUse 0 consumed 10841
@0x005477cb:[T:0x42a52490] ti.sdo.dmai - [Vdec2] VIDDEC2_process() non-fatal error 0x445
0:00:05.194364000 678 0x70788 LOG TIViddec2 gsttividdec2.c:1648:gst_tividdec2_decode_thread: Vdec2_process returned success code 4
0:00:05.195618333 678 0x70788 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:123:gst_tidmaibuffertransport_finalize: begin finalize
0:00:05.196891000 678 0x70788 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:142:gst_tidmaibuffertransport_finalize: calling Buffer_delete()
0:00:05.198084083 678 0x70788 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:169:gst_tidmaibuffertransport_finalize: end finalize
0:00:05.199287417 678 0x70788 LOG TICircBuffer gstticircbuffer.c:445:gst_ticircbuffer_data_consumed: 10841 bytes consumed
0:00:05.200435250 678 0x70788 LOG TICircBuffer gstticircbuffer.c:613:gst_ticircbuffer_broadcast_consumer: broadcast_consumer: input unblocked
@0x005494dc:[T:0x42a52490] ti.sdo.dmai - [Buffer] Set user pointer 0x43d6ba59 (physical 0x86b9ea59)
0:00:05.201846708 678 0x70788 LOG TICircBuffer gstticircbuffer.c:547:gst_ticircbuffer_get_data: returning data at offset 10841
0:00:05.203040000 678 0x70788 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:85:gst_tidmaibuffertransport_init: begin init
0:00:05.204108167 678 0x70788 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:90:gst_tidmaibuffertransport_init: end init
0:00:05.205218250 678 0x70788 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:215:gst_tidmaibuffertransport_new: end new
0:00:05.206388708 678 0x70788 LOG TIViddec2 gsttividdec2.c:1568:gst_tividdec2_decode_thread: no video data remains
@0x0054ae95:[T:0x42a52490] ti.sdo.dmai - [Buffer] Alloc Buffer of size 1 at 0x445a3000 (0x8546d000 phys)
0:00:05.208513125 678 0x70788 LOG TIViddec2 gsttividdec2.c:1633:gst_tividdec2_decode_thread: invoking the video decoder
@0x0054b598:[T:0x42a52490] ti.sdo.dmai - [Vdec2] VIDDEC2_process() ret -1 inId 0 inUse 0 consumed 0
@0x0054b680:[T:0x42a52490] ti.sdo.dmai - [Vdec2] VIDDEC2_process() non-fatal error 0x0
0:00:05.210409125 678 0x70788 LOG TIViddec2 gsttividdec2.c:1648:gst_tividdec2_decode_thread: Vdec2_process returned success code 4
0:00:05.211659375 678 0x70788 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:123:gst_tidmaibuffertransport_finalize: begin finalize
0:00:05.212809833 678 0x70788 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:142:gst_tidmaibuffertransport_finalize: calling Buffer_delete()
0:00:05.545633375 678 0x70788 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:169:gst_tidmaibuffertransport_finalize: end finalize
0:00:05.546948166 678 0x70788 LOG TICircBuffer gstticircbuffer.c:445:gst_ticircbuffer_data_consumed: 0 bytes consumed
0:00:05.548090166 678 0x70788 LOG TICircBuffer gstticircbuffer.c:613:gst_ticircbuffer_broadcast_consumer: broadcast_consumer: input unblocked
@0x0059e2eb:[T:0x42a52490] ti.sdo.dmai - [Buffer] Set user pointer 0x43d6ba59 (physical 0x86b9ea59)
0:00:05.549508333 678 0x70788 LOG TICircBuffer gstticircbuffer.c:547:gst_ticircbuffer_get_data: returning data at offset 10841
0:00:05.550746000 678 0x70788 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:85:gst_tidmaibuffertransport_init: begin init
0:00:05.551867833 678 0x70788 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:90:gst_tidmaibuffertransport_init: end init
0:00:05.553013375 678 0x70788 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:215:gst_tidmaibuffertransport_new: end new
0:00:05.554140541 678 0x70788 LOG TIViddec2 gsttividdec2.c:1568:gst_tividdec2_decode_thread: no video data remains
@0x0059fa37:[T:0x42a52490] ti.sdo.dmai - [Buffer] Free Buffer of size 1 at 0x445a3000 (0x8546d000 phys)
0:00:05.555684583 678 0x70788 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:123:gst_tidmaibuffertransport_finalize: begin finalize
0:00:05.556915083 678 0x70788 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:142:gst_tidmaibuffertransport_finalize: calling Buffer_delete()
0:00:05.558094958 678 0x70788 LOG TIDmaiBufferTransport gsttidmaibuffertransport.c:169:gst_tidmaibuffertransport_finalize: end finalize
0:00:05.559224042 678 0x70788 LOG TICircBuffer gstticircbuffer.c:445:gst_ticircbuffer_data_consumed: 0 bytes consumed
0:00:05.560386792 678 0x70788 LOG TICircBuffer gstticircbuffer.c:613:gst_ticircbuffer_broadcast_consumer: broadcast_consumer: input unblocked
0:00:05.561629667 678 0x70788 LOG TIViddec2 gsttividdec2.c:1267:gst_tividdec2_codec_stop: freeing cicrular input buffer
0:00:05.562825208 678 0x70788 LOG TICircBuffer gstticircbuffer.c:134:gst_ticircbuffer_finalize: Maximum bytes consumed: 10841
@0x005a1c7f:[T:0x42a52490] ti.sdo.dmai - [Buffer] Free Buffer of size 1614592 at 0x43d69000 (0x86b9c000 phys)
0:00:05.583963333 678 0x70788 LOG TIViddec2 gsttividdec2.c:1276:gst_tividdec2_codec_stop: freeing output buffers
@0x005a6f25:[T:0x42a52490] ti.sdo.dmai - [Buffer] Free Buffer of size 608256 at 0x44207000 (0x85bee000 phys)
@0x005a7463:[T:0x42a52490] ti.sdo.dmai - [Buffer] Free Buffer of size 608256 at 0x442e6000 (0x85b0f000 phys)
@0x005a7a0e:[T:0x42a52490] ti.sdo.dmai - [Buffer] Free Buffer of size 608256 at 0x443c5000 (0x85a30000 phys)
@0x005a7f7b:[T:0x42a52490] ti.sdo.dmai - [Buffer] Free Buffer of size 608256 at 0x444a4000 (0x85951000 phys)
0:00:05.590824417 678 0x70788 LOG TIViddec2 gsttividdec2.c:1283:gst_tividdec2_codec_stop: closing video decoder
0:00:05.814689875 678 0x70788 LOG TIViddec2 gsttividdec2.c:1289:gst_tividdec2_codec_stop: closing codec engine
0:00:05.815886542 678 0x70788 LOG TIViddec2 gsttividdec2.c:1812:gst_tividdec2_decode_thread: exit video decode_thread (0)
0:00:05.817743833 678 0x15088 LOG TIViddec2 gsttividdec2.c:1151:gst_tividdec2_exit_video: shutting down decode thread
0:00:05.818920208 678 0x15088 LOG TIViddec2 gsttividdec2.c:1177:gst_tividdec2_exit_video: freeing sps_pps buffers
0:00:05.820039166 678 0x15088 LOG TIViddec2 gsttividdec2.c:1183:gst_tividdec2_exit_video: freeing nal code prefix buffers
0:00:05.821118041 678 0x15088 LOG TIViddec2 gsttividdec2.c:1189:gst_tividdec2_exit_video: reseting nal length to zero
0:00:05.822183333 678 0x15088 LOG TIViddec2 gsttividdec2.c:1198:gst_tividdec2_exit_video: end exit_video
0:00:05.823244500 678 0x15088 LOG TIViddec2 gsttividdec2.c:1254:gst_tividdec2_change_state: end change_state
0:00:05.824934750 678 0x15088 LOG TIAuddec1 gsttiauddec1.c:1008:gst_tiauddec1_change_state: begin change_state (17)
0:00:05.826437166 678 0x15088 LOG TIAuddec1 gsttiauddec1.c:949:gst_tiauddec1_exit_audio: begin exit_audio
0:00:05.827713583 678 0x15088 LOG TICircBuffer gstticircbuffer.c:572:gst_ticircbuffer_broadcast_producer: broadcast_producer: output unblocked
0:00:05.828967083 678 0x741a0 LOG TIAuddec1 gsttiauddec1.c:1058:gst_tiauddec1_codec_stop: freeing cicrular input buffer
0:00:05.830174500 678 0x741a0 LOG TICircBuffer gstticircbuffer.c:134:gst_ticircbuffer_finalize: Maximum bytes consumed: 475
@0x005e308d:[T:0x44eff490] ti.sdo.dmai - [Buffer] Free Buffer of size 46848 at 0x44590000 (0x854db000 phys)
0:00:05.831752500 678 0x741a0 LOG TIAuddec1 gsttiauddec1.c:1066:gst_tiauddec1_codec_stop: freeing output buffers
@0x005e36e8:[T:0x44eff490] ti.sdo.dmai - [Buffer] Free Buffer of size 4096 at 0x445a1000 (0x8546b000 phys)
@0x005e3860:[T:0x44eff490] ti.sdo.dmai - [Buffer] Free Buffer of size 4096 at 0x445a2000 (0x8546c000 phys)
0:00:05.833713542 678 0x741a0 LOG TIAuddec1 gsttiauddec1.c:1072:gst_tiauddec1_codec_stop: closing audio decoder
0:00:05.835618083 678 0x741a0 LOG TIAuddec1 gsttiauddec1.c:1078:gst_tiauddec1_codec_stop: closing codec engine
0:00:05.836954333 678 0x741a0 LOG TIAuddec1 gsttiauddec1.c:1394:gst_tiauddec1_decode_thread: exit audio decode_thread (-1)
0:00:05.838774375 678 0x15088 LOG TIAuddec1 gsttiauddec1.c:959:gst_tiauddec1_exit_audio: shutting down decode thread
0:00:06.173135417 678 0x15088 DEBUG TIAuddec1 gsttiauddec1.c:964:gst_tiauddec1_exit_audio: decode thread exited with an error condition
0:00:06.174339583 678 0x15088 LOG TIAuddec1 gsttiauddec1.c:989:gst_tiauddec1_exit_audio: end exit_audio
0:00:06.175400625 678 0x15088 LOG TIAuddec1 gsttiauddec1.c:1045:gst_tiauddec1_change_state: end change_state
Freeing pipeline ...
/usr/app $