From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: by yocto-www.yoctoproject.org (Postfix, from userid 118) id B44D2E00953; Sun, 18 Jan 2015 01:33:51 -0800 (PST) X-Spam-Checker-Version: SpamAssassin 3.3.1 (2010-03-16) on yocto-www.yoctoproject.org X-Spam-Level: X-Spam-Status: No, score=-2.7 required=5.0 tests=BAYES_00,DKIM_SIGNED, DKIM_VALID,DKIM_VALID_AU,FREEMAIL_FROM,HTML_MESSAGE,RCVD_IN_DNSWL_LOW autolearn=ham version=3.3.1 X-Spam-HAM-Report: * -0.7 RCVD_IN_DNSWL_LOW RBL: Sender listed at http://www.dnswl.org/, low * trust * [209.85.212.172 listed in list.dnswl.org] * 0.0 FREEMAIL_FROM Sender email is commonly abused enduser mail provider * (p.hafner75[at]gmail.com) * -1.9 BAYES_00 BODY: Bayes spam probability is 0 to 1% * [score: 0.0000] * 0.0 HTML_MESSAGE BODY: HTML included in message * -0.1 DKIM_VALID_AU Message has a valid DKIM or DK signature from author's * domain * 0.1 DKIM_SIGNED Message has a DKIM or DK signature, not necessarily * valid * -0.1 DKIM_VALID Message has at least one valid DKIM or DK signature Received: from mail-wi0-f172.google.com (mail-wi0-f172.google.com [209.85.212.172]) by yocto-www.yoctoproject.org (Postfix) with ESMTP id 5D14FE0076F for ; Sun, 18 Jan 2015 01:33:38 -0800 (PST) Received: by mail-wi0-f172.google.com with SMTP id bs8so11622814wib.5 for ; Sun, 18 Jan 2015 01:33:37 -0800 (PST) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=gmail.com; s=20120113; h=message-id:date:from:user-agent:mime-version:to:subject:references :in-reply-to:content-type; bh=s190clSPIYdAzm7TMFB4KVCXZviCtiqYZngOVu6hKGA=; b=VWV+cDjh1VloY/cTIcNuez2+aby1dBgW3WEi2U8Jg7yUA796W/LT0zA6FtYLDI0Yg2 Jlj1CubeOyDeAFLBshvz19Dp45Je1tbX7sehLY5AhkO+EdvBvr13luMqZWOwy5eTT/TK QfofOxjZmv/vGPj6JX6hdjrFmAjA4b53/BGz9HKfOl4aLhrz2Kke9TuZZnrHWX1c1h5e 4NjHM0N+vyN1tTyqyERdlxY1jl5ySg46mdD+hKWurpfHJZNw8z3XZ3FyEF1QYksUlbqO Crn5ojAWVffuGxd8MUdMO7/PWWlF/AeUbbhY9bJ0JJ4ULExzNJdMvnKOEAUyT7aTggFR G3yA== X-Received: by 10.180.72.177 with SMTP id e17mr23105264wiv.42.1421573617356; Sun, 18 Jan 2015 01:33:37 -0800 (PST) Received: from [192.168.1.163] (p54A46CF9.dip0.t-ipconnect.de. [84.164.108.249]) by mx.google.com with ESMTPSA id gl1sm4755481wib.13.2015.01.18.01.33.33 (version=TLSv1.2 cipher=ECDHE-RSA-AES128-GCM-SHA256 bits=128/128); Sun, 18 Jan 2015 01:33:36 -0800 (PST) Message-ID: <54BB7DEC.8030901@gmail.com> Date: Sun, 18 Jan 2015 10:33:32 +0100 From: Peter Hafner User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:31.0) Gecko/20100101 Thunderbird/31.4.0 MIME-Version: 1.0 To: Carlos Rafael Giani , meta-freescale@yoctoproject.org References: <54B43D34.509@gmail.com> <54B4FEDD.2030409@pseudoterminal.org> <54B54F45.9010508@gmail.com> <54B57553.8050905@pseudoterminal.org> In-Reply-To: <54B57553.8050905@pseudoterminal.org> Subject: Re: imxvpudec vs. jpegdec and when I need blocksize ? X-BeenThere: meta-freescale@yoctoproject.org X-Mailman-Version: 2.1.13 Precedence: list List-Id: Usage and development list for the meta-fsl-* layers List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Sun, 18 Jan 2015 09:33:51 -0000 Content-Type: multipart/alternative; boundary="------------000608050906080307080005" --------------000608050906080307080005 Content-Type: text/plain; charset=windows-1252; format=flowed Content-Transfer-Encoding: 7bit Hmm, is there any other possibility to get this running ? Is that helpful, when my app not push data to stdout, that we using file src, I can try to implement appsrc! And try to handle my EOS, by my self! Regards Peter Am 13.01.2015 um 20:43 schrieb Carlos Rafael Giani: > 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> initializing physical memory allocator >>>> 0:00:00.085588174 874 0x845780 INFO imxipuallocator >>>> ../src/ipu/allocator.c:169:gst_imx_ipu_allocator_init: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> initialized blitter >>>> 0:00:00.087939252 874 0x845780 DEBUG imxipublitter >>>> ../src/ipu/blitter.c:212:gst_imx_ipu_blitter_set_deinterlace_mode: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> set crop to 0 >>>> 0:00:00.089586640 874 0x845780 INFO imxvpudec >>>> ../src/vpu/decoder/decoder.c:666:gst_imx_vpu_dec_start: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> setting decoder format >>>> 0:00:00.115784842 874 0x8460c0 INFO imxvpudec >>>> ../src/vpu/decoder/decoder.c:763:gst_imx_vpu_dec_set_format: >>>> 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: >>>> 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: >>>> setting format finished >>>> 0:00:00.117229890 874 0x8460c0 WARN videodecoder >>>> gstvideodecoder.c:2162:gst_video_decoder_chain: >>>> 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: >>>> VPU_DecDecodeBuf returns: 201 >>>> 0:00:00.117658571 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:961:gst_imx_vpu_dec_handle_frame: >>>> 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: >>>> 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: >>>> interlacing: 0 >>>> 0:00:00.118021583 874 0x8460c0 INFO imxvpuframebuffers >>>> ../src/vpu/framebuffers.c:249:gst_imx_vpu_framebuffers_configure: >>>> 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: >>>> num framebuffers: total: 7 available: 7 >>>> 0:00:00.118211590 874 0x8460c0 INFO imxvpuframebuffers >>>> ../src/vpu/framebuffers.c:259:gst_imx_vpu_framebuffers_configure: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> initializing VPU buffer pool >>>> 0:00:00.706879455 874 0x8460c0 INFO imxvpudec >>>> ../src/vpu/decoder/decoder.c:1512:gst_imx_vpu_dec_decide_allocation: >>>> 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: >>>> 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: >>>> output frame: codecframe: 0x85c5e8 framebuffer phys addr: >>>> 0x49500000 system frame number: 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: >>>> 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: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.711624946 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.714566044 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.715035059 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.717967157 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.718474507 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.721398937 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.721885953 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.725473406 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.725914087 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.728608176 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.729031524 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.731703612 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.732125626 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.734801049 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.735247730 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.737888818 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.738333499 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.741244596 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.741647610 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.744284364 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.744705712 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.747363467 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.747779814 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> 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: >>>> 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: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.751211928 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> 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: >>>> 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: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.753745346 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.757389801 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.760733912 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.760910918 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.763640675 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.763778680 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.766536438 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.766680110 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.768621841 874 0x75902150 DEBUG imxipuallocator >>>> ../src/ipu/allocator.c:73:gst_imx_ipu_alloc_phys_mem: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> need more input >>>> 0:00:00.773289663 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.773744012 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.775391067 874 0x75902150 LOG imxphysmemallocator >>>> ../src/common/phys_mem_allocator.c:205:gst_imx_phys_mem_allocator_unmap: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> 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: >>>> need to clear empty regions >>>> 0:00:00.776146758 874 0x75902150 LOG imxipublitter >>>> ../src/ipu/blitter.c:461:gst_imx_ipu_blitter_blit_frame: >>>> 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: >>>> 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: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.778291163 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.780808580 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.781069589 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.783764345 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.784042355 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.786569105 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.786845781 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.789352865 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.789625540 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.792153291 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.792441634 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.794923384 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.795226727 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.797720477 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.797989152 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.800487569 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.800753244 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.803279662 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.803554671 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.806044754 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.806329430 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.808818513 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.809086855 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.811584605 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.811854614 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.813589672 874 0x75902150 ERROR imxipublitter >>>> ../src/ipu/blitter.c:474:gst_imx_ipu_blitter_blit_frame: >>>> 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: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.816428433 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.820376564 874 0x8460c0 LOG imxvpudec >>>> ../src/vpu/decoder/decoder.c:925:gst_imx_vpu_dec_handle_frame: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.820694242 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> 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: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.825384731 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> 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: >>>> 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: >>>> VPU_DecDecodeBuf returns: 109 >>>> 0:00:00.828056153 874 0x8460c0 DEBUG imxvpudec >>>> ../src/vpu/decoder/decoder.c:1042:gst_imx_vpu_dec_handle_frame: >>>> need more input >>>> 0:00:00.828229826 874 0x75902150 LOG imxphysmemallocator >>>> ../src/common/phys_mem_allocator.c:178:gst_imx_phys_mem_allocator_map: >>>> mapping 2626560 bytes from >>>> >>> >> > --------------000608050906080307080005 Content-Type: text/html; charset=windows-1252 Content-Transfer-Encoding: 8bit Hmm,
is there any other  possibility to get this running ?
Is that helpful, when my app not push data to stdout, that we using file src, I can try to implement appsrc!
And try to handle my EOS, by my self!

Regards
Peter

Am 13.01.2015 um 20:43 schrieb Carlos Rafael Giani:
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@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@0x783910> initializing physical memory allocator
0:00:00.085588174   874   0x845780 INFO         imxipuallocator ../src/ipu/allocator.c:169:gst_imx_ipu_allocator_init:<GstImxIpuAllocator@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@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@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@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@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





--------------000608050906080307080005--