raspberrypi / linux

Kernel source tree for Raspberry Pi-provided kernel builds. Issues unrelated to the linux kernel should be posted on the community forum at https://forums.raspberrypi.com/
Other
11.14k stars 4.99k forks source link

Kernel 5.10 - Kodi does not play HEVC videos anymore #4380

Closed skrenes closed 1 year ago

skrenes commented 3 years ago

Playback of many HEVC videos (regardless of resolution/bitrate) no longer seem possible with a fresh install of Raspberry Pi OS on Kodi 18.7 (fully upgraded using apt). This comes with kernel 5.10.17-v7l+, but I've also tested this with kernel 5.10.42-v7l+, the latest version available from rpi-update at the time of writing this. I've also tried variations on setting cma-512 and gpu_mem=320.

Reverting back to kernel 5.4.83-v7l+ via the command sudo BRANCH=stable rpi-update b7c8ef64ea24435519f05c38a2238658908c038e and setting config.txt to use gpu_mem=320 with dtoverlay=vc4-fkms-v3d restores HEVC playback. Anything else crashes with certain files.

I really hope these issues with kernel 5.10 can be remedied soon, especially since it's now the default kernel. This issue seems related to issues #4112, #4136 and #4167.

To reproduce

  1. Install latest version of Raspberry Pi OS
  2. Fully update the system: sudo apt update && sudo apt upgrade -y && sudo apt full-upgrade -y && sudo apt autoremove -y
  3. Add gpu_mem=320 to config.txt
  4. Reboot: sudo reboot
  5. Install Kodi: sudo apt install -y kodi
  6. Playback HEVC video within Kodi. Another user provided this example file: wget user-images.githubusercontent.com/53397439/110206300-27e0fc80-7e7d-11eb-8c46-1e318b80f35a.mp4

Expected behaviour HEVC video plays back smoothly.

Actual behaviour HEVC video crashes Raspberry Pi on kernel 5.10.42-v7l+:

rpi_ctrl_ffmpeg_init
 id=0x5489ee0
setup_io: 00000000 -> 0x9968d000 (fd:55)
setup_io: 00000000 -> 0x97c0c000 (fd:56)
***** gpu_malloc_uncached_internal, 67108864
A 100000000 apb:0x9968d000 axi.arm:0x8c500000 axi.vc:1d700000

Reverting back to kernel 5.4.83-v7l+ via the command sudo BRANCH=stable rpi-update b7c8ef64ea24435519f05c38a2238658908c038e restores functionality with smooth playback but with similar errors in the console (unnoticeable if running Kodi as a systemd service):

rpi_ctrl_ffmpeg_init
 id=0xb0ac7528
setup_io: 00000000 -> 0xae701000 (fd:59)
setup_io: 00000000 -> 0xa4821000 (fd:60)
***** gpu_malloc_uncached_internal, 67108864
A 100000000 apb:0xae701000 axi.arm:0x8ab49000 axi.vc:3a383000

...and then when stopping playback of the HEVC clip (not possible on kernel 5.10, since it's crashed at this point):

rpi_ctrl_ffmpeg_free id=0xb0ac7528
***** gpu_free_internal
rpi_ctrl_ffmpeg_free freed axi mem
rpi_ctrl_ffmpeg_free closed mbox
rpi_ctrl_ffmpeg_free freed rpi

System

System Information
------------------

Raspberry Pi 4 Model B Rev 1.1
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"

Raspberry Pi reference 2021-05-07
Generated using pi-gen, https://github.com/RPi-Distro/pi-gen, dcfd74d7d1fa293065ac6d565711e9ff891fe2b8, stage2

Linux RPi-Kodi 5.10.42-v7l+ #1422 SMP Tue Jun 8 13:03:05 BST 2021 armv7l GNU/Linux
Revision    : b03111
Serial      : 10000000ebadc0de
Model       : Raspberry Pi 4 Model B Rev 1.1
Throttled flag  : throttled=0x0
Camera          : supported=0 detected=0

Videocore information
---------------------

Jun  8 2021 13:06:28 
Copyright (c) 2012 Broadcom
version 03df0e450c356822f03454c27edea347fd8d211b (clean) (release) (start)

alloc failures:     0
compactions:        0
legacy block fails: 0

Filesystem information
----------------------
Filesystem                1K-blocks    Used Available Use% Mounted on
192.168.168.200:/RPi-Kodi   4194304 3504600    689704  84% /
devtmpfs                     702688       0    702688   0% /dev
tmpfs                        834784       0    834784   0% /dev/shm
tmpfs                        834784    8584    826200   2% /run
tmpfs                          5120       4      5116   1% /run/lock
tmpfs                        834784       0    834784   0% /sys/fs/cgroup
tmpfs                        166956       0    166956   0% /run/user/1000

Filename                Type        Size        Used        Priority
/var/swap                               file        102396      0       -2

Package version information
---------------------------
raspberrypi-ui-mods:
  Installed: (none)
raspberrypi-sys-mods:
  Installed: 20210310
openbox:
  Installed: (none)
lxpanel:
  Installed: (none)
pcmanfm:
  Installed: (none)
rpd-plym-splash:
  Installed: (none)

Networking Information
----------------------

eth0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
        inet x.x.x.x  netmask x.x.x.x  broadcast x.x.x.x
        inet6 y::y.y.y.y  prefixlen 64  scopeid 0x20<link>
        ether m.m.m.m  txqueuelen 1000  (Ethernet)
        RX packets 73368  bytes 90985175 (86.7 MiB)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 41065  bytes 7418580 (7.0 MiB)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

lo: flags=73<UP,LOOPBACK,RUNNING>  mtu 65536
        inet x.x.x.x  netmask x.x.x.x
        inet6 ::1  prefixlen 128  scopeid 0x10<host>
        loop  txqueuelen 1000  (Local Loopback)
        RX packets 0  bytes 0 (0.0 B)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 0  bytes 0 (0.0 B)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

wlan0: flags=4099<UP,BROADCAST,MULTICAST>  mtu 1500
        ether m.m.m.m  txqueuelen 1000  (Ethernet)
        RX packets 0  bytes 0 (0.0 B)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 0  bytes 0 (0.0 B)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

USB Information
---------------

/:  Bus 02.Port 1: Dev 1, Class=root_hub, Driver=xhci_hcd/4p, 5000M
/:  Bus 01.Port 1: Dev 1, Class=root_hub, Driver=xhci_hcd/1p, 480M
    |__ Port 1: Dev 2, If 0, Class=Hub, Driver=hub/4p, 480M

config.txt
----------

arm_freq=1500
audio_pwm_mode=514
config_hdmi_boost=5
core_freq=500
core_freq_min=200
disable_commandline_tags=2
disable_l2cache=1
display_hdmi_rotate=-1
display_lcd_rotate=-1
dvfs=3
enable_gic=1
force_eeprom_read=1
force_pwm_open=1
framebuffer_ignore_alpha=1
framebuffer_swap=1
gpu_freq=500
gpu_freq_min=250
init_uart_clock=0x2dc6c00
lcd_framerate=60
mask_gpu_interrupt0=1024
mask_gpu_interrupt1=0x10000
max_framebuffers=2
over_voltage_avs=1250
pause_burst_frames=1
program_serial_random=1
total_mem=2048
hdmi_force_cec_address:0=65535
hdmi_force_cec_address:1=65535
hdmi_ignore_cec_init:0=1
hdmi_pixel_freq_limit:0=0x11e1a300
hdmi_pixel_freq_limit:1=0x11e1a300
device_tree=-
overlay_prefix=overlays/
hdmi_cvt:0=
hdmi_cvt:1=
hdmi_edid_filename:0=
hdmi_edid_filename:1=
hdmi_timings:0=
hdmi_timings:1=

cmdline.txt
-----------
coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 video=HDMI-A-1:1920x1080M@60 smsc95xx.macaddr=DC:A6:32:0B:AA:AA vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  root=/dev/nfs nfsroot=192.168.168.200:/RPi-Kodi,vers=3,proto=tcp rw ip=dhcp rootwait elevator=deadline

raspi-gpio settings
-------------------

BANK0 (GPIO 0 to 27):
GPIO 0: level=1 fsel=0 func=INPUT pull=UP
GPIO 1: level=1 fsel=0 func=INPUT pull=UP
GPIO 2: level=1 fsel=0 func=INPUT pull=UP
GPIO 3: level=1 fsel=0 func=INPUT pull=UP
GPIO 4: level=1 fsel=0 func=INPUT pull=UP
GPIO 5: level=1 fsel=0 func=INPUT pull=UP
GPIO 6: level=1 fsel=0 func=INPUT pull=UP
GPIO 7: level=1 fsel=0 func=INPUT pull=UP
GPIO 8: level=1 fsel=0 func=INPUT pull=UP
GPIO 9: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 10: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 11: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 12: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 13: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 14: level=1 fsel=0 func=INPUT pull=NONE
GPIO 15: level=1 fsel=0 func=INPUT pull=UP
GPIO 16: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 17: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 18: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 19: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 20: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 21: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 22: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 23: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 24: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 25: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 26: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 27: level=0 fsel=0 func=INPUT pull=DOWN
BANK1 (GPIO 28 to 45):
GPIO 28: level=1 fsel=2 alt=5 func=RGMII_MDIO pull=UP
GPIO 29: level=0 fsel=2 alt=5 func=RGMII_MDC pull=DOWN
GPIO 30: level=0 fsel=7 alt=3 func=CTS0 pull=UP
GPIO 31: level=0 fsel=7 alt=3 func=RTS0 pull=NONE
GPIO 32: level=1 fsel=7 alt=3 func=TXD0 pull=NONE
GPIO 33: level=1 fsel=7 alt=3 func=RXD0 pull=UP
GPIO 34: level=0 fsel=7 alt=3 func=SD1_CLK pull=NONE
GPIO 35: level=1 fsel=7 alt=3 func=SD1_CMD pull=UP
GPIO 36: level=1 fsel=7 alt=3 func=SD1_DAT0 pull=UP
GPIO 37: level=1 fsel=7 alt=3 func=SD1_DAT1 pull=UP
GPIO 38: level=1 fsel=7 alt=3 func=SD1_DAT2 pull=UP
GPIO 39: level=1 fsel=7 alt=3 func=SD1_DAT3 pull=UP
GPIO 40: level=0 fsel=4 alt=0 func=PWM1_0 pull=NONE
GPIO 41: level=0 fsel=4 alt=0 func=PWM1_1 pull=NONE
GPIO 42: level=0 fsel=1 func=OUTPUT pull=UP
GPIO 43: level=1 fsel=0 func=INPUT pull=UP
GPIO 44: level=1 fsel=0 func=INPUT pull=UP
GPIO 45: level=1 fsel=0 func=INPUT pull=UP
BANK2 (GPIO 46 to 53):
GPIO 46: level=0 fsel=0 func=INPUT pull=UP
GPIO 47: level=0 fsel=0 func=INPUT pull=UP
GPIO 48: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 49: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 50: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 51: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 52: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 53: level=0 fsel=0 func=INPUT pull=DOWN

vcdbg log messages
------------------

008796.839: genet: LINK STATUS: speed: 1000 full duplex
008797.673: genet: GENET START: 128 8 8
008799.794: genet: GENET: UMAC_START 0xdca6320b 0x53490000
008802.010: brfs: File read: /mfs/sd/config.txt
008802.810: brfs: File read: 1928 bytes
008893.513: HDMI1:EDID error reading EDID block 0 attempt 0
008899.536: HDMI1:EDID error reading EDID block 0 attempt 1
008905.561: HDMI1:EDID error reading EDID block 0 attempt 2
008911.583: HDMI1:EDID error reading EDID block 0 attempt 3
008917.607: HDMI1:EDID error reading EDID block 0 attempt 4
008923.630: HDMI1:EDID error reading EDID block 0 attempt 5
008929.655: HDMI1:EDID error reading EDID block 0 attempt 6
008935.677: HDMI1:EDID error reading EDID block 0 attempt 7
008941.701: HDMI1:EDID error reading EDID block 0 attempt 8
008947.724: HDMI1:EDID error reading EDID block 0 attempt 9
008948.739: HDMI1:EDID giving up on reading EDID block 0
008950.401: brfs: File read: /mfs/sd/config.txt
010024.085: gpioman: gpioman_get_pin_num: pin DISPLAY_DSI_PORT not defined
010026.587: *** Restart logging
010026.608: brfs: File read: 1928 bytes
010063.941: hdmi: HDMI1:EDID error reading EDID block 0 attempt 0
010069.970: hdmi: HDMI1:EDID error reading EDID block 0 attempt 1
010075.994: hdmi: HDMI1:EDID error reading EDID block 0 attempt 2
010084.153: hdmi: HDMI1:EDID error reading EDID block 0 attempt 3
010090.206: hdmi: HDMI1:EDID error reading EDID block 0 attempt 4
010096.260: hdmi: HDMI1:EDID error reading EDID block 0 attempt 5
010102.310: hdmi: HDMI1:EDID error reading EDID block 0 attempt 6
010108.363: hdmi: HDMI1:EDID error reading EDID block 0 attempt 7
010114.414: hdmi: HDMI1:EDID error reading EDID block 0 attempt 8
010120.468: hdmi: HDMI1:EDID error reading EDID block 0 attempt 9
010121.500: hdmi: HDMI1:EDID giving up on reading EDID block 0
010126.597: hdmi: HDMI1:EDID error reading EDID block 0 attempt 0
010132.650: hdmi: HDMI1:EDID error reading EDID block 0 attempt 1
010138.701: hdmi: HDMI1:EDID error reading EDID block 0 attempt 2
010144.754: hdmi: HDMI1:EDID error reading EDID block 0 attempt 3
010150.807: hdmi: HDMI1:EDID error reading EDID block 0 attempt 4
010156.859: hdmi: HDMI1:EDID error reading EDID block 0 attempt 5
010162.909: hdmi: HDMI1:EDID error reading EDID block 0 attempt 6
010168.963: hdmi: HDMI1:EDID error reading EDID block 0 attempt 7
010175.014: hdmi: HDMI1:EDID error reading EDID block 0 attempt 8
010181.068: hdmi: HDMI1:EDID error reading EDID block 0 attempt 9
010182.101: hdmi: HDMI1:EDID giving up on reading EDID block 0
010182.139: hdmi: HDMI:hdmi_get_state is deprecated, use hdmi_get_display_state instead
010182.169: HDMI0: hdmi_pixel_encoding: 300000000
010182.194: HDMI1: hdmi_pixel_encoding: 300000000
010187.919: dtb_file 'bcm2711-rpi-4-b.dtb'
010199.724: brfs: File read: /mfs/sd/bcm2711-rpi-4-b.dtb
010199.754: Loading 'bcm2711-rpi-4-b.dtb' to 0x100 size 0xc042
010231.315: brfs: File read: 49218 bytes
010232.841: brfs: File read: /mfs/sd/overlays/overlay_map.dtb
010390.877: brfs: File read: 1559 bytes
010392.225: brfs: File read: /mfs/sd/config.txt
010393.149: dtparam: audio=on
010418.115: brfs: File read: 1928 bytes
010419.476: brfs: File read: /mfs/sd/overlays/vc4-fkms-v3d.dtbo
010462.550: Loaded overlay 'vc4-fkms-v3d'
010562.517: brfs: File read: 1446 bytes
010563.592: brfs: File read: /mfs/sd/cmdline.txt
010563.654: Read command line from file 'cmdline.txt':
010563.677: 'root=/dev/nfs nfsroot=192.168.168.200:/RPi-Kodi,vers=3,proto=tcp rw ip=dhcp rootwait elevator=deadline'
011787.837: brfs: File read: 110 bytes
013410.866: brfs: File read: /mfs/sd/kernel7l.img
013410.905: Loading 'kernel7l.img' to 0x8000 size 0x675a48
013410.946: Device tree loaded to 0x2bfe3900 (size 0xc62a)
013417.877: genet: RX_DISCARD: 0
013417.891: genet: 
013417.917: genet:   GR64: 0x00000007 
013417.941: genet:   GR64: 0x00000020 
013417.967: genet:  GR127: 0x00000005 
013417.993: genet:  GR255: 0x00000001 
013418.008: genet: 
013418.031: genet:  GR511: 0x00000005 
013418.054: genet: GR1023: 0x00001a16 
013418.076: genet: GR1518: 0x00000000 
013418.100: genet:  GRMGV: 0x00000000 
013418.113: genet: 
013418.133: genet: GR2047: 0x00000000 
013418.158: genet: GR4095: 0x00000000 
013418.185: genet: GR9216: 0x00001a48 
013418.210: genet:  GRPKT: 0x006d9181 
013418.228: genet: 
013418.251: genet:  GRBYT: 0x00000009 
013418.275: genet:  GRMCA: 0x00000000 
013418.297: genet:  GRBCA: 0x00000000 
013418.322: genet:  GRFCS: 0x00000000 
013418.334: genet: 
013418.356: genet:  GRXCF: 0x00000000 
013418.382: genet:  GRXPF: 0x00000000 
013418.408: genet:  GRXUO: 0x00000000 
013418.432: genet:  GRALN: 0x00000000 
013418.450: genet: 
013418.474: genet:  GRFLR: 0x00000000 
013418.498: genet:  GRCDE: 0x00000000 
013418.521: genet:  GRFCR: 0x00000000 
013418.544: genet:  GROVR: 0x00000000 
013418.557: genet: 
013418.579: genet:  GRJBR: 0x00000000 
013418.604: genet: GRMTUE: 0x00001a48 
013418.631: genet:  GRPOK: 0x00001a3f 
013418.655: genet:   GRUC: 0x00000000 
013418.672: genet: 
013418.695: genet:  GRPPP: 0x00000000 
013418.720: genet:  GRCRC: 0x00000000 
013418.732: genet: 
013418.746: genet: 
013418.770: genet:   TR64: 0x00001a28 
013418.794: genet:  TR127: 0x0000002b 
013418.816: genet:  TR255: 0x00000000 
013418.839: genet:  TR511: 0x00000000 
013418.852: genet: 
013418.873: genet: TR1023: 0x00000000 
013418.898: genet: TR1518: 0x00000000 
013418.924: genet:  TRMGV: 0x00000000 
013418.948: genet: TR2047: 0x00000000 
013418.964: genet: 
013418.988: genet: TR4095: 0x00000000 
013419.011: genet: TR9216: 0x00000000 
013419.034: genet:  GTPKT: 0x00001a53 
013419.059: genet:  GTMCA: 0x00000000 
013419.072: genet: 
013419.095: genet:  GTBCA: 0x00000000 
013419.121: genet:  GTXPF: 0x00000000 
013419.146: genet:  GTXCF: 0x00000000 
013419.170: genet:  GTFCS: 0x00000000 
013419.186: genet: 
013419.212: genet:  GTOVR: 0x00000000 
013419.235: genet:  GTDRF: 0x00000000 
013419.258: genet:  GTEDF: 0x00000000 
013419.282: genet:  GTSCL: 0x00000000 
013419.295: genet: 
013419.334: genet:  GTMCL: 0x00000000 
013419.360: genet:  GTLCL: 0x00000000 
013419.386: genet:  GTXCL: 0x00000000 
013419.410: genet:  GTFRG: 0x00000000 
013419.428: genet: 
013419.451: genet:  GTNCL: 0x00000000 
013419.476: genet:  GTJBR: 0x00000000 
013419.498: genet:  GTBYT: 0x00069801 
013419.523: genet:  GTPOK: 0x00001a53 
013419.534: genet: 
013419.557: genet:   GTUC: 0x00001a53 
013419.573: genet: 
013429.606: genet: GENET STOP: 0
016130.436: vchiq_core: vchiq_init_state: slot_zero = 0xdbd80000, is_master = 1
016133.962: hdmi: HDMI:hdmi_get_state is deprecated, use hdmi_get_display_state instead
016142.017: TV service:host side not connected, dropping notification 0x00000002, 0x00000002, 0x00000052
024428.393: brfs: File read: 6773320 bytes
025405.419: TV service:host side not connected, dropping notification 0x00000004, 0x00000004, 0x00000000

dmesg log
---------

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 5.10.42-v7l+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1422 SMP Tue Jun 8 13:03:05 BST 2021
[    0.000000] CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d
[    0.000000] CPU: div instructions available: patching division code
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
[    0.000000] OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.1
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] Reserved memory: created CMA memory pool at 0x000000001bc00000, size 256 MiB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000000000-0x000000002bffffff]
[    0.000000]   Normal   empty
[    0.000000]   HighMem  [mem 0x000000002c000000-0x000000007fffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x000000002bffffff]
[    0.000000]   node   0: [mem 0x0000000040000000-0x000000007fffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000007fffffff]
[    0.000000] On node 0 totalpages: 442368
[    0.000000]   DMA zone: 1584 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 180224 pages, LIFO batch:63
[    0.000000]   HighMem zone: 262144 pages, LIFO batch:63
[    0.000000] percpu: Embedded 20 pages/cpu s50764 r8192 d22964 u81920
[    0.000000] pcpu-alloc: s50764 r8192 d22964 u81920 alloc=20*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 440784
[    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 video=HDMI-A-1:1920x1080M@60 smsc95xx.macaddr=m.m.m.m vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  root=/dev/nfs nfsroot=192.168.168.200:/RPi-Kodi,vers=3,proto=tcp rw ip=dhcp rootwait elevator=deadline
[    0.000000] Kernel parameter elevator= does not have any effect anymore.
               Please use sysfs to set IO scheduler for individual devices.
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] software IO TLB: mapped [mem 0x0000000016a00000-0x000000001aa00000] (64MB)
[    0.000000] Memory: 1405380K/1769472K available (10240K kernel code, 1364K rwdata, 3188K rodata, 2048K init, 891K bss, 101948K reserved, 262144K cma-reserved, 1048576K highmem)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 34287 entries in 67 pages
[    0.000000] ftrace: allocated 67 pages with 3 groups
[    0.000000] rcu: Hierarchical RCU implementation.
[    0.000000]  Rude variant of Tasks RCU enabled.
[    0.000000]  Tracing variant of Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000] GIC: Using split EOI/Deactivate mode
[    0.000000] random: get_random_bytes called from start_kernel+0x3c8/0x59c with crng_init=0
[    0.000008] sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns
[    0.000038] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns
[    0.000106] bcm2835: system timer (irq = 25)
[    0.000756] arch_timer: cp15 timer(s) running at 54.00MHz (phys).
[    0.000779] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns
[    0.000802] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns
[    0.000821] Switching to timer-based delay loop, resolution 18ns
[    0.001091] Console: colour dummy device 80x30
[    0.001842] printk: console [tty0] enabled
[    0.001911] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000)
[    0.001966] pid_max: default: 32768 minimum: 301
[    0.002149] LSM: Security Framework initializing
[    0.002356] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.002402] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.004056] Disabling memory control group subsystem
[    0.004194] CPU: Testing write buffer coherency: ok
[    0.004692] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.005932] Setting up static identity map for 0x200000 - 0x20003c
[    0.006152] rcu: Hierarchical SRCU implementation.
[    0.007115] smp: Bringing up secondary CPUs ...
[    0.008408] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.009819] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.011286] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.011445] smp: Brought up 1 node, 4 CPUs
[    0.011493] SMP: Total of 4 processors activated (432.00 BogoMIPS).
[    0.011523] CPU: All CPU(s) started in HYP mode.
[    0.011550] CPU: Virtualization extensions available.
[    0.012414] devtmpfs: initialized
[    0.026863] VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0
[    0.027141] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.027195] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.034024] pinctrl core: initialized pinctrl subsystem
[    0.035195] NET: Registered protocol family 16
[    0.039371] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.040174] audit: initializing netlink subsys (disabled)
[    0.040455] audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1
[    0.041091] thermal_sys: Registered thermal governor 'step_wise'
[    0.041817] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.041871] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.042320] Serial: AMBA PL011 UART driver
[    0.074556] bcm2835-mbox fe00b880.mailbox: mailbox enabled
[    0.090926] raspberrypi-firmware soc:firmware: Attached to firmware from 2021-06-08T13:06:28, variant start
[    0.100940] raspberrypi-firmware soc:firmware: Firmware hash is 03df0e450c356822f03454c27edea347fd8d211b
[    0.141221] Kprobes globally optimized
[    0.146481] bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1
[    0.150675] vgaarb: loaded
[    0.151223] SCSI subsystem initialized
[    0.151486] usbcore: registered new interface driver usbfs
[    0.151564] usbcore: registered new interface driver hub
[    0.151654] usbcore: registered new device driver usb
[    0.152037] usb_phy_generic phy: supply vcc not found, using dummy regulator
[    0.154033] clocksource: Switched to clocksource arch_sys_counter
[    1.155216] VFS: Disk quotas dquot_6.6.0
[    1.155352] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    1.155556] FS-Cache: Loaded
[    1.155761] CacheFiles: Loaded
[    1.156874] simple-framebuffer 3e384000.framebuffer: framebuffer at 0x3e384000, 0x7f8000 bytes, mapped to 0x(ptrval)
[    1.156920] simple-framebuffer 3e384000.framebuffer: format=a8r8g8b8, mode=1920x1080x32, linelength=7680
[    1.168049] Console: switching to colour frame buffer device 240x67
[    1.178262] simple-framebuffer 3e384000.framebuffer: fb0: simplefb registered!
[    1.187028] NET: Registered protocol family 2
[    1.187335] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    1.188770] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
[    1.188998] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    1.189151] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    1.189294] TCP: Hash tables configured (established 8192 bind 8192)
[    1.189533] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[    1.189641] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[    1.189980] NET: Registered protocol family 1
[    1.190776] RPC: Registered named UNIX socket transport module.
[    1.190856] RPC: Registered udp transport module.
[    1.190922] RPC: Registered tcp transport module.
[    1.190988] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    1.191076] PCI: CLS 0 bytes, default 64
[    1.194306] Initialise system trusted keyrings
[    1.194612] workingset: timestamp_bits=14 max_order=19 bucket_order=5
[    1.203063] zbud: loaded
[    1.205433] FS-Cache: Netfs 'nfs' registered for caching
[    1.206290] NFS: Registering the id_resolver key type
[    1.206393] Key type id_resolver registered
[    1.206456] Key type id_legacy registered
[    1.206654] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.206740] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    1.207878] Key type asymmetric registered
[    1.207945] Asymmetric key parser 'x509' registered
[    1.208196] bounce: pool size: 64 pages
[    1.208300] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[    1.208598] io scheduler mq-deadline registered
[    1.208666] io scheduler kyber registered
[    1.213305] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
[    1.213417] brcm-pcie fd500000.pcie:   No bus range found for /scb/pcie@7d500000, using [bus 00-ff]
[    1.216584] brcm-pcie fd500000.pcie:      MEM 0x0600000000..0x063fffffff -> 0x00c0000000
[    1.219653] brcm-pcie fd500000.pcie:   IB MEM 0x0000000000..0x007fffffff -> 0x0400000000
[    1.286134] brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC)
[    1.289460] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
[    1.292350] pci_bus 0000:00: root bus resource [bus 00-ff]
[    1.295306] pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff])
[    1.298249] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
[    1.301403] pci 0000:00:00.0: PME# supported from D0 D3hot
[    1.307774] PCI: bus0: Fast back to back transfers disabled
[    1.311007] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
[    1.313952] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
[    1.317359] pci 0000:01:00.0: PME# supported from D0 D3cold
[    1.323631] PCI: bus1: Fast back to back transfers disabled
[    1.326559] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
[    1.329380] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
[    1.332284] pci 0000:00:00.0: PCI bridge to [bus 01]
[    1.335129] pci 0000:00:00.0:   bridge window [mem 0x600000000-0x6000fffff]
[    1.338406] pcieport 0000:00:00.0: enabling device (0140 -> 0142)
[    1.341520] pcieport 0000:00:00.0: PME: Signaling with IRQ 62
[    1.353294] iproc-rng200 fe104000.rng: hwrng registered
[    1.356577] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    1.360342] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000
[    1.375743] brd: module loaded
[    1.391201] loop: module loaded
[    1.396102] Loading iSCSI transport class v2.0-870.
[    1.402289] libphy: Fixed MDIO Bus: probed
[    1.406998] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000
[    1.424066] libphy: bcmgenet MII bus: probed
[    1.504204] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus
[    1.508237] usbcore: registered new interface driver r8152
[    1.511100] usbcore: registered new interface driver lan78xx
[    1.513877] usbcore: registered new interface driver smsc95xx
[    1.518301] xhci_hcd 0000:01:00.0: enabling device (0140 -> 0142)
[    1.521170] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.523863] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
[    1.529857] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0000060000000890
[    1.533865] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10
[    1.536626] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.539310] usb usb1: Product: xHCI Host Controller
[    1.541977] usb usb1: Manufacturer: Linux 5.10.42-v7l+ xhci-hcd
[    1.544661] usb usb1: SerialNumber: 0000:01:00.0
[    1.548047] hub 1-0:1.0: USB hub found
[    1.550816] hub 1-0:1.0: 1 port detected
[    1.554201] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.556892] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[    1.559600] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[    1.562753] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10
[    1.565497] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.568213] usb usb2: Product: xHCI Host Controller
[    1.570905] usb usb2: Manufacturer: Linux 5.10.42-v7l+ xhci-hcd
[    1.573623] usb usb2: SerialNumber: 0000:01:00.0
[    1.577283] hub 2-0:1.0: USB hub found
[    1.580031] hub 2-0:1.0: 4 ports detected
[    1.584479] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    1.587470] dwc_otg: FIQ enabled
[    1.587485] dwc_otg: NAK holdoff enabled
[    1.587500] dwc_otg: FIQ split-transaction FSM enabled
[    1.587518] Module dwc_common_port init
[    1.587989] usbcore: registered new interface driver uas
[    1.590758] usbcore: registered new interface driver usb-storage
[    1.593591] mousedev: PS/2 mouse device common for all mice
[    1.597917] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    1.605024] sdhci: Secure Digital Host Controller Interface driver
[    1.607722] sdhci: Copyright(c) Pierre Ossman
[    1.611077] mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe
[    1.614331] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.620104] ledtrig-cpu: registered to indicate activity on CPUs
[    1.623131] hid: raw HID events driver (C) Jiri Kosina
[    1.626062] usbcore: registered new interface driver usbhid
[    1.628740] usbhid: USB HID core driver
[    1.637498] Initializing XFRM netlink socket
[    1.640277] NET: Registered protocol family 17
[    1.643182] Key type dns_resolver registered
[    1.646195] Registering SWP/SWPB emulation handler
[    1.648952] registered taskstats version 1
[    1.651544] Loading compiled-in X.509 certificates
[    1.655013] Key type ._fscrypt registered
[    1.657608] Key type .fscrypt registered
[    1.660164] Key type fscrypt-provisioning registered
[    1.674357] uart-pl011 fe201000.serial: there is not valid maps for state default
[    1.677315] uart-pl011 fe201000.serial: cts_event_workaround enabled
[    1.679930] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 36, base_baud = 0) is a PL011 rev2
[    1.689834] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    1.693620] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0
[    1.696289] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated
[    1.728112] of_cfs_init
[    1.730999] of_cfs_init: OK
[    1.743896] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[    1.748154] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    1.752390] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    1.757999] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[    1.762175] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    1.766231] mmc0: SDHCI controller on fe340000.emmc2 [fe340000.emmc2] using ADMA
[    1.773201] bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay)
[    1.776363] bcmgenet fd580000.ethernet eth0: Link is Down
[    1.797526] random: fast init done
[    1.834067] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[    1.841024] mmc1: new high speed SDIO card at address 0001
[    2.016672] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
[    2.019285] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    2.021899] usb 1-1: Product: USB2.0 Hub
[    2.026403] hub 1-1:1.0: USB hub found
[    2.029235] hub 1-1:1.0: 4 ports detected
[    5.914348] bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
[    5.974058] Sending DHCP requests ., OK
[    6.016687] IP-Config: Got DHCP answer from 192.168.168.1, my address is 192.168.168.120
[    6.019321] IP-Config: Complete:
[    6.021917]      device=eth0, hwaddr=m.m.m.m, ipaddr=192.168.168.120, mask=255.255.255.0, gw=192.168.168.1
[    6.024658]      host=RPi-Kodi, domain=Garfield, nis-domain=(none)
[    6.027352]      bootserver=192.168.168.1, rootserver=192.168.168.200, rootpath=
[    6.027367]      nameserver0=192.168.168.200, nameserver1=1.1.1.1
[    6.042061] VFS: Mounted root (nfs filesystem) on device 0:17.
[    6.045555] devtmpfs: mounted
[    6.057351] Freeing unused kernel memory: 2048K
[    6.104402] Run /sbin/init as init process
[    6.107077]   with arguments:
[    6.107092]     /sbin/init
[    6.107106]   with environment:
[    6.107121]     HOME=/
[    6.107135]     TERM=linux
[    6.539312] systemd[1]: System time before build time, advancing clock.
[    6.633032] NET: Registered protocol family 10
[    6.637547] Segment Routing with IPv6
[    6.708675] systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid)
[    6.717044] systemd[1]: Detected architecture arm.
[    6.795261] systemd[1]: Set hostname to <RPi-Kodi>.
[    7.654195] random: systemd: uninitialized urandom read (16 bytes read)
[    7.659433] systemd[1]: Listening on Journal Socket (/dev/log).
[    7.666895] random: systemd: uninitialized urandom read (16 bytes read)
[    7.670279] systemd[1]: Listening on initctl Compatibility Named Pipe.
[    7.677541] random: systemd: uninitialized urandom read (16 bytes read)
[    7.682839] systemd[1]: Created slice User and Session Slice.
[    7.690357] systemd[1]: Reached target Slices.
[    7.698673] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    7.708659] systemd[1]: Created slice system-getty.slice.
[    7.717229] systemd[1]: Listening on Journal Socket.
[    8.397255] random: crng init done
[    8.397273] random: 7 urandom warning(s) missed due to ratelimiting
[    8.483665] systemd-journald[133]: Received request to flush runtime journal from PID 1
[    9.344787] rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000
[    9.348636] rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000
[    9.350018] rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000
[    9.350832] rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000
[    9.516506] mc: Linux media interface: v0.10
[    9.612171] videodev: Linux video capture interface: v2.00
[    9.739222] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[    9.740238] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[    9.741607] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[    9.741641] [vc_sm_connected_init]: start
[    9.755211] [vc_sm_connected_init]: installed successfully
[    9.777438] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    9.781670] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    9.823233] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[    9.879151] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[    9.905200] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[    9.911482] bcm2835_audio bcm2835_audio: card created with 4 channels
[    9.932247] bcm2835_audio bcm2835_audio: card created with 4 channels
[    9.940426] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[    9.940884] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[    9.973558] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[   10.037943] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[   10.038001] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[   10.042588] 8021q: 802.1Q VLAN Support v1.8
[   10.043246] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[   10.046737] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[   10.050036] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[   10.056525] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[   10.056608] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[   10.057992] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[   10.058032] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[   10.072015] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[   10.072045] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[   10.072085] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[   10.076349] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[   10.471076] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[   10.578834] [drm] Initialized v3d 1.0.0 20180419 for fec00000.v3d on minor 0
[   10.672859] uart-pl011 fe201000.serial: no DMA platform data
[   10.676149] vc4-drm gpu: bound fe600000.firmwarekms (ops vc4_fkms_ops [vc4])
[   10.676399] checking generic (3e384000 7f8000) vs hw (0 ffffffffffffffff)
[   10.676418] fb0: switching to vc4drmfb from simple
[   10.682986] Console: switching to colour dummy device 80x30
[   10.707706] [drm] Initialized vc4 0.0.0 20140616 for gpu on minor 1
[   10.745520] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[   10.874713] brcmfmac: F1 signature read @0x18000000=0x15264345
[   10.928732] Console: switching to colour frame buffer device 240x67
[   10.934307] Adding 102396k swap on /var/swap.  Priority:-2 extents:1 across:102396k FS
[   10.947537] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[   10.948792] usbcore: registered new interface driver brcmfmac
[   10.952624] vc4-drm gpu: [drm] fb0: vc4drmfb frame buffer device
[   10.979470] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,4-model-b.txt failed with error -2
[   11.245192] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[   11.261764] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Jan  4 2021 19:56:29 version 7.45.229 (617f1f5 CY) FWID 01-2dbd9d2e
[   11.788278] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
[   17.701705] Bluetooth: Core ver 2.22
[   17.701827] NET: Registered protocol family 31
[   17.701844] Bluetooth: HCI device and connection manager initialized
[   17.701877] Bluetooth: HCI socket layer initialized
[   17.701900] Bluetooth: L2CAP socket layer initialized
[   17.701935] Bluetooth: SCO socket layer initialized
[   17.714506] Bluetooth: HCI UART driver ver 2.3
[   17.714522] Bluetooth: HCI UART protocol H4 registered
[   17.714590] Bluetooth: HCI UART protocol Three-wire (H5) registered
[   17.714794] Bluetooth: HCI UART protocol Broadcom registered
[   17.969618] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[   17.969627] Bluetooth: BNEP filters: protocol multicast
[   17.969640] Bluetooth: BNEP socket layer initialized
ZedeN1 commented 3 years ago

This doesn't address your issue directly but I have had the exact same HEVC issues with: RPi4, v5.10 and Kodi v18.7 from the official repo. I managed to get all HEVC videos playing perfectly using 5.10 and Kodi 19.1. As a workaround you could do the same:

For Linux raspberrypi 5.10.46-v7l+ #1433 SMP Mon Jul 5 15:04:09 BST 2021 armv7l GNU/Linux try the following /boot/config.txt, based on LibreELEC.

dtoverlay=vc4-kms-v3d,cma-512
dtoverlay=rpivid-v4l2
disable_overscan=1
disable_fw_kms_setup=1

It works for me using 19.1 (19.1.0) Git:20210701-a12eccbe35 Media Center Kodi from @popcornmix github.

Follow build instructions. You will probably need most of the following dependencies on Raspberry Pi OS (buster) 32bit:

sudo apt update
sudo apt install \
autoconf automake autopoint autotools-dev cmake cpp curl debhelper default-jre doxygen g++ gawk gcc gdc gettext gperf libasound-dev libasound2-dev libass-dev libavahi-client-dev libavahi-common-dev libbluetooth-dev libbluray-dev libbz2-dev libcap-dev libcdio-dev libcec-dev libclang-dev libcrossguid-dev libcurl4-openssl-dev libcwiid-dev libdbus-1-dev libdrm-dev libegl1-mesa-dev libenca-dev libflac-dev libfmt-dev libfontconfig-dev libfreetype6-dev libfribidi-dev libfstrcmp-dev libgbm-dev libgcrypt-dev libgif-dev libgl-dev libgl1-mesa-dev libgles2-mesa-dev libglew-dev libglu-dev libglu1-mesa-dev libgnutls28-dev libgpg-error-dev libgtest-dev libinput-dev libiso9660-dev libjpeg-dev liblcms2-dev liblirc-dev libltdl-dev liblzo2-dev libmariadb-dev libmicrohttpd-dev libnfs-dev libogg-dev libomxil-bellagio-dev libp8-platform-dev libpcre3-dev libplist-dev libpng-dev libpulse-dev libshairplay-dev libsmbclient-dev libspdlog-dev libsqlite3-dev libssl-dev libtag1-dev libtiff-dev libtiff5-dev libtinyxml-dev libtool libudev-dev libudf-dev libunistring-dev libva-dev libvdpau-dev libvorbis-dev libxkbcommon-dev libxmu-dev libxrandr-dev libxslt-dev libxslt1-dev libxt-dev lsb-release meson nasm netcat ninja-build python3-dev python3-minimal python3-pil python3-pip rapidjson-dev raspberrypi-kernel-headers swig unzip uuid-dev wayland-protocols wipe yasm zip zlib1g-dev zlib1g-dev  doxygen libcap-dev libsndio-dev libmariadbd-dev

Just change the configure command to be something like this:

cmake ../xbmc \
-DPYTHON_INCLUDE_DIR=$(python -c "from distutils.sysconfig import get_python_inc; print(get_python_inc())")  \
-DPYTHON_LIBRARY=$(python -c "import distutils.sysconfig as sysconfig; print(sysconfig.get_config_var('LIBDIR'))") \
-DCMAKE_INSTALL_PREFIX=/usr/local \
-DCORE_PLATFORM_NAME=gbm \
-DAPP_RENDER_SYSTEM=gles \
-DENABLE_VAAPI=OFF -DENABLE_VDPAU=OFF -DENABLE_DEBUGFISSION=OFF -DENABLE_TESTING=OFF -DENABLE_INTERNAL_FLATBUFFERS=ON -DENABLE_INTERNAL_FFMPEG=ON -DENABLE_INTERNAL_SPDLOG=ON -DENABLE_INTERNAL_FMT=ON

EDIT: Updated some commands

Harvie commented 3 years ago

Oh cr*p. Just upgraded and TV does not work anymore... I will try the downgrade mentioned in original post.

skrenes commented 3 years ago

Thanks @ZedeN1 . It's nice to know 19.1 is working with it. I may do that in the future, but right now, the downgraded kernel and otherwise vanilla install is serving me well.

gsjv2 commented 3 years ago

This doesn't address your issue directly but I have had the exact same HEVC issues with: RPi4, v5.10 and Kodi v18.7 from the official repo. I managed to get all HEVC videos playing perfectly using 5.10 and Kodi 19.1. As a workaround you could do the same:

For Linux raspberrypi 5.10.46-v7l+ #1433 SMP Mon Jul 5 15:04:09 BST 2021 armv7l GNU/Linux try the following /boot/config.txt, based on LibreELEC.

dtoverlay=vc4-kms-v3d,cma-512
dtoverlay=rpivid-v4l2
disable_overscan=1
disable_fw_kms_setup=1

It works for me using 19.1 (19.1.0) Git:20210701-a12eccbe35 Media Center Kodi from @popcornmix github.

Follow build instructions. You will probably need most of the following dependencies on Raspberry Pi OS (buster) 32bit:

sudo apt update
sudo apt install autoconf automake autopoint gettext autotools-dev cmake curl default-jre gawk gcc gcc-4.9 g++ g++-4.9 cpp cpp-4.9 gdc gperf libasound2-dev libasound-dev libass-dev libavahi-client-dev libavahi-common-dev libbluetooth-dev libbluray-dev libbz2-dev libcdio-dev libcec-dev libp8-platform-dev libcrossguid-dev libcwiid-dev libdbus-1-dev libegl1-mesa-dev libenca-dev libflac-dev libfontconfig-dev  libfreetype6-dev libfribidi-dev libfstrcmp-dev libgcrypt-dev libgif-dev libgles2-mesa-dev libgl1-mesa-dev libgl-dev libglew-dev libglu1-mesa-dev libglu-dev libgnutls28-dev libgpg-error-dev libgtest-dev libiso9660-dev libjpeg-dev liblcms2-dev liblirc-dev libltdl-dev liblzo2-dev libmicrohttpd-dev libnfs-dev libogg-dev libomxil-bellagio-dev libpcre3-dev libplist-dev  libpulse-dev libshairplay-dev libsmbclient-dev libspdlog-dev libsqlite3-dev libssl-dev libtag1-dev libtiff5-dev libtiff-dev libtinyxml-dev libtool libudev-dev libva-dev libvdpau-dev libvorbis-dev libxkbcommon-dev libxmu-dev libxrandr-dev libxslt1-dev libxslt-dev libxt-dev netcat wayland-protocols wipe lsb-release meson nasm ninja-build python3-dev python3-pil  python-support python3-minimal rapidjson-dev swig unzip uuid-dev yasm zip zlib1g-dev libcap-dev libclang-dev libcdio-dev libiso9660-dev libudf-dev libspdlog-dev autoconf automake autopoint autotools-dev cmake cpp curl default-jre g++ gawk gcc gdc gettext gperf libasound2-dev libass-dev libavahi-client-dev libavahi-common-dev libbluetooth-dev libbluray-dev libbz2-dev libcdio-dev libcec4 libcec-dev libcrossguid-dev libcurl4-openssl-dev libcwiid-dev libdbus-1-dev libdrm-dev libegl1-mesa-dev libenca-dev libflac-dev libfmt3-dev libfontconfig-dev libfreetype6-dev libfribidi-dev libfstrcmp-dev libgbm-dev libgcrypt-dev libgif-dev libgles2-mesa-dev libglew-dev libglu1-mesa-dev libgnutls28-dev libgpg-error-dev libgtest-dev libinput-dev libiso9660-dev libjpeg-dev liblcms2-dev liblirc-dev libltdl-dev liblzo2-dev libmariadb-dev libmicrohttpd-dev libnfs-dev  libogg-dev libomxil-bellagio-dev libpcre3-dev libplist-dev libpng-dev libpulse-dev libshairplay-dev libsmbclient-dev libsqlite3-dev libssl-dev libtag1-dev libtiff5-dev libtinyxml-dev libtool libudev-dev libunistring-dev libva-dev libvdpau-dev libvorbis-dev libxkbcommon-dev libxmu-dev libxrandr-dev libxslt1-dev libxt-dev lsb-release meson nasm ninja-build python3-dev python3-pil python-support rapidjson-dev swig unzip uuid-dev wayland-protocols yasm zip zlib1g-dev raspberrypi-kernel-headers

Just change the configure command to be something like this:

cmake .. -DCMAKE_INSTALL_PREFIX=/usr/local -DCORE_PLATFORM_NAME=gbm -DAPP_RENDER_SYSTEM=gles -DENABLE_VAAPI=OFF -DENABLE_VDPAU=OFF -DENABLE_DEBUGFISSION=OFF -DENABLE_TESTING=OFF -DENABLE_INTERNAL_FLATBUFFERS=ON -DENABLE_INTERNAL_FFMPEG=ON -DENABLE_INTERNAL_SPDLOG=ON -DENABLE_INTERNAL_FMT=ON

This totally works. I just tested and HVEC HW decoding is working!

andrebrait commented 2 years ago

This is still needed as of today on Buster (running Kodi 19.3 on top of Raspbian on a Raspberry Pi 4).

At least the part below. I can't tell if the order of those lines matter.

dtoverlay=vc4-kms-v3d,cma-512
dtoverlay=rpivid-v4l2
johanbluecreek commented 2 years ago

Looking at this issue and #4112 , using a rpi4, Linux raspberrypi 5.15.26-v7l+ kernel, Kodi 19.3, I managed to get a HEVC playing flawlessly once after a reboot (not been able to reproduce since) by having the following in my config.txt

[pi4]
dtoverlay=vc4-fkms-v3d,cma-384
dtoverlay=rpivid-v4l2
disable_fw_kms_setup=1
max_framebuffers=2
[all]
enable_uart=1
gpu_mem=384
hdmi_enable_4kp60=1

The most common thing that happens is that the video plays for a few seconds and then stops (except for that one time). If I do not have the rpivid-v4l2 line I don't even get the video to play those initial seconds, and changing the memory reserved for cma or gpu either causes a kernel panic (if the sum of them are higher or equal to 384+512), or the pi stops sending a HDMI signal mid boot.

Is the downgrade option and/or building Kodi from source the only work-arounds available?

andrebrait commented 2 years ago

@johanbluecreek

I have an Raspberry Pi 4B 4GB and I can play any HEVC (tested up to 4k@10bpc) just fine for any amount of time.

I'm on kernel 5.10.92-v7l+ on 32-bit Raspbian Buster, though. All I have in my config is:

[pi4]
dtoverlay=rpivid-v4l2
dtoverlay=vc4-kms-v3d,cma-512

This is KMS, not Fake KMS. Also, gpu_mem doesn't do anything anymore for the KMS driver, so don't bother using it.

Akrai commented 2 years ago

Has HEVC stopped working again on 5.15? No issues on 5.10.63-v8+ so far, not gonna upgrade any time soon it seems

I also have:

dtoverlay=rpivid-v4l2 dtoverlay=vc4-kms-v3d,cma-512

popcornmix commented 2 years ago

HEVC is fine on 5.15 for me (on bullseye).

johanbluecreek commented 2 years ago

Thanks for the quick replies everyone, setting the dtoverlay as suggested (my config now being)

$ cat /boot/config.txt | grep -v ^# | grep -v ^$
disable_overscan=1
dtparam=i2c_arm=on
dtparam=audio=on
[pi4]
dtoverlay=rpivid-v4l2
dtoverlay=vc4-kms-v3d,cma-512
[all]
enable_uart=1
hdmi_enable_4kp60=1

works even worse than before. Playing a video in Kodi gives me a prompt saying

Playback Failed One or more items failed to play. Check the log for more information about this message.

and the relevant snippet from the log

2022-03-08 15:20:49.983 T:1239     INFO <general>: Loading skin file: MyVideoNav.xml, load type: KEEP_IN_MEMORY
2022-03-08 15:20:50.121 T:1286    ERROR <general>: CFileCache::Open - </home/pi/storage/videos/video.mkv> failed to open
2022-03-08 15:20:50.121 T:1286    ERROR <general>: InputStream: Error opening, /home/pi/storage/videos/video.mkv
2022-03-08 15:20:52.638 T:1239     INFO <general>: VideoPlayer::OpenFile: /home/pi/storage/videos/video.mkv
2022-03-08 15:20:52.640 T:1324     INFO <general>: Creating InputStream
2022-03-08 15:20:52.667 T:1324    ERROR <general>: CFileCache::Open - </home/pi/storage/videos/video.mkv> failed to open
2022-03-08 15:20:52.667 T:1324    ERROR <general>: CVideoPlayer::OpenInputStream - error opening [/home/pi/storage/videos/video.mkv]
2022-03-08 15:20:52.667 T:1324     INFO <general>: CVideoPlayer::OnExit()
2022-03-08 15:20:52.800 T:1239     INFO <general>: Loading skin file: DialogConfirm.xml, load type: KEEP_IN_MEMORY
2022-03-08 15:20:52.806 T:1239     INFO <general>: CVideoPlayer::CloseFile()
2022-03-08 15:20:52.806 T:1239     INFO <general>: VideoPlayer: waiting for threads to exit
2022-03-08 15:20:52.807 T:1239     INFO <general>: VideoPlayer: finished waiting
2022-03-08 15:20:53.909 T:1270     INFO <general>: CPythonInvoker(5, /home/pi/.kodi/addons/service.xbmc.versioncheck/resources/lib/runner.py): script successfully run
2022-03-08 15:20:53.981 T:1270     INFO <general>: Python interpreter stopped

This is also on bullseye.

Any suggestions?

andrebrait commented 2 years ago

@johanbluecreek

Could you check if all your firmware is up to date?

Maybe run sudo rpi-eeprom-update and see if there's anything to update there. If so, you can update it with sudo rpi-eeprom-update -a and then systemctl reboot it.

Keep the Pi powered on the entire time, of course.

Also, can you enable debug logging on Kodi and try again? These error logs don't have enough information.

popcornmix commented 2 years ago

@johanbluecreek your issue isn't related to this bug report.

CFileCache::Open - </home/pi/storage/videos/video.mkv> failed to open

the video file couldn't be opened at all - it never gets near the hevc decoder. Now, I don't know if /home/pi/storage/videos/ is on the sdcard, or a mounted USB or network drive, but that should be where you are looking to fix it.

johanbluecreek commented 2 years ago

@popcornmix Sorry, I messed up the permissions on that file. I fixed that and moved the file to the SD card to not allow the mount to play a role. The log file is now

2022-03-09 10:12:01.110 T:1114     INFO <general>: CActiveAESink::OpenSink - initialize sink
2022-03-09 10:12:01.111 T:1114     INFO <general>: CAESinkALSA::Initialize - Attempting to open device "default"
2022-03-09 10:12:01.119 T:1114     INFO <general>: CAESinkALSA::Initialize - Opened device "default"
2022-03-09 10:12:01.120 T:1114     INFO <general>: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
2022-03-09 10:12:01.120 T:1114     INFO <general>: CAESinkALSA::InitializeHW - Using data format AE_FMT_S16NE
2022-03-09 10:12:01.145 T:1109     INFO <general>: VideoPlayer::OpenFile: /home/pi/video_sd/vid.mkv
2022-03-09 10:12:01.147 T:1222     INFO <general>: Creating InputStream
2022-03-09 10:12:01.173 T:1222     INFO <general>: Creating Demuxer
2022-03-09 10:12:01.332 T:1222     INFO <general>: ffmpeg[0x4c5edb8X]: Input #0, matroska,webm, from '/home/pi/video_sd/vid.mkv':
2022-03-09 10:12:01.332 T:1222     INFO <general>: ffmpeg[0x4c5edb8X]:   Metadata:
2022-03-09 10:12:01.332 T:1222     INFO <general>: ffmpeg[0x4c5edb8X]:     encoder         : libebml v1.3.4 + libmatroska v1.5.0
2022-03-09 10:12:01.332 T:1222     INFO <general>: ffmpeg[0x4c5edb8X]:     creation_time   : 2019-10-24T02:02:28.000000Z
2022-03-09 10:12:01.332 T:1222     INFO <general>: ffmpeg[0x4c5edb8X]:   Duration: 00:31:23.12, start: 0.000000, bitrate: 1384 kb/s
2022-03-09 10:12:01.333 T:1222     INFO <general>: ffmpeg[0x4c5edb8X]:     Stream #0:0: Video: hevc (Main 10), yuv420p10le(tv, bt709/unknown/unknown), 1920x800, SAR 1:1 DAR 12:5, 23.98 fps, 23.98 tbr, 1k tbn, 23.98 tbc (default)
2022-03-09 10:12:01.333 T:1222     INFO <general>: ffmpeg[0x4c5edb8X]:     Stream #0:1(eng): Audio: aac (HE-AAC), 48000 Hz, 5.1, fltp
2022-03-09 10:12:01.333 T:1222     INFO <general>: ffmpeg[0x4c5edb8X]:     Stream #0:2(eng): Subtitle: subrip
2022-03-09 10:12:01.337 T:1222     INFO <general>: Opening stream: 0 source: 256
2022-03-09 10:12:01.337 T:1222     INFO <general>: Creating video codec with codec id: 173
2022-03-09 10:12:01.337 T:1222     INFO <general>: CDVDVideoCodecDRMPRIME::Open - using decoder HEVC (High Efficiency Video Coding)
2022-03-09 10:12:01.340 T:1222     INFO <general>: Creating video thread
2022-03-09 10:12:01.340 T:1230     INFO <general>: running thread: video_thread
2022-03-09 10:12:01.340 T:1222     INFO <general>: Opening stream: 1 source: 256
2022-03-09 10:12:01.341 T:1222     INFO <general>: Finding audio codec for: 86018
2022-03-09 10:12:01.350 T:1222     INFO <general>: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac
2022-03-09 10:12:01.350 T:1222     INFO <general>: Creating audio thread
2022-03-09 10:12:01.350 T:1231     INFO <general>: running thread: CVideoPlayerAudio::Process()
2022-03-09 10:12:01.351 T:1222     INFO <general>: Opening stream: 2 source: 256
2022-03-09 10:12:01.364 T:1231     INFO <general>: Skipped 1 duplicate messages..
2022-03-09 10:12:01.364 T:1231     INFO <general>: Creating audio stream (codec id: 86018, channels: 6, sample rate: 48000, no pass-through)
2022-03-09 10:12:01.381 T:1114     INFO <general>: CActiveAESink::OpenSink - initialize sink
2022-03-09 10:12:01.581 T:1114     INFO <general>: CAESinkALSA::Initialize - Attempting to open device "default"
2022-03-09 10:12:01.584 T:1114     INFO <general>: CAESinkALSA::Initialize - Opened device "default"
2022-03-09 10:12:01.585 T:1114     INFO <general>: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
2022-03-09 10:12:01.585 T:1114     INFO <general>: CAESinkALSA::InitializeHW - Using data format AE_FMT_S16NE
2022-03-09 10:12:11.381 T:1222    ERROR <general>: Skipped 1 duplicate messages..
2022-03-09 10:12:11.381 T:1222    ERROR <general>: ffmpeg[0x4c5edb8X]: [matroska,webm] File ended prematurely
2022-03-09 10:12:21.515 T:1109     INFO <general>: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY
2022-03-09 10:12:24.817 T:1222     INFO <general>: Process - eof reading from demuxer
2022-03-09 10:12:24.817 T:1222     INFO <general>: CVideoPlayer::OnExit()
2022-03-09 10:12:24.817 T:1222     INFO <general>: VideoPlayer: eof, waiting for queues to empty
2022-03-09 10:12:24.817 T:1222     INFO <general>: Closing stream player 1
2022-03-09 10:12:24.817 T:1222     INFO <general>: CDVDMessageQueue(audio)::WaitUntilEmpty
2022-03-09 10:12:24.818 T:1222     INFO <general>: Waiting for audio thread to exit
2022-03-09 10:12:24.818 T:1231     INFO <general>: thread end: CVideoPlayerAudio::OnExit()
2022-03-09 10:12:24.818 T:1222     INFO <general>: Closing audio device
2022-03-09 10:12:25.201 T:1222     INFO <general>: Skipped 2 duplicate messages..
2022-03-09 10:12:25.201 T:1222     INFO <general>: Deleting audio codec
2022-03-09 10:12:25.201 T:1222     INFO <general>: Closing stream player 2
2022-03-09 10:12:25.201 T:1222     INFO <general>: CDVDMessageQueue(video)::WaitUntilEmpty
2022-03-09 10:12:25.202 T:1222     INFO <general>: waiting for video thread to exit
2022-03-09 10:12:25.202 T:1230    ERROR <general>: Got MSGQ_ABORT or MSGO_IS_ERROR return true
2022-03-09 10:12:25.202 T:1230     INFO <general>: thread end: video_thread
2022-03-09 10:12:25.202 T:1222     INFO <general>: deleting video codec
2022-03-09 10:12:25.224 T:1222     INFO <general>: Closing stream player 3
2022-03-09 10:12:25.258 T:1109     INFO <general>: CVideoPlayer::CloseFile()
2022-03-09 10:12:25.259 T:1109     INFO <general>: VideoPlayer: waiting for threads to exit
2022-03-09 10:12:25.259 T:1109     INFO <general>: VideoPlayer: finished waiting
2022-03-09 10:12:25.259 T:1109     INFO <general>: CVideoPlayer::CloseFile()
2022-03-09 10:12:25.259 T:1109     INFO <general>: VideoPlayer: waiting for threads to exit
2022-03-09 10:12:25.259 T:1109     INFO <general>: VideoPlayer: finished waiting
2022-03-09 10:12:25.550 T:1114     INFO <general>: CActiveAESink::OpenSink - initialize sink
2022-03-09 10:12:25.720 T:1114     INFO <general>: CAESinkALSA::Initialize - Attempting to open device "default"
2022-03-09 10:12:25.724 T:1114     INFO <general>: CAESinkALSA::Initialize - Opened device "default"
2022-03-09 10:12:25.725 T:1114     INFO <general>: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
2022-03-09 10:12:25.725 T:1114     INFO <general>: CAESinkALSA::InitializeHW - Using data format AE_FMT_S16NE

When starting to play the video, I get the "busy" animation thing for a few seconds, then it starts playing the video for a few seconds, and then stops.

I also noticed that when I switch to vc4-kms-v3d from vc4-fkms-v3d all audio in Kodi is gone, fixed by last comment in #4543 , meaning my config.txt is now

$ cat /boot/config.txt | grep -v ^# | grep -v ^$
disable_overscan=1
dtparam=i2c_arm=on
[pi4]
dtoverlay=vc4-kms-v3d,cma-512
dtoverlay=rpivid-v4l2
[all]
enable_uart=1
hdmi_enable_4kp60=1

@andrebrait

Maybe run sudo rpi-eeprom-update and see if there's anything to update there. If so, you can update it with sudo rpi-eeprom-update -a and then systemctl reboot it.

There was only a bootloader update available. I performed the update anyway, and it had no effect.

Also, can you enable debug logging on Kodi and try again? These error logs don't have enough information.

The log file above is fixed, but if debug is still needed, see: https://pastebin.com/k24eszTd

andrebrait commented 2 years ago

@johanbluecreek

According to the logs, it seems your file is broken. It says it ended prematurely. I think it may have reached EOF sooner than it expected.

Are you able to play the file on your computer or some other device?

The only difference between our config.txt files os that I still have the dtparam=audio=on line and I got dtparam=i2c_arm=on commented out.

popcornmix commented 2 years ago

The log did contain

2022-03-09 10:12:01.182 T:1222    DEBUG <general>: Open - probing detected format [matroska,webm]
2022-03-09 10:12:01.187 T:1225    DEBUG <general>: Thread Terminating with Exception: std::bad_alloc

which suggests an allocation failure (this is a normal malloc style alloc, rather than cma or gpu_mem) Your debug log file was cut down so didn't show if there were any harmful advancedsettings. Have you changed default caching behaviour? Too large a file cache could fail with allocation error.

johanbluecreek commented 2 years ago

The log did contain

2022-03-09 10:12:01.182 T:1222    DEBUG <general>: Open - probing detected format [matroska,webm]
2022-03-09 10:12:01.187 T:1225    DEBUG <general>: Thread Terminating with Exception: std::bad_alloc

which suggests an allocation failure (this is a normal malloc style alloc, rather than cma or gpu_mem) Your debug log file was cut down so didn't show if there were any harmful advancedsettings. Have you changed default caching behaviour? Too large a file cache could fail with allocation error.

I had changed the default. Changing to <memorysize>1073741824</memorysize> there seems to be no issue left. Thank you very much.