Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Qt5 app with audio freezes Raspberry Pi 400 #1516

Open
piec opened this issue Dec 21, 2020 · 29 comments
Open

Qt5 app with audio freezes Raspberry Pi 400 #1516

piec opened this issue Dec 21, 2020 · 29 comments

Comments

@piec
Copy link

piec commented Dec 21, 2020

Hi,

I'm using a Raspberry Pi 400 with default Raspberry Pi OS provided in the kit's MicroSD card. I upgraded the system today.
The system freezes after ~1s after starting gcompris an educative software for kids (qt), always reproducible.
To my opinion It seems very much related to a firmware getting stuck according to the kernel log.
This bug was initially reported here https://bugs.kde.org/show_bug.cgi?id=429125 and interestingly when running without audio everything works fine (as discovered by another user).

To reproduce

  • Install Raspberry Pi OS on a rpi 400
  • sudo apt update && sudo apt upgrade && sudo apt install gcompris-qt
  • gcompris-qt -m: without sound, works
  • gcompris-qt -s: with sound, freezes system

Could the audio make the firmware freeze and make further firmware calls fail?

Cheers,
Pierre


Expected behaviour
System does not freeze

Actual behaviour
System freezes

System

raspinfo before crashing
pi@raspberrypi:~ $ raspinfo
System Information
------------------

Raspberry Pi 400 Rev 1.0
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"

Raspberry Pi reference 2020-08-20
Generated using pi-gen, https://github.com/RPi-Distro/pi-gen, 9a3a10bf1019ebb2d59053564dc6b90068bad27d, stage5

Linux raspberrypi 5.4.79-v7l+ #1373 SMP Mon Nov 23 13:27:40 GMT 2020 armv7l GNU/Linux
Revision	: c03130
Serial		: 10000000b4f1f308
Model		: Raspberry Pi 400 Rev 1.0
Throttled flag  : throttled=0x0
Camera          : supported=0 detected=0

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

Nov 30 2020 22:12:08
Copyright (c) 2012 Broadcom
version ab1181cc0cb6df52bfae3b1d3fef0ce7c325166c (clean) (release) (start)

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

Filesystem information
----------------------
Sys. de fichiers blocs de 1K Utilisé Disponible Uti% Monté sur
/dev/root           14986672 9417256    4873540  66% /
devtmpfs             1827800       0    1827800   0% /dev
tmpfs                1959896       0    1959896   0% /dev/shm
tmpfs                1959896    8748    1951148   1% /run
tmpfs                   5120       4       5116   1% /run/lock
tmpfs                1959896       0    1959896   0% /sys/fs/cgroup
/dev/mmcblk0p1        258095   55051     203045  22% /boot
tmpfs                 391976       0     391976   0% /run/user/109
tmpfs                 391976       0     391976   0% /run/user/1000

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

Package version information
---------------------------
raspberrypi-ui-mods:
  Installé : 1.20201210
raspberrypi-sys-mods:
  Installé : 20201026
openbox:
  Installé : 3.6.1-8+rpt4
lxpanel:
  Installé : 0.10.0-2+rpt13
pcmanfm:
  Installé : 1.3.1-1+rpt24
rpd-plym-splash:
  Installé : 0.25

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

eth0: 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

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  (Boucle locale)
        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=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.y.y.y  prefixlen 64  scopeid 0x0<global>
        inet6 y.y.y.y.y.y.y.y  prefixlen 64  scopeid 0x0<global>
        inet6 y::y.y.y.y  prefixlen 64  scopeid 0x20<link>
        ether m.m.m.m  txqueuelen 1000  (Ethernet)
        RX packets 2356  bytes 1225500 (1.1 MiB)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 1375  bytes 171046 (167.0 KiB)
        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
        |__ Port 1: Dev 3, If 0, Class=Human Interface Device, Driver=usbhid, 1.5M
        |__ Port 4: Dev 4, If 1, Class=Human Interface Device, Driver=usbhid, 1.5M
        |__ Port 4: Dev 4, If 0, Class=Human Interface Device, Driver=usbhid, 1.5M

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

arm_freq=1800
audio_pwm_mode=514
config_hdmi_boost=5
core_freq=500
core_freq_min=200
disable_commandline_tags=2
disable_l2cache=1
disable_overscan=1
display_hdmi_rotate=-1
display_lcd_rotate=-1
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=0x11170
over_voltage_avs=-20000
pause_burst_frames=1
program_serial_random=1
total_mem=4096
hdmi_force_cec_address:0=65535
hdmi_force_cec_address:1=65535
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:E6:67:F5 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  console=ttyS0,115200 console=tty1 root=PARTUUID=1c27fe2f-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait quiet splash plymouth.ignore-serial-consoles

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=1 fsel=0 func=INPUT pull=NONE
GPIO 42: level=1 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=0 fsel=4 alt=0 func=PWM0_1 pull=UP
BANK2 (GPIO 46 to 53):
GPIO 46: level=0 fsel=0 func=INPUT pull=DOWN
GPIO 47: level=0 fsel=0 func=INPUT pull=DOWN
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
------------------

008643.619: arasan: arasan_emmc_open
008803.047: brfs: File read: /mfs/sd/config.txt
008803.831: brfs: File read: 1783 bytes
008898.875: brfs: File read: /mfs/sd/config.txt
008915.383: brfs: File read: 1783 bytes
008920.427: gpioman: gpioman_get_pin_num: pin FLASH_0_ENABLE not defined
008920.442: gpioman: gpioman_get_pin_num: pin FLASH_0_INDICATOR not defined
008920.472: gpioman: gpioman_get_pin_num: pin FLASH_0_ENABLE not defined
008920.490: gpioman: gpioman_get_pin_num: pin FLASH_0_INDICATOR not defined
009375.241: gpioman: gpioman_get_pin_num: pin DISPLAY_DSI_PORT not defined
009377.714: *** Restart logging
009381.529: hdmi: HDMI:hdmi_get_state is deprecated, use hdmi_get_display_state instead
009419.793: HDMI0: hdmi_pixel_encoding: 300000000
009419.806: HDMI1: hdmi_pixel_encoding: 300000000
009420.494: gpioman: gpioman_get_pin_num: pin CAMERA_0_SDA_PIN not defined
009420.506: gpioman: gpioman_get_pin_num: pin CAMERA_0_SCL_PIN not defined
009420.521: gpioman: gpioman_get_pin_num: pin CAMERA_0_I2C_PORT not defined
009424.778: dtb_file 'bcm2711-rpi-400.dtb'
009431.998: brfs: File read: /mfs/sd/bcm2711-rpi-400.dtb
009432.015: Loading 'bcm2711-rpi-400.dtb' to 0x100 size 0xba29
009444.173: brfs: File read: 47657 bytes
009457.015: brfs: File read: /mfs/sd/overlays/overlay_map.dtb
009514.046: brfs: File read: 1523 bytes
009518.442: brfs: File read: /mfs/sd/config.txt
009518.953: dtparam: audio=on
009528.185: brfs: File read: 1783 bytes
009542.512: brfs: File read: /mfs/sd/overlays/vc4-fkms-v3d.dtbo
009559.412: Loaded overlay 'vc4-fkms-v3d'
009600.981: brfs: File read: 1446 bytes
009604.882: brfs: File read: /mfs/sd/cmdline.txt
009604.929: Read command line from file 'cmdline.txt':
009604.945: 'console=serial0,115200 console=tty1 root=PARTUUID=1c27fe2f-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait quiet splash plymouth.ignore-serial-consoles'
010691.225: brfs: File read: 166 bytes
011944.100: brfs: File read: /mfs/sd/kernel7l.img
011944.139: Loading 'kernel7l.img' to 0x8000 size 0x5dc538
011944.189: Device tree loaded to 0x2eff4000 (size 0xbfc8)
011952.842: gpioman: gpioman_get_pin_num: pin SDCARD_CONTROL_POWER not defined
015157.662: vchiq_core: vchiq_init_state: slot_zero = 0xded80000, is_master = 1
015161.194: hdmi: HDMI:hdmi_get_state is deprecated, use hdmi_get_display_state instead
015168.773: TV service:host side not connected, dropping notification 0x00000002, 0x00000001, 0x00000010
020415.457: TV service:host side not connected, dropping notification 0x00000008, 0x00000004, 0x00000001

dmesg log
---------

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 5.4.79-v7l+ (dom@buildbot) (gcc version 8.4.0 (Ubuntu/Linaro 8.4.0-3ubuntu1)) #1373 SMP Mon Nov 23 13:27:40 GMT 2020
[    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 400 Rev 1.0
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] Reserved memory: created CMA memory pool at 0x000000001ec00000, size 256 MiB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[    0.000000] On node 0 totalpages: 1012736
[    0.000000]   DMA zone: 2304 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 196608 pages, LIFO batch:63
[    0.000000]   HighMem zone: 816128 pages, LIFO batch:63
[    0.000000] percpu: Embedded 20 pages/cpu s49804 r8192 d23924 u81920
[    0.000000] pcpu-alloc: s49804 r8192 d23924 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: 1010432
[    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  console=ttyS0,115200 console=tty1 root=PARTUUID=1c27fe2f-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait quiet splash plymouth.ignore-serial-consoles
[    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 0x17cc0000-0x1bcc0000] (64MB)
[    0.000000] Memory: 3655604K/4050944K available (10240K kernel code, 739K rwdata, 2816K rodata, 2048K init, 854K bss, 133196K reserved, 262144K cma-reserved, 3264512K highmem)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 31111 entries in 61 pages
[    0.000000] rcu: Hierarchical RCU implementation.
[    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+0x344/0x518 with crng_init=0
[    0.000008] sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns
[    0.000023] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns
[    0.000103] bcm2835: system timer (irq = 17)
[    0.000752] arch_timer: cp15 timer(s) running at 54.00MHz (phys).
[    0.000768] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns
[    0.000785] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns
[    0.000797] Switching to timer-based delay loop, resolution 18ns
[    0.001049] Console: colour dummy device 80x30
[    0.001084] printk: console [tty1] enabled
[    0.001137] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000)
[    0.001159] pid_max: default: 32768 minimum: 301
[    0.001310] LSM: Security Framework initializing
[    0.001511] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.001536] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.002941] Disabling memory control group subsystem
[    0.003067] CPU: Testing write buffer coherency: ok
[    0.003603] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.004531] Setting up static identity map for 0x200000 - 0x20003c
[    0.004738] rcu: Hierarchical SRCU implementation.
[    0.005445] smp: Bringing up secondary CPUs ...
[    0.006704] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.008098] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.009415] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.009575] smp: Brought up 1 node, 4 CPUs
[    0.009592] SMP: Total of 4 processors activated (432.00 BogoMIPS).
[    0.009607] CPU: All CPU(s) started in HYP mode.
[    0.009620] CPU: Virtualization extensions available.
[    0.010492] devtmpfs: initialized
[    0.024652] VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0
[    0.024926] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.024956] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.032164] pinctrl core: initialized pinctrl subsystem
[    0.033215] NET: Registered protocol family 16
[    0.037362] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.037970] audit: initializing netlink subsys (disabled)
[    0.038266] audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1
[    0.039441] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.039456] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.039815] Serial: AMBA PL011 UART driver
[    0.043591] bcm2835-mbox fe00b880.mailbox: mailbox enabled
[    0.060848] raspberrypi-firmware soc:firmware: Attached to firmware from 2020-11-30 22:12, variant start
[    0.070861] raspberrypi-firmware soc:firmware: Firmware hash is ab1181cc0cb6df52bfae3b1d3fef0ce7c325166c
[    0.126421] bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1
[    0.131716] vgaarb: loaded
[    0.132208] SCSI subsystem initialized
[    0.132427] usbcore: registered new interface driver usbfs
[    0.132496] usbcore: registered new interface driver hub
[    0.132625] usbcore: registered new device driver usb
[    0.132956] usb_phy_generic phy: phy supply vcc not found, using dummy regulator
[    0.134928] clocksource: Switched to clocksource arch_sys_counter
[    0.896856] VFS: Disk quotas dquot_6.6.0
[    0.896962] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.897135] FS-Cache: Loaded
[    0.897358] CacheFiles: Loaded
[    0.898364] simple-framebuffer 3e402000.framebuffer: framebuffer at 0x3e402000, 0x7f8000 bytes, mapped to 0x(ptrval)
[    0.898383] simple-framebuffer 3e402000.framebuffer: format=a8r8g8b8, mode=1920x1080x32, linelength=7680
[    0.898896] Console: switching to colour frame buffer device 240x67
[    0.909535] simple-framebuffer 3e402000.framebuffer: fb0: simplefb registered!
[    0.920049] thermal_sys: Registered thermal governor 'step_wise'
[    0.920437] NET: Registered protocol family 2
[    0.921191] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
[    0.921225] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    0.921286] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.921360] TCP: Hash tables configured (established 8192 bind 8192)
[    0.921528] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[    0.921563] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[    0.921839] NET: Registered protocol family 1
[    0.922636] RPC: Registered named UNIX socket transport module.
[    0.922650] RPC: Registered udp transport module.
[    0.922662] RPC: Registered tcp transport module.
[    0.922675] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.922697] PCI: CLS 0 bytes, default 64
[    0.924710] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
[    0.927064] Initialise system trusted keyrings
[    0.927315] workingset: timestamp_bits=14 max_order=20 bucket_order=6
[    0.938496] FS-Cache: Netfs 'nfs' registered for caching
[    0.939247] NFS: Registering the id_resolver key type
[    0.939288] Key type id_resolver registered
[    0.939302] Key type id_legacy registered
[    0.939326] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.940483] Key type asymmetric registered
[    0.940497] Asymmetric key parser 'x509' registered
[    0.940688] bounce: pool size: 64 pages
[    0.940738] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[    0.940984] io scheduler mq-deadline registered
[    0.941001] io scheduler kyber registered
[    0.945308] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
[    0.945332] brcm-pcie fd500000.pcie:   No bus range found for /scb/pcie@7d500000, using [bus 00-ff]
[    0.945406] brcm-pcie fd500000.pcie:      MEM 0x0600000000..0x0603ffffff -> 0x00f8000000
[    0.945477] brcm-pcie fd500000.pcie:   IB MEM 0x0000000000..0x00ffffffff -> 0x0200000000
[    1.007051] brcm-pcie fd500000.pcie: link up, 5 GT/s x1 (SSC)
[    1.007379] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
[    1.007398] pci_bus 0000:00: root bus resource [bus 00-ff]
[    1.007418] pci_bus 0000:00: root bus resource [mem 0x600000000-0x603ffffff] (bus address [0xf8000000-0xfbffffff])
[    1.007479] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
[    1.007755] pci 0000:00:00.0: PME# supported from D0 D3hot
[    1.011079] PCI: bus0: Fast back to back transfers disabled
[    1.011309] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
[    1.011458] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
[    1.011892] pci 0000:01:00.0: PME# supported from D0 D3hot
[    1.015239] PCI: bus1: Fast back to back transfers disabled
[    1.015303] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
[    1.015327] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
[    1.015410] pci 0000:00:00.0: PCI bridge to [bus 01]
[    1.015435] pci 0000:00:00.0:   bridge window [mem 0x600000000-0x6000fffff]
[    1.015772] pcieport 0000:00:00.0: enabling device (0140 -> 0142)
[    1.016022] pcieport 0000:00:00.0: PME: Signaling with IRQ 55
[    1.016424] pcieport 0000:00:00.0: AER: enabled with IRQ 55
[    1.016766] pci 0000:01:00.0: enabling device (0140 -> 0142)
[    1.067509] pci 0000:01:00.0: quirk_usb_early_handoff+0x0/0x800 took 49569 usecs
[    1.075470] iproc-rng200 fe104000.rng: hwrng registered
[    1.075821] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    1.076546] vc-sm: Videocore shared memory driver
[    1.077132] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000
[    1.089802] brd: module loaded
[    1.102759] loop: module loaded
[    1.104265] Loading iSCSI transport class v2.0-870.
[    1.106498] libphy: Fixed MDIO Bus: probed
[    1.107132] bcmgenet fd580000.ethernet: failed to get enet clock
[    1.107154] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000
[    1.107177] bcmgenet fd580000.ethernet: failed to get enet-wol clock
[    1.107199] bcmgenet fd580000.ethernet: failed to get enet-eee clock
[    1.107226] bcmgenet: Skipping UMAC reset
[    1.125010] libphy: bcmgenet MII bus: probed
[    1.215038] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus
[    1.216134] usbcore: registered new interface driver r8152
[    1.216200] usbcore: registered new interface driver lan78xx
[    1.216261] usbcore: registered new interface driver smsc95xx
[    1.216703] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.216740] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
[    1.219198] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0000003000000890
[    1.220500] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04
[    1.220517] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.220533] usb usb1: Product: xHCI Host Controller
[    1.220548] usb usb1: Manufacturer: Linux 5.4.79-v7l+ xhci-hcd
[    1.220563] usb usb1: SerialNumber: 0000:01:00.0
[    1.221193] hub 1-0:1.0: USB hub found
[    1.221289] hub 1-0:1.0: 1 port detected
[    1.221863] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.221889] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[    1.221914] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[    1.222411] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.04
[    1.222428] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.222444] usb usb2: Product: xHCI Host Controller
[    1.222459] usb usb2: Manufacturer: Linux 5.4.79-v7l+ xhci-hcd
[    1.222474] usb usb2: SerialNumber: 0000:01:00.0
[    1.223099] hub 2-0:1.0: USB hub found
[    1.223170] hub 2-0:1.0: 4 ports detected
[    1.224667] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    1.225029] dwc_otg: FIQ enabled
[    1.225042] dwc_otg: NAK holdoff enabled
[    1.225055] dwc_otg: FIQ split-transaction FSM enabled
[    1.225073] Module dwc_common_port init
[    1.225568] usbcore: registered new interface driver uas
[    1.225689] usbcore: registered new interface driver usb-storage
[    1.225902] mousedev: PS/2 mouse device common for all mice
[    1.229447] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    1.233518] sdhci: Secure Digital Host Controller Interface driver
[    1.233531] sdhci: Copyright(c) Pierre Ossman
[    1.234158] mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe
[    1.234744] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.238729] ledtrig-cpu: registered to indicate activity on CPUs
[    1.239058] hidraw: raw HID events driver (C) Jiri Kosina
[    1.239255] usbcore: registered new interface driver usbhid
[    1.239268] usbhid: USB HID core driver
[    1.240392] vchiq: vchiq_init_state: slot_zero = (ptrval)
[    1.242518] [vc_sm_connected_init]: start
[    1.252650] [vc_sm_connected_init]: end - returning 0
[    1.254957] Initializing XFRM netlink socket
[    1.254999] NET: Registered protocol family 17
[    1.255110] Key type dns_resolver registered
[    1.255555] Registering SWP/SWPB emulation handler
[    1.255933] registered taskstats version 1
[    1.255954] Loading compiled-in X.509 certificates
[    1.256491] Key type ._fscrypt registered
[    1.256505] Key type .fscrypt registered
[    1.267471] uart-pl011 fe201000.serial: cts_event_workaround enabled
[    1.267551] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 29, base_baud = 0) is a PL011 rev2
[    1.273777] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    1.274786] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0
[    1.274803] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated
[    1.328489] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[    1.330162] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    1.331836] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    1.334837] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[    1.336512] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    1.343001] mmc0: SDHCI controller on fe340000.emmc2 [fe340000.emmc2] using ADMA
[    1.346317] of_cfs_init
[    1.346469] of_cfs_init: OK
[    1.347729] Waiting for root device PARTUUID=1c27fe2f-02...
[    1.387137] random: fast init done
[    1.420312] mmc1: new high speed SDIO card at address 0001
[    1.454273] mmc0: new ultra high speed DDR50 SDHC card at address aaaa
[    1.455324] mmcblk0: mmc0:aaaa SC16G 14.8 GiB
[    1.458666]  mmcblk0: p1 p2
[    1.474477] EXT4-fs (mmcblk0p2): INFO: recovery required on readonly filesystem
[    1.474493] EXT4-fs (mmcblk0p2): write access will be enabled during recovery
[    1.584974] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[    1.767650] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
[    1.767668] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    1.767703] usb 1-1: Product: USB2.0 Hub
[    1.769819] hub 1-1:1.0: USB hub found
[    1.770114] hub 1-1:1.0: 4 ports detected
[    1.807606] EXT4-fs (mmcblk0p2): recovery complete
[    1.812347] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[    1.812438] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[    1.813315] devtmpfs: mounted
[    1.822203] Freeing unused kernel memory: 2048K
[    1.822504] Run /sbin/init as init process
[    2.095041] usb 1-1.1: new low-speed USB device number 3 using xhci_hcd
[    2.233154] systemd[1]: System time before build time, advancing clock.
[    2.234996] usb 1-1.1: New USB device found, idVendor=093a, idProduct=2510, bcdDevice= 1.00
[    2.235023] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    2.235040] usb 1-1.1: Product: USB Optical Mouse
[    2.235057] usb 1-1.1: Manufacturer: PixArt
[    2.245161] input: PixArt USB Optical Mouse as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1:1.0/0003:093A:2510.0001/input/input0
[    2.245693] hid-generic 0003:093A:2510.0001: input,hidraw0: USB HID v1.11 Mouse [PixArt USB Optical Mouse] on usb-0000:01:00.0-1.1/input0
[    2.331504] NET: Registered protocol family 10
[    2.332915] Segment Routing with IPv6
[    2.344998] usb 1-1.4: new low-speed USB device number 4 using xhci_hcd
[    2.364894] 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)
[    2.365805] systemd[1]: Detected architecture arm.
[    2.431796] systemd[1]: Set hostname to <raspberrypi>.
[    2.536495] usb 1-1.4: New USB device found, idVendor=04d9, idProduct=0007, bcdDevice= 1.61
[    2.536516] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    2.536534] usb 1-1.4: Product: Raspberry Pi Internal Keyboard
[    2.536550] usb 1-1.4: Manufacturer:
[    2.567206] input:   Raspberry Pi Internal Keyboard as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.0/0003:04D9:0007.0002/input/input1
[    2.636075] hid-generic 0003:04D9:0007.0002: input,hidraw1: USB HID v1.11 Keyboard [  Raspberry Pi Internal Keyboard] on usb-0000:01:00.0-1.4/input0
[    2.659082] input:   Raspberry Pi Internal Keyboard as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.1/0003:04D9:0007.0003/input/input2
[    2.726075] hid-generic 0003:04D9:0007.0003: input,hidraw2: USB HID v1.11 Device [  Raspberry Pi Internal Keyboard] on usb-0000:01:00.0-1.4/input1
[    3.194005] random: systemd: uninitialized urandom read (16 bytes read)
[    3.207169] random: systemd: uninitialized urandom read (16 bytes read)
[    3.213260] systemd[1]: Listening on Journal Socket (/dev/log).
[    3.213813] random: systemd: uninitialized urandom read (16 bytes read)
[    3.213895] systemd[1]: Reached target Swap.
[    3.214523] systemd[1]: Listening on udev Kernel Socket.
[    3.215213] systemd[1]: Listening on Syslog Socket.
[    3.217501] systemd[1]: Created slice User and Session Slice.
[    3.218305] systemd[1]: Listening on udev Control Socket.
[    3.218447] systemd[1]: Reached target Slices.
[    3.332899] i2c /dev entries driver
[    3.846696] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[    3.985395] systemd-journald[118]: Received request to flush runtime journal from PID 1
[    4.594640] rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000
[    4.597369] rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000
[    4.597853] rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000
[    4.598274] rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000
[    4.690527] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[    4.692765] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[    4.699241] [vc_sm_connected_init]: start
[    4.703947] mc: Linux media interface: v0.10
[    4.708247] [vc_sm_connected_init]: installed successfully
[    4.774153] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[    4.896154] videodev: Linux video capture interface: v2.00
[    4.915067] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    4.929414] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[    4.941996] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[    4.950466] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    4.974559] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[    5.003348] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[    5.003405] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[    5.026695] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[    5.026763] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[    5.034894] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[    5.036251] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[    5.158301] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[    5.158849] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[    5.159330] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[    5.159790] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[    5.159820] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    5.159848] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    5.159869] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    5.159891] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    5.162926] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[    5.192960] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[    5.196080] bcm2835_audio bcm2835_audio: card created with 8 channels
[    5.220556] [drm] Initialized v3d 1.0.0 20180419 for fec00000.v3d on minor 0
[    5.357079] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[    5.412109] brcmfmac: F1 signature read @0x18000000=0x15294345
[    5.425398] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43456-sdio for chip BCM4345/9
[    5.426411] usbcore: registered new interface driver brcmfmac
[    5.445843] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43456-sdio.raspberrypi,400.txt failed with error -2
[    5.465791] vc4-drm gpu: bound fe600000.firmwarekms (ops vc4_fkms_ops [vc4])
[    5.465819] checking generic (3e402000 7f8000) vs hw (0 ffffffffffffffff)
[    5.465834] fb0: switching to vc4drmfb from simple
[    5.471390] Console: switching to colour dummy device 80x30
[    5.476506] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[    5.476521] [drm] No driver support for vblank timestamp query.
[    5.476534] [drm] Setting vblank_disable_immediate to false because get_vblank_timestamp == NULL
[    5.479565] [drm] Initialized vc4 0.0.0 20140616 for gpu on minor 1
[    5.535364] Console: switching to colour frame buffer device 240x67
[    5.535412] vc4-drm gpu: fb0: vc4drmfb frame buffer device
[    5.660756] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43456-sdio for chip BCM4345/9
[    5.684371] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/9 wl0: May 14 2020 17:26:08 version 7.84.17.1 (r871554) FWID 01-3d9e1d87
[    7.601191] random: crng init done
[    7.601209] random: 7 urandom warning(s) missed due to ratelimiting
[    7.754238] uart-pl011 fe201000.serial: no DMA platform data
[    7.852244] 8021q: 802.1Q VLAN Support v1.8
[    8.135031] Adding 102396k swap on /var/swap.  Priority:-2 extents:1 across:102396k SSFS
[    8.362381] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
[    8.769122] bcmgenet: Skipping UMAC reset
[    8.777250] bcmgenet fd580000.ethernet: configuring instance for external RGMII
[    8.777699] bcmgenet fd580000.ethernet eth0: Link is Down
[    9.565888] warning: process `colord-sane' used the deprecated sysctl system call with 8.1.2.
[   10.392983] broken atomic modeset userspace detected, disabling atomic
[   12.794519] fuse: init (API version 7.31)
[   13.676664] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   13.707530] Bluetooth: Core ver 2.22
[   13.707570] NET: Registered protocol family 31
[   13.707575] Bluetooth: HCI device and connection manager initialized
[   13.707588] Bluetooth: HCI socket layer initialized
[   13.707596] Bluetooth: L2CAP socket layer initialized
[   13.707609] Bluetooth: SCO socket layer initialized
[   13.712682] Bluetooth: HCI UART driver ver 2.3
[   13.712691] Bluetooth: HCI UART protocol H4 registered
[   13.712729] Bluetooth: HCI UART protocol Three-wire (H5) registered
[   13.712849] Bluetooth: HCI UART protocol Broadcom registered
[   13.847586] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[   13.847594] Bluetooth: BNEP filters: protocol multicast
[   13.847605] Bluetooth: BNEP socket layer initialized
[   15.307471] ICMPv6: process `dhcpcd' is using deprecated sysctl (syscall) net.ipv6.neigh.wlan0.retrans_time - use net.ipv6.neigh.wlan0.retrans_time_ms instead
  • Which model of Raspberry Pi? e.g. Pi3B+, PiZeroW
    Raspberry Pi 400
  • Which OS and version (cat /etc/rpi-issue)?
Raspberry Pi reference 2020-08-20
Generated using pi-gen, https://github.com/RPi-Distro/pi-gen, 9a3a10bf1019ebb2d59053564dc6b90068bad27d, stage5
  • Which firmware version (vcgencmd version)?
Nov 30 2020 22:12:08
Copyright (c) 2012 Broadcom
version ab1181cc0cb6df52bfae3b1d3fef0ce7c325166c (clean) (release) (start)
  • Which kernel version (uname -a)?
Linux raspberrypi 5.4.79-v7l+ #1373 SMP Mon Nov 23 13:27:40 GMT 2020 armv7l GNU/Linux

Logs
https://gist.github.com/piec/5cbec01161158bd2fb7cd180254c3949
https://gist.github.com/piec/90dc5e4696ecdc812faef6c8ee07a41e
Also see https://bugs.kde.org/show_bug.cgi?id=429125

Additional context

pi@raspberrypi:~ $ sudo rpi-eeprom-update
BCM2711 detected
VL805 firmware in bootloader EEPROM
BOOTLOADER: up-to-date
CURRENT: jeudi 3 septembre 2020, 12:11:43 (UTC+0000) (1599135103)
 LATEST: jeudi 3 septembre 2020, 12:11:43 (UTC+0000) (1599135103)
 FW DIR: /lib/firmware/raspberrypi/bootloader/critical
VL805: up-to-date
CURRENT: 000138a1
 LATEST: 000138a1
pi@raspberrypi:~ $ sudo rpi-eeprom-config
[all]
BOOT_UART=0
WAKE_ON_GPIO=1
POWER_OFF_ON_HALT=0
DHCP_TIMEOUT=45000
DHCP_REQ_TIMEOUT=4000
TFTP_FILE_TIMEOUT=30000
ENABLE_SELF_UPDATE=1
DISABLE_HDMI=0
BOOT_ORDER=0xf41
@popcornmix
Copy link
Contributor

Do you have two hdmi displays connected?

@piec
Copy link
Author

piec commented Dec 23, 2020

No just a single 1080p hdmi samsung screen. I tried the 2 mini hdmi outputs, same effect

@popcornmix
Copy link
Contributor

Might be worth trying latest firmware/kernel from rpi-update.
This moves to 5.10 kernel. Backing up is recommended first.

@Donno191
Copy link

Donno191 commented Jan 3, 2021

Same Issue with multiple apps Gcompris, snap etc. Using Raspberry Pi 4B 8GB(Note Browser working fine and aplay in CLI)

@cipy
Copy link

cipy commented Jan 4, 2021

Hi there,

Unfortunately this issue is generic today for all RPi 3B+, 4B with the latest stock kernels from Raspbian, including the latest pushed (yesterday) with rpi-update @popcornmix; it doesn't matter if you set the sound beforehand to go to the AV jack or HDMI.

Here's a kernel dump https://mail.kde.org/pipermail/gcompris-devel/2021-January/005936.html

Thanks,
Ciprian

@cipy
Copy link

cipy commented Jan 4, 2021

looks like switching back to the EL33T kernel version (1337) solves this 💯 for now

sudo rpi-update e70a90efc09925adbc84e9dded5526ee155e00e0

also the build before this one seem to work, I'll let the fun of finding the breaking change to @popcornmix

@cipy
Copy link

cipy commented Jan 4, 2021

039088f7d929b0d1cc49e8d2012df0652f908518 is the last build when GCompris +audio works on both 3B+ & 4B
ca14404412d6c2a9966d7766bbad6cc24989a33f is the very first iteration when things go kernel blurps

firmware: hdmi/edid: Add option to ignore any odd horizontal timings on Pi4
firmware: sdcard: Hybrid MBR - only select GPT if it is the first primary partition See: raspberrypi/firmware#1465
firmware: audioplus: Avoid broken audio when requesting hdmi audio device when using composite display See: https://www.raspberrypi.org/forums/viewtopic.php?f=28&t=283639

@cipy
Copy link

cipy commented Jan 4, 2021

looks like installing the gstreamer1.0-pulseaudio package solves this problem 🥇

@popcornmix
Copy link
Contributor

@piec do either of these fix the issue for you?

  • installing the gstreamer1.0-pulseaudio package
  • switching to firmware/kernel 039088f7d929b0d1cc49e8d2012df0652f908518 with rpi-update?

@petitlapin
Copy link

@piec do either of these fix the issue for you?

* installing the gstreamer1.0-pulseaudio package

* switching to firmware/kernel 039088f7d929b0d1cc49e8d2012df0652f908518 with rpi-update?

Both work: (from the GCompris mailing-list https://mail.kde.org/pipermail/gcompris-devel/2021-January/005942.html):

Yes, you may:
a) have an up to date Raspbian OS and just install the gstreamer1.0-pulseaudio on top of it,
b) downgrade your kernel (no need for gstreamer1.0-pulseaudio) to the EL33T version

@piec
Copy link
Author

piec commented Jan 5, 2021

Thanks for the answers

@popcornmix

  • with gstreamer1.0-pulseaudio: works fine (audio as well)
  • firmware/kernel 039088f7d929b0d1cc49e8d2012df0652f908518 without gstreamer1.0-pulseaudio: gcompris works but no audio, no audio at all in the system.
  • firmware/kernel 039088f7d929b0d1cc49e8d2012df0652f908518 and gstreamer1.0-pulseaudio: works but no audio (same as previous)

I'm using the speakers of my hdmi screen

@cipy
Copy link

cipy commented Jan 5, 2021

Hi there,

I had to make it work/test with pulseaudio as this one is the recommended way forward in most recent RPi kernels

Therefore my setup had also:

apt-get purge -y bluealsa
apt-get install -y pulseaudio-module-bluetooth

and all the other packages up to date. If you still have *alsa it might explain the difference, or maybe I was off by one 👎

My backup plan was to find the very first/last kernel at which the GCompris won't freeze, which I believe it is 0390

@piec
Copy link
Author

piec commented Jan 5, 2021

More info, discussed on the kde thread
Qt uses gstreamer as audio backend, itself can use different audio sinks. When gstreamer1.0-pulseaudio is installed gstreamer will use that which works fine, if not present it will use omxhdmiaudiosink which generates the bug described in the issue (firmware freeze).
It can be reproduced using: gst-launch-1.0 audiotestsrc ! omxhdmiaudiosink

@piec
Copy link
Author

piec commented Jan 5, 2021

@cipy as far as I know I need alsa, it's the layer below pulseaudio
(pulseaudio depends on libasound2)
Not sure I understood what you meant

@popcornmix
Copy link
Contributor

I've reproduced a hang with gst-launch-1.0 audiotestsrc ! omxhdmiaudiosink. Investigating.

popcornmix added a commit that referenced this issue Jan 7, 2021
kernel: V4L2 ISP/Codec additional formats
See: raspberrypi/linux#4052

kernel: vc4: Avoid hdmi audio underrun with heavy sdram traffic
See: raspberrypi/linux#4053

firmware: audioplus: Fix hang when switching destination
See: #1516

firmware: HAT/I2C updates

firmware: MMAL/IL: Add support for the 16bpp Bayer/Grey raw 10/12/14 formats
@popcornmix
Copy link
Contributor

Potential fix has been pushed to rpi-update firmware. Can you test?

popcornmix added a commit to Hexxeh/rpi-firmware that referenced this issue Jan 7, 2021
kernel: V4L2 ISP/Codec additional formats
See: raspberrypi/linux#4052

kernel: vc4: Avoid hdmi audio underrun with heavy sdram traffic
See: raspberrypi/linux#4053

firmware: audioplus: Fix hang when switching destination
See: raspberrypi/firmware#1516

firmware: HAT/I2C updates

firmware: MMAL/IL: Add support for the 16bpp Bayer/Grey raw 10/12/14 formats
@piec
Copy link
Author

piec commented Jan 7, 2021

Thanks I should be able to test tomorrow

@piec
Copy link
Author

piec commented Jan 8, 2021

@popcornmix 👍 after rpi-update gst-launch-1.0 audiotestsrc ! omxhdmiaudiosink doesn't crash anymore. It doesn't output sound though.

@popcornmix
Copy link
Contributor

I get audio here. A fixed tone comes out of hdmi display.

pi@pi4:~ $ vcgencmd version
Jan  7 2021 18:27:29 
Copyright (c) 2012 Broadcom
version fb345a0c2d5544957f4ba1a2b9e968970e3312c4 (clean) (release) (start)
pi@pi4:~ $ gst-launch-1.0 audiotestsrc ! omxhdmiaudiosink
Setting pipeline to PAUSED ...
Pipeline is PREROLLING ...
Redistribute latency...
Pipeline is PREROLLED ...
Setting pipeline to PLAYING ...
New clock: GstAudioSinkClock

Does the simpler:

/opt/vc/src/hello_pi/hello_audio/hello_audio.bin 1

produce audio? Has it with any version of firmware?

@piec
Copy link
Author

piec commented Jan 8, 2021

No luck with these:

pi@raspberrypi:.../hello_pi/hello_audio $ vcgencmd version
Jan  7 2021 18:27:29
Copyright (c) 2012 Broadcom
version fb345a0c2d5544957f4ba1a2b9e968970e3312c4 (clean) (release) (start)
pi@raspberrypi:.../hello_pi/hello_audio $ gst-launch-1.0 audiotestsrc ! omxhdmiaudiosink
Setting pipeline to PAUSED ...
Pipeline is PREROLLING ...
Redistribute latency...
Pipeline is PREROLLED ...
Setting pipeline to PLAYING ...
New clock: GstAudioSinkClock

--> ❌ no sound on my hdmi monitor


pi@raspberrypi:.../hello_pi/hello_audio $ ./hello_audio.bin 1
Outputting audio to hdmi

--> ❌ no sound on my hdmi monitor
(I've just built it a few minutes ago)


pi@raspberrypi:.../hello_pi/hello_audio $ gst-launch-1.0 audiotestsrc ! pulsesink
Setting pipeline to PAUSED ...
Pipeline is PREROLLING ...
Redistribute latency...
Pipeline is PREROLLED ...
Setting pipeline to PLAYING ...
New clock: GstPulseSinkClock

--> ✔️ works, I hear sound on my hdmi monitor


I don't know if hello_audio is influenced by volumes set in alsamixer, but I checked them anyway

@popcornmix
Copy link
Contributor

What does "tvservice -a" report?

@piec
Copy link
Author

piec commented Jan 8, 2021

$ tvservice -a
     PCM supported: Max channels: 2, Max samplerate:  48kHz, Max samplesize 24 bits

I'm going to test hello_audio.bin after rpi-update 039088f7d929b0d1cc49e8d2012df0652f908518 to see if it worked

@piec
Copy link
Author

piec commented Jan 8, 2021

I have no sound on hdmi on my rpi 400 with revision 039088f7d929b0d1cc49e8d2012df0652f908518 (as far as I know there is no other audio output on the rpi 400)
Tested with:

  • hello_audio.bin 1
  • gst-launch-1.0 audiotestsrc ! pulsesink
  • gst-launch-1.0 audiotestsrc ! omxhdmiaudiosink

@popcornmix
Copy link
Contributor

What does "tvservice -s" report?
Not sure what to say if audio wasn't working that long ago (it's obviously not a problem most users have).
Can you try with a different display?
Does hdmi_drive=1 in config.txt make a difference?

@piec
Copy link
Author

piec commented Jan 11, 2021

I'm checking trying this as soon as possible

@piec
Copy link
Author

piec commented Jan 15, 2021

$ tvservice -s
state 0xa [HDMI CEA (16) RGB lim 16:9], 1920x1080 @ 60.00Hz, progressive

I'll try another display too & hdmi_drive=1

@do-dat-thang
Copy link

do-dat-thang commented Mar 13, 2021

Has anyone worked to root cause on this? I am hitting an almost identical problem. The end result is the same as originally reported here but the trace-back and apps being run are a bit different.

I have Rpi4 with dual HDMI and camera connected. I have an app that creates and destroys windows and takes pictures of the display to validate. After a bit, the mmal timeout occurs hanging raspistill which is used to take the pictures. Then Xorg hangs up and croaks about every 2 minutes or so.

I didn't think this was related to the audio, but I went ahead and "apt install gstreamer1.0-pulseaudio". This had no effect to help the problem.

Attaching raspinfo output.
raspinfo.txt

Please let me know if this is the right place to ask, or where I should otherwise.

@do-dat-thang
Copy link

Hmmm - looking at raspberrypi.c's rpi_firmware_transaction() source code, the error is very generic. So, although my problem ends up with the same issue, it is probably worlds apart.

But... still I'm kind of thinking this may be the right place to start(?) Is it best to put a new issue on the pile? I'm (if you haven't noticed) new to the work flow here. Thought I'd start by trying to find out if my problem has already been resolved before I dig too deep through the code.

@popcornmix
Copy link
Contributor

popcornmix commented Mar 14, 2021

If your issue doesn't involve Qt5 and Pi400 and it's only connection with this issue is rpi_firmware_transaction (which basically means firmware has stopped responding which can have many causes) then create a new issue.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

6 participants