From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: by yocto-www.yoctoproject.org (Postfix, from userid 118) id 910EDE0095D; Wed, 27 May 2015 05:05:07 -0700 (PDT) X-Spam-Checker-Version: SpamAssassin 3.3.1 (2010-03-16) on yocto-www.yoctoproject.org X-Spam-Level: X-Spam-Status: No, score=-1.9 required=5.0 tests=BAYES_00 autolearn=ham version=3.3.1 X-Spam-HAM-Report: * -1.9 BAYES_00 BODY: Bayes spam probability is 0 to 1% * [score: 0.0000] Received: from mail.chez-thomas.org (mail.mlbassoc.com [65.100.170.105]) by yocto-www.yoctoproject.org (Postfix) with ESMTP id 8DB17E00293 for ; Wed, 27 May 2015 05:05:03 -0700 (PDT) Received: by mail.chez-thomas.org (Postfix, from userid 1998) id 70917F811E1; Wed, 27 May 2015 06:05:03 -0600 (MDT) Received: from [192.168.1.114] (zeus [192.168.1.114]) by mail.chez-thomas.org (Postfix) with ESMTP id 0AE0BF81199; Wed, 27 May 2015 06:05:02 -0600 (MDT) Message-ID: <5565B306.3020805@mlbassoc.com> Date: Wed, 27 May 2015 06:05:26 -0600 From: Gary Thomas User-Agent: Mozilla/5.0 (X11; Linux x86_64; rv:31.0) Gecko/20100101 Thunderbird/31.7.0 MIME-Version: 1.0 To: meta-freescale@yoctoproject.org References: <555979FE.50306@satel.pl> <5559A04D.5080500@mail.bg> <5559D536.5030206@mlbassoc.com> <555A0B81.3090905@mail.bg> <555A130C.9060605@mlbassoc.com> <555A7B5B.90906@mail.bg> <555B1634.9050003@gmail.com> <555B18AB.4080403@mail.bg> <555B19AA.4010309@gmail.com> <555B1A48.9030303@pseudoterminal.org> <555B1BD6.7090704@mlbassoc.com> <555B1D37.4040300@pseudoterminal.org> <555B248D.2070401@mlbassoc.com> <555B5234.9060104@pseudoterminal.org> <555DE360.3000303@gmail.com> <555DEFAB.9040802@pseudoterminal.org> <555DF13F.1010308@mlbassoc.com> <55647A23.9020701@mlbassoc.com> <55647C3F.2060909@pseudoterminal.org> <55647D61.7000601@mlbassoc.com> <5564C129.9090905@pseudoterminal.org> In-Reply-To: <5564C129.9090905@pseudoterminal.org> Subject: Re: imxipuvideosink in 3.10.53 on Nitrogex6xlite 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: Wed, 27 May 2015 12:05:07 -0000 X-Groupsio-MsgNum: 14080 Content-Type: multipart/mixed; boundary="------------000605000802000907030506" --------------000605000802000907030506 Content-Type: text/plain; charset=windows-1252; format=flowed Content-Transfer-Encoding: 7bit On 2015-05-26 12:53, Carlos Rafael Giani wrote: > Try to re-run the playbin pipeline with the GST_DEBUG environment variable set to: "2,*imx*:9", and post the log please. Here it is. > Am 2015-05-26 um 16:04 schrieb Gary Thomas: >> On 2015-05-26 07:59, Carlos Rafael Giani wrote: >>> On 05/26/2015 03:50 PM, Gary Thomas wrote: >>>> >>>> Any ideas on how to get this to work (i.e. fix the broken locking)? >>> >>> Try if building the current master (not 0.10.1) fixes it. >> >> That's what I'm running: >> meta-fsl-arm: f52c9106689f33c78b09496f4929ae1e87d13970 >> > -- ------------------------------------------------------------ Gary Thomas | Consulting for the MLB Associates | Embedded world ------------------------------------------------------------ --------------000605000802000907030506 Content-Type: text/x-log; name="gst-play.log" Content-Transfer-Encoding: 7bit Content-Disposition: attachment; filename="gst-play.log" 0:00:00.134832666 1507 0x1d53580 INFO imxeglplatform_x11 egl_platform_x11.c:114:gst_imx_egl_viv_sink_egl_platform_create: X11 EGL platform initialized, using EGL 1.4 0:00:00.139438000 1507 0x1d53580 WARN basesrc gstbasesrc.c:3470:gst_base_src_start_complete: pad not activated yet 0:00:00.142412333 1507 0x1d53580 WARN basesrc gstbasesrc.c:3470:gst_base_src_start_complete: pad not activated yet 0:00:00.239343000 1507 0xabf3cfb0 WARN qtdemux qtdemux_types.c:202:qtdemux_type_get: unknown QuickTime node type iods 0:00:00.239724333 1507 0xabf3cfb0 WARN qtdemux qtdemux_types.c:202:qtdemux_type_get: unknown QuickTime node type pasp 0:00:00.239807667 1507 0xabf3cfb0 WARN qtdemux qtdemux_types.c:202:qtdemux_type_get: unknown QuickTime node type sgpd 0:00:00.239872000 1507 0xabf3cfb0 WARN qtdemux qtdemux_types.c:202:qtdemux_type_get: unknown QuickTime node type sbgp 0:00:00.240018667 1507 0xabf3cfb0 WARN qtdemux qtdemux_types.c:202:qtdemux_type_get: unknown QuickTime node type iods 0:00:00.240146333 1507 0xabf3cfb0 WARN qtdemux qtdemux_types.c:202:qtdemux_type_get: unknown QuickTime node type pasp 0:00:00.247243667 1507 0xabf3cfb0 WARN qtdemux qtdemux_types.c:202:qtdemux_type_get: unknown QuickTime node type sgpd 0:00:00.247285667 1507 0xabf3cfb0 WARN qtdemux qtdemux_types.c:202:qtdemux_type_get: unknown QuickTime node type sbgp 0:00:00.288039333 1507 0xab412f80 INFO imxvpudec decoder.c:666:gst_imx_vpu_dec_start: starting VPU decoder 0:00:00.293150000 1507 0xab412f80 INFO imxvpudec decoder.c:340:gst_imx_vpu_dec_load: VPU loaded 0:00:00.293204000 1507 0xab412f80 INFO imxvpudec decoder.c:341:gst_imx_vpu_dec_load: VPU firmware version 3.1.1_r46063 0:00:00.293246333 1507 0xab412f80 INFO imxvpudec decoder.c:342:gst_imx_vpu_dec_load: VPU library version 5.4.28 0:00:00.293278000 1507 0xab412f80 INFO imxvpudec decoder.c:343:gst_imx_vpu_dec_load: VPU wrapper version 1.0.58 VPUWRAPPER_ARM_LINUX Build on May 21 2015 10:23:30 0:00:00.293350000 1507 0xab412f80 INFO imxvpudec decoder.c:384:gst_imx_vpu_dec_alloc_dec_mem_blocks: need to allocate 2 sub blocks for decoding 0:00:00.293384333 1507 0xab412f80 INFO imxvpudec decoder.c:388:gst_imx_vpu_dec_alloc_dec_mem_blocks: sub block 0 type: virtual size: 5676 0:00:00.293469000 1507 0xab412f80 INFO imxvpumemblocks mem_blocks.c:50:gst_imx_vpu_alloc_virt_mem_block: allocated 5676 bytes of heap memory at virt addr 0xaaa10010 0:00:00.293513667 1507 0xab412f80 INFO imxvpudec decoder.c:388:gst_imx_vpu_dec_alloc_dec_mem_blocks: sub block 1 type: physical size: 5236744 0:00:00.293723000 1507 0xab412f80 INFO imxphysmemallocator phys_mem_allocator.c:62:gst_imx_phys_mem_allocator_init: initializing physical memory allocator 0:00:00.293841000 1507 0xab412f80 DEBUG imxphysmemallocator 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.310837333 1507 0xab412f80 DEBUG imxvpudecallocator allocator.c:84:gst_imx_vpu_dec_alloc_phys_mem: addresses: virt: 0xa985a000 phys: 0x38800000 cpu: 0xe8800000 0:00:00.310908333 1507 0xab412f80 INFO imxphysmemallocator phys_mem_allocator.c:152:gst_imx_phys_mem_allocator_alloc: allocated memory block 0xaaa04a98 at phys addr 0x38800000 with 5236744 bytes 0:00:00.310954667 1507 0xab412f80 INFO imxvpudec decoder.c:693:gst_imx_vpu_dec_start: VPU decoder started 0:00:00.312911333 1507 0xab412f80 INFO imxvpudec decoder.c:760:gst_imx_vpu_dec_set_format: setting decoder format 0:00:00.312947000 1507 0xab412f80 INFO imxvpudec decoder.c:763:gst_imx_vpu_dec_set_format: draining remaining frames from decoder 0:00:00.312991000 1507 0xab412f80 INFO imxvpudec decoder.c:460:gst_imx_vpu_dec_fill_param_set: setting h.264 as stream format 0:00:00.318514333 1507 0xab412f80 INFO imxvpudec decoder.c:861:gst_imx_vpu_dec_set_format: setting format finished 0:00:00.319014667 1507 0xab412f80 LOG imxvpudec decoder.c:925:gst_imx_vpu_dec_handle_frame: VPU_DecDecodeBuf returns: 201 0:00:00.319069333 1507 0xab412f80 LOG imxvpudec decoder.c:961:gst_imx_vpu_dec_handle_frame: using I420 as video output format 0:00:00.319106333 1507 0xab412f80 INFO imxvpudec decoder.c:974:gst_imx_vpu_dec_handle_frame: minimum number of framebuffers indicated by the VPU: 6 chosen number: 12 0:00:00.319174667 1507 0xab412f80 INFO imxvpudec decoder.c:975:gst_imx_vpu_dec_handle_frame: interlacing: 0 0:00:00.319394333 1507 0xab412f80 INFO imxvpuframebuffers framebuffers.c:249:gst_imx_vpu_framebuffers_configure: framebuffer requested width/height: 1280/720 actual width/height (after alignment): 1280/720 Y stride: 1280 0:00:00.319451000 1507 0xab412f80 INFO imxvpuframebuffers framebuffers.c:254:gst_imx_vpu_framebuffers_configure: num framebuffers: total: 12 available: 12 0:00:00.319487667 1507 0xab412f80 INFO imxvpuframebuffers framebuffers.c:259:gst_imx_vpu_framebuffers_configure: framebuffer memory block size: total: 1612801 Y: 921600 U: 230400 V: 230400 Mv: 230400 alignment: 1 0:00:00.319538333 1507 0xab412f80 INFO imxvpuframebuffers framebuffers.c:264:gst_imx_vpu_framebuffers_configure: total memory required for all framebuffers: 1612801 * 12 = 19353612 byte 0:00:00.319579667 1507 0xab412f80 DEBUG imxphysmemallocator phys_mem_allocator.c:117:gst_imx_phys_mem_allocator_alloc_internal: alloc_internal called: maxsize: 1612801, align: 0, offset: 0, size: 1612801 0:00:00.326331667 1507 0xab412f80 DEBUG imxvpudecallocator allocator.c:84:gst_imx_vpu_dec_alloc_phys_mem: addresses: virt: 0xa8b76000 phys: 0x38d00000 cpu: 0xe8d00000 0:00:00.326424333 1507 0xab412f80 INFO imxphysmemallocator phys_mem_allocator.c:152:gst_imx_phys_mem_allocator_alloc: allocated memory block 0xaaa04cc0 at phys addr 0x38d00000 with 1612801 bytes 0:00:00.326487000 1507 0xab412f80 DEBUG imxphysmemallocator phys_mem_allocator.c:117:gst_imx_phys_mem_allocator_alloc_internal: alloc_internal called: maxsize: 1612801, align: 0, offset: 0, size: 1612801 Home directory not accessible: Permission denied 0:00:00.332008000 1507 0xab413260 WARN pulse pulsesink.c:615:gst_pulseringbuffer_open_device: error: Failed to connect: Connection refused 0:00:00.333299667 1507 0xab413260 WARN playbin gstplaybin2.c:4497:autoplug_select_cb: Could not activate sink pulsesink 0:00:00.336636667 1507 0xab412f80 DEBUG imxvpudecallocator allocator.c:84:gst_imx_vpu_dec_alloc_phys_mem: addresses: virt: 0xa89ec000 phys: 0x38f00000 cpu: 0xe8f00000 0:00:00.336711333 1507 0xab412f80 INFO imxphysmemallocator phys_mem_allocator.c:152:gst_imx_phys_mem_allocator_alloc: allocated memory block 0xaaa04d10 at phys addr 0x38f00000 with 1612801 bytes 0:00:00.336758667 1507 0xab412f80 DEBUG imxphysmemallocator phys_mem_allocator.c:117:gst_imx_phys_mem_allocator_alloc_internal: alloc_internal called: maxsize: 1612801, align: 0, offset: 0, size: 1612801 0:00:00.342781667 1507 0xab412f80 DEBUG imxvpudecallocator allocator.c:84:gst_imx_vpu_dec_alloc_phys_mem: addresses: virt: 0xa8862000 phys: 0x39100000 cpu: 0xe9100000 0:00:00.342856667 1507 0xab412f80 INFO imxphysmemallocator phys_mem_allocator.c:152:gst_imx_phys_mem_allocator_alloc: allocated memory block 0xaaa04c20 at phys addr 0x39100000 with 1612801 bytes 0:00:00.342923667 1507 0xab412f80 DEBUG imxphysmemallocator phys_mem_allocator.c:117:gst_imx_phys_mem_allocator_alloc_internal: alloc_internal called: maxsize: 1612801, align: 0, offset: 0, size: 1612801 0:00:00.349786333 1507 0xab412f80 DEBUG imxvpudecallocator allocator.c:84:gst_imx_vpu_dec_alloc_phys_mem: addresses: virt: 0xa86d8000 phys: 0x39300000 cpu: 0xe9300000 0:00:00.349860000 1507 0xab412f80 INFO imxphysmemallocator phys_mem_allocator.c:152:gst_imx_phys_mem_allocator_alloc: allocated memory block 0xaaa04c70 at phys addr 0x39300000 with 1612801 bytes 0:00:00.349905333 1507 0xab412f80 DEBUG imxphysmemallocator phys_mem_allocator.c:117:gst_imx_phys_mem_allocator_alloc_internal: alloc_internal called: maxsize: 1612801, align: 0, offset: 0, size: 1612801 0:00:00.356415667 1507 0xab412f80 DEBUG imxvpudecallocator allocator.c:84:gst_imx_vpu_dec_alloc_phys_mem: addresses: virt: 0xa854e000 phys: 0x39500000 cpu: 0xe9500000 0:00:00.356494333 1507 0xab412f80 INFO imxphysmemallocator phys_mem_allocator.c:152:gst_imx_phys_mem_allocator_alloc: allocated memory block 0xaaa04b38 at phys addr 0x39500000 with 1612801 bytes 0:00:00.356546333 1507 0xab412f80 DEBUG imxphysmemallocator phys_mem_allocator.c:117:gst_imx_phys_mem_allocator_alloc_internal: alloc_internal called: maxsize: 1612801, align: 0, offset: 0, size: 1612801 0:00:00.358923667 1507 0xab413260 WARN alsa conf.c:4563:parse_args: alsalib error: Unknown parameter AES0 0:00:00.358987000 1507 0xab413260 WARN alsa conf.c:4723:snd_config_expand: alsalib error: Parse arguments error: No such file or directory 0:00:00.359033667 1507 0xab413260 WARN alsa pcm.c:2267:snd_pcm_open_noupdate: alsalib error: Unknown PCM default:{AES0 0x02 AES1 0x82 AES2 0x00 AES3 0x02} 0:00:00.361610000 1507 0xab413260 DEBUG imxuniaudiocodec uniaudio_codec.c:64:gst_imx_audio_uniaudio_codec_table_init_internal: Caps: audio/mpeg, mpegversion=(int)4, framed=(boolean)true, stream-format=(string){ raw, adts, adif } 0:00:00.361742000 1507 0xab413260 DEBUG imxuniaudiocodec uniaudio_codec.c:92:gst_imx_audio_uniaudio_codec_load_codec: trying to load library /usr/lib/imx-mm/audio-codec/wrap/lib_aacd_wrap_arm12_elinux.so.3 0:00:00.365151667 1507 0xab412f80 DEBUG imxvpudecallocator allocator.c:84:gst_imx_vpu_dec_alloc_phys_mem: addresses: virt: 0xa83c4000 phys: 0x39700000 cpu: 0xe9700000 0:00:00.365318333 1507 0xab412f80 INFO imxphysmemallocator phys_mem_allocator.c:152:gst_imx_phys_mem_allocator_alloc: allocated memory block 0xaaa04ae8 at phys addr 0x39700000 with 1612801 bytes 0:00:00.365369333 1507 0xab412f80 DEBUG imxphysmemallocator phys_mem_allocator.c:117:gst_imx_phys_mem_allocator_alloc_internal: alloc_internal called: maxsize: 1612801, align: 0, offset: 0, size: 1612801 0:00:00.365519333 1507 0xab413260 DEBUG imxuniaudiocodec uniaudio_codec.c:64:gst_imx_audio_uniaudio_codec_table_init_internal: Caps: audio/mpeg, mpegversion=(int)1, layer=(int)3, parsed=(boolean)true 0:00:00.365597333 1507 0xab413260 DEBUG imxuniaudiocodec uniaudio_codec.c:92:gst_imx_audio_uniaudio_codec_load_codec: trying to load library /usr/lib/imx-mm/audio-codec/wrap/lib_mp3d_wrap_arm12_elinux.so.3 0:00:00.366516000 1507 0xab413260 DEBUG imxuniaudiocodec uniaudio_codec.c:64:gst_imx_audio_uniaudio_codec_table_init_internal: Caps: audio/x-vorbis 0:00:00.366558667 1507 0xab413260 DEBUG imxuniaudiocodec uniaudio_codec.c:92:gst_imx_audio_uniaudio_codec_load_codec: trying to load library /usr/lib/imx-mm/audio-codec/wrap/lib_vorbisd_wrap_arm12_elinux.so.3 0:00:00.367674667 1507 0xab413260 DEBUG imxuniaudiocodec uniaudio_codec.c:64:gst_imx_audio_uniaudio_codec_table_init_internal: Caps: audio/AMR 0:00:00.367828667 1507 0xab413260 DEBUG imxuniaudiocodec uniaudio_codec.c:92:gst_imx_audio_uniaudio_codec_load_codec: trying to load library /usr/lib/imx-mm/audio-codec/wrap/lib_nbamrd_wrap_arm11_elinux.so.1 0:00:00.369606667 1507 0xab413260 DEBUG imxuniaudiocodec uniaudio_codec.c:64:gst_imx_audio_uniaudio_codec_table_init_internal: Caps: audio/AMR-WB 0:00:00.369698000 1507 0xab413260 DEBUG imxuniaudiocodec uniaudio_codec.c:92:gst_imx_audio_uniaudio_codec_load_codec: trying to load library /usr/lib/imx-mm/audio-codec/wrap/lib_wbamrd_wrap_arm12_elinux.so.1 0:00:00.371135000 1507 0xab413260 DEBUG imxuniaudiodec uniaudio_decoder.c:170:gst_imx_audio_uniaudio_dec_class_init: decoder sink caps: audio/mpeg, mpegversion=(int)4, framed=(boolean)true, stream-format=(string){ raw, adts, adif }; audio/mpeg, mpegversion=(int)1, layer=(int)3, parsed=(boolean)true; audio/x-vorbis; audio/AMR; audio/AMR-WB 0:00:00.372574000 1507 0xab412f80 DEBUG imxvpudecallocator allocator.c:84:gst_imx_vpu_dec_alloc_phys_mem: addresses: virt: 0xa81f1000 phys: 0x39900000 cpu: 0xe9900000 0:00:00.372659333 1507 0xab412f80 INFO imxphysmemallocator phys_mem_allocator.c:152:gst_imx_phys_mem_allocator_alloc: allocated memory block 0xaaa049f8 at phys addr 0x39900000 with 1612801 bytes 0:00:00.372706333 1507 0xab412f80 DEBUG imxphysmemallocator phys_mem_allocator.c:117:gst_imx_phys_mem_allocator_alloc_internal: alloc_internal called: maxsize: 1612801, align: 0, offset: 0, size: 1612801 0:00:00.374362667 1507 0xab413260 DEBUG imxuniaudiocodec uniaudio_codec.c:167:gst_imx_audio_uniaudio_codec_table_get_codec: trying to find suitable codec for caps audio/mpeg, mpegversion=(int)4, framed=(boolean)true, stream-format=(string)raw, level=(string)2, base-profile=(string)lc, profile=(string)lc, codec_data=(buffer)1190, rate=(int)48000, channels=(int)2 0:00:00.374505000 1507 0xab413260 DEBUG imxuniaudiocodec uniaudio_codec.c:172:gst_imx_audio_uniaudio_codec_table_get_codec: codec caps audio/mpeg, mpegversion=(int)4, framed=(boolean)true, stream-format=(string){ raw, adts, adif } compatible: yes 0:00:00.374823667 1507 0xab413260 DEBUG imxuniaudiodec uniaudio_decoder.c:299:gst_imx_audio_uniaudio_dec_set_format: input is framed: 1 0:00:00.374902667 1507 0xab413260 DEBUG imxuniaudiodec uniaudio_decoder.c:304:gst_imx_audio_uniaudio_dec_set_format: input caps sample rate: 48000 Hz 0:00:00.374953333 1507 0xab413260 DEBUG imxuniaudiodec uniaudio_decoder.c:313:gst_imx_audio_uniaudio_dec_set_format: input caps channel count: 2 0:00:00.375003000 1507 0xab413260 DEBUG imxuniaudiodec uniaudio_decoder.c:346:gst_imx_audio_uniaudio_dec_set_format: input caps stream format: raw 0:00:00.375053000 1507 0xab413260 DEBUG imxuniaudiodec uniaudio_decoder.c:395:gst_imx_audio_uniaudio_dec_set_format: reading codec_data value 0:00:00.375155333 1507 0xab413260 DEBUG imxuniaudiodec uniaudio_decoder.c:442:gst_imx_audio_uniaudio_dec_set_format: codec data: 2 byte 0:00:00.375210000 1507 0xab413260 DEBUG imxuniaudiodec uniaudio_decoder.c:446:gst_imx_audio_uniaudio_dec_set_format: decoder configured 0:00:00.375390333 1507 0xab413260 TRACE imxuniaudiodec uniaudio_decoder.c:492:gst_imx_audio_uniaudio_dec_handle_frame: feeding 427 bytes to the decoder 0:00:00.376612667 1507 0xab413260 TRACE imxuniaudiodec uniaudio_decoder.c:503:gst_imx_audio_uniaudio_dec_handle_frame: decode_frame: return 0x200 offset 427 out_size 0 0:00:00.376727667 1507 0xab413260 DEBUG imxuniaudiodec uniaudio_decoder.c:563:gst_imx_audio_uniaudio_dec_handle_frame: output sample width: 16 depth: 16 0:00:00.376776667 1507 0xab413260 DEBUG imxuniaudiodec uniaudio_decoder.c:566:gst_imx_audio_uniaudio_dec_handle_frame: setting output format to: S16LE 48000 Hz 2 channels 0:00:00.376853000 1507 0xab413260 DEBUG imxuniaudiodec uniaudio_decoder.c:656:gst_imx_audio_uniaudio_dec_fill_channel_positions: channel positions are in valid order, no need to reorder channels 0:00:00.377197333 1507 0xab413260 TRACE imxuniaudiodec uniaudio_decoder.c:492:gst_imx_audio_uniaudio_dec_handle_frame: feeding 426 bytes to the decoder 0:00:00.377556667 1507 0xab413260 TRACE imxuniaudiodec uniaudio_decoder.c:503:gst_imx_audio_uniaudio_dec_handle_frame: decode_frame: return 0x0 offset 426 out_size 4096 0:00:00.380412667 1507 0xab412f80 DEBUG imxvpudecallocator allocator.c:84:gst_imx_vpu_dec_alloc_phys_mem: addresses: virt: 0xa7e76000 phys: 0x39b00000 cpu: 0xe9b00000 0:00:00.380487000 1507 0xab412f80 INFO imxphysmemallocator phys_mem_allocator.c:152:gst_imx_phys_mem_allocator_alloc: allocated memory block 0xaaa04a48 at phys addr 0x39b00000 with 1612801 bytes 0:00:00.380532333 1507 0xab412f80 DEBUG imxphysmemallocator phys_mem_allocator.c:117:gst_imx_phys_mem_allocator_alloc_internal: alloc_internal called: maxsize: 1612801, align: 0, offset: 0, size: 1612801 0:00:00.387196000 1507 0xab412f80 DEBUG imxvpudecallocator allocator.c:84:gst_imx_vpu_dec_alloc_phys_mem: addresses: virt: 0xa7cec000 phys: 0x39d00000 cpu: 0xe9d00000 0:00:00.387272000 1507 0xab412f80 INFO imxphysmemallocator phys_mem_allocator.c:152:gst_imx_phys_mem_allocator_alloc: allocated memory block 0xaaa049a8 at phys addr 0x39d00000 with 1612801 bytes 0:00:00.387316667 1507 0xab412f80 DEBUG imxphysmemallocator phys_mem_allocator.c:117:gst_imx_phys_mem_allocator_alloc_internal: alloc_internal called: maxsize: 1612801, align: 0, offset: 0, size: 1612801 0:00:00.399804667 1507 0xab412f80 DEBUG imxvpudecallocator allocator.c:84:gst_imx_vpu_dec_alloc_phys_mem: addresses: virt: 0xa7b62000 phys: 0x39f00000 cpu: 0xe9f00000 0:00:00.399878333 1507 0xab412f80 INFO imxphysmemallocator phys_mem_allocator.c:152:gst_imx_phys_mem_allocator_alloc: allocated memory block 0xaaa04958 at phys addr 0x39f00000 with 1612801 bytes 0:00:00.399924000 1507 0xab412f80 DEBUG imxphysmemallocator phys_mem_allocator.c:117:gst_imx_phys_mem_allocator_alloc_internal: alloc_internal called: maxsize: 1612801, align: 0, offset: 0, size: 1612801 0:00:00.406092000 1507 0xab412f80 DEBUG imxvpudecallocator allocator.c:84:gst_imx_vpu_dec_alloc_phys_mem: addresses: virt: 0xa79d8000 phys: 0x3a100000 cpu: 0xea100000 0:00:00.406166667 1507 0xab412f80 INFO imxphysmemallocator phys_mem_allocator.c:152:gst_imx_phys_mem_allocator_alloc: allocated memory block 0xaaa04868 at phys addr 0x3a100000 with 1612801 bytes 0:00:00.406213667 1507 0xab412f80 DEBUG imxphysmemallocator phys_mem_allocator.c:117:gst_imx_phys_mem_allocator_alloc_internal: alloc_internal called: maxsize: 1612801, align: 0, offset: 0, size: 1612801 0:00:00.412735667 1507 0xab412f80 DEBUG imxvpudecallocator allocator.c:84:gst_imx_vpu_dec_alloc_phys_mem: addresses: virt: 0xa784e000 phys: 0x3a300000 cpu: 0xea300000 0:00:00.412810667 1507 0xab412f80 INFO imxphysmemallocator phys_mem_allocator.c:152:gst_imx_phys_mem_allocator_alloc: allocated memory block 0xaaa048b8 at phys addr 0x3a300000 with 1612801 bytes 0:00:00.413073000 1507 0xab412f80 DEBUG imxvpudec decoder.c:1331:gst_imx_vpu_dec_handle_frame: nothing to output (ret code: 0x201) 0:00:00.419212333 1507 0xab412f80 LOG imxvpudec decoder.c:925:gst_imx_vpu_dec_handle_frame: VPU_DecDecodeBuf returns: 809 0:00:00.419253667 1507 0xab412f80 LOG imxvpudec decoder.c:1076:gst_imx_vpu_dec_handle_frame: one frame got consumed: cur_frame: 0xab407b48 framebuffer: 0xaaa100a0 system frame number: 0 stuff length: 31 frame length: 999 0:00:00.419310000 1507 0xab412f80 LOG imxvpuframebuffers framebuffers.c:178:gst_imx_vpu_framebuffers_wait_until_frames_available: flushing = 0 exit_loop = 0 0:00:00.419343667 1507 0xab412f80 LOG imxvpudec decoder.c:1109:gst_imx_vpu_dec_handle_frame: number of available buffers: 12 -> 12 -> 11 0:00:00.419376000 1507 0xab412f80 DEBUG imxvpudec decoder.c:1331:gst_imx_vpu_dec_handle_frame: nothing to output (ret code: 0x809) 0:00:00.425978333 1507 0xab412f80 LOG imxvpudec decoder.c:925:gst_imx_vpu_dec_handle_frame: VPU_DecDecodeBuf returns: 809 0:00:00.426019333 1507 0xab412f80 LOG imxvpudec decoder.c:1076:gst_imx_vpu_dec_handle_frame: one frame got consumed: cur_frame: 0xab407bf0 framebuffer: 0xaaa100f0 system frame number: 1 stuff length: 0 frame length: 11458 0:00:00.426061667 1507 0xab412f80 LOG imxvpuframebuffers framebuffers.c:178:gst_imx_vpu_framebuffers_wait_until_frames_available: flushing = 0 exit_loop = 0 0:00:00.426093000 1507 0xab412f80 LOG imxvpudec decoder.c:1109:gst_imx_vpu_dec_handle_frame: number of available buffers: 11 -> 11 -> 10 0:00:00.426124000 1507 0xab412f80 DEBUG imxvpudec decoder.c:1331:gst_imx_vpu_dec_handle_frame: nothing to output (ret code: 0x809) 0:00:00.432687333 1507 0xab412f80 LOG imxvpudec decoder.c:925:gst_imx_vpu_dec_handle_frame: VPU_DecDecodeBuf returns: 809 0:00:00.432729000 1507 0xab412f80 LOG imxvpudec decoder.c:1076:gst_imx_vpu_dec_handle_frame: one frame got consumed: cur_frame: 0xab407c98 framebuffer: 0xaaa10140 system frame number: 2 stuff length: 0 frame length: 14878 0:00:00.432771667 1507 0xab412f80 LOG imxvpuframebuffers framebuffers.c:178:gst_imx_vpu_framebuffers_wait_until_frames_available: flushing = 0 exit_loop = 0 0:00:00.432803000 1507 0xab412f80 LOG imxvpudec decoder.c:1109:gst_imx_vpu_dec_handle_frame: number of available buffers: 10 -> 10 -> 9 0:00:00.432834333 1507 0xab412f80 DEBUG imxvpudec decoder.c:1331:gst_imx_vpu_dec_handle_frame: nothing to output (ret code: 0x809) 0:00:00.440284000 1507 0xab412f80 LOG imxvpudec decoder.c:925:gst_imx_vpu_dec_handle_frame: VPU_DecDecodeBuf returns: 809 0:00:00.440329333 1507 0xab412f80 LOG imxvpudec decoder.c:1076:gst_imx_vpu_dec_handle_frame: one frame got consumed: cur_frame: 0xab407d40 framebuffer: 0xaaa10190 system frame number: 3 stuff length: 0 frame length: 1210 0:00:00.440370667 1507 0xab412f80 LOG imxvpuframebuffers framebuffers.c:178:gst_imx_vpu_framebuffers_wait_until_frames_available: flushing = 0 exit_loop = 0 0:00:00.440401667 1507 0xab412f80 LOG imxvpudec decoder.c:1109:gst_imx_vpu_dec_handle_frame: number of available buffers: 9 -> 9 -> 8 0:00:00.440434333 1507 0xab412f80 DEBUG imxvpudec decoder.c:1331:gst_imx_vpu_dec_handle_frame: nothing to output (ret code: 0x809) 0:00:00.447610333 1507 0xab412f80 LOG imxvpudec decoder.c:925:gst_imx_vpu_dec_handle_frame: VPU_DecDecodeBuf returns: 805 0:00:00.447649667 1507 0xab412f80 LOG imxvpudec decoder.c:1076:gst_imx_vpu_dec_handle_frame: one frame got consumed: cur_frame: 0xab407de8 framebuffer: 0xaaa101e0 system frame number: 4 stuff length: 0 frame length: 975 0:00:00.447691667 1507 0xab412f80 LOG imxvpuframebuffers framebuffers.c:178:gst_imx_vpu_framebuffers_wait_until_frames_available: flushing = 0 exit_loop = 0 0:00:00.447723000 1507 0xab412f80 LOG imxvpudec decoder.c:1109:gst_imx_vpu_dec_handle_frame: number of available buffers: 8 -> 8 -> 7 0:00:00.447769667 1507 0xab412f80 LOG imxvpudec decoder.c:1195:gst_imx_vpu_dec_handle_frame: system frame number valid and corresponding frame is still pending 0:00:00.474733333 1507 0xaaa02a90 LOG imxeglplatform_x11 egl_platform_x11.c:460:gst_imx_egl_viv_sink_egl_platform_set_video_info: window not open - cannot set video info 0:00:00.474820667 1507 0xaaa02a90 LOG imxgles2renderer gles2_renderer.c:1140:gst_imx_egl_viv_sink_gles2_renderer_set_force_aspect_ratio: setting force_aspect_ratio to 1 0:00:00.475737333 1507 0xab412f80 INFO imxvpudec decoder.c:1467:gst_imx_vpu_dec_decide_allocation: number of allocation pools in query: 1 0:00:00.475811333 1507 0xab412f80 INFO imxvpudec decoder.c:1500:gst_imx_vpu_dec_decide_allocation: no pool supports VPU buffers; creating new pool 0:00:00.475995333 1507 0xab412f80 INFO imxvpufbbufferpool fb_buffer_pool.c:240:gst_imx_vpu_fb_buffer_pool_init: initializing VPU buffer pool 0:00:00.476073000 1507 0xab412f80 INFO imxvpudec decoder.c:1512:gst_imx_vpu_dec_decide_allocation: pool config: outcaps: video/x-raw, format=(string)I420, width=(int)1280, height=(int)720, pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)progressive, colorimetry=(string)bt709, framerate=(fraction)25/1 size: 1612801 min buffers: 0 max buffers: 0 0:00:00.476816667 1507 0xab412f80 LOG imxvpufbbufferpool fb_buffer_pool.c:319:gst_imx_vpu_set_buffer_contents: setting phys mem meta for buffer with pointer 0xaaa04000: phys addr 0x38d00000 x/y padding 0/0 0:00:00.476896667 1507 0xab412f80 LOG imxvpudec decoder.c:1226:gst_imx_vpu_dec_handle_frame: output frame: codecframe: 0xab4079f8 framebuffer phys addr: 0x38d00000 system frame number: 0 gstbuffer addr: 0xaaa04000 field type: 0 pic type: 3 Y stride: 1280 CbCr stride: 640 0:00:00.479308333 1507 0xab412f80 INFO imxvpudec decoder.c:1467:gst_imx_vpu_dec_decide_allocation: number of allocation pools in query: 1 0:00:00.479461000 1507 0xab412f80 INFO imxvpudec decoder.c:1500:gst_imx_vpu_dec_decide_allocation: no pool supports VPU buffers; creating new pool 0:00:00.479664333 1507 0xab412f80 INFO imxvpufbbufferpool fb_buffer_pool.c:240:gst_imx_vpu_fb_buffer_pool_init: initializing VPU buffer pool 0:00:00.479685667 1507 0xab413260 TRACE imxuniaudiodec uniaudio_decoder.c:492:gst_imx_audio_uniaudio_dec_handle_frame: feeding 427 bytes to the decoder 0:00:00.479744000 1507 0xab412f80 INFO imxvpudec decoder.c:1512:gst_imx_vpu_dec_decide_allocation: pool config: outcaps: video/x-raw, format=(string)I420, width=(int)1280, height=(int)720, pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)progressive, colorimetry=(string)bt709, framerate=(fraction)25/1 size: 1612801 min buffers: 0 max buffers: 0 0:00:00.480036667 1507 0xab413260 TRACE imxuniaudiodec uniaudio_decoder.c:503:gst_imx_audio_uniaudio_dec_handle_frame: decode_frame: return 0x0 offset 427 out_size 4096 0:00:00.480769667 1507 0xab413260 TRACE imxuniaudiodec uniaudio_decoder.c:492:gst_imx_audio_uniaudio_dec_handle_frame: feeding 427 bytes to the decoder 0:00:00.481164667 1507 0xab413260 TRACE imxuniaudiodec uniaudio_decoder.c:503:gst_imx_audio_uniaudio_dec_handle_frame: decode_frame: return 0x0 offset 427 out_size 4096 0:00:00.481799333 1507 0xab413260 TRACE imxuniaudiodec uniaudio_decoder.c:492:gst_imx_audio_uniaudio_dec_handle_frame: feeding 426 bytes to the decoder 0:00:00.482132333 1507 0xab413260 TRACE imxuniaudiodec uniaudio_decoder.c:503:gst_imx_audio_uniaudio_dec_handle_frame: decode_frame: return 0x0 offset 426 out_size 4096 0:00:00.482596667 1507 0xab413260 TRACE imxuniaudiodec uniaudio_decoder.c:492:gst_imx_audio_uniaudio_dec_handle_frame: feeding 427 bytes to the decoder 0:00:00.482961333 1507 0xab413260 TRACE imxuniaudiodec uniaudio_decoder.c:503:gst_imx_audio_uniaudio_dec_handle_frame: decode_frame: return 0x0 offset 427 out_size 4096 0:00:00.483379000 1507 0xab413260 TRACE imxuniaudiodec uniaudio_decoder.c:492:gst_imx_audio_uniaudio_dec_handle_frame: feeding 427 bytes to the decoder 0:00:00.483626667 1507 0xab413260 TRACE imxuniaudiodec uniaudio_decoder.c:503:gst_imx_audio_uniaudio_dec_handle_frame: decode_frame: return 0x0 offset 427 out_size 4096 0:00:00.484281667 1507 0xaaa02a90 LOG imxeglplatform_x11 egl_platform_x11.c:428:gst_imx_egl_viv_sink_egl_platform_send_cmd: window not open - cannot send cmd Attempt to unlock mutex that was not locked 0:00:00.484483333 1507 0xab413260 TRACE imxuniaudiodec uniaudio_decoder.c:492:gst_imx_audio_uniaudio_dec_handle_frame: feeding 426 bytes to the decoder 0:00:00.486027333 1507 0xab413260 TRACE imxuniaudiodec uniaudio_decoder.c:503:gst_imx_audio_uniaudio_dec_handle_frame: decode_frame: return 0x0 offset 426 out_size 4096 Aborted --------------000605000802000907030506--