search

MiczFlor / RPi-Jukebox-RFID

A Raspberry Pi jukebox, playing local music, podcasts, web radio and streams triggered by RFID cards, web app or home automation. All plug and play via USB. GPIO scripts available.
http://phoniebox.de
MIT License
1.38k stars 397 forks source link

🐛 | Startup/Shutdown sounds do not play #2291

Closed dvalob closed 7 months ago

dvalob commented 8 months ago

Version

2.5.0

Branch

master

OS

Raspbian GNU/Linux 10 (buster)

Pi model

3B 1.2

Hardware

USB reader

What happened?

Startup and shutdown sounds do not work. I have a segmentation fault in my logs. A similar issue was reported by the past but closed because too old (https://github.com/MiczFlor/RPi-Jukebox-RFID/issues/1178).

My startup volume is correctly set to 25 (issue fixed in a previous release)

Mar  8 20:43:56 raspberrypi bash[913]: /home/pi/RPi-Jukebox-RFID/scripts/startup-scripts.sh: line 53:  1032 **Segmentation fault**      /usr/bin/mpg123 -f -${mpgvolume} /home/pi/RPi-Jukebox-RFID/shared/startupsound.mp3
Mar  8 20:43:56 raspberrypi bash[913]: Updating DB (#1) ...
Mar  8 20:43:56 raspberrypi bash[913]: volume: 25%   repeat: off   random: off   single: off   consume: off_

Logs

ar  8 20:43:42 raspberrypi fake-hwclock[116]: Fri  8 Mar 20:43:38 UTC 2024
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] Booting Linux on physical CPU 0x0
Mar  8 20:43:42 raspberrypi systemd-fsck[135]: e2fsck 1.44.5 (15-Dec-2018)
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] Linux version 5.10.103-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) #1529 SMP Tue Mar 8 12:21:37 GMT 2022
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] CPU: div instructions available: patching division code
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] OF: fdt: Machine model: Raspberry Pi 3 Model B Rev 1.2
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] random: fast init done
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] Memory policy: Data cache writealloc
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] Reserved memory: created CMA memory pool at 0x37400000, size 64 MiB
Mar  8 20:43:42 raspberrypi systemd[1]: Started udev Coldplug all Devices.
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] Zone ranges:
Mar  8 20:43:42 raspberrypi kernel: [    0.000000]   DMA      [mem 0x0000000000000000-0x000000003b3fffff]
Mar  8 20:43:42 raspberrypi kernel: [    0.000000]   Normal   empty
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Helper to synchronize boot up for ifupdown...
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] Movable zone start for each node
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] Early memory node ranges
Mar  8 20:43:42 raspberrypi kernel: [    0.000000]   node   0: [mem 0x0000000000000000-0x000000003b3fffff]
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000003b3fffff]
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] On node 0 totalpages: 242688
Mar  8 20:43:42 raspberrypi kernel: [    0.000000]   DMA zone: 2133 pages used for memmap
Mar  8 20:43:42 raspberrypi systemd[1]: Started Helper to synchronize boot up for ifupdown.
Mar  8 20:43:42 raspberrypi kernel: [    0.000000]   DMA zone: 0 pages reserved
Mar  8 20:43:42 raspberrypi kernel: [    0.000000]   DMA zone: 242688 pages, LIFO batch:63
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] percpu: Embedded 20 pages/cpu s50828 r8192 d22900 u81920
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] pcpu-alloc: s50828 r8192 d22900 u81920 alloc=20*4096
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 240555
Mar  8 20:43:42 raspberrypi systemd-fsck[135]: rootfs: clean, 114270/1797408 files, 943027/7748608 blocks
Mar  8 20:43:42 raspberrypi 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=2ad10d96-02 rootfstype=ext4 fsck.repair=yes rootwait
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
Mar  8 20:43:42 raspberrypi systemd[1]: Started File System Check on Root Device.
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] Memory: 878804K/970752K available (10240K kernel code, 1312K rwdata, 2952K rodata, 1024K init, 862K bss, 26412K reserved, 65536K cma-reserved)
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] ftrace: allocating 32081 entries in 95 pages
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] ftrace: allocated 94 pages with 5 groups
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] rcu: Hierarchical RCU implementation.
Mar  8 20:43:42 raspberrypi kernel: [    0.000000]  Rude variant of Tasks RCU enabled.
Mar  8 20:43:42 raspberrypi kernel: [    0.000000]  Tracing variant of Tasks RCU enabled.
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] random: get_random_bytes called from start_kernel+0x3ac/0x580 with crng_init=1
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys).
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
Mar  8 20:43:42 raspberrypi kernel: [    0.000008] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
Mar  8 20:43:42 raspberrypi kernel: [    0.000025] Switching to timer-based delay loop, resolution 52ns
Mar  8 20:43:42 raspberrypi kernel: [    0.000310] Console: colour dummy device 80x30
Mar  8 20:43:42 raspberrypi kernel: [    0.001134] printk: console [tty1] enabled
Mar  8 20:43:42 raspberrypi kernel: [    0.001202] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
Mar  8 20:43:42 raspberrypi kernel: [    0.001260] pid_max: default: 32768 minimum: 301
Mar  8 20:43:42 raspberrypi kernel: [    0.001482] LSM: Security Framework initializing
Mar  8 20:43:42 raspberrypi kernel: [    0.001745] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
Mar  8 20:43:42 raspberrypi kernel: [    0.001795] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
Mar  8 20:43:42 raspberrypi kernel: [    0.003267] cgroup: Disabling memory control group subsystem
Mar  8 20:43:42 raspberrypi kernel: [    0.003553] CPU: Testing write buffer coherency: ok
Mar  8 20:43:42 raspberrypi kernel: [    0.004066] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
Mar  8 20:43:42 raspberrypi kernel: [    0.005319] Setting up static identity map for 0x100000 - 0x10003c
Mar  8 20:43:42 raspberrypi kernel: [    0.005527] rcu: Hierarchical SRCU implementation.
Mar  8 20:43:42 raspberrypi kernel: [    0.006466] smp: Bringing up secondary CPUs ...
Mar  8 20:43:42 raspberrypi kernel: [    0.007649] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
Mar  8 20:43:42 raspberrypi kernel: [    0.008926] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
Mar  8 20:43:42 raspberrypi kernel: [    0.010248] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
Mar  8 20:43:42 raspberrypi kernel: [    0.010407] smp: Brought up 1 node, 4 CPUs
Mar  8 20:43:42 raspberrypi kernel: [    0.010461] SMP: Total of 4 processors activated (153.60 BogoMIPS).
Mar  8 20:43:42 raspberrypi kernel: [    0.010492] CPU: All CPU(s) started in HYP mode.
Mar  8 20:43:42 raspberrypi kernel: [    0.010519] CPU: Virtualization extensions available.
Mar  8 20:43:42 raspberrypi kernel: [    0.011536] devtmpfs: initialized
Mar  8 20:43:42 raspberrypi kernel: [    0.029067] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
Mar  8 20:43:42 raspberrypi kernel: [    0.029365] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
Mar  8 20:43:42 raspberrypi kernel: [    0.029426] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
Mar  8 20:43:42 raspberrypi kernel: [    0.032449] pinctrl core: initialized pinctrl subsystem
Mar  8 20:43:42 raspberrypi kernel: [    0.033678] NET: Registered protocol family 16
Mar  8 20:43:42 raspberrypi kernel: [    0.037908] DMA: preallocated 1024 KiB pool for atomic coherent allocations
Mar  8 20:43:42 raspberrypi kernel: [    0.043629] audit: initializing netlink subsys (disabled)
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Remount Root and Kernel File Systems...
Mar  8 20:43:42 raspberrypi kernel: [    0.044551] thermal_sys: Registered thermal governor 'step_wise'
Mar  8 20:43:42 raspberrypi kernel: [    0.045384] audit: type=2000 audit(0.040:1): state=initialized audit_enabled=0 res=1
Mar  8 20:43:42 raspberrypi kernel: [    0.045612] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
Mar  8 20:43:42 raspberrypi kernel: [    0.045653] hw-breakpoint: maximum watchpoint size is 8 bytes.
Mar  8 20:43:42 raspberrypi kernel: [    0.045973] Serial: AMBA PL011 UART driver
Mar  8 20:43:42 raspberrypi kernel: [    0.064336] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
Mar  8 20:43:42 raspberrypi systemd[1]: Started Set the console keyboard layout.
Mar  8 20:43:42 raspberrypi kernel: [    0.080149] raspberrypi-firmware soc:firmware: Attached to firmware from 2023-05-09T12:17:54, variant start
Mar  8 20:43:42 raspberrypi kernel: [    0.090161] raspberrypi-firmware soc:firmware: Firmware hash is 30aa0d70ab280427ba04ebc718c81d4350b9d394
Mar  8 20:43:42 raspberrypi kernel: [    0.134714] Kprobes globally optimized
Mar  8 20:43:42 raspberrypi kernel: [    0.139985] bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1
Mar  8 20:43:42 raspberrypi systemd[1]: Started Remount Root and Kernel File Systems.
Mar  8 20:43:42 raspberrypi kernel: [    0.142369] SCSI subsystem initialized
Mar  8 20:43:42 raspberrypi kernel: [    0.142652] usbcore: registered new interface driver usbfs
Mar  8 20:43:42 raspberrypi kernel: [    0.142738] usbcore: registered new interface driver hub
Mar  8 20:43:42 raspberrypi kernel: [    0.142831] usbcore: registered new device driver usb
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Flush Journal to Persistent Storage...
Mar  8 20:43:42 raspberrypi kernel: [    0.144809] clocksource: Switched to clocksource arch_sys_counter
Mar  8 20:43:42 raspberrypi kernel: [    1.862463] VFS: Disk quotas dquot_6.6.0
Mar  8 20:43:42 raspberrypi kernel: [    1.862609] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Mar  8 20:43:42 raspberrypi kernel: [    1.862834] FS-Cache: Loaded
Mar  8 20:43:42 raspberrypi kernel: [    1.863089] CacheFiles: Loaded
Mar  8 20:43:42 raspberrypi systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped.
Mar  8 20:43:42 raspberrypi kernel: [    1.874252] NET: Registered protocol family 2
Mar  8 20:43:42 raspberrypi kernel: [    1.874565] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
Mar  8 20:43:42 raspberrypi kernel: [    1.876565] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
Mar  8 20:43:42 raspberrypi kernel: [    1.876658] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Create System Users...
Mar  8 20:43:42 raspberrypi kernel: [    1.876803] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear)
Mar  8 20:43:42 raspberrypi kernel: [    1.877016] TCP: Hash tables configured (established 8192 bind 8192)
Mar  8 20:43:42 raspberrypi kernel: [    1.877220] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
Mar  8 20:43:42 raspberrypi kernel: [    1.877298] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
Mar  8 20:43:42 raspberrypi kernel: [    1.877578] NET: Registered protocol family 1
Mar  8 20:43:42 raspberrypi kernel: [    1.878668] RPC: Registered named UNIX socket transport module.
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Load/Save Random Seed...
Mar  8 20:43:42 raspberrypi kernel: [    1.878706] RPC: Registered udp transport module.
Mar  8 20:43:42 raspberrypi kernel: [    1.878735] RPC: Registered tcp transport module.
Mar  8 20:43:42 raspberrypi kernel: [    1.878764] RPC: Registered tcp NFSv4.1 backchannel transport module.
Mar  8 20:43:42 raspberrypi kernel: [    1.880536] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
Mar  8 20:43:42 raspberrypi systemd[1]: Started Load/Save Random Seed.
Mar  8 20:43:42 raspberrypi kernel: [    1.884280] Initialise system trusted keyrings
Mar  8 20:43:42 raspberrypi kernel: [    1.884584] workingset: timestamp_bits=14 max_order=18 bucket_order=4
Mar  8 20:43:42 raspberrypi kernel: [    1.894157] zbud: loaded
Mar  8 20:43:42 raspberrypi kernel: [    1.896321] FS-Cache: Netfs 'nfs' registered for caching
Mar  8 20:43:42 raspberrypi kernel: [    1.897240] NFS: Registering the id_resolver key type
Mar  8 20:43:42 raspberrypi systemd[1]: Started Create System Users.
Mar  8 20:43:42 raspberrypi kernel: [    1.897314] Key type id_resolver registered
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Create Static Device Nodes in /dev...
Mar  8 20:43:42 raspberrypi systemd[1]: Started Flush Journal to Persistent Storage.
Mar  8 20:43:42 raspberrypi systemd[1]: Started Create Static Device Nodes in /dev.
Mar  8 20:43:42 raspberrypi systemd[1]: Reached target Local File Systems (Pre).
Mar  8 20:43:42 raspberrypi systemd[1]: Starting udev Kernel Device Manager...
Mar  8 20:43:42 raspberrypi systemd[1]: Started udev Kernel Device Manager.
Mar  8 20:43:42 raspberrypi mtp-probe: checking bus 1, device 4: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4"
Mar  8 20:43:42 raspberrypi mtp-probe: bus: 1, device: 4 was not an MTP device
Mar  8 20:43:42 raspberrypi mtp-probe: checking bus 1, device 3: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1"
Mar  8 20:43:42 raspberrypi mtp-probe: bus: 1, device: 3 was not an MTP device
Mar  8 20:43:42 raspberrypi kernel: [    1.897344] Key type id_legacy registered
Mar  8 20:43:42 raspberrypi systemd[1]: Found device /dev/disk/by-partuuid/2ad10d96-01.
Mar  8 20:43:42 raspberrypi kernel: [    1.897528] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
Mar  8 20:43:42 raspberrypi kernel: [    1.897565] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
Mar  8 20:43:42 raspberrypi kernel: [    1.898814] Key type asymmetric registered
Mar  8 20:43:42 raspberrypi kernel: [    1.898848] Asymmetric key parser 'x509' registered
Mar  8 20:43:42 raspberrypi kernel: [    1.898920] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
Mar  8 20:43:42 raspberrypi systemd[1]: Starting File System Check on /dev/disk/by-partuuid/2ad10d96-01...
Mar  8 20:43:42 raspberrypi kernel: [    1.898961] io scheduler mq-deadline registered
Mar  8 20:43:42 raspberrypi kernel: [    1.898990] io scheduler kyber registered
Mar  8 20:43:42 raspberrypi kernel: [    1.904008] bcm2708_fb soc:fb: FB found 1 display(s)
Mar  8 20:43:42 raspberrypi kernel: [    1.915754] Console: switching to colour frame buffer device 82x26
Mar  8 20:43:42 raspberrypi systemd-udevd[173]: Using default interface naming scheme 'v240'.
Mar  8 20:43:42 raspberrypi kernel: [    1.923025] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 656x416
Mar  8 20:43:42 raspberrypi kernel: [    1.933722] bcm2835-rng 3f104000.rng: hwrng registered
Mar  8 20:43:42 raspberrypi kernel: [    1.936798] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
Mar  8 20:43:42 raspberrypi kernel: [    1.942723] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
Mar  8 20:43:42 raspberrypi systemd-udevd[180]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
Mar  8 20:43:42 raspberrypi systemd-udevd[178]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
Mar  8 20:43:42 raspberrypi systemd-fsck[276]: fsck.fat 4.1 (2017-01-24)
Mar  8 20:43:42 raspberrypi systemd-fsck[276]: /dev/mmcblk0p1: 289 files, 98789/516190 clusters
Mar  8 20:43:42 raspberrypi systemd[1]: Started File System Check on /dev/disk/by-partuuid/2ad10d96-01.
Mar  8 20:43:42 raspberrypi systemd-udevd[172]: Using default interface naming scheme 'v240'.
Mar  8 20:43:42 raspberrypi systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
Mar  8 20:43:42 raspberrypi systemd[1]: Condition check resulted in FUSE Control File System being skipped.
Mar  8 20:43:42 raspberrypi systemd[1]: Condition check resulted in Huge Pages File System being skipped.
Mar  8 20:43:42 raspberrypi systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped.
Mar  8 20:43:42 raspberrypi systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped.
Mar  8 20:43:42 raspberrypi systemd[1]: Mounting /boot...
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Load/Save RF Kill Switch Status...
Mar  8 20:43:42 raspberrypi systemd[1]: Mounted /boot.
Mar  8 20:43:42 raspberrypi systemd[1]: Reached target Local File Systems.
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Preprocess NFS configuration...
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Set console font and keymap...
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Raise network interfaces...
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Create Volatile Files and Directories...
Mar  8 20:43:42 raspberrypi systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped.
Mar  8 20:43:42 raspberrypi systemd[1]: Started Load/Save RF Kill Switch Status.
Mar  8 20:43:42 raspberrypi systemd[1]: nfs-config.service: Succeeded.
Mar  8 20:43:42 raspberrypi systemd[1]: Started Preprocess NFS configuration.
Mar  8 20:43:42 raspberrypi systemd[1]: Started Set console font and keymap.
Mar  8 20:43:42 raspberrypi systemd[1]: Condition check resulted in RPC security service for NFS server being skipped.
Mar  8 20:43:42 raspberrypi systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped.
Mar  8 20:43:42 raspberrypi systemd[1]: Reached target NFS client services.
Mar  8 20:43:42 raspberrypi systemd[1]: Reached target Remote File Systems (Pre).
Mar  8 20:43:42 raspberrypi systemd[1]: Reached target Remote File Systems.
Mar  8 20:43:42 raspberrypi kernel: [    1.960361] brd: module loaded
Mar  8 20:43:42 raspberrypi systemd[1]: Started Create Volatile Files and Directories.
Mar  8 20:43:42 raspberrypi kernel: [    1.975318] loop: module loaded
Mar  8 20:43:42 raspberrypi kernel: [    1.979913] Loading iSCSI transport class v2.0-870.
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Network Time Synchronization...
Mar  8 20:43:42 raspberrypi kernel: [    1.984429] usbcore: registered new interface driver lan78xx
Mar  8 20:43:42 raspberrypi kernel: [    1.987238] usbcore: registered new interface driver smsc95xx
Mar  8 20:43:42 raspberrypi kernel: [    1.989790] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
Mar  8 20:43:42 raspberrypi kernel: [    2.720656] Core Release: 2.80a
Mar  8 20:43:42 raspberrypi kernel: [    2.723208] Setting default values for core params
Mar  8 20:43:42 raspberrypi kernel: [    2.725770] Finished setting default values for core params
Mar  8 20:43:42 raspberrypi kernel: [    2.928597] Using Buffer DMA mode
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Update UTMP about System Boot/Shutdown...
Mar  8 20:43:42 raspberrypi kernel: [    2.931034] Periodic Transfer Interrupt Enhancement - disabled
Mar  8 20:43:42 raspberrypi kernel: [    2.933588] Multiprocessor Interrupt Enhancement - disabled
Mar  8 20:43:42 raspberrypi kernel: [    2.936173] OTG VER PARAM: 0, OTG VER FLAG: 0
Mar  8 20:43:42 raspberrypi kernel: [    2.938701] Dedicated Tx FIFOs mode
Mar  8 20:43:42 raspberrypi systemd[1]: Started Update UTMP about System Boot/Shutdown.
Mar  8 20:43:42 raspberrypi kernel: [    2.941735] 
Mar  8 20:43:42 raspberrypi kernel: [    2.941755] WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = b7504000 dma = 0xf7504000 len=9024
Mar  8 20:43:42 raspberrypi kernel: [    2.948879] FIQ FSM acceleration enabled for :
Mar  8 20:43:42 raspberrypi kernel: [    2.948879] Non-periodic Split Transactions
Mar  8 20:43:42 raspberrypi systemd[1]: Started Raise network interfaces.
Mar  8 20:43:42 raspberrypi kernel: [    2.948879] Periodic Split Transactions
Mar  8 20:43:42 raspberrypi kernel: [    2.948879] High-Speed Isochronous Endpoints
Mar  8 20:43:42 raspberrypi kernel: [    2.948879] Interrupt/Control Split Transaction hack enabled
Mar  8 20:43:42 raspberrypi kernel: [    2.959857] dwc_otg: Microframe scheduler enabled
Mar  8 20:43:42 raspberrypi systemd[1]: Started Network Time Synchronization.
Mar  8 20:43:42 raspberrypi kernel: [    2.959920] 
Mar  8 20:43:42 raspberrypi kernel: [    2.959929] WARN::hcd_init_fiq:457: FIQ on core 1
Mar  8 20:43:42 raspberrypi kernel: [    2.964237] 
Mar  8 20:43:42 raspberrypi kernel: [    2.964246] WARN::hcd_init_fiq:458: FIQ ASM at 807cb8b8 length 36
Mar  8 20:43:42 raspberrypi systemd[1]: Reached target System Time Synchronized.
Mar  8 20:43:42 raspberrypi kernel: [    2.968678] 
Mar  8 20:43:42 raspberrypi kernel: [    2.968686] WARN::hcd_init_fiq:497: MPHI regs_base at bb810000
Mar  8 20:43:42 raspberrypi kernel: [    2.973164] dwc_otg 3f980000.usb: DWC OTG Controller
Mar  8 20:43:42 raspberrypi kernel: [    2.975498] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
Mar  8 20:43:42 raspberrypi systemd[1]: Reached target System Initialization.
Mar  8 20:43:42 raspberrypi systemd[1]: Listening on mpd.socket.
Mar  8 20:43:42 raspberrypi systemd[1]: Listening on triggerhappy.socket.
Mar  8 20:43:42 raspberrypi systemd[1]: Started Daily Cleanup of Temporary Directories.
Mar  8 20:43:42 raspberrypi systemd[1]: Started Daily man-db regeneration.
Mar  8 20:43:42 raspberrypi systemd[1]: Listening on D-Bus System Message Bus Socket.
Mar  8 20:43:42 raspberrypi systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
Mar  8 20:43:42 raspberrypi systemd[1]: Reached target Sockets.
Mar  8 20:43:42 raspberrypi systemd[1]: Reached target Basic System.
Mar  8 20:43:42 raspberrypi systemd[1]: Started Manage Sound Card State (restore and store).
Mar  8 20:43:42 raspberrypi systemd[1]: Starting triggerhappy global hotkey daemon...
Mar  8 20:43:42 raspberrypi alsactl[385]: alsactl 1.1.8 daemon started
Mar  8 20:43:42 raspberrypi systemd[1]: Starting LSB: Switch to ondemand cpu governor (unless shift key is pressed)...
Mar  8 20:43:42 raspberrypi kernel: [    2.977875] dwc_otg 3f980000.usb: irq 89, io mem 0x00000000
Mar  8 20:43:42 raspberrypi kernel: [    2.980197] Init: Port Power? op_state=1
Mar  8 20:43:42 raspberrypi kernel: [    2.982429] Init: Power Port (0)
Mar  8 20:43:42 raspberrypi kernel: [    2.984955] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10
Mar  8 20:43:42 raspberrypi kernel: [    2.989559] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Mar  8 20:43:42 raspberrypi kernel: [    2.992026] usb usb1: Product: DWC OTG Controller
Mar  8 20:43:42 raspberrypi kernel: [    2.994390] usb usb1: Manufacturer: Linux 5.10.103-v7+ dwc_otg_hcd
Mar  8 20:43:42 raspberrypi kernel: [    2.996812] usb usb1: SerialNumber: 3f980000.usb
Mar  8 20:43:42 raspberrypi kernel: [    2.999951] hub 1-0:1.0: USB hub found
Mar  8 20:43:42 raspberrypi kernel: [    3.002395] hub 1-0:1.0: 1 port detected
Mar  8 20:43:42 raspberrypi kernel: [    3.006190] dwc_otg: FIQ enabled
Mar  8 20:43:42 raspberrypi kernel: [    3.006204] dwc_otg: NAK holdoff enabled
Mar  8 20:43:42 raspberrypi kernel: [    3.006215] dwc_otg: FIQ split-transaction FSM enabled
Mar  8 20:43:42 raspberrypi kernel: [    3.006233] Module dwc_common_port init
Mar  8 20:43:42 raspberrypi kernel: [    3.006577] usbcore: registered new interface driver usb-storage
Mar  8 20:43:42 raspberrypi kernel: [    3.009275] mousedev: PS/2 mouse device common for all mice
Mar  8 20:43:42 raspberrypi kernel: [    3.012884] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
Mar  8 20:43:42 raspberrypi kernel: [    3.017870] sdhci: Secure Digital Host Controller Interface driver
Mar  8 20:43:42 raspberrypi kernel: [    3.020413] sdhci: Copyright(c) Pierre Ossman
Mar  8 20:43:42 raspberrypi kernel: [    3.023499] mmc-bcm2835 3f300000.mmcnr: could not get clk, deferring probe
Mar  8 20:43:42 raspberrypi kernel: [    3.026959] sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe
Mar  8 20:43:42 raspberrypi kernel: [    3.029780] sdhci-pltfm: SDHCI platform and OF driver helper
Mar  8 20:43:42 raspberrypi kernel: [    3.034260] ledtrig-cpu: registered to indicate activity on CPUs
Mar  8 20:43:42 raspberrypi kernel: [    3.037379] hid: raw HID events driver (C) Jiri Kosina
Mar  8 20:43:42 raspberrypi kernel: [    3.040194] usbcore: registered new interface driver usbhid
Mar  8 20:43:42 raspberrypi kernel: [    3.042808] usbhid: USB HID core driver
Mar  8 20:43:42 raspberrypi kernel: [    3.050581] Initializing XFRM netlink socket
Mar  8 20:43:42 raspberrypi kernel: [    3.053210] NET: Registered protocol family 17
Mar  8 20:43:42 raspberrypi kernel: [    3.055891] Key type dns_resolver registered
Mar  8 20:43:42 raspberrypi kernel: [    3.058887] Registering SWP/SWPB emulation handler
Mar  8 20:43:42 raspberrypi kernel: [    3.061396] registered taskstats version 1
Mar  8 20:43:42 raspberrypi kernel: [    3.063740] Loading compiled-in X.509 certificates
Mar  8 20:43:42 raspberrypi kernel: [    3.066937] Key type ._fscrypt registered
Mar  8 20:43:42 raspberrypi kernel: [    3.069269] Key type .fscrypt registered
Mar  8 20:43:42 raspberrypi kernel: [    3.071589] Key type fscrypt-provisioning registered
Mar  8 20:43:42 raspberrypi kernel: [    3.085412] uart-pl011 3f201000.serial: cts_event_workaround enabled
Mar  8 20:43:42 raspberrypi kernel: [    3.087903] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 114, base_baud = 0) is a PL011 rev2
Mar  8 20:43:42 raspberrypi kernel: [    3.095009] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
Mar  8 20:43:42 raspberrypi kernel: [    3.099182] mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0
Mar  8 20:43:42 raspberrypi kernel: [    3.101654] mmc-bcm2835 3f300000.mmcnr: DMA channel allocated
Mar  8 20:43:42 raspberrypi kernel: [    3.130959] sdhost: log_buf @ (ptrval) (f7507000)
Mar  8 20:43:42 raspberrypi kernel: [    3.134997] Indeed it is in host mode hprt0 = 00021501
Mar  8 20:43:42 raspberrypi kernel: [    3.232812] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
Mar  8 20:43:42 raspberrypi kernel: [    3.236873] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Mar  8 20:43:42 raspberrypi kernel: [    3.240806] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Mar  8 20:43:42 raspberrypi kernel: [    3.245135] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
Mar  8 20:43:42 raspberrypi kernel: [    3.250276] of_cfs_init
Mar  8 20:43:42 raspberrypi kernel: [    3.252642] of_cfs_init: OK
Mar  8 20:43:42 raspberrypi kernel: [    3.255924] Waiting for root device PARTUUID=2ad10d96-02...
Mar  8 20:43:42 raspberrypi kernel: [    3.268840] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
Mar  8 20:43:42 raspberrypi kernel: [    3.307997] mmc0: host does not support reading read-only switch, assuming write-enable
Mar  8 20:43:42 raspberrypi kernel: [    3.315671] mmc0: new high speed SDHC card at address 0001
Mar  8 20:43:42 raspberrypi kernel: [    3.319225] mmcblk0: mmc0:0001 EB1QT 29.8 GiB
Mar  8 20:43:42 raspberrypi kernel: [    3.324538]  mmcblk0: p1 p2
Mar  8 20:43:42 raspberrypi kernel: [    3.358726] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
Mar  8 20:43:42 raspberrypi kernel: [    3.363460] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
Mar  8 20:43:42 raspberrypi kernel: [    3.374270] devtmpfs: mounted
Mar  8 20:43:42 raspberrypi kernel: [    3.374867] usb 1-1: new high-speed USB device number 2 using dwc_otg
Mar  8 20:43:42 raspberrypi kernel: [    3.379154] Indeed it is in host mode hprt0 = 00001101
Mar  8 20:43:42 raspberrypi kernel: [    3.443352] Freeing unused kernel memory: 1024K
Mar  8 20:43:42 raspberrypi kernel: [    3.450418] mmc1: new high speed SDIO card at address 0001
Mar  8 20:43:42 raspberrypi kernel: [    3.465333] Run /sbin/init as init process
Mar  8 20:43:42 raspberrypi kernel: [    3.467801]   with arguments:
Mar  8 20:43:42 raspberrypi kernel: [    3.467813]     /sbin/init
Mar  8 20:43:42 raspberrypi kernel: [    3.467824]   with environment:
Mar  8 20:43:42 raspberrypi kernel: [    3.467835]     HOME=/
Mar  8 20:43:42 raspberrypi kernel: [    3.467846]     TERM=linux
Mar  8 20:43:42 raspberrypi kernel: [    3.615246] usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00
Mar  8 20:43:42 raspberrypi kernel: [    3.620631] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Mar  8 20:43:42 raspberrypi kernel: [    3.624295] hub 1-1:1.0: USB hub found
Mar  8 20:43:42 raspberrypi kernel: [    3.627379] hub 1-1:1.0: 5 ports detected
Mar  8 20:43:42 raspberrypi kernel: [    3.944871] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
Mar  8 20:43:42 raspberrypi kernel: [    4.075286] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00
Mar  8 20:43:42 raspberrypi kernel: [    4.080925] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Mar  8 20:43:42 raspberrypi kernel: [    4.086759] smsc95xx v2.0.0
Mar  8 20:43:42 raspberrypi kernel: [    4.208627] NET: Registered protocol family 10
Mar  8 20:43:42 raspberrypi kernel: [    4.213007] Segment Routing with IPv6
Mar  8 20:43:42 raspberrypi kernel: [    4.214669] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:b9:f7:e2
Mar  8 20:43:42 raspberrypi kernel: [    4.314882] usb 1-1.4: new full-speed USB device number 4 using dwc_otg
Mar  8 20:43:42 raspberrypi kernel: [    4.462561] usb 1-1.4: New USB device found, idVendor=1a86, idProduct=dd01, bcdDevice= 0.00
Mar  8 20:43:42 raspberrypi kernel: [    4.474463] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Mar  8 20:43:42 raspberrypi kernel: [    4.489294] usb 1-1.4: Product: RFID Reader
Mar  8 20:43:42 raspberrypi kernel: [    4.492626] usb 1-1.4: Manufacturer: RFID Reader
Mar  8 20:43:42 raspberrypi kernel: [    4.495773] usb 1-1.4: SerialNumber: ID READER
Mar  8 20:43:42 raspberrypi kernel: [    4.523209] input: RFID Reader RFID Reader as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.0/0003:1A86:DD01.0001/input/input0
Mar  8 20:43:42 raspberrypi kernel: [    4.535190] input: RFID Reader RFID Reader Keyboard as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.0/0003:1A86:DD01.0001/input/input1
Mar  8 20:43:42 raspberrypi kernel: [    4.606067] hid-generic 0003:1A86:DD01.0001: input,hidraw0: USB HID v1.10 Device [RFID Reader RFID Reader] on usb-3f980000.usb-1.4/input0
Mar  8 20:43:42 raspberrypi kernel: [    5.390789] random: systemd: uninitialized urandom read (16 bytes read)
Mar  8 20:43:42 raspberrypi kernel: [    5.414418] random: systemd: uninitialized urandom read (16 bytes read)
Mar  8 20:43:42 raspberrypi kernel: [    5.428932] random: systemd: uninitialized urandom read (16 bytes read)
Mar  8 20:43:42 raspberrypi kernel: [    6.345370] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
Mar  8 20:43:42 raspberrypi kernel: [    7.101050] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
Mar  8 20:43:42 raspberrypi kernel: [    7.103935] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
Mar  8 20:43:42 raspberrypi kernel: [    7.103964] [vc_sm_connected_init]: start
Mar  8 20:43:42 raspberrypi kernel: [    7.112152] mc: Linux media interface: v0.10
Mar  8 20:43:42 raspberrypi kernel: [    7.115596] [vc_sm_connected_init]: installed successfully
Mar  8 20:43:42 raspberrypi kernel: [    7.182543] videodev: Linux video capture interface: v2.00
Mar  8 20:43:42 raspberrypi kernel: [    7.216049] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
Mar  8 20:43:42 raspberrypi kernel: [    7.228299] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
Mar  8 20:43:42 raspberrypi kernel: [    7.246250] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
Mar  8 20:43:42 raspberrypi kernel: [    7.258584] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
Mar  8 20:43:42 raspberrypi kernel: [    7.269129] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
Mar  8 20:43:42 raspberrypi kernel: [    7.288115] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
Mar  8 20:43:42 raspberrypi kernel: [    7.291066] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
Mar  8 20:43:42 raspberrypi kernel: [    7.308571] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
Mar  8 20:43:42 raspberrypi kernel: [    7.308637] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
Mar  8 20:43:42 raspberrypi kernel: [    7.319900] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
Mar  8 20:43:42 raspberrypi kernel: [    7.320078] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
Mar  8 20:43:42 raspberrypi kernel: [    7.328674] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
Mar  8 20:43:42 raspberrypi kernel: [    7.329366] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
Mar  8 20:43:42 raspberrypi kernel: [    7.329844] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
Mar  8 20:43:42 raspberrypi kernel: [    7.330035] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
Mar  8 20:43:42 raspberrypi kernel: [    7.330104] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
Mar  8 20:43:42 raspberrypi kernel: [    7.330316] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
Mar  8 20:43:42 raspberrypi kernel: [    7.330363] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
Mar  8 20:43:42 raspberrypi kernel: [    7.330422] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
Mar  8 20:43:42 raspberrypi kernel: [    7.330455] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
Mar  8 20:43:42 raspberrypi kernel: [    7.330483] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
Mar  8 20:43:42 raspberrypi kernel: [    7.330962] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
Mar  8 20:43:42 raspberrypi kernel: [    7.331153] bcm2835_audio bcm2835_audio: card created with 8 channels
Mar  8 20:43:42 raspberrypi kernel: [    7.346559] bcm2835-codec bcm2835-codec: Device registered as /dev/video18
Mar  8 20:43:42 raspberrypi kernel: [    7.346644] bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx
Mar  8 20:43:42 raspberrypi kernel: [    7.607963] cfg80211: Loading compiled-in X.509 certificates for regulatory database
Mar  8 20:43:42 raspberrypi kernel: [    7.728942] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
Mar  8 20:43:42 raspberrypi kernel: [    7.757695] cfg80211: loaded regulatory.db is malformed or signature is missing/invalid
Mar  8 20:43:42 raspberrypi kernel: [    7.892045] brcmfmac: F1 signature read @0x18000000=0x1541a9a6
Mar  8 20:43:42 raspberrypi kernel: [    7.919389] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1
Mar  8 20:43:42 raspberrypi kernel: [    7.920235] usbcore: registered new interface driver brcmfmac
Mar  8 20:43:42 raspberrypi kernel: [    8.211451] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1
Mar  8 20:43:42 raspberrypi kernel: [    8.227376] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Jul 19 2021 03:24:18 version 7.45.98 (TOB) (56df937 CY) FWID 01-8e14b897
Mar  8 20:43:42 raspberrypi kernel: [    9.444950] Under-voltage detected! (0x00050005)
Mar  8 20:43:42 raspberrypi kernel: [    9.721092] random: crng init done
Mar  8 20:43:42 raspberrypi kernel: [    9.721112] random: 7 urandom warning(s) missed due to ratelimiting
Mar  8 20:43:42 raspberrypi systemd[1]: Starting System Logging Service...
Mar  8 20:43:42 raspberrypi systemd[1]: Started Regular background program processing daemon.
Mar  8 20:43:42 raspberrypi systemd[1]: Starting rng-tools.service...
Mar  8 20:43:42 raspberrypi thd[386]: Found socket passed from systemd
Mar  8 20:43:42 raspberrypi thd[386]: Device /dev/input/event0 not suitable.
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Check for Raspberry Pi EEPROM updates...
Mar  8 20:43:42 raspberrypi cron[389]: (CRON) INFO (pidfile fd = 3)
Mar  8 20:43:42 raspberrypi systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped.
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Deferred execution scheduler...
Mar  8 20:43:42 raspberrypi systemd[1]: Starting dhcpcd on all interfaces...
Mar  8 20:43:42 raspberrypi rng-tools[391]: Starting Hardware RNG entropy gatherer daemon: rngd.
Mar  8 20:43:42 raspberrypi rngd[399]: rngd 2-unofficial-mt.14 starting up...
Mar  8 20:43:42 raspberrypi systemd[1]: Condition check resulted in Turn on SSH if /boot/ssh is present being skipped.
Mar  8 20:43:42 raspberrypi systemd[1]: Starting dphys-swapfile - set up, mount/unmount, and delete a swap file...
Mar  8 20:43:42 raspberrypi cron[389]: (CRON) INFO (Running @reboot jobs)
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
Mar  8 20:43:42 raspberrypi systemd[1]: Started Daily apt download activities.
Mar  8 20:43:42 raspberrypi rngd[399]: entropy feed to the kernel ready
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Login Service...
Mar  8 20:43:42 raspberrypi systemd[1]: Started Clean PHP session files every 30 mins.
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Configure Bluetooth Modems connected by UART...
Mar  8 20:43:42 raspberrypi systemd[1]: Started D-Bus System Message Bus.
Mar  8 20:43:42 raspberrypi rpi-eeprom-update[392]: This tool only works with a Raspberry Pi 4
Mar  8 20:43:42 raspberrypi systemd[1]: Starting WPA supplicant...
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Save/Restore Sound Card State...
Mar  8 20:43:42 raspberrypi systemd[1]: Started Daily apt upgrade and clean activities.
Mar  8 20:43:42 raspberrypi systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped.
Mar  8 20:43:42 raspberrypi systemd[1]: Started Daily rotation of log files.
Mar  8 20:43:42 raspberrypi systemd[1]: Reached target Timers.
Mar  8 20:43:42 raspberrypi dhcpcd[397]: dev: loaded udev
Mar  8 20:43:42 raspberrypi rsyslogd: imuxsock: Acquired UNIX socket '/run/systemd/journal/syslog' (fd 3) from systemd.  [v8.1901.0]
Mar  8 20:43:42 raspberrypi rsyslogd:  [origin software="rsyslogd" swVersion="8.1901.0" x-pid="388" x-info="https://www.rsyslog.com"] start
Mar  8 20:43:42 raspberrypi systemd[1]: Started triggerhappy global hotkey daemon.
Mar  8 20:43:42 raspberrypi systemd[1]: Started System Logging Service.
Mar  8 20:43:42 raspberrypi systemd[1]: Started rng-tools.service.
Mar  8 20:43:42 raspberrypi systemd[1]: Started Check for Raspberry Pi EEPROM updates.
Mar  8 20:43:42 raspberrypi avahi-daemon[404]: Found user 'avahi' (UID 108) and group 'avahi' (GID 113).
Mar  8 20:43:42 raspberrypi avahi-daemon[404]: Successfully dropped root privileges.
Mar  8 20:43:42 raspberrypi avahi-daemon[404]: avahi-daemon 0.7 starting up.
Mar  8 20:43:42 raspberrypi systemd[1]: Started Deferred execution scheduler.
Mar  8 20:43:42 raspberrypi systemd[1]: Started Save/Restore Sound Card State.
Mar  8 20:43:42 raspberrypi kernel: [    9.971174] 8021q: 802.1Q VLAN Support v1.8
Mar  8 20:43:42 raspberrypi systemd[1]: Reached target Sound Card.
Mar  8 20:43:42 raspberrypi dphys-swapfile[403]: want /var/swap=100MByte, checking existing: keeping it
Mar  8 20:43:42 raspberrypi kernel: [   10.106609] uart-pl011 3f201000.serial: no DMA platform data
Mar  8 20:43:42 raspberrypi btuart[414]: Cannot open directory '/etc/firmware': No such file or directory
Mar  8 20:43:42 raspberrypi btuart[414]: Patch not found, continue anyway
Mar  8 20:43:42 raspberrypi raspi-config[387]: Checking if shift key is held down:Device /dev/input/event0 not suitable.
Mar  8 20:43:42 raspberrypi dbus-daemon[416]: [system] Successfully activated service 'org.freedesktop.systemd1'
Mar  8 20:43:42 raspberrypi avahi-daemon[404]: Successfully called chroot().
Mar  8 20:43:42 raspberrypi avahi-daemon[404]: Successfully dropped remaining capabilities.
Mar  8 20:43:42 raspberrypi avahi-daemon[404]: No service file found in /etc/avahi/services.
Mar  8 20:43:42 raspberrypi systemd[1]: Started Login Service.
Mar  8 20:43:42 raspberrypi avahi-daemon[404]: Network interface enumeration completed.
Mar  8 20:43:42 raspberrypi avahi-daemon[404]: Server startup complete. Host name is raspberrypi.local. Local service cookie is 1775547982.
Mar  8 20:43:42 raspberrypi wpa_supplicant[422]: Successfully initialized wpa_supplicant
Mar  8 20:43:42 raspberrypi systemd[1]: Started Avahi mDNS/DNS-SD Stack.
Mar  8 20:43:42 raspberrypi systemd[1]: Started WPA supplicant.
Mar  8 20:43:42 raspberrypi kernel: [   10.237762] Bluetooth: Core ver 2.22
Mar  8 20:43:42 raspberrypi kernel: [   10.237915] NET: Registered protocol family 31
Mar  8 20:43:42 raspberrypi kernel: [   10.237928] Bluetooth: HCI device and connection manager initialized
Mar  8 20:43:42 raspberrypi kernel: [   10.238668] Bluetooth: HCI socket layer initialized
Mar  8 20:43:42 raspberrypi kernel: [   10.238692] Bluetooth: L2CAP socket layer initialized
Mar  8 20:43:42 raspberrypi kernel: [   10.238730] Bluetooth: SCO socket layer initialized
Mar  8 20:43:42 raspberrypi dhcpcd[397]: wlan0: starting wpa_supplicant
Mar  8 20:43:42 raspberrypi btuart[414]: bcm43xx_init
Mar  8 20:43:42 raspberrypi btuart[414]: Set BDADDR UART: b8:27:eb:13:5d:48
Mar  8 20:43:42 raspberrypi btuart[414]: Set Controller UART speed to 921600 bit/s
Mar  8 20:43:42 raspberrypi btuart[414]: Device setup complete
Mar  8 20:43:42 raspberrypi systemd[1]: Started Configure Bluetooth Modems connected by UART.
Mar  8 20:43:42 raspberrypi kernel: [   10.265283] Bluetooth: HCI UART driver ver 2.3
Mar  8 20:43:42 raspberrypi kernel: [   10.265311] Bluetooth: HCI UART protocol H4 registered
Mar  8 20:43:42 raspberrypi kernel: [   10.265453] Bluetooth: HCI UART protocol Three-wire (H5) registered
Mar  8 20:43:42 raspberrypi kernel: [   10.265755] Bluetooth: HCI UART protocol Broadcom registered
Mar  8 20:43:42 raspberrypi dhcpcd-run-hooks[484]: wlan0: starting wpa_supplicant
Mar  8 20:43:42 raspberrypi kernel: [   10.295083] Adding 102396k swap on /var/swap.  Priority:-2 extents:1 across:102396k SSFS
Mar  8 20:43:42 raspberrypi systemd[1]: Started dphys-swapfile - set up, mount/unmount, and delete a swap file.
Mar  8 20:43:42 raspberrypi systemd[1]: Created slice system-bthelper.slice.
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Raspberry Pi bluetooth helper...
Mar  8 20:43:42 raspberrypi kernel: [   10.462620] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
Mar  8 20:43:42 raspberrypi bthelper[489]: Raspberry Pi BDADDR already set
Mar  8 20:43:42 raspberrypi systemd[1]: Started Raspberry Pi bluetooth helper.
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Bluetooth service...
Mar  8 20:43:42 raspberrypi bluetoothd[499]: Bluetooth daemon 5.50
Mar  8 20:43:43 raspberrypi systemd[1]: Started Bluetooth service.
Mar  8 20:43:43 raspberrypi systemd[1]: Reached target Bluetooth.
Mar  8 20:43:43 raspberrypi bluetoothd[499]: Starting SDP server
Mar  8 20:43:43 raspberrypi kernel: [   10.728045] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Mar  8 20:43:43 raspberrypi kernel: [   10.728069] Bluetooth: BNEP filters: protocol multicast
Mar  8 20:43:43 raspberrypi kernel: [   10.728099] Bluetooth: BNEP socket layer initialized
Mar  8 20:43:43 raspberrypi dbus-daemon[416]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=499 comm="/usr/lib/bluetooth/bluetoothd ")
Mar  8 20:43:43 raspberrypi bluetoothd[499]: Bluetooth management interface 1.18 initialized
Mar  8 20:43:43 raspberrypi bluetoothd[499]: Sap driver initialization failed.
Mar  8 20:43:43 raspberrypi bluetoothd[499]: sap-server: Operation not permitted (1)
Mar  8 20:43:43 raspberrypi systemd[1]: Starting Hostname Service...
Mar  8 20:43:43 raspberrypi dhcpcd[397]: wlan0: connected to Access Point `'
Mar  8 20:43:43 raspberrypi bluetoothd[499]: Failed to set privacy: Rejected (0x0b)
Mar  8 20:43:43 raspberrypi dhcpcd[397]: eth0: waiting for carrier
Mar  8 20:43:43 raspberrypi dhcpcd[397]: wlan0: waiting for carrier
Mar  8 20:43:43 raspberrypi kernel: [   10.977837] SMSC LAN8700 usb-001:003:01: attached PHY driver [SMSC LAN8700] (mii_bus:phy_addr=usb-001:003:01, irq=POLL)
Mar  8 20:43:43 raspberrypi kernel: [   10.978366] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
Mar  8 20:43:43 raspberrypi kernel: [   10.996223] smsc95xx 1-1.1:1.0 eth0: Link is Down
Mar  8 20:43:43 raspberrypi dbus-daemon[416]: [system] Successfully activated service 'org.freedesktop.hostname1'
Mar  8 20:43:43 raspberrypi systemd[1]: Started Hostname Service.
Mar  8 20:43:43 raspberrypi raspi-config[387]:  No. Switching to ondemand scaling governor.
Mar  8 20:43:43 raspberrypi systemd[1]: Started LSB: Switch to ondemand cpu governor (unless shift key is pressed).
Mar  8 20:43:44 raspberrypi dhcpcd[397]: wlan0: carrier acquired
Mar  8 20:43:44 raspberrypi dhcpcd[397]: wlan0: connected to Access Point `Bbox-DE'
Mar  8 20:43:44 raspberrypi kernel: [   12.079383] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Mar  8 20:43:44 raspberrypi dhcpcd[397]: DUID 00:01:00:01:29:3a:ef:2b:b8:27:eb:ec:a2:b7
Mar  8 20:43:44 raspberrypi dhcpcd[397]: wlan0: IAID eb:ec:a2:b7
Mar  8 20:43:44 raspberrypi dhcpcd[397]: wlan0: adding address fe80::d72b:f5e5:e13a:76a9
Mar  8 20:43:44 raspberrypi avahi-daemon[404]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::d72b:f5e5:e13a:76a9.
Mar  8 20:43:44 raspberrypi dhcpcd[397]: wlan0: probing address 192.168.1.124/24
Mar  8 20:43:44 raspberrypi avahi-daemon[404]: New relevant interface wlan0.IPv6 for mDNS.
Mar  8 20:43:44 raspberrypi avahi-daemon[404]: Registering new address record for fe80::d72b:f5e5:e13a:76a9 on wlan0.*.
Mar  8 20:43:45 raspberrypi dhcpcd[397]: wlan0: soliciting an IPv6 router
Mar  8 20:43:45 raspberrypi dhcpcd[397]: wlan0: Router Advertisement from fe80::6eff:ceff:fe2e:79fc
Mar  8 20:43:45 raspberrypi dhcpcd[397]: wlan0: adding address 2001:861:4002:1090:e2c9:9eeb:7f33:b58d/64
Mar  8 20:43:45 raspberrypi avahi-daemon[404]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::d72b:f5e5:e13a:76a9.
Mar  8 20:43:45 raspberrypi avahi-daemon[404]: Joining mDNS multicast group on interface wlan0.IPv6 with address 2001:861:4002:1090:e2c9:9eeb:7f33:b58d.
Mar  8 20:43:45 raspberrypi avahi-daemon[404]: Registering new address record for 2001:861:4002:1090:e2c9:9eeb:7f33:b58d on wlan0.*.
Mar  8 20:43:45 raspberrypi avahi-daemon[404]: Withdrawing address record for fe80::d72b:f5e5:e13a:76a9 on wlan0.
Mar  8 20:43:45 raspberrypi dhcpcd[397]: wlan0: adding route to 2001:861:4002:1090::/64
Mar  8 20:43:45 raspberrypi dhcpcd[397]: wlan0: requesting DHCPv6 information
Mar  8 20:43:45 raspberrypi dhcpcd[397]: wlan0: fe80::6eff:ceff:fe2e:79fc is reachable again
Mar  8 20:43:45 raspberrypi kernel: [   13.306994] ICMPv6: process `dhcpcd' is using deprecated sysctl (syscall) net.ipv6.neigh.wlan0.retrans_time - use net.ipv6.neigh.wlan0.retrans_time_ms instead
Mar  8 20:43:45 raspberrypi dhcpcd[397]: wlan0: adding default route via fe80::6eff:ceff:fe2e:79fc
Mar  8 20:43:47 raspberrypi systemd[1]: systemd-rfkill.service: Succeeded.
Mar  8 20:43:47 raspberrypi bthelper[489]: Changing power off succeeded
Mar  8 20:43:48 raspberrypi bthelper[489]: Changing power on succeeded
Mar  8 20:43:49 raspberrypi dhcpcd[397]: wlan0: using static address 192.168.1.124/24
Mar  8 20:43:49 raspberrypi avahi-daemon[404]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.124.
Mar  8 20:43:49 raspberrypi avahi-daemon[404]: New relevant interface wlan0.IPv4 for mDNS.
Mar  8 20:43:49 raspberrypi avahi-daemon[404]: Registering new address record for 192.168.1.124 on wlan0.IPv4.
Mar  8 20:43:49 raspberrypi dhcpcd[397]: wlan0: adding route to 192.168.1.0/24
Mar  8 20:43:49 raspberrypi dhcpcd[397]: wlan0: adding default route via 192.168.1.254
Mar  8 20:43:49 raspberrypi dhcpcd[397]: forked to background, child pid 570
Mar  8 20:43:49 raspberrypi systemd[1]: Started dhcpcd on all interfaces.
Mar  8 20:43:49 raspberrypi systemd[1]: Reached target Network.
Mar  8 20:43:49 raspberrypi systemd[1]: Starting Music Player Daemon...
Mar  8 20:43:49 raspberrypi systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped.
Mar  8 20:43:49 raspberrypi systemd[1]: Starting Permit User Sessions...
Mar  8 20:43:49 raspberrypi systemd[1]: Starting OpenBSD Secure Shell server...
Mar  8 20:43:49 raspberrypi systemd[1]: Reached target Network is Online.
Mar  8 20:43:49 raspberrypi systemd[1]: Starting LSB: exim Mail Transport Agent...
Mar  8 20:43:49 raspberrypi systemd[1]: Starting Lighttpd Daemon...
Mar  8 20:43:49 raspberrypi systemd[1]: Started Phoniebox RFID-Reader Service.
Mar  8 20:43:50 raspberrypi systemd[1]: Started Phoniebox GPIO Control Service.
Mar  8 20:43:50 raspberrypi systemd[1]: Starting Samba NMB Daemon...
Mar  8 20:43:50 raspberrypi systemd[1]: Starting /etc/rc.local Compatibility...
Mar  8 20:43:50 raspberrypi systemd[1]: Started Phoniebox Idle Watchdog Service.
Mar  8 20:43:50 raspberrypi systemd[1]: Started Permit User Sessions.
Mar  8 20:43:50 raspberrypi systemd[1]: Started /etc/rc.local Compatibility.
Mar  8 20:43:50 raspberrypi systemd[1]: Started Getty on tty1.
Mar  8 20:43:50 raspberrypi systemd[1]: Reached target Login Prompts.
Mar  8 20:43:50 raspberrypi systemd[1]: Started OpenBSD Secure Shell server.
Mar  8 20:43:51 raspberrypi lighttpd[575]: 2024-03-08 20:43:50: (server.c.1493) WARNING: unknown config-key: deflate.cache-dir (ignored)
Mar  8 20:43:51 raspberrypi systemd[1]: Started Lighttpd Daemon.
Mar  8 20:43:52 raspberrypi systemd[1]: Started Samba NMB Daemon.
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:GPIO Started
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:['VolumeControl', 'PrevNextControl', 'PlayPause', 'Shutdown', 'PauseShutdown', 'Volume0', 'VolumeUp', 'VolumeDown', 'NextSong', 'PrevSong', 'FastForward', 'Rewind', 'Halt', 'RFIDDevice']
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:adding GPIO-Device, VolumeControl
Mar  8 20:43:52 raspberrypi systemd[1]: Starting Samba SMB Daemon...
Mar  8 20:43:52 raspberrypi exim4[574]: Starting MTA: exim4.
Mar  8 20:43:52 raspberrypi systemd[1]: Started LSB: exim Mail Transport Agent.
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:Device PrevNextControl not enabled
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:adding GPIO-Device, PlayPause
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:adding GPIO-Device, Shutdown
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:Device PauseShutdown not enabled
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:Device Volume0 not enabled
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:Device VolumeUp not enabled
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:Device VolumeDown not enabled
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:adding GPIO-Device, NextSong
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:adding GPIO-Device, PrevSong
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:Device FastForward not enabled
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:Device Rewind not enabled
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:Device Halt not enabled
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:Device RFIDDevice not enabled
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:Ready for taking actions
Mar  8 20:43:52 raspberrypi daemon_rfid_reader.py[576]: 2024-03-08 20:43:52,791 - root - INFO - Dir_PATH: /home/pi/RPi-Jukebox-RFID/scripts
Mar  8 20:43:53 raspberrypi systemd[1]: Started Samba SMB Daemon.
Mar  8 20:43:55 raspberrypi mpd: zeroconf: No global port, disabling zeroconf
Mar  8 20:43:55 raspberrypi systemd[1]: Started Music Player Daemon.
Mar  8 20:43:55 raspberrypi systemd[1]: Starting Phoniebox Startup...
Mar  8 20:43:55 raspberrypi bash[913]: Phoniebox is starting...
Mar  8 20:43:55 raspberrypi bash[913]: 2.5.0
Mar  8 20:43:55 raspberrypi bash[913]: AUDIOFOLDERSPATH="/home/pi/RPi-Jukebox-RFID/shared/audiofolders"
Mar  8 20:43:55 raspberrypi bash[913]: PLAYLISTSFOLDERPATH="/home/pi/RPi-Jukebox-RFID/playlists"
Mar  8 20:43:55 raspberrypi bash[913]: SWIPEORPLACE="SWIPENOTPLACE"
Mar  8 20:43:55 raspberrypi bash[913]: SECONDSWIPE="NOAUDIOPLAY"
Mar  8 20:43:55 raspberrypi bash[913]: SECONDSWIPEPAUSE="2"
Mar  8 20:43:55 raspberrypi bash[913]: SECONDSWIPEPAUSECONTROLS="ON"
Mar  8 20:43:55 raspberrypi bash[913]: AUDIOIFACENAME="Headphone"
Mar  8 20:43:55 raspberrypi bash[913]: AUDIOIFACEACTIVE="0"
Mar  8 20:43:55 raspberrypi bash[913]: VOLUMEMANAGER="mpd"
Mar  8 20:43:55 raspberrypi bash[913]: AUDIOVOLCHANGESTEP="3"
Mar  8 20:43:55 raspberrypi bash[913]: AUDIOVOLMAXLIMIT="60"
Mar  8 20:43:55 raspberrypi bash[913]: AUDIOVOLMINLIMIT="1"
Mar  8 20:43:55 raspberrypi bash[913]: AUDIOVOLSTARTUP="25"
Mar  8 20:43:55 raspberrypi bash[913]: AUDIOVOLBOOT="25"
Mar  8 20:43:55 raspberrypi bash[913]: VOLCHANGEIDLE="TRUE"
Mar  8 20:43:55 raspberrypi bash[913]: IDLETIMESHUTDOWN="0"
Mar  8 20:43:55 raspberrypi bash[913]: POWEROFFCMD="sudo poweroff"
Mar  8 20:43:55 raspberrypi bash[913]: SHOWCOVER="ON"
Mar  8 20:43:55 raspberrypi bash[913]: READWLANIPYN="OFF"
Mar  8 20:43:55 raspberrypi bash[913]: EDITION="classic"
Mar  8 20:43:55 raspberrypi bash[913]: LANG="fr-FR"
Mar  8 20:43:55 raspberrypi bash[913]: VERSION="2.5.0 - 4101984b - master"
Mar  8 20:43:55 raspberrypi bash[913]: CHAPTEREXTENSIONS="mp4,m4a,m4b,m4r"
Mar  8 20:43:55 raspberrypi bash[913]: CHAPTERMINDURATION="600"
Mar  8 20:43:55 raspberrypi bash[913]: CMDVOLUP=""
Mar  8 20:43:55 raspberrypi bash[913]: CMDVOLDOWN=""
Mar  8 20:43:55 raspberrypi bash[913]: CMDNEXT=""
Mar  8 20:43:55 raspberrypi bash[913]: CMDPREV=""
Mar  8 20:43:55 raspberrypi bash[913]: CMDREWIND=""
Mar  8 20:43:55 raspberrypi bash[913]: CMDSEEKFORW=""
Mar  8 20:43:55 raspberrypi bash[913]: CMDSEEKBACK=""
Mar  8 20:43:55 raspberrypi bash[913]: 25 is the mpd startup volume
Mar  8 20:43:55 raspberrypi systemd[1]: Created slice User Slice of UID 1000.
Mar  8 20:43:55 raspberrypi systemd[1]: Starting User Runtime Directory /run/user/1000...
Mar  8 20:43:55 raspberrypi systemd[1]: Started User Runtime Directory /run/user/1000.
Mar  8 20:43:55 raspberrypi systemd[1]: Starting User Manager for UID 1000...
Mar  8 20:43:55 raspberrypi bash[913]: OK MPD 0.21.4
Mar  8 20:43:55 raspberrypi bash[913]: OK
Mar  8 20:43:55 raspberrypi bash[913]: OK MPD 0.21.4
Mar  8 20:43:55 raspberrypi bash[913]: OK
Mar  8 20:43:55 raspberrypi bash[913]: 8192 is the mpg123 startup volume
Mar  8 20:43:56 raspberrypi bash[913]: High Performance MPEG 1.0/2.0/2.5 Audio Player for Layers 1, 2 and 3
Mar  8 20:43:56 raspberrypi bash[913]: #011version 1.25.10; written and copyright by Michael Hipp and others
Mar  8 20:43:56 raspberrypi bash[913]: #011free software (LGPL) without any warranty but with best wishes
Mar  8 20:43:56 raspberrypi systemd[954]: Listening on GnuPG cryptographic agent and passphrase cache.
Mar  8 20:43:56 raspberrypi systemd[954]: Reached target Timers.
Mar  8 20:43:56 raspberrypi systemd[954]: Listening on GnuPG cryptographic agent and passphrase cache (restricted).
Mar  8 20:43:56 raspberrypi systemd[954]: Listening on GnuPG cryptographic agent (ssh-agent emulation).
Mar  8 20:43:56 raspberrypi systemd[954]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers).
Mar  8 20:43:56 raspberrypi systemd[954]: Reached target Paths.
Mar  8 20:43:56 raspberrypi systemd[954]: Listening on GnuPG network certificate management daemon.
Mar  8 20:43:56 raspberrypi systemd[954]: Reached target Sockets.
Mar  8 20:43:56 raspberrypi systemd[954]: Reached target Basic System.
Mar  8 20:43:56 raspberrypi systemd[1]: Started User Manager for UID 1000.
Mar  8 20:43:56 raspberrypi systemd[954]: Starting Music Player Daemon...
Mar  8 20:43:56 raspberrypi systemd[1]: Started Session 1 of user pi.
Mar  8 20:43:56 raspberrypi bash[913]: Cannot connect to server socket err = No such file or directory
Mar  8 20:43:56 raspberrypi bash[913]: Cannot connect to server request channel
Mar  8 20:43:56 raspberrypi bash[913]: jack server is not running or cannot be started
Mar  8 20:43:56 raspberrypi bash[913]: JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock
Mar  8 20:43:56 raspberrypi bash[913]: JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock
Mar  8 20:43:56 raspberrypi bash[913]: /home/pi/RPi-Jukebox-RFID/scripts/startup-scripts.sh: line 53:  1032 Segmentation fault      /usr/bin/mpg123 -f -${mpgvolume} /home/pi/RPi-Jukebox-RFID/shared/startupsound.mp3
Mar  8 20:43:56 raspberrypi bash[913]: Updating DB (#1) ...
Mar  8 20:43:56 raspberrypi bash[913]: volume: 25%   repeat: off   random: off   single: off   consume: off
Mar  8 20:43:56 raspberrypi mpd: update: updating Cuentos/Arreglar el mundo _ Cuento con valores (192kbit_AAC).m4a
Mar  8 20:43:56 raspberrypi systemd[1]: Started Phoniebox Startup.
Mar  8 20:43:56 raspberrypi dhcpcd[570]: wlan0: fe80::6eff:ceff:fe2e:79fc is reachable again
Mar  8 20:43:56 raspberrypi systemd[1]: Reached target Multi-User System.
Mar  8 20:43:56 raspberrypi dhcpcd[570]: wlan0: fe80::6eff:ceff:fe2e:79fc is reachable again
Mar  8 20:43:56 raspberrypi systemd[1]: Starting Update UTMP about System 00340434666707177973j

Configuration

No response

More info

No response

s-martin commented 8 months ago

/home/pi/RPi-Jukebox-RFID/scripts/startup-scripts.sh: line 53: 1032 Segmentation fault /usr/bin/mpg123 -f **-${mpgvolume}** /home/pi/RPi-Jukebox-RFID/shared/startupsound.mp3

I assume the not expanded variable {mpgvolume} is the issue. Not sure what causes this, though.

dvalob commented 8 months ago

it is declared here, right? image

s-martin commented 8 months ago

~~ Can you try to remove the - right before {mpgvolume} here:

https://github.com/MiczFlor/RPi-Jukebox-RFID/blob/develop/scripts%2Fstartup-scripts.sh#L53 ~~

Nevermind, could be negative

dvalob commented 8 months ago

I do not think it will help. I have another phonie box where the start/stop sound play, and the code is exactlly the same on both: image

They have the exactly same OS version, same HW. The left one is 2.4 - abcf6e92 - master, the right one is 2.5.0 - 4101984b - master

AlvinSchiller commented 8 months ago

Do you use the audiojack or another audio output (like hifiberry)?

dvalob commented 8 months ago

Jack output

Le ven. 15 mars 2024 à 16:19, Alvin Schiller @.***> a écrit :

Do you use the audiojack or another audio output (like hifiberry)?

— Reply to this email directly, view it on GitHub https://github.com/MiczFlor/RPi-Jukebox-RFID/issues/2291#issuecomment-1999891579, or unsubscribe https://github.com/notifications/unsubscribe-auth/ALLOICE3WUXVZ2C3DSY55PLYYMGQNAVCNFSM6AAAAABEXSHFQ2VHI2DSMVQWIX3LMV43OSLTON2WKQ3PNVWWK3TUHMYTSOJZHA4TCNJXHE . You are receiving this because you authored the thread.Message ID: @.***>

AlvinSchiller commented 8 months ago

What happens if you trigger the command manually? /usr/bin/mpg123 /home/pi/RPi-Jukebox-RFID/shared/startupsound.mp3

And do you have a file /home/pi/.asoundrc?

dvalob commented 7 months ago

Hi! Please find the output

Le ven. 15 mars 2024 à 23:16, Alvin Schiller @.***> a écrit :

What happens if you trigger the command manually? /usr/bin/mpg123 /home/pi/RPi-Jukebox-RFID/shared/startupsound.mp3

— Reply to this email directly, view it on GitHub https://github.com/MiczFlor/RPi-Jukebox-RFID/issues/2291#issuecomment-2000574909, or unsubscribe https://github.com/notifications/unsubscribe-auth/ALLOICBJMF6644ECOYX45WLYYNXM5AVCNFSM6AAAAABEXSHFQ2VHI2DSMVQWIX3LMV43OSLTON2WKQ3PNVWWK3TUHMZDAMBQGU3TIOJQHE . You are receiving this because you authored the thread.Message ID: @.***>

dvalob commented 7 months ago

IMG_4984

And yes, I do have the file /home/pi/.asoundrc image image

AlvinSchiller commented 7 months ago

Have you added the .asoundrc yourself? Can you try and remove it (or backup with mv .asoundrc .asoundrc.bak) and reboot?

dvalob commented 7 months ago

no it was already there. I did what you suggested and now I can play both startup and shutdown sounds through /usr/bin/mpg123 /home/pi/RPi-Jukebox-RFID/shared/startupsound.mp3. Thanks!

When I start/stop the box I have both now. Thanks for your support!!

dvalob commented 7 months ago

Should I close it myself, or let you close it (maybe with a note to fix it in the new versions?)

s-martin commented 7 months ago

When it's fixed for you, you can close it

dvalob commented 7 months ago

Closed when removing the .asoundrc file. thanks