Volumio Debian Buster Beta - Raspi images debugging

Volumio Development Development talks
1

Cross posting from the github issue - in the similar spirit as x86-debian-buster forum thread
Made some progress with Buster (and Node 12 14) for the Raspberry pi, and would be good to get some brave souls to test it out!

2021-01-03, version 0.015
Raspberry Pi Volumio-3.015-2021-01-03-pi
Updates:

  • Fix more vc quirks (symlink /vc/bins/, update LD_LIBRARY_PATH)
  • Enable airplay (added missing shairport-sync service)
  • Fix more legacy node/npm paths
  • Fix (?) I2S card numbering with 5.xx kernels
  • Bump to 5.10.y kernel

2020-12-10, version 0.014
Raspberry Pi Volumio-3.014-2020-12-10-pi
Updates:

  • myVolumio
  • Bump to 5.4.79 kernel

2020-08-21, version 0.010
Raspberry Pi Volumio-3.010-2020-08-21-pi
Updates:

  • Bump to 5.4.y kernel

2020-08-21, version 0.009
Raspberry Pi Volumio-3.009-2020-08-21-pi
Updates:

  • Reverted back to node v8, and default Volumio BE repo

2020-05-23, version 0.008
Raspberry Pi Volumio-3.008-2020-05-23-pi
Updates:

  • Fixed wireless startup for certain dongles
  • Switch to unofficial node (armv6) binaries for all pi versions
NOTE: Images .007 and lower will not work out of the box on older arm6l versions (RPi 1, CM1, RPi Zero) with network access, however a fixup script can be run to download the right armv6 binaries

2020-05-18, version 0.007
Raspberry Pi Volumio-3.007-2020-05-18-pi
Updates:

  • (?) Fixed issues hotspot (wireless.js)
  • (?) Fixed kodi issue by including libcec4
  • Updated to node v14

2020-05-03, version 0.006
Raspberry Pi Volumio-3.006-2020-05-03-pi
Updates:

  • Fix missing log folders for samba, and remove wifistart.sh (thanks @mike42)
  • Added missing libblockdev libs
  • Fixed issues with resolv.conf and hostapd.conf
  • Fixed xbindkeysrc service

2020-03-07, version 0.005
Raspberry Pi Volumio-3.005-2020-03-07-pi
Updates:

  • Tentatively fixes wireless.js/hotspot.sh
  • Use custom shairport-sync to avoid pulseaudio version issues

2020-03-02, version 0.004
Raspberry Pi Volumio-3.004-2020-03-02-pi
Updates:
-Fixes /boot/config.txt issues causing kernel panics on reboot after DAC activation

2020-02-25, version 0.003
Raspberry Pi Volumio-3.003-2020-02-25-pi

Known issues
Missing Volumio specific binaries (updater, etc)
mpd version config issues
wireless.js aka hotspot mode will probably also fail
Certain plugins will fail - (please report, will need modules to be recompiled for Node 12)

1 Like
2

Made this one sticky as it is a priority development activity

3

]Hello,

Flashed the buster 20200225 raspi image to RPi4-4Gb + HifiBerry DAC+.

First boot OK, I went through the startup configuration wizard, added some local music sources and webradio, playback was working properly from all sources.
First reboot ran into kernel panic. Had another go with disabling Wifi (hotspot also) but after reboot same error came up:

https://pasteboard.co/IWLXzIh.jpg

I hope it helps, if you need anything more to check just let me know.

Thank you for going on with the development!

4

:thumbs_up:

Thanks for testing!

Ah, interesting - do you have the initial details of the panic as well?

5

Unfortunatelly not, as the initial part scrolls out pretty fast. I tried to capture a video of the boot process, here is the first frame captured, pretty bad but let’s see if you can figure out something:

https://pasteboard.co/IWMWOTq.png

For what I can see for some reason the CPU was identified as BCM2835 while the board clearly has the BCM2711 SoC.

6

Could you ssh into the device and share boot logs from dmesg?

That is correct, /proc/cpuinfo will be incorrect for Pi 2, 3 and 4 due the kernel choices.
You can confirm that the board is identified correctly with cat /proc/device-tree/model

7

I did the install on a rpi4
First boot ok, sound ok (usb DAC)
install touchdisplay plugin (fail to start due to authorisation)
reboot and kernel panic!

8

To be honest, I only tested this on pi3, I unfortunately don’t have a pi4, but could someone capture some serial boot logs of this kernel panic?

9

Sure, here you are, this is boot log for first run:

bash-5.0# dmesg [ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Linux version 4.19.86-v7l+ (dom@buildbot) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1283 SMP Fri Nov 29 18:37:40 GMT 2019 [ 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.2 [ 0.000000] Memory policy: Data cache writealloc [ 0.000000] cma: Reserved 64 MiB at 0x000000003a000000 [ 0.000000] On node 0 totalpages: 1024000 [ 0.000000] DMA zone: 1728 pages used for memmap [ 0.000000] DMA zone: 0 pages reserved [ 0.000000] DMA zone: 196608 pages, LIFO batch:63 [ 0.000000] HighMem zone: 827392 pages, LIFO batch:63 [ 0.000000] random: get_random_bytes called from start_kernel+0xc0/0x4e8 with crng_init=0 [ 0.000000] percpu: Embedded 17 pages/cpu s36928 r8192 d24512 u69632 [ 0.000000] pcpu-alloc: s36928 r8192 d24512 u69632 alloc=17*4096 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1022272 [ 0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=1 cma=64M bcm2708_fb.fbwidth=1824 bcm2708_fb.fbheight=984 bcm2708_fb.fbswap=1 smsc95xx.macaddr=DC:A6:32:76:43:04 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 earlycon=ttyAMA0,115200 dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 console=ttyAMA0,115200 kgdboc=ttyAMA0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh elevator=noop rootwait bootdelay=5 logo.nologo vt.global_cursor_default=0 loglevel=8 net.ifnames=0 [ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) [ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) [ 0.000000] Memory: 3955060K/4096000K available (8192K kernel code, 687K rwdata, 2408K rodata, 2048K init, 850K bss, 75404K reserved, 65536K cma-reserved, 3244032K highmem) [ 0.000000] Virtual kernel memory layout: vector : 0xffff0000 - 0xffff1000 ( 4 kB) fixmap : 0xffc00000 - 0xfff00000 (3072 kB) vmalloc : 0xf0800000 - 0xff800000 ( 240 MB) lowmem : 0xc0000000 - 0xf0000000 ( 768 MB) pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB) modules : 0xbf000000 - 0xbfe00000 ( 14 MB) .text : 0x(ptrval) - 0x(ptrval) (10208 kB) .init : 0x(ptrval) - 0x(ptrval) (2048 kB) .data : 0x(ptrval) - 0x(ptrval) ( 688 kB) .bss : 0x(ptrval) - 0x(ptrval) ( 851 kB) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] ftrace: allocating 28674 entries in 85 pages [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 [ 0.000000] GIC: Using split EOI/Deactivate mode [ 0.000000] arch_timer: cp15 timer(s) running at 54.00MHz (phys). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns [ 0.000005] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns [ 0.000022] Switching to timer-based delay loop, resolution 18ns [ 0.000243] Console: colour dummy device 80x30 [ 0.000804] console [tty1] enabled [ 0.000859] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000) [ 0.000901] pid_max: default: 32768 minimum: 301 [ 0.001179] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.001210] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.002029] CPU: Testing write buffer coherency: ok [ 0.002444] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 [ 0.003093] Setting up static identity map for 0x200000 - 0x20003c [ 0.003266] rcu: Hierarchical SRCU implementation. [ 0.004125] smp: Bringing up secondary CPUs ... [ 0.004919] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 [ 0.005822] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 [ 0.006679] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 [ 0.006811] smp: Brought up 1 node, 4 CPUs [ 0.006875] SMP: Total of 4 processors activated (432.00 BogoMIPS). [ 0.006897] CPU: All CPU(s) started in HYP mode. [ 0.006917] CPU: Virtualization extensions available. [ 0.007623] devtmpfs: initialized [ 0.018181] VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0 [ 0.018422] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns [ 0.018465] futex hash table entries: 1024 (order: 4, 65536 bytes) [ 0.020638] pinctrl core: initialized pinctrl subsystem [ 0.021430] NET: Registered protocol family 16 [ 0.024363] DMA: preallocated 1024 KiB pool for atomic coherent allocations [ 0.025910] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. [ 0.025941] hw-breakpoint: maximum watchpoint size is 8 bytes. [ 0.026139] Serial: AMBA PL011 UART driver [ 0.029069] bcm2835-mbox fe00b880.mailbox: mailbox enabled [ 0.065004] bcm2835-dma fe007000.dma: DMA legacy API manager at (ptrval), dmachans=0x1 [ 0.067078] vgaarb: loaded [ 0.067448] SCSI subsystem initialized [ 0.067661] usbcore: registered new interface driver usbfs [ 0.067728] usbcore: registered new interface driver hub [ 0.067838] usbcore: registered new device driver usb [ 0.080331] raspberrypi-firmware soc:firmware: Attached to firmware from 2019-11-29 18:44, variant start [ 0.090082] raspberrypi-firmware soc:firmware: Firmware hash is aabb1fb5c19d80db268aeccd67b9f4e0f3d48a2a [ 0.101878] clocksource: Switched to clocksource arch_sys_counter [ 0.178768] VFS: Disk quotas dquot_6.6.0 [ 0.178865] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 0.179029] FS-Cache: Loaded [ 0.179220] CacheFiles: Loaded [ 0.188526] NET: Registered protocol family 2 [ 0.189185] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes) [ 0.189231] TCP established hash table entries: 8192 (order: 3, 32768 bytes) [ 0.189311] TCP bind hash table entries: 8192 (order: 4, 65536 bytes) [ 0.189395] TCP: Hash tables configured (established 8192 bind 8192) [ 0.189525] UDP hash table entries: 512 (order: 2, 16384 bytes) [ 0.189570] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) [ 0.189782] NET: Registered protocol family 1 [ 0.190320] RPC: Registered named UNIX socket transport module. [ 0.190345] RPC: Registered udp transport module. [ 0.190365] RPC: Registered tcp transport module. [ 0.190386] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.190414] PCI: CLS 0 bytes, default 64 [ 0.190619] Trying to unpack rootfs image as initramfs... [ 1.923341] Freeing initrd memory: 22280K [ 1.937957] Initialise system trusted keyrings [ 1.938169] workingset: timestamp_bits=14 max_order=20 bucket_order=6 [ 1.947133] FS-Cache: Netfs 'nfs' registered for caching [ 1.947657] NFS: Registering the id_resolver key type [ 1.947695] Key type id_resolver registered [ 1.947717] Key type id_legacy registered [ 1.947748] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 1.950123] Key type asymmetric registered [ 1.950149] Asymmetric key parser 'x509' registered [ 1.950269] bounce: pool size: 64 pages [ 1.950321] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) [ 1.950495] io scheduler noop registered (default) [ 1.950520] io scheduler deadline registered [ 1.950682] io scheduler cfq registered [ 1.950706] io scheduler mq-deadline registered [ 1.950727] io scheduler kyber registered [ 1.994444] brcm-pcie fd500000.pcie: dmabounce: initialised - 32768 kB, threshold 0x00000000c0000000 [ 1.994489] brcm-pcie fd500000.pcie: could not get clock [ 1.994563] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: [ 1.994614] brcm-pcie fd500000.pcie: MEM 0x600000000..0x603ffffff -> 0xf8000000 [ 2.051914] brcm-pcie fd500000.pcie: link up, 5.0 Gbps x1 (!SSC) [ 2.052205] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 [ 2.052236] pci_bus 0000:00: root bus resource [bus 00-01] [ 2.052266] pci_bus 0000:00: root bus resource [mem 0x600000000-0x603ffffff] (bus address [0xf8000000-0xfbffffff]) [ 2.052329] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 [ 2.052479] pci 0000:00:00.0: PME# supported from D0 D3hot [ 2.055274] PCI: bus0: Fast back to back transfers disabled [ 2.055305] pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring [ 2.055488] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 [ 2.055561] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit] [ 2.055740] pci 0000:01:00.0: PME# supported from D0 D3cold [ 2.058420] PCI: bus1: Fast back to back transfers disabled [ 2.058450] pci_bus 0000:01: busn_res: [bus 01] end is updated to 01 [ 2.058508] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff] [ 2.058541] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit] [ 2.058588] pci 0000:00:00.0: PCI bridge to [bus 01] [ 2.058618] pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] [ 2.058842] pcieport 0000:00:00.0: enabling device (0140 -> 0142) [ 2.059020] pcieport 0000:00:00.0: Signaling PME with IRQ 53 [ 2.059190] pcieport 0000:00:00.0: AER enabled with IRQ 53 [ 2.059339] pci 0000:01:00.0: enabling device (0140 -> 0142) [ 2.059725] bcm2708_fb soc:fb: FB found 1 display(s) [ 2.089056] Console: switching to colour frame buffer device 228x61 [ 2.098140] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 1824x984 [ 2.100267] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled [ 2.102402] iproc-rng200 fe104000.rng: hwrng registered [ 2.102676] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) [ 2.103262] vc-sm: Videocore shared memory driver [ 2.103695] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000 [ 2.113598] brd: module loaded [ 2.123023] loop: module loaded [ 2.123808] Loading iSCSI transport class v2.0-870. [ 2.125498] libphy: Fixed MDIO Bus: probed [ 2.125957] bcmgenet fd580000.genet: failed to get enet clock [ 2.126027] bcmgenet fd580000.genet: GENET 5.0 EPHY: 0x0000 [ 2.126095] bcmgenet fd580000.genet: failed to get enet-wol clock [ 2.126164] bcmgenet fd580000.genet: failed to get enet-eee clock [ 2.126241] bcmgenet: Skipping UMAC reset [ 2.126506] unimac-mdio unimac-mdio.-19: DMA mask not set [ 2.141912] libphy: bcmgenet MII bus: probed [ 2.192528] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus at 0x(ptrval) [ 2.193493] usbcore: registered new interface driver r8152 [ 2.193610] usbcore: registered new interface driver lan78xx [ 2.193715] usbcore: registered new interface driver smsc95xx [ 2.193979] xhci_hcd 0000:01:00.0: xHCI Host Controller [ 2.194058] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 [ 2.196627] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0000001000000890 [ 2.196884] genirq: irq_chip Brcm_MSI did not update eff. affinity mask of irq 54 [ 2.197605] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19 [ 2.197694] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 2.197769] usb usb1: Product: xHCI Host Controller [ 2.197825] usb usb1: Manufacturer: Linux 4.19.86-v7l+ xhci-hcd [ 2.197888] usb usb1: SerialNumber: 0000:01:00.0 [ 2.198458] hub 1-0:1.0: USB hub found [ 2.200982] hub 1-0:1.0: 1 port detected [ 2.203879] xhci_hcd 0000:01:00.0: xHCI Host Controller [ 2.206344] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 [ 2.208802] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed [ 2.211552] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 4.19 [ 2.214061] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 2.216537] usb usb2: Product: xHCI Host Controller [ 2.219003] usb usb2: Manufacturer: Linux 4.19.86-v7l+ xhci-hcd [ 2.221459] usb usb2: SerialNumber: 0000:01:00.0 [ 2.224435] hub 2-0:1.0: USB hub found [ 2.226901] hub 2-0:1.0: 4 ports detected [ 2.230414] dwc_otg: version 3.00a 10-AUG-2012 (platform bus) [ 2.233063] dwc_otg: FIQ enabled [ 2.235441] dwc_otg: NAK holdoff enabled [ 2.237793] dwc_otg: FIQ split-transaction FSM enabled [ 2.240154] Module dwc_common_port init [ 2.242706] usbcore: registered new interface driver uas [ 2.245161] usbcore: registered new interface driver usb-storage [ 2.247674] mousedev: PS/2 mouse device common for all mice [ 2.251172] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer [ 2.254975] sdhci: Secure Digital Host Controller Interface driver [ 2.257340] sdhci: Copyright(c) Pierre Ossman [ 2.260043] mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe [ 2.262822] sdhci-pltfm: SDHCI platform and OF driver helper [ 2.268149] ledtrig-cpu: registered to indicate activity on CPUs [ 2.270690] hidraw: raw HID events driver (C) Jiri Kosina [ 2.273263] usbcore: registered new interface driver usbhid [ 2.275658] usbhid: USB HID core driver [ 2.278906] vchiq: vchiq_init_state: slot_zero = (ptrval), is_master = 0 [ 2.282716] [vc_sm_connected_init]: start [ 2.291219] [vc_sm_connected_init]: end - returning 0 [ 2.294769] Initializing XFRM netlink socket [ 2.297139] NET: Registered protocol family 17 [ 2.299530] Key type dns_resolver registered [ 2.302309] Registering SWP/SWPB emulation handler [ 2.305175] registered taskstats version 1 [ 2.307431] Loading compiled-in X.509 certificates [ 2.316434] uart-pl011 fe201000.serial: cts_event_workaround enabled [ 2.318798] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 34, base_baud = 0) is a PL011 rev2 [ 4.140149] console [ttyAMA0] enabled [ 4.149537] fe215040.serial: ttyS0 at MMIO 0x0 (irq = 36, base_baud = 62500000) is a 16550 [ 4.160674] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver [ 4.170834] brcmstb_thermal fd5d2200.thermal: registered AVS TMON of-sensor driver [ 4.181517] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 [ 4.190009] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated [ 4.224283] sdhci-iproc fe340000.emmc2: Linked as a consumer to regulator.1 [ 4.250815] mmc1: queuing unknown CIS tuple 0x80 (2 bytes) [ 4.260868] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [ 4.270393] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [ 4.278340] mmc0: SDHCI controller on fe340000.emmc2 [fe340000.emmc2] using ADMA [ 4.290576] of_cfs_init [ 4.290841] mmc1: queuing unknown CIS tuple 0x80 (7 bytes) [ 4.295579] of_cfs_init: OK [ 4.305043] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [ 4.324293] Freeing unused kernel memory: 2048K [ 4.331474] Run /init as init process [ 4.364780] random: fast init done [ 4.402425] usb 1-1: new high-speed USB device number 2 using xhci_hcd [ 4.420133] mmc1: new high speed SDIO card at address 0001 [ 4.441078] mmc0: new ultra high speed DDR50 SDHC card at address aaaa [ 4.451333] mmcblk0: mmc0:aaaa SC32G 29.7 GiB [ 4.462598] mmcblk0: p1 p2 p3 [ 4.499460] initramfs: Booting Volumio for BCM2835 [ 4.506982] initramfs: This script mounts rootfs RO with an overlay RW layer. [ 4.546134] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 4.567405] initramfs: IMGPART=/dev/mmcblk0p2 [ 4.574376] initramfs: IMGFILE=/volumio_current.sqsh [ 4.581957] initramfs: Boot delay (except first time) will be 5 seconds [ 4.591274] initramfs: /dev/mmcblk0p2 not detected,retrying mdev in 5 seconds [ 4.603289] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.20 [ 4.614152] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 [ 4.623789] usb 1-1: Product: USB2.0 Hub [ 4.631764] hub 1-1:1.0: USB hub found [ 4.638183] hub 1-1:1.0: 4 ports detected [ 4.961913] usb 1-1.1: new low-speed USB device number 3 using xhci_hcd [ 5.122477] usb 1-1.1: New USB device found, idVendor=046e, idProduct=3697, bcdDevice= 1.00 [ 5.133412] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 [ 5.143263] usb 1-1.1: Product: USB Multimedia Keyboard [ 5.151027] usb 1-1.1: Manufacturer: BTC [ 5.175300] input: BTC USB Multimedia Keyboard 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:046E:3697.0001/input/input0 [ 5.237995] initramfs: /dev/mmcblk0p2: [ 5.262484] hid-generic 0003:046E:3697.0001: input,hidraw0: USB HID v1.10 Keyboard [BTC USB Multimedia Keyboard] on usb-0000:01:00.0-1.1/input0 [ 5.293506] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) [ 5.299367] input: BTC USB Multimedia Keyboard System Control 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.1/0003:046E:3697.0002/input/input1 [ 5.325023] initramfs: Creating factory image, this will take a minute, please wait... [ 5.392118] input: BTC USB Multimedia Keyboard Consumer Control 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.1/0003:046E:3697.0002/input/input2 [ 5.413201] hid-generic 0003:046E:3697.0002: input,hiddev96,hidraw1: USB HID v1.10 Device [BTC USB Multimedia Keyboard] on usb-0000:01:00.0-1.1/input1 [ 44.242372] initramfs: Factory image created [ 44.249910] initramfs: Creating archive for factory kernel... [ 62.640973] initramfs: Checking for USB updates [ 62.732618] initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) [ 63.041743] initramfs: Re-sizing Volumio data partition [ 74.662338] initramfs: Volumio data partition succesfully resized [ 74.969199] EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) [ 75.001138] initramfs: With Option: [ 75.189050] initramfs: [ 75.194630] initramfs: Finish initramfs, continue booting Volumio [ 75.837462] systemd[1]: System time before build time, advancing clock. [ 75.940288] NET: Registered protocol family 10 [ 75.948991] Segment Routing with IPv6 [ 75.983305] systemd[1]: systemd 244-3~bpo10+1 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) [ 76.012783] systemd[1]: Detected architecture arm. [ 76.062422] systemd[1]: Set hostname to <volumio>. [ 76.226425] uart-pl011 fe201000.serial: no DMA platform data [ 76.762251] systemd[1]: /lib/systemd/system/dbus.socket:5: ListenStream= references a path below legacy directory /var/run/, updating /var/run/dbus/system_bus_socket → /run/dbus/system_bus_socket; please update the unit file accordingly. [ 76.793305] systemd[1]: /lib/systemd/system/winbind.service:9: PIDFile= references a path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. [ 76.890107] systemd[1]: /lib/systemd/system/smbd.service:10: PIDFile= references a path below legacy directory /var/run/, updating /var/run/samba/smbd.pid → /run/samba/smbd.pid; please update the unit file accordingly. [ 76.965925] systemd[1]: /lib/systemd/system/nmbd.service:10: PIDFile= references a path below legacy directory /var/run/, updating /var/run/samba/nmbd.pid → /run/samba/nmbd.pid; please update the unit file accordingly. [ 77.158377] random: systemd: uninitialized urandom read (16 bytes read) [ 77.174472] systemd[1]: Created slice system-getty.slice. [ 77.188842] random: systemd: uninitialized urandom read (16 bytes read) [ 77.200803] systemd[1]: Created slice system-serial\x2dgetty.slice. [ 77.215607] random: systemd: uninitialized urandom read (16 bytes read) [ 77.227282] systemd[1]: Created slice system-systemd\x2dfsck.slice. [ 77.243939] systemd[1]: Created slice User and Session Slice. [ 77.257980] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ 77.274301] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. [ 77.292500] systemd[1]: Reached target Slices. [ 77.304832] systemd[1]: Reached target System Time Set. [ 77.317948] systemd[1]: Reached target System Time Synchronized. [ 77.363020] systemd[1]: Listening on RPCbind Server Activation Socket. [ 77.379433] systemd[1]: Listening on fsck to fsckd communication Socket. [ 77.394741] systemd[1]: Listening on initctl Compatibility Named Pipe. [ 77.434615] systemd[1]: Condition check resulted in Journal Audit Socket being skipped. [ 77.448007] systemd[1]: Listening on Journal Socket (/dev/log). [ 77.462908] systemd[1]: Listening on Journal Socket. [ 77.477166] systemd[1]: Listening on udev Control Socket. [ 77.491308] systemd[1]: Listening on udev Kernel Socket. [ 77.505631] systemd[1]: Condition check resulted in Huge Pages File System being skipped. [ 77.523446] systemd[1]: Mounting POSIX Message Queue File System... [ 77.544842] systemd[1]: Mounting RPC Pipe File System... [ 77.565685] systemd[1]: Mounting Kernel Debug File System... [ 77.581024] systemd[1]: Condition check resulted in Kernel Module supporting RPCSEC_GSS being skipped. [ 77.600402] systemd[1]: Started dynamicswap service. [ 77.629291] systemd[1]: Reached target Swap. [ 77.659283] systemd[1]: Starting Restore / save the current clock... [ 77.684011] systemd[1]: Starting Create list of static device nodes for the current kernel... [ 77.705381] systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. [ 77.728090] systemd[1]: Starting Journal Service... [ 77.750222] systemd[1]: Starting Load Kernel Modules... [ 77.770362] systemd[1]: Starting Remount Root and Kernel File Systems... [ 77.792018] systemd[1]: Starting udev Coldplug all Devices... [ 77.825406] systemd[1]: Mounted POSIX Message Queue File System. [ 77.844909] systemd[1]: Mounted RPC Pipe File System. [ 77.848851] i2c /dev entries driver [ 77.871404] systemd[1]: Mounted Kernel Debug File System. [ 77.886564] systemd[1]: dynamicswap.service: Succeeded. [ 77.901584] systemd[1]: Started Restore / save the current clock. [ 77.919091] systemd[1]: Started Create list of static device nodes for the current kernel. [ 77.938535] systemd[1]: Started Load Kernel Modules. [ 77.956465] systemd[1]: Started Remount Root and Kernel File Systems. [ 77.972334] systemd[1]: Condition check resulted in FUSE Control File System being skipped. [ 77.989795] systemd[1]: Mounting Kernel Configuration File System... [ 78.005190] systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. [ 78.022790] systemd[1]: Starting Load/Save Random Seed... [ 78.042158] systemd[1]: Starting Apply Kernel Variables... [ 78.061626] systemd[1]: Starting Create System Users... [ 78.093774] systemd[1]: Mounted Kernel Configuration File System. [ 78.118616] systemd[1]: Started Apply Kernel Variables. [ 78.148278] systemd[1]: Started Journal Service. [ 78.647014] systemd-journald[210]: Received client request to flush runtime journal. [ 78.953345] rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x0000ffff [ 78.953813] rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00000fff [ 78.954266] rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x0000ffff [ 78.954698] rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x0000ffff [ 78.959406] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. [ 78.961345] bcm2835_vc_sm_cma_probe: Videocore shared memory driver [ 78.961360] [vc_sm_connected_init]: start [ 78.982374] [vc_sm_connected_init]: installed successfully [ 78.987151] media: Linux media interface: v0.10 [ 79.158773] videodev: Linux video capture interface: v2.00 [ 79.273618] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. [ 79.297754] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. [ 79.348635] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. [ 79.404796] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. [ 79.431302] bcm2835-codec bcm2835-codec: Device registered as /dev/video10 [ 79.431337] bcm2835-codec bcm2835-codec: Loaded V4L2 decode [ 79.448386] bcm2835-codec bcm2835-codec: Device registered as /dev/video11 [ 79.448417] bcm2835-codec bcm2835-codec: Loaded V4L2 encode [ 79.455644] bcm2835-codec bcm2835-codec: Device registered as /dev/video12 [ 79.455677] bcm2835-codec bcm2835-codec: Loaded V4L2 isp [ 79.483609] cfg80211: Loading compiled-in X.509 certificates for regulatory database [ 79.555746] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. [ 79.591509] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' [ 79.594006] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 [ 79.594022] cfg80211: failed to load regulatory.db [ 79.597148] bcm2835_audio soc:audio: card created with 8 channels [ 79.599126] snd-rpi-hifiberry-dacplus soc:sound: ASoC: CPU DAI (null) not registered - will retry [ 79.662615] snd-rpi-hifiberry-dacplus soc:sound: ASoC: CODEC DAI pcm512x-hifi not registered - will retry [ 79.768599] snd-rpi-hifiberry-dacplus soc:sound: ASoC: CODEC DAI pcm512x-hifi not registered - will retry [ 79.769935] brcmfmac: F1 signature read @0x18000000=0x15264345 [ 79.782691] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 [ 79.783674] usbcore: registered new interface driver brcmfmac [ 79.785320] snd-rpi-hifiberry-dacplus soc:sound: ASoC: CODEC DAI pcm512x-hifi not registered - will retry [ 80.100411] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 [ 80.140424] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Feb 27 2018 03:15:32 version 7.45.154 (r684107 CY) FWID 01-4fbe0b04 [ 80.257374] brcmfmac: power management disabled [ 80.425499] 8021q: 802.1Q VLAN Support v1.8 [ 80.433325] pcm512x 1-004d: 1-004d supply AVDD not found, using dummy regulator [ 80.433410] pcm512x 1-004d: Linked as a consumer to regulator.0 [ 80.433433] pcm512x 1-004d: 1-004d supply DVDD not found, using dummy regulator [ 80.433512] pcm512x 1-004d: 1-004d supply CPVDD not found, using dummy regulator [ 80.557526] snd-rpi-hifiberry-dacplus soc:sound: pcm512x-hifi <-> fe203000.i2s mapping ok [ 80.566609] bcmgenet: Skipping UMAC reset [ 80.672788] bcmgenet fd580000.genet: configuring instance for external RGMII (no delay) [ 80.785048] random: crng init done [ 80.785060] random: 7 urandom warning(s) missed due to ratelimiting [ 80.789056] brcmfmac: power management disabled [ 81.764280] bcmgenet fd580000.genet eth0: Link is Down [ 85.912034] bcmgenet fd580000.genet eth0: Link is Up - 1Gbps/Full - flow control off [ 87.870156] Bluetooth: Core ver 2.22 [ 87.870207] NET: Registered protocol family 31 [ 87.870211] Bluetooth: HCI device and connection manager initialized [ 87.870222] Bluetooth: HCI socket layer initialized [ 87.870230] Bluetooth: L2CAP socket layer initialized [ 87.870250] Bluetooth: SCO socket layer initialized [ 87.882204] Bluetooth: HCI UART driver ver 2.3 [ 87.882211] Bluetooth: HCI UART protocol H4 registered [ 87.882271] Bluetooth: HCI UART protocol Three-wire (H5) registered [ 87.882397] Bluetooth: HCI UART protocol Broadcom registered [ 88.046271] Bluetooth: BNEP (Ethernet Emulation) ver 1.3 [ 88.046278] Bluetooth: BNEP filters: protocol multicast [ 88.046289] Bluetooth: BNEP socket layer initialized bash-5.0#

Some CPU, model info:

[code]bash-5.0# uname -a
Linux volumio 4.19.86-v7l+ #1283 SMP Fri Nov 29 18:37:40 GMT 2019 armv7l GNU/Linux
bash-5.0# cat /proc/cpuinfo
processor : 0
model name : ARMv7 Processor rev 3 (v7l)
BogoMIPS : 270.00
Features : half thumb fastmult vfp edsp neon vfpv3 tls vfpv4 idiva idivt vfpd32 lpae evtstrm crc32
CPU implementer : 0x41
CPU architecture: 7
CPU variant : 0x0
CPU part : 0xd08
CPU revision : 3

processor : 1
model name : ARMv7 Processor rev 3 (v7l)
BogoMIPS : 270.00
Features : half thumb fastmult vfp edsp neon vfpv3 tls vfpv4 idiva idivt vfpd32 lpae evtstrm crc32
CPU implementer : 0x41
CPU architecture: 7
CPU variant : 0x0
CPU part : 0xd08
CPU revision : 3

processor : 2
model name : ARMv7 Processor rev 3 (v7l)
BogoMIPS : 270.00
Features : half thumb fastmult vfp edsp neon vfpv3 tls vfpv4 idiva idivt vfpd32 lpae evtstrm crc32
CPU implementer : 0x41
CPU architecture: 7
CPU variant : 0x0
CPU part : 0xd08
CPU revision : 3

processor : 3
model name : ARMv7 Processor rev 3 (v7l)
BogoMIPS : 270.00
Features : half thumb fastmult vfp edsp neon vfpv3 tls vfpv4 idiva idivt vfpd32 lpae evtstrm crc32
CPU implementer : 0x41
CPU architecture: 7
CPU variant : 0x0
CPU part : 0xd08
CPU revision : 3

Hardware : BCM2835
Revision : c03112
Serial : 1000000007debc00
Model : Raspberry Pi 4 Model B Rev 1.2
bash-5.0# cat /proc/device-tree/model
Raspberry Pi 4 Model B Rev 1.2bash-5.0#
[/code]

After reboot as boot doesn’t complete and no IP address is assigned I cannot SSH into the Pi. I mounted the SD card on another linux device but I cannot see anything saved about last boot and why it failed.

If you have any idea what I should check I’ll take a look.

10

Don’t see anything suspicious from the logs. Not sure if there is a way to debug the kernel post the panic from the file system.
Do you have access to a serial console by any chance? Else I will try getting my hands on a pi4 and debug further.

11

OK, I found an USB serial cable at the bottom of the drawer, so tried to setup connection,
Strange thing was that serial was not working so checked config.txt for UART settings and noticed that there is an accidental EOL in the original file:

"

Volumio i2s setting below: do not alter

dtoverlay=
pi3-miniuart-bt
"

Changed it to:
"

Volumio i2s setting below: do not alter

dtoverlay=pi3-miniuart-bt
"

After reboot serial was working but also no kernel panic after rebooting from web interface, device restarts properly. :slight_smile:

12

This is quite interesting. Looks like there is some issue with the I2S overlay addition - and it doesn’t play nice with a pre-existing dtoverlay line.
The original config.txt has the right entry enabling the UART only. I had enabled it for boot debugging, but guess I should move it over to userconfig.txt instead.

I haven’t dug into this in detail - but it might look like forceConfigTxtBannerCompat() doesn’t like this particular dtoverlay parameter.

The temp fix would be to move it into userconfig.txt

13

Added a new image that should fix this issue! :slight_smile:

14

Thanks for updating the image!:slight_smile:

DId a quick test (RPi4+HifiBerry DAC+):

-rebooting works now
-Webradio playback working
-Local NAS (NFS) playback working

Adding Spotify premium account not working, the error message states that username or password is not correct but they are, error log:

2020-03-03T12:09:24.594Z - info: SpopD Daemon Started
2020-03-03T12:09:29.596Z - info: Connecting to daemon
2020-03-03T12:09:29.600Z - error: SPOP command error: Error: connect ECONNREFUSED 127.0.0.1:6602
2020-03-03T12:09:29.600Z - error: SPOP status error: Error: connect ECONNREFUSED 127.0.0.1:6602
2020-03-03T12:09:29.601Z - info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify
2020-03-03T12:09:29.601Z - info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources

I remember there was a similar issue before but I didn’t have time to check for the solution but I think current stable plugin should have that implemented.

Kodi is having blank screen on display, though I had that with the current stable also, it was never working for me.

15

– Spop: I think the Spop plugin is having issues which should not be specific to this Buster version…
– Kodi: What version of the plugin are you using? I recall Sayato mentioning something required an upgrade to Buster…

16

I just installed the one from the plugin list: version 2.2.13 If you can find Saiyato’s specs to add for Buster I will check it to see if it works.

17

I see that buster has a version 17.6 (packages.debian.org/buster/kodi) available, and Sayato’s plugin uses a 17.4 so in theory you could just install the one from the Buster Repo. But - I am not the expert here, better to tag @Sayato for inputs.

Duh, this isn’t github/discord, so tags don’t work :stuck_out_tongue:

18

I didn’t have much time but tried these:
-Saiyato’s plugin still pulls from stretch as I see, gave it a try but wasn’t working
-installing from Debian buster rpi repository failed with missing dependencies
-also tried this Kodi 18.4 rpi4 build without success: https://www.raspberrypi.org/forums/viewtopic.php?f=38&t=246837

So no change, Kodi is still not working for me.

19

Tagged Sayato over on github, so he should take a look when he can :slight_smile:

20

Evening,

I’ve update the plugin on GitHub, but as it appears I’m unable to install Kodi on Buster using the Pi2. When I have the time I could test for Pi3, but my Pi4 is currently in use for another project. Feel free to test though, and post back the results :slight_smile: