phoniebox stopped playing music

[manuel-koch]

I'm running a phoniebox (version 2.3). It was working fine but suddenly stop playing music and
I find strange errors in logs but have no clue what the culprit is or how to get it working again.

Excerpt from the logs:

Aug 24 09:57:40 phoniebox systemd[814]: Starting Music Player Daemon...
Aug 24 09:57:40 phoniebox systemd[1]: Started Samba SMB Daemon.
Aug 24 09:57:42 phoniebox mpd: exception: Failed to bind to '[::1]:6600'
Aug 24 09:57:42 phoniebox mpd: exception: nested: Failed to bind socket: Address already in use
Aug 24 09:57:42 phoniebox systemd[814]: mpd.service: Main process exited, code=exited, status=1/FAILURE
Aug 24 09:57:42 phoniebox systemd[814]: mpd.service: Failed with result 'exit-code'.
Aug 24 09:57:42 phoniebox systemd[814]: Failed to start Music Player Daemon.
....
Aug 24 09:57:43 phoniebox mpd: exception: Failed to read mixer for 'My ALSA Device': failed to attach to default: No such file or directory
...
Aug 24 09:57:44 phoniebox bash[864]: /home/pi/RPi-Jukebox-RFID/scripts/startup-scripts.sh: line 53:   970 Segmentation fault      /usr/bin/mpg123 -f -${mpgvolume} /home/pi/RPi-Jukebox-RFID/shared/startupsound.mp3

E.g. syslog:

Aug 24 09:57:25 phoniebox fake-hwclock[119]: Sat 24 Aug 08:57:20 UTC 2024
Aug 24 09:57:25 phoniebox systemd-fsck[136]: e2fsck 1.44.5 (15-Dec-2018)
Aug 24 09:57:25 phoniebox systemd-fsck[136]: rootfs: clean, 116321/1789824 files, 7212909/7725184 blocks
Aug 24 09:57:25 phoniebox systemd[1]: Started File System Check on Root Device.
Aug 24 09:57:25 phoniebox systemd[1]: Starting Remount Root and Kernel File Systems...
Aug 24 09:57:25 phoniebox systemd[1]: Started udev Coldplug all Devices.
Aug 24 09:57:25 phoniebox systemd[1]: Starting Helper to synchronize boot up for ifupdown...
Aug 24 09:57:25 phoniebox systemd[1]: Started Helper to synchronize boot up for ifupdown.
Aug 24 09:57:25 phoniebox systemd[1]: Started Set the console keyboard layout.
Aug 24 09:57:25 phoniebox systemd[1]: Started Remount Root and Kernel File Systems.
Aug 24 09:57:25 phoniebox systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped.
Aug 24 09:57:25 phoniebox systemd[1]: Starting Flush Journal to Persistent Storage...
Aug 24 09:57:25 phoniebox systemd[1]: Starting Load/Save Random Seed...
Aug 24 09:57:25 phoniebox systemd[1]: Starting Create System Users...
Aug 24 09:57:25 phoniebox systemd[1]: Started Load/Save Random Seed.
Aug 24 09:57:25 phoniebox systemd[1]: Started Flush Journal to Persistent Storage.
Aug 24 09:57:25 phoniebox systemd[1]: Started Create System Users.
Aug 24 09:57:25 phoniebox systemd[1]: Starting Create Static Device Nodes in /dev...
Aug 24 09:57:25 phoniebox systemd[1]: Started Create Static Device Nodes in /dev.
Aug 24 09:57:25 phoniebox systemd[1]: Starting udev Kernel Device Manager...
Aug 24 09:57:25 phoniebox systemd[1]: Reached target Local File Systems (Pre).
Aug 24 09:57:25 phoniebox systemd[1]: Started udev Kernel Device Manager.
Aug 24 09:57:25 phoniebox mtp-probe: checking bus 1, device 4: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.2"
Aug 24 09:57:25 phoniebox mtp-probe: bus: 1, device: 4 was not an MTP device
Aug 24 09:57:25 phoniebox mtp-probe: checking bus 1, device 5: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.1"
Aug 24 09:57:25 phoniebox mtp-probe: bus: 1, device: 5 was not an MTP device
Aug 24 09:57:25 phoniebox systemd-udevd[171]: Using default interface naming scheme 'v240'.
Aug 24 09:57:25 phoniebox systemd[1]: Found device /dev/disk/by-partuuid/ae99cb2e-01.
Aug 24 09:57:25 phoniebox systemd-udevd[178]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
Aug 24 09:57:25 phoniebox systemd-udevd[174]: Using default interface naming scheme 'v240'.
Aug 24 09:57:25 phoniebox systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
Aug 24 09:57:25 phoniebox systemd[1]: Condition check resulted in FUSE Control File System being skipped.
Aug 24 09:57:25 phoniebox systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped.
Aug 24 09:57:25 phoniebox systemd[1]: Condition check resulted in Huge Pages File System being skipped.
Aug 24 09:57:25 phoniebox systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped.
Aug 24 09:57:25 phoniebox kernel: [    0.000000] Booting Linux on physical CPU 0x0
Aug 24 09:57:25 phoniebox kernel: [    0.000000] Linux version 5.10.63-v7+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1496 SMP Wed Dec 1 15:58:11 GMT 2021
Aug 24 09:57:25 phoniebox kernel: [    0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
Aug 24 09:57:25 phoniebox systemd[1]: Starting File System Check on /dev/disk/by-partuuid/ae99cb2e-01...
Aug 24 09:57:25 phoniebox kernel: [    0.000000] CPU: div instructions available: patching division code
Aug 24 09:57:25 phoniebox kernel: [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
Aug 24 09:57:25 phoniebox kernel: [    0.000000] OF: fdt: Machine model: Raspberry Pi 3 Model B Plus Rev 1.3
Aug 24 09:57:25 phoniebox kernel: [    0.000000] random: fast init done
Aug 24 09:57:25 phoniebox systemd[1]: Starting Load/Save RF Kill Switch Status...
Aug 24 09:57:25 phoniebox kernel: [    0.000000] Memory policy: Data cache writealloc
Aug 24 09:57:25 phoniebox kernel: [    0.000000] Reserved memory: created CMA memory pool at 0x37400000, size 64 MiB
Aug 24 09:57:25 phoniebox kernel: [    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
Aug 24 09:57:25 phoniebox kernel: [    0.000000] Zone ranges:
Aug 24 09:57:25 phoniebox kernel: [    0.000000]   DMA      [mem 0x0000000000000000-0x000000003b3fffff]
Aug 24 09:57:25 phoniebox kernel: [    0.000000]   Normal   empty
Aug 24 09:57:25 phoniebox systemd[1]: Started Load/Save RF Kill Switch Status.
Aug 24 09:57:25 phoniebox kernel: [    0.000000] Movable zone start for each node
Aug 24 09:57:25 phoniebox kernel: [    0.000000] Early memory node ranges
Aug 24 09:57:25 phoniebox kernel: [    0.000000]   node   0: [mem 0x0000000000000000-0x000000003b3fffff]
Aug 24 09:57:25 phoniebox kernel: [    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000003b3fffff]
Aug 24 09:57:25 phoniebox systemd-fsck[289]: fsck.fat 4.1 (2017-01-24)
Aug 24 09:57:25 phoniebox kernel: [    0.000000] On node 0 totalpages: 242688
Aug 24 09:57:25 phoniebox kernel: [    0.000000]   DMA zone: 2133 pages used for memmap
Aug 24 09:57:25 phoniebox kernel: [    0.000000]   DMA zone: 0 pages reserved
Aug 24 09:57:25 phoniebox kernel: [    0.000000]   DMA zone: 242688 pages, LIFO batch:63
Aug 24 09:57:25 phoniebox systemd-fsck[289]: /dev/mmcblk0p1: 377 files, 100640/516190 clusters
Aug 24 09:57:25 phoniebox kernel: [    0.000000] percpu: Embedded 20 pages/cpu s50828 r8192 d22900 u81920
Aug 24 09:57:25 phoniebox kernel: [    0.000000] pcpu-alloc: s50828 r8192 d22900 u81920 alloc=20*4096
Aug 24 09:57:25 phoniebox kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
Aug 24 09:57:25 phoniebox kernel: [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 240555
Aug 24 09:57:25 phoniebox systemd[1]: Started File System Check on /dev/disk/by-partuuid/ae99cb2e-01.
Aug 24 09:57:25 phoniebox kernel: [    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=656 bcm2708_fb.fbheight=416 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  console=ttyS0,115200 console=tty1 root=PARTUUID=ae99cb2e-02 rootfstype=ext4 fsck.repair=yes rootwait
Aug 24 09:57:25 phoniebox kernel: [    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)
Aug 24 09:57:25 phoniebox kernel: [    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)
Aug 24 09:57:25 phoniebox kernel: [    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
Aug 24 09:57:25 phoniebox systemd[1]: Mounting /boot...
Aug 24 09:57:25 phoniebox kernel: [    0.000000] Memory: 878808K/970752K available (10240K kernel code, 1311K rwdata, 2948K rodata, 1024K init, 859K bss, 26408K reserved, 65536K cma-reserved)
Aug 24 09:57:25 phoniebox kernel: [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
Aug 24 09:57:25 phoniebox kernel: [    0.000000] ftrace: allocating 32054 entries in 63 pages
Aug 24 09:57:25 phoniebox kernel: [    0.000000] ftrace: allocated 63 pages with 6 groups
Aug 24 09:57:25 phoniebox systemd[1]: Mounted /boot.
Aug 24 09:57:25 phoniebox kernel: [    0.000000] rcu: Hierarchical RCU implementation.
Aug 24 09:57:25 phoniebox kernel: [    0.000000] 	Rude variant of Tasks RCU enabled.
Aug 24 09:57:25 phoniebox kernel: [    0.000000] 	Tracing variant of Tasks RCU enabled.
Aug 24 09:57:25 phoniebox kernel: [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
Aug 24 09:57:25 phoniebox systemd[1]: Reached target Local File Systems.
Aug 24 09:57:25 phoniebox kernel: [    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
Aug 24 09:57:25 phoniebox kernel: [    0.000000] random: get_random_bytes called from start_kernel+0x3ac/0x580 with crng_init=1
Aug 24 09:57:25 phoniebox kernel: [    0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys).
Aug 24 09:57:25 phoniebox kernel: [    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
Aug 24 09:57:25 phoniebox kernel: [    0.000007] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
Aug 24 09:57:25 phoniebox systemd[1]: Starting Set console font and keymap...
Aug 24 09:57:25 phoniebox kernel: [    0.000025] Switching to timer-based delay loop, resolution 52ns
Aug 24 09:57:25 phoniebox kernel: [    0.000315] Console: colour dummy device 80x30
Aug 24 09:57:25 phoniebox kernel: [    0.001138] printk: console [tty1] enabled
Aug 24 09:57:25 phoniebox kernel: [    0.001205] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
Aug 24 09:57:25 phoniebox kernel: [    0.001262] pid_max: default: 32768 minimum: 301
Aug 24 09:57:25 phoniebox systemd[1]: Starting Preprocess NFS configuration...
Aug 24 09:57:25 phoniebox kernel: [    0.001488] LSM: Security Framework initializing
Aug 24 09:57:25 phoniebox kernel: [    0.001747] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
Aug 24 09:57:25 phoniebox kernel: [    0.001796] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
Aug 24 09:57:25 phoniebox kernel: [    0.003271] cgroup: Disabling memory control group subsystem
Aug 24 09:57:25 phoniebox kernel: [    0.003556] CPU: Testing write buffer coherency: ok
Aug 24 09:57:25 phoniebox systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped.
Aug 24 09:57:25 phoniebox kernel: [    0.004074] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
Aug 24 09:57:25 phoniebox kernel: [    0.005336] Setting up static identity map for 0x100000 - 0x10003c
Aug 24 09:57:25 phoniebox kernel: [    0.005545] rcu: Hierarchical SRCU implementation.
Aug 24 09:57:25 phoniebox kernel: [    0.006489] smp: Bringing up secondary CPUs ...
Aug 24 09:57:25 phoniebox kernel: [    0.007666] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
Aug 24 09:57:25 phoniebox kernel: [    0.008947] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
Aug 24 09:57:25 phoniebox kernel: [    0.010265] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
Aug 24 09:57:25 phoniebox kernel: [    0.010421] smp: Brought up 1 node, 4 CPUs
Aug 24 09:57:25 phoniebox systemd[1]: Starting Raise network interfaces...
Aug 24 09:57:25 phoniebox kernel: [    0.010475] SMP: Total of 4 processors activated (153.60 BogoMIPS).
Aug 24 09:57:25 phoniebox kernel: [    0.010506] CPU: All CPU(s) started in HYP mode.
Aug 24 09:57:25 phoniebox kernel: [    0.010533] CPU: Virtualization extensions available.
Aug 24 09:57:25 phoniebox kernel: [    0.011546] devtmpfs: initialized
Aug 24 09:57:25 phoniebox systemd[1]: Starting Create Volatile Files and Directories...
Aug 24 09:57:25 phoniebox kernel: [    0.029238] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
Aug 24 09:57:25 phoniebox kernel: [    0.029534] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
Aug 24 09:57:25 phoniebox kernel: [    0.029594] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
Aug 24 09:57:25 phoniebox kernel: [    0.032860] pinctrl core: initialized pinctrl subsystem
Aug 24 09:57:25 phoniebox kernel: [    0.034072] NET: Registered protocol family 16
Aug 24 09:57:25 phoniebox systemd[1]: Started Set console font and keymap.
Aug 24 09:57:25 phoniebox kernel: [    0.038291] DMA: preallocated 1024 KiB pool for atomic coherent allocations
Aug 24 09:57:25 phoniebox kernel: [    0.044026] audit: initializing netlink subsys (disabled)
Aug 24 09:57:25 phoniebox kernel: [    0.044941] thermal_sys: Registered thermal governor 'step_wise'
Aug 24 09:57:25 phoniebox systemd[1]: nfs-config.service: Succeeded.
Aug 24 09:57:25 phoniebox kernel: [    0.045765] audit: type=2000 audit(0.040:1): state=initialized audit_enabled=0 res=1
Aug 24 09:57:25 phoniebox kernel: [    0.045998] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
Aug 24 09:57:25 phoniebox kernel: [    0.046038] hw-breakpoint: maximum watchpoint size is 8 bytes.
Aug 24 09:57:25 phoniebox kernel: [    0.046347] Serial: AMBA PL011 UART driver
Aug 24 09:57:25 phoniebox kernel: [    0.064635] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
Aug 24 09:57:25 phoniebox systemd[1]: Started Preprocess NFS configuration.
Aug 24 09:57:25 phoniebox kernel: [    0.080149] raspberrypi-firmware soc:firmware: Attached to firmware from 2021-12-01T15:07:06, variant start
Aug 24 09:57:25 phoniebox systemd[1]: Condition check resulted in RPC security service for NFS server being skipped.
Aug 24 09:57:25 phoniebox kernel: [    0.090160] raspberrypi-firmware soc:firmware: Firmware hash is 71bd3109023a0c8575585ba87cbb374d2eeb038f
Aug 24 09:57:25 phoniebox kernel: [    0.134481] Kprobes globally optimized
Aug 24 09:57:25 phoniebox kernel: [    0.139546] bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1
Aug 24 09:57:25 phoniebox kernel: [    0.141902] SCSI subsystem initialized
Aug 24 09:57:25 phoniebox kernel: [    0.142185] usbcore: registered new interface driver usbfs
Aug 24 09:57:25 phoniebox kernel: [    0.142272] usbcore: registered new interface driver hub
Aug 24 09:57:25 phoniebox kernel: [    0.142364] usbcore: registered new device driver usb
Aug 24 09:57:25 phoniebox systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped.
Aug 24 09:57:25 phoniebox kernel: [    0.144321] clocksource: Switched to clocksource arch_sys_counter
Aug 24 09:57:25 phoniebox kernel: [    1.916341] VFS: Disk quotas dquot_6.6.0
Aug 24 09:57:25 phoniebox kernel: [    1.916486] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Aug 24 09:57:25 phoniebox systemd[1]: Reached target NFS client services.
Aug 24 09:57:25 phoniebox systemd[1]: Reached target Remote File Systems (Pre).
Aug 24 09:57:25 phoniebox systemd[1]: Reached target Remote File Systems.
Aug 24 09:57:25 phoniebox systemd[1]: Started Create Volatile Files and Directories.
Aug 24 09:57:25 phoniebox systemd[1]: Starting Network Time Synchronization...
Aug 24 09:57:25 phoniebox systemd[1]: Starting Update UTMP about System Boot/Shutdown...
Aug 24 09:57:25 phoniebox systemd[1]: Started Update UTMP about System Boot/Shutdown.
Aug 24 09:57:25 phoniebox systemd[1]: Started Raise network interfaces.
Aug 24 09:57:25 phoniebox systemd[1]: Started Network Time Synchronization.
Aug 24 09:57:25 phoniebox systemd[1]: Reached target System Initialization.
Aug 24 09:57:25 phoniebox systemd[1]: Started Daily Cleanup of Temporary Directories.
Aug 24 09:57:25 phoniebox systemd[1]: Listening on mpd.socket.
Aug 24 09:57:25 phoniebox systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
Aug 24 09:57:25 phoniebox systemd[1]: Listening on D-Bus System Message Bus Socket.
Aug 24 09:57:25 phoniebox systemd[1]: Listening on triggerhappy.socket.
Aug 24 09:57:25 phoniebox systemd[1]: Reached target Sockets.
Aug 24 09:57:25 phoniebox systemd[1]: Reached target Basic System.
Aug 24 09:57:25 phoniebox systemd[1]: Started Regular background program processing daemon.
Aug 24 09:57:25 phoniebox systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped.
Aug 24 09:57:25 phoniebox systemd[1]: Condition check resulted in Turn on SSH if /boot/ssh is present being skipped.
Aug 24 09:57:25 phoniebox systemd[1]: Starting LSB: Switch to ondemand cpu governor (unless shift key is pressed)...
Aug 24 09:57:25 phoniebox cron[355]: (CRON) INFO (pidfile fd = 3)
Aug 24 09:57:25 phoniebox systemd[1]: Starting System Logging Service...
Aug 24 09:57:25 phoniebox systemd[1]: Starting dphys-swapfile - set up, mount/unmount, and delete a swap file...
Aug 24 09:57:25 phoniebox systemd[1]: Started Manage Sound Card State (restore and store).
Aug 24 09:57:25 phoniebox cron[355]: (CRON) INFO (Running @reboot jobs)
Aug 24 09:57:25 phoniebox systemd[1]: Starting Login Service...
Aug 24 09:57:25 phoniebox alsactl[360]: alsactl 1.1.8 daemon started
Aug 24 09:57:25 phoniebox systemd[1]: Started D-Bus System Message Bus.
Aug 24 09:57:25 phoniebox systemd[1]: Starting Check for Raspberry Pi EEPROM updates...
Aug 24 09:57:25 phoniebox systemd[1]: Starting rng-tools.service...
Aug 24 09:57:25 phoniebox systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped.
Aug 24 09:57:25 phoniebox systemd[1]: Starting Deferred execution scheduler...
Aug 24 09:57:25 phoniebox systemd[1]: Starting Configure Bluetooth Modems connected by UART...
Aug 24 09:57:25 phoniebox systemd[1]: Starting triggerhappy global hotkey daemon...
Aug 24 09:57:25 phoniebox systemd[1]: Starting dhcpcd on all interfaces...
Aug 24 09:57:25 phoniebox systemd[1]: Starting Save/Restore Sound Card State...
Aug 24 09:57:25 phoniebox rng-tools[370]: Starting Hardware RNG entropy gatherer daemon: rngd.
Aug 24 09:57:25 phoniebox kernel: [    1.916711] FS-Cache: Loaded
Aug 24 09:57:25 phoniebox rngd[389]: rngd 2-unofficial-mt.14 starting up...
Aug 24 09:57:25 phoniebox kernel: [    1.917009] CacheFiles: Loaded
Aug 24 09:57:25 phoniebox kernel: [    1.927861] NET: Registered protocol family 2
Aug 24 09:57:25 phoniebox kernel: [    1.928157] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
Aug 24 09:57:25 phoniebox kernel: [    1.929596] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
Aug 24 09:57:25 phoniebox kernel: [    1.929696] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
Aug 24 09:57:25 phoniebox kernel: [    1.929924] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear)
Aug 24 09:57:25 phoniebox kernel: [    1.930139] TCP: Hash tables configured (established 8192 bind 8192)
Aug 24 09:57:25 phoniebox kernel: [    1.930338] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
Aug 24 09:57:25 phoniebox kernel: [    1.930417] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
Aug 24 09:57:25 phoniebox kernel: [    1.931094] NET: Registered protocol family 1
Aug 24 09:57:25 phoniebox kernel: [    1.931943] RPC: Registered named UNIX socket transport module.
Aug 24 09:57:25 phoniebox kernel: [    1.931978] RPC: Registered udp transport module.
Aug 24 09:57:25 phoniebox kernel: [    1.932007] RPC: Registered tcp transport module.
Aug 24 09:57:25 phoniebox kernel: [    1.932036] RPC: Registered tcp NFSv4.1 backchannel transport module.
Aug 24 09:57:25 phoniebox kernel: [    1.933703] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
Aug 24 09:57:25 phoniebox kernel: [    1.937596] Initialise system trusted keyrings
Aug 24 09:57:25 phoniebox kernel: [    1.937908] workingset: timestamp_bits=14 max_order=18 bucket_order=4
Aug 24 09:57:25 phoniebox kernel: [    1.947509] zbud: loaded
Aug 24 09:57:25 phoniebox kernel: [    1.949606] FS-Cache: Netfs 'nfs' registered for caching
Aug 24 09:57:25 phoniebox kernel: [    1.950503] NFS: Registering the id_resolver key type
Aug 24 09:57:25 phoniebox kernel: [    1.950571] Key type id_resolver registered
Aug 24 09:57:25 phoniebox kernel: [    1.950601] Key type id_legacy registered
Aug 24 09:57:25 phoniebox kernel: [    1.950775] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
Aug 24 09:57:25 phoniebox kernel: [    1.950811] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
Aug 24 09:57:25 phoniebox kernel: [    1.952051] Key type asymmetric registered
Aug 24 09:57:25 phoniebox kernel: [    1.952085] Asymmetric key parser 'x509' registered
Aug 24 09:57:25 phoniebox kernel: [    1.952158] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
Aug 24 09:57:25 phoniebox kernel: [    1.952198] io scheduler mq-deadline registered
Aug 24 09:57:25 phoniebox kernel: [    1.952228] io scheduler kyber registered
Aug 24 09:57:25 phoniebox kernel: [    1.955837] bcm2708_fb soc:fb: FB found 1 display(s)
Aug 24 09:57:25 phoniebox kernel: [    1.968332] Console: switching to colour frame buffer device 82x26
Aug 24 09:57:25 phoniebox kernel: [    1.975680] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 656x416
Aug 24 09:57:25 phoniebox kernel: [    1.986410] bcm2835-rng 3f104000.rng: hwrng registered
Aug 24 09:57:25 phoniebox kernel: [    1.989383] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
Aug 24 09:57:25 phoniebox kernel: [    1.995403] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
Aug 24 09:57:25 phoniebox kernel: [    2.013022] brd: module loaded
Aug 24 09:57:25 phoniebox kernel: [    2.028059] loop: module loaded
Aug 24 09:57:25 phoniebox kernel: [    2.032518] Loading iSCSI transport class v2.0-870.
Aug 24 09:57:25 phoniebox kernel: [    2.036866] libphy: Fixed MDIO Bus: probed
Aug 24 09:57:25 phoniebox kernel: [    2.039779] usbcore: registered new interface driver lan78xx
Aug 24 09:57:25 phoniebox kernel: [    2.042463] usbcore: registered new interface driver smsc95xx
Aug 24 09:57:25 phoniebox kernel: [    2.045065] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
Aug 24 09:57:25 phoniebox kernel: [    2.775894] Core Release: 2.80a
Aug 24 09:57:25 phoniebox kernel: [    2.778376] Setting default values for core params
Aug 24 09:57:25 phoniebox kernel: [    2.780832] Finished setting default values for core params
Aug 24 09:57:25 phoniebox kernel: [    2.983680] Using Buffer DMA mode
Aug 24 09:57:25 phoniebox kernel: [    2.986165] Periodic Transfer Interrupt Enhancement - disabled
Aug 24 09:57:25 phoniebox kernel: [    2.988715] Multiprocessor Interrupt Enhancement - disabled
Aug 24 09:57:25 phoniebox kernel: [    2.991246] OTG VER PARAM: 0, OTG VER FLAG: 0
Aug 24 09:57:25 phoniebox kernel: [    2.993731] Dedicated Tx FIFOs mode
Aug 24 09:57:25 phoniebox kernel: [    2.996682]
Aug 24 09:57:25 phoniebox kernel: [    2.996703] WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = b7514000 dma = 0xf7514000 len=9024
Aug 24 09:57:25 phoniebox kernel: [    3.003635] FIQ FSM acceleration enabled for :
Aug 24 09:57:25 phoniebox kernel: [    3.003635] Non-periodic Split Transactions
Aug 24 09:57:25 phoniebox kernel: [    3.003635] Periodic Split Transactions
Aug 24 09:57:25 phoniebox kernel: [    3.003635] High-Speed Isochronous Endpoints
Aug 24 09:57:25 phoniebox kernel: [    3.003635] Interrupt/Control Split Transaction hack enabled
Aug 24 09:57:25 phoniebox kernel: [    3.014531] dwc_otg: Microframe scheduler enabled
Aug 24 09:57:25 phoniebox kernel: [    3.014595]
Aug 24 09:57:25 phoniebox kernel: [    3.014605] WARN::hcd_init_fiq:457: FIQ on core 1
Aug 24 09:57:25 phoniebox kernel: [    3.018907]
Aug 24 09:57:25 phoniebox kernel: [    3.018917] WARN::hcd_init_fiq:458: FIQ ASM at 807c9984 length 36
Aug 24 09:57:25 phoniebox kernel: [    3.023338]
Aug 24 09:57:25 phoniebox kernel: [    3.023346] WARN::hcd_init_fiq:497: MPHI regs_base at bb810000
Aug 24 09:57:25 phoniebox kernel: [    3.027826] dwc_otg 3f980000.usb: DWC OTG Controller
Aug 24 09:57:25 phoniebox kernel: [    3.030136] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
Aug 24 09:57:25 phoniebox kernel: [    3.032505] dwc_otg 3f980000.usb: irq 89, io mem 0x00000000
Aug 24 09:57:25 phoniebox kernel: [    3.034842] Init: Port Power? op_state=1
Aug 24 09:57:25 phoniebox kernel: [    3.037072] Init: Power Port (0)
Aug 24 09:57:25 phoniebox kernel: [    3.039594] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10
Aug 24 09:57:25 phoniebox kernel: [    3.044179] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Aug 24 09:57:25 phoniebox kernel: [    3.046654] usb usb1: Product: DWC OTG Controller
Aug 24 09:57:25 phoniebox kernel: [    3.049008] usb usb1: Manufacturer: Linux 5.10.63-v7+ dwc_otg_hcd
Aug 24 09:57:25 phoniebox kernel: [    3.051393] usb usb1: SerialNumber: 3f980000.usb
Aug 24 09:57:25 phoniebox kernel: [    3.054548] hub 1-0:1.0: USB hub found
Aug 24 09:57:25 phoniebox kernel: [    3.056990] hub 1-0:1.0: 1 port detected
Aug 24 09:57:25 phoniebox kernel: [    3.060567] dwc_otg: FIQ enabled
Aug 24 09:57:25 phoniebox kernel: [    3.060580] dwc_otg: NAK holdoff enabled
Aug 24 09:57:25 phoniebox kernel: [    3.060591] dwc_otg: FIQ split-transaction FSM enabled
Aug 24 09:57:25 phoniebox kernel: [    3.060609] Module dwc_common_port init
Aug 24 09:57:25 phoniebox kernel: [    3.060915] usbcore: registered new interface driver usb-storage
Aug 24 09:57:25 phoniebox kernel: [    3.063693] mousedev: PS/2 mouse device common for all mice
Aug 24 09:57:25 phoniebox kernel: [    3.067340] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
Aug 24 09:57:25 phoniebox kernel: [    3.072658] sdhci: Secure Digital Host Controller Interface driver
Aug 24 09:57:25 phoniebox kernel: [    3.075245] sdhci: Copyright(c) Pierre Ossman
Aug 24 09:57:25 phoniebox kernel: [    3.078474] mmc-bcm2835 3f300000.mmcnr: could not get clk, deferring probe
Aug 24 09:57:25 phoniebox kernel: [    3.081705] sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe
Aug 24 09:57:25 phoniebox kernel: [    3.084544] sdhci-pltfm: SDHCI platform and OF driver helper
Aug 24 09:57:25 phoniebox kernel: [    3.088991] ledtrig-cpu: registered to indicate activity on CPUs
Aug 24 09:57:25 phoniebox kernel: [    3.092010] hid: raw HID events driver (C) Jiri Kosina
Aug 24 09:57:25 phoniebox kernel: [    3.094810] usbcore: registered new interface driver usbhid
Aug 24 09:57:25 phoniebox kernel: [    3.097409] usbhid: USB HID core driver
Aug 24 09:57:25 phoniebox kernel: [    3.105219] Initializing XFRM netlink socket
Aug 24 09:57:25 phoniebox kernel: [    3.107830] NET: Registered protocol family 17
Aug 24 09:57:25 phoniebox kernel: [    3.110451] Key type dns_resolver registered
Aug 24 09:57:25 phoniebox kernel: [    3.113417] Registering SWP/SWPB emulation handler
Aug 24 09:57:25 phoniebox kernel: [    3.115963] registered taskstats version 1
Aug 24 09:57:25 phoniebox kernel: [    3.118306] Loading compiled-in X.509 certificates
Aug 24 09:57:25 phoniebox kernel: [    3.121552] Key type ._fscrypt registered
Aug 24 09:57:25 phoniebox kernel: [    3.123878] Key type .fscrypt registered
Aug 24 09:57:25 phoniebox kernel: [    3.126235] Key type fscrypt-provisioning registered
Aug 24 09:57:25 phoniebox kernel: [    3.140391] uart-pl011 3f201000.serial: cts_event_workaround enabled
Aug 24 09:57:25 phoniebox kernel: [    3.142890] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 114, base_baud = 0) is a PL011 rev2
Aug 24 09:57:25 phoniebox kernel: [    3.150003] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
Aug 24 09:57:25 phoniebox kernel: [    3.154186] mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0
Aug 24 09:57:25 phoniebox kernel: [    3.156708] mmc-bcm2835 3f300000.mmcnr: DMA channel allocated
Aug 24 09:57:25 phoniebox kernel: [    3.185859] Indeed it is in host mode hprt0 = 00021501
Aug 24 09:57:25 phoniebox kernel: [    3.248389] sdhost: log_buf @ (ptrval) (f7513000)
Aug 24 09:57:25 phoniebox kernel: [    3.285535] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
Aug 24 09:57:25 phoniebox kernel: [    3.289567] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Aug 24 09:57:25 phoniebox kernel: [    3.293485] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Aug 24 09:57:25 phoniebox kernel: [    3.298702] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
Aug 24 09:57:25 phoniebox kernel: [    3.301919] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
Aug 24 09:57:25 phoniebox kernel: [    3.307107] of_cfs_init
Aug 24 09:57:25 phoniebox kernel: [    3.309398] of_cfs_init: OK
Aug 24 09:57:25 phoniebox kernel: [    3.312550] Waiting for root device PARTUUID=ae99cb2e-02...
Aug 24 09:57:25 phoniebox kernel: [    3.390995] mmc0: host does not support reading read-only switch, assuming write-enable
Aug 24 09:57:25 phoniebox kernel: [    3.395379] usb 1-1: new high-speed USB device number 2 using dwc_otg
Aug 24 09:57:25 phoniebox kernel: [    3.397911] Indeed it is in host mode hprt0 = 00001101
Aug 24 09:57:25 phoniebox kernel: [    3.462076] mmc0: new high speed SDHC card at address aaaa
Aug 24 09:57:25 phoniebox kernel: [    3.465585] mmcblk0: mmc0:aaaa SC32G 29.7 GiB
Aug 24 09:57:25 phoniebox kernel: [    3.468651] mmc1: new high speed SDIO card at address 0001
Aug 24 09:57:25 phoniebox kernel: [    3.476897]  mmcblk0: p1 p2
Aug 24 09:57:25 phoniebox kernel: [    3.519813] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
Aug 24 09:57:25 phoniebox kernel: [    3.524335] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
Aug 24 09:57:25 phoniebox kernel: [    3.536697] devtmpfs: mounted
Aug 24 09:57:25 phoniebox kernel: [    3.546092] Freeing unused kernel memory: 1024K
Aug 24 09:57:25 phoniebox kernel: [    3.574819] Run /sbin/init as init process
Aug 24 09:57:25 phoniebox kernel: [    3.577191]   with arguments:
Aug 24 09:57:25 phoniebox kernel: [    3.577203]     /sbin/init
Aug 24 09:57:25 phoniebox kernel: [    3.577213]   with environment:
Aug 24 09:57:25 phoniebox kernel: [    3.577225]     HOME=/
Aug 24 09:57:25 phoniebox kernel: [    3.577235]     TERM=linux
Aug 24 09:57:25 phoniebox kernel: [    3.634734] usb 1-1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3
Aug 24 09:57:25 phoniebox kernel: [    3.639963] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Aug 24 09:57:25 phoniebox kernel: [    3.643637] hub 1-1:1.0: USB hub found
Aug 24 09:57:25 phoniebox kernel: [    3.646581] hub 1-1:1.0: 4 ports detected
Aug 24 09:57:25 phoniebox kernel: [    3.964405] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
Aug 24 09:57:25 phoniebox kernel: [    4.094770] usb 1-1.1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3
Aug 24 09:57:25 phoniebox kernel: [    4.100378] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Aug 24 09:57:25 phoniebox kernel: [    4.104096] hub 1-1.1:1.0: USB hub found
Aug 24 09:57:25 phoniebox kernel: [    4.107058] hub 1-1.1:1.0: 3 ports detected
Aug 24 09:57:25 phoniebox kernel: [    4.303693] NET: Registered protocol family 10
Aug 24 09:57:25 phoniebox kernel: [    4.307984] Segment Routing with IPv6
Aug 24 09:57:25 phoniebox kernel: [    4.434365] usb 1-1.1.2: new full-speed USB device number 4 using dwc_otg
Aug 24 09:57:25 phoniebox kernel: [    4.579199] usb 1-1.1.2: New USB device found, idVendor=ffff, idProduct=0035, bcdDevice= 1.00
Aug 24 09:57:25 phoniebox kernel: [    4.592736] usb 1-1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Aug 24 09:57:25 phoniebox kernel: [    4.610803] usb 1-1.1.2: Product: IC Reader
Aug 24 09:57:25 phoniebox kernel: [    4.613826] usb 1-1.1.2: Manufacturer: IC Reader
Aug 24 09:57:25 phoniebox kernel: [    4.616879] usb 1-1.1.2: SerialNumber: 08FF20171101
Aug 24 09:57:25 phoniebox kernel: [    4.630760] input: IC Reader IC Reader as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.0/0003:FFFF:0035.0001/input/input0
Aug 24 09:57:25 phoniebox kernel: [    4.705968] hid-generic 0003:FFFF:0035.0001: input,hidraw0: USB HID v1.10 Keyboard [IC Reader IC Reader] on usb-3f980000.usb-1.1.2/input0
Aug 24 09:57:25 phoniebox kernel: [    4.713901] usbhid 1-1.1.2:1.1: couldn't find an input interrupt endpoint
Aug 24 09:57:25 phoniebox kernel: [    5.014405] usb 1-1.1.1: new high-speed USB device number 5 using dwc_otg
Aug 24 09:57:25 phoniebox kernel: [    5.144970] usb 1-1.1.1: New USB device found, idVendor=0424, idProduct=7800, bcdDevice= 3.00
Aug 24 09:57:25 phoniebox kernel: [    5.150801] usb 1-1.1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Aug 24 09:57:25 phoniebox kernel: [    5.422526] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): No External EEPROM. Setting MAC Speed
Aug 24 09:57:25 phoniebox kernel: [    5.431013] libphy: lan78xx-mdiobus: probed
Aug 24 09:57:25 phoniebox kernel: [    5.456297] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): int urb period 64
Aug 24 09:57:25 phoniebox kernel: [    5.499583] random: systemd: uninitialized urandom read (16 bytes read)
Aug 24 09:57:25 phoniebox kernel: [    5.519162] random: systemd: uninitialized urandom read (16 bytes read)
Aug 24 09:57:25 phoniebox kernel: [    5.532749] random: systemd: uninitialized urandom read (16 bytes read)
Aug 24 09:57:25 phoniebox kernel: [    6.434664] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
Aug 24 09:57:25 phoniebox kernel: [    7.141992] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
Aug 24 09:57:25 phoniebox kernel: [    7.144785] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
Aug 24 09:57:25 phoniebox kernel: [    7.144812] [vc_sm_connected_init]: start
Aug 24 09:57:25 phoniebox kernel: [    7.150949] mc: Linux media interface: v0.10
Aug 24 09:57:25 phoniebox kernel: [    7.154034] [vc_sm_connected_init]: installed successfully
Aug 24 09:57:25 phoniebox kernel: [    7.213968] videodev: Linux video capture interface: v2.00
Aug 24 09:57:25 phoniebox kernel: [    7.281083] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
Aug 24 09:57:25 phoniebox kernel: [    7.285346] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
Aug 24 09:57:25 phoniebox kernel: [    7.292877] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
Aug 24 09:57:25 phoniebox kernel: [    7.306379] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
Aug 24 09:57:25 phoniebox kernel: [    7.306974] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
Aug 24 09:57:25 phoniebox kernel: [    7.307421] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
Aug 24 09:57:25 phoniebox kernel: [    7.307845] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
Aug 24 09:57:25 phoniebox kernel: [    7.307882] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
Aug 24 09:57:25 phoniebox kernel: [    7.307908] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
Aug 24 09:57:25 phoniebox kernel: [    7.307929] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
Aug 24 09:57:25 phoniebox kernel: [    7.307951] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
Aug 24 09:57:25 phoniebox kernel: [    7.308170] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
Aug 24 09:57:25 phoniebox kernel: [    7.314719] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
Aug 24 09:57:25 phoniebox kernel: [    7.322683] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
Aug 24 09:57:25 phoniebox kernel: [    7.329457] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
Aug 24 09:57:25 phoniebox kernel: [    7.375350] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
Aug 24 09:57:25 phoniebox kernel: [    7.375532] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
Aug 24 09:57:25 phoniebox kernel: [    7.391972] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
Aug 24 09:57:25 phoniebox kernel: [    7.392166] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
Aug 24 09:57:25 phoniebox kernel: [    7.396175] bcm2835_audio bcm2835_audio: card created with 8 channels
Aug 24 09:57:25 phoniebox kernel: [    7.408424] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
Aug 24 09:57:25 phoniebox kernel: [    7.408480] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
Aug 24 09:57:25 phoniebox kernel: [    7.416946] bcm2835-codec bcm2835-codec: Device registered as /dev/video18
Aug 24 09:57:25 phoniebox kernel: [    7.417001] bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx
Aug 24 09:57:25 phoniebox kernel: [    7.511032] cfg80211: Loading compiled-in X.509 certificates for regulatory database
Aug 24 09:57:25 phoniebox kernel: [    7.630883] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
Aug 24 09:57:25 phoniebox kernel: [    7.755044] brcmfmac: F1 signature read @0x18000000=0x15264345
Aug 24 09:57:25 phoniebox kernel: [    7.765442] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
Aug 24 09:57:25 phoniebox kernel: [    7.767618] usbcore: registered new interface driver brcmfmac
Aug 24 09:57:25 phoniebox kernel: [    7.909270] random: crng init done
Aug 24 09:57:25 phoniebox kernel: [    7.909292] random: 7 urandom warning(s) missed due to ratelimiting
Aug 24 09:57:25 phoniebox kernel: [    8.062899] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
Aug 24 09:57:25 phoniebox kernel: [    8.063036] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
Aug 24 09:57:25 phoniebox kernel: [    8.075219] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Jan  4 2021 19:56:29 version 7.45.229 (617f1f5 CY) FWID 01-2dbd9d2e
Aug 24 09:57:25 phoniebox kernel: [    9.594457] Under-voltage detected! (0x00050000)
Aug 24 09:57:25 phoniebox rngd[389]: entropy feed to the kernel ready
Aug 24 09:57:25 phoniebox systemd[1]: Starting WPA supplicant...
Aug 24 09:57:25 phoniebox rsyslogd: imuxsock: Acquired UNIX socket '/run/systemd/journal/syslog' (fd 3) from systemd.  [v8.1901.0]
Aug 24 09:57:25 phoniebox thd[376]: Found socket passed from systemd
Aug 24 09:57:25 phoniebox rsyslogd:  [origin software="rsyslogd" swVersion="8.1901.0" x-pid="357" x-info="https://www.rsyslog.com"] start
Aug 24 09:57:25 phoniebox systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
Aug 24 09:57:25 phoniebox rpi-eeprom-update[368]: This tool only works with a Raspberry Pi 4
Aug 24 09:57:25 phoniebox systemd[1]: Reached target System Time Synchronized.
Aug 24 09:57:25 phoniebox systemd[1]: Started Clean PHP session files every 30 mins.
Aug 24 09:57:25 phoniebox systemd[1]: Started Daily rotation of log files.
Aug 24 09:57:25 phoniebox systemd[1]: Started Daily man-db regeneration.
Aug 24 09:57:25 phoniebox systemd[1]: Started Daily apt download activities.
Aug 24 09:57:25 phoniebox systemd[1]: Started Daily apt upgrade and clean activities.
Aug 24 09:57:25 phoniebox systemd[1]: Reached target Timers.
Aug 24 09:57:25 phoniebox systemd[1]: Started System Logging Service.
Aug 24 09:57:25 phoniebox systemd[1]: Started triggerhappy global hotkey daemon.
Aug 24 09:57:25 phoniebox systemd[1]: Started Check for Raspberry Pi EEPROM updates.
Aug 24 09:57:25 phoniebox dhcpcd[377]: dev: loaded udev
Aug 24 09:57:25 phoniebox systemd[1]: Started rng-tools.service.
Aug 24 09:57:25 phoniebox systemd[1]: Started Deferred execution scheduler.
Aug 24 09:57:25 phoniebox systemd[1]: Started Save/Restore Sound Card State.
Aug 24 09:57:25 phoniebox avahi-daemon[409]: Found user 'avahi' (UID 108) and group 'avahi' (GID 113).
Aug 24 09:57:25 phoniebox avahi-daemon[409]: Successfully dropped root privileges.
Aug 24 09:57:25 phoniebox avahi-daemon[409]: avahi-daemon 0.7 starting up.
Aug 24 09:57:25 phoniebox kernel: [   10.980336] uart-pl011 3f201000.serial: no DMA platform data
Aug 24 09:57:25 phoniebox systemd[1]: Reached target Sound Card.
Aug 24 09:57:25 phoniebox kernel: [   10.996157] 8021q: 802.1Q VLAN Support v1.8
Aug 24 09:57:25 phoniebox dbus-daemon[367]: [system] Successfully activated service 'org.freedesktop.systemd1'
Aug 24 09:57:25 phoniebox systemd[1]: Started Login Service.
Aug 24 09:57:25 phoniebox avahi-daemon[409]: Successfully called chroot().
Aug 24 09:57:25 phoniebox avahi-daemon[409]: Successfully dropped remaining capabilities.
Aug 24 09:57:25 phoniebox avahi-daemon[409]: No service file found in /etc/avahi/services.
Aug 24 09:57:25 phoniebox avahi-daemon[409]: Network interface enumeration completed.
Aug 24 09:57:25 phoniebox avahi-daemon[409]: Server startup complete. Host name is phoniebox.local. Local service cookie is 1342834420.
Aug 24 09:57:25 phoniebox systemd[1]: Started Avahi mDNS/DNS-SD Stack.
Aug 24 09:57:25 phoniebox systemd[1]: Started WPA supplicant.
Aug 24 09:57:25 phoniebox wpa_supplicant[397]: Successfully initialized wpa_supplicant
Aug 24 09:57:25 phoniebox dhcpcd[377]: wlan0: starting wpa_supplicant
Aug 24 09:57:25 phoniebox dhcpcd-run-hooks[466]: wlan0: starting wpa_supplicant
Aug 24 09:57:25 phoniebox dphys-swapfile[359]: want /var/swap=100MByte, checking existing: deleting wrong size file (52428800), generating swapfile ... of 100MBytes
Aug 24 09:57:25 phoniebox kernel: [   11.394521] Adding 102396k swap on /var/swap.  Priority:-2 extents:2 across:14651388k SSFS
Aug 24 09:57:25 phoniebox systemd[1]: Started dphys-swapfile - set up, mount/unmount, and delete a swap file.
Aug 24 09:57:25 phoniebox kernel: [   11.448052] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
Aug 24 09:57:25 phoniebox kernel: [   11.674520] Voltage normalised (0x00000000)
Aug 24 09:57:26 phoniebox dhcpcd[377]: wlan0: connected to Access Point `'
Aug 24 09:57:26 phoniebox dhcpcd[377]: eth0: waiting for carrier
Aug 24 09:57:26 phoniebox dhcpcd[377]: wlan0: waiting for carrier
Aug 24 09:57:26 phoniebox kernel: [   11.846800] 8021q: adding VLAN 0 to HW filter on device eth0
Aug 24 09:57:26 phoniebox raspi-config[356]: Checking if shift key is held down: No. Switching to ondemand scaling governor.
Aug 24 09:57:26 phoniebox systemd[1]: Started LSB: Switch to ondemand cpu governor (unless shift key is pressed).
Aug 24 09:57:28 phoniebox systemd[1]: systemd-rfkill.service: Succeeded.
Aug 24 09:57:31 phoniebox dhcpcd[377]: wlan0: carrier acquired
Aug 24 09:57:31 phoniebox kernel: [   16.860814] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Aug 24 09:57:31 phoniebox dhcpcd[377]: wlan0: connected to Access Point `xxxxxxx'
Aug 24 09:57:31 phoniebox dhcpcd[377]: DUID 00:01:00:01:29:3a:ec:cc:b8:27:eb:0b:d0:f7
Aug 24 09:57:31 phoniebox dhcpcd[377]: wlan0: IAID eb:0b:d0:f7
Aug 24 09:57:31 phoniebox dhcpcd[377]: wlan0: adding address fe80::d6be:de9b:374e:e332
Aug 24 09:57:31 phoniebox avahi-daemon[409]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::d6be:de9b:374e:e332.
Aug 24 09:57:31 phoniebox avahi-daemon[409]: New relevant interface wlan0.IPv6 for mDNS.
Aug 24 09:57:31 phoniebox avahi-daemon[409]: Registering new address record for fe80::d6be:de9b:374e:e332 on wlan0.*.
Aug 24 09:57:31 phoniebox dhcpcd[377]: wlan0: rebinding lease of 192.168.178.34
Aug 24 09:57:31 phoniebox dhcpcd[377]: wlan0: probing address 192.168.178.34/24
Aug 24 09:57:31 phoniebox kernel: [   17.699867] Bluetooth: Core ver 2.22
Aug 24 09:57:31 phoniebox kernel: [   17.700000] NET: Registered protocol family 31
Aug 24 09:57:31 phoniebox kernel: [   17.700010] Bluetooth: HCI device and connection manager initialized
Aug 24 09:57:31 phoniebox kernel: [   17.700656] Bluetooth: HCI socket layer initialized
Aug 24 09:57:31 phoniebox kernel: [   17.700676] Bluetooth: L2CAP socket layer initialized
Aug 24 09:57:31 phoniebox kernel: [   17.700706] Bluetooth: SCO socket layer initialized
Aug 24 09:57:31 phoniebox kernel: [   17.714272] Bluetooth: HCI UART driver ver 2.3
Aug 24 09:57:31 phoniebox kernel: [   17.714386] Bluetooth: HCI UART protocol H4 registered
Aug 24 09:57:31 phoniebox kernel: [   17.714503] Bluetooth: HCI UART protocol Three-wire (H5) registered
Aug 24 09:57:31 phoniebox btuart[375]: bcm43xx_init
Aug 24 09:57:31 phoniebox btuart[375]: Flash firmware /lib/firmware/brcm/BCM4345C0.hcd
Aug 24 09:57:31 phoniebox btuart[375]: Set BDADDR UART: b8:27:eb:f4:2f:08
Aug 24 09:57:31 phoniebox kernel: [   17.715696] Bluetooth: HCI UART protocol Broadcom registered
Aug 24 09:57:31 phoniebox btuart[375]: Set Controller UART speed to 3000000 bit/s
Aug 24 09:57:31 phoniebox btuart[375]: Device setup complete
Aug 24 09:57:31 phoniebox systemd[1]: Starting Load/Save RF Kill Switch Status...
Aug 24 09:57:31 phoniebox systemd[1]: Started Configure Bluetooth Modems connected by UART.
Aug 24 09:57:32 phoniebox systemd[1]: Started Load/Save RF Kill Switch Status.
Aug 24 09:57:32 phoniebox systemd[1]: Created slice system-bthelper.slice.
Aug 24 09:57:32 phoniebox systemd[1]: Starting Raspberry Pi bluetooth helper...
Aug 24 09:57:32 phoniebox dhcpcd[377]: wlan0: soliciting an IPv6 router
Aug 24 09:57:32 phoniebox bthelper[493]: Raspberry Pi BDADDR already set
Aug 24 09:57:32 phoniebox systemd[1]: Started Raspberry Pi bluetooth helper.
Aug 24 09:57:32 phoniebox systemd[1]: Starting Bluetooth service...
Aug 24 09:57:32 phoniebox bluetoothd[503]: Bluetooth daemon 5.50
Aug 24 09:57:32 phoniebox systemd[1]: Started Bluetooth service.
Aug 24 09:57:32 phoniebox systemd[1]: Reached target Bluetooth.
Aug 24 09:57:32 phoniebox bluetoothd[503]: Starting SDP server
Aug 24 09:57:32 phoniebox dbus-daemon[367]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=503 comm="/usr/lib/bluetooth/bluetoothd ")
Aug 24 09:57:32 phoniebox bluetoothd[503]: Bluetooth management interface 1.18 initialized
Aug 24 09:57:32 phoniebox kernel: [   18.017765] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Aug 24 09:57:32 phoniebox kernel: [   18.017777] Bluetooth: BNEP filters: protocol multicast
Aug 24 09:57:32 phoniebox kernel: [   18.017794] Bluetooth: BNEP socket layer initialized
Aug 24 09:57:32 phoniebox bluetoothd[503]: Sap driver initialization failed.
Aug 24 09:57:32 phoniebox bluetoothd[503]: sap-server: Operation not permitted (1)
Aug 24 09:57:32 phoniebox systemd[1]: Starting Hostname Service...
Aug 24 09:57:32 phoniebox bluetoothd[503]: Failed to set privacy: Rejected (0x0b)
Aug 24 09:57:32 phoniebox dbus-daemon[367]: [system] Successfully activated service 'org.freedesktop.hostname1'
Aug 24 09:57:32 phoniebox systemd[1]: Started Hostname Service.
Aug 24 09:57:32 phoniebox kernel: [   18.563682] ICMPv6: process `dhcpcd' is using deprecated sysctl (syscall) net.ipv6.neigh.wlan0.retrans_time - use net.ipv6.neigh.wlan0.retrans_time_ms instead
Aug 24 09:57:32 phoniebox dhcpcd[377]: wlan0: Router Advertisement from fe80::7eff:4dff:fe97:22fe
Aug 24 09:57:32 phoniebox dhcpcd[377]: wlan0: adding address 2a01:c23:8dc8:bd00:c0:e3c8:2233:8860/64
Aug 24 09:57:32 phoniebox avahi-daemon[409]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::d6be:de9b:374e:e332.
Aug 24 09:57:32 phoniebox avahi-daemon[409]: Joining mDNS multicast group on interface wlan0.IPv6 with address 2a01:c23:8dc8:bd00:c0:e3c8:2233:8860.
Aug 24 09:57:32 phoniebox avahi-daemon[409]: Registering new address record for 2a01:c23:8dc8:bd00:c0:e3c8:2233:8860 on wlan0.*.
Aug 24 09:57:32 phoniebox dhcpcd[377]: wlan0: adding route to 2a01:c23:8dc8:bd00::/64
Aug 24 09:57:32 phoniebox avahi-daemon[409]: Withdrawing address record for fe80::d6be:de9b:374e:e332 on wlan0.
Aug 24 09:57:32 phoniebox dhcpcd[377]: wlan0: confirming prior DHCPv6 lease
Aug 24 09:57:32 phoniebox dhcpcd[377]: wlan0: fe80::7eff:4dff:fe97:22fe is reachable again
Aug 24 09:57:32 phoniebox dhcpcd[377]: wlan0: adding default route via fe80::7eff:4dff:fe97:22fe
Aug 24 09:57:33 phoniebox dhcpcd[377]: wlan0: REPLY6 received from fe80::7eff:4dff:fe97:22fe
Aug 24 09:57:33 phoniebox dhcpcd[377]: wlan0: adding address 2a01:c23:8dc8:bd00:d6be:de9b:374e:e332/128
Aug 24 09:57:33 phoniebox dhcpcd[377]: wlan0: renew in 1800, rebind in 2880, expire in 7200 seconds
Aug 24 09:57:33 phoniebox avahi-daemon[409]: Registering new address record for 2a01:c23:8dc8:bd00:d6be:de9b:374e:e332 on wlan0.*.
Aug 24 09:57:37 phoniebox systemd[1]: systemd-rfkill.service: Succeeded.
Aug 24 09:57:37 phoniebox dhcpcd[377]: wlan0: leased 192.168.178.34 for 864000 seconds
Aug 24 09:57:37 phoniebox avahi-daemon[409]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.34.
Aug 24 09:57:37 phoniebox avahi-daemon[409]: New relevant interface wlan0.IPv4 for mDNS.
Aug 24 09:57:37 phoniebox avahi-daemon[409]: Registering new address record for 192.168.178.34 on wlan0.IPv4.
Aug 24 09:57:37 phoniebox dhcpcd[377]: wlan0: adding route to 192.168.178.0/24
Aug 24 09:57:37 phoniebox dhcpcd[377]: wlan0: adding default route via 192.168.178.1
Aug 24 09:57:37 phoniebox bthelper[493]: Changing power off succeeded
Aug 24 09:57:37 phoniebox dhcpcd[377]: forked to background, child pid 533
Aug 24 09:57:37 phoniebox systemd[1]: Started dhcpcd on all interfaces.
Aug 24 09:57:37 phoniebox systemd[1]: Reached target Network.
Aug 24 09:57:37 phoniebox systemd[1]: Started Phoniebox RFID-Reader Service.
Aug 24 09:57:37 phoniebox systemd[1]: Started Phoniebox Idle Watchdog Service.
Aug 24 09:57:37 phoniebox systemd[1]: Starting OpenBSD Secure Shell server...
Aug 24 09:57:37 phoniebox systemd[1]: Starting Permit User Sessions...
Aug 24 09:57:37 phoniebox systemd[1]: Reached target Network is Online.
Aug 24 09:57:37 phoniebox systemd[1]: Starting Lighttpd Daemon...
Aug 24 09:57:37 phoniebox systemd[1]: Starting LSB: exim Mail Transport Agent...
Aug 24 09:57:37 phoniebox systemd[1]: Starting Samba NMB Daemon...
Aug 24 09:57:37 phoniebox systemd[1]: Starting Music Player Daemon...
Aug 24 09:57:37 phoniebox systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped.
Aug 24 09:57:37 phoniebox systemd[1]: Starting /etc/rc.local Compatibility...
Aug 24 09:57:37 phoniebox systemd[1]: Started Permit User Sessions.
Aug 24 09:57:37 phoniebox systemd[1]: Started /etc/rc.local Compatibility.
Aug 24 09:57:37 phoniebox bthelper[493]: Changing power on succeeded
Aug 24 09:57:37 phoniebox bthelper[493]: [#033[0;93mCHG#033[0m] Controller B8:27:EB:F4:2F:08 Powered: yes
Aug 24 09:57:37 phoniebox systemd[1]: Started Getty on tty1.
Aug 24 09:57:37 phoniebox systemd[1]: Reached target Login Prompts.
Aug 24 09:57:38 phoniebox systemd[1]: Started OpenBSD Secure Shell server.
Aug 24 09:57:38 phoniebox kernel: [   24.154666] Under-voltage detected! (0x00050005)
Aug 24 09:57:39 phoniebox systemd[1]: Created slice User Slice of UID 1000.
Aug 24 09:57:39 phoniebox systemd[1]: Starting User Runtime Directory /run/user/1000...
Aug 24 09:57:39 phoniebox systemd[1]: Started Lighttpd Daemon.
Aug 24 09:57:39 phoniebox systemd[1]: Started User Runtime Directory /run/user/1000.
Aug 24 09:57:39 phoniebox systemd[1]: Starting User Manager for UID 1000...
Aug 24 09:57:39 phoniebox exim4[542]: Starting MTA: exim4.
Aug 24 09:57:39 phoniebox systemd[1]: Started LSB: exim Mail Transport Agent.
Aug 24 09:57:39 phoniebox systemd[1]: Started Samba NMB Daemon.
Aug 24 09:57:39 phoniebox systemd[1]: Starting Samba SMB Daemon...
Aug 24 09:57:39 phoniebox daemon_rfid_reader.py[534]: 2024-08-24 09:57:39,875 - root - INFO - Dir_PATH: /home/pi/RPi-Jukebox-RFID/scripts
Aug 24 09:57:40 phoniebox systemd[814]: Listening on GnuPG cryptographic agent (ssh-agent emulation).
Aug 24 09:57:40 phoniebox systemd[814]: Listening on GnuPG network certificate management daemon.
Aug 24 09:57:40 phoniebox systemd[814]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers).
Aug 24 09:57:40 phoniebox systemd[814]: Listening on GnuPG cryptographic agent and passphrase cache (restricted).
Aug 24 09:57:40 phoniebox systemd[814]: Reached target Paths.
Aug 24 09:57:40 phoniebox systemd[814]: Reached target Timers.
Aug 24 09:57:40 phoniebox systemd[814]: Listening on GnuPG cryptographic agent and passphrase cache.
Aug 24 09:57:40 phoniebox systemd[814]: Reached target Sockets.
Aug 24 09:57:40 phoniebox systemd[814]: Reached target Basic System.
Aug 24 09:57:40 phoniebox systemd[1]: Started User Manager for UID 1000.
Aug 24 09:57:40 phoniebox systemd[1]: Started Session 1 of user pi.
Aug 24 09:57:40 phoniebox systemd[814]: Starting Music Player Daemon...
Aug 24 09:57:40 phoniebox systemd[1]: Started Samba SMB Daemon.
Aug 24 09:57:42 phoniebox mpd: exception: Failed to bind to '[::1]:6600'
Aug 24 09:57:42 phoniebox mpd: exception: nested: Failed to bind socket: Address already in use
Aug 24 09:57:42 phoniebox systemd[814]: mpd.service: Main process exited, code=exited, status=1/FAILURE
Aug 24 09:57:42 phoniebox systemd[814]: mpd.service: Failed with result 'exit-code'.
Aug 24 09:57:42 phoniebox systemd[814]: Failed to start Music Player Daemon.
Aug 24 09:57:42 phoniebox systemd[814]: Reached target Default.
Aug 24 09:57:42 phoniebox systemd[814]: Startup finished in 3.070s.
Aug 24 09:57:42 phoniebox mpd: zeroconf: No global port, disabling zeroconf
Aug 24 09:57:42 phoniebox systemd[1]: Started Music Player Daemon.
Aug 24 09:57:43 phoniebox systemd[1]: Starting Phoniebox Startup...
Aug 24 09:57:43 phoniebox bash[864]: Phoniebox is starting...
Aug 24 09:57:43 phoniebox bash[864]: 2.3
Aug 24 09:57:43 phoniebox bash[864]: AUDIOFOLDERSPATH="/home/pi/RPi-Jukebox-RFID/shared/audiofolders"
Aug 24 09:57:43 phoniebox bash[864]: PLAYLISTSFOLDERPATH="/home/pi/RPi-Jukebox-RFID/playlists"
Aug 24 09:57:43 phoniebox bash[864]: SWIPEORPLACE="SWIPENOTPLACE"
Aug 24 09:57:43 phoniebox bash[864]: SECONDSWIPE="PAUSE"
Aug 24 09:57:43 phoniebox bash[864]: SECONDSWIPEPAUSE="2"
Aug 24 09:57:43 phoniebox bash[864]: SECONDSWIPEPAUSECONTROLS="ON"
Aug 24 09:57:43 phoniebox bash[864]: AUDIOIFACENAME="Speaker"
Aug 24 09:57:43 phoniebox bash[864]: AUDIOIFACEACTIVE="0"
Aug 24 09:57:43 phoniebox bash[864]: VOLUMEMANAGER="mpd"
Aug 24 09:57:43 phoniebox bash[864]: AUDIOVOLCHANGESTEP="5"
Aug 24 09:57:43 phoniebox bash[864]: AUDIOVOLMAXLIMIT="100"
Aug 24 09:57:43 phoniebox bash[864]: AUDIOVOLMINLIMIT="1"
Aug 24 09:57:43 phoniebox bash[864]: AUDIOVOLSTARTUP="50"
Aug 24 09:57:43 phoniebox bash[864]: AUDIOVOLBOOT="50"
Aug 24 09:57:43 phoniebox bash[864]: VOLCHANGEIDLE="TRUE"
Aug 24 09:57:43 phoniebox bash[864]: IDLETIMESHUTDOWN="0"
Aug 24 09:57:43 phoniebox bash[864]: POWEROFFCMD="sudo poweroff"
Aug 24 09:57:43 phoniebox bash[864]: SHOWCOVER="ON"
Aug 24 09:57:43 phoniebox bash[864]: READWLANIPYN="OFF"
Aug 24 09:57:43 phoniebox bash[864]: EDITION="classic"
Aug 24 09:57:43 phoniebox bash[864]: LANG="de-DE"
Aug 24 09:57:43 phoniebox bash[864]: VERSION="2.3 - b3e91ae5 - develop"
Aug 24 09:57:43 phoniebox bash[864]: CHAPTEREXTENSIONS="mp4,m4a,m4b,m4r"
Aug 24 09:57:43 phoniebox bash[864]: CHAPTERMINDURATION="600"
Aug 24 09:57:43 phoniebox bash[864]: CMDVOLUP="03009040"
Aug 24 09:57:43 phoniebox bash[864]: CMDVOLDOWN=""
Aug 24 09:57:43 phoniebox bash[864]: CMDNEXT="19538480"
Aug 24 09:57:43 phoniebox bash[864]: CMDPREV="17158448"
Aug 24 09:57:43 phoniebox bash[864]: CMDREWIND=""
Aug 24 09:57:43 phoniebox bash[864]: CMDSEEKFORW=""
Aug 24 09:57:43 phoniebox bash[864]: CMDSEEKBACK=""
Aug 24 09:57:43 phoniebox bash[864]: 50 is the mpd startup volume
Aug 24 09:57:43 phoniebox mpd: exception: Failed to read mixer for 'My ALSA Device': failed to attach to default: No such file or directory
Aug 24 09:57:43 phoniebox dhcpcd[533]: wlan0: fe80::7eff:4dff:fe97:22fe is reachable again
Aug 24 09:57:43 phoniebox bash[864]: OK MPD 0.21.4
Aug 24 09:57:43 phoniebox bash[864]: OK
Aug 24 09:57:43 phoniebox dhcpcd[533]: wlan0: fe80::7eff:4dff:fe97:22fe is reachable again
Aug 24 09:57:43 phoniebox bash[864]: OK MPD 0.21.4
Aug 24 09:57:43 phoniebox bash[864]: OK
Aug 24 09:57:43 phoniebox bash[864]: 16384 is the mpg123 startup volume
Aug 24 09:57:43 phoniebox bash[864]: High Performance MPEG 1.0/2.0/2.5 Audio Player for Layers 1, 2 and 3
Aug 24 09:57:43 phoniebox bash[864]: #011version 1.25.10; written and copyright by Michael Hipp and others
Aug 24 09:57:43 phoniebox bash[864]: #011free software (LGPL) without any warranty but with best wishes
Aug 24 09:57:44 phoniebox bash[864]: Cannot connect to server socket err = No such file or directory
Aug 24 09:57:44 phoniebox bash[864]: Cannot connect to server request channel
Aug 24 09:57:44 phoniebox bash[864]: jack server is not running or cannot be started
Aug 24 09:57:44 phoniebox bash[864]: JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock
Aug 24 09:57:44 phoniebox bash[864]: JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock
Aug 24 09:57:44 phoniebox bash[864]: /home/pi/RPi-Jukebox-RFID/scripts/startup-scripts.sh: line 53:   970 Segmentation fault      /usr/bin/mpg123 -f -${mpgvolume} /home/pi/RPi-Jukebox-RFID/shared/startupsound.mp3
Aug 24 09:57:44 phoniebox bash[864]: 1000_Themen Was Kinder wissen wollen Piraten/01_Piraten.mp3
Aug 24 09:57:44 phoniebox bash[864]: [paused]  #1/12   0:00/1:28 (0%)
Aug 24 09:57:44 phoniebox bash[864]: Updating DB (#1) ...
Aug 24 09:57:44 phoniebox bash[864]: volume: n/a   repeat: off   random: off   single: off   consume: off
Aug 24 09:57:44 phoniebox systemd[1]: Started Phoniebox Startup.
Aug 24 09:57:44 phoniebox systemd[1]: Reached target Multi-User System.
Aug 24 09:57:44 phoniebox systemd[1]: Reached target Graphical Interface.
Aug 24 09:57:44 phoniebox systemd[1]: Starting Update UTMP about System Runlevel Changes...
Aug 24 09:57:44 phoniebox systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Aug 24 09:57:44 phoniebox systemd[1]: Started Update UTMP about System Runlevel Changes.
Aug 24 09:57:44 phoniebox systemd[1]: Startup finished in 4.089s (kernel) + 25.749s (userspace) = 29.838s.
Aug 24 09:57:46 phoniebox kernel: [   32.474445] Voltage normalised (0x00000000)
Aug 24 09:57:53 phoniebox systemd[1]: systemd-fsckd.service: Succeeded.
Aug 24 09:58:08 phoniebox systemd-timesyncd[315]: Synchronized to time server for the first time [2a01:4f9:c012:5e8a::1]:123 (2.debian.pool.ntp.org).
Aug 24 09:58:12 phoniebox kernel: [   44.954498] Under-voltage detected! (0x00050005)
Aug 24 09:58:15 phoniebox systemd[1]: systemd-hostnamed.service: Succeeded.
Aug 24 09:58:20 phoniebox kernel: [   53.274386] Voltage normalised (0x00000000)
Aug 24 09:58:51 phoniebox systemd[1]: phoniebox-idle-watchdog.service: Succeeded.
Aug 24 10:09:01 phoniebox CRON[1090]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Aug 24 10:09:16 phoniebox systemd[1]: Starting Clean php session files...
Aug 24 10:09:17 phoniebox systemd[1]: phpsessionclean.service: Succeeded.
Aug 24 10:09:17 phoniebox systemd[1]: Started Clean php session files.
Aug 24 10:12:43 phoniebox systemd[1]: Starting Cleanup of Temporary Directories...
Aug 24 10:12:43 phoniebox systemd[1]: systemd-tmpfiles-clean.service: Succeeded.
Aug 24 10:12:43 phoniebox systemd[1]: Started Cleanup of Temporary Directories.

dmesg:

pi@phoniebox:~ $ dmesg
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 5.10.63-v7+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1496 SMP Wed Dec 1 15:58:11 GMT 2021
[    0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
[    0.000000] CPU: div instructions available: patching division code
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] OF: fdt: Machine model: Raspberry Pi 3 Model B Plus Rev 1.3
[    0.000000] random: fast init done
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] Reserved memory: created CMA memory pool at 0x37400000, 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-0x000000003b3fffff]
[    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] Initmem setup node 0 [mem 0x0000000000000000-0x000000003b3fffff]
[    0.000000] On node 0 totalpages: 242688
[    0.000000]   DMA zone: 2133 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 242688 pages, LIFO batch:63
[    0.000000] percpu: Embedded 20 pages/cpu s50828 r8192 d22900 u81920
[    0.000000] pcpu-alloc: s50828 r8192 d22900 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: 240555
[    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=656 bcm2708_fb.fbheight=416 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  console=ttyS0,115200 console=tty1 root=PARTUUID=ae99cb2e-02 rootfstype=ext4 fsck.repair=yes rootwait
[    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] Memory: 878808K/970752K available (10240K kernel code, 1311K rwdata, 2948K rodata, 1024K init, 859K bss, 26408K reserved, 65536K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 32054 entries in 63 pages
[    0.000000] ftrace: allocated 63 pages with 6 groups
[    0.000000] rcu: Hierarchical RCU implementation.
[    0.000000] 	Rude variant of Tasks RCU enabled.
[    0.000000] 	Tracing variant of Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000] random: get_random_bytes called from start_kernel+0x3ac/0x580 with crng_init=1
[    0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
[    0.000007] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
[    0.000025] Switching to timer-based delay loop, resolution 52ns
[    0.000315] Console: colour dummy device 80x30
[    0.001138] printk: console [tty1] enabled
[    0.001205] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
[    0.001262] pid_max: default: 32768 minimum: 301
[    0.001488] LSM: Security Framework initializing
[    0.001747] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.001796] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.003271] cgroup: Disabling memory control group subsystem
[    0.003556] CPU: Testing write buffer coherency: ok
[    0.004074] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.005336] Setting up static identity map for 0x100000 - 0x10003c
[    0.005545] rcu: Hierarchical SRCU implementation.
[    0.006489] smp: Bringing up secondary CPUs ...
[    0.007666] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.008947] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.010265] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.010421] smp: Brought up 1 node, 4 CPUs
[    0.010475] SMP: Total of 4 processors activated (153.60 BogoMIPS).
[    0.010506] CPU: All CPU(s) started in HYP mode.
[    0.010533] CPU: Virtualization extensions available.
[    0.011546] devtmpfs: initialized
[    0.029238] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
[    0.029534] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.029594] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.032860] pinctrl core: initialized pinctrl subsystem
[    0.034072] NET: Registered protocol family 16
[    0.038291] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.044026] audit: initializing netlink subsys (disabled)
[    0.044941] thermal_sys: Registered thermal governor 'step_wise'
[    0.045765] audit: type=2000 audit(0.040:1): state=initialized audit_enabled=0 res=1
[    0.045998] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.046038] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.046347] Serial: AMBA PL011 UART driver
[    0.064635] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
[    0.080149] raspberrypi-firmware soc:firmware: Attached to firmware from 2021-12-01T15:07:06, variant start
[    0.090160] raspberrypi-firmware soc:firmware: Firmware hash is 71bd3109023a0c8575585ba87cbb374d2eeb038f
[    0.134481] Kprobes globally optimized
[    0.139546] bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1
[    0.141902] SCSI subsystem initialized
[    0.142185] usbcore: registered new interface driver usbfs
[    0.142272] usbcore: registered new interface driver hub
[    0.142364] usbcore: registered new device driver usb
[    0.144321] clocksource: Switched to clocksource arch_sys_counter
[    1.916341] VFS: Disk quotas dquot_6.6.0
[    1.916486] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    1.916711] FS-Cache: Loaded
[    1.917009] CacheFiles: Loaded
[    1.927861] NET: Registered protocol family 2
[    1.928157] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    1.929596] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
[    1.929696] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    1.929924] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    1.930139] TCP: Hash tables configured (established 8192 bind 8192)
[    1.930338] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[    1.930417] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[    1.931094] NET: Registered protocol family 1
[    1.931943] RPC: Registered named UNIX socket transport module.
[    1.931978] RPC: Registered udp transport module.
[    1.932007] RPC: Registered tcp transport module.
[    1.932036] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    1.933703] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
[    1.937596] Initialise system trusted keyrings
[    1.937908] workingset: timestamp_bits=14 max_order=18 bucket_order=4
[    1.947509] zbud: loaded
[    1.949606] FS-Cache: Netfs 'nfs' registered for caching
[    1.950503] NFS: Registering the id_resolver key type
[    1.950571] Key type id_resolver registered
[    1.950601] Key type id_legacy registered
[    1.950775] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.950811] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    1.952051] Key type asymmetric registered
[    1.952085] Asymmetric key parser 'x509' registered
[    1.952158] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[    1.952198] io scheduler mq-deadline registered
[    1.952228] io scheduler kyber registered
[    1.955837] bcm2708_fb soc:fb: FB found 1 display(s)
[    1.968332] Console: switching to colour frame buffer device 82x26
[    1.975680] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 656x416
[    1.986410] bcm2835-rng 3f104000.rng: hwrng registered
[    1.989383] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    1.995403] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
[    2.013022] brd: module loaded
[    2.028059] loop: module loaded
[    2.032518] Loading iSCSI transport class v2.0-870.
[    2.036866] libphy: Fixed MDIO Bus: probed
[    2.039779] usbcore: registered new interface driver lan78xx
[    2.042463] usbcore: registered new interface driver smsc95xx
[    2.045065] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    2.775894] Core Release: 2.80a
[    2.778376] Setting default values for core params
[    2.780832] Finished setting default values for core params
[    2.983680] Using Buffer DMA mode
[    2.986165] Periodic Transfer Interrupt Enhancement - disabled
[    2.988715] Multiprocessor Interrupt Enhancement - disabled
[    2.991246] OTG VER PARAM: 0, OTG VER FLAG: 0
[    2.993731] Dedicated Tx FIFOs mode
[    2.996703] WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = b7514000 dma = 0xf7514000 len=9024
[    3.003635] FIQ FSM acceleration enabled for :
               Non-periodic Split Transactions
               Periodic Split Transactions
               High-Speed Isochronous Endpoints
               Interrupt/Control Split Transaction hack enabled
[    3.014531] dwc_otg: Microframe scheduler enabled
[    3.014605] WARN::hcd_init_fiq:457: FIQ on core 1
[    3.018917] WARN::hcd_init_fiq:458: FIQ ASM at 807c9984 length 36
[    3.023346] WARN::hcd_init_fiq:497: MPHI regs_base at bb810000
[    3.027826] dwc_otg 3f980000.usb: DWC OTG Controller
[    3.030136] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
[    3.032505] dwc_otg 3f980000.usb: irq 89, io mem 0x00000000
[    3.034842] Init: Port Power? op_state=1
[    3.037072] Init: Power Port (0)
[    3.039594] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10
[    3.044179] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    3.046654] usb usb1: Product: DWC OTG Controller
[    3.049008] usb usb1: Manufacturer: Linux 5.10.63-v7+ dwc_otg_hcd
[    3.051393] usb usb1: SerialNumber: 3f980000.usb
[    3.054548] hub 1-0:1.0: USB hub found
[    3.056990] hub 1-0:1.0: 1 port detected
[    3.060567] dwc_otg: FIQ enabled
[    3.060580] dwc_otg: NAK holdoff enabled
[    3.060591] dwc_otg: FIQ split-transaction FSM enabled
[    3.060609] Module dwc_common_port init
[    3.060915] usbcore: registered new interface driver usb-storage
[    3.063693] mousedev: PS/2 mouse device common for all mice
[    3.067340] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    3.072658] sdhci: Secure Digital Host Controller Interface driver
[    3.075245] sdhci: Copyright(c) Pierre Ossman
[    3.078474] mmc-bcm2835 3f300000.mmcnr: could not get clk, deferring probe
[    3.081705] sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe
[    3.084544] sdhci-pltfm: SDHCI platform and OF driver helper
[    3.088991] ledtrig-cpu: registered to indicate activity on CPUs
[    3.092010] hid: raw HID events driver (C) Jiri Kosina
[    3.094810] usbcore: registered new interface driver usbhid
[    3.097409] usbhid: USB HID core driver
[    3.105219] Initializing XFRM netlink socket
[    3.107830] NET: Registered protocol family 17
[    3.110451] Key type dns_resolver registered
[    3.113417] Registering SWP/SWPB emulation handler
[    3.115963] registered taskstats version 1
[    3.118306] Loading compiled-in X.509 certificates
[    3.121552] Key type ._fscrypt registered
[    3.123878] Key type .fscrypt registered
[    3.126235] Key type fscrypt-provisioning registered
[    3.140391] uart-pl011 3f201000.serial: cts_event_workaround enabled
[    3.142890] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 114, base_baud = 0) is a PL011 rev2
[    3.150003] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    3.154186] mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0
[    3.156708] mmc-bcm2835 3f300000.mmcnr: DMA channel allocated
[    3.185859] Indeed it is in host mode hprt0 = 00021501
[    3.248389] sdhost: log_buf @ (ptrval) (f7513000)
[    3.285535] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[    3.289567] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    3.293485] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    3.298702] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[    3.301919] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
[    3.307107] of_cfs_init
[    3.309398] of_cfs_init: OK
[    3.312550] Waiting for root device PARTUUID=ae99cb2e-02...
[    3.390995] mmc0: host does not support reading read-only switch, assuming write-enable
[    3.395379] usb 1-1: new high-speed USB device number 2 using dwc_otg
[    3.397911] Indeed it is in host mode hprt0 = 00001101
[    3.462076] mmc0: new high speed SDHC card at address aaaa
[    3.465585] mmcblk0: mmc0:aaaa SC32G 29.7 GiB
[    3.468651] mmc1: new high speed SDIO card at address 0001
[    3.476897]  mmcblk0: p1 p2
[    3.519813] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[    3.524335] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[    3.536697] devtmpfs: mounted
[    3.546092] Freeing unused kernel memory: 1024K
[    3.574819] Run /sbin/init as init process
[    3.577191]   with arguments:
[    3.577203]     /sbin/init
[    3.577213]   with environment:
[    3.577225]     HOME=/
[    3.577235]     TERM=linux
[    3.634734] usb 1-1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3
[    3.639963] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    3.643637] hub 1-1:1.0: USB hub found
[    3.646581] hub 1-1:1.0: 4 ports detected
[    3.964405] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
[    4.094770] usb 1-1.1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3
[    4.100378] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    4.104096] hub 1-1.1:1.0: USB hub found
[    4.107058] hub 1-1.1:1.0: 3 ports detected
[    4.151265] systemd[1]: System time before build time, advancing clock.
[    4.303693] NET: Registered protocol family 10
[    4.307984] Segment Routing with IPv6
[    4.396174] 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)
[    4.406334] systemd[1]: Detected architecture arm.
[    4.434365] usb 1-1.1.2: new full-speed USB device number 4 using dwc_otg
[    4.491753] systemd[1]: Set hostname to <phoniebox>.
[    4.579199] usb 1-1.1.2: New USB device found, idVendor=ffff, idProduct=0035, bcdDevice= 1.00
[    4.592736] usb 1-1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    4.610803] usb 1-1.1.2: Product: IC Reader
[    4.613826] usb 1-1.1.2: Manufacturer: IC Reader
[    4.616879] usb 1-1.1.2: SerialNumber: 08FF20171101
[    4.630760] input: IC Reader IC Reader as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.0/0003:FFFF:0035.0001/input/input0
[    4.705968] hid-generic 0003:FFFF:0035.0001: input,hidraw0: USB HID v1.10 Keyboard [IC Reader IC Reader] on usb-3f980000.usb-1.1.2/input0
[    4.713901] usbhid 1-1.1.2:1.1: couldn't find an input interrupt endpoint
[    5.014405] usb 1-1.1.1: new high-speed USB device number 5 using dwc_otg
[    5.144970] usb 1-1.1.1: New USB device found, idVendor=0424, idProduct=7800, bcdDevice= 3.00
[    5.150801] usb 1-1.1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    5.310273] systemd[1]: /lib/systemd/system/smbd.service:9: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/smbd.pid \xe2\x86\x92 /run/samba/smbd.pid; please update the unit file accordingly.
[    5.361988] systemd[1]: /lib/systemd/system/nmbd.service:9: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/nmbd.pid \xe2\x86\x92 /run/samba/nmbd.pid; please update the unit file accordingly.
[    5.422526] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): No External EEPROM. Setting MAC Speed
[    5.431013] libphy: lan78xx-mdiobus: probed
[    5.456297] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): int urb period 64
[    5.499583] random: systemd: uninitialized urandom read (16 bytes read)
[    5.519162] random: systemd: uninitialized urandom read (16 bytes read)
[    5.525416] systemd[1]: Created slice User and Session Slice.
[    5.532749] random: systemd: uninitialized urandom read (16 bytes read)
[    5.537285] systemd[1]: Listening on Journal Socket.
[    5.551201] systemd[1]: Mounting POSIX Message Queue File System...
[    5.560135] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    5.579381] systemd[1]: Starting Set the console keyboard layout...
[    6.434664] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[    6.550385] systemd-journald[134]: Received request to flush runtime journal from PID 1
[    7.141992] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[    7.144785] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[    7.144812] [vc_sm_connected_init]: start
[    7.150949] mc: Linux media interface: v0.10
[    7.154034] [vc_sm_connected_init]: installed successfully
[    7.213968] videodev: Linux video capture interface: v2.00
[    7.281083] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    7.285346] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    7.292877] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[    7.306379] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[    7.306974] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[    7.307421] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[    7.307845] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[    7.307882] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    7.307908] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    7.307929] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    7.307951] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    7.308170] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[    7.314719] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[    7.322683] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[    7.329457] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[    7.375350] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[    7.375532] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[    7.391972] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[    7.392166] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[    7.396175] bcm2835_audio bcm2835_audio: card created with 8 channels
[    7.408424] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[    7.408480] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[    7.416946] bcm2835-codec bcm2835-codec: Device registered as /dev/video18
[    7.417001] bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx
[    7.511032] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[    7.630883] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[    7.755044] brcmfmac: F1 signature read @0x18000000=0x15264345
[    7.765442] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    7.767618] usbcore: registered new interface driver brcmfmac
[    7.909270] random: crng init done
[    7.909292] random: 7 urandom warning(s) missed due to ratelimiting
[    8.062899] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    8.063036] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    8.075219] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Jan  4 2021 19:56:29 version 7.45.229 (617f1f5 CY) FWID 01-2dbd9d2e
[    9.594457] Under-voltage detected! (0x00050000)
[   10.980336] uart-pl011 3f201000.serial: no DMA platform data
[   10.996157] 8021q: 802.1Q VLAN Support v1.8
[   11.394521] Adding 102396k swap on /var/swap.  Priority:-2 extents:2 across:14651388k SSFS
[   11.448052] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
[   11.674520] Voltage normalised (0x00000000)
[   11.846800] 8021q: adding VLAN 0 to HW filter on device eth0
[   16.860814] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   17.699867] Bluetooth: Core ver 2.22
[   17.700000] NET: Registered protocol family 31
[   17.700010] Bluetooth: HCI device and connection manager initialized
[   17.700656] Bluetooth: HCI socket layer initialized
[   17.700676] Bluetooth: L2CAP socket layer initialized
[   17.700706] Bluetooth: SCO socket layer initialized
[   17.714272] Bluetooth: HCI UART driver ver 2.3
[   17.714386] Bluetooth: HCI UART protocol H4 registered
[   17.714503] Bluetooth: HCI UART protocol Three-wire (H5) registered
[   17.715696] Bluetooth: HCI UART protocol Broadcom registered
[   18.017765] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[   18.017777] Bluetooth: BNEP filters: protocol multicast
[   18.017794] Bluetooth: BNEP socket layer initialized
[   18.563682] ICMPv6: process `dhcpcd' is using deprecated sysctl (syscall) net.ipv6.neigh.wlan0.retrans_time - use net.ipv6.neigh.wlan0.retrans_time_ms instead
[   24.154666] Under-voltage detected! (0x00050005)
[   32.474445] Voltage normalised (0x00000000)
[   44.954498] Under-voltage detected! (0x00050005)
[   53.274386] Voltage normalised (0x00000000)

Running sudo alsa-info results in the following:

upload=true&script=true&cardinfo=
!!################################
!!ALSA Information Script v 0.5.3
!!################################

!!Script ran on: Sat Aug 24 09:30:25 UTC 2024


!!Linux Distribution
!!------------------

ID_LIKE=debian


!!DMI Information
!!---------------

Manufacturer:
Product Name:
Product Version:
Firmware Version:
System SKU:
Board Vendor:
Board Name:


!!ACPI Device Status Information
!!---------------



!!ACPI SoundWire Device Status Information
!!---------------



!!Kernel Information
!!------------------

Kernel release:    #1496 SMP Wed Dec 1 15:58:11 GMT 2021
Operating System:  GNU/Linux
Architecture:      armv7l
Processor:         unknown
SMP Enabled:       Yes


!!ALSA Version
!!------------

Driver version:     k5.10.63-v7+
Library version:    1.1.8
Utilities version:  1.1.8


!!Loaded ALSA modules
!!-------------------

snd_bcm2835 (card 0)


!!Sound Servers on this system
!!----------------------------

No sound servers found.


!!Soundcards recognised by ALSA
!!-----------------------------

 0 [Headphones     ]: bcm2835_headpho - bcm2835 Headphones
                      bcm2835 Headphones


!!PCI Soundcards installed in the system
!!--------------------------------------



!!Modprobe options (Sound related)
!!--------------------------------

snd_pcsp: index=-2
snd_usb_audio: index=-2
snd_atiixp_modem: index=-2
snd_intel8x0m: index=-2
snd_via82xx_modem: index=-2
snd_bcm2835: enable_compat_alsa=0
snd_bcm2835: enable_hdmi=1


!!Loaded sound module options
!!---------------------------

!!Module: snd_bcm2835
	enable_compat_alsa : N
	enable_hdmi : Y
	enable_headphones : Y
	force_bulk : N


!!Sysfs card info
!!---------------

!!Card: /sys/class/sound/card0
Driver: /sys/bus/platform/drivers/bcm2835_audio
Tree:


!!ALSA Device nodes
!!-----------------

crw-rw---- 1 root audio 116,  0 Aug 24 09:57 /dev/snd/controlC0
crw-rw---- 1 root audio 116, 16 Aug 24 09:57 /dev/snd/pcmC0D0p
crw-rw---- 1 root audio 116,  1 Aug 24 09:57 /dev/snd/seq
crw-rw---- 1 root audio 116, 33 Aug 24 09:57 /dev/snd/timer

/dev/snd/by-path:
total 0
drwxr-xr-x 2 root root  60 Aug 24 09:57 .
drwxr-xr-x 3 root root 140 Aug 24 09:57 ..
lrwxrwxrwx 1 root root  12 Aug 24 09:57 platform-bcm2835_audio -> ../controlC0


!!ALSA configuration files
!!------------------------

!!System wide config file (/etc/asound.conf)

pcm.!default {
 type hw
 card 1
}

ctl.!default {
 type hw
 card 1
}


!!Aplay/Arecord output
!!--------------------

APLAY

**** List of PLAYBACK Hardware Devices ****
card 0: Headphones [bcm2835 Headphones], device 0: bcm2835 Headphones [bcm2835 Headphones]
  Subdevices: 8/8
  Subdevice #0: subdevice #0
  Subdevice #1: subdevice #1
  Subdevice #2: subdevice #2
  Subdevice #3: subdevice #3
  Subdevice #4: subdevice #4
  Subdevice #5: subdevice #5
  Subdevice #6: subdevice #6
  Subdevice #7: subdevice #7

ARECORD

**** List of CAPTURE Hardware Devices ****

!!Amixer output
!!-------------

!!-------Mixer controls for card Headphones

Card hw:0 'Headphones'/'bcm2835 Headphones'
  Mixer name	: 'Broadcom Mixer'
  Components	: ''
  Controls      : 2
  Simple ctrls  : 1
Simple mixer control 'Headphone',0
  Capabilities: pvolume pvolume-joined pswitch pswitch-joined
  Playback channels: Mono
  Limits: Playback -10239 - 400
  Mono: Playback -529 [91%] [-5.29dB] [on]


!!Alsactl output
!!--------------

--startcollapse--
state.Headphones {
	control.1 {
		iface MIXER
		name 'Headphone Playback Volume'
		value -529
		comment {
			access 'read write'
			type INTEGER
			count 1
			range '-10239 - 400'
			dbmin -9999999
			dbmax 400
			dbvalue.0 -529
		}
	}
	control.2 {
		iface MIXER
		name 'Headphone Playback Switch'
		value true
		comment {
			access 'read write'
			type BOOLEAN
			count 1
		}
	}
}
--endcollapse--


!!All Loaded Modules
!!------------------

8021q
bcm2835_codec
bcm2835_isp
bcm2835_mmal_vchiq
bcm2835_v4l2
bluetooth
bnep
brcmfmac
brcmutil
btbcm
cfg80211
cmac
ecc
ecdh_generic
fixed
garp
hci_uart
ip_tables
ipv6
llc
mc
raspberrypi_hwmon
rfkill
sha256_generic
snd
snd_bcm2835
snd_pcm
snd_timer
stp
uio
uio_pdrv_genirq
v4l2_mem2mem
vc_sm_cma
videobuf2_common
videobuf2_dma_contig
videobuf2_memops
videobuf2_v4l2
videobuf2_vmalloc
videodev
x_tables


!!ALSA/HDA dmesg
!!--------------

[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 240555
[    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=656 bcm2708_fb.fbheight=416 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  console=ttyS0,115200 console=tty1 root=PARTUUID=ae99cb2e-02 rootfstype=ext4 fsck.repair=yes rootwait
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)
--
[    7.314719] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[    7.322683] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[    7.329457] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
--
[    7.392166] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[    7.396175] bcm2835_audio bcm2835_audio: card created with 8 channels
[    7.408424] bcm2835-codec bcm2835-codec: Device registered as /dev/video12


!!Packages installed
!!--------------------

ii  alsa-utils                     1.1.8-2+rpt1                        armhf        Utilities for configuring and using ALSA

What could be the cause for not playing audio anymore ?
It stopped working from one day to another without any changes applied.
I noticed in the past that a full filesystem can cause similar problems, I have cleaned up temporary/log files, but the system can't play audio anymore, regardless of restarts.

Any help appreciated!

[manuel-koch]

After further investigations I switched audio output from the attached USB audio card to the built in analog audio jacket of the raspi and that played sound.
So my suggestion is that the USB audio card is broken.
I bought a new one ( UGREEN USB Externe Soundkarte 2 in 1 USB ) and that is working OK now !