search

RPi-Distro / vlc

GNU General Public License v2.0
41 stars 4 forks source link

Looping VLC Freezes after any amount of time #45

Open nixternal opened 3 years ago

nixternal commented 3 years ago

VLC command line: DISPLAY=:0 cvlc --vout gles2 -fq --no-osd --one-instance BigBuckBunny_2000hevc.mp4 -L

File used to reproduce this: https://s3.amazonaws.com/x265.org/video/BigBuckBunny_2000hevc.mp4

Latest VLC & System Updates as of June 3, 2021. Run VLC from python script & systemd. I can still get VLC to crash when not firing it from a script & systemd & just from the command line. The kern.log file is similar to the log in firmware bug report https://github.com/raspberrypi/firmware/issues/1384.

NOTE: The freeze could happen at any time, from a couple of hours to a couple of days. It isn't consistent time wise, but it freezes consistently.

System info:

Hardware        : BCM2711
Revision        : c03112
Model           : Raspberry Pi 4 Model B Rev 1.2
Kernel          : 5.10.17-v7l+
BOOTLOADER: up to date
   CURRENT: Thu 29 Apr 2021 04:11:25 PM UTC (1619712685)
    LATEST: Thu 29 Apr 2021 04:11:25 PM UTC (1619712685)
   RELEASE: default (/lib/firmware/raspberrypi/bootloader/default)
            Use raspi-config to change the release.

  VL805_FW: Dedicated VL805 EEPROM
     VL805: up to date
   CURRENT: 000138a1
    LATEST: 000138a1

/boot/cmdline.txt: console=serial0,115200 console=tty1 root=PARTUUID=738a4d67-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait logo.nologo consoleblank=0 loglevel=0 quiet splash cma=512M

/boot/config.txt:

# For more options and information see
# http://rpf.io/configtxt
# Some settings may impact device functionality. See link above for details

# Disable Pi Rainbow Color Test
disable_splash=1

# uncomment if you get no picture on HDMI for a default "safe" mode
#hdmi_safe=1

# uncomment this if your display has a black border of unused pixels visible
# and your display can output without overscan
disable_overscan=1

# uncomment the following to adjust overscan. Use positive numbers if console
# goes off screen, and negative if there is too much border
#overscan_left=16
#overscan_right=16
#overscan_top=16
#overscan_bottom=16

# uncomment to force a console size. By default it will be display's size minus
# overscan.
#framebuffer_width=1280
#framebuffer_height=720

# uncomment if hdmi display is not detected and composite is being output
#hdmi_force_hotplug=1

# uncomment to force a specific HDMI mode (this will force VGA)
#hdmi_group=1
#hdmi_mode=1

# uncomment to force a HDMI mode rather than DVI. This can make audio work in
# DMT (computer monitor) modes
#hdmi_drive=2

# uncomment to increase signal to HDMI, if you have interference, blanking, or
# no display
#config_hdmi_boost=4

# uncomment for composite PAL
#sdtv_mode=2

#uncomment to overclock the arm. 700 MHz is the default.
#arm_freq=800

# Uncomment some or all of these to enable the optional hardware interfaces
#dtparam=i2c_arm=on
#dtparam=i2s=on
#dtparam=spi=on

# Uncomment this to enable infrared communication.
#dtoverlay=gpio-ir,gpio_pin=17
#dtoverlay=gpio-ir-tx,gpio_pin=18

# Additional overlays and parameters are documented /boot/overlays/README

# Enable audio (loads snd_bcm2835)
dtparam=audio=on

[pi4]
# Enable DRM VC4 V3D driver on top of the dispmanx display stack
dtoverlay=vc4-fkms-v3d
max_framebuffers=2

[all]
dtoverlay=vc4-fkms-v3d
hdmi_enable_4kp60=1
gpu_mem=128

When it freezes, this is the message in /var/log/syslog (every time it freezes this is the message):

Jun 3 16:21:02 foobar kernel: [20567.498302] vc_sm_cma_vchi_rx_ack: received response 0, throw away...

And when I do systemctl --user stop myvlcscript.conf this is what is logged in kern.log as well as a messy stdout:

Jun  3 23:01:35 foobar kernel: [44641.539952] [vc_sm_cma_ioctl_alloc]: something failed - cleanup. ret -512
Jun  3 23:01:35 foobar kernel: [44641.540011] ------------[ cut here ]------------
Jun  3 23:01:35 foobar kernel: [44641.540046] WARNING: CPU: 1 PID: 14783 at mm/memory.c:2519 __apply_to_page_range+0x56c/0x570
Jun  3 23:01:35 foobar kernel: [44641.540061] Modules linked in: tun cmac bnep hci_uart btbcm bluetooth ecdh_generic ecc 8021q garp stp llc brcmfmac brcmutil sha256_generic cfg80211 bcm2835_v4l2(C) v3d gpu_sched rfkill bcm2835_codec(C) raspberrypi_hwmon bcm2835_isp(C) v4l2_mem2mem videobuf2_vmalloc vc4 bcm2835_mmal_vchiq(C) snd_bcm2835(C) videobuf2_dma_contig cec videobuf2_memops videobuf2_v4l2 videobuf2_common drm_kms_helper videodev mc drm vc_sm_cma(C) drm_panel_orientation_quirks snd_soc_core snd_compress snd_pcm_dmaengine snd_pcm rpivid_mem snd_timer nvmem_rmem snd syscopyarea sysfillrect sysimgblt fb_sys_fops backlight uio_pdrv_genirq uio uinput ip_tables x_tables ipv6
Jun  3 23:01:35 foobar kernel: [44641.540935] CPU: 1 PID: 14783 Comm: vlc Tainted: G         C        5.10.17-v7l+ #1421
Jun  3 23:01:35 foobar kernel: [44641.540945] Hardware name: BCM2711
Jun  3 23:01:35 foobar kernel: [44641.540957] Backtrace: 
Jun  3 23:01:35 foobar kernel: [44641.540992] [<c0b63050>] (dump_backtrace) from [<c0b633e4>] (show_stack+0x20/0x24)
Jun  3 23:01:35 foobar kernel: [44641.541007]  r7:ffffffff r6:00000000 r5:60000013 r4:c12e69fc
Jun  3 23:01:35 foobar kernel: [44641.541026] [<c0b633c4>] (show_stack) from [<c0b67774>] (dump_stack+0xcc/0xf8)
Jun  3 23:01:35 foobar kernel: [44641.541045] [<c0b676a8>] (dump_stack) from [<c0220bac>] (__warn+0xfc/0x114)
Jun  3 23:01:35 foobar kernel: [44641.541060]  r10:00000001 r9:00000009 r8:c03f06ec r7:000009d7 r6:00000009 r5:c03f06ec
Jun  3 23:01:35 foobar kernel: [44641.541071]  r4:c0e3a78c r3:c1205094
Jun  3 23:01:35 foobar kernel: [44641.541089] [<c0220ab0>] (__warn) from [<c0b63b54>] (warn_slowpath_fmt+0x70/0xd8)
Jun  3 23:01:35 foobar kernel: [44641.541102]  r7:000009d7 r6:c0e3a78c r5:c1205048 r4:00000000
Jun  3 23:01:35 foobar kernel: [44641.541123] [<c0b63ae8>] (warn_slowpath_fmt) from [<c03f06ec>] (__apply_to_page_range+0x56c/0x570)
Jun  3 23:01:35 foobar kernel: [44641.541136]  r9:d0000000 r8:d0000000 r7:d0000000 r6:00000000 r5:cbfc0000 r4:ffffffea
Jun  3 23:01:35 foobar kernel: [44641.541156] [<c03f0180>] (__apply_to_page_range) from [<c03f0718>] (apply_to_page_range+0x28/0x30)
Jun  3 23:01:35 foobar kernel: [44641.541169]  r10:00000001 r9:d0000000 r8:d0000000 r7:c224bc10 r6:00000000 r5:cbfc0000
Jun  3 23:01:35 foobar kernel: [44641.541179]  r4:c1205048
Jun  3 23:01:35 foobar kernel: [44641.541197] [<c03f06f0>] (apply_to_page_range) from [<c0215f80>] (cma_allocator_free+0xd4/0xe8)
Jun  3 23:01:35 foobar kernel: [44641.541214] [<c0215eac>] (cma_allocator_free) from [<c0216a38>] (__arm_dma_free.constprop.3+0x190/0x218)
Jun  3 23:01:35 foobar kernel: [44641.541227]  r9:00000000 r8:d0000000 r7:00000000 r6:c2d2e5c0 r5:d0000000 r4:c1205048
Jun  3 23:01:35 foobar kernel: [44641.541243] [<c02168a8>] (__arm_dma_free.constprop.3) from [<c0216aec>] (arm_dma_free+0x2c/0x34)
Jun  3 23:01:35 foobar kernel: [44641.541257]  r10:00000000 r9:00000001 r8:00080060 r7:d0000000 r6:c224bc10 r5:00000000
Jun  3 23:01:35 foobar kernel: [44641.541266]  r4:d0000000
Jun  3 23:01:35 foobar kernel: [44641.541282] [<c0216ac0>] (arm_dma_free) from [<c0217084>] (arch_dma_free+0x2c/0x34)
Jun  3 23:01:35 foobar kernel: [44641.541292]  r5:00000000 r4:d0000000
Jun  3 23:01:35 foobar kernel: [44641.541311] [<c0217058>] (arch_dma_free) from [<c02a2938>] (dma_direct_free+0x12c/0x174)
Jun  3 23:01:35 foobar kernel: [44641.541322]  r5:d0000000 r4:d0000000
Jun  3 23:01:35 foobar kernel: [44641.541340] [<c02a280c>] (dma_direct_free) from [<c02a1158>] (dma_free_attrs+0x80/0xd0)
Jun  3 23:01:35 foobar kernel: [44641.541354]  r10:c4b737e8 r9:00000001 r8:00080060 r7:00000000 r6:00000000 r5:d0000000
Jun  3 23:01:35 foobar kernel: [44641.541363]  r4:c224bc10
Jun  3 23:01:35 foobar kernel: [44641.541402] [<c02a10d8>] (dma_free_attrs) from [<bf0cad04>] (vc_sm_release_resource+0xe4/0x104 [vc_sm_cma])
Jun  3 23:01:35 foobar kernel: [44641.541414]  r7:00000000 r6:d0000000 r5:bf0cf2c0 r4:c3676980
Jun  3 23:01:35 foobar kernel: [44641.541450] [<bf0cac20>] (vc_sm_release_resource [vc_sm_cma]) from [<bf0cadc0>] (vc_sm_dma_buf_release+0x54/0xa8 [vc_sm_cma])
Jun  3 23:01:35 foobar kernel: [44641.541463]  r7:00080040 r6:c3d261e8 r5:c3676990 r4:c3676980
Jun  3 23:01:35 foobar kernel: [44641.541492] [<bf0cad6c>] (vc_sm_dma_buf_release [vc_sm_cma]) from [<c0871990>] (dma_buf_release+0x54/0x90)
Jun  3 23:01:35 foobar kernel: [44641.541502]  r5:00000000 r4:c2dc9b40
Jun  3 23:01:35 foobar kernel: [44641.541522] [<c087193c>] (dma_buf_release) from [<c045651c>] (__dentry_kill+0x140/0x1d0)
Jun  3 23:01:35 foobar kernel: [44641.541533]  r5:00000000 r4:c3d26198
Jun  3 23:01:35 foobar kernel: [44641.541549] [<c04563dc>] (__dentry_kill) from [<c04575a0>] (dput.part.8+0x218/0x3d0)
Jun  3 23:01:35 foobar kernel: [44641.541562]  r7:00080040 r6:c0e3deac r5:c3d261e8 r4:c3d26198
Jun  3 23:01:35 foobar kernel: [44641.541576] [<c0457388>] (dput.part.8) from [<c0457770>] (dput+0x18/0x1c)
Jun  3 23:01:35 foobar kernel: [44641.541590]  r10:ffffe000 r9:00000000 r8:c3d26198 r7:c18cd790 r6:00080007 r5:c4b737e8
Jun  3 23:01:35 foobar kernel: [44641.541601]  r4:c4692900
Jun  3 23:01:35 foobar kernel: [44641.541615] [<c0457758>] (dput) from [<c043cda0>] (__fput+0xd8/0x25c)
Jun  3 23:01:35 foobar kernel: [44641.541630] [<c043ccc8>] (__fput) from [<c043cf3c>] (____fput+0x18/0x1c)
Jun  3 23:01:35 foobar kernel: [44641.541643]  r9:fffffe30 r8:c1354d6c r7:c2848000 r6:c2848554 r5:00000000 r4:c4692900
Jun  3 23:01:35 foobar kernel: [44641.541662] [<c043cf24>] (____fput) from [<c02429e8>] (task_work_run+0xb4/0xe4)
Jun  3 23:01:35 foobar kernel: [44641.541683] [<c0242934>] (task_work_run) from [<c0233dd0>] (get_signal+0x654/0xae0)
Jun  3 23:01:35 foobar kernel: [44641.541696]  r9:fffffe30 r8:5ac3c35a r7:c4d09a40 r6:b6ddf518 r5:c4692900 r4:ffffe000
Jun  3 23:01:35 foobar kernel: [44641.541714] [<c023377c>] (get_signal) from [<c020b87c>] (do_work_pending+0x30c/0x5a8)
Jun  3 23:01:35 foobar kernel: [44641.541727]  r10:c1205048 r9:fffffe30 r8:5ac3c35a r7:c426ff44 r6:b6ddf518 r5:c426ffb0
Jun  3 23:01:35 foobar kernel: [44641.541737]  r4:ffffe000
Jun  3 23:01:35 foobar kernel: [44641.541751] [<c020b570>] (do_work_pending) from [<c0200074>] (slow_work_pending+0xc/0x20)
Jun  3 23:01:35 foobar kernel: [44641.541762] Exception stack(0xc426ffb0 to 0xc426fff8)
Jun  3 23:01:35 foobar kernel: [44641.541774] ffa0:                                     00000007 80404a5a aa4adaa8 20636f6c
Jun  3 23:01:35 foobar kernel: [44641.541788] ffc0: b127870c 00878000 00000007 00000036 aa4adaa8 00000000 00000001 0000000b
Jun  3 23:01:35 foobar kernel: [44641.541801] ffe0: b02a9154 aa4ada8c b0295224 b6ddf518 a0000010 00000007
Jun  3 23:01:35 foobar kernel: [44641.541815]  r10:00000036 r9:c426e000 r8:c0200204 r7:00000036 r6:00000007 r5:00878000
Jun  3 23:01:35 foobar kernel: [44641.541825]  r4:b127870c
Jun  3 23:01:35 foobar kernel: [44641.541838] ---[ end trace f0ab2836e5589be2 ]---
Jun  3 23:01:35 foobar kernel: [44641.541874] 8<--- cut here ---
Jun  3 23:01:35 foobar kernel: [44641.541901] Unable to handle kernel NULL pointer dereference at virtual address 00000004
Jun  3 23:01:35 foobar kernel: [44641.541925] pgd = 35b09424
Jun  3 23:01:35 foobar kernel: [44641.541947] [00000004] *pgd=05387003, *pmd=e7d74003
Jun  3 23:01:35 foobar kernel: [44641.541998] Internal error: Oops: a07 [#1] SMP ARM
Jun  3 23:01:35 foobar kernel: [44641.542012] Modules linked in: tun cmac bnep hci_uart btbcm bluetooth ecdh_generic ecc 8021q garp stp llc brcmfmac brcmutil sha256_generic cfg80211 bcm2835_v4l2(C) v3d gpu_sched rfkill bcm2835_codec(C) raspberrypi_hwmon bcm2835_isp(C) v4l2_mem2mem videobuf2_vmalloc vc4 bcm2835_mmal_vchiq(C) snd_bcm2835(C) videobuf2_dma_contig cec videobuf2_memops videobuf2_v4l2 videobuf2_common drm_kms_helper videodev mc drm vc_sm_cma(C) drm_panel_orientation_quirks snd_soc_core snd_compress snd_pcm_dmaengine snd_pcm rpivid_mem snd_timer nvmem_rmem snd syscopyarea sysfillrect sysimgblt fb_sys_fops backlight uio_pdrv_genirq uio uinput ip_tables x_tables ipv6
Jun  3 23:01:35 foobar kernel: [44641.542488] CPU: 1 PID: 14783 Comm: vlc Tainted: G        WC        5.10.17-v7l+ #1421
Jun  3 23:01:35 foobar kernel: [44641.542502] Hardware name: BCM2711
Jun  3 23:01:35 foobar kernel: [44641.542529] PC is at vc_sm_release_resource+0x78/0x104 [vc_sm_cma]
Jun  3 23:01:35 foobar kernel: [44641.542547] LR is at _cond_resched+0x34/0x58
Jun  3 23:01:35 foobar kernel: [44641.542562] pc : [<bf0cac98>]    lr : [<c0b6db44>]    psr: 60000013
Jun  3 23:01:35 foobar kernel: [44641.542575] sp : c426fda0  ip : 00000100  fp : c426fdcc
Jun  3 23:01:35 foobar kernel: [44641.542588] r10: c4b737e8  r9 : 00000001  r8 : 00080060
Jun  3 23:01:35 foobar kernel: [44641.542602] r7 : 00000000  r6 : d0000000  r5 : bf0cf2c0  r4 : c3676980
Jun  3 23:01:35 foobar kernel: [44641.542616] r3 : 00000000  r2 : 00000000  r1 : 00000122  r0 : c2828240
Jun  3 23:01:35 foobar kernel: [44641.542632] Flags: nZCv  IRQs on  FIQs on  Mode SVC_32  ISA ARM  Segment user
Jun  3 23:01:35 foobar kernel: [44641.542647] Control: 30c5383d  Table: 04538ec0  DAC: 55555555
Jun  3 23:01:35 foobar kernel: [44641.542663] Process vlc (pid: 14783, stack limit = 0xfd245e33)
Jun  3 23:01:35 foobar kernel: [44641.542677] Stack: (0xc426fda0 to 0xc4270000)
Jun  3 23:01:35 foobar kernel: [44641.542695] fda0: d0000000 00000000 00000000 3f71ea38 c3676980 c3676990 c3d261e8 00080040
Jun  3 23:01:35 foobar kernel: [44641.542712] fdc0: c426fde4 c426fdd0 bf0cadc0 bf0cac2c c2dc9b40 00000000 c426fdfc c426fde8
Jun  3 23:01:35 foobar kernel: [44641.542729] fde0: c0871990 bf0cad78 c3d26198 00000000 c426fe1c c426fe00 c045651c c0871948
Jun  3 23:01:35 foobar kernel: [44641.542747] fe00: c3d26198 c3d261e8 c0e3deac 00080040 c426fe54 c426fe20 c04575a0 c04563e8
Jun  3 23:01:35 foobar kernel: [44641.542764] fe20: c13fe950 c4b73858 c426fe4c c4692900 c4b737e8 00080007 c18cd790 c3d26198
Jun  3 23:01:35 foobar kernel: [44641.542780] fe40: 00000000 ffffe000 c426fe64 c426fe58 c0457770 c0457394 c426fe9c c426fe68
Jun  3 23:01:35 foobar kernel: [44641.542797] fe60: c043cda0 c0457764 00000000 c4b737e8 00000000 ffffe000 c4692900 00000000
Jun  3 23:01:35 foobar kernel: [44641.542813] fe80: c2848554 c2848000 c1354d6c fffffe30 c426feac c426fea0 c043cf3c c043ccd4
Jun  3 23:01:35 foobar kernel: [44641.542830] fea0: c426fed4 c426feb0 c02429e8 c043cf30 ffffe000 c4692900 b6ddf518 c4d09a40
Jun  3 23:01:35 foobar kernel: [44641.542847] fec0: 5ac3c35a fffffe30 c426ff2c c426fed8 c0233dd0 c0242940 c137a760 c1093c10
Jun  3 23:01:35 foobar kernel: [44641.542863] fee0: 418004fc c0200204 c426ff58 5ac3c35a fffffe30 c337fc00 400004d8 c426ff44
Jun  3 23:01:35 foobar kernel: [44641.542881] ff00: c032e008 ffffe000 c426ffb0 b6ddf518 c426ff44 5ac3c35a fffffe30 c1205048
Jun  3 23:01:35 foobar kernel: [44641.542898] ff20: c426ffac c426ff30 c020b87c c0233788 c020cb6c b6ddf51c fffffe00 c120504c
Jun  3 23:01:35 foobar kernel: [44641.542914] ff40: 00000000 c1093c10 c0200e70 ffffffff 00000000 30c5387d c0201024 00000001
Jun  3 23:01:35 foobar kernel: [44641.542931] ff60: c426ff94 c426ff70 c032e008 c032f234 b6edb100 3f71ea38 ffffffff 3f71ea38
Jun  3 23:01:35 foobar kernel: [44641.542948] ff80: 00878000 b127870c 00878000 00000007 00000036 c0200204 c426e000 00000036
Jun  3 23:01:35 foobar kernel: [44641.542964] ffa0: 00000000 c426ffb0 c0200074 c020b57c 00000007 80404a5a aa4adaa8 20636f6c
Jun  3 23:01:35 foobar kernel: [44641.542981] ffc0: b127870c 00878000 00000007 00000036 aa4adaa8 00000000 00000001 0000000b
Jun  3 23:01:35 foobar kernel: [44641.542998] ffe0: b02a9154 aa4ada8c b0295224 b6ddf518 a0000010 00000007 00000000 00000000
Jun  3 23:01:35 foobar kernel: [44641.543011] Backtrace: 
Jun  3 23:01:35 foobar kernel: [44641.543059] [<bf0cac20>] (vc_sm_release_resource [vc_sm_cma]) from [<bf0cadc0>] (vc_sm_dma_buf_release+0x54/0xa8 [vc_sm_cma])
Jun  3 23:01:35 foobar kernel: [44641.543076]  r7:00080040 r6:c3d261e8 r5:c3676990 r4:c3676980
Jun  3 23:01:35 foobar kernel: [44641.543104] [<bf0cad6c>] (vc_sm_dma_buf_release [vc_sm_cma]) from [<c0871990>] (dma_buf_release+0x54/0x90)
Jun  3 23:01:35 foobar kernel: [44641.543118]  r5:00000000 r4:c2dc9b40
Jun  3 23:01:35 foobar kernel: [44641.543139] [<c087193c>] (dma_buf_release) from [<c045651c>] (__dentry_kill+0x140/0x1d0)
Jun  3 23:01:35 foobar kernel: [44641.543154]  r5:00000000 r4:c3d26198
Jun  3 23:01:35 foobar kernel: [44641.543173] [<c04563dc>] (__dentry_kill) from [<c04575a0>] (dput.part.8+0x218/0x3d0)
Jun  3 23:01:35 foobar kernel: [44641.543188]  r7:00080040 r6:c0e3deac r5:c3d261e8 r4:c3d26198
Jun  3 23:01:35 foobar kernel: [44641.543206] [<c0457388>] (dput.part.8) from [<c0457770>] (dput+0x18/0x1c)
Jun  3 23:01:35 foobar kernel: [44641.543223]  r10:ffffe000 r9:00000000 r8:c3d26198 r7:c18cd790 r6:00080007 r5:c4b737e8
Jun  3 23:01:35 foobar kernel: [44641.543236]  r4:c4692900
Jun  3 23:01:35 foobar kernel: [44641.543254] [<c0457758>] (dput) from [<c043cda0>] (__fput+0xd8/0x25c)
Jun  3 23:01:35 foobar kernel: [44641.543272] [<c043ccc8>] (__fput) from [<c043cf3c>] (____fput+0x18/0x1c)
Jun  3 23:01:35 foobar kernel: [44641.543289]  r9:fffffe30 r8:c1354d6c r7:c2848000 r6:c2848554 r5:00000000 r4:c4692900
Jun  3 23:01:35 foobar kernel: [44641.543310] [<c043cf24>] (____fput) from [<c02429e8>] (task_work_run+0xb4/0xe4)
Jun  3 23:01:35 foobar kernel: [44641.543332] [<c0242934>] (task_work_run) from [<c0233dd0>] (get_signal+0x654/0xae0)
Jun  3 23:01:35 foobar kernel: [44641.543349]  r9:fffffe30 r8:5ac3c35a r7:c4d09a40 r6:b6ddf518 r5:c4692900 r4:ffffe000
Jun  3 23:01:35 foobar kernel: [44641.543369] [<c023377c>] (get_signal) from [<c020b87c>] (do_work_pending+0x30c/0x5a8)
Jun  3 23:01:35 foobar kernel: [44641.543386]  r10:c1205048 r9:fffffe30 r8:5ac3c35a r7:c426ff44 r6:b6ddf518 r5:c426ffb0
Jun  3 23:01:35 foobar kernel: [44641.543399]  r4:ffffe000
Jun  3 23:01:35 foobar kernel: [44641.543416] [<c020b570>] (do_work_pending) from [<c0200074>] (slow_work_pending+0xc/0x20)
Jun  3 23:01:35 foobar kernel: [44641.543430] Exception stack(0xc426ffb0 to 0xc426fff8)
Jun  3 23:01:35 foobar kernel: [44641.543445] ffa0:                                     00000007 80404a5a aa4adaa8 20636f6c
Jun  3 23:01:35 foobar kernel: [44641.543462] ffc0: b127870c 00878000 00000007 00000036 aa4adaa8 00000000 00000001 0000000b
Jun  3 23:01:35 foobar kernel: [44641.543478] ffe0: b02a9154 aa4ada8c b0295224 b6ddf518 a0000010 00000007
Jun  3 23:01:35 foobar kernel: [44641.543495]  r10:00000036 r9:c426e000 r8:c0200204 r7:00000036 r6:00000007 r5:00878000
Jun  3 23:01:35 foobar kernel: [44641.543508]  r4:b127870c
Jun  3 23:01:35 foobar kernel: [44641.543526] Code: e1c420d0 e5950000 e3a0cc01 e3001122 (e5823004) 
Jun  3 23:01:35 foobar kernel: [44641.543547] ---[ end trace f0ab2836e5589be3 ]---
jc-kynesim commented 3 years ago

Thanks for that. I'm looking at VLC currently - I'll look into it. I'd like to blame the issue on a kernel driver problem (and I'm not ruling that out) but HEVC decode does have the ability to break things too.

nixternal commented 3 years ago

Thanks @jc-kynesim! I just found it interesting that the dump looks similar to that Chrome one in the bug report for firmware. To be honest, I don't know if this has been happening all of the time, but I swear pre-February 26, 2021 it wasn't bombing like this, however I can't be positive. I do have a machine that is from that time frame & it ran for a week and didn't crash.

EDIT: It did crash, just after 7 to 10 days, not within hours or a day or 2 max.

KenT2 commented 3 years ago

Similar reported here https://www.raspberrypi.org/forums/viewtopic.php?f=66&t=312054

dividuum commented 3 years ago

I'd like to add another observation: My software info-beamer currently still uses a heavily modified ffmpeg branch originally based on this patch. So while this is probably not immediately helpful with regards to VLC, I also observe the occasional and seemingly random playback lockup that I've not yet been able to reliably reproduce. Maybe these issues are related and the problem isn't VLC?

dmesg output follows. Similar to the original post, note the difference between the very first log line and all other lines. The first line is what caused my program to get stuck. The following lines get added once I forcefully terminate my program.

[ 4039.365072] vc_sm_cma_vchi_rx_ack: received response 1818324333, throw away...
[ 5023.137220] [vc_sm_cma_ioctl_alloc]: something failed - cleanup. ret -512
[ 5023.137338] ------------[ cut here ]------------
[ 5023.137361] WARNING: CPU: 3 PID: 6927 at mm/memory.c:2519 __apply_to_page_range+0x56c/0x570
[ 5023.137368] Modules linked in: rpivid_mem v3d gpu_sched vc4 cec drm_kms_helper drm fb_sys_fops sysfillrect sysimgblt syscopyarea drm_panel_orientation_quirks vc_sm_cma(C) enc28j60 pwm_bcm2835 rtc_ds1307 rtc_pcf8523 i2c_bcm2708 i2c_dev regmap_i2c ftdi_sio pl2303 cp210x usbserial rpi_poe_fan raspberrypi_hwmon dm_crypt dm_mod rpi_backlight backlight hid_multitouch goodix snd_soc_core snd_pcm_dmaengine snd_compress snd_bcm2
[ 5023.137846] CPU: 3 PID: 6927 Comm: video buffer Tainted: G         C        5.10.17-v7l+ #1403
[ 5023.137850] Hardware name: BCM2711
[ 5023.137854] Backtrace:
[ 5023.137868] [<c0b63050>] (dump_backtrace) from [<c0b633e4>] (show_stack+0x20/0x24)
[ 5023.137875]  r7:ffffffff r6:00000000 r5:60000113 r4:c12e69fc
[ 5023.137883] [<c0b633c4>] (show_stack) from [<c0b67774>] (dump_stack+0xcc/0xf8)
[ 5023.137892] [<c0b676a8>] (dump_stack) from [<c0220bac>] (__warn+0xfc/0x114)
[ 5023.137899]  r10:00000001 r9:00000009 r8:c03f06ec r7:000009d7 r6:00000009 r5:c03f06ec
[ 5023.137903]  r4:c0e3a78c r3:c1205094
[ 5023.137910] [<c0220ab0>] (__warn) from [<c0b63b54>] (warn_slowpath_fmt+0x70/0xd8)
[ 5023.137916]  r7:000009d7 r6:c0e3a78c r5:c1205048 r4:00000000
[ 5023.137924] [<c0b63ae8>] (warn_slowpath_fmt) from [<c03f06ec>] (__apply_to_page_range+0x56c/0x570)
[ 5023.137929]  r9:ddd00000 r8:ddd00000 r7:ddd00000 r6:00000000 r5:cd997000 r4:ffffffea
[ 5023.137937] [<c03f0180>] (__apply_to_page_range) from [<c03f0718>] (apply_to_page_range+0x28/0x30)
[ 5023.137943]  r10:00000001 r9:ddd00000 r8:ddd00000 r7:c22f3010 r6:00000000 r5:cd997000
[ 5023.137946]  r4:c1205048
[ 5023.137955] [<c03f06f0>] (apply_to_page_range) from [<c0215f80>] (cma_allocator_free+0xd4/0xe8)
[ 5023.137961] [<c0215eac>] (cma_allocator_free) from [<c0216a38>] (__arm_dma_free.constprop.3+0x190/0x218)
[ 5023.137967]  r9:00000000 r8:ddd00000 r7:00000000 r6:c3d10d40 r5:ddd00000 r4:c1205048
[ 5023.137973] [<c02168a8>] (__arm_dma_free.constprop.3) from [<c0216aec>] (arm_dma_free+0x2c/0x34)
[ 5023.137979]  r10:00000000 r9:00000001 r8:00080060 r7:ddd00000 r6:c22f3010 r5:00000000
[ 5023.137983]  r4:ddd00000
[ 5023.137989] [<c0216ac0>] (arm_dma_free) from [<c0217084>] (arch_dma_free+0x2c/0x34)
[ 5023.137993]  r5:00000000 r4:ddd00000
[ 5023.138003] [<c0217058>] (arch_dma_free) from [<c02a2938>] (dma_direct_free+0x12c/0x174)
[ 5023.138007]  r5:ddd00000 r4:ddd00000
[ 5023.138014] [<c02a280c>] (dma_direct_free) from [<c02a1158>] (dma_free_attrs+0x80/0xd0)
[ 5023.138020]  r10:c3b57cb0 r9:00000001 r8:00080060 r7:00000000 r6:00000000 r5:ddd00000
[ 5023.138024]  r4:c22f3010
[ 5023.138043] [<c02a10d8>] (dma_free_attrs) from [<bf502d04>] (vc_sm_release_resource+0xe4/0x104 [vc_sm_cma])
[ 5023.138048]  r7:00000000 r6:ddd00000 r5:bf5072c0 r4:c3751380
[ 5023.138062] [<bf502c20>] (vc_sm_release_resource [vc_sm_cma]) from [<bf502dc0>] (vc_sm_dma_buf_release+0x54/0xa8 [vc_sm_cma])
[ 5023.138067]  r7:00080040 r6:c3b87270 r5:c3751390 r4:c3751380
[ 5023.138078] [<bf502d6c>] (vc_sm_dma_buf_release [vc_sm_cma]) from [<c0871990>] (dma_buf_release+0x54/0x90)
[ 5023.138082]  r5:00000000 r4:c3ca0b40
[ 5023.138092] [<c087193c>] (dma_buf_release) from [<c045651c>] (__dentry_kill+0x140/0x1d0)
[ 5023.138096]  r5:00000000 r4:c3b87220
[ 5023.138103] [<c04563dc>] (__dentry_kill) from [<c04575a0>] (dput.part.8+0x218/0x3d0)
[ 5023.138108]  r7:00080040 r6:c0e3deac r5:c3b87270 r4:c3b87220
[ 5023.138113] [<c0457388>] (dput.part.8) from [<c0457770>] (dput+0x18/0x1c)
[ 5023.138119]  r10:ffffe000 r9:00000000 r8:c3b87220 r7:c18cd790 r6:00080007 r5:c3b57cb0
[ 5023.138122]  r4:c31a8e40
[ 5023.138129] [<c0457758>] (dput) from [<c043cda0>] (__fput+0xd8/0x25c)
[ 5023.138134] [<c043ccc8>] (__fput) from [<c043cf3c>] (____fput+0x18/0x1c)
[ 5023.138140]  r9:fffffe30 r8:c1354d6c r7:c3790f80 r6:c37914d4 r5:00000000 r4:c31a8e40
[ 5023.138150] [<c043cf24>] (____fput) from [<c02429e8>] (task_work_run+0xb4/0xe4)
[ 5023.138159] [<c0242934>] (task_work_run) from [<c0233dd0>] (get_signal+0x654/0xae0)
[ 5023.138165]  r9:fffffe30 r8:5ac3c35a r7:c2d24ec0 r6:b6541518 r5:c31a8e40 r4:ffffe000
[ 5023.138172] [<c023377c>] (get_signal) from [<c020b87c>] (do_work_pending+0x30c/0x5a8)
[ 5023.138178]  r10:c1205048 r9:fffffe30 r8:5ac3c35a r7:c3df5f44 r6:b6541518 r5:c3df5fb0
[ 5023.138182]  r4:ffffe000
[ 5023.138188] [<c020b570>] (do_work_pending) from [<c0200074>] (slow_work_pending+0xc/0x20)
[ 5023.138192] Exception stack(0xc3df5fb0 to 0xc3df5ff8)
[ 5023.138197] 5fa0:                                     00000014 80404a5a 9a1fea38 763e2d70
[ 5023.138203] 5fc0: b6b4e57c 04000000 00000014 00000036 9a1fea38 9a23d0d0 b6b4e224 00000013
[ 5023.138208] 5fe0: b66ba154 9a1fea1c b66a6224 b6541518 a0000010 00000014
[ 5023.138214]  r10:00000036 r9:c3df4000 r8:c0200204 r7:00000036 r6:00000014 r5:04000000
[ 5023.138218]  r4:b6b4e57c
[ 5023.138224] ---[ end trace 06c3a2d3ae4afb4c ]---
[ 5023.138408] 8<--- cut here ---
[ 5023.138434] Unable to handle kernel NULL pointer dereference at virtual address 00000004
[ 5023.138472] pgd = 9901dfd4
[ 5023.138490] [00000004] *pgd=03335003, *pmd=7ff2c003
[ 5023.138528] Internal error: Oops: a07 [#1] SMP ARM
[ 5023.138550] Modules linked in: rpivid_mem v3d gpu_sched vc4 cec drm_kms_helper drm fb_sys_fops sysfillrect sysimgblt syscopyarea drm_panel_orientation_quirks vc_sm_cma(C) enc28j60 pwm_bcm2835 rtc_ds1307 rtc_pcf8523 i2c_bcm2708 i2c_dev regmap_i2c ftdi_sio pl2303 cp210x usbserial rpi_poe_fan raspberrypi_hwmon dm_crypt dm_mod rpi_backlight backlight hid_multitouch goodix snd_soc_core snd_pcm_dmaengine snd_compress snd_bcm2
[ 5023.139081] CPU: 3 PID: 6927 Comm: video buffer Tainted: G        WC        5.10.17-v7l+ #1403
[ 5023.139116] Hardware name: BCM2711
[ 5023.139141] PC is at vc_sm_release_resource+0x78/0x104 [vc_sm_cma]
[ 5023.139174] LR is at _cond_resched+0x34/0x58
[ 5023.139193] pc : [<bf502c98>]    lr : [<c0b6db44>]    psr: 60000113
[ 5023.139220] sp : c3df5da0  ip : 00000100  fp : c3df5dcc
[ 5023.139243] r10: c3b57cb0  r9 : 00000001  r8 : 00080060
[ 5023.139266] r7 : 00000000  r6 : ddd00000  r5 : bf5072c0  r4 : c3751380
[ 5023.139293] r3 : 00000000  r2 : 00000000  r1 : 00000122  r0 : c2bff440
[ 5023.139322] Flags: nZCv  IRQs on  FIQs on  Mode SVC_32  ISA ARM  Segment user
[ 5023.139352] Control: 30c5383d  Table: 0239b300  DAC: 55555555
[ 5023.139379] Process video buffer (pid: 6927, stack limit = 0xaee60556)
[ 5023.139408] Stack: (0xc3df5da0 to 0xc3df6000)
[ 5023.139430] 5da0: ddd00000 00000000 00000000 72e49a58 c3751380 c3751390 c3b87270 00080040
[ 5023.139465] 5dc0: c3df5de4 c3df5dd0 bf502dc0 bf502c2c c3ca0b40 00000000 c3df5dfc c3df5de8
[ 5023.139499] 5de0: c0871990 bf502d78 c3b87220 00000000 c3df5e1c c3df5e00 c045651c c0871948
[ 5023.139532] 5e00: c3b87220 c3b87270 c0e3deac 00080040 c3df5e54 c3df5e20 c04575a0 c04563e8
[ 5023.139566] 5e20: c13fe950 c3b57d20 c3df5e4c c31a8e40 c3b57cb0 00080007 c18cd790 c3b87220
[ 5023.139600] 5e40: 00000000 ffffe000 c3df5e64 c3df5e58 c0457770 c0457394 c3df5e9c c3df5e68
[ 5023.139634] 5e60: c043cda0 c0457764 00000000 c3b57cb0 00000000 ffffe000 c31a8e40 00000000
[ 5023.139668] 5e80: c37914d4 c3790f80 c1354d6c fffffe30 c3df5eac c3df5ea0 c043cf3c c043ccd4
[ 5023.139703] 5ea0: c3df5ed4 c3df5eb0 c02429e8 c043cf30 ffffe000 c31a8e40 b6541518 c2d24ec0
[ 5023.139738] 5ec0: 5ac3c35a fffffe30 c3df5f2c c3df5ed8 c0233dd0 c0242940 c137a760 c1093c10
[ 5023.139772] 5ee0: 418004fc c0200204 c3df5f58 5ac3c35a fffffe30 c3469500 400004d8 c3df5f44
[ 5023.139806] 5f00: c032e008 ffffe000 c3df5fb0 b6541518 c3df5f44 5ac3c35a fffffe30 c1205048
[ 5023.139840] 5f20: c3df5fac c3df5f30 c020b87c c0233788 c04389ec b654151c fffffe00 c120504c
[ 5023.139874] 5f40: 00000000 00000000 00000000 00000000 00020000 00000000 00000000 00000000
[ 5023.139908] 5f60: 00000000 00000000 00000000 72e49a58 c3df4000 72e49a58 b6ffc968 72e49a58
[ 5023.139942] 5f80: 04000000 b6b4e57c 04000000 00000014 00000036 c0200204 c3df4000 00000036
[ 5023.139975] 5fa0: 00000000 c3df5fb0 c0200074 c020b57c 00000014 80404a5a 9a1fea38 763e2d70
[ 5023.140009] 5fc0: b6b4e57c 04000000 00000014 00000036 9a1fea38 9a23d0d0 b6b4e224 00000013
[ 5023.140043] 5fe0: b66ba154 9a1fea1c b66a6224 b6541518 a0000010 00000014 00000000 00000000
[ 5023.140075] Backtrace: 
[ 5023.140102] [<bf502c20>] (vc_sm_release_resource [vc_sm_cma]) from [<bf502dc0>] (vc_sm_dma_buf_release+0x54/0xa8 [vc_sm_cma])
[ 5023.140146]  r7:00080040 r6:c3b87270 r5:c3751390 r4:c3751380
[ 5023.140179] [<bf502d6c>] (vc_sm_dma_buf_release [vc_sm_cma]) from [<c0871990>] (dma_buf_release+0x54/0x90)
[ 5023.140217]  r5:00000000 r4:c3ca0b40 
[ 5023.140239] [<c087193c>] (dma_buf_release) from [<c045651c>] (__dentry_kill+0x140/0x1d0)
[ 5023.140271]  r5:00000000 r4:c3b87220
[ 5023.140291] [<c04563dc>] (__dentry_kill) from [<c04575a0>] (dput.part.8+0x218/0x3d0)
[ 5023.140323]  r7:00080040 r6:c0e3deac r5:c3b87270 r4:c3b87220
[ 5023.140348] [<c0457388>] (dput.part.8) from [<c0457770>] (dput+0x18/0x1c)
[ 5023.140377]  r10:ffffe000 r9:00000000 r8:c3b87220 r7:c18cd790 r6:00080007 r5:c3b57cb0
[ 5023.140409]  r4:c31a8e40 
[ 5023.140424] [<c0457758>] (dput) from [<c043cda0>] (__fput+0xd8/0x25c)
[ 5023.140456] [<c043ccc8>] (__fput) from [<c043cf3c>] (____fput+0x18/0x1c)
[ 5023.140485]  r9:fffffe30 r8:c1354d6c r7:c3790f80 r6:c37914d4 r5:00000000 r4:c31a8e40
[ 5023.140521] [<c043cf24>] (____fput) from [<c02429e8>] (task_work_run+0xb4/0xe4)
[ 5023.140555] [<c0242934>] (task_work_run) from [<c0233dd0>] (get_signal+0x654/0xae0)
[ 5023.140587]  r9:fffffe30 r8:5ac3c35a r7:c2d24ec0 r6:b6541518 r5:c31a8e40 r4:ffffe000
[ 5023.140621] [<c023377c>] (get_signal) from [<c020b87c>] (do_work_pending+0x30c/0x5a8)
[ 5023.140654]  r10:c1205048 r9:fffffe30 r8:5ac3c35a r7:c3df5f44 r6:b6541518 r5:c3df5fb0
[ 5023.140685]  r4:ffffe000 
[ 5023.140701] [<c020b570>] (do_work_pending) from [<c0200074>] (slow_work_pending+0xc/0x20)
[ 5023.140733] Exception stack(0xc3df5fb0 to 0xc3df5ff8)
[ 5023.142451] 5fa0:                                     00000014 80404a5a 9a1fea38 763e2d70
[ 5023.144061] 5fc0: b6b4e57c 04000000 00000014 00000036 9a1fea38 9a23d0d0 b6b4e224 00000013
[ 5023.145689] 5fe0: b66ba154 9a1fea1c b66a6224 b6541518 a0000010 00000014
[ 5023.147299]  r10:00000036 r9:c3df4000 r8:c0200204 r7:00000036 r6:00000014 r5:04000000
[ 5023.148965]  r4:b6b4e57c 
[ 5023.150609] Code: e1c420d0 e5950000 e3a0cc01 e3001122 (e5823004) 
[ 5023.152307] ---[ end trace 06c3a2d3ae4afb4d ]---

It's a Compute Module 4 Rev 1.0 (b03140), still running the original EEPROM version:

info-beamer-3878290628 /space # uname -a
Linux info-beamer-3878290628 5.10.17-v7l+ #1403 SMP Mon Feb 22 11:33:35 GMT 2021 armv7l GNU/Linux

info-beamer-3878290628 /space # vcgencmd version
Feb 25 2021 12:11:39 
Copyright (c) 2012 Broadcom
version 564e5f9b852b23a330b1764bcf0b2d022a20afd0 (clean) (release) (start_x)

info-beamer-3878290628 /space # vcgencmd bootloader_version
Oct  2 2020 08:44:17
version bfe1c7ddc094b735134e96f2338c0ab2c9b10a5b (release)
timestamp 1601624657
update-time 0
capabilities 0x00000000
dividuum commented 3 years ago

And another one. Pi4 (d03114):

[23252.649143] vc_sm_cma_vchi_rx_ack: received response 0, throw away...
[33300.648109] [vc_sm_cma_ioctl_alloc]: something failed - cleanup. ret -512
[33300.648201] ------------[ cut here ]------------
[33300.648221] WARNING: CPU: 2 PID: 3593 at mm/memory.c:2519 __apply_to_page_range+0x56c/0x570
[33300.648228] Modules linked in: rpivid_mem v3d gpu_sched vc4 cec drm_kms_helper drm fb_sys_fops sysfillrect sysimgblt syscopyarea drm_panel_orientation_quirks vc_sm_cma(C) snd_soc_bcm2835_i2s snd_soc_pcm512x_i2c snd_soc_pcm512x snd_soc_pcm5102a snd_soc_rpi_simple_soundcard snd_soc_hifiberry_dacplus clk_hifiberry_dacpro enc28j60 pwm_bcm2835 rtc_ds1307 rtc_pcf8523 i2c_bcm2708 i2c_dev regmap_i2c ftdi_sio pl2303 cp210x usbserial rpi_poe_fan raspberrypi_hwmon dm_crypt dm_mod rpi_backlight backlight hid_multitouch goodix snd_soc_core snd_pcm_dmaengine snd_compress snd_bcm2835(C) snd_pcm snd_timer snd rt2800usb rt2800lib rt2x00usb rt2x00lib brcmfmac brcmutil rtl8192cu rtl8192c_common rtl_usb rtlwifi xpad ff_memless tun i2c_bcm2835 overlay lib80211_crypt_tkip lib80211_crypt_ccmp lib80211 mac80211 cfg80211 rfkill xt_owner xt_REDIRECT iptable_nat xt_MASQUERADE nf_nat xt_state nf_conntrack ipt_REJECT nf_reject_ipv4 nf_defrag_ipv6 nf_defrag_ipv4 xt_tcpudp iptable_filter ip_tables x_tables crc_ccitt
[33300.648695]  squashfs libarc4 ctr ccm crypto_simd cryptd sha256_generic
[33300.648744] CPU: 2 PID: 3593 Comm: video buffer Tainted: G         C        5.10.17-v7l+ #1403
[33300.648748] Hardware name: BCM2711
[33300.648753] Backtrace: 
[33300.648767] [<c0b63050>] (dump_backtrace) from [<c0b633e4>] (show_stack+0x20/0x24)
[33300.648774]  r7:ffffffff r6:00000000 r5:60000013 r4:c12e69fc
[33300.648782] [<c0b633c4>] (show_stack) from [<c0b67774>] (dump_stack+0xcc/0xf8)
[33300.648790] [<c0b676a8>] (dump_stack) from [<c0220bac>] (__warn+0xfc/0x114)
[33300.648796]  r10:00000001 r9:00000009 r8:c03f06ec r7:000009d7 r6:00000009 r5:c03f06ec
[33300.648800]  r4:c0e3a78c r3:c1205094
[33300.648807] [<c0220ab0>] (__warn) from [<c0b63b54>] (warn_slowpath_fmt+0x70/0xd8)
[33300.648812]  r7:000009d7 r6:c0e3a78c r5:c1205048 r4:00000000
[33300.648820] [<c0b63ae8>] (warn_slowpath_fmt) from [<c03f06ec>] (__apply_to_page_range+0x56c/0x570)
[33300.648826]  r9:d3100000 r8:d3100000 r7:d3100000 r6:00000000 r5:c8f93000 r4:ffffffea
[33300.648834] [<c03f0180>] (__apply_to_page_range) from [<c03f0718>] (apply_to_page_range+0x28/0x30)
[33300.648839]  r10:00000001 r9:d3100000 r8:d3100000 r7:c235c410 r6:00000000 r5:c8f93000
[33300.648843]  r4:c1205048
[33300.648851] [<c03f06f0>] (apply_to_page_range) from [<c0215f80>] (cma_allocator_free+0xd4/0xe8)
[33300.648858] [<c0215eac>] (cma_allocator_free) from [<c0216a38>] (__arm_dma_free.constprop.3+0x190/0x218)
[33300.648863]  r9:00000000 r8:d3100000 r7:00000000 r6:c42a7ac0 r5:d3100000 r4:c1205048
[33300.648870] [<c02168a8>] (__arm_dma_free.constprop.3) from [<c0216aec>] (arm_dma_free+0x2c/0x34)
[33300.648875]  r10:00000000 r9:00000001 r8:00080060 r7:d3100000 r6:c235c410 r5:00000000
[33300.648879]  r4:d3100000
[33300.648885] [<c0216ac0>] (arm_dma_free) from [<c0217084>] (arch_dma_free+0x2c/0x34)
[33300.648890]  r5:00000000 r4:d3100000
[33300.648898] [<c0217058>] (arch_dma_free) from [<c02a2938>] (dma_direct_free+0x12c/0x174)
[33300.648903]  r5:d3100000 r4:d3100000
[33300.648910] [<c02a280c>] (dma_direct_free) from [<c02a1158>] (dma_free_attrs+0x80/0xd0)
[33300.648916]  r10:c3fa6330 r9:00000001 r8:00080060 r7:00000000 r6:00000000 r5:d3100000
[33300.648920]  r4:c235c410
[33300.648938] [<c02a10d8>] (dma_free_attrs) from [<bf503d04>] (vc_sm_release_resource+0xe4/0x104 [vc_sm_cma])
[33300.648943]  r7:00000000 r6:d3100000 r5:bf5082c0 r4:c4107c80
[33300.648957] [<bf503c20>] (vc_sm_release_resource [vc_sm_cma]) from [<bf503dc0>] (vc_sm_dma_buf_release+0x54/0xa8 [vc_sm_cma])
[33300.648962]  r7:00080040 r6:c3f88490 r5:c4107c90 r4:c4107c80
[33300.648974] [<bf503d6c>] (vc_sm_dma_buf_release [vc_sm_cma]) from [<c0871990>] (dma_buf_release+0x54/0x90)
[33300.648979]  r5:00000000 r4:c311e540
[33300.648987] [<c087193c>] (dma_buf_release) from [<c045651c>] (__dentry_kill+0x140/0x1d0)
[33300.648991]  r5:00000000 r4:c3f88440
[33300.648998] [<c04563dc>] (__dentry_kill) from [<c04575a0>] (dput.part.8+0x218/0x3d0)
[33300.649002]  r7:00080040 r6:c0e3deac r5:c3f88490 r4:c3f88440
[33300.649008] [<c0457388>] (dput.part.8) from [<c0457770>] (dput+0x18/0x1c)
[33300.649013]  r10:ffffe000 r9:00000000 r8:c3f88440 r7:c18cd790 r6:00080007 r5:c3fa6330
[33300.649017]  r4:c2aec0c0
[33300.649024] [<c0457758>] (dput) from [<c043cda0>] (__fput+0xd8/0x25c)
[33300.649030] [<c043ccc8>] (__fput) from [<c043cf3c>] (____fput+0x18/0x1c)
[33300.649035]  r9:fffffe30 r8:c1354d6c r7:c2ffdd00 r6:c2ffe254 r5:00000000 r4:c2aec0c0
[33300.649043] [<c043cf24>] (____fput) from [<c02429e8>] (task_work_run+0xb4/0xe4)
[33300.649052] [<c0242934>] (task_work_run) from [<c0233dd0>] (get_signal+0x654/0xae0)
[33300.649057]  r9:fffffe30 r8:5ac3c35a r7:c3518a80 r6:b64a9518 r5:c2aec0c0 r4:ffffe000
[33300.649064] [<c023377c>] (get_signal) from [<c020b87c>] (do_work_pending+0x30c/0x5a8)
[33300.649070]  r10:c1205048 r9:fffffe30 r8:5ac3c35a r7:c437ff44 r6:b64a9518 r5:c437ffb0
[33300.649074]  r4:ffffe000
[33300.649080] [<c020b570>] (do_work_pending) from [<c0200074>] (slow_work_pending+0xc/0x20)
[33300.649084] Exception stack(0xc437ffb0 to 0xc437fff8)
[33300.649089] ffa0:                                     00000014 80404a5a 9ae04a38 763e2d70
[33300.649095] ffc0: b6ab657c 04000000 00000014 00000036 9ae04a38 a12d2ff0 b6ab6224 00000019
[33300.649100] ffe0: b6622154 9ae04a1c b660e224 b64a9518 a0000010 00000014
[33300.649106]  r10:00000036 r9:c437e000 r8:c0200204 r7:00000036 r6:00000014 r5:04000000
[33300.649110]  r4:b6ab657c
[33300.649136] ---[ end trace 04059466cc2e08a3 ]---
[33300.649153] 8<--- cut here ---
[33300.649176] Unable to handle kernel NULL pointer dereference at virtual address 00000004
[33300.649214] pgd = 6afba947
[33300.649231] [00000004] *pgd=03489003, *pmd=1fc41c003
[33300.649275] Internal error: Oops: a07 [#1] SMP ARM
[33300.649299] Modules linked in: rpivid_mem v3d gpu_sched vc4 cec drm_kms_helper drm fb_sys_fops sysfillrect sysimgblt syscopyarea drm_panel_orientation_quirks vc_sm_cma(C) snd_soc_bcm2835_i2s snd_soc_pcm512x_i2c snd_soc_pcm512x snd_soc_pcm5102a snd_soc_rpi_simple_soundcard snd_soc_hifiberry_dacplus clk_hifiberry_dacpro enc28j60 pwm_bcm2835 rtc_ds1307 rtc_pcf8523 i2c_bcm2708 i2c_dev regmap_i2c ftdi_sio pl2303 cp210x usbserial rpi_poe_fan raspberrypi_hwmon dm_crypt dm_mod rpi_backlight backlight hid_multitouch goodix snd_soc_core snd_pcm_dmaengine snd_compress snd_bcm2835(C) snd_pcm snd_timer snd rt2800usb rt2800lib rt2x00usb rt2x00lib brcmfmac brcmutil rtl8192cu rtl8192c_common rtl_usb rtlwifi xpad ff_memless tun i2c_bcm2835 overlay lib80211_crypt_tkip lib80211_crypt_ccmp lib80211 mac80211 cfg80211 rfkill xt_owner xt_REDIRECT iptable_nat xt_MASQUERADE nf_nat xt_state nf_conntrack ipt_REJECT nf_reject_ipv4 nf_defrag_ipv6 nf_defrag_ipv4 xt_tcpudp iptable_filter ip_tables x_tables crc_ccitt
[33300.649575]  squashfs libarc4 ctr ccm crypto_simd cryptd sha256_generic
[33300.649918] CPU: 2 PID: 3593 Comm: video buffer Tainted: G        WC        5.10.17-v7l+ #1403
[33300.649951] Hardware name: BCM2711
[33300.649975] PC is at vc_sm_release_resource+0x78/0x104 [vc_sm_cma]
[33300.650006] LR is at _cond_resched+0x34/0x58
[33300.650027] pc : [<bf503c98>]    lr : [<c0b6db44>]    psr: 60000013
[33300.650054] sp : c437fda0  ip : 00000100  fp : c437fdcc
[33300.650077] r10: c3fa6330  r9 : 00000001  r8 : 00080060
[33300.650100] r7 : 00000000  r6 : d3100000  r5 : bf5082c0  r4 : c4107c80
[33300.650128] r3 : 00000000  r2 : 00000000  r1 : 00000122  r0 : c1aaab40
[33300.650156] Flags: nZCv  IRQs on  FIQs on  Mode SVC_32  ISA ARM  Segment user
[33300.650186] Control: 30c5383d  Table: 033c5780  DAC: 55555555
[33300.650211] Process video buffer (pid: 3593, stack limit = 0xd75ac888)
[33300.650240] Stack: (0xc437fda0 to 0xc4380000)
[33300.650263] fda0: d3100000 00000000 00000000 140437d2 c4107c80 c4107c90 c3f88490 00080040
[33300.650297] fdc0: c437fde4 c437fdd0 bf503dc0 bf503c2c c311e540 00000000 c437fdfc c437fde8
[33300.650331] fde0: c0871990 bf503d78 c3f88440 00000000 c437fe1c c437fe00 c045651c c0871948
[33300.650365] fe00: c3f88440 c3f88490 c0e3deac 00080040 c437fe54 c437fe20 c04575a0 c04563e8
[33300.650399] fe20: c13fe950 c3fa63a0 c437fe4c c2aec0c0 c3fa6330 00080007 c18cd790 c3f88440
[33300.650432] fe40: 00000000 ffffe000 c437fe64 c437fe58 c0457770 c0457394 c437fe9c c437fe68
[33300.650466] fe60: c043cda0 c0457764 00000000 c3fa6330 00000000 ffffe000 c2aec0c0 00000000
[33300.650500] fe80: c2ffe254 c2ffdd00 c1354d6c fffffe30 c437feac c437fea0 c043cf3c c043ccd4
[33300.650534] fea0: c437fed4 c437feb0 c02429e8 c043cf30 ffffe000 c2aec0c0 b64a9518 c3518a80
[33300.650568] fec0: 5ac3c35a fffffe30 c437ff2c c437fed8 c0233dd0 c0242940 c137a760 c1093c10
[33300.650602] fee0: 418004fc c0200204 c437ff58 5ac3c35a fffffe30 c1aad200 400004d8 c437ff44
[33300.650636] ff00: c032e008 ffffe000 c437ffb0 b64a9518 c437ff44 5ac3c35a fffffe30 c1205048
[33300.650670] ff20: c437ffac c437ff30 c020b87c c0233788 c04389ec b64a951c fffffe00 c120504c
[33300.650703] ff40: 00000000 00000000 00000000 00000000 00020000 00000000 00000000 00000000
[33300.650737] ff60: 00000000 00000000 c437ffac 140437d2 9ae02584 140437d2 b6f64968 140437d2
[33300.650772] ff80: 04000000 b6ab657c 04000000 00000014 00000036 c0200204 c437e000 00000036
[33300.650806] ffa0: 00000000 c437ffb0 c0200074 c020b57c 00000014 80404a5a 9ae04a38 763e2d70
[33300.650840] ffc0: b6ab657c 04000000 00000014 00000036 9ae04a38 a12d2ff0 b6ab6224 00000019
[33300.650874] ffe0: b6622154 9ae04a1c b660e224 b64a9518 a0000010 00000014 00000000 00000000
[33300.650907] Backtrace: 
[33300.650933] [<bf503c20>] (vc_sm_release_resource [vc_sm_cma]) from [<bf503dc0>] (vc_sm_dma_buf_release+0x54/0xa8 [vc_sm_cma])
[33300.650979]  r7:00080040 r6:c3f88490 r5:c4107c90 r4:c4107c80
[33300.651010] [<bf503d6c>] (vc_sm_dma_buf_release [vc_sm_cma]) from [<c0871990>] (dma_buf_release+0x54/0x90)
[33300.651049]  r5:00000000 r4:c311e540
[33300.651070] [<c087193c>] (dma_buf_release) from [<c045651c>] (__dentry_kill+0x140/0x1d0)
[33300.651102]  r5:00000000 r4:c3f88440
[33300.651123] [<c04563dc>] (__dentry_kill) from [<c04575a0>] (dput.part.8+0x218/0x3d0)
[33300.651155]  r7:00080040 r6:c0e3deac r5:c3f88490 r4:c3f88440
[33300.651181] [<c0457388>] (dput.part.8) from [<c0457770>] (dput+0x18/0x1c)
[33300.651210]  r10:ffffe000 r9:00000000 r8:c3f88440 r7:c18cd790 r6:00080007 r5:c3fa6330
[33300.651241]  r4:c2aec0c0
[33300.651257] [<c0457758>] (dput) from [<c043cda0>] (__fput+0xd8/0x25c)
[33300.651286] [<c043ccc8>] (__fput) from [<c043cf3c>] (____fput+0x18/0x1c)
[33300.651315]  r9:fffffe30 r8:c1354d6c r7:c2ffdd00 r6:c2ffe254 r5:00000000 r4:c2aec0c0
[33300.651350] [<c043cf24>] (____fput) from [<c02429e8>] (task_work_run+0xb4/0xe4)
[33300.651384] [<c0242934>] (task_work_run) from [<c0233dd0>] (get_signal+0x654/0xae0)
[33300.651416]  r9:fffffe30 r8:5ac3c35a r7:c3518a80 r6:b64a9518 r5:c2aec0c0 r4:ffffe000
[33300.651450] [<c023377c>] (get_signal) from [<c020b87c>] (do_work_pending+0x30c/0x5a8)
[33300.651483]  r10:c1205048 r9:fffffe30 r8:5ac3c35a r7:c437ff44 r6:b64a9518 r5:c437ffb0
[33300.651514]  r4:ffffe000
[33300.651530] [<c020b570>] (do_work_pending) from [<c0200074>] (slow_work_pending+0xc/0x20)
[33300.653279] Exception stack(0xc437ffb0 to 0xc437fff8)
[33300.654917] ffa0:                                     00000014 80404a5a 9ae04a38 763e2d70
[33300.656546] ffc0: b6ab657c 04000000 00000014 00000036 9ae04a38 a12d2ff0 b6ab6224 00000019
[33300.658209] ffe0: b6622154 9ae04a1c b660e224 b64a9518 a0000010 00000014
[33300.659861]  r10:00000036 r9:c437e000 r8:c0200204 r7:00000036 r6:00000014 r5:04000000
[33300.661523]  r4:b6ab657c
[33300.663165] Code: e1c420d0 e5950000 e3a0cc01 e3001122 (e5823004) 
[33300.664801] ---[ end trace 04059466cc2e08a4 ]---
[33300.688566] bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=1)

Version numbers:

info-beamer-2776321857 /space/log # uname -a
Linux info-beamer-2776321857 5.10.17-v7l+ #1403 SMP Mon Feb 22 11:33:35 GMT 2021 armv7l GNU/Linux

info-beamer-2776321857 /space/log # vcgencmd version
Feb 25 2021 12:11:39 
Copyright (c) 2012 Broadcom
version 564e5f9b852b23a330b1764bcf0b2d022a20afd0 (clean) (release) (start_x)

info-beamer-2776321857 /space/log # vcgencmd bootloader_version
Apr 29 2021 17:11:25
version c2f8c388c4ee37ad709ace403467d163e8dd91ce (release)
timestamp 1619712685
update-time 1619712685
capabilities 0x0000001f
nixternal commented 2 years ago

Here to confirm the issue persists in Raspbian 11 (bullseye) unfortunately. Not using FKMS with the new Raspbian version, or really anything out of ordinary. Took Raspbian Lite, installed X, Nodm, Matchbox Window Manager, and VLC.

cvlc -f --no-osd --one-instance media/BigBuckBunny_2000hevc-1.mp4 -L

pi4vlcdmesg.txt

nixternal commented 2 years ago

OK, something I have noticed. https://www.libde265.org/hevc-bitstreams/bbb-3840x2160-cfg02.mkv

mediainfo command says CODEC ID: V_MPEGH/ISO/HEVC

If I clip out just 5 seconds of that video in Handbrake & keep it HEVC/H.265, it still has that for a CODEC ID. However, if you clip it with Adobe or whatever Windows uses, it shows CODEC ID: hvc1.

hvc1 crashes, V_MPEGH/ISO/HEVC does not crash.

nixternal commented 2 years ago

Also noticed that H.264 is still running just fine as well.