Thank you for your donation!


Cloudsmith graciously provides open-source package management and distribution for our project.


Problem: Moode Audio freezes randomly
#1
Hi there, 

I'm running moode audio on Raspberry Pi 4b latest stock image, I've had no issues for the past 1+ years running moode audio.  Currently my moode audio freezes randomly.  Haven't had luck isolating.  The only thing that runs besides moode, is a small python script that queries Spotify api via spotipy, but it runs fine without any errors, and has been running fine on previous versions.  I'm using an external dac that's connected via USB. 

Any help would be appreciated.  Thank you.

Code:
pi@moode:~ $ moodeutl -l
20221007 001708 worker: --
20221007 001708 worker: -- Start moOde 8 series
20221007 001708 worker: --
20221007 001708 worker: Successfully daemonized
20221007 001711 worker: File check (OK)
20221007 001712 worker: Session vacuumed
20221007 001712 worker: Session loaded
20221007 001712 worker: Debug logging (OFF)
20221007 001712 worker: --
20221007 001712 worker: -- Audio debug
20221007 001712 worker: --
20221007 001712 worker: ALSA cards: (0:b1 | 1:Headphones | 2:Digital | 3:empty
20221007 001712 worker: MPD config: (2:Pre Box S2 Digital | mixer:(Pre Box S2 Digital ) | card:2)
20221007 001712 worker: ALSA mixer actual (Pre Box S2 Digital )
20221007 001712 worker: ALSA Pre Box S2 Digital volume set to (0%)
20221007 001712 worker: --
20221007 001712 worker: -- System
20221007 001712 worker: --
20221007 001713 worker: Host      (moode)
20221007 001713 worker: moOde     (8.2.0 2022-09-16)
20221007 001713 worker: RaspiOS   (11.5)
20221007 001713 worker: Kernel    (5.15.61-v8+ #1579)
20221007 001713 worker: Platform  (Pi-4B 1.2 4GB)
20221007 001713 worker: ARM arch  (aarch64, 64-bit)
20221007 001713 worker: MPD ver   (0.23.5)
20221007 001713 worker: CPU gov   (ondemand)
20221007 001713 worker: USB boot  (enabled)
20221007 001713 worker: File sys  (expanded)
20221007 001713 worker: HDMI port (On)
20221007 001713 worker: Sys LED0  (On)
20221007 001713 worker: Sys LED1  (On)
20221007 001713 worker: --
20221007 001713 worker: -- Network
20221007 001713 worker: --
20221007 001713 worker: eth0 adapter exists
20221007 001713 worker: eth0 check for address (Yes)
20221007 001713 worker: eth0 address check (90 secs)
20221007 001713 worker: eth0 check 0 for IP address
20221007 001715 worker: eth0 check 1 for IP address
20221007 001717 worker: eth0 check 2 for IP address
20221007 001720 worker: eth0 check 3 for IP address
20221007 001722 worker: eth0 check 4 for IP address
20221007 001724 worker: eth0 check 5 for IP address
20221007 001726 worker: eth0 check 6 for IP address
20221007 001728 worker: eth0 check 7 for IP address
20221007 001730 worker: eth0 check 8 for IP address
20221007 001732 worker: eth0 check 9 for IP address
20221007 001734 worker: eth0 check 10 for IP address
20221007 001736 worker: eth0 check 11 for IP address
20221007 004902 worker: eth0 check 12 for IP address
20221007 004904 worker: eth0 check 13 for IP address
20221007 004906 worker: eth0 check 14 for IP address
20221007 004908 worker: eth0 check 15 for IP address
20221007 004910 worker: eth0 check 16 for IP address
20221007 004912 worker: eth0 check 17 for IP address
20221007 004915 worker: eth0 check 18 for IP address
20221007 004917 worker: eth0 check 19 for IP address
20221007 004919 worker: eth0 check 20 for IP address
20221007 004921 worker: eth0 check 21 for IP address
20221007 004923 worker: eth0 check 22 for IP address
20221007 004925 worker: eth0 check 23 for IP address
20221007 004927 worker: eth0 check 24 for IP address
20221007 004929 worker: eth0 check 25 for IP address
20221007 004931 worker: eth0 check 26 for IP address
20221007 004933 worker: eth0 check 27 for IP address
20221007 004935 worker: eth0 check 28 for IP address
20221007 004937 worker: eth0 check 29 for IP address
20221007 004939 worker: eth0 check 30 for IP address
20221007 004941 worker: eth0 check 31 for IP address
20221007 004943 worker: eth0 check 32 for IP address
20221007 004945 worker: eth0 check 33 for IP address
20221007 004947 worker: eth0 check 34 for IP address
20221007 004949 worker: eth0 check 35 for IP address
20221007 004951 worker: eth0 check 36 for IP address
20221007 004953 worker: eth0 check 37 for IP address
20221007 004955 worker: eth0 check 38 for IP address
20221007 004957 worker: eth0 check 39 for IP address
20221007 004959 worker: eth0 check 40 for IP address
20221007 005002 worker: eth0 check 41 for IP address
20221007 005004 worker: eth0 check 42 for IP address
20221007 005006 worker: eth0 check 43 for IP address
20221007 005008 worker: eth0 check 44 for IP address
20221007 005010 worker: eth0 address not assigned
20221007 005010 worker: wlan0 adapter exists
20221007 005010 worker: wlan0 country (US)
20221007 005010 worker: wlan0 trying configured SSID (happy5ghz)
20221007 005010 worker: wlan0 connected SSID is (happy5ghz)
20221007 005010 worker: IP addr (192.168.0.200)
20221007 005010 worker: Netmask (255.255.255.0)
20221007 005010 worker: Gateway (192.168.0.1)
20221007 005010 worker: Pri DNS (192.168.0.1)
20221007 005010 worker: Domain  ()
20221007 005010 worker: wlan0 power save disabled
20221007 005010 worker: apd0 router mode (Off)
20221007 005010 worker: --
20221007 005010 worker: -- Software update
20221007 005010 worker: --
20221007 005010 worker: Automatic check (Off)
20221007 005010 worker: --
20221007 005010 worker: -- Audio config
20221007 005010 worker: --
20221007 005010 worker: MPD conf update skipped (USB device)
20221007 005010 worker: ALSA card number (2)
20221007 005010 worker: MPD audio output (Pre Box S2 Digital)
20221007 005010 worker: Audio formats (S32_LE, SPECIAL, DSD_U32_BE)
20221007 005010 worker: ALSA mixer name (Pre Box S2 Digital )
20221007 005010 worker: MPD mixer type (software)
20221007 005010 worker: Hdwr volume controller exists
20221007 005010 worker: Max ALSA volume (100%)
20221007 005010 worker: ALSA output mode (Direct: hw)
20221007 005010 worker: ALSA loopback (Off)
20221007 005010 worker: Reset renderer active flags
20221007 005010 worker: CamillaDSP (off)
20221007 005010 worker: --
20221007 005010 worker: -- File sharing
20221007 005010 worker: --
20221007 005010 worker: SMB file sharing (Off)
20221007 005010 worker: NFS file sharing (Off)
20221007 005010 worker: --
20221007 005010 worker: -- MPD startup
20221007 005010 worker: --
20221007 005011 worker: MPD started
20221007 005011 worker: MPD accepting connections
20221007 005011 worker: MPD output 1 ALSA Default (On)
20221007 005011 worker: MPD output 2 ALSA Bluetooth (Off)
20221007 005011 worker: MPD output 3 HTTP Server (Off)
20221007 005011 worker: MPD crossfade (off)
20221007 005011 worker: MPD ignore CUE files (no)
20221007 005011 worker: --
20221007 005011 worker: -- Music sources
20221007 005011 worker: --
20221007 005011 worker: USB sources (none attached)
20221007 005014 worker: NAS sources (mountall initiated)
20221007 005014 worker: --
20221007 005014 worker: -- Feature availability
20221007 005014 worker: --
20221007 005014 worker: Source select (available)
20221007 005014 worker: Source select (source: MPD)
20221007 005014 worker: Source select (output: Pre Box S2 Digital)
20221007 005021 worker: Bluetooth pairing agent (started)
20221007 005021 worker: Bluetooth (available: started)
20221007 005021 worker: Airplay renderer (available: started)
20221007 005021 worker: Spotify renderer (available: started)
20221007 005021 worker: Squeezelite (available)
20221007 005021 worker: RoonBridge renderer (not installed)
20221007 005021 worker: Multiroom sender (available)
20221007 005021 worker: Multiroom receiver (available)
20221007 005021 worker: UPnP renderer (available)
20221007 005021 worker: DLNA server (available)
20221007 005021 worker: GPIO button handler (available)
20221007 005021 worker: Stream recorder (n/a)
20221007 005021 worker: --
20221007 005021 worker: -- Other
20221007 005021 worker: --
20221007 005021 worker: USB volume knob (Off)
20221007 005021 worker: USB auto-mounter (udisks-glue)
20221007 005021 worker: Saved MPD vol level (0)
20221007 005021 worker: Preamp volume level (0)
20221007 005021 worker: MPD volume level (64) restored
20221007 005021 worker: ALSA Pre Box S2 Digital volume (100%)
20221007 005021 worker: Auto-play (Off)
20221007 005021 worker: LocalUI started
20221007 005021 worker: CoverView toggle (-off)
20221007 005021 worker: Maintenance interval (120 minutes)
20221007 005021 worker: Screen saver activation (Never)
20221007 005021 worker: Session permissions (OK)
20221007 005021 worker: Watchdog started
20221007 005021 worker: Ready

Code:
pi@moode:~ $ dmesg
[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd083]
[    0.000000] Linux version 5.15.61-v8+ (dom@buildbot) (aarch64-linux-gnu-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1579 SMP PREEMPT Fri Aug 26 11:16:44 BST 2022
[    0.000000] random: crng init done
[    0.000000] Machine model: Raspberry Pi 4 Model B Rev 1.2
[    0.000000] efi: UEFI not found.
[    0.000000] Reserved memory: created CMA memory pool at 0x000000002ac00000, size 64 MiB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000000000-0x000000003fffffff]
[    0.000000]   DMA32    [mem 0x0000000040000000-0x00000000fbffffff]
[    0.000000]   Normal   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x000000003b3fffff]
[    0.000000]   node   0: [mem 0x0000000040000000-0x00000000fbffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000000fbffffff]
[    0.000000] On node 0, zone DMA32: 19456 pages in unavailable ranges
[    0.000000] On node 0, zone DMA32: 16384 pages in unavailable ranges
[    0.000000] percpu: Embedded 28 pages/cpu s77272 r8192 d29224 u114688
[    0.000000] pcpu-alloc: s77272 r8192 d29224 u114688 alloc=28*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[    0.000000] Detected PIPT I-cache on CPU0
[    0.000000] CPU features: detected: Spectre-v2
[    0.000000] CPU features: detected: Spectre-v3a
[    0.000000] CPU features: detected: Spectre-v4
[    0.000000] CPU features: detected: Spectre-BHB
[    0.000000] CPU features: kernel page table isolation forced ON by KASLR
[    0.000000] CPU features: detected: Kernel page table isolation (KPTI)
[    0.000000] CPU features: detected: ARM errata 1165522, 1319367, or 1530923
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 996912
[    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=E4:5F:01:01:A1:A5 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  console=ttyS0,115200 console=tty1 root=PARTUUID=1c0349df-02 rootfstype=ext4 fsck.repair=yes rootwait
[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] software IO TLB: mapped [mem 0x0000000037400000-0x000000003b400000] (64MB)
[    0.000000] Memory: 3816052K/4050944K available (11520K kernel code, 1956K rwdata, 4140K rodata, 3840K init, 974K bss, 169356K reserved, 65536K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 38040 entries in 149 pages
[    0.000000] ftrace: allocated 149 pages with 4 groups
[    0.000000] trace event string verifier disabled
[    0.000000] rcu: Preemptible hierarchical RCU implementation.
[    0.000000] rcu: RCU event tracing is enabled.
[    0.000000] rcu: RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4.
[    0.000000] Trampoline variant of Tasks RCU enabled.
[    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 25 jiffies.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] Root IRQ handler: gic_handle_irq
[    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.000000] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns
[    0.000303] Console: colour dummy device 80x25
[    0.000869] printk: console [tty1] enabled
[    0.000939] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000)
[    0.000979] pid_max: default: 32768 minimum: 301
[    0.001120] LSM: Security Framework initializing
[    0.001374] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.001454] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.002702] cgroup: Disabling memory control group subsystem
[    0.005380] rcu: Hierarchical SRCU implementation.
[    0.006475] EFI services will not be available.
[    0.007011] smp: Bringing up secondary CPUs ...
[    0.008150] Detected PIPT I-cache on CPU1
[    0.008226] CPU1: Booted secondary processor 0x0000000001 [0x410fd083]
[    0.009393] Detected PIPT I-cache on CPU2
[    0.009444] CPU2: Booted secondary processor 0x0000000002 [0x410fd083]
[    0.010559] Detected PIPT I-cache on CPU3
[    0.010609] CPU3: Booted secondary processor 0x0000000003 [0x410fd083]
[    0.010759] smp: Brought up 1 node, 4 CPUs
[    0.010848] SMP: Total of 4 processors activated.
[    0.010869] CPU features: detected: 32-bit EL0 Support
[    0.010887] CPU features: detected: 32-bit EL1 Support
[    0.010908] CPU features: detected: CRC32 instructions
[    0.043382] CPU: All CPU(s) started at EL2
[    0.043475] alternatives: patching kernel code
[    0.045032] devtmpfs: initialized
[    0.055629] Enabled cp15_barrier support
[    0.055694] Enabled setend support
[    0.055723] KASLR enabled
[    0.055913] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.055961] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.057615] pinctrl core: initialized pinctrl subsystem
[    0.058432] DMI not present or invalid.
[    0.059124] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.062766] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations
[    0.063091] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.064011] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.064144] audit: initializing netlink subsys (disabled)
[    0.064432] audit: type=2000 audit(0.064:1): state=initialized audit_enabled=0 res=1
[    0.064968] thermal_sys: Registered thermal governor 'step_wise'
[    0.065182] cpuidle: using governor menu
[    0.065513] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.065719] ASID allocator initialised with 32768 entries
[    0.065882] Serial: AMBA PL011 UART driver
[    0.075960] bcm2835-mbox fe00b880.mailbox: mailbox enabled
[    0.096281] raspberrypi-firmware soc:firmware: Attached to firmware from 2022-08-26T14:03:16, variant start
[    0.100301] raspberrypi-firmware soc:firmware: Firmware hash is 102f1e848393c2112206fadffaaf86db04e98326
[    0.149855] bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1
[    0.153954] vgaarb: loaded
[    0.154392] SCSI subsystem initialized
[    0.154611] usbcore: registered new interface driver usbfs
[    0.154677] usbcore: registered new interface driver hub
[    0.154752] usbcore: registered new device driver usb
[    0.155075] usb_phy_generic phy: supply vcc not found, using dummy regulator
[    0.155286] usb_phy_generic phy: dummy supplies not allowed for exclusive requests
[    0.155595] pps_core: LinuxPPS API ver. 1 registered
[    0.155619] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.155655] PTP clock support registered
[    0.157217] clocksource: Switched to clocksource arch_sys_counter
[    0.245765] VFS: Disk quotas dquot_6.6.0
[    0.245889] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.246074] FS-Cache: Loaded
[    0.246323] CacheFiles: Loaded
[    0.257037] NET: Registered PF_INET protocol family
[    0.257590] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)
[    0.260729] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)
[    0.260798] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    0.260844] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)
[    0.261057] TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear)
[    0.261603] TCP: Hash tables configured (established 32768 bind 32768)
[    0.261860] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)
[    0.261943] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)
[    0.262245] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    0.263283] RPC: Registered named UNIX socket transport module.
[    0.263319] RPC: Registered udp transport module.
[    0.263338] RPC: Registered tcp transport module.
[    0.263356] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.263385] PCI: CLS 0 bytes, default 64
[    0.266072] hw perfevents: enabled with armv8_cortex_a72 PMU driver, 7 counters available
[    0.266406] kvm [1]: IPA Size Limit: 44 bits
[    0.267587] kvm [1]: vgic interrupt IRQ9
[    0.267867] kvm [1]: Hyp mode initialized successfully
[    1.076311] Initialise system trusted keyrings
[    1.076725] workingset: timestamp_bits=46 max_order=20 bucket_order=0
[    1.083639] zbud: loaded
[    1.085552] FS-Cache: Netfs 'nfs' registered for caching
[    1.086251] NFS: Registering the id_resolver key type
[    1.086302] Key type id_resolver registered
[    1.086323] Key type id_legacy registered
[    1.086436] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.086463] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    1.087655] Key type asymmetric registered
[    1.087683] Asymmetric key parser 'x509' registered
[    1.087783] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[    1.088050] io scheduler mq-deadline registered
[    1.088077] io scheduler kyber registered
[    1.095691] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
[    1.095755] brcm-pcie fd500000.pcie:   No bus range found for /scb/pcie@7d500000, using [bus 00-ff]
[    1.095853] brcm-pcie fd500000.pcie:      MEM 0x0600000000..0x063fffffff -> 0x00c0000000
[    1.095947] brcm-pcie fd500000.pcie:   IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000
[    1.143316] brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC)
[    1.143672] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
[    1.143703] pci_bus 0000:00: root bus resource [bus 00-ff]
[    1.143754] pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff])
[    1.143853] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
[    1.144109] pci 0000:00:00.0: PME# supported from D0 D3hot
[    1.148175] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
[    1.148287] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
[    1.148721] pci 0000:01:00.0: PME# supported from D0 D3cold
[    1.152609] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
[    1.152649] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
[    1.152701] pci 0000:00:00.0: PCI bridge to [bus 01]
[    1.152731] pci 0000:00:00.0:   bridge window [mem 0x600000000-0x6000fffff]
[    1.153635] bcm2708_fb soc:fb: FB found 1 display(s)
[    1.167198] Console: switching to colour frame buffer device 80x30
[    1.170426] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480
[    1.182999] iproc-rng200 fe104000.rng: hwrng registered
[    1.184467] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    1.187900] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000
[    1.202539] brd: module loaded
[    1.213191] loop: module loaded
[    1.214978] Loading iSCSI transport class v2.0-870.
[    1.220807] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000
[    1.281377] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus
[    1.283513] usbcore: registered new interface driver r8152
[    1.284617] usbcore: registered new interface driver lan78xx
[    1.285742] usbcore: registered new interface driver smsc95xx
[    1.288325] pci 0000:00:00.0: enabling device (0000 -> 0002)
[    1.289385] xhci_hcd 0000:01:00.0: enabling device (0000 -> 0002)
[    1.290478] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.291486] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
[    1.294240] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x00003c0000000890
[    1.297361] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.298415] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[    1.300444] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[    1.301918] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15
[    1.304037] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.306107] usb usb1: Product: xHCI Host Controller
[    1.307128] usb usb1: Manufacturer: Linux 5.15.61-v8+ xhci-hcd
[    1.308162] usb usb1: SerialNumber: 0000:01:00.0
[    1.309939] hub 1-0:1.0: USB hub found
[    1.311039] hub 1-0:1.0: 1 port detected
[    1.313011] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.15
[    1.315073] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.317243] usb usb2: Product: xHCI Host Controller
[    1.318364] usb usb2: Manufacturer: Linux 5.15.61-v8+ xhci-hcd
[    1.319480] usb usb2: SerialNumber: 0000:01:00.0
[    1.321284] hub 2-0:1.0: USB hub found
[    1.322468] hub 2-0:1.0: 4 ports detected
[    1.325080] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    1.326422] dwc_otg: FIQ enabled
[    1.326434] dwc_otg: NAK holdoff enabled
[    1.326443] dwc_otg: FIQ split-transaction FSM enabled
[    1.326464] Module dwc_common_port init
[    1.327033] usbcore: registered new interface driver uas
[    1.328190] usbcore: registered new interface driver usb-storage
[    1.329453] mousedev: PS/2 mouse device common for all mice
[    1.335486] sdhci: Secure Digital Host Controller Interface driver
[    1.336534] sdhci: Copyright(c) Pierre Ossman
[    1.338058] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.342374] ledtrig-cpu: registered to indicate activity on CPUs
[    1.343946] hid: raw HID events driver (C) Jiri Kosina
[    1.345070] usbcore: registered new interface driver usbhid
[    1.346050] usbhid: USB HID core driver
[    1.347302] ashmem: initialized
[    1.354492] NET: Registered PF_PACKET protocol family
[    1.355572] Key type dns_resolver registered
[    1.357753] registered taskstats version 1
[    1.358725] Loading compiled-in X.509 certificates
[    1.360394] Key type ._fscrypt registered
[    1.361359] Key type .fscrypt registered
[    1.362221] Key type fscrypt-provisioning registered
[    1.374172] uart-pl011 fe201000.serial: there is not valid maps for state default
[    1.376195] uart-pl011 fe201000.serial: cts_event_workaround enabled
[    1.377313] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 17, base_baud = 0) is a PL011 rev2
[    1.388003] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    1.389517] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    1.392598] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0
[    1.393656] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated
[    1.425805] usb usb2-port1: over-current change #1
[    1.427171] of_cfs_init
[    1.429449] of_cfs_init: OK
[    1.459504] mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA
[    1.461133] Waiting for root device PARTUUID=1c0349df-02...
[    1.527444] mmc1: new high speed SDIO card at address 0001
[    1.559491] mmc0: new ultra high speed DDR50 SDXC card at address 59b4
[    1.561509] mmcblk0: mmc0:59b4 SDU1  59.0 GiB
[    1.565285] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[    1.566523]  mmcblk0: p1 p2
[    1.568114] mmcblk0: mmc0:59b4 SDU1  59.0 GiB
[    1.580926] EXT4-fs (mmcblk0p2): INFO: recovery required on readonly filesystem
[    1.583104] EXT4-fs (mmcblk0p2): write access will be enabled during recovery
[    1.641699] usb usb2-port2: over-current change #1
[    1.657602] EXT4-fs (mmcblk0p2): orphan cleanup on readonly fs
[    1.672654] EXT4-fs (mmcblk0p2): 6 orphan inodes deleted
[    1.673836] EXT4-fs (mmcblk0p2): recovery complete
[    1.712187] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
[    1.714480] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[    1.717291] devtmpfs: mounted
[    1.718697] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
[    1.720794] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    1.721942] usb 1-1: Product: USB2.0 Hub
[    1.725405] hub 1-1:1.0: USB hub found
[    1.727112] hub 1-1:1.0: 4 ports detected
[    1.730175] Freeing unused kernel memory: 3840K
[    1.731460] Run /sbin/init as init process
[    1.732512]   with arguments:
[    1.732521]     /sbin/init
[    1.732531]   with environment:
[    1.732539]     HOME=/
[    1.732549]     TERM=linux
[    1.838989] usb 1-1-port1: over-current change #1
[    1.857657] usb usb2-port3: over-current change #1
[    2.060113] usb 1-1-port2: over-current change #1
[    2.073802] usb usb2-port4: over-current change #1
[    2.284096] usb 1-1-port3: over-current change #1
[    2.289658] usb usb2-port1: over-current change #2
[    2.372327] systemd[1]: System time before build time, advancing clock.
[    2.505603] usb usb2-port2: over-current change #2
[    2.510600] usb 1-1-port4: over-current change #1
[    2.613618] NET: Registered PF_INET6 protocol family
[    2.616469] Segment Routing with IPv6
[    2.617493] In-situ OAM (IOAM) with IPv6
[    2.721578] usb usb2-port3: over-current change #2
[    2.730688] systemd[1]: systemd 247.3-7+deb11u1 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +ZSTD +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=unified)
[    2.735717] systemd[1]: Detected architecture arm64.
[    2.838806] systemd[1]: Set hostname to <moode>.
[    2.953324] usb 1-1.4: new high-speed USB device number 3 using xhci_hcd
[    3.054267] usb 1-1.4: New USB device found, idVendor=2772, idProduct=0230, bcdDevice= 2.12
[    3.056274] usb 1-1.4: New USB device strings: Mfr=1, Product=3, SerialNumber=0
[    3.058348] usb 1-1.4: Product: Pre Box S2 Digital
[    3.059409] usb 1-1.4: Manufacturer: Pro-Ject
[    3.064984] input: Pro-Ject Pre Box S2 Digital 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.3/0003:2772:0230.0001/input/input0
[    3.125672] hid-generic 0003:2772:0230.0001: input,hidraw0: USB HID v1.10 Device [Pro-Ject Pre Box S2 Digital] on usb-0000:01:00.0-1.4/input3
[    3.129142] usb 1-1-port1: over-current change #2
[    3.638651] systemd[1]: Queued start job for default target Multi-User System.
[    3.645350] systemd[1]: Created slice system-getty.slice.
[    3.649511] systemd[1]: Created slice system-modprobe.slice.
[    3.653153] systemd[1]: Created slice system-systemd\x2dfsck.slice.
[    3.656705] systemd[1]: Created slice User and Session Slice.
[    3.659953] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[    3.664419] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    3.669578] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    3.673915] systemd[1]: Reached target Local Encrypted Volumes.
[    3.676903] systemd[1]: Reached target Paths.
[    3.679806] systemd[1]: Reached target Slices.
[    3.682598] systemd[1]: Reached target Swap.
[    3.702874] systemd[1]: Listening on RPCbind Server Activation Socket.
[    3.707003] systemd[1]: Listening on Syslog Socket.
[    3.710512] systemd[1]: Listening on fsck to fsckd communication Socket.
[    3.713697] systemd[1]: Listening on initctl Compatibility Named Pipe.
[    3.717662] systemd[1]: Listening on Journal Audit Socket.
[    3.721088] systemd[1]: Listening on Journal Socket (/dev/log).
[    3.724747] systemd[1]: Listening on Journal Socket.
[    3.735593] systemd[1]: Listening on udev Control Socket.
[    3.739285] systemd[1]: Listening on udev Kernel Socket.
[    3.742870] systemd[1]: Condition check resulted in Huge Pages File System being skipped.
[    3.749324] systemd[1]: Mounting POSIX Message Queue File System...
[    3.756493] systemd[1]: Mounting RPC Pipe File System...
[    3.764643] systemd[1]: Mounting Kernel Debug File System...
[    3.772437] systemd[1]: Mounting Kernel Trace File System...
[    3.775805] systemd[1]: Condition check resulted in Kernel Module supporting RPCSEC_GSS being skipped.
[    3.784854] systemd[1]: Starting Restore / save the current clock...
[    3.806298] systemd[1]: Starting Set the console keyboard layout...
[    3.814401] systemd[1]: Starting Create list of static device nodes for the current kernel...
[    3.823701] systemd[1]: Starting Load Kernel Module configfs...
[    3.832086] systemd[1]: Starting Load Kernel Module drm...
[    3.841003] systemd[1]: Starting Load Kernel Module fuse...
[    3.852793] systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped.
[    3.860364] systemd[1]: Starting File System Check on Root Device...
[    3.873133] systemd[1]: Starting Journal Service...
[    3.903065] systemd[1]: Starting Load Kernel Modules...
[    3.913880] fuse: init (API version 7.34)
[    3.922126] systemd[1]: Starting Coldplug All udev Devices...
[    3.954176] systemd[1]: Mounted POSIX Message Queue File System.
[    3.957858] systemd[1]: Mounted RPC Pipe File System.
[    3.961868] systemd[1]: Mounted Kernel Debug File System.
[    3.965654] systemd[1]: Mounted Kernel Trace File System.
[    3.970560] systemd[1]: Finished Restore / save the current clock.
[    3.975890] systemd[1]: Finished Create list of static device nodes for the current kernel.
[    3.982729] systemd[1]: modprobe@configfs.service: Succeeded.
[    3.986722] systemd[1]: Finished Load Kernel Module configfs.
[    3.988582] i2c_dev: i2c /dev entries driver
[    3.993076] systemd[1]: modprobe@fuse.service: Succeeded.
[    3.998511] systemd[1]: Finished Load Kernel Module fuse.
[    4.007461] systemd[1]: Finished Load Kernel Modules.
[    4.041859] systemd[1]: Mounting FUSE Control File System...
[    4.055881] systemd[1]: Mounting Kernel Configuration File System...
[    4.063042] systemd[1]: Starting pNFS block layout mapping daemon...
[    4.071195] systemd[1]: Started File System Check Daemon to report status.
[    4.082931] systemd[1]: Starting Apply Kernel Variables...
[    4.101853] systemd[1]: modprobe@drm.service: Succeeded.
[    4.106756] systemd[1]: Finished Load Kernel Module drm.
[    4.111456] systemd[1]: Mounted FUSE Control File System.
[    4.116676] systemd[1]: Mounted Kernel Configuration File System.
[    4.167061] systemd[1]: Started pNFS block layout mapping daemon.
[    4.226494] systemd[1]: Finished Apply Kernel Variables.
[    4.265009] systemd[1]: Started Journal Service.
[    4.432436] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null). Quota mode: none.
[    4.490271] systemd-journald[142]: Received client request to flush runtime journal.
[    4.526177] systemd-journald[142]: File /var/log/journal/a71d94435cec4f27b529a68e2f3c72cf/system.journal corrupted or uncleanly shut down, renaming and replacing.
[    5.318436] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[    5.329581] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[    5.329623] [vc_sm_connected_init]: start
[    5.345507] mc: Linux media interface: v0.10
[    5.346557] [vc_sm_connected_init]: installed successfully
[    5.436659] videodev: Linux video capture interface: v2.00
[    5.606336] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    5.636182] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    5.734226] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[    5.741472] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[    5.760353] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[    5.761273] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[    5.762064] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[    5.762689] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[    5.762726] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    5.762748] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    5.762766] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    5.762783] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    5.771667] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[    5.771741] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[    5.787406] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[    5.797923] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[    5.797989] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[    5.837445] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[    5.837508] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[    5.853631] bcm2835-codec bcm2835-codec: Device registered as /dev/video18
[    5.853704] bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx
[    5.854692] rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned.
[    5.872823] rpivid feb10000.codec: Device registered as /dev/video19
[    5.873909] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20
[    5.874809] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21
[    5.878310] bcm2835-codec bcm2835-codec: Device registered as /dev/video31
[    5.878365] bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image
[    5.879015] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22
[    5.879658] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23
[    5.879692] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    5.879713] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    5.879731] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    5.879748] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    5.880472] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[    5.907363] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[    5.931658] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[    5.965249] cfg80211: loaded regulatory.db is malformed or signature is missing/invalid
[    5.981419] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[    6.179558] bcm2835_audio bcm2835_audio: card created with 4 channels
[    6.195226] brcmfmac: F1 signature read @0x18000000=0x15264345
[    6.228840] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    6.230822] bcm2835_audio bcm2835_audio: card created with 4 channels
[    6.232138] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,4-model-b.bin failed with error -2
[    6.322815] usbcore: registered new interface driver brcmfmac
[    6.545289] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    6.545453] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    6.552237] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov  1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60
[    6.993427] usb 1-1.4: 1:2 : unsupported format bits 0x100000000
[    7.010398] usbcore: registered new interface driver snd-usb-audio
[    8.170213] 8021q: 802.1Q VLAN Support v1.8
[    8.820351] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
[    9.414904] bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay)
[    9.415841] bcmgenet fd580000.ethernet eth0: Link is Down
[   14.999017] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   31.709419] cam-dummy-reg: disabling
[  109.060587] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
[  111.507045] FS-Cache: Netfs 'cifs' registered for caching
[  111.514755] Key type cifs.spnego registered
[  111.514781] Key type cifs.idmap registered
[  111.517144] Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers

[  111.517167] CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers
[  111.517184] CIFS: Attempting to mount \\BIGGYBOY.local\Music
[  112.844919] Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers

[  112.844941] CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers
[  112.844960] CIFS: Attempting to mount \\BIGGYBOY.local\Music
[  113.040084] uart-pl011 fe201000.serial: no DMA platform data
[  119.759182] Bluetooth: Core ver 2.22
[  119.759275] NET: Registered PF_BLUETOOTH protocol family
[  119.759281] Bluetooth: HCI device and connection manager initialized
[  119.759298] Bluetooth: HCI socket layer initialized
[  119.759306] Bluetooth: L2CAP socket layer initialized
[  119.759321] Bluetooth: SCO socket layer initialized
[  119.769503] Bluetooth: HCI UART driver ver 2.3
[  119.769526] Bluetooth: HCI UART protocol H4 registered
[  119.769592] Bluetooth: HCI UART protocol Three-wire (H5) registered
[  119.769740] Bluetooth: HCI UART protocol Broadcom registered
[  120.039655] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[  120.039675] Bluetooth: BNEP filters: protocol multicast
[  120.039691] Bluetooth: BNEP socket layer initialized
[  120.062704] NET: Registered PF_ALG protocol family
Reply
#2
The symptom "Currently my moode audio freezes randomly." suggests a hardware, media, or other equipment issue. The usual troubleshooting approach is to start swapping components one by one.

When it "freezes" can you SSH into the Pi?

p.s. I put the log output in code boxes for readability. Its a button on the right side of the toolbar.

ETA: You might want to set "Wait for Ethernet address" to "No" in System Config. It will save you 90 secs during startup.
Enjoy the Music!
moodeaudio.org | Mastodon Feed | GitHub
Reply
#3
(10-07-2022, 11:59 AM)Tim Curtis Wrote: The symptom "Currently my moode audio freezes randomly." suggests a hardware, media, or other equipment issue. The usual troubleshooting approach is to start swapping components one by one.

When it "freezes" can you SSH into the Pi?

p.s. I put the log output in code boxes for readability. Its a button on the right side of the toolbar.

ETA: You might want to set "Wait for Ethernet address" to "No" in System Config. It will save you 90 secs during startup.

When it freezes I can't ssh into it, or ping it, just have to restart.
Reply
#4
That sounds like a hardware, power supply or media issue.
Enjoy the Music!
moodeaudio.org | Mastodon Feed | GitHub
Reply
#5
(10-07-2022, 12:54 PM)Tim Curtis Wrote: That sounds like a hardware, power supply or media issue.

Got it, thanks.  Will keep troubleshooting.
Reply


Forum Jump: