Linux/DRA746: Waylansink:Video playback hangs when playing with subtitle files.

Ikshwaku Chauhan

Part Number: DRA746

Tool/software: Linux

Hello Team,

We are trying to play video file with separate subtitle file using below commands. When we play Divx video file + external subtitle, we observed the video will stop after play a while, but same file plays properly without subtitle file.

gst-launch-1.0 playbin uri=file:///tmp/USB1-1.4-sda1/02_DivX6\(AVI\)_with_SRT_subtitle\(German\).AVI suburi=file:///tmp/USB1-1.4-sda1/02_DivX6\(AVI\)_with_SRT_subtitle\(German\).SRT video-sink=waylandsink

0:00:07.778620689 2735 0xb5d044c0 WARN waylandsink wlvideoformat.c:102:gst_wl_shm_format_to_video_format: gst video format not found
0:00:07.778672539 2735 0xb5d044c0 WARN waylandsink wlvideoformat.c:102:gst_wl_shm_format_to_video_format: gst video format not found
0:00:07.778847745 2735 0xb5d044c0 WARN default gstvalue.c:210:gst_value_serialize_any_list: Could not serialize list/array value of type 'gchararray'
0:00:07.778914845 2735 0xb5d044c0 WARN default gstvalue.c:210:gst_value_serialize_any_list: Could not serialize list/array value of type 'gchararray'
0:00:07.778828936 2735 0xb5d044c0 DEBUG waylandsink gstwaylandsink.c:478:gst_wayland_sink_get_caps:<waylandsink0> display caps: video/x-raw, format=(string){ BGRA, BGRx, RGB16, YUV9, Y41B, I420, Y42B, v308, NV12, NV16, YUY2 }, width=(int)[ 1, 2147483647 ], height=(int)[ 1, 2147483647 ], framerate=(fraction)[ 0/1, 2147483647/1 ]
0:00:07.797768079 2735 0xb5d044c0 WARN waylandsink wlvideoformat.c:102:gst_wl_shm_format_to_video_format: gst video format not found
0:00:07.797850259 2735 0xb5d044c0 WARN waylandsink wlvideoformat.c:102:gst_wl_shm_format_to_video_format: gst video format not found
0:00:07.798034445 2735 0xb5d044c0 WARN default gstvalue.c:210:gst_value_serialize_any_list: Could not serialize list/array value of type 'gchararray'
0:00:07.798099173 2735 0xb5d044c0 WARN default gstvalue.c:210:gst_value_serialize_any_list: Could not serialize list/array value of type 'gchararray'
0:00:07.798016145 2735 0xb5d044c0 DEBUG waylandsink gstwaylandsink.c:478:gst_wayland_sink_get_caps:<waylandsink0> display caps: video/x-raw, format=(string){ BGRA, BGRx, RGB16, YUV9, Y41B, I420, Y42B, v308, NV12, NV16, YUY2 }, width=(int)[ 1, 2147483647 ], height=(int)[ 1, 2147483647 ], framerate=(fraction)[ 0/1, 2147483647/1 ]
0:00:07.834835063 2735 0xb4002580 WARN ducati gstducatividdec.c:570:codec_process:<decoder> err=-1, extendedError=02001000
0:00:07.834918769 2735 0xb4002580 ERROR ducati gstducati.c:61:gst_ducati_log_extended_error_info: Bit 12 (00001000): corrupted header
0:00:07.834987055 2735 0xb4002580 ERROR ducati gstducati.c:61:gst_ducati_log_extended_error_info: Bit 25 (02000000): given vop is not codec
0:00:07.835471158 2735 0xb4002580 ERROR GST_BUFFER gstbuffer.c:558:gst_buffer_copy_into: failed to copy meta 0xb53698e4 of API type GstMetaDucatiBufferPrivAPI
0:00:07.866697054 2735 0x1c7cf0 DEBUG waylandsink wldrm.c:92:gst_wl_drm_memory_construct_wl_buffer: create planar buffer: 0xb404d7a0 (name=18)
0:00:07.887893196 2735 0xb4002580 WARN ducati gstducatividdec.c:570:codec_process:<decoder> err=-1, extendedError=02001000
0:00:07.887961483 2735 0xb4002580 ERROR ducati gstducati.c:61:gst_ducati_log_extended_error_info: Bit 12 (00001000): corrupted header
0:00:07.888002149 2735 0xb4002580 ERROR ducati gstducati.c:61:gst_ducati_log_extended_error_info: Bit 25 (02000000): given vop is not codec
0:00:07.888711105 2735 0xb5d044c0 ERROR GST_BUFFER gstbuffer.c:558:gst_buffer_copy_into: failed to copy meta 0xb40543c4 of API type GstMetaDucatiBufferPrivAPI
0:00:07.895326723 2735 0xb5d044c0 ERROR GST_BUFFER gstbuffer.c:558:gst_buffer_copy_into: failed to copy meta 0xb4052564 of API type GstMetaDucatiBufferPrivAPI
0:00:07.921353896 2735 0x1c7cf0 DEBUG waylandsink wldrm.c:92:gst_wl_drm_memory_construct_wl_buffer: create planar buffer: 0xb40527b0 (name=19)
0:00:07.924367804 2735 0xb4002580 WARN ducati gstducatividdec.c:570:codec_process:<decoder> err=-1, extendedError=02001000
0:00:07.924553345 2735 0xb4002580 ERROR ducati gstducati.c:61:gst_ducati_log_extended_error_info: Bit 12 (00001000): corrupted header
0:00:07.924715673 2735 0xb4002580 ERROR ducati gstducati.c:61:gst_ducati_log_extended_error_info: Bit 25 (02000000): given vop is not codec
0:00:08.002211250 2735 0x1c7cf0 DEBUG waylandsink wldrm.c:92:gst_wl_drm_memory_construct_wl_buffer: create planar buffer: 0xb40527b0 (name=20)
0:00:08.024522337 2735 0xb5d044c0 ERROR GST_BUFFER gstbuffer.c:558:gst_buffer_copy_into: failed to copy meta 0xb40535a4 of API type GstMetaDucatiBufferPrivAPI
0:00:08.051918281 2735 0x1c7cf0 DEBUG waylandsink wldrm.c:92:gst_wl_drm_memory_construct_wl_buffer: create planar buffer: 0xb4026538 (name=21)
0:00:08.073308098 2735 0xb4002580 WARN ducati gstducatividdec.c:570:codec_process:<decoder> err=-1, extendedError=02001000
0:00:08.073544981 2735 0xb4002580 ERROR ducati gstducati.c:61:gst_ducati_log_extended_error_info: Bit 12 (00001000): corrupted header
0:00:08.073738148 2735 0xb4002580 ERROR ducati gstducati.c:61:gst_ducati_log_extended_error_info: Bit 25 (02000000): given vop is not codec

Sharing you the files separately.

Could you please suggest us on what might be going wrong?

Thanks & Regards,

Ikshwaku

over 7 years ago

0 Ramprasad over 7 years ago

TI__Mastermind 30480 points

Hi Ikshwaku,
Can you check the dmesg logs when the issue is reproduced? I see it is OOM situaltion and there is no LowMemory left
and this is resulting in this issue. Board had to be rebooted to run again in my case.

When sub-title is present, playbin is using textoverlay plugin to overlay subtitles on main video. This is resulting in some LowMemory
situation. You can keep checking cat /proc/meminfo, LowMem keeps coming down.

Thanks
Ram

0 Ikshwaku Chauhan over 7 years ago in reply to Ramprasad

Expert 2630 points

Hello Ram,

I have checked the dmesg and I am not getting the OOM in logs. Also in /proc/meminfo there is no much difference for LowMemory before and after playback.

I am getting these dmesg logs when we start playback:

[ 44.718227] systemd[1]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/systemd1/job/148 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=15 reply_cookie=0 error=n/a
[ 44.738174] systemd[1]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/systemd1/unit/weston_2eservice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=16 reply_cookie=0 error=n/a
[ 44.748270] systemd[1]: Received SIGCHLD from PID 2544 (chmod).
[ 44.758280] systemd[1]: weston.service: Forked /bin/sleep as 2547
[ 44.768473] systemd[1]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/systemd1/unit/weston_2eservice interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=527 reply_cookie=0 error=n/a
[ 134.492400] free private data struct
[ 134.715844] Jacinto6_pcm_hw_params: channels 2
[ 134.716010] Jacinto6_pcm_hw_params: rate 48000
[ 134.716020] Jacinto6_pcm_hw_params: period_size 768
[ 134.716029] Jacinto6_pcm_hw_params: periods 6
[ 134.716038] Jacinto6_pcm_hw_params: buffer_size 4608
[ 134.716047] Jacinto6_pcm_hw_params: buffer_bytes 18432
[ 134.980312] Jacinto6_pcm_trigger play 0
[ 183.715126] Jacinto6_pcm_trigger stop 0
[ 183.728784] free private data struct

Regards,
Ikshwaku

0 Ramprasad over 7 years ago in reply to Ikshwaku Chauhan

TI__Mastermind 30480 points

Fullscreen 3058.Log.txt Download

root@dra7xx-evm:~#
root@dra7xx-evm:~#
root@dra7xx-evm:~# gst-launch-1.0 playbin uri=file:///home/root/02.avi suburi=file:///home/root/02.SRT video-sink=waylandsink
Setting pipeline to PAUSED ...
Pipeline is PREROLLING ...
[   69.682165] omap-iommu 55082000.mmu: 55082000.mmu: version 2.1
Pipeline is PREROLLED ...
Setting pipeline to PLAYING ...
New clock: GstAudioSinkClock
[  184.589598] vqueue:src invoked oom-killer: gfp_mask=0x24200c4, order=0, oom_score_adj=0
[  184.597729] vqueue:src cpuset=/ mems_allowed=0
[  184.603313] CPU: 1 PID: 1140 Comm: vqueue:src Tainted: G           O    4.4.84-g742f84423d #1
[  184.611875] Hardware name: Generic DRA74X (Flattened Device Tree)
[  184.617994] Backtrace:
[  184.620475] [<c00131d4>] (dump_backtrace) from [<c00133d0>] (show_stack+0x18/0x1c)
[  184.628080]  r7:ed158a78 r6:60070013 r5:00000000 r4:c0959810
[  184.633815] [<c00133b8>] (show_stack) from [<c02b21a8>] (dump_stack+0x8c/0xa0)
[  184.641076] [<c02b211c>] (dump_stack) from [<c011e2f8>] (dump_header+0x5c/0x1ac)
[  184.648501]  r7:ed158a78 r6:00000000 r5:e6fcbc04 r4:ed158600
[  184.654224] [<c011e29c>] (dump_header) from [<c00d3b28>] (oom_kill_process+0x2fc/0x448)
[  184.662259]  r10:c093f8b8 r9:00001aed r8:00000011 r7:ed158a78 r6:0005df5c r5:e6fcbc04
[  184.670163]  r4:ed158600
[  184.672716] [<c00d382c>] (oom_kill_process) from [<c00d3fd4>] (out_of_memory+0x2f0/0x32c)
[  184.680926]  r10:c093f8b8 r9:00001aed r8:c093f8b8 r7:c093fb38 r6:0005df5c r5:e6fcbc04
[  184.688828]  r4:ed158600
[  184.691382] [<c00d3ce4>] (out_of_memory) from [<c00d8e9c>] (__alloc_pages_nodemask+0x924/0x964)
[  184.700117]  r10:c0988500 r9:024200c4 r8:00000000 r7:c093c66c r6:e6fca000 r5:00000000
[  184.708020]  r4:00000000
[  184.710575] [<c00d8578>] (__alloc_pages_nodemask) from [<c00e7288>] (shmem_getpage_gfp+0x3ac/0x814)
[  184.719657]  r10:fffffffe r9:00000001 r8:ec635d40 r7:000000a0 r6:00000000 r5:eec62a80
[  184.727561]  r4:eba181c0
[  184.730113] [<c00e6edc>] (shmem_getpage_gfp) from [<c00e91cc>] (shmem_read_mapping_page_gfp+0x38/0x5c)
[  184.739457]  r10:38e38e39 r9:000fffff r8:000000a0 r7:ef86ba58 r6:eb187400 r5:000000ab
[  184.747359]  r4:ec635e24
[  184.749915] [<c00e9194>] (shmem_read_mapping_page_gfp) from [<c038e938>] (drm_gem_get_pages+0xd0/0x190)
[  184.759353] [<c038e868>] (drm_gem_get_pages) from [<c03ce7c4>] (get_pages+0x58/0x2a8)
[  184.767214]  r10:ad755000 r9:00000aa8 r8:e6fcbdb4 r7:ee7a3c00 r6:e6fcbd84 r5:e84e46c0
[  184.775118]  r4:00000000
[  184.777673] [<c03ce76c>] (get_pages) from [<c03cf3b8>] (omap_gem_fault+0x38/0x150)
[  184.785273]  r10:ad755000 r9:00000aa8 r8:e6fcbdb4 r7:ead29de0 r6:eba181c0 r5:e84e46c0
[  184.793174]  r4:ee7a3c34
[  184.795728] [<c03cf380>] (omap_gem_fault) from [<c00f5edc>] (__do_fault+0x44/0xa0)
[  184.803327]  r8:00000000 r7:ad755000 r6:eba181c0 r5:c09b8880 r4:00000000
[  184.810101] [<c00f5e98>] (__do_fault) from [<c00f9940>] (handle_mm_fault+0x22c/0x1294)
[  184.818049]  r5:c09b8880 r4:ead29de0
[  184.821658] [<c00f9714>] (handle_mm_fault) from [<c001cec8>] (do_page_fault+0x288/0x358)
[  184.829781]  r10:00000055 r9:eba181fc r8:ad755000 r7:00000a06 r6:eba181c0 r5:ed157800
[  184.837683]  r4:e6fcbfb0
[  184.840235] [<c001cc40>] (do_page_fault) from [<c001d130>] (do_translation_fault+0x104/0x108)
[  184.848792]  r10:00000000 r9:af7446b8 r8:e6fcbfb0 r7:ad755000 r6:c001d02c r5:00000a06
[  184.856695]  r4:c0940f0c
[  184.859248] [<c001d02c>] (do_translation_fault) from [<c0009298>] (do_DataAbort+0x38/0xb8)
[  184.867545]  r9:af7446b8 r8:e6fcbfb0 r7:ad755000 r6:c001d02c r5:00000a06 r4:c0940f0c
[  184.875366] [<c0009260>] (do_DataAbort) from [<c0014140>] (__dabt_usr+0x40/0x60)
[  184.882792] Exception stack(0xe6fcbfb0 to 0xe6fcbff8)
[  184.887865] bfa0:                                     ad755000 ad955020 000ab000 00000000
[  184.896078] bfc0: b2528318 b5458ac8 000ab000 b6f19e7c 000aad40 af7446b8 00000000 af7446ec
[  184.904289] bfe0: ad755000 af74467c b6e337a8 b6b01dc0 20030010 ffffffff
[  184.910928]  r8:30c5387d r7:30c5387d r6:ffffffff r5:20030010 r4:b6b01dc0
[  184.918428] Mem-Info:
[  184.920808] active_anon:6444 inactive_anon:137219 isolated_anon:0
[  184.920808]  active_file:3054 inactive_file:7728 isolated_file:0
[  184.920808]  unevictable:0 dirty:0 writeback:0 unstable:0
[  184.920808]  slab_reclaimable:1525 slab_unreclaimable:3607
[  184.920808]  mapped:11390 shmem:137654 pagetables:260 bounce:0
[  184.920808]  free:187595 free_pcp:262 free_cma:42440
[  184.956047] DMA free:94520kB min:3080kB low:3848kB high:4620kB active_anon:11256kB inactive_anon:534896kB active_file:112kB inactive_file:248kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:782336kB managed:757104kB mlocked:0kB dirty:0kB writeback:0kB mapped:18564kB shmem:535412kB slab_reclaimable:6100kB slab_unreclaimable:14428kB kernel_stack:1248kB pagetables:400kB unstable:0kB bounce:0kB free_pcp:656kB local_pcp:216kB free_cma:92064kB writeback_tmp:0kB pages_scanned:2252 all_unreclaimable? yes
[  185.002910] lowmem_reserve[]: 0 0 636 636
[  185.007001] HighMem free:655860kB min:512kB low:1356kB high:2200kB active_anon:14520kB inactive_anon:13980kB active_file:12172kB inactive_file:30664kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:782336kB managed:782336kB mlocked:0kB dirty:0kB writeback:0kB mapped:26996kB shmem:15204kB slab_reclaimable:0kB slab_unreclaimable:0kB kernel_stack:0kB pagetables:640kB unstable:0kB bounce:0kB free_pcp:372kB local_pcp:248kB free_cma:77696kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[  185.051874] lowmem_reserve[]: 0 0 0 0
[  185.056910] DMA: 317*4kB (UMEC) 143*8kB (UME) 42*16kB (UME) 5*32kB (UC) 4*64kB (C) 4*128kB (C) 0*256kB 4*512kB (C) 3*1024kB (C) 0*2048kB 21*4096kB (C) = 95148kB
[  185.071780] HighMem: 38*4kB (UMC) 263*8kB (UMC) 327*16kB (UMC) 259*32kB (UMC) 170*64kB (UMC) 87*128kB (UMC) 32*256kB (UMC) 10*512kB (UMC) 5*1024kB (UM) 3*2048kB (UC) 145*4096kB (MC) = 656288kB
[  185.089907] 148407 total pagecache pages
[  185.093850] 0 pages in swap cache
[  185.097841] Swap cache stats: add 0, delete 0, find 0/0
[  185.103084] Free swap  = 0kB
[  185.106219] Total swap = 0kB
[  185.109125] 391168 pages RAM
[  185.112097] 195584 pages HighMem/MovableOnly
[  185.120242] 6308 pages reserved
[  185.123403] 73728 pages cma reserved
[  185.130300] [ pid ]   uid  tgid total_vm      rss nr_ptes nr_pmds swapents oom_score_adj name
[  185.139219] [  156]     0   156     2656      743       7       2        0             0 systemd-journal
[  185.149009] [  195]     0   195     2681      495       6       2        0         -1000 systemd-udevd
[  185.158927] [  341]   998   341     3034      329       5       2        0             0 systemd-timesyn
[  185.169243] [  541]     0   541      478      205       4       2        0             0 starter
[  185.178328] [  555]     0   555     2185     1335       8       2        0             0 ofonod
[  185.187311] [  582]   999   582     1081      447       5       2        0          -900 dbus-daemon
[  185.196719] [  670]   997   670     2717      370       5       2        0             0 systemd-network
[  185.208138] [  678]     0   678      494      257       4       2        0             0 syslogd
[  185.217491] [  680]     0   680      650      223       4       2        0             0 systemd-logind
[  185.227574] [  681]     0   681      490      252       3       2        0             0 klogd
[  185.236366] [  694]   994   694     1198      478       6       2        0             0 avahi-daemon
[  185.245752] [  718]   994   718     1165      274       5       2        0             0 avahi-daemon
[  185.255292] [  723]     0   723    35759      803      24       2        0             0 charon
[  185.264190] [  845]   996   845      746      278       4       2        0             0 systemd-resolve
[  185.274213] [  847]     0   847      446      158       4       2        0             0 agetty
[  185.283384] [  848]     0   848     1216      267       6       2        0             0 login
[  185.292157] [  861]     0   861     1316      560       5       2        0             0 lighttpd
[  185.301443] [  916]     0   916      667      266       4       2        0             0 runWeston
[  185.310565] [  927]     0   927      593      243       4       2        0             0 telnetd
[  185.320355] [  928]     0   928     2439      155       4       2        0             0 lad_dra7xx
[  185.330950] [  929]     0   929    25323     7072      32       2        0             0 weston
[  185.339849] [  935] 65534   935      613      298       4       2        0             0 thttpd
[  185.349577] [  940]     0   940      573      150       4       2        0             0 rngd
[  185.359996] [ 1056]     0  1056     1749      750       7       2        0             0 weston-keyboard
[  185.371552] [ 1057]     0  1057     6003     2881      13       2        0             0 weston-desktop-
[  185.381152] [ 1103]     0  1103     1339      793       6       2        0             0 systemd
[  185.390012] [ 1108]     0  1108     1838      494       6       2        0             0 (sd-pam)
[  185.398955] [ 1114]     0  1114      701      308       5       2        0             0 sh
[  185.407381] [ 1120]     0  1120     1217      268       6       2        0             0 login
[  185.416058] [ 1124]     0  1124      694      425       5       2        0             0 sh
[  185.424611] [ 1127]     0  1127    39084     4516      41       2        0             0 gst-launch-1.0
[  185.434082] Out of memory: Kill process 929 (weston) score 17 or sacrifice child
[  185.441548] Killed process 1057 (weston-desktop-) total-vm:24012kB, anon-rss:724kB, file-rss:10800kB
WARNING: from element /GstPlayBin:playbin0/GstPlaySink:playsink/GstBin:vbin/GstWaylandSink:waylandsink0: A lot of buffers are being dropped.
Additional debug info:
../../../../gstreamer-1.6.3/libs/gst/base/gstbasesink.c(2846): gst_base_sink_is_too_late (): /GstPlayBin:playbin0/GstPlaySink:playsink/GstBin:vbin/GstWaylandSink:waylandsink0:
There may be a timestamping problem, or this computer is too slow.
[  185.900521] weston invoked oom-killer: gfp_mask=0x24000c4, order=0, oom_score_adj=0
[  185.908327] weston cpuset=/ mems_allowed=0
[  185.912861] CPU: 0 PID: 929 Comm: weston Tainted: G           O    4.4.84-g742f84423d #1
[  185.920981] Hardware name: Generic DRA74X (Flattened Device Tree)
[  185.927095] Backtrace:
[  185.929564] [<c00131d4>] (dump_backtrace) from [<c00133d0>] (show_stack+0x18/0x1c)
[  185.937160]  r7:ed158a78 r6:600e0013 r5:00000000 r4:c0959810
[  185.942871] [<c00133b8>] (show_stack) from [<c02b21a8>] (dump_stack+0x8c/0xa0)
[  185.950125] [<c02b211c>] (dump_stack) from [<c011e2f8>] (dump_header+0x5c/0x1ac)
[  185.957548]  r7:ed158a78 r6:00000000 r5:ed209b34 r4:ed158600
[  185.963256] [<c011e29c>] (dump_header) from [<c00d3b28>] (oom_kill_process+0x2fc/0x448)
[  185.971288]  r10:c093f8b8 r9:00001aa5 r8:00000011 r7:ed158a78 r6:0005df5c r5:ed209b34
[  185.979177]  r4:ed158600
[  185.981724] [<c00d382c>] (oom_kill_process) from [<c00d3fd4>] (out_of_memory+0x2f0/0x32c)
[  185.989931]  r10:c093f8b8 r9:00001aa5 r8:c093f8b8 r7:c093fb38 r6:0005df5c r5:ed209b34
[  185.997824]  r4:ed158600
[  186.000373] [<c00d3ce4>] (out_of_memory) from [<c00d8e9c>] (__alloc_pages_nodemask+0x924/0x964)
[  186.009103]  r10:c0988500 r9:024000c4 r8:00000000 r7:c093c66c r6:ed208000 r5:00000000
[  186.016993]  r4:00000000
[  186.019589] [<c00d8578>] (__alloc_pages_nodemask) from [<bf2079fc>] (NewAllocPagesLinuxMemArea+0xcc/0x278 [pvrsrvkm])
[  186.030238]  r10:00004000 r9:00000000 r8:00000000 r7:bf2365ec r6:00000e34 r5:0000038d
[  186.038128]  r4:eda98e34
[  186.040740] [<bf207930>] (NewAllocPagesLinuxMemArea [pvrsrvkm]) from [<bf202a18>] (OSAllocPages_Impl+0xe4/0xfc [pvrsrvkm])
[  186.051825]  r10:00000000 r9:ebee05c0 r8:003fc000 r7:82014200 r6:ed209ca0 r5:80000203
[  186.059714]  r4:82014200
[  186.062326] [<bf202934>] (OSAllocPages_Impl [pvrsrvkm]) from [<bf20a92c>] (BM_ImportMemory+0x284/0x580 [pvrsrvkm])
[  186.072713]  r5:80000203 r4:ed108180
[  186.076381] [<bf20a6a8>] (BM_ImportMemory [pvrsrvkm]) from [<bf20afd4>] (BM_Alloc+0x3ac/0x50c [pvrsrvkm])
[  186.085983]  r10:00000040 r9:eba87400 r8:003fc000 r7:c78ad788 r6:eba9e600 r5:ed108180
[  186.093872]  r4:80000203
[  186.096487] [<bf20ac28>] (BM_Alloc [pvrsrvkm]) from [<bf20b350>] (AllocDeviceMem+0xb4/0x194 [pvrsrvkm])
[  186.105915]  r10:bf235b98 r9:ed108180 r8:00000000 r7:80000003 r6:ed209d4c r5:c78ad780
[  186.113803]  r4:00000000
[  186.116418] [<bf20b29c>] (AllocDeviceMem [pvrsrvkm]) from [<bf20bcf8>] (_PVRSRVAllocDeviceMemKM+0xb8/0x224 [pvrsrvkm])
[  186.127154]  r9:ed27ccc0 r8:f3d13000 r7:ebee0240 r6:ebd25400 r5:ed108180 r4:80000003
[  186.135030] [<bf20bc40>] (_PVRSRVAllocDeviceMemKM [pvrsrvkm]) from [<bf21765c>] (PVRSRVAllocDeviceMemBW+0x194/0x40c [pvrsrvkm])
[  186.146550]  r7:ebee0240 r6:00000000 r5:f3d14000 r4:00000000
[  186.152323] [<bf2174c8>] (PVRSRVAllocDeviceMemBW [pvrsrvkm]) from [<bf21a1dc>] (BridgedDispatchKM+0x94/0x25c [pvrsrvkm])
[  186.163233]  r8:f3d14000 r7:f3d13000 r6:bf2174c8 r5:ebee0240 r4:ed209e68
[  186.170058] [<bf21a148>] (BridgedDispatchKM [pvrsrvkm]) from [<bf206d74>] (PVRSRV_BridgeDispatchKM+0x180/0x338 [pvrsrvkm])
[  186.181143]  r8:00000040 r7:ed27ccc0 r6:000003a1 r5:c01c6707 r4:ed209e68
[  186.187937] [<bf206bf4>] (PVRSRV_BridgeDispatchKM [pvrsrvkm]) from [<c038fd88>] (drm_ioctl+0x140/0x454)
[  186.197364]  r7:ed24f800 r6:c09c3810 r5:0000001c r4:ed209e68
[  186.203071] [<c038fc48>] (drm_ioctl) from [<c0131ae4>] (do_vfs_ioctl+0x3f0/0x614)
[  186.210580]  r10:00000000 r9:ed208000 r8:befcf0bc r7:0000000e r6:ed27c600 r5:ebe88958
[  186.218469]  r4:befcf0bc
[  186.221015] [<c01316f4>] (do_vfs_ioctl) from [<c0131d44>] (SyS_ioctl+0x3c/0x64)
[  186.228349]  r10:00000000 r9:ed208000 r8:befcf0bc r7:401c6440 r6:ed27c600 r5:0000000e
[  186.236238]  r4:ed27c600
[  186.238786] [<c0131d08>] (SyS_ioctl) from [<c000fae0>] (ret_fast_syscall+0x0/0x34)
[  186.246382]  r9:ed208000 r8:c000fc84 r7:00000036 r6:401c6440 r5:befcf0bc r4:0000001c
[  186.257448] Mem-Info:
[  186.260320] active_anon:8678 inactive_anon:137746 isolated_anon:0
[  186.260320]  active_file:3182 inactive_file:7976 isolated_file:0
[  186.260320]  unevictable:0 dirty:0 writeback:34 unstable:0
[  186.260320]  slab_reclaimable:1523 slab_unreclaimable:3625
[  186.260320]  mapped:9543 shmem:138178 pagetables:244 bounce:0
[  186.260320]  free:185595 free_pcp:543 free_cma:42191
[  186.295418] DMA free:94992kB min:3080kB low:3848kB high:4620kB active_anon:14504kB inactive_anon:535972kB active_file:120kB inactive_file:204kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:782336kB managed:757104kB mlocked:0kB dirty:0kB writeback:136kB mapped:9320kB shmem:536460kB slab_reclaimable:6092kB slab_unreclaimable:14500kB kernel_stack:1232kB pagetables:364kB unstable:0kB bounce:0kB free_pcp:1372kB local_pcp:676kB free_cma:91068kB writeback_tmp:0kB pages_scanned:4 all_unreclaimable? no
[  186.342074] lowmem_reserve[]: 0 0 636 636
[  186.346776] HighMem free:647944kB min:512kB low:1356kB high:2200kB active_anon:20208kB inactive_anon:14332kB active_file:12608kB inactive_file:31768kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:782336kB managed:782336kB mlocked:0kB dirty:0kB writeback:0kB mapped:28852kB shmem:15552kB slab_reclaimable:0kB slab_unreclaimable:0kB kernel_stack:0kB pagetables:612kB unstable:0kB bounce:0kB free_pcp:732kB local_pcp:176kB free_cma:77696kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[  186.392862] lowmem_reserve[]: 0 0 0 0
[  186.397092] DMA: 363*4kB (MEC) 99*8kB (MEC) 41*16kB (UMEC) 12*32kB (UMEC) 2*64kB (U) 1*128kB (C) 1*256kB (C) 4*512kB (UC) 3*1024kB (C) 0*2048kB 21*4096kB (C) = 94932kB
[  186.413642] HighMem: 25*4kB (UMC) 9*8kB (UMC) 18*16kB (UC) 14*32kB (UC) 144*64kB (UMC) 92*128kB (UMC) 35*256kB (UMC) 15*512kB (UMC) 7*1024kB (UM) 4*2048kB (UMC) 145*4096kB (MC) = 647820kB
[  186.431195] 149361 total pagecache pages
[  186.435133] 0 pages in swap cache
[  186.438483] Swap cache stats: add 0, delete 0, find 0/0
[  186.443888] Free swap  = 0kB
[  186.446803] Total swap = 0kB
[  186.449692] 391168 pages RAM
[  186.452776] 195584 pages HighMem/MovableOnly
[  186.457789] 6308 pages reserved
[  186.462425] 73728 pages cma reserved
[  186.466148] [ pid ]   uid  tgid total_vm      rss nr_ptes nr_pmds swapents oom_score_adj name
[  186.476862] [  156]     0   156     2656      794       7       2        0             0 systemd-journal
[  186.488476] [  195]     0   195     2681      495       6       2        0         -1000 systemd-udevd
[  186.501660] [  341]   998   341     3034      329       5       2        0             0 systemd-timesyn
[  186.512292] [  541]     0   541      478      205       4       2        0             0 starter
[  186.521744] [  555]     0   555     2185     1335       8       2        0             0 ofonod
[  186.530518] [  582]   999   582     1081      447       5       2        0          -900 dbus-daemon
[  186.539750] [  670]   997   670     2717      370       5       2        0             0 systemd-network
[  186.549406] [  678]     0   678      494      261       4       2        0             0 syslogd
[  186.558332] [  680]     0   680      650      223       4       2        0             0 systemd-logind
[  186.574403] [  681]     0   681      490      269       3       2        0             0 klogd
[  186.583152] [  694]   994   694     1198      478       6       2        0             0 avahi-daemon
[  186.592544] [  718]   994   718     1165      274       5       2        0             0 avahi-daemon
[  186.601911] [  723]     0   723    35759      803      24       2        0             0 charon
[  186.610761] [  845]   996   845      746      278       4       2        0             0 systemd-resolve
[  186.620387] [  847]     0   847      446      158       4       2        0             0 agetty
[  186.629223] [  848]     0   848     1216      267       6       2        0             0 login
[  186.637969] [  861]     0   861     1316      560       5       2        0             0 lighttpd
[  186.647010] [  916]     0   916      667      266       4       2        0             0 runWeston
[  186.656092] [  927]     0   927      593      247       4       2        0             0 telnetd
[  186.664913] [  928]     0   928     2439      155       4       2        0             0 lad_dra7xx
[  186.674257] [  929]     0   929    23294     7003      26       2        0             0 weston
[  186.683013] [  935] 65534   935      613      298       4       2        0             0 thttpd
[  186.691758] [  940]     0   940      573      150       4       2        0             0 rngd
[  186.700327] [ 1056]     0  1056     1749      750       7       2        0             0 weston-keyboard
[  186.709874] [ 1103]     0  1103     1339      793       6       2        0             0 systemd
[  186.718705] [ 1108]     0  1108     1838      494       6       2        0             0 (sd-pam)
[  186.727628] [ 1114]     0  1114      701      308       5       2        0             0 sh
[  186.736021] [ 1120]     0  1120     1217      268       6       2        0             0 login
[  186.744756] [ 1124]     0  1124      694      425       5       2        0             0 sh
[  186.753196] [ 1127]     0  1127    39084     4563      41       2        0             0 gst-launch-1.0
[  186.762686] [ 1311]     0  1311     6003     3276      12       2        0             0 weston-desktop-
[  186.772219] Out of memory: Kill process 929 (weston) score 17 or sacrifice child
[  186.779683] Killed process 1311 (weston-desktop-) total-vm:24012kB, anon-rss:724kB, file-rss:12380kB
[  186.795506] systemd-journal invoked oom-killer: gfp_mask=0x24000d0, order=0, oom_score_adj=0
[  186.804877] systemd-journal cpuset=/ mems_allowed=0
[  186.810073] CPU: 0 PID: 156 Comm: systemd-journal Tainted: G           O    4.4.84-g742f84423d #1
[  186.818979] Hardware name: Generic DRA74X (Flattened Device Tree)
[  186.825093] Backtrace:
[  186.827562] [<c00131d4>] (dump_backtrace) from [<c00133d0>] (show_stack+0x18/0x1c)
[  186.835159]  r7:ed158a78 r6:600d0013 r5:00000000 r4:c0959810
[  186.840870] [<c00133b8>] (show_stack) from [<c02b21a8>] (dump_stack+0x8c/0xa0)
[  186.848125] [<c02b211c>] (dump_stack) from [<c011e2f8>] (dump_header+0x5c/0x1ac)
[  186.855547]  r7:ed158a78 r6:00000000 r5:ed161ec4 r4:ed158600
[  186.861256] [<c011e29c>] (dump_header) from [<c00d3b28>] (oom_kill_process+0x2fc/0x448)
[  186.869290]  r10:c093f8b8 r9:00001aa5 r8:00000011 r7:ed158a78 r6:0005df5c r5:ed161ec4
[  186.877182]  r4:ed158600
[  186.879730] [<c00d382c>] (oom_kill_process) from [<c00d3fd4>] (out_of_memory+0x2f0/0x32c)
[  186.887936]  r10:c093f8b8 r9:00001aa5 r8:c093f8b8 r7:c093fb38 r6:0005df5c r5:ed161ec4
[  186.895828]  r4:ed158600
[  186.898376] [<c00d3ce4>] (out_of_memory) from [<c00d8e9c>] (__alloc_pages_nodemask+0x924/0x964)
[  186.907106]  r10:c0988500 r9:024000d0 r8:00000000 r7:c0988510 r6:ed160000 r5:00000000
[  186.914995]  r4:00000000
[  186.917543] [<c00d8578>] (__alloc_pages_nodemask) from [<c00d8ef4>] (__get_free_pages+0x18/0x34)
[  186.926360]  r10:00000063 r9:e9795ca0 r8:ed161f6c r7:00000063 r6:00474f50 r5:e9795ca0
[  186.934249]  r4:00000000
[  186.936797] [<c00d8edc>] (__get_free_pages) from [<c0174e5c>] (proc_pid_readlink+0x64/0x110)
[  186.945270] [<c0174df8>] (proc_pid_readlink) from [<c012574c>] (SyS_readlinkat+0x9c/0xe4)
[  186.953476]  r7:bea4cec8 r6:ffffff9c r5:00004000 r4:ffffffea
[  186.959182] [<c01256b0>] (SyS_readlinkat) from [<c000fae0>] (ret_fast_syscall+0x0/0x34)
[  186.967215]  r10:00000000 r9:ed160000 r8:c000fc84 r7:0000014c r6:00000063 r5:00474f50
[  186.975103]  r4:00000064
[  186.978373] Mem-Info:
[  186.980753] active_anon:8508 inactive_anon:138188 isolated_anon:0
[  186.980753]  active_file:3334 inactive_file:7778 isolated_file:0
[  186.980753]  unevictable:0 dirty:0 writeback:9 unstable:0
[  186.980753]  slab_reclaimable:1523 slab_unreclaimable:3625
[  186.980753]  mapped:9268 shmem:138628 pagetables:244 bounce:0
[  186.980753]  free:185829 free_pcp:60 free_cma:42191
[  187.014732] DMA free:94132kB min:3080kB low:3848kB high:4620kB active_anon:14232kB inactive_anon:538420kB active_file:116kB inactive_file:88kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:782336kB managed:757104kB mlocked:0kB dirty:0kB writeback:36kB mapped:9320kB shmem:538960kB slab_reclaimable:6092kB slab_unreclaimable:14500kB kernel_stack:1232kB pagetables:364kB unstable:0kB bounce:0kB free_pcp:252kB local_pcp:132kB free_cma:91068kB writeback_tmp:0kB pages_scanned:1224 all_unreclaimable? yes
[  187.060468] lowmem_reserve[]: 0 0 636 636
[  187.064567] HighMem free:649184kB min:512kB low:1356kB high:2200kB active_anon:19800kB inactive_anon:14332kB active_file:13288kB inactive_file:31020kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:782336kB managed:782336kB mlocked:0kB dirty:0kB writeback:0kB mapped:27752kB shmem:15552kB slab_reclaimable:0kB slab_unreclaimable:0kB kernel_stack:0kB pagetables:612kB unstable:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:77696kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[  187.109245] lowmem_reserve[]: 0 0 0 0
[  187.112975] DMA: 417*4kB (MEC) 102*8kB (UMEC) 34*16kB (MEC) 3*32kB (MC) 0*64kB 1*128kB (C) 1*256kB (C) 3*512kB (C) 3*1024kB (C) 0*2048kB 21*4096kB (C) = 94132kB
[  187.127664] HighMem: 73*4kB (UMC) 60*8kB (UMC) 21*16kB (UMC) 16*32kB (UMC) 151*64kB (UMC) 92*128kB (UMC) 36*256kB (UMC) 15*512kB (UMC) 7*1024kB (UM) 4*2048kB (UMC) 145*4096kB (MC) = 649236kB
[  187.145076] 149767 total pagecache pages
[  187.149077] 0 pages in swap cache
[  187.152422] Swap cache stats: add 0, delete 0, find 0/0
[  187.157743] Free swap  = 0kB
[  187.160651] Total swap = 0kB
[  187.163564] 391168 pages RAM
[  187.166539] 195584 pages HighMem/MovableOnly
[  187.170843] 6308 pages reserved
[  187.174016] 73728 pages cma reserved
[  187.177689] [ pid ]   uid  tgid total_vm      rss nr_ptes nr_pmds swapents oom_score_adj name
[  187.186377] [  156]     0   156     2656      795       7       2        0             0 systemd-journal
[  187.195967] [  195]     0   195     2681      495       6       2        0         -1000 systemd-udevd
[  187.205385] [  341]   998   341     3034      329       5       2        0             0 systemd-timesyn
[  187.214969] [  541]     0   541      478      205       4       2        0             0 starter
[  187.223899] [  555]     0   555     2185     1335       8       2        0             0 ofonod
[  187.232741] [  582]   999   582     1081      447       5       2        0          -900 dbus-daemon
[  187.242007] [  670]   997   670     2717      370       5       2        0             0 systemd-network
[  187.251622] [  678]     0   678      494      261       4       2        0             0 syslogd
[  187.261478] [  680]     0   680      650      223       4       2        0             0 systemd-logind
[  187.271002] [  681]     0   681      490      269       3       2        0             0 klogd
[  187.279760] [  694]   994   694     1198      478       6       2        0             0 avahi-daemon
[  187.289110] [  718]   994   718     1165      274       5       2        0             0 avahi-daemon
[  187.298473] [  723]     0   723    35759      803      24       2        0             0 charon
[  187.307284] [  845]   996   845      746      278       4       2        0             0 systemd-resolve
[  187.316865] [  847]     0   847      446      158       4       2        0             0 agetty
[  187.325719] [  848]     0   848     1216      267       6       2        0             0 login
[  187.334399] [  861]     0   861     1316      560       5       2        0             0 lighttpd
[  187.343411] [  916]     0   916      667      266       4       2        0             0 runWeston
[  187.352501] [  927]     0   927      593      247       4       2        0             0 telnetd
[  187.361404] [  928]     0   928     2439      155       4       2        0             0 lad_dra7xx
[  187.370635] [  929]     0   929    23294     7003      26       2        0             0 weston
[  187.379512] [  935] 65534   935      613      298       4       2        0             0 thttpd
[  187.388325] [  940]     0   940      573      150       4       2        0             0 rngd
[  187.397046] [ 1056]     0  1056     1749      750       7       2        0             0 weston-keyboard
[  187.406639] [ 1103]     0  1103     1339      793       6       2        0             0 systemd
[  187.415609] [ 1108]     0  1108     1838      494       6       2        0             0 (sd-pam)
[  187.424590] [ 1114]     0  1114      701      308       5       2        0             0 sh
[  187.433123] [ 1120]     0  1120     1217      268       6       2        0             0 login
[  187.442411] [ 1124]     0  1124      694      425       5       2        0             0 sh
[  187.451009] [ 1127]     0  1127    39084     4563      41       2        0             0 gst-launch-1.0
[  187.460636] Out of memory: Kill process 929 (weston) score 17 or sacrifice child
[  187.468321] Killed process 1056 (weston-keyboard) total-vm:6996kB, anon-rss:712kB, file-rss:2288kB
[  187.485519] weston invoked oom-killer: gfp_mask=0x24000c4, order=0, oom_score_adj=0
[  187.497989] weston cpuset=/ mems_allowed=0
[  187.502230] CPU: 1 PID: 929 Comm: weston Tainted: G           O    4.4.84-g742f84423d #1
[  187.510352] Hardware name: Generic DRA74X (Flattened Device Tree)
[  187.516467] Backtrace:
[  187.518936] [<c00131d4>] (dump_backtrace) from [<c00133d0>] (show_stack+0x18/0x1c)
[  187.526532]  r7:ed158a78 r6:600d0013 r5:00000000 r4:c0959810
[  187.532243] [<c00133b8>] (show_stack) from [<c02b21a8>] (dump_stack+0x8c/0xa0)
[  187.539498] [<c02b211c>] (dump_stack) from [<c011e2f8>] (dump_header+0x5c/0x1ac)
[  187.546919]  r7:ed158a78 r6:00000000 r5:ed209b34 r4:ed158600
[  187.552625] [<c011e29c>] (dump_header) from [<c00d3b28>] (oom_kill_process+0x2fc/0x448)
[  187.560658]  r10:c093f8b8 r9:00001aa5 r8:00000011 r7:ed158a78 r6:0005df5c r5:ed209b34
[  187.568546]  r4:ed158600
[  187.571092] [<c00d382c>] (oom_kill_process) from [<c00d3fd4>] (out_of_memory+0x2f0/0x32c)
[  187.579299]  r10:c093f8b8 r9:00001aa5 r8:c093f8b8 r7:c093fb38 r6:0005df5c r5:ed209b34
[  187.587188]  r4:ed158600
[  187.589737] [<c00d3ce4>] (out_of_memory) from [<c00d8e9c>] (__alloc_pages_nodemask+0x924/0x964)
[  187.598466]  r10:c0988500 r9:024000c4 r8:00000000 r7:c093c66c r6:ed208000 r5:00000000
[  187.606356]  r4:00000000
[  187.608952] [<c00d8578>] (__alloc_pages_nodemask) from [<bf2079fc>] (NewAllocPagesLinuxMemArea+0xcc/0x278 [pvrsrvkm])
[  187.619602]  r10:00004000 r9:00000000 r8:00000000 r7:bf2365ec r6:00000e38 r5:0000038e
[  187.627490]  r4:eda98e38
[  187.630104] [<bf207930>] (NewAllocPagesLinuxMemArea [pvrsrvkm]) from [<bf202a18>] (OSAllocPages_Impl+0xe4/0xfc [pvrsrvkm])
[  187.641189]  r10:00000000 r9:ebee05c0 r8:003fc000 r7:82014200 r6:ed209ca0 r5:80000203
[  187.649080]  r4:82014200
[  187.651692] [<bf202934>] (OSAllocPages_Impl [pvrsrvkm]) from [<bf20a92c>] (BM_ImportMemory+0x284/0x580 [pvrsrvkm])
[  187.662079]  r5:80000203 r4:ed108180
[  187.665746] [<bf20a6a8>] (BM_ImportMemory [pvrsrvkm]) from [<bf20afd4>] (BM_Alloc+0x3ac/0x50c [pvrsrvkm])
[  187.675349]  r10:00000040 r9:eba87400 r8:003fc000 r7:c78ad788 r6:eba9e600 r5:ed108180
[  187.683239]  r4:80000203
[  187.685853] [<bf20ac28>] (BM_Alloc [pvrsrvkm]) from [<bf20b350>] (AllocDeviceMem+0xb4/0x194 [pvrsrvkm])
[  187.695281]  r10:bf235b98 r9:ed108180 r8:00000000 r7:80000003 r6:ed209d4c r5:c78ad780
[  187.703169]  r4:00000000
[  187.705785] [<bf20b29c>] (AllocDeviceMem [pvrsrvkm]) from [<bf20bcf8>] (_PVRSRVAllocDeviceMemKM+0xb8/0x224 [pvrsrvkm])
[  187.716521]  r9:ed27ccc0 r8:f3d13000 r7:ebee0240 r6:ebd25400 r5:ed108180 r4:80000003
[  187.724396] [<bf20bc40>] (_PVRSRVAllocDeviceMemKM [pvrsrvkm]) from [<bf21765c>] (PVRSRVAllocDeviceMemBW+0x194/0x40c [pvrsrvkm])
[  187.735915]  r7:ebee0240 r6:00000000 r5:f3d14000 r4:00000000
[  187.741690] [<bf2174c8>] (PVRSRVAllocDeviceMemBW [pvrsrvkm]) from [<bf21a1dc>] (BridgedDispatchKM+0x94/0x25c [pvrsrvkm])
[  187.752601]  r8:f3d14000 r7:f3d13000 r6:bf2174c8 r5:ebee0240 r4:ed209e68
[  187.759430] [<bf21a148>] (BridgedDispatchKM [pvrsrvkm]) from [<bf206d74>] (PVRSRV_BridgeDispatchKM+0x180/0x338 [pvrsrvkm])
[  187.770514]  r8:00000040 r7:ed27ccc0 r6:000003a1 r5:c01c6707 r4:ed209e68
[  187.777309] [<bf206bf4>] (PVRSRV_BridgeDispatchKM [pvrsrvkm]) from [<c038fd88>] (drm_ioctl+0x140/0x454)
[  187.786736]  r7:ed24f800 r6:c09c3810 r5:0000001c r4:ed209e68
[  187.792443] [<c038fc48>] (drm_ioctl) from [<c0131ae4>] (do_vfs_ioctl+0x3f0/0x614)
[  187.799952]  r10:00000000 r9:ed208000 r8:befcf0bc r7:0000000e r6:ed27c600 r5:ebe88958
[  187.807842]  r4:befcf0bc
[  187.810389] [<c01316f4>] (do_vfs_ioctl) from [<c0131d44>] (SyS_ioctl+0x3c/0x64)
[  187.817724]  r10:00000000 r9:ed208000 r8:befcf0bc r7:401c6440 r6:ed27c600 r5:0000000e
[  187.825613]  r4:ed27c600
[  187.828161] [<c0131d08>] (SyS_ioctl) from [<c000fae0>] (ret_fast_syscall+0x0/0x34)
[  187.835757]  r9:ed208000 r8:c000fc84 r7:00000036 r6:401c6440 r5:befcf0bc r4:0000001c
[  187.851645] Mem-Info:
[  187.853941] active_anon:8328 inactive_anon:138215 isolated_anon:0
[  187.853941]  active_file:3388 inactive_file:7758 isolated_file:0
[  187.853941]  unevictable:0 dirty:0 writeback:7 unstable:0
[  187.853941]  slab_reclaimable:1523 slab_unreclaimable:3620
[  187.853941]  mapped:9103 shmem:138656 pagetables:233 bounce:0
[  187.853941]  free:185724 free_pcp:361 free_cma:42191
[  187.888188] DMA free:93944kB min:3080kB low:3848kB high:4620kB active_anon:13992kB inactive_anon:538528kB active_file:176kB inactive_file:76kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:782336kB managed:757104kB mlocked:0kB dirty:0kB writeback:28kB mapped:9032kB shmem:539072kB slab_reclaimable:6092kB slab_unreclaimable:14480kB kernel_stack:1232kB pagetables:348kB unstable:0kB bounce:0kB free_pcp:664kB local_pcp:368kB free_cma:91068kB writeback_tmp:0kB pages_scanned:1744 all_unreclaimable? yes
[  187.934306] lowmem_reserve[]: 0 0 636 636
[  187.938692] HighMem free:648952kB min:512kB low:1356kB high:2200kB active_anon:19320kB inactive_anon:14332kB active_file:13376kB inactive_file:30952kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:782336kB managed:782336kB mlocked:0kB dirty:0kB writeback:0kB mapped:27380kB shmem:15552kB slab_reclaimable:0kB slab_unreclaimable:0kB kernel_stack:0kB pagetables:584kB unstable:0kB bounce:0kB free_pcp:780kB local_pcp:588kB free_cma:77696kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[  187.983697] lowmem_reserve[]: 0 0 0 0
[  187.987424] DMA: 348*4kB (UMEC) 105*8kB (UMEC) 38*16kB (MEC) 4*32kB (MC) 0*64kB 1*128kB (C) 1*256kB (C) 3*512kB (C) 3*1024kB (C) 0*2048kB 21*4096kB (C) = 93976kB
[  188.002178] HighMem: 41*4kB (UMC) 16*8kB (UC) 24*16kB (UMC) 20*32kB (UMC) 152*64kB (UMC) 92*128kB (UMC) 36*256kB (UMC) 15*512kB (UMC) 7*1024kB (UM) 4*2048kB (UMC) 145*4096kB (MC) = 648996kB
[  188.019363] 149742 total pagecache pages
[  188.023311] 0 pages in swap cache
[  188.026674] Swap cache stats: add 0, delete 0, find 0/0
[  188.031922] Free swap  = 0kB
[  188.034816] Total swap = 0kB
[  188.037774] 391168 pages RAM
[  188.040669] 195584 pages HighMem/MovableOnly
[  188.044960] 6308 pages reserved
[  188.048152] 73728 pages cma reserved
[  188.051739] [ pid ]   uid  tgid total_vm      rss nr_ptes nr_pmds swapents oom_score_adj name
[  188.060352] [  156]     0   156     2656      807       7       2        0             0 systemd-journal
[  188.069926] [  195]     0   195     2681      495       6       2        0         -1000 systemd-udevd
[  188.079315] [  341]   998   341     3034      329       5       2        0             0 systemd-timesyn
[  188.088862] [  541]     0   541      478      205       4       2        0             0 starter
[  188.097729] [  555]     0   555     2185     1335       8       2        0             0 ofonod
[  188.106487] [  582]   999   582     1081      447       5       2        0          -900 dbus-daemon
[  188.115681] [  670]   997   670     2717      370       5       2        0             0 systemd-network
[  188.125210] [  678]     0   678      494      261       4       2        0             0 syslogd
[  188.134056] [  680]     0   680      650      223       4       2        0             0 systemd-logind
[  188.143528] [  681]     0   681      490      269       3       2        0             0 klogd
[  188.152201] [  694]   994   694     1198      478       6       2        0             0 avahi-daemon
[  188.161483] [  718]   994   718     1165      274       5       2        0             0 avahi-daemon
[  188.170766] [  723]     0   723    35759      803      24       2        0             0 charon
[  188.179525] [  845]   996   845      746      278       4       2        0             0 systemd-resolve
[  188.189074] [  847]     0   847      446      158       4       2        0             0 agetty
[  188.197824] [  848]     0   848     1216      267       6       2        0             0 login
[  188.206494] [  861]     0   861     1316      560       5       2        0             0 lighttpd
[  188.215427] [  916]     0   916      667      266       4       2        0             0 runWeston
[  188.224433] [  927]     0   927      593      247       4       2        0             0 telnetd
[  188.233277] [  928]     0   928     2439      155       4       2        0             0 lad_dra7xx
[  188.242377] [  929]     0   929    23294     7003      26       2        0             0 weston
[  188.251134] [  935] 65534   935      613      300       4       2        0             0 thttpd
[  188.260885] [  940]     0   940      573      150       4       2        0             0 rngd
[  188.269482] [ 1103]     0  1103     1339      793       6       2        0             0 systemd
[  188.278331] [ 1108]     0  1108     1838      494       6       2        0             0 (sd-pam)
[  188.287267] [ 1114]     0  1114      701      308       5       2        0             0 sh
[  188.295685] [ 1120]     0  1120     1217      268       6       2        0             0 login
[  188.304351] [ 1124]     0  1124      694      425       5       2        0             0 sh
[  188.312774] [ 1127]     0  1127    39084     4574      41       2        0             0 gst-launch-1.0
[  188.322241] Out of memory: Kill process 929 (weston) score 17 or sacrifice child
[  188.329702] Killed process 929 (weston) total-vm:93176kB, anon-rss:10120kB, file-rss:17892kB
WARNING: from element /GstPlayBin:playbin0/GstPlaySink:playsink/GstBin:vbin/GstWaylandSink:waylandsink0: A lot of buffers are being dropped.
Additional debug info:
../../../../gstreamer-1.6.3/libs/gst/base/gstbasesink.c(2846): gst_base_sink_is_too_late (): /GstPlayBin:playbin0/GstPlaySink:playsink/GstBin:vbin/GstWaylandSink:waylandsink0:
There may be a timestamping problem, or this computer is too slow.

Hi Ikshwaku,

I have attached the log on my observation.

I kept on checking cat /proc/meminfo | grep LowFree

it came down significantly.

I am not sure what we can fix in this issue. Looks like some element is not freeing memory.

Ram

0 Ikshwaku Chauhan over 7 years ago in reply to Ramprasad

Expert 2630 points

Hi ram,

When we try to play with fakesink and filesink issue is not observed.
Could you please suggest how to debug this issue and where we can see about the memory consumption.

Thanks,
Ikshwaku

0 Ikshwaku Chauhan over 7 years ago in reply to Ikshwaku Chauhan

Expert 2630 points

Hello Ram,

Could you please share the explicit pipeline for playing (DIVX/MP4 + extrenal subtitle)video file.

Also share the recommended way of playing with playbin.

Regards,
Ikshwaku

0 Ramprasad over 7 years ago in reply to Ikshwaku Chauhan

TI__Mastermind 30480 points

Hi Ikshwaku,
I tried with below explicit pipeline. I see OOM issue here too

gst-launch-1.0 filesrc location=/home/root/02.avi ! avidemux ! mpeg4videoparse ! ducatimpeg4decvpe ! textoverlay name=txt ! waylandsink \
filesrc location=/home/root/02.SRT ! subparse ! txt.

Check the behaviour with explicit pipeline on your system.

With playbin are you seeing video freeze?
Is this always consistently at a particular duration of video?

0 Ikshwaku Chauhan over 7 years ago in reply to Ramprasad

Expert 2630 points

Hello Ram,

when I am trying to play with explicit pipeline getting following error:
0:00:00.129463675 6798 0x1a1ce0 ERROR GST_PIPELINE grammar.y:691:priv_gst_parse_yyparse: no element "txt"
0:00:00.129586014 6798 0x1a1ce0 ERROR GST_PIPELINE grammar.y:776:priv_gst_parse_yyparse: link has no sink [source=@0x1a5118]
WARNING: erroneous pipeline: no element "txt"

With Playbin:
Yes video freeze always after 6-7 sec.

Regards,
Ikshwaku

0 Ramprasad over 7 years ago in reply to Ikshwaku Chauhan

TI__Mastermind 30480 points

There is a . after txt here
filesrc location=/home/root/02.SRT ! subparse ! txt.

0 Ramprasad over 7 years ago in reply to Ramprasad

TI__Mastermind 30480 points

Hi Ikshwaku,
On TI-EVM, playback is fine till 1:49 (There is a timer in the 02.avi ).
02.SRT shows 7th second first subtitle starts. I guess you are seeing issue when first sub-title starts .

0 Ikshwaku Chauhan over 7 years ago in reply to Ramprasad

Expert 2630 points

Hello Ram,

with explicit pipeline it plays for 28/44 sec and then hanged.

Also not able to see the subtitles.

Are you able to play any DIVX + external subtitle at you end, if yes please share the video and subtitle files

Regards,
Ikshwaku

0 Ramprasad over 7 years ago in reply to Ikshwaku Chauhan

TI__Mastermind 30480 points

Your 02.avi and 02.SRT is showing subtitles at the bottom of the video with explicit pipeline . I used below command

gst-launch-1.0 filesrc location=/home/root/02.avi ! avidemux ! mpeg4videoparse ! ducatimpeg4decvpe ! textoverlay name=txt ! waylandsink window-resolution=1920x1080 \
filesrc location=/home/root/02.SRT ! subparse ! txt.

but I see this message, "There may be a timestamping problem, or this computer is too slow." from 0.20s. then it OOM at 0.55s.

Are you able to start a different video after this video freeze?

0 Ikshwaku Chauhan over 7 years ago in reply to Ramprasad

Expert 2630 points

yes I am able to start different/same video after the video freeze.

0 Ramprasad over 7 years ago in reply to Ikshwaku Chauhan

TI__Mastermind 30480 points

Try with software decoder once
gst-launch-1.0 filesrc location=/home/root/02.avi ! avidemux ! mpeg4videoparse ! ducatimpeg4decvpe ! textoverlay name=txt ! waylandsink window-resolution=1920x1080 use-drm=true \
filesrc location=/home/root/02.SRT ! subparse ! txt.

I still see OOM. It is textoverlay plugin is causing OOM

0 Ramprasad over 7 years ago in reply to Ramprasad

TI__Mastermind 30480 points

Ikshwaku,

I compared the gstreamer playbin flow with our pipeline with PSDKLA3.04. There is not much difference in the elements but video parser and subtitle parser in your case are from Ittiam.

Not sure if this can make a difference.

0 Ikshwaku Chauhan over 7 years ago in reply to Ramprasad

Expert 2630 points

Hello Ram,

I have tried following scenarios with two videos that i have already shared with you:

1. without ittiam plugins used explicit pipeline you shared

gst-launch-1.0 filesrc location=/home/root/03.AVI ! avidemux ! mpeg4videoparse ! ducatimpeg4decvpe ! textoverlay name=txt ! waylandsink filesrc location=/home/root/03.SUB ! subparse ! txt.

Getting video freeze, some time OOM in dmesg logs and Lowfree memory is dropping significantly always.

2. with ittiam plugins used explicit pipeline:

gst-launch-1.0 filesrc location=/home/root/03.AVI ! ia_avidemux ! mpeg4videoparse ! ducatimpeg4decvpe ! textoverlay name=txt ! waylandsink filesrc location=/home/root/03.SUB ! ia_subparse ! txt.

Getting video freeze, some time OOM in dmesg logs and Lowfree memory is dropping significantly always.

Have you done any further analysis for this issue or If you have any suggestions how to debug this issue please share.

One more qurey:
Can we play subtitle files without video by using videotestsrc and autovideosink. Found one pipeline, but it is not working

gst-launch-1.0 -v filesrc location=subtitles.srt ! subparse ! txt. videotestsrc ! timeoverlay ! textoverlay name=txt shaded-background=yes ! autovideosink

Regards,
Ikshwaku

0 Ramprasad over 7 years ago in reply to Ikshwaku Chauhan

TI__Mastermind 30480 points

HI Ikshwaku,

Good that you are able to reproduce the OOM issue. This is caused by textoverlay.

You can set silent property of textoverlay. silent=true means no overlay and silent=false by overlay. When textoverlay is used for overlaying a text, then only OOM is happening.

gst-launch-1.0 filesrc location=/home/root/02.avi ! avidemux ! mpeg4videoparse ! ducatimpeg4decvpe ! textoverlay name=txt silent=true ! waylandsink window-resolution=1920x1080 \

filesrc location=/home/root/02.SRT ! subparse ! txt.

One more thing I observed is OOM issue won't happen if VPE is used in the pipeline.

gst-launch-1.0 filesrc location=/home/root/02.avi ! avidemux ! mpeg4videoparse ! ducatimpeg4decvpe ! 'video/x-raw, format=(string)NV12, width=(int)1280, height=(int)720' ! textoverlay name=txt ! waylandsink window-resolution=1920x1080 \
filesrc location=/home/root/02.SRT ! subparse ! txt.

Check the behaviour with above pipeline. I have added a capsfilter here after vpe.

By default vpe will be bypassed for progressive streams. In above pipeline I am using vpe for scaling to 1280x720. Here OOM issue doesn't happen.

When vpe is not used, decoder output goes directly to textoverlay and it modifies the decoder output to add text overlay while same time same buffers/frames may get used by decoder as reference frames.

Ram

0 Ikshwaku Chauhan over 7 years ago in reply to Ramprasad

Expert 2630 points

Hello Ram,

Tried four cases:

1. gst-launch-1.0 filesrc location=/tmp/USB1-1.4-sda1/02_DivX6\(AVI\)_with_SRT_subtitle\(German\).AVI ! avidemux ! mpeg4videoparse ! ducatimpeg4decvpe ! textoverlay name=txt silent=true ! waylandsink filesrc location=/tmp/USB1-1.4-sda1/02_DivX6\(AVI\)_with_SRT_subtitle\(German\).SRT ! subparse ! txt.

Getting video freeze after first "A lot of buffers are being dropped" warning , no drop in LowFree memeory, no oom msg in dmesg

2. gst-launch-1.0 filesrc location=/tmp/USB1-1.4-sda1/02_DivX6\(AVI\)_with_SRT_subtitle\(German\).AVI ! ia_avidemux ! mpeg4videoparse ! ducatimpeg4decvpe ! textoverlay name=txt silent=true ! waylandsink filesrc location=/tmp/USB1-1.4-sda1/02_DivX6\(AVI\)_with_SRT_subtitle\(German\).SRT ! ia_subparse ! txt.

Video played without any error or warning.

3. gst-launch-1.0 filesrc location=/tmp/USB1-1.4-sda1/02_DivX6\(AVI\)_with_SRT_subtitle\(German\).AVI ! avidemux ! mpeg4videoparse ! ducatimpeg4decvpe ! 'video/x-raw, format=(string)NV12' ! textoverlay name=txt ! waylandsink filesrc location=/tmp/USB1-1.4-sda1/02_DivX6\(AVI\)_with_SRT_subtitle\(German\).SRT ! subparse ! txt.

Getting video freeze, some time OOM in dmesg logs and Lowfree memory is dropping significantly always and A lot of buffers are being dropped" warning .

4. gst-launch-1.0 filesrc location=/tmp/USB1-1.4-sda1/02_DivX6\(AVI\)_with_SRT_subtitle\(German\).AVI ! ia_avidemux ! mpeg4videoparse ! ducatimpeg4decvpe ! 'video/x-raw, format=(string)NV12' ! textoverlay name=txt ! waylandsink filesrc location=/tmp/USB1-1.4-sda1/02_DivX6\(AVI\)_with_SRT_subtitle\(German\).SRT ! ia_subparse ! txt.

Getting video freeze, some time OOM in dmesg logs and Lowfree memory is dropping significantly always and A lot of buffers are being dropped" warning .

Regards,

Ikshwaku

0 vikash patil over 7 years ago in reply to Ikshwaku Chauhan

Expert 1285 points

Hi Ram,

Could you please help here the further debug and fix this issue?

Regards,

Vikash

Processors

Processors forum

Linux/DRA746: Waylansink:Video playback hangs when playing with subtitle files.