[meta-freescale] imxvpudec vs. jpegdec and when I need blocksize ?

Carlos Rafael Giani dv at pseudoterminal.org
Tue Jan 13 11:43:15 PST 2015


Okay, I have a partial solution. The crashes most likely came from a bug 
I uncovered: internal temporary buffers were constantly being freed and 
allocated again. This is now fixed, and does not happen anymore.

But the reason why you get the h264parse error is that it simply does 
not receive any h264 data. This is because of an internal delay caused 
by a mechanism in the underlying libfslvpuwrap library. There is always 
a fixed delay of one frame. That is, the first encoded frame that is 
pushed into the decoder does not produce an output picture. Only after 
the second encoded frame is pushed, you get a decoded first picture. The 
VPU itself could deliver a picture immediately; this is a pure software 
problem. However, a fix is not trivial.
So, what currently happens is that after the JPEG gets pushed to the 
decoder, filesrc signals EOS. The decoder element then gets flushed; any 
frames that were already decoded but not yet pushed downstream are 
pushed downstream. In this case, this is precisely the decoded JPEG 
picture. It gets pushed, as expected. But the encoder does not receive 
this picture. This may be a bug in GStreamer (the encoder is not 
encoding anymore even though it should encode these remaining pictures). 
I'll ask in the GStreamer community.
It would of course be much simpler if there wasn't this one-frame delay. 
I am currently looking into possibilities on getting rid of that. It 
requires substantial refactoring of the VPU code in gstreamer-imx, but 
this is something I had planned already. I'll keep you posted.



On 2015-01-13 18:00, Peter Hafner wrote:
> It's Debian Wheezy's Gstreamer (1.0), exact version,
>
> ~# gst-launch-1.0 --version
> gst-launch-1.0 version 1.4.4
> GStreamer 1.4.4
> http://packages.qa.debian.org/gstreamer1.0
>
> Debian is running on a Hummingboard i.MX6 Dual.
>
> I'm using the gstreamer-imx version from here 
> https://github.com/Freescale/gstreamer-imx (master), not the latest, 
> one before. (467c91a from 29.12.2014)
>
> Please let me know, if I can do anything :-)
> Log's or what ever....
>
> This is the *working *pipeline:
> /root/Extender/extender/c_app/stream | gst-launch-1.0 -v fdsrc 
> do-timestamp=true blocksize=2000000 num-buffers=-1 ! 
> "image/jpeg,framerate=15/1,width=1280,height=720" ! jpegparse ! 
> imxvpudec ! autovideosink
>
> This is the *not working *pipeline:
> /root/Extender/extender/c_app/stream | gst-launch-1.0 -v fdsrc 
> do-timestamp=true blocksize=2000000 num-buffers=-1 ! 
> "image/jpeg,framerate=15/1,width=1280,height=720" ! jpegparse ! 
> imxvpudec ! imxvpuenc_h264  ! h264parse ! autovideosink
>
> Setting pipeline to PAUSED ...
> [INFO]    Product Info: i.MX6Q/D/S
> [INFO]    Product Info: i.MX6Q/D/S
> Pipeline is PREROLLING ...
> /GstPipeline:pipeline0/GstCapsFilter:capsfilter0.GstPad:src: caps = 
> "image/jpeg\,\ framerate\=\(fraction\)15/1\,\ width\=\(int\)1280\,\ 
> height\=\(int\)720"
> /GstPipeline:pipeline0/GstJpegParse:jpegparse0.GstPad:sink: caps = 
> "image/jpeg\,\ framerate\=\(fraction\)15/1\,\ width\=\(int\)1280\,\ 
> height\=\(int\)720"
> /GstPipeline:pipeline0/GstJpegParse:jpegparse0.GstPad:src: caps = 
> "image/jpeg\,\ parsed\=\(boolean\)true\,\ format\=\(string\)UYVY\,\ 
> interlaced\=\(boolean\)false\,\ width\=\(int\)1440\,\ 
> height\=\(int\)900\,\ framerate\=\(fraction\)15/1"
> /GstPipeline:pipeline0/GstImxVpuDec:imxvpudec0.GstPad:sink: caps = 
> "image/jpeg\,\ parsed\=\(boolean\)true\,\ format\=\(string\)UYVY\,\ 
> interlaced\=\(boolean\)false\,\ width\=\(int\)1440\,\ 
> height\=\(int\)900\,\ framerate\=\(fraction\)15/1"
> [INFO]    bitstreamMode 1, chromaInterleave 0, mapType 0, 
> tiled2LinearEnable 0
> /GstPipeline:pipeline0/GstImxVpuDec:imxvpudec0.GstPad:src: caps = 
> "video/x-raw\,\ format\=\(string\)Y42B\,\ width\=\(int\)1440\,\ 
> height\=\(int\)900\,\ pixel-aspect-ratio\=\(fraction\)1/1\,\ 
> interlace-mode\=\(string\)progressive\,\ 
> colorimetry\=\(string\)bt709\,\ framerate\=\(fraction\)15/1"
> handling interrupt.
> Interrupt: Stopping pipeline ...
> Setting pipeline to NULL ...
> /GstPipeline:pipeline0/GstImxVpuDec:imxvpudec0.GstPad:src: caps = "NULL"
> /GstPipeline:pipeline0/GstImxVpuDec:imxvpudec0.GstPad:sink: caps = "NULL"
> /GstPipeline:pipeline0/GstJpegParse:jpegparse0.GstPad:src: caps = "NULL"
> /GstPipeline:pipeline0/GstJpegParse:jpegparse0.GstPad:sink: caps = "NULL"
> /GstPipeline:pipeline0/GstCapsFilter:capsfilter0.GstPad:src: caps = "NULL"
> Freeing pipeline ...
>
>
>
> Thanks
> Peter
>
> Am 13.01.2015 um 12:17 schrieb Carlos Rafael Giani:
>> What GStreamer version is this? And what gstreamer-imx version are 
>> you using? 0.10.0? master?
>> I'll try to reproduce the problem as soon as I can. But I need to 
>> know the versions.
>>
>> On 2015-01-12 22:31, Peter Hafner wrote:
>>> Hi,
>>> I try to display a jpeg with gstreamer on i.mx6 dual.
>>>
>>> This pipeline is working without any problems.
>>> gst-launch-1.0 filesrc location=myfile3317.jpeg typefind=true ! 
>>> jpegdec ! imagefreeze ! autovideosink
>>>
>>> then I try to use the hw-encoder with this pipeline,
>>> gst-launch-1.0 filesrc location=myfile3317.jpeg typefind=true ! 
>>> "image/jpeg" ! imxvpudec ! imagefreeze ! autovideosink
>>>
>>> If I set a block size blocksize=800000 then the gstreamer show's 
>>> much more from the pic :-)
>>> gst-launch-1.0 filesrc location=myfile3317.jpeg typefind=true 
>>> blocksize=800000 ! "image/jpeg" ! imxvpudec ! imagefreeze ! 
>>> autovideosink
>>>
>>>
>>> Here, I didn't get a correct pic.
>>> here a part from the log.
>>>
>>>
>>> Setting pipeline to PAUSED ...
>>> 0:00:00.082927752   874   0x845780 INFO imxblittervideosink 
>>> ../src/common/blitter_video_sink.c:744:gst_imx_blitter_video_sink_open_framebuffer_device:<autovideosink0-actual-sink-imxipuvideo> 
>>> opening framebuffer /dev/fb0
>>> 0:00:00.083433102   874   0x845780 INFO imxblittervideosink 
>>> ../src/common/blitter_video_sink.c:754:gst_imx_blitter_video_sink_open_framebuffer_device:<autovideosink0-actual-sink-imxipuvideo> 
>>> framebuffer FD is 7
>>> 0:00:00.083678777   874   0x845780 INFO imxblittervideosink 
>>> ../src/common/blitter_video_sink.c:725:gst_imx_blitter_video_sink_get_format_from_fb:<autovideosink0-actual-sink-imxipuvideo> 
>>> framebuffer uses 32 bpp (sizes: r 8 g 8 b 8  offsets: r 16 g 8 b 0) 
>>> => format BGRA
>>> 0:00:00.083985454   874   0x845780 INFO imxblittervideosink 
>>> ../src/common/blitter_video_sink.c:824:gst_imx_blitter_video_sink_init_framebuffer:<autovideosink0-actual-sink-imxipuvideo> 
>>> framebuffer resolution is 1280 x 1024
>>> 0:00:00.084671143   874   0x845780 TRACE imxbaseblitter 
>>> ../src/common/base_blitter.c:67:gst_imx_base_blitter_init:<GstImxBaseBlitter at 0x858060> 
>>> initializing base blitter
>>> 0:00:00.084930485   874   0x845780 INFO imxipudevice 
>>> ../src/ipu/device.c:61:gst_imx_ipu_open: IPU device opened
>>> 0:00:00.085291831   874   0x845780 INFO imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:62:gst_imx_phys_mem_allocator_init:<GstImxPhysMemAllocator at 0x783910> 
>>> initializing physical memory allocator
>>> 0:00:00.085588174   874   0x845780 INFO imxipuallocator 
>>> ../src/ipu/allocator.c:169:gst_imx_ipu_allocator_init:<GstImxIpuAllocator at 0x783910> 
>>> initialized IPU allocator
>>> 0:00:00.085887184   874   0x845780 DEBUG imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:117:gst_imx_phys_mem_allocator_alloc_internal:<imxipuallocator0> 
>>> alloc_internal called: maxsize: 16384, align: 0, offset: 0, size: 16384
>>> 0:00:00.087249562   874   0x845780 DEBUG imxipuallocator 
>>> ../src/ipu/allocator.c:73:gst_imx_ipu_alloc_phys_mem:<imxipuallocator0> 
>>> allocated 16384 bytes of physical memory at address 0x400f4000
>>> 0:00:00.087358899   874   0x845780 INFO imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:152:gst_imx_phys_mem_allocator_alloc:<imxipuallocator0> 
>>> allocated memory block 0x781d90 at phys addr 0x400f4000 with 16384 
>>> bytes
>>> 0:00:00.087447235   874   0x845780 LOG imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:178:gst_imx_phys_mem_allocator_map:<imxipuallocator0> 
>>> mapping 16384 bytes from memory block 0x781d90 (phys addr 
>>> 0x400f4000), current mapping refcount = 0 -> 1
>>> 0:00:00.087553906   874   0x845780 LOG imxipuallocator 
>>> ../src/ipu/allocator.c:123:gst_imx_ipu_map_phys_mem:<imxipuallocator0> 
>>> mapped IPU physmem memory:  virt addr 0x76fc2000  phys addr 0x400f4000
>>> 0:00:00.087646909   874   0x845780 LOG imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:205:gst_imx_phys_mem_allocator_unmap:<imxipuallocator0> 
>>> unmapping memory block 0x781d90 (phys addr 0x400f4000), current 
>>> mapping refcount = 1 -> 0
>>> 0:00:00.087744579   874   0x845780 LOG imxipuallocator 
>>> ../src/ipu/allocator.c:135:gst_imx_ipu_unmap_phys_mem:<imxipuallocator0> 
>>> unmapped IPU physmem memory:  virt addr 0x76fc2000  phys addr 
>>> 0x400f4000
>>> 0:00:00.087834248   874   0x845780 INFO imxipublitter 
>>> ../src/ipu/blitter.c:160:gst_imx_ipu_blitter_init:<GstImxIpuBlitter at 0x858060> 
>>> initialized blitter
>>> 0:00:00.087939252   874   0x845780 DEBUG imxipublitter 
>>> ../src/ipu/blitter.c:212:gst_imx_ipu_blitter_set_deinterlace_mode:<imxipublitter0> 
>>> set deinterlace mode to none
>>> 0:00:00.088045922   874   0x845780 INFO imxblittervideosink 
>>> ../src/common/blitter_video_sink.c:880:gst_imx_blitter_video_sink_update_regions:<autovideosink0-actual-sink-imxipuvideo> 
>>> video info in initial state -> using 1:1 display ratio
>>> 0:00:00.088134592   874   0x845780 INFO imxblittervideosink 
>>> ../src/common/blitter_video_sink.c:972:gst_imx_blitter_video_sink_update_regions:<autovideosink0-actual-sink-imxipuvideo> 
>>> not keeping aspect ratio
>>> 0:00:00.088218261   874   0x845780 INFO imxblittervideosink 
>>> ../src/common/blitter_video_sink.c:973:gst_imx_blitter_video_sink_update_regions:<autovideosink0-actual-sink-imxipuvideo> 
>>> setting video region to cover the entire window rectangle: (0,0 - 
>>> 1280,1024)
>>> 0:00:00.088300597   874   0x845780 TRACE imxbaseblitter 
>>> ../src/common/base_blitter.c:638:gst_imx_base_blitter_calc_output_region_visibility:<imxipublitter0> 
>>> output region is fully outside of the output buffer region
>>> 0:00:00.088364933   874   0x845780 TRACE imxbaseblitter 
>>> ../src/common/base_blitter.c:292:gst_imx_base_blitter_set_output_regions:<imxipublitter0> 
>>> output region is fully outside of the output buffer region -> video 
>>> region not visible
>>> 0:00:00.088436602   874   0x845780 TRACE imxbaseblitter 
>>> ../src/common/base_blitter.c:537:gst_imx_base_blitter_enable_crop:<imxipublitter0> 
>>> set crop to 0
>>> 0:00:00.089586640   874   0x845780 INFO imxvpudec 
>>> ../src/vpu/decoder/decoder.c:666:gst_imx_vpu_dec_start:<imxvpudec0> 
>>> starting VPU decoder
>>> [INFO]  Product Info: i.MX6Q/D/S
>>> 0:00:00.095314830   874   0x845780 INFO imxvpudec 
>>> ../src/vpu/decoder/decoder.c:340:gst_imx_vpu_dec_load: VPU loaded
>>> 0:00:00.095408166   874   0x845780 INFO imxvpudec 
>>> ../src/vpu/decoder/decoder.c:341:gst_imx_vpu_dec_load: VPU firmware 
>>> version 2.1.2_r25182
>>> 0:00:00.095489502   874   0x845780 INFO imxvpudec 
>>> ../src/vpu/decoder/decoder.c:342:gst_imx_vpu_dec_load: VPU library 
>>> version 5.4.20
>>> 0:00:00.095559838   874   0x845780 INFO imxvpudec 
>>> ../src/vpu/decoder/decoder.c:343:gst_imx_vpu_dec_load: VPU wrapper 
>>> version 1.0.45 VPUWRAPPER_ARM_LINUX Build on Dec 22 2014 05:55:03
>>> 0:00:00.095669175   874   0x845780 INFO imxvpudec 
>>> ../src/vpu/decoder/decoder.c:384:gst_imx_vpu_dec_alloc_dec_mem_blocks:<imxvpudec0> 
>>> need to allocate 2 sub blocks for decoding
>>> 0:00:00.095742844   874   0x845780 INFO imxvpudec 
>>> ../src/vpu/decoder/decoder.c:388:gst_imx_vpu_dec_alloc_dec_mem_blocks:<imxvpudec0> 
>>> sub block 0  type: virtual  size: 5672
>>> 0:00:00.095883515   874   0x845780 INFO imxvpumemblocks 
>>> ../src/vpu/mem_blocks.c:50:gst_imx_vpu_alloc_virt_mem_block: 
>>> allocated 5672 bytes of heap memory at virt addr 0x85a200
>>> 0:00:00.095969185   874   0x845780 INFO imxvpudec 
>>> ../src/vpu/decoder/decoder.c:388:gst_imx_vpu_dec_alloc_dec_mem_blocks:<imxvpudec0> 
>>> sub block 1  type: physical  size: 5236744
>>> 0:00:00.096137190   874   0x845780 INFO imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:62:gst_imx_phys_mem_allocator_init:<GstImxPhysMemAllocator at 0x783988> 
>>> initializing physical memory allocator
>>> 0:00:00.096331197   874   0x845780 DEBUG imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:117:gst_imx_phys_mem_allocator_alloc_internal:<imxvpudecallocator0> 
>>> alloc_internal called: maxsize: 5236744, align: 0, offset: 0, size: 
>>> 5236744
>>> 0:00:00.112508067   874   0x845780 DEBUG imxvpudecallocator 
>>> ../src/vpu/decoder/allocator.c:84:gst_imx_vpu_dec_alloc_phys_mem:<imxvpudecallocator0> 
>>> addresses: virt: 0x7621a000 phys: 0x49000000 cpu: 0xb9000000
>>> 0:00:00.112627738   874   0x845780 INFO imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:152:gst_imx_phys_mem_allocator_alloc:<imxvpudecallocator0> 
>>> allocated memory block 0x781e30 at phys addr 0x49000000 with 5236744 
>>> bytes
>>> 0:00:00.112711407   874   0x845780 INFO imxvpudec 
>>> ../src/vpu/decoder/decoder.c:693:gst_imx_vpu_dec_start:<imxvpudec0> 
>>> VPU decoder started
>>> Pipeline is PREROLLING ...
>>> 0:00:00.115685506   874   0x8460c0 INFO imxvpudec 
>>> ../src/vpu/decoder/decoder.c:760:gst_imx_vpu_dec_set_format:<imxvpudec0> 
>>> setting decoder format
>>> 0:00:00.115784842   874   0x8460c0 INFO imxvpudec 
>>> ../src/vpu/decoder/decoder.c:763:gst_imx_vpu_dec_set_format:<imxvpudec0> 
>>> draining remaining frames from decoder
>>> 0:00:00.115866845   874   0x8460c0 INFO imxvpudec 
>>> ../src/vpu/decoder/decoder.c:540:gst_imx_vpu_dec_fill_param_set:<imxvpudec0> 
>>> setting motion JPEG as stream format
>>> [INFO]  bitstreamMode 1, chromaInterleave 0, mapType 0, 
>>> tiled2LinearEnable 0
>>> 0:00:00.117108220   874   0x8460c0 INFO imxvpudec 
>>> ../src/vpu/decoder/decoder.c:861:gst_imx_vpu_dec_set_format:<imxvpudec0> 
>>> setting format finished
>>> 0:00:00.117229890   874   0x8460c0 WARN videodecoder 
>>> gstvideodecoder.c:2162:gst_video_decoder_chain:<imxvpudec0> Received 
>>> buffer without a new-segment. Assuming timestamps start from 0.
>>> 0:00:00.117567235   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 201
>>> 0:00:00.117658571   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:961:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> using Y42B as video output format
>>> 0:00:00.117730240   874   0x8460c0 INFO imxvpudec 
>>> ../src/vpu/decoder/decoder.c:974:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> minimum number of framebuffers indicated by the VPU: 1  chosen 
>>> number: 7
>>> 0:00:00.117802576   874   0x8460c0 INFO imxvpudec 
>>> ../src/vpu/decoder/decoder.c:975:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> interlacing: 0
>>> 0:00:00.118021583   874   0x8460c0 INFO imxvpuframebuffers 
>>> ../src/vpu/framebuffers.c:249:gst_imx_vpu_framebuffers_configure:<imxvpuframebuffers0> 
>>> framebuffer requested width/height: 1440/900  actual width/height 
>>> (after alignment): 1440/912  Y stride: 1440
>>> 0:00:00.118117253   874   0x8460c0 INFO imxvpuframebuffers 
>>> ../src/vpu/framebuffers.c:254:gst_imx_vpu_framebuffers_configure:<imxvpuframebuffers0> 
>>> num framebuffers:  total: 7  available: 7
>>> 0:00:00.118211590   874   0x8460c0 INFO imxvpuframebuffers 
>>> ../src/vpu/framebuffers.c:259:gst_imx_vpu_framebuffers_configure:<imxvpuframebuffers0> 
>>> framebuffer memory block size:  total: 3283201  Y: 1313280  U: 
>>> 656640  V: 656640  Mv:  656640  alignment: 1
>>> 0:00:00.118299926   874   0x8460c0 INFO imxvpuframebuffers 
>>> ../src/vpu/framebuffers.c:264:gst_imx_vpu_framebuffers_configure:<imxvpuframebuffers0> 
>>> total memory required for all framebuffers: 3283201 * 7 = 22982407 byte
>>> 0:00:00.118378595   874   0x8460c0 DEBUG imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:117:gst_imx_phys_mem_allocator_alloc_internal:<imxvpudecallocator0> 
>>> alloc_internal called: maxsize: 3283201, align: 0, offset: 0, size: 
>>> 3283201
>>> 0:00:00.129105284   874   0x8460c0 DEBUG imxvpudecallocator 
>>> ../src/vpu/decoder/allocator.c:84:gst_imx_vpu_dec_alloc_phys_mem:<imxvpudecallocator0> 
>>> addresses: virt: 0x74bde000 phys: 0x49500000 cpu: 0xb9500000
>>> 0:00:00.129259956   874   0x8460c0 INFO imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:152:gst_imx_phys_mem_allocator_alloc:<imxvpudecallocator0> 
>>> allocated memory block 0x781e80 at phys addr 0x49500000 with 3283201 
>>> bytes
>>> 0:00:00.129342292   874   0x8460c0 DEBUG imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:117:gst_imx_phys_mem_allocator_alloc_internal:<imxvpudecallocator0> 
>>> alloc_internal called: maxsize: 3283201, align: 0, offset: 0, size: 
>>> 3283201
>>> 0:00:00.140380325   874   0x8460c0 DEBUG imxvpudecallocator 
>>> ../src/vpu/decoder/allocator.c:84:gst_imx_vpu_dec_alloc_phys_mem:<imxvpudecallocator0> 
>>> addresses: virt: 0x748bc000 phys: 0x49900000 cpu: 0xb9900000
>>> 0:00:00.140514996   874   0x8460c0 INFO imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:152:gst_imx_phys_mem_allocator_alloc:<imxvpudecallocator0> 
>>> allocated memory block 0x781ed0 at phys addr 0x49900000 with 3283201 
>>> bytes
>>> 0:00:00.140598999   874   0x8460c0 DEBUG imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:117:gst_imx_phys_mem_allocator_alloc_internal:<imxvpudecallocator0> 
>>> alloc_internal called: maxsize: 3283201, align: 0, offset: 0, size: 
>>> 3283201
>>> 0:00:00.155443158   874   0x8460c0 DEBUG imxvpudecallocator 
>>> ../src/vpu/decoder/allocator.c:84:gst_imx_vpu_dec_alloc_phys_mem:<imxvpudecallocator0> 
>>> addresses: virt: 0x7459a000 phys: 0x49d00000 cpu: 0xb9d00000
>>> 0:00:00.155580163   874   0x8460c0 INFO imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:152:gst_imx_phys_mem_allocator_alloc:<imxvpudecallocator0> 
>>> allocated memory block 0x781f20 at phys addr 0x49d00000 with 3283201 
>>> bytes
>>> 0:00:00.155664499   874   0x8460c0 DEBUG imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:117:gst_imx_phys_mem_allocator_alloc_internal:<imxvpudecallocator0> 
>>> alloc_internal called: maxsize: 3283201, align: 0, offset: 0, size: 
>>> 3283201
>>> 0:00:00.166008175   874   0x8460c0 DEBUG imxvpudecallocator 
>>> ../src/vpu/decoder/allocator.c:84:gst_imx_vpu_dec_alloc_phys_mem:<imxvpudecallocator0> 
>>> addresses: virt: 0x74278000 phys: 0x4a100000 cpu: 0xba100000
>>> 0:00:00.166145180   874   0x8460c0 INFO imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:152:gst_imx_phys_mem_allocator_alloc:<imxvpudecallocator0> 
>>> allocated memory block 0x781f70 at phys addr 0x4a100000 with 3283201 
>>> bytes
>>> 0:00:00.166252184   874   0x8460c0 DEBUG imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:117:gst_imx_phys_mem_allocator_alloc_internal:<imxvpudecallocator0> 
>>> alloc_internal called: maxsize: 3283201, align: 0, offset: 0, size: 
>>> 3283201
>>> 0:00:00.177512891   874   0x8460c0 DEBUG imxvpudecallocator 
>>> ../src/vpu/decoder/allocator.c:84:gst_imx_vpu_dec_alloc_phys_mem:<imxvpudecallocator0> 
>>> addresses: virt: 0x73f56000 phys: 0x4a500000 cpu: 0xba500000
>>> 0:00:00.177649562   874   0x8460c0 INFO imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:152:gst_imx_phys_mem_allocator_alloc:<imxvpudecallocator0> 
>>> allocated memory block 0x75908808 at phys addr 0x4a500000 with 
>>> 3283201 bytes
>>> 0:00:00.177733565   874   0x8460c0 DEBUG imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:117:gst_imx_phys_mem_allocator_alloc_internal:<imxvpudecallocator0> 
>>> alloc_internal called: maxsize: 3283201, align: 0, offset: 0, size: 
>>> 3283201
>>> 0:00:00.188530256   874   0x8460c0 DEBUG imxvpudecallocator 
>>> ../src/vpu/decoder/allocator.c:84:gst_imx_vpu_dec_alloc_phys_mem:<imxvpudecallocator0> 
>>> addresses: virt: 0x73c34000 phys: 0x4a900000 cpu: 0xba900000
>>> 0:00:00.188666261   874   0x8460c0 INFO imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:152:gst_imx_phys_mem_allocator_alloc:<imxvpudecallocator0> 
>>> allocated memory block 0x75908858 at phys addr 0x4a900000 with 
>>> 3283201 bytes
>>> 0:00:00.188750597   874   0x8460c0 DEBUG imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:117:gst_imx_phys_mem_allocator_alloc_internal:<imxvpudecallocator0> 
>>> alloc_internal called: maxsize: 3283201, align: 0, offset: 0, size: 
>>> 3283201
>>> 0:00:00.201501353   874   0x8460c0 DEBUG imxvpudecallocator 
>>> ../src/vpu/decoder/allocator.c:84:gst_imx_vpu_dec_alloc_phys_mem:<imxvpudecallocator0> 
>>> addresses: virt: 0x73912000 phys: 0x4ad00000 cpu: 0xbad00000
>>> 0:00:00.201640358   874   0x8460c0 INFO imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:152:gst_imx_phys_mem_allocator_alloc:<imxvpudecallocator0> 
>>> allocated memory block 0x759088a8 at phys addr 0x4ad00000 with 
>>> 3283201 bytes
>>> 0:00:00.201750695   874   0x8460c0 INFO imxvpudec 
>>> ../src/vpu/decoder/decoder.c:996:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> output state width is 0 - using the value 1440 from the framebuffers 
>>> object instead
>>> 0:00:00.201826364   874   0x8460c0 INFO imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1001:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> output state height is 0 - using the value 912 from the framebuffers 
>>> object instead
>>> 0:00:00.201916034   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1331:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> nothing to output (ret code: 0x201)
>>> [WARN]  pic was forced to be done
>>> 0:00:00.702796320   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 4
>>> 0:00:00.703094663   874   0x8460c0 LOG imxvpuframebuffers 
>>> ../src/vpu/framebuffers.c:178:gst_imx_vpu_framebuffers_wait_until_frames_available:<imxvpuframebuffers0> 
>>> flushing = 0  exit_loop = 0
>>> 0:00:00.704455042   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1183:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> not using system frame numbers with this bitstream format
>>> 0:00:00.705665748   874   0x8460c0 INFO imxblittervideosink 
>>> ../src/common/blitter_video_sink.c:915:gst_imx_blitter_video_sink_update_regions:<autovideosink0-actual-sink-imxipuvideo> 
>>> calculated display ratio:  30:19
>>> 0:00:00.705733417   874   0x8460c0 INFO imxblittervideosink 
>>> ../src/common/blitter_video_sink.c:951:gst_imx_blitter_video_sink_update_regions:<autovideosink0-actual-sink-imxipuvideo> 
>>> maximizing video width
>>> 0:00:00.705776419   874   0x8460c0 INFO imxblittervideosink 
>>> ../src/common/blitter_video_sink.c:966:gst_imx_blitter_video_sink_update_regions:<autovideosink0-actual-sink-imxipuvideo> 
>>> setting video region to (0,107 - 1280,917)
>>> 0:00:00.705833754   874   0x8460c0 TRACE imxbaseblitter 
>>> ../src/common/base_blitter.c:623:gst_imx_base_blitter_calc_output_region_visibility:<imxipublitter0> 
>>> output region is fully contained in the output buffer region
>>> 0:00:00.705878422   874   0x8460c0 TRACE imxbaseblitter 
>>> ../src/common/base_blitter.c:285:gst_imx_base_blitter_set_output_regions:<imxipublitter0> 
>>> output region is fully contained in the output buffer region -> 
>>> video region fully visible
>>> 0:00:00.706528110   874   0x8460c0 INFO imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1467:gst_imx_vpu_dec_decide_allocation:<imxvpudec0> 
>>> number of allocation pools in query: 1
>>> 0:00:00.706604113   874   0x8460c0 INFO imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1500:gst_imx_vpu_dec_decide_allocation:<imxvpudec0> 
>>> no pool supports VPU buffers; creating new pool
>>> 0:00:00.706799453   874   0x8460c0 INFO imxvpufbbufferpool 
>>> ../src/vpu/fb_buffer_pool.c:240:gst_imx_vpu_fb_buffer_pool_init:<GstImxVpuFbBufferPool at 0x7590f068> 
>>> initializing VPU buffer pool
>>> 0:00:00.706879455   874   0x8460c0 INFO imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1512:gst_imx_vpu_dec_decide_allocation:<imxvpufbbufferpool0> 
>>> pool config:  outcaps: video/x-raw, format=(string)Y42B, 
>>> width=(int)1440, height=(int)912, pixel-aspect-ratio=(fraction)1/1, 
>>> interlace-mode=(string)progressive, colorimetry=(string)bt709, 
>>> framerate=(fraction)0/1  size: 3283201  min buffers: 0  max buffers: 0
>>> 0:00:00.707307136   874   0x8460c0 LOG imxvpufbbufferpool 
>>> ../src/vpu/fb_buffer_pool.c:319:gst_imx_vpu_set_buffer_contents: 
>>> setting phys mem meta for buffer with pointer 0x75906048: phys addr 
>>> 0x49500000 x/y padding 0/0
>>> 0:00:00.707385139   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1230:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> system frame number invalid or unusable - getting oldest pending 
>>> frame instead
>>> 0:00:00.707433140   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1233:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> output frame:  codecframe: 0x85c5e8  framebuffer phys addr: 
>>> 0x49500000  system frame number: <none; oldest frame> gstbuffer 
>>> addr: 0x75906048  field type: 0  pic type: 0  Y stride: 1440  CbCr 
>>> stride: 720
>>> 0:00:00.707815820   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1339:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU did not use input frame, and no consumed frame info available -> 
>>> drop input frame
>>> 0:00:00.711211266   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.711624946   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.714566044   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.715035059   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.717967157   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.718474507   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.721398937   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.721885953   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.725473406   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.725914087   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.728608176   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.729031524   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.731703612   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.732125626   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.734801049   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.735247730   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.737888818   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.738333499   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.741244596   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.741647610   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.744284364   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.744705712   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.747363467   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.747779814   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.750314898   874 0x75902150 LOG imxphysmemmeta 
>>> ../src/common/phys_mem_meta.c:67:gst_imx_phys_meta_transform: not 
>>> copying metadata: only a region is being copied (not the entire block)
>>> 0:00:00.750716912   874 0x75902150 TRACE imxbaseblitter 
>>> ../src/common/base_blitter.c:160:gst_imx_base_blitter_set_input_buffer:<imxipublitter0> 
>>> input buffer does not use DMA memory - need to copy it to an 
>>> internal input DMA buffer
>>> 0:00:00.750727245   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.751211928   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.751255596   874 0x75902150 INFO imxphysmembufferpool 
>>> ../src/common/phys_mem_buffer_pool.c:288:gst_imx_phys_mem_buffer_pool_init:<GstImxPhysMemBufferPool at 0x75005140> 
>>> initializing physical memory buffer pool
>>> 0:00:00.753648676   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.753745346   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.753614675   874 0x75902150 INFO imxphysmembufferpool 
>>> ../src/common/phys_mem_buffer_pool.c:140:gst_imx_phys_mem_buffer_pool_set_config:<imxphysmembufferpool0> 
>>> caps used for config: video/x-raw, format=(string)Y42B, 
>>> width=(int)1440, height=(int)912, pixel-aspect-ratio=(fraction)1/1, 
>>> interlace-mode=(string)progressive, colorimetry=(string)bt709, 
>>> framerate=(fraction)25/1
>>> 0:00:00.754131359   874 0x75902150 INFO imxphysmembufferpool 
>>> ../src/common/phys_mem_buffer_pool.c:150:gst_imx_phys_mem_buffer_pool_set_config:<imxphysmembufferpool0> 
>>> using horiz/vert alignment: 16/8
>>> 0:00:00.754390034   874 0x75902150 INFO imxphysmembufferpool 
>>> ../src/common/phys_mem_buffer_pool.c:168:gst_imx_phys_mem_buffer_pool_set_config:<imxphysmembufferpool0> 
>>> aligned video info:  width/height: 1440/912  padding values 
>>> right/bottom 0 0
>>> 0:00:00.754659710   874 0x75902150 DEBUG imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:117:gst_imx_phys_mem_allocator_alloc_internal:<imxipuallocator0> 
>>> alloc_internal called: maxsize: 2626560, align: 0, offset: 0, size: 
>>> 2626560
>>> 0:00:00.756913785   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.757389801   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.760733912   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.760910918   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.763640675   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.763778680   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.766536438   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.766680110   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.768621841   874 0x75902150 DEBUG imxipuallocator 
>>> ../src/ipu/allocator.c:73:gst_imx_ipu_alloc_phys_mem:<imxipuallocator0> 
>>> allocated 2626560 bytes of physical memory at address 0x4b100000
>>> 0:00:00.768716178   874 0x75902150 INFO imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:152:gst_imx_phys_mem_allocator_alloc:<imxipuallocator0> 
>>> allocated memory block 0x75908d50 at phys addr 0x4b100000 with 
>>> 2626560 bytes
>>> 0:00:00.768792847   874 0x75902150 DEBUG imxphysmembufferpool 
>>> ../src/common/phys_mem_buffer_pool.c:209:gst_imx_phys_mem_buffer_pool_alloc_buffer:<imxphysmembufferpool0> 
>>> allocated 2626560 bytes for new buffer
>>> 0:00:00.769027188   874 0x75902150 DEBUG imxphysmembufferpool 
>>> ../src/common/phys_mem_buffer_pool.c:232:gst_imx_phys_mem_buffer_pool_alloc_buffer:<imxphysmembufferpool0> 
>>> added video meta with width/height 1440/912
>>> 0:00:00.769106524   874 0x75902150 DEBUG imxphysmembufferpool 
>>> ../src/common/phys_mem_buffer_pool.c:248:gst_imx_phys_mem_buffer_pool_alloc_buffer:<imxphysmembufferpool0> 
>>> phys mem meta padding: x/y 0/0 using horiz/vert alignment: 16/8
>>> 0:00:00.769217194   874 0x75902150 LOG imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:178:gst_imx_phys_mem_allocator_map:<imxipuallocator0> 
>>> mapping 2626560 bytes from memory block 0x75908d50 (phys addr 
>>> 0x4b100000), current mapping refcount = 0 -> 1
>>> 0:00:00.769357866   874 0x75902150 LOG imxipuallocator 
>>> ../src/ipu/allocator.c:123:gst_imx_ipu_map_phys_mem:<imxipuallocator0> 
>>> mapped IPU physmem memory:  virt addr 0x72b6e000  phys addr 0x4b100000
>>> 0:00:00.769437868   874 0x75902150 LOG imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:178:gst_imx_phys_mem_allocator_map:<imxipuallocator0> 
>>> mapping 2626560 bytes from memory block 0x75908d50 (phys addr 
>>> 0x4b100000), current mapping refcount = 1 -> 2
>>> 0:00:00.769507537   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.769523204   874 0x75902150 LOG imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:178:gst_imx_phys_mem_allocator_map:<imxipuallocator0> 
>>> mapping 2626560 bytes from memory block 0x75908d50 (phys addr 
>>> 0x4b100000), current mapping refcount = 2 -> 3
>>> 0:00:00.769591873   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.773289663   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.773744012   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.775391067   874 0x75902150 LOG imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:205:gst_imx_phys_mem_allocator_unmap:<imxipuallocator0> 
>>> unmapping memory block 0x75908d50 (phys addr 0x4b100000), current 
>>> mapping refcount = 3 -> 2
>>> 0:00:00.775530071   874 0x75902150 LOG imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:205:gst_imx_phys_mem_allocator_unmap:<imxipuallocator0> 
>>> unmapping memory block 0x75908d50 (phys addr 0x4b100000), current 
>>> mapping refcount = 2 -> 1
>>> 0:00:00.775619074   874 0x75902150 LOG imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:205:gst_imx_phys_mem_allocator_unmap:<imxipuallocator0> 
>>> unmapping memory block 0x75908d50 (phys addr 0x4b100000), current 
>>> mapping refcount = 1 -> 0
>>> 0:00:00.775791080   874 0x75902150 LOG imxipuallocator 
>>> ../src/ipu/allocator.c:135:gst_imx_ipu_unmap_phys_mem:<imxipuallocator0> 
>>> unmapped IPU physmem memory:  virt addr 0x72b6e000  phys addr 
>>> 0x4b100000
>>> 0:00:00.775889416   874 0x75902150 LOG imxipublitter 
>>> ../src/ipu/blitter.c:414:gst_imx_ipu_blitter_blit_frame:<imxipublitter0> 
>>> task input:  width:  1440  height: 912  format: 0x50323234 crop: 0,0 
>>> 1440x912  phys addr 0x4b100000  deinterlace enable 0 motion 0x0
>>> 0:00:00.775988086   874 0x75902150 LOG imxipublitter 
>>> ../src/ipu/blitter.c:423:gst_imx_ipu_blitter_blit_frame:<imxipublitter0> 
>>> task output:  width:  1280  height: 1024  format: 0x41524742 crop: 
>>> 0,107 1280x810  paddr 0x40100000  rotate: 0
>>> 0:00:00.776080756   874 0x75902150 LOG imxipublitter 
>>> ../src/ipu/blitter.c:436:gst_imx_ipu_blitter_blit_frame:<imxipublitter0> 
>>> need to clear empty regions
>>> 0:00:00.776146758   874 0x75902150 LOG imxipublitter 
>>> ../src/ipu/blitter.c:461:gst_imx_ipu_blitter_blit_frame:<imxipublitter0> 
>>> clear op task input:  width:  64  height: 64  format: 0x34424752 
>>> crop: 0,0 1440x912  phys addr 0x400f4000 deinterlace enable 0 motion 
>>> 0x0
>>> 0:00:00.776266096   874 0x75902150 LOG imxipublitter 
>>> ../src/ipu/blitter.c:470:gst_imx_ipu_blitter_blit_frame:<imxipublitter0> 
>>> clear op task output:  width:  1280  height: 1024  format: 
>>> 0x41524742  crop: 0,0 1280x1024  paddr 0x40100000  rotate: 0
>>> 0:00:00.777719144   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.778291163   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.780808580   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.781069589   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.783764345   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.784042355   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.786569105   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.786845781   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.789352865   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.789625540   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.792153291   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.792441634   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.794923384   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.795226727   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.797720477   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.797989152   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.800487569   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.800753244   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.803279662   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.803554671   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.806044754   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.806329430   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.808818513   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.809086855   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.811584605   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.811854614   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.813589672   874 0x75902150 ERROR imxipublitter 
>>> ../src/ipu/blitter.c:474:gst_imx_ipu_blitter_blit_frame:<imxipublitter0> 
>>> queuing IPU task failed: Invalid argument
>>> 0:00:00.816013086   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.816428433   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.820376564   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.820694242   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> Pipeline is PREROLLED ...
>>> Setting pipeline to PLAYING ...
>>> 0:00:00.825322729   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.825384731   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> New clock: GstSystemClock
>>> 0:00:00.827711475   874 0x75902150 TRACE imxbaseblitter 
>>> ../src/common/base_blitter.c:160:gst_imx_base_blitter_set_input_buffer:<imxipublitter0> 
>>> input buffer does not use DMA memory - need to copy it to an 
>>> internal input DMA buffer
>>> 0:00:00.827728142   874   0x8460c0 LOG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> VPU_DecDecodeBuf returns: 109
>>> 0:00:00.828056153   874   0x8460c0 DEBUG imxvpudec 
>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame:<imxvpudec0> 
>>> need more input
>>> 0:00:00.828229826   874 0x75902150 LOG imxphysmemallocator 
>>> ../src/common/phys_mem_allocator.c:178:gst_imx_phys_mem_allocator_map:<imxipuallocator0> 
>>> mapping 2626560 bytes from
>>>
>>
>

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.yoctoproject.org/pipermail/meta-freescale/attachments/20150113/e08477c8/attachment-0001.html>


More information about the meta-freescale mailing list