-- Logs begin at Wed 2024-09-18 00:06:54 KST, end at Wed 2024-09-18 00:07:17 KST. -- Sep 18 00:06:54 red kernel: Booting Linux on physical CPU 0x0 Sep 18 00:06:54 red kernel: Linux version 6.1.69-v7l+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1710 SMP Thu Dec 21 13:17:38 GMT 2023 Sep 18 00:06:54 red kernel: CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d Sep 18 00:06:54 red kernel: CPU: div instructions available: patching division code Sep 18 00:06:54 red kernel: CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache Sep 18 00:06:54 red kernel: OF: fdt: Machine model: Raspberry Pi Compute Module 4 Rev 1.1 Sep 18 00:06:54 red kernel: random: crng init done Sep 18 00:06:54 red kernel: Memory policy: Data cache writealloc Sep 18 00:06:54 red kernel: Reserved memory: created CMA memory pool at 0x000000002a000000, size 64 MiB Sep 18 00:06:54 red kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Sep 18 00:06:54 red kernel: Zone ranges: Sep 18 00:06:54 red kernel: DMA [mem 0x0000000000000000-0x000000002fffffff] Sep 18 00:06:54 red kernel: Normal empty Sep 18 00:06:54 red kernel: HighMem [mem 0x0000000030000000-0x000000007fffffff] Sep 18 00:06:54 red kernel: Movable zone start for each node Sep 18 00:06:54 red kernel: Early memory node ranges Sep 18 00:06:54 red kernel: node 0: [mem 0x0000000000000000-0x0000000037ffffff] Sep 18 00:06:54 red kernel: node 0: [mem 0x0000000040000000-0x000000007fffffff] Sep 18 00:06:54 red kernel: Initmem setup node 0 [mem 0x0000000000000000-0x000000007fffffff] Sep 18 00:06:54 red kernel: percpu: Embedded 17 pages/cpu s37012 r8192 d24428 u69632 Sep 18 00:06:54 red kernel: pcpu-alloc: s37012 r8192 d24428 u69632 alloc=17*4096 Sep 18 00:06:54 red kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Sep 18 00:06:54 red kernel: Built 1 zonelists, mobility grouping on. Total pages: 489792 Sep 18 00:06:54 red kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=D8:3A:DD:6D:97:EC vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=8543fa12-4125-4451-a534-cec5480e0606 imgfile=/volumio_current.sqsh bootpart=UUID=9C48-E5A3 datapart=UUID=8872eeac-d2c8-4050-805e-2a6938d0a401 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Sep 18 00:06:54 red kernel: Unknown kernel command line parameters "splash nodebug imgpart=UUID=8543fa12-4125-4451-a534-cec5480e0606 imgfile=/volumio_current.sqsh bootpart=UUID=9C48-E5A3 datapart=UUID=8872eeac-d2c8-4050-805e-2a6938d0a401 uuidconfig=cmdline.txt pcie_aspm=off bootdelay=7 use_kmsg=no", will be passed to user space. Sep 18 00:06:54 red kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Sep 18 00:06:54 red kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 18 00:06:54 red kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 18 00:06:54 red kernel: software IO TLB: area num 4. Sep 18 00:06:54 red kernel: software IO TLB: mapped [mem 0x0000000024e00000-0x0000000028e00000] (64MB) Sep 18 00:06:54 red kernel: Memory: 1782944K/1966080K available (10240K kernel code, 1505K rwdata, 3388K rodata, 2048K init, 637K bss, 117600K reserved, 65536K cma-reserved, 1179648K highmem) Sep 18 00:06:54 red kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 18 00:06:54 red kernel: ftrace: allocating 37226 entries in 110 pages Sep 18 00:06:54 red kernel: ftrace: allocated 110 pages with 5 groups Sep 18 00:06:54 red kernel: trace event string verifier disabled Sep 18 00:06:54 red kernel: rcu: Hierarchical RCU implementation. Sep 18 00:06:54 red kernel: Rude variant of Tasks RCU enabled. Sep 18 00:06:54 red kernel: Tracing variant of Tasks RCU enabled. Sep 18 00:06:54 red kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Sep 18 00:06:54 red kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Sep 18 00:06:54 red kernel: GIC: Using split EOI/Deactivate mode Sep 18 00:06:54 red kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 18 00:06:54 red kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Sep 18 00:06:54 red kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Sep 18 00:06:54 red kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Sep 18 00:06:54 red kernel: Switching to timer-based delay loop, resolution 18ns Sep 18 00:06:54 red kernel: Console: colour dummy device 80x30 Sep 18 00:06:54 red kernel: printk: console [tty1] enabled Sep 18 00:06:54 red kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000) Sep 18 00:06:54 red kernel: CPU: Testing write buffer coherency: ok Sep 18 00:06:54 red kernel: CPU0: Spectre BHB: enabling loop workaround for all CPUs Sep 18 00:06:54 red kernel: pid_max: default: 32768 minimum: 301 Sep 18 00:06:54 red kernel: LSM: Security Framework initializing Sep 18 00:06:54 red kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Sep 18 00:06:54 red kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Sep 18 00:06:54 red kernel: cgroup: Disabling memory control group subsystem Sep 18 00:06:54 red kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Sep 18 00:06:54 red kernel: cblist_init_generic: Setting adjustable number of callback queues. Sep 18 00:06:54 red kernel: cblist_init_generic: Setting shift to 2 and lim to 1. Sep 18 00:06:54 red kernel: cblist_init_generic: Setting adjustable number of callback queues. Sep 18 00:06:54 red kernel: cblist_init_generic: Setting shift to 2 and lim to 1. Sep 18 00:06:54 red kernel: Setting up static identity map for 0x200000 - 0x20003c Sep 18 00:06:54 red kernel: rcu: Hierarchical SRCU implementation. Sep 18 00:06:54 red kernel: rcu: Max phase no-delay instances is 1000. Sep 18 00:06:54 red kernel: smp: Bringing up secondary CPUs ... Sep 18 00:06:54 red kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Sep 18 00:06:54 red kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Sep 18 00:06:54 red kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Sep 18 00:06:54 red kernel: smp: Brought up 1 node, 4 CPUs Sep 18 00:06:54 red kernel: SMP: Total of 4 processors activated (432.00 BogoMIPS). Sep 18 00:06:54 red kernel: CPU: All CPU(s) started in HYP mode. Sep 18 00:06:54 red kernel: CPU: Virtualization extensions available. Sep 18 00:06:54 red kernel: devtmpfs: initialized Sep 18 00:06:54 red kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0 Sep 18 00:06:54 red kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Sep 18 00:06:54 red kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 18 00:06:54 red kernel: pinctrl core: initialized pinctrl subsystem Sep 18 00:06:54 red kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 18 00:06:54 red kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Sep 18 00:06:54 red kernel: audit: initializing netlink subsys (disabled) Sep 18 00:06:54 red kernel: audit: type=2000 audit(0.020:1): state=initialized audit_enabled=0 res=1 Sep 18 00:06:54 red kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 18 00:06:54 red kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Sep 18 00:06:54 red kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Sep 18 00:06:54 red kernel: Serial: AMBA PL011 UART driver Sep 18 00:06:54 red kernel: bcm2835-mbox fe00b880.mailbox: mailbox enabled Sep 18 00:06:54 red kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2023-10-17T15:39:16, variant start Sep 18 00:06:54 red kernel: raspberrypi-firmware soc:firmware: Firmware hash is 30f0c5e4d076da3ab4f341d88e7d505760b93ad7 Sep 18 00:06:54 red kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 18 00:06:54 red kernel: bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1 Sep 18 00:06:54 red kernel: SCSI subsystem initialized Sep 18 00:06:54 red kernel: usbcore: registered new interface driver usbfs Sep 18 00:06:54 red kernel: usbcore: registered new interface driver hub Sep 18 00:06:54 red kernel: usbcore: registered new device driver usb Sep 18 00:06:54 red kernel: usb_phy_generic phy: supply vcc not found, using dummy regulator Sep 18 00:06:54 red kernel: pps_core: LinuxPPS API ver. 1 registered Sep 18 00:06:54 red kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Sep 18 00:06:54 red kernel: PTP clock support registered Sep 18 00:06:54 red kernel: vgaarb: loaded Sep 18 00:06:54 red kernel: clocksource: Switched to clocksource arch_sys_counter Sep 18 00:06:54 red kernel: VFS: Disk quotas dquot_6.6.0 Sep 18 00:06:54 red kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Sep 18 00:06:54 red kernel: FS-Cache: Loaded Sep 18 00:06:54 red kernel: CacheFiles: Loaded Sep 18 00:06:54 red kernel: NET: Registered PF_INET protocol family Sep 18 00:06:54 red kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) Sep 18 00:06:54 red kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear) Sep 18 00:06:54 red kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 18 00:06:54 red kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Sep 18 00:06:54 red kernel: TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear) Sep 18 00:06:54 red kernel: TCP: Hash tables configured (established 8192 bind 8192) Sep 18 00:06:54 red kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Sep 18 00:06:54 red kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Sep 18 00:06:54 red kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 18 00:06:54 red kernel: RPC: Registered named UNIX socket transport module. Sep 18 00:06:54 red kernel: RPC: Registered udp transport module. Sep 18 00:06:54 red kernel: RPC: Registered tcp transport module. Sep 18 00:06:54 red kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Sep 18 00:06:54 red kernel: PCI: CLS 0 bytes, default 64 Sep 18 00:06:54 red kernel: Trying to unpack rootfs image as initramfs... Sep 18 00:06:54 red kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available Sep 18 00:06:54 red kernel: Initialise system trusted keyrings Sep 18 00:06:54 red kernel: workingset: timestamp_bits=14 max_order=19 bucket_order=5 Sep 18 00:06:54 red kernel: zbud: loaded Sep 18 00:06:54 red kernel: NFS: Registering the id_resolver key type Sep 18 00:06:54 red kernel: Key type id_resolver registered Sep 18 00:06:54 red kernel: Key type id_legacy registered Sep 18 00:06:54 red kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Sep 18 00:06:54 red kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Sep 18 00:06:54 red kernel: Key type asymmetric registered Sep 18 00:06:54 red kernel: Asymmetric key parser 'x509' registered Sep 18 00:06:54 red kernel: bounce: pool size: 64 pages Sep 18 00:06:54 red kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Sep 18 00:06:54 red kernel: io scheduler mq-deadline registered Sep 18 00:06:54 red kernel: io scheduler kyber registered Sep 18 00:06:54 red kernel: gpio-507 (ant1): hogged as output/high Sep 18 00:06:54 red kernel: gpio-511 (ant2): hogged as output/low Sep 18 00:06:54 red kernel: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: Sep 18 00:06:54 red kernel: brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff] Sep 18 00:06:54 red kernel: brcm-pcie fd500000.pcie: MEM 0x0600000000..0x063fffffff -> 0x00c0000000 Sep 18 00:06:54 red kernel: brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x007fffffff -> 0x0400000000 Sep 18 00:06:54 red kernel: brcm-pcie fd500000.pcie: setting SCB_ACCESS_EN, READ_UR_MODE, MAX_BURST_SIZE Sep 18 00:06:54 red kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Sep 18 00:06:54 red kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 18 00:06:54 red kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff]) Sep 18 00:06:54 red kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 Sep 18 00:06:54 red kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Sep 18 00:06:54 red kernel: PCI: bus0: Fast back to back transfers disabled Sep 18 00:06:54 red kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Sep 18 00:06:54 red kernel: pci_bus 0000:01: supply vpcie3v3 not found, using dummy regulator Sep 18 00:06:54 red kernel: pci_bus 0000:01: supply vpcie3v3aux not found, using dummy regulator Sep 18 00:06:54 red kernel: pci_bus 0000:01: supply vpcie12v not found, using dummy regulator Sep 18 00:06:54 red kernel: brcm-pcie fd500000.pcie: link down Sep 18 00:06:54 red kernel: PCI: bus1: Fast back to back transfers enabled Sep 18 00:06:54 red kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Sep 18 00:06:54 red kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Sep 18 00:06:54 red kernel: pci 0000:00:00.0: Max Payload Size set to 128/ 512 (was 128), Max Read Rq 512 Sep 18 00:06:54 red kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 45 Sep 18 00:06:54 red kernel: pci_bus 0000:01: busn_res: [bus 01] is released Sep 18 00:06:54 red kernel: pci_bus 0000:00: busn_res: [bus 00-ff] is released Sep 18 00:06:54 red kernel: bcm2708_fb soc:fb: FB found 1 display(s) Sep 18 00:06:54 red kernel: Console: switching to colour frame buffer device 80x30 Sep 18 00:06:54 red kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Sep 18 00:06:54 red kernel: iproc-rng200 fe104000.rng: hwrng registered Sep 18 00:06:54 red kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Sep 18 00:06:54 red kernel: brd: module loaded Sep 18 00:06:54 red kernel: loop: module loaded Sep 18 00:06:54 red kernel: Loading iSCSI transport class v2.0-870. Sep 18 00:06:54 red kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Sep 18 00:06:54 red kernel: Freeing initrd memory: 14316K Sep 18 00:06:54 red kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Sep 18 00:06:54 red kernel: usbcore: registered new device driver r8152-cfgselector Sep 18 00:06:54 red kernel: usbcore: registered new interface driver r8152 Sep 18 00:06:54 red kernel: usbcore: registered new interface driver lan78xx Sep 18 00:06:54 red kernel: usbcore: registered new interface driver smsc95xx Sep 18 00:06:54 red kernel: xhci-hcd fe9c0000.xhci: xHCI Host Controller Sep 18 00:06:54 red kernel: xhci-hcd fe9c0000.xhci: new USB bus registered, assigned bus number 1 Sep 18 00:06:54 red kernel: xhci-hcd fe9c0000.xhci: USB3 root hub has no ports Sep 18 00:06:54 red kernel: xhci-hcd fe9c0000.xhci: hcc params 0x0220fe65 hci version 0x110 quirks 0x0000000000010010 Sep 18 00:06:54 red kernel: xhci-hcd fe9c0000.xhci: irq 49, io mem 0xfe9c0000 Sep 18 00:06:54 red kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.01 Sep 18 00:06:54 red kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Sep 18 00:06:54 red kernel: usb usb1: Product: xHCI Host Controller Sep 18 00:06:54 red kernel: usb usb1: Manufacturer: Linux 6.1.69-v7l+ xhci-hcd Sep 18 00:06:54 red kernel: usb usb1: SerialNumber: fe9c0000.xhci Sep 18 00:06:54 red kernel: hub 1-0:1.0: USB hub found Sep 18 00:06:54 red kernel: hub 1-0:1.0: 1 port detected Sep 18 00:06:54 red kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Sep 18 00:06:54 red kernel: dwc_otg: FIQ enabled Sep 18 00:06:54 red kernel: dwc_otg: NAK holdoff enabled Sep 18 00:06:54 red kernel: dwc_otg: FIQ split-transaction FSM enabled Sep 18 00:06:54 red kernel: Module dwc_common_port init Sep 18 00:06:54 red kernel: usbcore: registered new interface driver uas Sep 18 00:06:54 red kernel: usbcore: registered new interface driver usb-storage Sep 18 00:06:54 red kernel: mousedev: PS/2 mouse device common for all mice Sep 18 00:06:54 red kernel: sdhci: Secure Digital Host Controller Interface driver Sep 18 00:06:54 red kernel: sdhci: Copyright(c) Pierre Ossman Sep 18 00:06:54 red kernel: sdhci-pltfm: SDHCI platform and OF driver helper Sep 18 00:06:54 red kernel: ledtrig-cpu: registered to indicate activity on CPUs Sep 18 00:06:54 red kernel: hid: raw HID events driver (C) Jiri Kosina Sep 18 00:06:54 red kernel: usbcore: registered new interface driver usbhid Sep 18 00:06:54 red kernel: usbhid: USB HID core driver Sep 18 00:06:54 red kernel: bcm2835_vchiq fe00b840.mailbox: there is not valid maps for state default Sep 18 00:06:54 red kernel: Initializing XFRM netlink socket Sep 18 00:06:54 red kernel: NET: Registered PF_PACKET protocol family Sep 18 00:06:54 red kernel: Key type dns_resolver registered Sep 18 00:06:54 red kernel: Registering SWP/SWPB emulation handler Sep 18 00:06:54 red kernel: registered taskstats version 1 Sep 18 00:06:54 red kernel: Loading compiled-in X.509 certificates Sep 18 00:06:54 red kernel: Key type .fscrypt registered Sep 18 00:06:54 red kernel: Key type fscrypt-provisioning registered Sep 18 00:06:54 red kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Sep 18 00:06:54 red kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Sep 18 00:06:54 red kernel: fe201000.serial: ttyAMA1 at MMIO 0xfe201000 (irq = 51, base_baud = 0) is a PL011 rev2 Sep 18 00:06:54 red kernel: serial serial0: tty port ttyAMA1 registered Sep 18 00:06:54 red kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Sep 18 00:06:54 red kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Sep 18 00:06:54 red kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Sep 18 00:06:54 red kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Sep 18 00:06:54 red kernel: of_cfs_init Sep 18 00:06:54 red kernel: of_cfs_init: OK Sep 18 00:06:54 red kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Sep 18 00:06:54 red kernel: Freeing unused kernel image (initmem) memory: 2048K Sep 18 00:06:54 red kernel: Run /init as init process Sep 18 00:06:54 red kernel: with arguments: Sep 18 00:06:54 red kernel: /init Sep 18 00:06:54 red kernel: splash Sep 18 00:06:54 red kernel: nodebug Sep 18 00:06:54 red kernel: with environment: Sep 18 00:06:54 red kernel: HOME=/ Sep 18 00:06:54 red kernel: TERM=linux Sep 18 00:06:54 red kernel: imgpart=UUID=8543fa12-4125-4451-a534-cec5480e0606 Sep 18 00:06:54 red kernel: imgfile=/volumio_current.sqsh Sep 18 00:06:54 red kernel: bootpart=UUID=9C48-E5A3 Sep 18 00:06:54 red kernel: datapart=UUID=8872eeac-d2c8-4050-805e-2a6938d0a401 Sep 18 00:06:54 red kernel: uuidconfig=cmdline.txt Sep 18 00:06:54 red kernel: pcie_aspm=off Sep 18 00:06:54 red kernel: bootdelay=7 Sep 18 00:06:54 red kernel: use_kmsg=no Sep 18 00:06:54 red kernel: mmc0: new ultra high speed DDR50 SDHC card at address aaaa Sep 18 00:06:54 red kernel: mmcblk0: mmc0:aaaa SP32G 29.7 GiB Sep 18 00:06:54 red kernel: mmcblk0: p1 p2 p3 Sep 18 00:06:54 red kernel: mmcblk0: mmc0:aaaa SP32G 29.7 GiB (quirks 0x00004000) Sep 18 00:06:54 red kernel: usb 1-1: new high-speed USB device number 2 using xhci-hcd Sep 18 00:06:54 red kernel: usb 1-1: New USB device found, idVendor=1a40, idProduct=0101, bcdDevice= 1.00 Sep 18 00:06:54 red kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Sep 18 00:06:54 red kernel: usb 1-1: Product: USB 2.0 Hub [MTT] Sep 18 00:06:54 red kernel: hub 1-1:1.0: USB hub found Sep 18 00:06:54 red kernel: hub 1-1:1.0: 4 ports detected Sep 18 00:06:54 red kernel: usb 1-1.1: new high-speed USB device number 3 using xhci-hcd Sep 18 00:06:54 red kernel: fuse: init (API version 7.37) Sep 18 00:06:54 red kernel: usb 1-1.1: New USB device found, idVendor=152a, idProduct=87c0, bcdDevice=30.20 Sep 18 00:06:54 red kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Sep 18 00:06:54 red kernel: usb 1-1.1: Product: Holo Audio UAC2.0 Gen2 - RED Sep 18 00:06:54 red kernel: usb 1-1.1: Manufacturer: Holo Audio Sep 18 00:06:54 red kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 18 00:06:54 red kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Sep 18 00:06:54 red kernel: EXT4-fs (mmcblk0p2): recovery complete Sep 18 00:06:54 red kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Quota mode: none. Sep 18 00:06:54 red kernel: loop0: detected capacity change from 0 to 983416 Sep 18 00:06:54 red kernel: EXT4-fs (mmcblk0p3): recovery complete Sep 18 00:06:54 red kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Quota mode: none. Sep 18 00:06:54 red systemd[1]: System time before build time, advancing clock. Sep 18 00:06:54 red kernel: NET: Registered PF_INET6 protocol family Sep 18 00:06:54 red kernel: Segment Routing with IPv6 Sep 18 00:06:54 red kernel: In-situ OAM (IOAM) with IPv6 Sep 18 00:06:54 red 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) Sep 18 00:06:54 red systemd[1]: Detected architecture arm. Sep 18 00:06:54 red systemd[1]: Set hostname to . Sep 18 00:06:54 red systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 18 00:06:54 red systemd[1]: Reached target System Time Synchronized. Sep 18 00:06:54 red systemd[1]: Started Forward Password Requests to Wall Directory Watch. Sep 18 00:06:54 red systemd[1]: Listening on fsck to fsckd communication Socket. Sep 18 00:06:54 red systemd[1]: Listening on udev Control Socket. Sep 18 00:06:54 red systemd[1]: Listening on udev Kernel Socket. Sep 18 00:06:54 red systemd[1]: Created slice system-systemd\x2dfsck.slice. Sep 18 00:06:54 red kernel: i2c_dev: i2c /dev entries driver Sep 18 00:06:54 red systemd-journald[321]: Journal started Sep 18 00:06:54 red systemd-journald[321]: Runtime journal (/run/log/journal/d9a3e155a633914cdbe1662066e99934) is 3.7M, max 30.0M, 26.2M free. Sep 18 00:06:54 red systemd-modules-load[310]: Inserted module 'i2c_dev' Sep 18 00:06:54 red fake-hwclock[311]: Tue Sep 17 15:06:54 UTC 2024 Sep 18 00:06:54 red systemd[1]: Started Load/Save Random Seed. Sep 18 00:06:54 red systemd[1]: Started udev Coldplug all Devices. Sep 18 00:06:54 red systemd[1]: Starting Helper to synchronize boot up for ifupdown... Sep 18 00:06:54 red systemd[1]: Started Create System Users. Sep 18 00:06:54 red systemd[1]: Starting Create Static Device Nodes in /dev... Sep 18 00:06:54 red systemd[1]: Started Helper to synchronize boot up for ifupdown. Sep 18 00:06:54 red systemd[1]: Started Create Static Device Nodes in /dev. Sep 18 00:06:54 red systemd[1]: Starting udev Kernel Device Manager... Sep 18 00:06:54 red systemd[1]: Reached target Local File Systems (Pre). Sep 18 00:06:54 red systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Sep 18 00:06:54 red systemd[1]: Mounting /var/log... Sep 18 00:06:54 red systemd[1]: Mounting /var/spool/cups... Sep 18 00:06:54 red systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Sep 18 00:06:54 red systemd[1]: Mounting /tmp... Sep 18 00:06:54 red systemd[1]: Mounted /var/log. Sep 18 00:06:54 red systemd[1]: Starting Flush Journal to Persistent Storage... Sep 18 00:06:54 red systemd[1]: Mounted /var/spool/cups. Sep 18 00:06:54 red systemd[1]: Mounted /tmp. Sep 18 00:06:54 red systemd[1]: Mounting /var/spool/cups/tmp... Sep 18 00:06:54 red systemd-udevd[346]: Network interface NamePolicy= disabled on kernel command line, ignoring. Sep 18 00:06:54 red systemd[1]: Mounted /var/spool/cups/tmp. Sep 18 00:06:54 red systemd-journald[321]: Runtime journal (/run/log/journal/d9a3e155a633914cdbe1662066e99934) is 3.7M, max 30.0M, 26.2M free. Sep 18 00:06:54 red systemd[1]: Started Flush Journal to Persistent Storage. Sep 18 00:06:54 red systemd[1]: Started udev Kernel Device Manager. Sep 18 00:06:54 red systemd[1]: Starting Show Plymouth Boot Screen... Sep 18 00:06:54 red systemd[1]: plymouth-start.service: Succeeded. Sep 18 00:06:54 red systemd[1]: Started Show Plymouth Boot Screen. Sep 18 00:06:54 red systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Sep 18 00:06:54 red systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Sep 18 00:06:54 red systemd[1]: Reached target Paths. Sep 18 00:06:54 red systemd[1]: Reached target Local Encrypted Volumes. Sep 18 00:06:54 red kernel: mc: Linux media interface: v0.10 Sep 18 00:06:54 red systemd-udevd[361]: Using default interface naming scheme 'v240'. Sep 18 00:06:55 red systemd-udevd[361]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Sep 18 00:06:55 red kernel: videodev: Linux video capture interface: v2.00 Sep 18 00:06:55 red kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Sep 18 00:06:55 red kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Sep 18 00:06:55 red kernel: [vc_sm_connected_init]: start Sep 18 00:06:55 red kernel: [vc_sm_connected_init]: installed successfully Sep 18 00:06:55 red kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Sep 18 00:06:55 red kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Sep 18 00:06:55 red kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Sep 18 00:06:55 red kernel: rpivid feb10000.codec: Device registered as /dev/video19 Sep 18 00:06:55 red kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Sep 18 00:06:55 red kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Sep 18 00:06:55 red kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Sep 18 00:06:55 red kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Sep 18 00:06:55 red kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Sep 18 00:06:55 red kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Sep 18 00:06:55 red kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Sep 18 00:06:55 red kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Sep 18 00:06:55 red kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Sep 18 00:06:55 red kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Sep 18 00:06:55 red kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Sep 18 00:06:55 red kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Sep 18 00:06:55 red kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Sep 18 00:06:55 red kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Sep 18 00:06:55 red kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Sep 18 00:06:55 red kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Sep 18 00:06:55 red kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Sep 18 00:06:55 red kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Sep 18 00:06:55 red kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Sep 18 00:06:55 red kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Sep 18 00:06:55 red kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Sep 18 00:06:55 red kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Sep 18 00:06:55 red kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Sep 18 00:06:55 red kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Sep 18 00:06:55 red kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Sep 18 00:06:55 red kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Sep 18 00:06:55 red kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Sep 18 00:06:55 red kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Sep 18 00:06:55 red kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Sep 18 00:06:55 red kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Sep 18 00:06:55 red kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Sep 18 00:06:55 red kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Sep 18 00:06:55 red systemd[1]: Found device /dev/disk/by-uuid/9C48-E5A3. Sep 18 00:06:55 red kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Sep 18 00:06:55 red kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Sep 18 00:06:55 red kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Sep 18 00:06:55 red kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Sep 18 00:06:55 red systemd-udevd[369]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Sep 18 00:06:56 red kernel: usb 1-1.1: 1:3 : unsupported format bits 0x100000000 Sep 18 00:06:56 red kernel: usbcore: registered new interface driver snd-usb-audio Sep 18 00:06:56 red kernel: Bluetooth: Core ver 2.22 Sep 18 00:06:56 red kernel: NET: Registered PF_BLUETOOTH protocol family Sep 18 00:06:56 red kernel: Bluetooth: HCI device and connection manager initialized Sep 18 00:06:56 red kernel: Bluetooth: HCI socket layer initialized Sep 18 00:06:56 red kernel: Bluetooth: L2CAP socket layer initialized Sep 18 00:06:56 red kernel: Bluetooth: SCO socket layer initialized Sep 18 00:06:56 red kernel: Bluetooth: HCI UART driver ver 2.3 Sep 18 00:06:56 red kernel: Bluetooth: HCI UART protocol H4 registered Sep 18 00:06:56 red kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Sep 18 00:06:56 red kernel: Bluetooth: HCI UART protocol Broadcom registered Sep 18 00:06:56 red kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Sep 18 00:06:56 red kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Sep 18 00:06:56 red kernel: uart-pl011 fe201000.serial: no DMA platform data Sep 18 00:06:57 red systemd-udevd[353]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Sep 18 00:06:57 red systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Sep 18 00:06:57 red systemd-udevd[368]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Sep 18 00:06:57 red systemd[1]: Created slice system-bthelper.slice. Sep 18 00:06:57 red systemd[1]: Starting Show Plymouth Boot Screen... Sep 18 00:06:57 red systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Sep 18 00:06:57 red systemd[1]: Condition check resulted in Huge Pages File System being skipped. Sep 18 00:06:57 red systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Sep 18 00:06:57 red systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Sep 18 00:06:57 red systemd[1]: Starting File System Check on /dev/disk/by-uuid/9C48-E5A3... Sep 18 00:06:57 red systemd[1]: Starting Load/Save RF Kill Switch Status... Sep 18 00:06:57 red systemd[1]: plymouth-start.service: Succeeded. Sep 18 00:06:57 red systemd[1]: Started Show Plymouth Boot Screen. Sep 18 00:06:57 red systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Sep 18 00:06:57 red systemd[1]: Started File System Check Daemon to report status. Sep 18 00:06:57 red systemd[1]: Started Load/Save RF Kill Switch Status. Sep 18 00:06:57 red systemd-fsck[482]: fsck.fat 4.1 (2017-01-24) Sep 18 00:06:57 red systemd-fsck[482]: 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Sep 18 00:06:57 red systemd-fsck[482]: Automatically removing dirty bit. Sep 18 00:06:57 red systemd-fsck[482]: Performing changes. Sep 18 00:06:57 red systemd-fsck[482]: /dev/mmcblk0p1: 370 files, 134162/184582 clusters Sep 18 00:06:57 red systemd[1]: Started File System Check on /dev/disk/by-uuid/9C48-E5A3. Sep 18 00:06:57 red systemd[1]: Mounting /boot... Sep 18 00:06:57 red systemd[1]: Mounted /boot. Sep 18 00:06:57 red systemd[1]: Reached target Local File Systems. Sep 18 00:06:57 red systemd[1]: Starting Raise network interfaces... Sep 18 00:06:57 red systemd[1]: Starting Create Volatile Files and Directories... Sep 18 00:06:57 red systemd[1]: Starting Preprocess NFS configuration... Sep 18 00:06:57 red systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Sep 18 00:06:57 red systemd[1]: Started ifup for eth0. Sep 18 00:06:57 red systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Sep 18 00:06:57 red systemd[1]: nfs-config.service: Succeeded. Sep 18 00:06:57 red systemd[1]: Started Preprocess NFS configuration. Sep 18 00:06:57 red systemd[1]: plymouth-read-write.service: Succeeded. Sep 18 00:06:57 red systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Sep 18 00:06:57 red systemd[1]: Received SIGRTMIN+20 from PID 205 (plymouthd). Sep 18 00:06:57 red systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Sep 18 00:06:57 red systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Sep 18 00:06:57 red systemd[1]: Reached target NFS client services. Sep 18 00:06:58 red systemd[1]: Started Create Volatile Files and Directories. Sep 18 00:06:58 red systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Sep 18 00:06:58 red systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Sep 18 00:06:58 red systemd[1]: Starting RPC bind portmap service... Sep 18 00:06:58 red systemd[1]: Starting Update UTMP about System Boot/Shutdown... Sep 18 00:06:58 red systemd[1]: Started RPC bind portmap service. Sep 18 00:06:58 red systemd[1]: Reached target RPC Port Mapper. Sep 18 00:06:58 red systemd[1]: Reached target Remote File Systems (Pre). Sep 18 00:06:58 red systemd[1]: Reached target Remote File Systems. Sep 18 00:06:58 red kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Sep 18 00:06:58 red kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Sep 18 00:06:58 red kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Sep 18 00:06:58 red kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Sep 18 00:06:58 red systemd[1]: Started Update UTMP about System Boot/Shutdown. Sep 18 00:06:58 red systemd[1]: Reached target System Initialization. Sep 18 00:06:58 red systemd[1]: Started Daily Cleanup of Temporary Directories. Sep 18 00:06:58 red systemd[1]: Listening on D-Bus System Message Bus Socket. Sep 18 00:06:58 red sh[496]: command failed: No such device (-19) Sep 18 00:06:58 red ifup[491]: command failed: No such device (-19) Sep 18 00:06:58 red systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Sep 18 00:06:58 red systemd[1]: Listening on triggerhappy.socket. Sep 18 00:06:58 red systemd[1]: Reached target Sockets. Sep 18 00:06:58 red systemd[1]: Reached target Basic System. Sep 18 00:06:58 red systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Sep 18 00:06:58 red systemd[1]: Starting Login Service... Sep 18 00:06:58 red systemd[1]: Starting Wireless Services... Sep 18 00:06:58 red systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Sep 18 00:06:58 red systemd[1]: Started Volumio Log Rotation Service. Sep 18 00:06:58 red systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Sep 18 00:06:58 red systemd[1]: Started D-Bus System Message Bus. Sep 18 00:06:58 red kernel: Bluetooth: hci0: command 0xfc18 tx timeout Sep 18 00:06:58 red systemd[1]: Starting WPA supplicant... Sep 18 00:06:58 red systemd[1]: Started volumio-remote-updater.service. Sep 18 00:06:58 red systemd[1]: Starting Configure Bluetooth Modems connected by UART... Sep 18 00:06:58 red systemd[1]: Started UPnP Renderer front-end to MPD. Sep 18 00:06:58 red systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Sep 18 00:06:58 red systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Sep 18 00:06:58 red systemd[1]: Starting triggerhappy global hotkey daemon... Sep 18 00:06:58 red systemd-logind[532]: New seat seat0. Sep 18 00:06:58 red systemd[1]: Started Volumio Iptables Module. Sep 18 00:06:58 red avahi-daemon[546]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Sep 18 00:06:58 red avahi-daemon[546]: Successfully dropped root privileges. Sep 18 00:06:58 red avahi-daemon[546]: avahi-daemon 0.7 starting up. Sep 18 00:06:58 red systemd[1]: Started Manage Sound Card State (restore and store). Sep 18 00:06:58 red systemd[1]: Starting Save/Restore Sound Card State... Sep 18 00:06:58 red systemd[1]: Starting dhcpcd on all interfaces... Sep 18 00:06:58 red alsactl[573]: alsactl 1.1.8 daemon started Sep 18 00:06:58 red systemd[1]: apt-daily.timer: Not using persistent file timestamp Sun 2024-10-20 01:02:56 KST as it is in the future. Sep 18 00:06:58 red systemd[1]: Started Daily apt download activities. Sep 18 00:06:58 red systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Sun 2024-10-20 06:03:56 KST as it is in the future. Sep 18 00:06:58 red systemd[1]: Started Daily apt upgrade and clean activities. Sep 18 00:06:58 red systemd[1]: Reached target Timers. Sep 18 00:06:58 red alsactl[577]: No state is present for card b1 Sep 18 00:06:58 red alsactl[577]: /usr/share/alsa/init/bcm2835:14: control element not found Sep 18 00:06:58 red alsactl[577]: No state is present for card b1 Sep 18 00:06:58 red alsactl[577]: No state is present for card Headphones Sep 18 00:06:58 red alsactl[577]: /usr/share/alsa/init/bcm2835:20: control element not found Sep 18 00:06:58 red alsactl[577]: No state is present for card Headphones Sep 18 00:06:58 red alsactl[577]: No state is present for card RED Sep 18 00:06:58 red alsactl[577]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:87c0" "" "" Sep 18 00:06:58 red alsactl[577]: Hardware is initialized using a generic method Sep 18 00:06:58 red alsactl[577]: No state is present for card RED Sep 18 00:06:58 red ifup[491]: command failed: No such device (-19) Sep 18 00:06:58 red thd[567]: Unable to parse trigger line: Sep 18 00:06:58 red thd[567]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Sep 18 00:06:58 red thd[567]: Unable to parse trigger line: Sep 18 00:06:58 red thd[567]: Found socket passed from systemd Sep 18 00:06:58 red dhcpcd[578]: Not running dhcpcd because /etc/network/interfaces Sep 18 00:06:58 red dhcpcd[578]: defines some interfaces that will use a Sep 18 00:06:58 red dhcpcd[578]: DHCP client or static address Sep 18 00:06:58 red alsactl[573]: No state is present for card b1 Sep 18 00:06:58 red alsactl[573]: /usr/share/alsa/init/bcm2835:14: control element not found Sep 18 00:06:58 red alsactl[573]: No state is present for card b1 Sep 18 00:06:58 red alsactl[573]: No state is present for card Headphones Sep 18 00:06:58 red alsactl[573]: /usr/share/alsa/init/bcm2835:20: control element not found Sep 18 00:06:58 red alsactl[573]: No state is present for card Headphones Sep 18 00:06:58 red alsactl[573]: No state is present for card RED Sep 18 00:06:58 red alsactl[573]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:87c0" "" "" Sep 18 00:06:58 red alsactl[573]: Hardware is initialized using a generic method Sep 18 00:06:58 red alsactl[573]: No state is present for card RED Sep 18 00:06:58 red kernel: 8021q: 802.1Q VLAN Support v1.8 Sep 18 00:06:58 red btuart[563]: Can't open serial port: No such file or directory Sep 18 00:06:58 red btuart[563]: Can't initialize device: No such file or directory Sep 18 00:06:59 red volumio-remote-updater[562]: Error: No active session Sep 18 00:06:59 red volumio-remote-updater[562]: [2024-09-18 00:06:59] [info] asio async_connect error: system:111 (Connection refused) Sep 18 00:06:59 red volumio-remote-updater[562]: [2024-09-18 00:06:59] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 18 00:06:59 red volumio-remote-updater[562]: [2024-09-18 00:06:59] [error] handle_connect error: Underlying Transport Error Sep 18 00:06:59 red systemd[1]: Started Login Service. Sep 18 00:06:59 red systemd[1]: Started triggerhappy global hotkey daemon. Sep 18 00:06:59 red avahi-daemon[546]: Successfully called chroot(). Sep 18 00:06:59 red systemd[1]: hciuart.service: Control process exited, code=exited, status=1/FAILURE Sep 18 00:06:59 red avahi-daemon[546]: Successfully dropped remaining capabilities. Sep 18 00:06:59 red systemd[1]: hciuart.service: Failed with result 'exit-code'. Sep 18 00:06:59 red wpa_supplicant[557]: Successfully initialized wpa_supplicant Sep 18 00:06:59 red systemd[1]: Failed to start Configure Bluetooth Modems connected by UART. Sep 18 00:06:59 red systemd[1]: Dependency failed for Raspberry Pi bluetooth helper. Sep 18 00:06:59 red systemd[1]: bthelper@hci0.service: Job bthelper@hci0.service/start failed with result 'dependency'. Sep 18 00:06:59 red systemd[1]: Started Save/Restore Sound Card State. Sep 18 00:06:59 red systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Sep 18 00:06:59 red systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Sep 18 00:06:59 red systemd[1]: Failed to start dhcpcd on all interfaces. Sep 18 00:06:59 red avahi-daemon[546]: Loading service file /services/volumio.service. Sep 18 00:06:59 red volumio[565]: Could not open config: /tmp/upmpdcli.conf Sep 18 00:06:59 red avahi-daemon[546]: Network interface enumeration completed. Sep 18 00:06:59 red avahi-daemon[546]: Server startup complete. Host name is red.local. Local service cookie is 1732862598. Sep 18 00:06:59 red avahi-daemon[546]: Service "RED" (/services/volumio.service) successfully established. Sep 18 00:06:59 red systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Sep 18 00:06:59 red systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Sep 18 00:06:59 red systemd[1]: Started WPA supplicant. Sep 18 00:06:59 red systemd[1]: Started Avahi mDNS/DNS-SD Stack. Sep 18 00:06:59 red systemd[1]: Reached target Sound Card. Sep 18 00:06:59 red systemd[1]: Starting Bluetooth service... Sep 18 00:06:59 red sh[496]: eth0: waiting for carrier Sep 18 00:06:59 red kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Sep 18 00:06:59 red kernel: bcmgenet fd580000.ethernet eth0: Link is Down Sep 18 00:06:59 red dhcpcd[543]: eth0: waiting for carrier Sep 18 00:07:00 red systemd[1]: Started Raise network interfaces. Sep 18 00:07:00 red systemd[1]: Reached target Network. Sep 18 00:07:00 red bluetoothd[632]: Bluetooth daemon 5.50 Sep 18 00:07:00 red bluetoothd[632]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Sep 18 00:07:00 red systemd[1]: Starting Network Time Service... Sep 18 00:07:00 red systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Sep 18 00:07:00 red systemd[1]: Starting Permit User Sessions... Sep 18 00:07:00 red bluetoothd[632]: Starting SDP server Sep 18 00:07:00 red systemd[1]: Reached target Network is Online. Sep 18 00:07:00 red bluetoothd[632]: Excluding (cli) sap Sep 18 00:07:00 red systemd[1]: Starting /etc/rc.local Compatibility... Sep 18 00:07:00 red systemd[1]: Starting LSB: Brings up/down network automatically... Sep 18 00:07:00 red loadcpufreq[566]: Loading cpufreq kernel modules...done (none). Sep 18 00:07:00 red systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 18 00:07:00 red systemd[1]: Starting Samba NMB Daemon... Sep 18 00:07:00 red systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Sep 18 00:07:00 red kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Sep 18 00:07:00 red kernel: Bluetooth: BNEP filters: protocol multicast Sep 18 00:07:00 red kernel: Bluetooth: BNEP socket layer initialized Sep 18 00:07:00 red bluetoothd[632]: Bluetooth management interface 1.22 initialized Sep 18 00:07:00 red dbus-daemon[551]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=632 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Sep 18 00:07:00 red systemd[1]: Started /etc/rc.local Compatibility. Sep 18 00:07:00 red systemd[1]: Started Bluetooth service. Sep 18 00:07:00 red systemd[1]: Started Permit User Sessions. Sep 18 00:07:00 red ntpd[672]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 18 00:07:00 red ntpd[672]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Sep 18 00:07:00 red ntpd[706]: proto: precision = 2.093 usec (-19) Sep 18 00:07:00 red ntpd[706]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 18 00:07:00 red ntpd[706]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 18 00:07:00 red ntpd[706]: Listen and drop on 0 v6wildcard [::]:123 Sep 18 00:07:00 red ntpd[706]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 18 00:07:00 red ntpd[706]: Listen normally on 2 lo 127.0.0.1:123 Sep 18 00:07:00 red ntpd[706]: Listening on routing socket on fd #19 for interface updates Sep 18 00:07:00 red ntpd[706]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 18 00:07:00 red ntpd[706]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 18 00:07:00 red systemd[1]: Started Network Time Service. Sep 18 00:07:00 red systemd[1]: iptables.service: Succeeded. Sep 18 00:07:00 red systemd[1]: Starting Hostname Service... Sep 18 00:07:00 red systemd[1]: Reached target Bluetooth. Sep 18 00:07:01 red systemd[1]: Starting Hold until boot process finishes up... Sep 18 00:07:01 red systemd[1]: Starting Terminate Plymouth Boot Screen... Sep 18 00:07:01 red systemd[1]: Starting LSB: set CPUFreq kernel parameters... Sep 18 00:07:01 red systemd[1]: Received SIGRTMIN+21 from PID 205 (plymouthd). Sep 18 00:07:01 red ifplugd(eth0)[725]: ifplugd 0.28 initializing. Sep 18 00:07:01 red ifplugd(eth0)[725]: Using interface eth0/D8:3A:DD:6D:97:EC with driver (version: 6.1.69-v7l+) Sep 18 00:07:01 red ifplugd(eth0)[725]: Using detection mode: SIOCETHTOOL Sep 18 00:07:01 red ifplugd(eth0)[725]: Initialization complete, link beat not detected. Sep 18 00:07:01 red systemd[1]: plymouth-quit-wait.service: Succeeded. Sep 18 00:07:01 red systemd[1]: Started Hold until boot process finishes up. Sep 18 00:07:01 red systemd[1]: plymouth-quit.service: Succeeded. Sep 18 00:07:01 red systemd[1]: Started Terminate Plymouth Boot Screen. Sep 18 00:07:01 red systemd[1]: Received SIGRTMIN+21 from PID 205 (n/a). Sep 18 00:07:01 red ifplugd[686]: Network Interface Plugging Daemon...start eth0...done. Sep 18 00:07:01 red systemd[1]: Started Getty on tty1. Sep 18 00:07:01 red systemd[1]: Reached target Login Prompts. Sep 18 00:07:01 red systemd[1]: Started LSB: Brings up/down network automatically. Sep 18 00:07:01 red dbus-daemon[551]: [system] Successfully activated service 'org.freedesktop.hostname1' Sep 18 00:07:01 red systemd[1]: Started Hostname Service. Sep 18 00:07:01 red cpufrequtils[715]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Sep 18 00:07:01 red systemd[1]: Started LSB: set CPUFreq kernel parameters. Sep 18 00:07:01 red nmbd[702]: [2024/09/18 00:07:01.517685, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Sep 18 00:07:01 red nmbd[702]: started asyncdns process 741 Sep 18 00:07:01 red nmbd[702]: [2024/09/18 00:07:01.519583, 0] ../lib/util/become_daemon.c:149(daemon_status) Sep 18 00:07:01 red nmbd[702]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Sep 18 00:07:01 red nmbd[702]: [2024/09/18 00:07:01.519711, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Sep 18 00:07:01 red nmbd[702]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Sep 18 00:07:01 red haveged[515]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Sep 18 00:07:01 red haveged[515]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Sep 18 00:07:01 red haveged[515]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00228 Sep 18 00:07:01 red haveged[515]: haveged: fills: 0, generated: 0 Sep 18 00:07:01 red ntpd[706]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Sep 18 00:07:01 red wireless.js[545]: WIRELESS: No wireless interface, exiting Sep 18 00:07:01 red iw[746]: command failed: No such device (-19) Sep 18 00:07:01 red iwconfig[749]: Error for wireless request "Set Power Management" (8B2C) : Sep 18 00:07:01 red iwconfig[749]: SET failed on device wlan0 ; No such device. Sep 18 00:07:01 red systemd[1]: wireless.service: Succeeded. Sep 18 00:07:01 red systemd[1]: Started Wireless Services. Sep 18 00:07:01 red systemd[1]: Started Volumio Backend Module. Sep 18 00:07:01 red systemd[1]: Started Volumio Cpu Tweaker. Sep 18 00:07:01 red volumio-cpu-tweak[753]: Setting RT Priority for mpd Sep 18 00:07:01 red volumio-cpu-tweak[753]: pid 35's current scheduling policy: SCHED_OTHER Sep 18 00:07:01 red volumio-cpu-tweak[753]: pid 35's current scheduling priority: 0 Sep 18 00:07:01 red volumio-cpu-tweak[753]: Setting MPD Affinity Sep 18 00:07:01 red volumio-cpu-tweak[753]: pid 3's current affinity mask: f Sep 18 00:07:01 red volumio-cpu-tweak[753]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Sep 18 00:07:01 red volumio-cpu-tweak[753]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Sep 18 00:07:01 red systemd[1]: volumio_cpu_tweak.service: Succeeded. Sep 18 00:07:02 red systemd[1]: systemd-rfkill.service: Succeeded. Sep 18 00:07:02 red ntpd[706]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Sep 18 00:07:03 red volumio[752]: info: ------------------------------------------- Sep 18 00:07:03 red volumio[752]: info: ----- Volumio3 ---- Sep 18 00:07:03 red volumio[752]: info: ------------------------------------------- Sep 18 00:07:03 red volumio[752]: info: ----- System startup ---- Sep 18 00:07:03 red volumio[752]: info: ------------------------------------------- Sep 18 00:07:03 red ntpd[706]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 18 00:07:03 red sh[496]: eth0: carrier acquired Sep 18 00:07:03 red dhcpcd[543]: eth0: carrier acquired Sep 18 00:07:03 red sh[496]: DUID 00:01:00:01:2e:7c:57:98:d8:3a:dd:6d:97:ec Sep 18 00:07:03 red sh[496]: eth0: IAID dd:6d:97:ec Sep 18 00:07:03 red dhcpcd[543]: DUID 00:01:00:01:2e:7c:57:98:d8:3a:dd:6d:97:ec Sep 18 00:07:03 red dhcpcd[543]: eth0: IAID dd:6d:97:ec Sep 18 00:07:03 red sh[496]: eth0: adding address fe80::a4b2:e12a:35f5:4050 Sep 18 00:07:03 red sh[496]: ipv6_addaddr1: Permission denied Sep 18 00:07:03 red dhcpcd[543]: eth0: adding address fe80::a4b2:e12a:35f5:4050 Sep 18 00:07:03 red dhcpcd[543]: ipv6_addaddr1: Permission denied Sep 18 00:07:03 red kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Sep 18 00:07:04 red sh[496]: eth0: rebinding lease of 73.4.19.20 Sep 18 00:07:04 red dhcpcd[543]: eth0: rebinding lease of 73.4.19.20 Sep 18 00:07:04 red sh[496]: eth0: probing address 73.4.19.20/24 Sep 18 00:07:04 red dhcpcd[543]: eth0: probing address 73.4.19.20/24 Sep 18 00:07:04 red ifplugd(eth0)[725]: Link beat detected. Sep 18 00:07:04 red volumio[752]: info: MYVOLUMIO Environment detected Sep 18 00:07:04 red ifplugd(eth0)[725]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Sep 18 00:07:04 red ifplugd(eth0)[725]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Sep 18 00:07:04 red volumio[752]: info: Plugin folders cleanup Sep 18 00:07:04 red volumio[752]: info: Scanning into folder /volumio/app/plugins/ Sep 18 00:07:04 red volumio[752]: info: Scanning category audio_interface Sep 18 00:07:04 red volumio[752]: info: Scanning category miscellanea Sep 18 00:07:04 red volumio[752]: info: Scanning category music_service Sep 18 00:07:04 red volumio[752]: info: Scanning category plugins.json Sep 18 00:07:04 red volumio[752]: info: Scanning category system_controller Sep 18 00:07:04 red volumio[752]: info: Scanning category user_interface Sep 18 00:07:04 red volumio[752]: info: Scanning into folder /data/plugins/ Sep 18 00:07:04 red volumio[752]: info: Scanning category music_service Sep 18 00:07:04 red volumio[752]: info: Plugin folders cleanup completed Sep 18 00:07:04 red volumio[752]: info: ------------------------------------------- Sep 18 00:07:04 red volumio[752]: info: ----- Core plugins startup ---- Sep 18 00:07:04 red volumio[752]: info: ------------------------------------------- Sep 18 00:07:04 red volumio[752]: info: Loading plugins from folder /volumio/app/plugins/ Sep 18 00:07:04 red volumio[752]: info: Adding plugin upnp to MyMusic Plugins Sep 18 00:07:04 red volumio[752]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 18 00:07:04 red volumio[752]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 18 00:07:04 red volumio[752]: info: Loading plugins from folder /data/plugins/ Sep 18 00:07:04 red volumio[752]: info: Loading plugin "system"... Sep 18 00:07:04 red volumio[752]: info: Loading plugin "appearance"... Sep 18 00:07:04 red volumio-remote-updater[562]: [2024-09-18 00:07:04] [connect] Successful connection Sep 18 00:07:04 red ntpd[706]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Sep 18 00:07:04 red sh[496]: eth0: soliciting an IPv6 router Sep 18 00:07:04 red dhcpcd[543]: eth0: soliciting an IPv6 router Sep 18 00:07:05 red volumio[752]: info: Loading plugin "network"... Sep 18 00:07:05 red volumio[752]: info: Refreshing Cached IP Addresses Sep 18 00:07:05 red volumio[752]: info: Loading plugin "services"... Sep 18 00:07:05 red volumio[752]: info: Loading plugin "alsa_controller"... Sep 18 00:07:05 red sudo[789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 18 00:07:05 red sudo[793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 18 00:07:05 red sudo[791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 18 00:07:05 red sudo[789]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:07:05 red sudo[793]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:07:05 red sudo[791]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:07:05 red sudo[789]: pam_unix(sudo:session): session closed for user root Sep 18 00:07:05 red sudo[793]: pam_unix(sudo:session): session closed for user root Sep 18 00:07:05 red sudo[791]: pam_unix(sudo:session): session closed for user root Sep 18 00:07:05 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 18 00:07:05 red volumio[752]: info: Loading plugin "wizard"... Sep 18 00:07:05 red volumio[752]: info: Loading plugin "networkfs"... Sep 18 00:07:05 red volumio[752]: info: Starting Udev Watcher for removable devices Sep 18 00:07:05 red sudo[804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=soulofsky,password=1104!!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //73.4.19.1/Synology_NAS /mnt/NAS/Synology_NAS Sep 18 00:07:05 red sudo[804]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:07:05 red volumio[752]: info: Ignoring mount for partition: boot Sep 18 00:07:05 red volumio[752]: info: Ignoring mount for partition: volumio Sep 18 00:07:05 red volumio[752]: info: Ignoring mount for partition: volumio_data Sep 18 00:07:05 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 18 00:07:05 red volumio[752]: info: Loading plugin "volumio_command_line_client"... Sep 18 00:07:05 red volumio[752]: info: Loading plugin "upnp"... Sep 18 00:07:05 red volumio[752]: info: [1726585625617] Starting Upmpd Daemon Sep 18 00:07:05 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 18 00:07:05 red volumio[752]: info: Loading plugin "my_music"... Sep 18 00:07:05 red volumio[752]: info: Loading plugin "mpd"... Sep 18 00:07:05 red sudo[804]: pam_unix(sudo:session): session closed for user root Sep 18 00:07:05 red kernel: Key type cifs.spnego registered Sep 18 00:07:05 red kernel: Key type cifs.idmap registered Sep 18 00:07:05 red kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Sep 18 00:07:05 red kernel: CIFS: Attempting to mount \\73.4.19.1\Synology_NAS Sep 18 00:07:05 red kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 18 00:07:05 red kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Sep 18 00:07:05 red volumio[752]: info: Loading plugin "upnp_browser"... Sep 18 00:07:06 red volumio[752]: info: Loading plugin "alarm-clock"... Sep 18 00:07:06 red volumio[752]: info: Loading plugin "airplay_emulation"... Sep 18 00:07:06 red volumio[752]: info: Starting Shairport Sync Sep 18 00:07:06 red volumio[752]: info: Loading plugin "last_100"... Sep 18 00:07:06 red volumio[752]: info: Loading plugin "webradio"... Sep 18 00:07:06 red volumio[752]: info: Loading plugin "i2s_dacs"... Sep 18 00:07:06 red volumio[752]: info: I2S DAC not set, start Auto-detection Sep 18 00:07:06 red volumio[752]: info: Loading plugin "volumiodiscovery"... Sep 18 00:07:06 red volumio[752]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 18 00:07:06 red volumio[752]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 18 00:07:06 red volumio[752]: *** WARNING *** For more information see Sep 18 00:07:06 red node[752]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 18 00:07:06 red volumio[752]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 18 00:07:06 red volumio[752]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 18 00:07:06 red volumio[752]: *** WARNING *** For more information see Sep 18 00:07:06 red node[752]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 18 00:07:06 red node[752]: *** WARNING *** For more information see Sep 18 00:07:06 red node[752]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 18 00:07:06 red node[752]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 18 00:07:06 red node[752]: *** WARNING *** For more information see Sep 18 00:07:06 red volumio[752]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 18 00:07:06 red volumio[752]: info: Discovery: Started advertising with name: RED Sep 18 00:07:06 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 18 00:07:06 red volumio[752]: info: Loading plugin "spop"... Sep 18 00:07:06 red kernel: Bluetooth: hci0: BCM: failed to write update baudrate (-110) Sep 18 00:07:06 red kernel: Bluetooth: hci0: Failed to set baudrate Sep 18 00:07:07 red volumio[752]: info: Loading plugin "outputs"... Sep 18 00:07:07 red volumio[752]: info: Loading plugin "albumart"... Sep 18 00:07:07 red volumio[752]: info: Plugin example_plugin is not enabled Sep 18 00:07:07 red volumio[752]: info: Loading plugin "inputs"... Sep 18 00:07:07 red volumio[752]: info: Loading plugin "updater_comm"... Sep 18 00:07:07 red volumio[752]: info: Plugin mpdemulation is not enabled Sep 18 00:07:07 red volumio[752]: info: Loading plugin "rest_api"... Sep 18 00:07:07 red volumio[752]: info: Loading plugin "websocket"... Sep 18 00:07:07 red volumio[752]: info: Starting Socket.io Server version 2.3.0 Sep 18 00:07:07 red volumio[752]: info: Loading plugin "RoonBridge"... Sep 18 00:07:07 red volumio[752]: Forking 3 albumart workers Sep 18 00:07:08 red volumio[752]: info: Applying required configuration parameters for plugin RoonBridge Sep 18 00:07:08 red volumio[752]: info: Loading i18n strings for locale ko Sep 18 00:07:08 red volumio[752]: Updating browse sources language Sep 18 00:07:08 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 18 00:07:08 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 18 00:07:08 red volumio[752]: info: CoreCommandRouter::initPlayerControls Sep 18 00:07:08 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:07:08 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:07:08 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:07:08 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:07:08 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:07:08 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:07:08 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:07:08 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:07:08 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 18 00:07:08 red volumio[752]: Express server listening on port 3000 Sep 18 00:07:08 red volumio[752]: [Metrics] WebUI: 5s 520.11ms Sep 18 00:07:08 red volumio[752]: info: CoreStateMachine::resetVolumioState Sep 18 00:07:08 red volumio[752]: info: CoreStateMachine::getcurrentVolume Sep 18 00:07:08 red volumio[752]: info: CoreCommandRouter::volumioRetrievevolume Sep 18 00:07:08 red volumio[752]: Starting albumart workers Sep 18 00:07:08 red volumio[752]: info: CoreStateMachine::pushState Sep 18 00:07:08 red volumio[752]: info: CorePlayQueue::getTrack 0 Sep 18 00:07:08 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 18 00:07:08 red volumio[752]: info: CoreCommandRouter::volumioPushState Sep 18 00:07:08 red volumio[752]: Starting albumart workers Sep 18 00:07:08 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 00:07:08 red volumio[752]: info: Cannot mount NAS Synology_NAS at system boot, trial number 1 ,retrying in 5 seconds Sep 18 00:07:08 red volumio[752]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 18 00:07:08 red volumio[752]: wlan0 Interface doesn't support scanning. Sep 18 00:07:08 red volumio[752]: info: Cannot use regular scanning, forcing with ap-force Sep 18 00:07:08 red volumio[752]: Starting albumart workers Sep 18 00:07:08 red sudo[852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 18 00:07:08 red sudo[852]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:07:08 red sudo[852]: pam_unix(sudo:session): session closed for user root Sep 18 00:07:08 red volumio[752]: command failed: No such device (-19) Sep 18 00:07:08 red volumio[752]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 18 00:07:08 red volumio[752]: command failed: No such device (-19) Sep 18 00:07:08 red volumio-remote-updater[562]: [2024-09-18 00:07:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1726585624 101 Sep 18 00:07:08 red volumio[752]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Sep 18 00:07:08 red volumio[752]: info: Setting Device type: Raspberry PI Sep 18 00:07:08 red volumio[752]: info: CoreStateMachine::setRepeat null single undefined Sep 18 00:07:08 red volumio[752]: info: CoreStateMachine::pushState Sep 18 00:07:08 red volumio[752]: info: CorePlayQueue::getTrack 0 Sep 18 00:07:08 red volumio[752]: info: CoreCommandRouter::volumioPushState Sep 18 00:07:08 red volumio[752]: info: CoreStateMachine::setRandom null Sep 18 00:07:08 red volumio[752]: info: CoreStateMachine::pushState Sep 18 00:07:08 red volumio[752]: info: CorePlayQueue::getTrack 0 Sep 18 00:07:08 red volumio[752]: info: CoreCommandRouter::volumioPushState Sep 18 00:07:08 red volumio[752]: info: Completed loading Core Plugins Sep 18 00:07:08 red volumio[752]: info: Preparing to generate the ALSA configuration file Sep 18 00:07:08 red volumio[752]: info: Asound.conf file unchanged, so no further update is needed Sep 18 00:07:08 red volumio[752]: info: Output device has changed, restarting MPD Sep 18 00:07:08 red volumio[752]: info: Output device has changed, restarting Shairport Sync Sep 18 00:07:08 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:07:08 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 00:07:08 red sudo[862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 18 00:07:08 red kernel: Bluetooth: hci0: command 0x0c03 tx timeout Sep 18 00:07:08 red sudo[862]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:07:08 red sudo[863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 18 00:07:08 red sudo[863]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:07:08 red sudo[862]: pam_unix(sudo:session): session closed for user root Sep 18 00:07:08 red volumio[752]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 18 00:07:08 red volumio[752]: info: ___________ START PLUGINS ___________ Sep 18 00:07:08 red volumio[752]: info: ControllerMpd::onStart: Initializing MPD Sep 18 00:07:08 red volumio[752]: info: Creating MPD Configuration file Sep 18 00:07:08 red systemd[1]: Listening on mpd.socket. Sep 18 00:07:08 red systemd[1]: Starting Music Player Daemon... Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 18 00:07:09 red volumio[752]: info: [1726585629003] CoreMusicLibrary::Adding element 미디어 서버 Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 18 00:07:09 red sudo[879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 18 00:07:09 red sudo[879]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 00:07:09 red sudo[879]: pam_unix(sudo:session): session closed for user root Sep 18 00:07:09 red sudo[891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 18 00:07:09 red sudo[891]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:07:09 red sudo[884]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 18 00:07:09 red sudo[884]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:07:09 red sudo[884]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Sep 18 00:07:09 red sudo[884]: pam_unix(sudo:session): session closed for user root Sep 18 00:07:09 red systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 18 00:07:09 red systemd[1]: mpd.service: Succeeded. Sep 18 00:07:09 red systemd[1]: Stopped Music Player Daemon. Sep 18 00:07:09 red systemd[1]: Starting Music Player Daemon... Sep 18 00:07:09 red volumio[752]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 00:07:09 red volumio[752]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 18 00:07:09 red volumio[752]: info: [1726585629137] CoreMusicLibrary::Adding element Last_100 Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 18 00:07:09 red volumio[752]: info: [1726585629140] CoreMusicLibrary::Adding element Webradio Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 18 00:07:09 red volumio[752]: info: Initializing BBC Radios Sep 18 00:07:09 red sudo[898]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 18 00:07:09 red sudo[898]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:07:09 red sudo[898]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Sep 18 00:07:09 red sudo[898]: pam_unix(sudo:session): session closed for user root Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 00:07:09 red volumio[752]: info: Creating Spotify config file Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:07:09 red volumio[752]: info: Volumio Calling Home Sep 18 00:07:09 red sudo[923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Sep 18 00:07:09 red sudo[923]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:07:09 red volumio[752]: Unhandled rejection Error: No sockets available, cannot start. Sep 18 00:07:09 red volumio[752]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Sep 18 00:07:09 red volumio[752]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Sep 18 00:07:09 red volumio[752]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Sep 18 00:07:09 red volumio[752]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Sep 18 00:07:09 red volumio[752]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Sep 18 00:07:09 red volumio[752]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Sep 18 00:07:09 red volumio[752]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Sep 18 00:07:09 red volumio[752]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Sep 18 00:07:09 red volumio[752]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Sep 18 00:07:09 red volumio[752]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Sep 18 00:07:09 red volumio[752]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Sep 18 00:07:09 red volumio[752]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Sep 18 00:07:09 red volumio[752]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Sep 18 00:07:09 red volumio[752]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Sep 18 00:07:09 red volumio[752]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Sep 18 00:07:09 red volumio[752]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 18 00:07:09 red volumio[752]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.prod.vlmapi.io Sep 18 00:07:09 red volumio[752]: info: MPD Permissions set Sep 18 00:07:09 red volumio[752]: info: MPD Permissions set Sep 18 00:07:09 red volumio[752]: info: Spotify config file written Sep 18 00:07:09 red volumio[752]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 18 00:07:09 red sudo[930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 18 00:07:09 red sudo[930]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:07:09 red systemd[1]: Started RoonBridge. Sep 18 00:07:09 red sudo[923]: pam_unix(sudo:session): session closed for user root Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:07:09 red systemd[1]: Started go-librespot Daemon. Sep 18 00:07:09 red sudo[930]: pam_unix(sudo:session): session closed for user root Sep 18 00:07:09 red go-librespot[943]: Librespot-go daemon starting... Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 18 00:07:09 red volumio[752]: info: No need to fix Spotify hosts Sep 18 00:07:09 red volumio[752]: info: Starting Shairport Sync Sep 18 00:07:09 red volumio[752]: info: Starting Shairport Sync Sep 18 00:07:09 red volumio[752]: info: Starting Shairport Sync Sep 18 00:07:09 red sh[496]: eth0: leased 73.4.19.20 for 86400 seconds Sep 18 00:07:09 red sh[496]: eth0: adding route to 73.4.19.0/24 Sep 18 00:07:09 red sh[496]: eth0: adding default route via 73.4.19.1 Sep 18 00:07:09 red dhcpcd[543]: eth0: leased 73.4.19.20 for 86400 seconds Sep 18 00:07:09 red dhcpcd[543]: eth0: adding route to 73.4.19.0/24 Sep 18 00:07:09 red dhcpcd[543]: eth0: adding default route via 73.4.19.1 Sep 18 00:07:09 red avahi-daemon[546]: Joining mDNS multicast group on interface eth0.IPv4 with address 73.4.19.20. Sep 18 00:07:09 red avahi-daemon[546]: New relevant interface eth0.IPv4 for mDNS. Sep 18 00:07:09 red avahi-daemon[546]: Registering new address record for 73.4.19.20 on eth0.IPv4. Sep 18 00:07:09 red sudo[955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 18 00:07:09 red sudo[950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 18 00:07:09 red sudo[955]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:07:09 red sudo[950]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:07:09 red volumio[752]: info: CoreCommandRouter::volumioGetState Sep 18 00:07:09 red volumio[752]: info: CorePlayQueue::getTrack 0 Sep 18 00:07:09 red sudo[952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 18 00:07:09 red sudo[952]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:07:09 red systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 18 00:07:09 red systemd[1]: shairport-sync.service: Succeeded. Sep 18 00:07:09 red systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 18 00:07:09 red sh[496]: forked to background, child pid 983 Sep 18 00:07:09 red dhcpcd[543]: forked to background, child pid 983 Sep 18 00:07:09 red systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 18 00:07:09 red sudo[950]: pam_unix(sudo:session): session closed for user root Sep 18 00:07:09 red sudo[952]: pam_unix(sudo:session): session closed for user root Sep 18 00:07:09 red sudo[955]: pam_unix(sudo:session): session closed for user root Sep 18 00:07:09 red volumio[752]: info: Shairport-Sync Started Sep 18 00:07:09 red volumio[752]: Error adding Membership: Error: addMembership EINVAL Sep 18 00:07:09 red volumio[752]: info: Shairport-Sync Started Sep 18 00:07:09 red volumio[752]: info: Shairport-Sync Started Sep 18 00:07:09 red ifplugd(eth0)[725]: client: ifup: interface eth0 already configured Sep 18 00:07:09 red sh[496]: eth0=eth0 Sep 18 00:07:10 red ifplugd(eth0)[725]: Program executed successfully. Sep 18 00:07:10 red go-librespot[943]: time="2024-09-18T00:07:10+09:00" level=info msg="generated new device id: e2d1ca6d9779a1e72ac874a3267b80ddafc8549c" Sep 18 00:07:10 red go-librespot[943]: time="2024-09-18T00:07:10+09:00" level=debug msg="stored credentials found for 31capcepzhxb5zrr2q33wpytccgu" Sep 18 00:07:10 red go-librespot[943]: time="2024-09-18T00:07:10+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Sep 18 00:07:10 red go-librespot[943]: time="2024-09-18T00:07:10+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Sep 18 00:07:10 red go-librespot[943]: time="2024-09-18T00:07:10+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Sep 18 00:07:10 red go-librespot[943]: time="2024-09-18T00:07:10+09:00" level=debug msg="zeroconf server listening on port 36651" Sep 18 00:07:10 red volumio[752]: info: Discovery: adding 5558e274-db7e-4c66-bfaa-de4fcbc4ae63 Sep 18 00:07:10 red volumio[752]: info: Discovery: Found device RED Sep 18 00:07:10 red volumio[752]: info: CoreCommandRouter::volumioGetState Sep 18 00:07:10 red volumio[752]: info: CorePlayQueue::getTrack 0 Sep 18 00:07:10 red ntpd[706]: Listen normally on 3 eth0 73.4.19.20:123 Sep 18 00:07:10 red ntpd[706]: new interface(s) found: waking up resolver Sep 18 00:07:10 red RoonBridge[928]: 00:00:00.010 Warn: get lock file path: /tmp/.rnbgem0- Sep 18 00:07:11 red go-librespot[943]: time="2024-09-18T00:07:11+09:00" level=debug msg="obtained new client token: AAA5j5TICUOaD1csnR31r5vdEre5xwE8EpRQxotF3EFbIfaGHP+k57a7s3HMZlPT04sGn0bxqw0JV+tHEq1wi/c5lCfhfj3IQp+mYBOZevGEDaGQphGRUOOekicgiUwBp1Wdem2vxuCOtN5Wh+2kVnaVRfKuit/QsKE2vLW5nJGMm7cFVrPCvx1wW0oAg8+K7/HfZLDDzTd5J3MWLg8H/d94HwZiqU4oVRoL2x6u16ehOMe0m6G0DjrruYLyzR8j" Sep 18 00:07:11 red RoonBridge[928]: 00:00:00.388 Trace: [childprocess] using unix child process Sep 18 00:07:11 red RoonBridge[928]: 00:00:00.451 Debug: PathForResource, filename: ../.update Sep 18 00:07:11 red RoonBridge[928]: 00:00:00.532 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Sep 18 00:07:11 red RoonBridge[928]: 00:00:00.539 Debug: PathForResource, filename: RAATServer Sep 18 00:07:11 red RoonBridge[928]: 00:00:00.540 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Sep 18 00:07:11 red RoonBridge[928]: 00:00:00.541 Debug: PathForResource, filename: RoonBridgeHelper Sep 18 00:07:11 red RoonBridge[928]: 00:00:00.541 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Sep 18 00:07:11 red RoonBridge[928]: Initializing Sep 18 00:07:11 red RoonBridge[928]: 00:00:00.578 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Sep 18 00:07:11 red go-librespot[943]: time="2024-09-18T00:07:11+09:00" level=info msg="connected to ap-gae2.spotify.com:4070" Sep 18 00:07:11 red RoonBridge[928]: 00:00:00.609 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Sep 18 00:07:11 red go-librespot[943]: time="2024-09-18T00:07:11+09:00" level=debug msg="completed keyexchange" Sep 18 00:07:11 red RoonBridge[928]: Not Running (.o) Sep 18 00:07:11 red nmbd[702]: [2024/09/18 00:07:11.524658, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 18 00:07:11 red systemd[1]: Started Samba NMB Daemon. Sep 18 00:07:11 red nmbd[702]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Sep 18 00:07:11 red mpd[913]: Sep 18 00:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 18 00:07:11 red systemd[1]: Starting Samba Winbind Daemon... Sep 18 00:07:11 red systemd[1]: Started Music Player Daemon. Sep 18 00:07:11 red sudo[891]: pam_unix(sudo:session): session closed for user root Sep 18 00:07:11 red sudo[863]: pam_unix(sudo:session): session closed for user root Sep 18 00:07:11 red volumio[752]: info: Completed starting Core Plugins Sep 18 00:07:11 red volumio[752]: info: ------------------------------------------- Sep 18 00:07:11 red volumio[752]: info: ----- MyVolumio plugins startup ---- Sep 18 00:07:11 red volumio[752]: info: ------------------------------------------- Sep 18 00:07:11 red volumio[752]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 18 00:07:11 red RoonBridge[928]: Running Sep 18 00:07:11 red volumio[752]: error: MPD error: The expression evaluated to a falsy value: Sep 18 00:07:11 red volumio[752]: assert.ok(self.idling) Sep 18 00:07:11 red volumio[752]: error: The expression evaluated to a falsy value: Sep 18 00:07:11 red volumio[752]: assert.ok(self.idling) Sep 18 00:07:11 red volumio[752]: info: MPD running with PID913 Sep 18 00:07:11 red volumio[752]: ,establishing connection Sep 18 00:07:11 red volumio[752]: error: MPD error: The expression evaluated to a falsy value: Sep 18 00:07:11 red volumio[752]: assert.ok(self.idling) Sep 18 00:07:11 red volumio[752]: error: The expression evaluated to a falsy value: Sep 18 00:07:11 red volumio[752]: assert.ok(self.idling) Sep 18 00:07:11 red volumio[752]: error: updateQueue error: null Sep 18 00:07:11 red winbindd[1030]: [2024/09/18 00:07:11.829823, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Sep 18 00:07:11 red winbindd[1030]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Sep 18 00:07:11 red winbindd[1030]: [2024/09/18 00:07:11.850389, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 18 00:07:11 red systemd[1]: Started Samba Winbind Daemon. Sep 18 00:07:11 red winbindd[1030]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Sep 18 00:07:11 red go-librespot[943]: time="2024-09-18T00:07:11+09:00" level=debug msg="completed challenge" Sep 18 00:07:11 red systemd[1]: Starting Samba SMB Daemon... Sep 18 00:07:11 red go-librespot[943]: time="2024-09-18T00:07:11+09:00" level=debug msg="authenticated as 31capcepzhxb5zrr2q33wpytccgu" Sep 18 00:07:12 red go-librespot[943]: time="2024-09-18T00:07:12+09:00" level=debug msg="authenticated as 31capcepzhxb5zrr2q33wpytccgu" Sep 18 00:07:12 red RoonBridge[928]: 00:00:00.024 Warn: get lock file path: /tmp/.rnbhgem0- Sep 18 00:07:12 red go-librespot[943]: time="2024-09-18T00:07:12+09:00" level=debug msg="dealer connection opened" Sep 18 00:07:12 red go-librespot[943]: time="2024-09-18T00:07:12+09:00" level=debug msg="initializing zeroconf session, username: 31capcepzhxb5zrr2q33wpytccgu" Sep 18 00:07:12 red go-librespot[943]: time="2024-09-18T00:07:12+09:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 18 00:07:12 red go-librespot[943]: time="2024-09-18T00:07:12+09:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 18 00:07:12 red go-librespot[943]: time="2024-09-18T00:07:12+09:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 18 00:07:12 red go-librespot[943]: time="2024-09-18T00:07:12+09:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 18 00:07:12 red go-librespot[943]: time="2024-09-18T00:07:12+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 493" Sep 18 00:07:12 red go-librespot[943]: time="2024-09-18T00:07:12+09:00" level=debug msg="autoplay enabled: false" Sep 18 00:07:12 red go-librespot[943]: time="2024-09-18T00:07:12+09:00" level=debug msg="received connection id: OGE5MTM5OTUtZGJlNC00YTU2LWI3M2YtNmRiMDIzNTlhOWExK2RlYWxlcit0Y3A6Ly8wYWM5NDhjNC5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArQUVDNjMxNjYzRTQ3NUY5MTA3Nzc4NURCQTg0QUM5NjZEMEI0MURFRTY2RkJCNTA4NTcxMUIxMjhGRDE2MzUzRg==" Sep 18 00:07:12 red winbindd[1030]: [2024/09/18 00:07:12.303020, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Sep 18 00:07:12 red winbindd[1030]: res_names->count = 3, expected 4 Sep 18 00:07:12 red smbd[1049]: [2024/09/18 00:07:12.325267, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 18 00:07:12 red smbd[1049]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Sep 18 00:07:12 red systemd[1]: Started Samba SMB Daemon. Sep 18 00:07:12 red systemd[1]: Reached target Multi-User System. Sep 18 00:07:12 red systemd[1]: Reached target Graphical Interface. Sep 18 00:07:12 red systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 18 00:07:12 red systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 18 00:07:12 red systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 18 00:07:12 red systemd[1]: Startup finished in 15.418s (kernel) + 20.151s (userspace) = 35.570s. Sep 18 00:07:12 red go-librespot[943]: time="2024-09-18T00:07:12+09:00" level=debug msg="put connect state because NEW_DEVICE" Sep 18 00:07:12 red volumio[752]: info: go-librespot daemon successfully initialized Sep 18 00:07:13 red sudo[1074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=soulofsky,password=1104!!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //73.4.19.1/Synology_NAS /mnt/NAS/Synology_NAS Sep 18 00:07:13 red sudo[1074]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:07:13 red kernel: CIFS: Attempting to mount \\73.4.19.1\Synology_NAS Sep 18 00:07:13 red sudo[1074]: pam_unix(sudo:session): session closed for user root Sep 18 00:07:15 red volumio[752]: info: Initializing connection to go-librespot Websocket Sep 18 00:07:15 red go-librespot[943]: time="2024-09-18T00:07:15+09:00" level=debug msg="new websocket client" Sep 18 00:07:15 red volumio[752]: info: Connection to go-librespot Websocket established Sep 18 00:07:15 red sudo[1111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 18 00:07:15 red sudo[1111]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:07:15 red sudo[1111]: pam_unix(sudo:session): session closed for user root Sep 18 00:07:15 red sudo[1113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 18 00:07:15 red sudo[1113]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:07:15 red sudo[1116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 18 00:07:15 red sudo[1116]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 18 00:07:15 red sudo[1113]: pam_unix(sudo:session): session closed for user root Sep 18 00:07:15 red systemd[1]: Started UPnP Renderer front-end to MPD. Sep 18 00:07:15 red sudo[1116]: pam_unix(sudo:session): session closed for user root Sep 18 00:07:15 red volumio[752]: info: Upmpdcli Daemon Started Sep 18 00:07:15 red volumio[1121]: Generating RSA private key, 4096 bit long modulus (2 primes) Sep 18 00:07:16 red go-librespot[943]: time="2024-09-18T00:07:16+09:00" level=debug msg="handling transfer player command from 041ef1bf50fc44ccff3ec42c5aa3ab94a2b37589" Sep 18 00:07:16 red go-librespot[943]: time="2024-09-18T00:07:16+09:00" level=debug msg="resolved context of track" uri="spotify:album:3TNYwRCO3ICazSpoI9Z1GF" Sep 18 00:07:16 red go-librespot[943]: time="2024-09-18T00:07:16+09:00" level=trace msg="fetched new page 0 with 32 items (list: 32)" uri="spotify:album:3TNYwRCO3ICazSpoI9Z1GF" Sep 18 00:07:16 red go-librespot[943]: time="2024-09-18T00:07:16+09:00" level=debug msg="loading track (paused: false, position: -2764060112ms)" uri="spotify:track:6SQXqgRUnrGFnUdR3i9m31" Sep 18 00:07:16 red go-librespot[943]: time="2024-09-18T00:07:16+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 18 00:07:16 red go-librespot[943]: time="2024-09-18T00:07:16+09:00" level=trace msg="emitting websocket event: will_play" Sep 18 00:07:16 red volumio[752]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6SQXqgRUnrGFnUdR3i9m31","play_origin":"playlist"}} Sep 18 00:07:16 red go-librespot[943]: time="2024-09-18T00:07:16+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Sep 18 00:07:16 red go-librespot[943]: time="2024-09-18T00:07:16+09:00" level=debug msg="selected format OGG_VORBIS_320 (156a250fd16a0980f925a8b3b0b65787e579a48d)" uri="spotify:track:6SQXqgRUnrGFnUdR3i9m31" Sep 18 00:07:16 red go-librespot[943]: time="2024-09-18T00:07:16+09:00" level=debug msg="requested aes key for file 156a250fd16a0980f925a8b3b0b65787e579a48d, gid: 6SQXqgRUnrGFnUdR3i9m31" Sep 18 00:07:16 red go-librespot[943]: time="2024-09-18T00:07:16+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1390" Sep 18 00:07:16 red volumio[752]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 18 00:07:16 red go-librespot[943]: time="2024-09-18T00:07:16+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1147" Sep 18 00:07:17 red kernel: Bluetooth: hci0: BCM: Reset failed (-110) Sep 18 00:07:17 red go-librespot[943]: time="2024-09-18T00:07:17+09:00" level=debug msg="fetched first chunk of 9, total size is 4571760 bytes" uri="spotify:track:6SQXqgRUnrGFnUdR3i9m31" Sep 18 00:07:17 red go-librespot[943]: time="2024-09-18T00:07:17+09:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:6SQXqgRUnrGFnUdR3i9m31" Sep 18 00:07:17 red go-librespot[943]: time="2024-09-18T00:07:17+09:00" level=debug msg="fetched chunk 2/8, size: 524288" uri="spotify:track:6SQXqgRUnrGFnUdR3i9m31" Sep 18 00:07:17 red go-librespot[943]: time="2024-09-18T00:07:17+09:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 5011 us, period size = 221 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 99 frames" Sep 18 00:07:17 red go-librespot[943]: time="2024-09-18T00:07:17+09:00" level=debug msg="created new output device" Sep 18 00:07:17 red go-librespot[943]: time="2024-09-18T00:07:17+09:00" level=info msg="loaded track \"Sonata in D Minor, Op. 1 No. 1, HWV 359a: II. Allegro\" (paused: false, position: -2764060112ms, duration: 104923ms, prefetched: false)" uri="spotify:track:6SQXqgRUnrGFnUdR3i9m31" Sep 18 00:07:17 red go-librespot[943]: time="2024-09-18T00:07:17+09:00" level=debug msg="fetched chunk 3/8, size: 524288" uri="spotify:track:6SQXqgRUnrGFnUdR3i9m31" Sep 18 00:07:17 red go-librespot[943]: time="2024-09-18T00:07:17+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 18 00:07:17 red go-librespot[943]: time="2024-09-18T00:07:17+09:00" level=trace msg="scheduling prefetch in 75s" Sep 18 00:07:17 red go-librespot[943]: time="2024-09-18T00:07:17+09:00" level=trace msg="emitting websocket event: metadata" Sep 18 00:07:17 red go-librespot[943]: time="2024-09-18T00:07:17+09:00" level=trace msg="emitting websocket event: active" Sep 18 00:07:17 red go-librespot[943]: time="2024-09-18T00:07:17+09:00" level=debug msg="sending successful reply for dealer request" Sep 18 00:07:17 red volumio[752]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:6SQXqgRUnrGFnUdR3i9m31","name":"Sonata in D Minor, Op. 1 No. 1, HWV 359a: II. Allegro","artist_names":["George Frideric Handel","Bojan Čičić","Steven Devine"],"album_name":"Handel: Complete Violin Sonatas","album_cover_url":"https://i.scdn.co/image/ab67616d00001e029277b81751b9eb5426e9606b","position":-2764060112,"duration":104923,"release_date":"year:2024 month:2 day:23","track_number":10,"disc_number":1}} Sep 18 00:07:17 red go-librespot[943]: time="2024-09-18T00:07:17+09:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Sep 18 00:07:17 red go-librespot[943]: time="2024-09-18T00:07:17+09:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Sep 18 00:07:17 red volumio[752]: SPOTIFY: received: {"type":"active","data":null} Sep 18 00:07:17 red go-librespot[943]: time="2024-09-18T00:07:17+09:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Sep 18 00:07:17 red volumio[752]: info: Aligning Spotify Volume to Volumio Volume Sep 18 00:07:17 red volumio[752]: info: CoreCommandRouter::volumioGetState Sep 18 00:07:17 red volumio[752]: info: CorePlayQueue::getTrack 0 Sep 18 00:07:17 red go-librespot[943]: time="2024-09-18T00:07:17+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 18 00:07:17 red go-librespot[943]: time="2024-09-18T00:07:17+09:00" level=trace msg="emitting websocket event: playing" Sep 18 00:07:17 red volumio[752]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:6SQXqgRUnrGFnUdR3i9m31","play_origin":"playlist"}} Sep 18 00:07:17 red volumio[752]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 18 00:07:17 red volumio[752]: TypeError: Cannot read property 'service' of undefined Sep 18 00:07:17 red volumio[752]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:337:50) Sep 18 00:07:17 red volumio[752]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:263:18) Sep 18 00:07:17 red volumio[752]: at WebSocket.message (/data/plugins/music_service/spop/index.js:190:14) Sep 18 00:07:17 red volumio[752]: at WebSocket.emit (events.js:315:20) Sep 18 00:07:17 red volumio[752]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Sep 18 00:07:17 red volumio[752]: at Receiver.emit (events.js:315:20) Sep 18 00:07:17 red volumio[752]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Sep 18 00:07:17 red volumio[752]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Sep 18 00:07:17 red volumio[752]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Sep 18 00:07:17 red volumio[752]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Sep 18 00:07:17 red volumio[752]: at writeOrBuffer (internal/streams/writable.js:358:12) Sep 18 00:07:17 red volumio[752]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Sep 18 00:07:17 red volumio[752]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Sep 18 00:07:17 red volumio[752]: at Socket.emit (events.js:315:20) Sep 18 00:07:17 red volumio[752]: at addChunk (internal/streams/readable.js:309:12) Sep 18 00:07:17 red volumio[752]: at readableAddChunk (internal/streams/readable.js:284:9) Sep 18 00:07:17 red volumio[752]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 18 00:07:17 red go-librespot[943]: time="2024-09-18T00:07:17+09:00" level=debug msg="fetched chunk 1/8, size: 524288" uri="spotify:track:6SQXqgRUnrGFnUdR3i9m31" Sep 18 00:07:17 red sudo[1148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-18 00:06 Sep 18 00:07:17 red sudo[1148]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"