(8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
root@(none):/example/stbdemo/src# GST_DEBUG=*src*:5 gst-launch souphttpsrc locat
ion=http://192.168.10.101:10243/WMPNSSv3/3691493703/1_e0UyNjBDNDMxLTlBNDQtNDUwMS
1BOTY3LTAwNTYwQ0M4QjAyQ30uMC43RTZFQkY1MA.avi blocksize=1048576 ! queue2 use-buff
ering=TRUE max-size-bytes=20971520 max-size-buffers=0 max-size-time=0 ! qtdemux 
name=demux demux.audio_00 ! emmaauddec ! queue ! emmaaudiosink volume=2 mute=TRU
E demux.video_00 ! emmaviddec ! queue ! emmavideosink videoStd=1080I videoOutput
=COMPONENT
(8)MMAC_VIDEO_GetDisplayingPts1

(gst-launch-0.10:1048): GStreamer-WARNING **: External plugin loader failed. This most likely means that the plugin loader helper binary was not found or could not be run. If you are running an uninstalled GStreamer setup, you might need to update your gst-uninstalled script so that the GST_PLUGIN_SCANNER environment variable gets set.
(8)MMAC_VIDEO_GetDisplayingPts1
0:00:01.368212680  1048   0x417018 DEBUG                basesrc gstbasesrc.c:416:gst_base_src_init:<GstBaseSrc@0x4bc020> creating src pad
0:00:01.371466040  1048   0x417018 DEBUG                basesrc gstbasesrc.c:419:gst_base_src_init:<GstBaseSrc@0x4bc020> setting functions on src pad
0:00:01.372388630  1048   0x417018 DEBUG                basesrc gstbasesrc.c:432:gst_base_src_init:<GstBaseSrc@0x4bc020> adding src pad
0:00:01.373450065  1048   0x417018 DEBUG                basesrc gstbasesrc.c:446:gst_base_src_init:<GstBaseSrc@0x4bc020> init done
gst_emmaaudiosink_init ok
.....set mmac_volume = 2
Open /dev/mcif_cmd_audio ,ret= 3
(3)MMAC_AUDIO_SetVolume
.....set mmac_mute = TRUE
^^^^^^gst_emmavideosink_get_caps
^^^^^^gst_emmavideosink_get_caps
Setting pipeline to PAUSED ...
[audiosink_mmac_init] channel=0************************
(5)MMAC_AUDIO_Open11
(5)MMAC_AUDIO_Open22
(5)MMAC_AUDIO_Open33
(5)MMAC_AUDIO_Open44
(5)MMAC_AUDIO_Open55
(5)MMAC_AUDIO_Open66
(5)MMAC_AUDIO_Open77
(0)MMAC_AUDIO_SetVolume
(0)MMAC_AUDIO_Mute,g_AudioHandle.handle=807df424
(0)MMAC_AUDIO_Mute11,g_AudioHandleThrough.handle=807df43c
(3)MMAC_AUDIO_Mute11,g_AudioHandleDownmix.handle=807df448
(3)MMAC_AUDIO_Mute11,g_AudioHandleOutDownmix0.handle=807df460
(3)MMAC_AUDIO_Mute11,g_AudioHandleOutSPDIF.handle=807df478
(3)HDMI, g_AudioHandleOutHDMI.handle=807df46c
0:00:01.598252945  1048   0x417018 DEBUG                basesrc gstbasesrc.c:2928:gst_base_src_activate_push:<souphttpsrc0> Activating in push mode
0:00:01.599005000  1048   0x417018 DEBUG                basesrc gstbasesrc.c:2694:gst_base_src_start:<souphttpsrc0> starting source
0:00:01.599655610  1048   0x417018 DEBUG            souphttpsrc gstsouphttpsrc.c:1300:gst_soup_http_src_start:<souphttpsrc0> start("http://192.168.10.101:10243/WMPNSSv3/3691493703/1_e0UyNjBDNDMxLTlBNDQtNDUwMS1BOTY3LTAwNTYwQ0M4QjAyQ30uMC43RTZFQkY1MA.avi")
0:00:01.616660545  1048   0x417018 DEBUG            souphttpsrc gstsouphttpsrc.c:1410:gst_soup_http_src_get_size:<souphttpsrc0> get_size() = FALSE
0:00:01.617454485  1048   0x417018 DEBUG                basesrc gstbasesrc.c:2727:gst_base_src_start:<souphttpsrc0> setting size -1
0:00:01.618109520  1048   0x417018 DEBUG                basesrc gstbasesrc.c:2740:gst_base_src_start:<souphttpsrc0> format: bytes, have size: 0, size: -1, duration: -1
0:00:01.618835365  1048   0x417018 DEBUG                basesrc gstbasesrc.c:2743:gst_base_src_start:<souphttpsrc0> is seekable: 0
0:00:01.619428245  1048   0x417018 DEBUG                basesrc gstbasesrc.c:2748:gst_base_src_start:<souphttpsrc0> is random_access: 0
0:00:01.620148610  1048   0x417018 DEBUG                basesrc gstbasesrc.c:2604:gst_base_src_default_negotiate:<souphttpsrc0> caps of src: 0x470fa0
0:00:01.620763455  1048   0x417018 DEBUG                basesrc gstbasesrc.c:2652:gst_base_src_default_negotiate:<souphttpsrc0> no negotiation needed
0:00:01.621623015  1048   0x417018 DEBUG                basesrc gstbasesrc.c:1308:gst_base_src_perform_seek:<souphttpsrc0> doing seek: (nil)
0:00:01.622383235  1048   0x417018 DEBUG            souphttpsrc gstsouphttpsrc.c:1391:gst_soup_http_src_unlock_stop:<souphttpsrc0> unlock_stop()
0:00:01.623009205  1048   0x417018 DEBUG                basesrc gstbasesrc.c:1363:gst_base_src_perform_seek:<souphttpsrc0> seek with seqnum 36
0:00:01.623636565  1048   0x417018 DEBUG                basesrc gstbasesrc.c:1399:gst_base_src_perform_seek:<souphttpsrc0> segment configured from 0 to -1, position 0
0:00:01.624343305  1048   0x417018 DEBUG            souphttpsrc gstsouphttpsrc.c:1427:gst_soup_http_src_do_seek:<souphttpsrc0> do_seek(0)
0:00:01.624944035  1048   0x417018 DEBUG            souphttpsrc gstsouphttpsrc.c:1430:gst_soup_http_src_do_seek:<souphttpsrc0> Seeking to current read position
0:00:01.625725585  1048   0x417018 DEBUG                basesrc gstbasesrc.c:1459:gst_base_src_perform_seek:<souphttpsrc0> Sending newsegment from 0 to -1
0:00:01.631295875  1048   0x4e0420 LOG                  basesrc gstbasesrc.c:2392:gst_base_src_loop:<souphttpsrc0> next_ts 0:00:00.000000000 size 1048576
0:00:01.632598665  1048   0x4e0420 DEBUG                basesrc gstbasesrc.c:2045:gst_base_src_update_length:<souphttpsrc0> reading offset 0, length 1048576, size -1, segment.stop -1, maxsize -1
0:00:01.633535890  1048   0x4e0420 DEBUG                basesrc gstbasesrc.c:2135:gst_base_src_get_range:<souphttpsrc0> calling create offset 0 length 1048576, time 0
Pipeline is PREROLLED ...
0:00:01.656780615  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1198:gst_soup_http_src_build_message:<souphttpsrc0> request headers:
0:00:01.657647700  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:721:gst_soup_http_src_headers_foreach:<souphttpsrc0>  Connection: close
0:00:01.658267955  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:721:gst_soup_http_src_headers_foreach:<souphttpsrc0>  transferMode.dlna.org: Streaming
0:00:01.658959745  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1259:gst_soup_http_src_create:<souphttpsrc0> Queueing connection request
Setting pipeline to PLAYING ...
[gst_emmaviddec_src_event]
[video-decoder]pad "src" EVENT received:  latency
0:00:01.726231245  1048   0x417018 DEBUG                basesrc gstbasesrc.c:3065:gst_base_src_change_state:<souphttpsrc0> PAUSED->PLAYING
New clock: GstSystemClock
WARNING: from element /GstPipeline:pipeline0: Internal GStreamer error: clock problem.  Please file a bug at http://bugzilla.gnome.org/enter_bug.cgi?product=GStreamer.
Additional debug info:
gstbin.c(2375): gst_bin_do_latency_func (): /GstPipeline:pipeline0:
Failed to configure latency of 0:00:00.000000000
(8)MMAC_VIDEO_GetDisplayingPts1
0:00:02.153520770  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:733:gst_soup_http_src_got_headers_cb:<souphttpsrc0> got headers:
0:00:02.154571680  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:721:gst_soup_http_src_headers_foreach:<souphttpsrc0>  Content-Length: 1086686196
0:00:02.155290250  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:721:gst_soup_http_src_headers_foreach:<souphttpsrc0>  Content-Type: video/avi
0:00:02.155932960  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:721:gst_soup_http_src_headers_foreach:<souphttpsrc0>  Last-Modified: Sat, 10 Mar 2012 14:58:04 GMT
0:00:02.156557565  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:721:gst_soup_http_src_headers_foreach:<souphttpsrc0>  Server: Microsoft-HTTPAPI/1.0
0:00:02.157387660  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:721:gst_soup_http_src_headers_foreach:<souphttpsrc0>  Accept-Ranges: bytes
0:00:02.158006000  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:721:gst_soup_http_src_headers_foreach:<souphttpsrc0>  transferMode.dlna.org: Streaming
0:00:02.160954785  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:721:gst_soup_http_src_headers_foreach:<souphttpsrc0>  Date: Wed, 09 May 2012 01:44:02 GMT
0:00:02.161808585  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:721:gst_soup_http_src_headers_foreach:<souphttpsrc0>  Connection: close
0:00:02.162474460  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:757:gst_soup_http_src_got_headers_cb:<souphttpsrc0> size = 1086686196
0:00:02.163735825  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:788:gst_soup_http_src_got_headers_cb:<souphttpsrc0> Content-Type: video/avi
0:00:02.164816365  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:977:gst_soup_http_src_chunk_allocator:<souphttpsrc0> allocating 1048576 bytes (soup max_len: 0)
0:00:02.168235790  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 1448 bytes
0:00:02.173657765  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 1047128 at offset 1448
0:00:02.175620065  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 17376 bytes
0:00:02.176541600  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 1029752 at offset 18824
0:00:02.182672115  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 1029752 at offset 18824
0:00:02.184180050  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 5792 bytes
0:00:02.185258235  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 1023960 at offset 24616
0:00:02.195178075  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 1023960 at offset 24616
0:00:02.221651890  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 17376 bytes
0:00:02.222570250  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 1006584 at offset 41992
0:00:02.235123635  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 39472 bytes
0:00:02.236048215  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 967112 at offset 81464
0:00:02.237598485  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 1448 bytes
0:00:02.238467155  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 965664 at offset 82912
0:00:02.577334530  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 83824 bytes
0:00:02.578271010  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 881840 at offset 166736
0:00:02.598468135  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 881840 at offset 166736
0:00:02.601645235  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 21720 bytes
0:00:02.602518870  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 860120 at offset 188456
0:00:02.603628690  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 1448 bytes
0:00:02.605507020  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 858672 at offset 189904
0:00:02.606594525  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 1448 bytes
0:00:02.607506875  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 857224 at offset 191352
0:00:02.616890420  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 857224 at offset 191352
0:00:02.619869185  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 30408 bytes
0:00:02.620796085  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 826816 at offset 221760
0:00:02.636935245  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 826816 at offset 221760
0:00:02.639745345  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 26064 bytes
0:00:02.640664085  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 800752 at offset 247824
0:00:02.646015615  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 800752 at offset 247824
0:00:02.648197495  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 14320 bytes
0:00:02.649276245  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 786432 at offset 262144
0:00:02.656360395  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 786432 at offset 262144
0:00:02.658485530  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 11584 bytes
0:00:02.659360550  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 774848 at offset 273728
0:00:02.692637845  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 774848 at offset 273728
0:00:02.696375760  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 48696 bytes
0:00:02.697455030  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 726152 at offset 322424
0:00:02.701252340  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 726152 at offset 322424
0:00:02.703068500  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 5256 bytes
0:00:03.036613165  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 720896 at offset 327680
(8)MMAC_VIDEO_GetDisplayingPts1
0:00:03.044562855  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 84360 bytes
0:00:03.045573955  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 636536 at offset 412040
0:00:03.075466530  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 636536 at offset 412040
0:00:03.076599850  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 1448 bytes
0:00:03.077608815  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 635088 at offset 413488
0:00:03.084439480  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 635088 at offset 413488
0:00:03.085861605  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 1448 bytes
0:00:03.086789740  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 633640 at offset 414936
0:00:03.094740405  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 633640 at offset 414936
0:00:03.097914140  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 14480 bytes
0:00:03.098840645  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 619160 at offset 429416
0:00:03.105552690  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 619160 at offset 429416
0:00:03.107372850  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 5792 bytes
0:00:03.108300140  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 613368 at offset 435208
0:00:03.116091265  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 613368 at offset 435208
0:00:03.117353250  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 1448 bytes
0:00:03.118251905  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 611920 at offset 436656
0:00:03.120865635  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 611920 at offset 436656
0:00:03.122196245  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 1448 bytes
0:00:03.123055160  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 610472 at offset 438104
0:00:03.126489775  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 8688 bytes
0:00:03.128058905  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 601784 at offset 446792
0:00:03.465990495  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 83288 bytes
0:00:03.466905380  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 518496 at offset 530080
0:00:03.492639410  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 518496 at offset 530080
0:00:03.493905995  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 1448 bytes
0:00:03.494813875  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 517048 at offset 531528
0:00:03.502333510  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 517048 at offset 531528
0:00:03.503651445  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 1448 bytes
0:00:03.504570635  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 515600 at offset 532976
0:00:03.508127905  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 20272 bytes
0:00:03.509192020  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 495328 at offset 553248
0:00:03.510931425  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 1448 bytes
0:00:03.511839090  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 493880 at offset 554696
0:00:03.518534390  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 493880 at offset 554696
0:00:03.520399290  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 10136 bytes
0:00:03.521463190  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 483744 at offset 564832
0:00:03.525222050  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 11584 bytes
0:00:03.527092445  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 472160 at offset 576416
0:00:03.528883180  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 5792 bytes
0:00:03.529993760  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 466368 at offset 582208
0:00:03.535846120  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 466368 at offset 582208
0:00:03.537902550  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 7616 bytes
0:00:03.538774345  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 458752 at offset 589824
0:00:03.567140530  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 458752 at offset 589824
0:00:03.568271840  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 1448 bytes
0:00:03.569245215  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 457304 at offset 591272
0:00:03.907801165  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 84360 bytes
0:00:03.908717750  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 372944 at offset 675632
0:00:03.929814035  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 372944 at offset 675632
0:00:03.932215075  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 20272 bytes
0:00:03.933239725  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 352672 at offset 695904
0:00:03.948269955  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 352672 at offset 695904
0:00:03.951142040  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 24992 bytes
0:00:03.952066390  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 327680 at offset 720896
0:00:03.957528375  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 327680 at offset 720896
0:00:03.958662375  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 1448 bytes
0:00:03.959523875  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 326232 at offset 722344
0:00:03.970981865  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 326232 at offset 722344
0:00:03.972367295  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 2896 bytes
0:00:03.973457060  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 323336 at offset 725240
0:00:03.976827320  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 323336 at offset 725240
0:00:03.978908875  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 8688 bytes
0:00:03.980413900  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 314648 at offset 733928
0:00:03.981688100  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 1448 bytes
0:00:03.982589705  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 313200 at offset 735376
0:00:04.006920665  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 313200 at offset 735376
0:00:04.010291405  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 38560 bytes
0:00:04.011176270  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 274640 at offset 773936
0:00:04.018973550  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 274640 at offset 773936
0:00:04.021218020  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 12496 bytes
0:00:04.022137340  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 262144 at offset 786432
(8)MMAC_VIDEO_GetDisplayingPts1
0:00:04.362747575  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 84360 bytes
0:00:04.363681470  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 177784 at offset 870792
0:00:04.376036840  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 177784 at offset 870792
0:00:04.378257250  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 11584 bytes
0:00:04.379130635  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 166200 at offset 882376
0:00:04.384572395  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 166200 at offset 882376
0:00:04.386716685  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 11584 bytes
0:00:04.387587295  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 154616 at offset 893960
0:00:04.394238160  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 154616 at offset 893960
0:00:04.396292935  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 11584 bytes
0:00:04.397304390  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 143032 at offset 905544
0:00:04.402495135  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 143032 at offset 905544
0:00:04.403603740  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 1448 bytes
0:00:04.404509050  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 141584 at offset 906992
0:00:04.412070590  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 141584 at offset 906992
0:00:04.413326500  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 1448 bytes
0:00:04.414236605  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 140136 at offset 908440
0:00:04.436533220  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 140136 at offset 908440
0:00:04.438650470  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 9064 bytes
0:00:04.439565615  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 131072 at offset 917504
0:00:04.449323330  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 131072 at offset 917504
0:00:04.453771795  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 65536 bytes
0:00:04.454690565  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 65536 at offset 983040
0:00:04.481853365  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 65536 at offset 983040
0:00:04.483368435  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 2896 bytes
0:00:04.491986945  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 62640 at offset 985936
0:00:04.829748810  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 62640 bytes
0:00:04.830474470  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1062:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> block size filled, pushing buffer
0:00:04.831611080  1048   0x4e0420 DEBUG                basesrc gstbasesrc.c:2009:gst_base_src_do_sync:<souphttpsrc0> no sync needed
0:00:04.832184090  1048   0x4e0420 DEBUG                basesrc gstbasesrc.c:2180:gst_base_src_get_range:<souphttpsrc0> buffer ok
buffering... 0%  Buffering, setting pipeline to PAUSED ...
__ENTER video_pause
Open /dev/mcif_cmd_video ,ret= 11
(8)MMAC_VIDEO_Play_0
Open /dev/mcif_cmd_audio ,ret= 12
(3)MC_CMD_AUDIO_PAUSE ON
0:00:04.845327615  1048   0x417018 DEBUG                basesrc gstbasesrc.c:3082:gst_base_src_change_state:<souphttpsrc0> PLAYING->PAUSED
0:00:04.862420670  1048   0x4da3c0 DEBUG                basesrc gstbasesrc.c:1710:gst_base_src_default_event:<souphttpsrc0> handle event 0x4dee30
0:00:04.863282345  1048   0x4da3c0 DEBUG                basesrc gstbasesrc.c:1308:gst_base_src_perform_seek:<souphttpsrc0> doing seek: 0x4dee30
0:00:04.864539775  1048   0x4da3c0 DEBUG            souphttpsrc gstsouphttpsrc.c:1376:gst_soup_http_src_unlock:<souphttpsrc0> unlock()
0:00:04.865350605  1048   0x4da3c0 DEBUG            souphttpsrc gstsouphttpsrc.c:1391:gst_soup_http_src_unlock_stop:<souphttpsrc0> unlock_stop()
0:00:04.866205025  1048   0x4e0420 DEBUG                basesrc gstbasesrc.c:2527:gst_base_src_loop:<souphttpsrc0> we are flushing
0:00:04.866781585  1048   0x4e0420 DEBUG                basesrc gstbasesrc.c:2537:gst_base_src_loop:<souphttpsrc0> pausing task, reason wrong-state
0:00:04.867696165  1048   0x4da3c0 DEBUG                basesrc gstbasesrc.c:1363:gst_base_src_perform_seek:<souphttpsrc0> seek with seqnum 69
0:00:04.868389010  1048   0x4da3c0 DEBUG                basesrc gstbasesrc.c:1399:gst_base_src_perform_seek:<souphttpsrc0> segment configured from 1086534893 to -1, position 1086534893
0:00:04.869273455  1048   0x4da3c0 DEBUG            souphttpsrc gstsouphttpsrc.c:1427:gst_soup_http_src_do_seek:<souphttpsrc0> do_seek(1086534893)
0:00:04.871622110  1048   0x4da3c0 DEBUG                basesrc gstbasesrc.c:1459:gst_base_src_perform_seek:<souphttpsrc0> Sending newsegment from 1086534893 to 1086686196
0:00:04.874068580  1048   0x4e0420 LOG                  basesrc gstbasesrc.c:2392:gst_base_src_loop:<souphttpsrc0> next_ts 0:00:01.086534893 size 1048576
0:00:04.874884390  1048   0x4e0420 DEBUG                basesrc gstbasesrc.c:2045:gst_base_src_update_length:<souphttpsrc0> reading offset 1086534893, length 1048576, size 1086686196, segment.stop -1, maxsize 1086686196
0:00:04.875637210  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1406:gst_soup_http_src_get_size:<souphttpsrc0> get_size() = 1086686196
0:00:04.876353300  1048   0x4e0420 DEBUG                basesrc gstbasesrc.c:2135:gst_base_src_get_range:<souphttpsrc0> calling create offset 1086534893 length 151303, time 1086534893
0:00:04.877210290  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1226:gst_soup_http_src_create:<souphttpsrc0> Seek from position 1048576 to 1086534893: requeueing connection request
0:00:04.880575165  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:905:gst_soup_http_src_finished_cb:<souphttpsrc0> finished
0:00:04.882265310  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1084:gst_soup_http_src_response_cb:<souphttpsrc0> got response 1: Cancelled
Prerolled, waiting for buffering to finish...
buffering... 5%  buffering... 0%  0:00:04.896120845  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1198:gst_soup_http_src_build_message:<souphttpsrc0> request headers:
(8)MMAC_VIDEO_GetDisplayingPts1
0:00:05.231242155  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:721:gst_soup_http_src_headers_foreach:<souphttpsrc0>  Connection: close
0:00:05.231954995  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:721:gst_soup_http_src_headers_foreach:<souphttpsrc0>  transferMode.dlna.org: Streaming
0:00:05.232594290  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:721:gst_soup_http_src_headers_foreach:<souphttpsrc0>  Range: bytes=1086534893-
0:00:05.233440335  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1259:gst_soup_http_src_create:<souphttpsrc0> Queueing connection request
0:00:05.274161655  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:733:gst_soup_http_src_got_headers_cb:<souphttpsrc0> got headers:
0:00:05.274831475  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:721:gst_soup_http_src_headers_foreach:<souphttpsrc0>  Content-Length: 151303
0:00:05.275459625  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:721:gst_soup_http_src_headers_foreach:<souphttpsrc0>  Content-Type: video/avi
0:00:05.276139955  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:721:gst_soup_http_src_headers_foreach:<souphttpsrc0>  Last-Modified: Sat, 10 Mar 2012 14:58:04 GMT
0:00:05.276775265  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:721:gst_soup_http_src_headers_foreach:<souphttpsrc0>  Server: Microsoft-HTTPAPI/1.0
0:00:05.277598695  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:721:gst_soup_http_src_headers_foreach:<souphttpsrc0>  Accept-Ranges: bytes
0:00:05.278213895  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:721:gst_soup_http_src_headers_foreach:<souphttpsrc0>  Content-Range: bytes 1086534893-1086686195/1086686196
0:00:05.281332205  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:721:gst_soup_http_src_headers_foreach:<souphttpsrc0>  transferMode.dlna.org: Streaming
0:00:05.281993190  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:721:gst_soup_http_src_headers_foreach:<souphttpsrc0>  Date: Wed, 09 May 2012 01:44:05 GMT
0:00:05.282615975  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:721:gst_soup_http_src_headers_foreach:<souphttpsrc0>  Connection: close
0:00:05.283408555  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:788:gst_soup_http_src_got_headers_cb:<souphttpsrc0> Content-Type: video/avi
0:00:05.284352720  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:977:gst_soup_http_src_chunk_allocator:<souphttpsrc0> allocating 1048576 bytes (soup max_len: 0)
0:00:05.286583400  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 1448 bytes
0:00:05.287474580  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 1047128 at offset 1448
0:00:05.291074130  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 5792 bytes
0:00:05.291970040  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 1041336 at offset 7240
0:00:05.295809325  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 5792 bytes
0:00:05.296730550  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 1035544 at offset 13032
0:00:05.298318060  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 5792 bytes
0:00:05.299192270  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 1029752 at offset 18824
0:00:05.636903135  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 66984 bytes
0:00:05.637957365  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 962768 at offset 85808
0:00:05.658927995  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 962768 at offset 85808
0:00:05.660279165  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 2896 bytes
0:00:05.671471820  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 959872 at offset 88704
0:00:05.675002815  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 37648 bytes
0:00:05.675937760  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 922224 at offset 126352
0:00:05.690377210  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 922224 at offset 126352
0:00:05.692957450  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1037:gst_soup_http_src_got_chunk_cb:<souphttpsrc0> got chunk of 24951 bytes
0:00:05.694060315  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1009:gst_soup_http_src_chunk_allocator:<souphttpsrc0> created sub-buffer of size 897273 at offset 151303
0:00:05.695110665  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:890:gst_soup_http_src_got_body_cb:<souphttpsrc0> got body
0:00:05.696261085  1048   0x4e0420 DEBUG                basesrc gstbasesrc.c:2009:gst_base_src_do_sync:<souphttpsrc0> no sync needed
0:00:05.696894405  1048   0x4e0420 DEBUG                basesrc gstbasesrc.c:2180:gst_base_src_get_range:<souphttpsrc0> buffer ok
0:00:05.721586515  1048   0x4e0420 LOG                  basesrc gstbasesrc.c:2392:gst_base_src_loop:<souphttpsrc0> next_ts 0:00:01.086686196 size 1048576
0:00:05.722403295  1048   0x4e0420 DEBUG                basesrc gstbasesrc.c:2045:gst_base_src_update_length:<souphttpsrc0> reading offset 1086686196, length 1048576, size 1086686196, segment.stop -1, maxsize 1086686196
0:00:05.723184575  1048   0x4e0420 DEBUG            souphttpsrc gstsouphttpsrc.c:1406:gst_soup_http_src_get_size:<souphttpsrc0> get_size() = 1086686196
0:00:05.723828495  1048   0x4e0420 DEBUG                basesrc gstbasesrc.c:2240:gst_base_src_get_range:<souphttpsrc0> unexpected length 1048576 (offset=1086686196, size=1086686196)
0:00:05.724521000  1048   0x4e0420 INFO                 basesrc gstbasesrc.c:2397:gst_base_src_loop:<souphttpsrc0> pausing after gst_base_src_get_range() = unexpected
0:00:05.725341790  1048   0x4e0420 DEBUG                basesrc gstbasesrc.c:2537:gst_base_src_loop:<souphttpsrc0> pausing task, reason unexpected
buffering... 100%  Done buffering, setting pipeline to PLAYING ...
[gst_emmaviddec_src_event]
[video-decoder]pad "src" EVENT received:  latency
__ENTER video_pause
(8)MMAC_VIDEO_GetDisplayingPts3
(8)MMAC_VIDEO_Play_1000
(3)MMAC_AUDIO_GETAVSYNCSTATUS, g_AudioHandle.handle=0x0
get_av_status.avSyncStatus.pts=0
(3)MC_CMD_AUDIO_PAUSE OFF
0:00:05.744640995  1048   0x417018 DEBUG                basesrc gstbasesrc.c:3065:gst_base_src_change_state:<souphttpsrc0> PAUSED->PLAYING
WARNING: from element /GstPipeline:pipeline0: Internal GStreamer error: clock problem.  Please file a bug at http://bugzilla.gnome.org/enter_bug.cgi?product=GStreamer.
Additional debug info:
gstbin.c(2375): gst_bin_do_latency_func (): /GstPipeline:pipeline0:
Failed to configure latency of 0:00:00.000000000
0:00:05.812629055  1048   0x4da3c0 DEBUG                basesrc gstbasesrc.c:1710:gst_base_src_default_event:<souphttpsrc0> handle event 0x4eb060
0:00:05.813461680  1048   0x4da3c0 DEBUG                basesrc gstbasesrc.c:1308:gst_base_src_perform_seek:<souphttpsrc0> doing seek: 0x4eb060
[audio-decoder]pad "sink" EVENT received:  flush-start
[audiodec]---------AUDIO FLUSH_START
(3)MC_CMD_AUDIO_STOP
(3)MC_CMD_AUDIO_RESETBUFFER
[gst_emmaviddec_sink_event]1
[gst_emmaviddec_sink_event]2
[video-decoder]pad "sink" EVENT received:  flush-start
[Viddec]---------VIDEO FLUSH_START
(8)MMAC_VIDEO_SetBlackBackMode
(8)MMAC_VIDEO_Stop
(8)MMAC_VIDEO_BufferFlush
0:00:06.020973720  1048   0x4da3c0 DEBUG            souphttpsrc gstsouphttpsrc.c:1376:gst_soup_http_src_unlock:<souphttpsrc0> unlock()
0:00:06.021727920  1048   0x4da3c0 DEBUG            souphttpsrc gstsouphttpsrc.c:1391:gst_soup_http_src_unlock_stop:<souphttpsrc0> unlock_stop()
0:00:06.022383915  1048   0x4da3c0 DEBUG                basesrc gstbasesrc.c:1363:gst_base_src_perform_seek:<souphttpsrc0> seek with seqnum 94
0:00:06.023065740  1048   0x4da3c0 DEBUG                basesrc gstbasesrc.c:1399:gst_base_src_perform_seek:<souphttpsrc0> segment configured from 32 to -1, position 32
0:00:06.023787985  1048   0x4da3c0 DEBUG            souphttpsrc gstsouphttpsrc.c:1427:gst_soup_http_src_do_seek:<souphttpsrc0> do_seek(32)
[audio-decoder]pad "sink" EVENT received:  flush-stop
[gst_emmaviddec_sink_event]1
[gst_emmaviddec_sink_event]2
[video-decoder]pad "sink" EVENT received:  flush-stop
0:00:06.028297250  1048   0x4da3c0 DEBUG                basesrc gstbasesrc.c:1459:gst_base_src_perform_seek:<souphttpsrc0> Sending newsegment from 32 to 1086686196
0:00:06.029748400  1048   0x4e0420 LOG                  basesrc gstbasesrc.c:2392:gst_base_src_loop:<souphttpsrc0> next_ts 0:00:00.000000032 size 1048576
0:00:06.030546855  1048   0x4e0420 DEBUG                basesrc gstbasesrc.c:2045:gst_base_src_update_length:<souphttpsrc0> reading offset 32, length 1048576, size 1086686196, segment.stop -1, maxsize 1086686196
0:00:06.031304295  1048   0x4e0420 DEBUG                basesrc gstbasesrc.c:2135:gst_base_src_get_range:<souphttpsrc0> calling create offset 32 length 1048576, time 32
0:00:06.032049515  1048   0x4e0420 DEBUG                basesrc gstbasesrc.c:2224:gst_base_src_get_range:<souphttpsrc0> create returned -3 (unexpected)
0:00:06.032685365  1048   0x4e0420 INFO                 basesrc gstbasesrc.c:2397:gst_base_src_loop:<souphttpsrc0> pausing after gst_base_src_get_range() = unexpected
0:00:06.033489580  1048   0x4e0420 DEBUG                basesrc gstbasesrc.c:2537:gst_base_src_loop:<souphttpsrc0> pausing task, reason unexpected
[audio-decoder]pad "sink" EVENT received:  eos
[gst_emmaviddec_sink_event]1
(8)MMAC_VIDEO_GetDisplayingPts1
[gst_emmaviddec_sink_event]2
[video-decoder]pad "sink" EVENT received:  eos
++++++++++++VIDEO EOS !!!!!!!!!!!!!!
Got EOS from element "pipeline0".
Execution ended after 4315706500 ns.
Setting pipeline to PAUSED ...
__ENTER video_pause
(8)MMAC_VIDEO_Play_0
(3)MC_CMD_AUDIO_PAUSE ON
0:00:06.052197275  1048   0x417018 DEBUG                basesrc gstbasesrc.c:3082:gst_base_src_change_state:<souphttpsrc0> PLAYING->PAUSED
Setting pipeline to READY ...
+++++++++++++++++the play end!!!!!!!!!!!!!!
(8)MMAC_VIDEO_Stop
(8)MMAC_VIDEO_BufferFlush
(8)MMAC_VIDEO_Close
(3)MC_CMD_AUDIO_STOP
Open /dev/mcif_cmd_demux ,ret= 13
(3)MMAC_DEMUX_DisablePidFilter
(3)MMAC_DEMUX_SetPidFilterBufferNearlyFullPointerOffset
(3)MMAC_DEMUX_FreePidFilter
(3)MMAC_DEMUX_FreePidFilter
(3)MMAC_AUDIO_ResetBuffer
(3)MMAC_AUDIO_CLOSE
(3)MMAC_AUDIO_CLOSE
(3)MMAC_AUDIO_CLOSE
(3)MMAC_AUDIO_CLOSE
(3)MMAC_AUDIO_CLOSE
(3)MMAC_AUDIO_CLOSE
0:00:06.281806775  1048   0x417018 DEBUG                basesrc gstbasesrc.c:2955:gst_base_src_activate_push:<souphttpsrc0> Deactivating in push mode
0:00:06.282441760  1048   0x417018 DEBUG            souphttpsrc gstsouphttpsrc.c:1376:gst_soup_http_src_unlock:<souphttpsrc0> unlock()
0:00:06.283021325  1048   0x417018 DEBUG            souphttpsrc gstsouphttpsrc.c:1391:gst_soup_http_src_unlock_stop:<souphttpsrc0> unlock_stop()
0:00:06.284675740  1048   0x417018 DEBUG                basesrc gstbasesrc.c:2802:gst_base_src_stop:<souphttpsrc0> stopping source
0:00:06.285424875  1048   0x417018 DEBUG            souphttpsrc gstsouphttpsrc.c:1352:gst_soup_http_src_stop:<souphttpsrc0> stop()
0:00:06.288419960  1048   0x417018 DEBUG            souphttpsrc gstsouphttpsrc.c:905:gst_soup_http_src_finished_cb:<souphttpsrc0> finished
0:00:06.290782770  1048   0x417018 DEBUG            souphttpsrc gstsouphttpsrc.c:1084:gst_soup_http_src_response_cb:<souphttpsrc0> got response 1: Cancelled
Setting pipeline to NULL ...
Open /dev/mcif_cmd_scaler ,ret= 6
(65538)MC_CMD_SCALER_DELETEWINDOW
(65538)MC_CMD_SCALER_CLOSE
(8)MMAC_VIDEO_Stop
(8)MMAC_VIDEO_BufferFlush
(8)MMAC_VIDEO_Close
(3)MC_CMD_AUDIO_STOP
(3)MMAC_DEMUX_DisablePidFilter
(3)MMAC_DEMUX_SetPidFilterBufferNearlyFullPointerOffset
(3)MMAC_DEMUX_FreePidFilter
(3)MMAC_DEMUX_FreePidFilter
(3)MMAC_AUDIO_ResetBuffer
(3)MMAC_AUDIO_CLOSE
(3)MMAC_AUDIO_CLOSE
(3)MMAC_AUDIO_CLOSE
(3)MMAC_AUDIO_CLOSE
(3)MMAC_AUDIO_CLOSE
(3)MMAC_AUDIO_CLOSE
(3)MMAC_DEMUX_CloseDevice
Freeing pipeline ...
0:00:06.531100585  1048   0x417018 DEBUG            souphttpsrc gstsouphttpsrc.c:371:gst_soup_http_src_finalize:<souphttpsrc0> finalize
root@(none):/example/stbdemo/src# (8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
(8)MMAC_VIDEO_GetDisplayingPts1
