-- Logs begin at Sun 2024-10-13 17:41:52 KST, end at Sun 2024-10-13 17:42:37 KST. -- Oct 13 17:41:52 volumio-pi kernel: Booting Linux on physical CPU 0x0 Oct 13 17:41:52 volumio-pi kernel: Linux version 6.1.69-v7+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1710 SMP Thu Dec 21 13:14:13 GMT 2023 Oct 13 17:41:52 volumio-pi kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d Oct 13 17:41:52 volumio-pi kernel: CPU: div instructions available: patching division code Oct 13 17:41:52 volumio-pi kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache Oct 13 17:41:52 volumio-pi kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B Plus Rev 1.3 Oct 13 17:41:52 volumio-pi kernel: random: crng init done Oct 13 17:41:52 volumio-pi kernel: Memory policy: Data cache writealloc Oct 13 17:41:52 volumio-pi kernel: Reserved memory: created CMA memory pool at 0x34000000, size 64 MiB Oct 13 17:41:52 volumio-pi kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Oct 13 17:41:52 volumio-pi kernel: Zone ranges: Oct 13 17:41:52 volumio-pi kernel: DMA [mem 0x0000000000000000-0x0000000037ffffff] Oct 13 17:41:52 volumio-pi kernel: Normal empty Oct 13 17:41:52 volumio-pi kernel: Movable zone start for each node Oct 13 17:41:52 volumio-pi kernel: Early memory node ranges Oct 13 17:41:52 volumio-pi kernel: node 0: [mem 0x0000000000000000-0x0000000037ffffff] Oct 13 17:41:52 volumio-pi kernel: Initmem setup node 0 [mem 0x0000000000000000-0x0000000037ffffff] Oct 13 17:41:52 volumio-pi kernel: percpu: Embedded 17 pages/cpu s37012 r8192 d24428 u69632 Oct 13 17:41:52 volumio-pi kernel: pcpu-alloc: s37012 r8192 d24428 u69632 alloc=17*4096 Oct 13 17:41:52 volumio-pi kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 13 17:41:52 volumio-pi kernel: Built 1 zonelists, mobility grouping on. Total pages: 227360 Oct 13 17:41:52 volumio-pi kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 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=59add7c1-f5c7-4787-a0bf-7fec952d5a50 imgfile=/volumio_current.sqsh bootpart=UUID=E8CB-9B11 datapart=UUID=c7049a41-b6fb-4f8f-807b-264845b2b35c 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 Oct 13 17:41:52 volumio-pi kernel: Unknown kernel command line parameters "splash nodebug imgpart=UUID=59add7c1-f5c7-4787-a0bf-7fec952d5a50 imgfile=/volumio_current.sqsh bootpart=UUID=E8CB-9B11 datapart=UUID=c7049a41-b6fb-4f8f-807b-264845b2b35c uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe bootdelay=7 use_kmsg=no", will be passed to user space. Oct 13 17:41:52 volumio-pi kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Oct 13 17:41:52 volumio-pi kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 13 17:41:52 volumio-pi kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 13 17:41:52 volumio-pi kernel: Memory: 810820K/917504K available (10240K kernel code, 1455K rwdata, 3144K rodata, 1024K init, 611K bss, 41148K reserved, 65536K cma-reserved) Oct 13 17:41:52 volumio-pi kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 13 17:41:52 volumio-pi kernel: ftrace: allocating 34929 entries in 103 pages Oct 13 17:41:52 volumio-pi kernel: ftrace: allocated 103 pages with 5 groups Oct 13 17:41:52 volumio-pi kernel: trace event string verifier disabled Oct 13 17:41:52 volumio-pi kernel: rcu: Hierarchical RCU implementation. Oct 13 17:41:52 volumio-pi kernel: Rude variant of Tasks RCU enabled. Oct 13 17:41:52 volumio-pi kernel: Tracing variant of Tasks RCU enabled. Oct 13 17:41:52 volumio-pi kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Oct 13 17:41:52 volumio-pi kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Oct 13 17:41:52 volumio-pi kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 13 17:41:52 volumio-pi kernel: arch_timer: cp15 timer(s) running at 19.20MHz (phys). Oct 13 17:41:52 volumio-pi kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns Oct 13 17:41:52 volumio-pi kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns Oct 13 17:41:52 volumio-pi kernel: Switching to timer-based delay loop, resolution 52ns Oct 13 17:41:52 volumio-pi kernel: Console: colour dummy device 80x30 Oct 13 17:41:52 volumio-pi kernel: printk: console [tty1] enabled Oct 13 17:41:52 volumio-pi kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) Oct 13 17:41:52 volumio-pi kernel: CPU: Testing write buffer coherency: ok Oct 13 17:41:52 volumio-pi kernel: pid_max: default: 32768 minimum: 301 Oct 13 17:41:52 volumio-pi kernel: LSM: Security Framework initializing Oct 13 17:41:52 volumio-pi kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Oct 13 17:41:52 volumio-pi kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Oct 13 17:41:52 volumio-pi kernel: cgroup: Disabling memory control group subsystem Oct 13 17:41:52 volumio-pi kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Oct 13 17:41:52 volumio-pi kernel: cblist_init_generic: Setting adjustable number of callback queues. Oct 13 17:41:52 volumio-pi kernel: cblist_init_generic: Setting shift to 2 and lim to 1. Oct 13 17:41:52 volumio-pi kernel: cblist_init_generic: Setting adjustable number of callback queues. Oct 13 17:41:52 volumio-pi kernel: cblist_init_generic: Setting shift to 2 and lim to 1. Oct 13 17:41:52 volumio-pi kernel: Setting up static identity map for 0x100000 - 0x10003c Oct 13 17:41:52 volumio-pi kernel: rcu: Hierarchical SRCU implementation. Oct 13 17:41:52 volumio-pi kernel: rcu: Max phase no-delay instances is 1000. Oct 13 17:41:52 volumio-pi kernel: smp: Bringing up secondary CPUs ... Oct 13 17:41:52 volumio-pi kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Oct 13 17:41:52 volumio-pi kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Oct 13 17:41:52 volumio-pi kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Oct 13 17:41:52 volumio-pi kernel: smp: Brought up 1 node, 4 CPUs Oct 13 17:41:52 volumio-pi kernel: SMP: Total of 4 processors activated (153.60 BogoMIPS). Oct 13 17:41:52 volumio-pi kernel: CPU: All CPU(s) started in HYP mode. Oct 13 17:41:52 volumio-pi kernel: CPU: Virtualization extensions available. Oct 13 17:41:52 volumio-pi kernel: devtmpfs: initialized Oct 13 17:41:52 volumio-pi kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4 Oct 13 17:41:52 volumio-pi kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Oct 13 17:41:52 volumio-pi kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 13 17:41:52 volumio-pi kernel: pinctrl core: initialized pinctrl subsystem Oct 13 17:41:52 volumio-pi kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 13 17:41:52 volumio-pi kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Oct 13 17:41:52 volumio-pi kernel: audit: initializing netlink subsys (disabled) Oct 13 17:41:52 volumio-pi kernel: audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1 Oct 13 17:41:52 volumio-pi kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 13 17:41:52 volumio-pi kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Oct 13 17:41:52 volumio-pi kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Oct 13 17:41:52 volumio-pi kernel: Serial: AMBA PL011 UART driver Oct 13 17:41:52 volumio-pi kernel: bcm2835-mbox 3f00b880.mailbox: mailbox enabled Oct 13 17:41:52 volumio-pi kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2023-10-17T15:42:39, variant start Oct 13 17:41:52 volumio-pi kernel: raspberrypi-firmware soc:firmware: Firmware hash is 30f0c5e4d076da3ab4f341d88e7d505760b93ad7 Oct 13 17:41:52 volumio-pi kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Oct 13 17:41:52 volumio-pi kernel: bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1 Oct 13 17:41:52 volumio-pi kernel: SCSI subsystem initialized Oct 13 17:41:52 volumio-pi kernel: usbcore: registered new interface driver usbfs Oct 13 17:41:52 volumio-pi kernel: usbcore: registered new interface driver hub Oct 13 17:41:52 volumio-pi kernel: usbcore: registered new device driver usb Oct 13 17:41:52 volumio-pi kernel: usb_phy_generic phy: supply vcc not found, using dummy regulator Oct 13 17:41:52 volumio-pi kernel: pps_core: LinuxPPS API ver. 1 registered Oct 13 17:41:52 volumio-pi kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Oct 13 17:41:52 volumio-pi kernel: PTP clock support registered Oct 13 17:41:52 volumio-pi kernel: clocksource: Switched to clocksource arch_sys_counter Oct 13 17:41:52 volumio-pi kernel: VFS: Disk quotas dquot_6.6.0 Oct 13 17:41:52 volumio-pi kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Oct 13 17:41:52 volumio-pi kernel: FS-Cache: Loaded Oct 13 17:41:52 volumio-pi kernel: CacheFiles: Loaded Oct 13 17:41:52 volumio-pi kernel: NET: Registered PF_INET protocol family Oct 13 17:41:52 volumio-pi kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) Oct 13 17:41:52 volumio-pi kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear) Oct 13 17:41:52 volumio-pi kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 13 17:41:52 volumio-pi kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Oct 13 17:41:52 volumio-pi kernel: TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear) Oct 13 17:41:52 volumio-pi kernel: TCP: Hash tables configured (established 8192 bind 8192) Oct 13 17:41:52 volumio-pi kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Oct 13 17:41:52 volumio-pi kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Oct 13 17:41:52 volumio-pi kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 13 17:41:52 volumio-pi kernel: RPC: Registered named UNIX socket transport module. Oct 13 17:41:52 volumio-pi kernel: RPC: Registered udp transport module. Oct 13 17:41:52 volumio-pi kernel: RPC: Registered tcp transport module. Oct 13 17:41:52 volumio-pi kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Oct 13 17:41:52 volumio-pi kernel: Trying to unpack rootfs image as initramfs... Oct 13 17:41:52 volumio-pi kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available Oct 13 17:41:52 volumio-pi kernel: Freeing initrd memory: 14316K Oct 13 17:41:52 volumio-pi kernel: Initialise system trusted keyrings Oct 13 17:41:52 volumio-pi kernel: workingset: timestamp_bits=14 max_order=18 bucket_order=4 Oct 13 17:41:52 volumio-pi kernel: zbud: loaded Oct 13 17:41:52 volumio-pi kernel: NFS: Registering the id_resolver key type Oct 13 17:41:52 volumio-pi kernel: Key type id_resolver registered Oct 13 17:41:52 volumio-pi kernel: Key type id_legacy registered Oct 13 17:41:52 volumio-pi kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Oct 13 17:41:52 volumio-pi kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Oct 13 17:41:52 volumio-pi kernel: Key type asymmetric registered Oct 13 17:41:52 volumio-pi kernel: Asymmetric key parser 'x509' registered Oct 13 17:41:52 volumio-pi kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Oct 13 17:41:52 volumio-pi kernel: io scheduler mq-deadline registered Oct 13 17:41:52 volumio-pi kernel: io scheduler kyber registered Oct 13 17:41:52 volumio-pi kernel: bcm2708_fb soc:fb: FB found 1 display(s) Oct 13 17:41:52 volumio-pi kernel: Console: switching to colour frame buffer device 80x30 Oct 13 17:41:52 volumio-pi kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Oct 13 17:41:52 volumio-pi kernel: bcm2835-rng 3f104000.rng: hwrng registered Oct 13 17:41:52 volumio-pi kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Oct 13 17:41:52 volumio-pi kernel: brd: module loaded Oct 13 17:41:52 volumio-pi kernel: loop: module loaded Oct 13 17:41:52 volumio-pi kernel: Loading iSCSI transport class v2.0-870. Oct 13 17:41:52 volumio-pi kernel: usbcore: registered new interface driver lan78xx Oct 13 17:41:52 volumio-pi kernel: usbcore: registered new interface driver smsc95xx Oct 13 17:41:52 volumio-pi kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Oct 13 17:41:52 volumio-pi kernel: Core Release: 2.80a Oct 13 17:41:52 volumio-pi kernel: Setting default values for core params Oct 13 17:41:52 volumio-pi kernel: Finished setting default values for core params Oct 13 17:41:52 volumio-pi kernel: Using Buffer DMA mode Oct 13 17:41:52 volumio-pi kernel: Periodic Transfer Interrupt Enhancement - disabled Oct 13 17:41:52 volumio-pi kernel: Multiprocessor Interrupt Enhancement - disabled Oct 13 17:41:52 volumio-pi kernel: OTG VER PARAM: 0, OTG VER FLAG: 0 Oct 13 17:41:52 volumio-pi kernel: Dedicated Tx FIFOs mode Oct 13 17:41:52 volumio-pi kernel: Oct 13 17:41:52 volumio-pi kernel: WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = b4114000 dma = 0xf4114000 len=9024 Oct 13 17:41:52 volumio-pi kernel: FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled Oct 13 17:41:52 volumio-pi kernel: dwc_otg: Microframe scheduler enabled Oct 13 17:41:52 volumio-pi kernel: Oct 13 17:41:52 volumio-pi kernel: WARN::hcd_init_fiq:457: FIQ on core 1 Oct 13 17:41:52 volumio-pi kernel: Oct 13 17:41:52 volumio-pi kernel: WARN::hcd_init_fiq:458: FIQ ASM at 807cb80c length 36 Oct 13 17:41:52 volumio-pi kernel: Oct 13 17:41:52 volumio-pi kernel: WARN::hcd_init_fiq:497: MPHI regs_base at b8810000 Oct 13 17:41:52 volumio-pi kernel: dwc_otg 3f980000.usb: DWC OTG Controller Oct 13 17:41:52 volumio-pi kernel: dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 Oct 13 17:41:52 volumio-pi kernel: dwc_otg 3f980000.usb: irq 89, io mem 0x00000000 Oct 13 17:41:52 volumio-pi kernel: Init: Port Power? op_state=1 Oct 13 17:41:52 volumio-pi kernel: Init: Power Port (0) Oct 13 17:41:52 volumio-pi kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.01 Oct 13 17:41:52 volumio-pi kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 13 17:41:52 volumio-pi kernel: usb usb1: Product: DWC OTG Controller Oct 13 17:41:52 volumio-pi kernel: usb usb1: Manufacturer: Linux 6.1.69-v7+ dwc_otg_hcd Oct 13 17:41:52 volumio-pi kernel: usb usb1: SerialNumber: 3f980000.usb Oct 13 17:41:52 volumio-pi kernel: hub 1-0:1.0: USB hub found Oct 13 17:41:52 volumio-pi kernel: hub 1-0:1.0: 1 port detected Oct 13 17:41:52 volumio-pi kernel: dwc_otg: FIQ enabled Oct 13 17:41:52 volumio-pi kernel: dwc_otg: NAK holdoff enabled Oct 13 17:41:52 volumio-pi kernel: dwc_otg: FIQ split-transaction FSM enabled Oct 13 17:41:52 volumio-pi kernel: Module dwc_common_port init Oct 13 17:41:52 volumio-pi kernel: usbcore: registered new interface driver usb-storage Oct 13 17:41:52 volumio-pi kernel: mousedev: PS/2 mouse device common for all mice Oct 13 17:41:52 volumio-pi kernel: sdhci: Secure Digital Host Controller Interface driver Oct 13 17:41:52 volumio-pi kernel: sdhci: Copyright(c) Pierre Ossman Oct 13 17:41:52 volumio-pi kernel: sdhci-pltfm: SDHCI platform and OF driver helper Oct 13 17:41:52 volumio-pi kernel: ledtrig-cpu: registered to indicate activity on CPUs Oct 13 17:41:52 volumio-pi kernel: hid: raw HID events driver (C) Jiri Kosina Oct 13 17:41:52 volumio-pi kernel: usbcore: registered new interface driver usbhid Oct 13 17:41:52 volumio-pi kernel: usbhid: USB HID core driver Oct 13 17:41:52 volumio-pi kernel: Initializing XFRM netlink socket Oct 13 17:41:52 volumio-pi kernel: NET: Registered PF_PACKET protocol family Oct 13 17:41:52 volumio-pi kernel: Key type dns_resolver registered Oct 13 17:41:52 volumio-pi kernel: Registering SWP/SWPB emulation handler Oct 13 17:41:52 volumio-pi kernel: registered taskstats version 1 Oct 13 17:41:52 volumio-pi kernel: Loading compiled-in X.509 certificates Oct 13 17:41:52 volumio-pi kernel: Key type .fscrypt registered Oct 13 17:41:52 volumio-pi kernel: Key type fscrypt-provisioning registered Oct 13 17:41:52 volumio-pi kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled Oct 13 17:41:52 volumio-pi kernel: 3f201000.serial: ttyAMA1 at MMIO 0x3f201000 (irq = 114, base_baud = 0) is a PL011 rev2 Oct 13 17:41:52 volumio-pi kernel: serial serial0: tty port ttyAMA1 registered Oct 13 17:41:52 volumio-pi kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Oct 13 17:41:52 volumio-pi kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Oct 13 17:41:52 volumio-pi kernel: mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0 Oct 13 17:41:52 volumio-pi kernel: mmc-bcm2835 3f300000.mmcnr: DMA channel allocated Oct 13 17:41:52 volumio-pi kernel: sdhost: log_buf @ 126fe2ac (f4113000) Oct 13 17:41:52 volumio-pi kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) Oct 13 17:41:52 volumio-pi kernel: of_cfs_init Oct 13 17:41:52 volumio-pi kernel: of_cfs_init: OK Oct 13 17:41:52 volumio-pi kernel: Freeing unused kernel image (initmem) memory: 1024K Oct 13 17:41:52 volumio-pi kernel: Indeed it is in host mode hprt0 = 00021501 Oct 13 17:41:52 volumio-pi kernel: mmc0: host does not support reading read-only switch, assuming write-enable Oct 13 17:41:52 volumio-pi kernel: mmc0: new high speed SDHC card at address 0007 Oct 13 17:41:52 volumio-pi kernel: mmcblk0: mmc0:0007 SD32G 29.0 GiB Oct 13 17:41:52 volumio-pi kernel: Run /init as init process Oct 13 17:41:52 volumio-pi kernel: with arguments: Oct 13 17:41:52 volumio-pi kernel: /init Oct 13 17:41:52 volumio-pi kernel: splash Oct 13 17:41:52 volumio-pi kernel: nodebug Oct 13 17:41:52 volumio-pi kernel: with environment: Oct 13 17:41:52 volumio-pi kernel: HOME=/ Oct 13 17:41:52 volumio-pi kernel: TERM=linux Oct 13 17:41:52 volumio-pi kernel: imgpart=UUID=59add7c1-f5c7-4787-a0bf-7fec952d5a50 Oct 13 17:41:52 volumio-pi kernel: imgfile=/volumio_current.sqsh Oct 13 17:41:52 volumio-pi kernel: bootpart=UUID=E8CB-9B11 Oct 13 17:41:52 volumio-pi kernel: datapart=UUID=c7049a41-b6fb-4f8f-807b-264845b2b35c Oct 13 17:41:52 volumio-pi kernel: uuidconfig=cmdline.txt Oct 13 17:41:52 volumio-pi kernel: pcie_aspm=off Oct 13 17:41:52 volumio-pi kernel: pci=pcie_bus_safe Oct 13 17:41:52 volumio-pi kernel: bootdelay=7 Oct 13 17:41:52 volumio-pi kernel: use_kmsg=no Oct 13 17:41:52 volumio-pi kernel: mmcblk0: p1 p2 p3 Oct 13 17:41:52 volumio-pi kernel: mmcblk0: mmc0:0007 SD32G 29.0 GiB Oct 13 17:41:52 volumio-pi kernel: mmc1: new high speed SDIO card at address 0001 Oct 13 17:41:52 volumio-pi kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg Oct 13 17:41:52 volumio-pi kernel: Indeed it is in host mode hprt0 = 00001101 Oct 13 17:41:52 volumio-pi kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3 Oct 13 17:41:52 volumio-pi kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Oct 13 17:41:52 volumio-pi kernel: hub 1-1:1.0: USB hub found Oct 13 17:41:52 volumio-pi kernel: hub 1-1:1.0: 4 ports detected Oct 13 17:41:52 volumio-pi kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg Oct 13 17:41:52 volumio-pi kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3 Oct 13 17:41:52 volumio-pi kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Oct 13 17:41:52 volumio-pi kernel: hub 1-1.1:1.0: USB hub found Oct 13 17:41:52 volumio-pi kernel: hub 1-1.1:1.0: 3 ports detected Oct 13 17:41:52 volumio-pi kernel: fuse: init (API version 7.37) Oct 13 17:41:52 volumio-pi kernel: dwc_otg_handle_wakeup_detected_intr lxstate = 2 Oct 13 17:41:52 volumio-pi kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 13 17:41:52 volumio-pi kernel: usbcore: registered new interface driver uas Oct 13 17:41:52 volumio-pi kernel: usb 1-1.1.1: new high-speed USB device number 4 using dwc_otg Oct 13 17:41:52 volumio-pi kernel: usb 1-1.1.1: New USB device found, idVendor=0424, idProduct=7800, bcdDevice= 3.00 Oct 13 17:41:52 volumio-pi kernel: usb 1-1.1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Oct 13 17:41:52 volumio-pi kernel: lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): No External EEPROM. Setting MAC Speed Oct 13 17:41:52 volumio-pi kernel: lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): int urb period 64 Oct 13 17:41:52 volumio-pi kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Oct 13 17:41:52 volumio-pi kernel: EXT4-fs (mmcblk0p2): recovery complete Oct 13 17:41:52 volumio-pi kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Quota mode: none. Oct 13 17:41:52 volumio-pi kernel: loop0: detected capacity change from 0 to 983416 Oct 13 17:41:52 volumio-pi kernel: EXT4-fs (mmcblk0p3): recovery complete Oct 13 17:41:52 volumio-pi kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Quota mode: none. Oct 13 17:41:52 volumio-pi systemd[1]: System time before build time, advancing clock. Oct 13 17:41:52 volumio-pi kernel: NET: Registered PF_INET6 protocol family Oct 13 17:41:52 volumio-pi kernel: Segment Routing with IPv6 Oct 13 17:41:52 volumio-pi kernel: In-situ OAM (IOAM) with IPv6 Oct 13 17:41:52 volumio-pi 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) Oct 13 17:41:52 volumio-pi systemd[1]: Detected architecture arm. Oct 13 17:41:52 volumio-pi systemd[1]: Set hostname to . Oct 13 17:41:52 volumio-pi 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. Oct 13 17:41:52 volumio-pi systemd[1]: Listening on Journal Socket (/dev/log). Oct 13 17:41:52 volumio-pi systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 13 17:41:52 volumio-pi systemd[1]: Created slice User and Session Slice. Oct 13 17:41:52 volumio-pi systemd[1]: Listening on udev Control Socket. Oct 13 17:41:52 volumio-pi systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 13 17:41:52 volumio-pi systemd[1]: Listening on RPCbind Server Activation Socket. Oct 13 17:41:52 volumio-pi kernel: i2c_dev: i2c /dev entries driver Oct 13 17:41:52 volumio-pi systemd-journald[311]: Journal started Oct 13 17:41:52 volumio-pi systemd-journald[311]: Runtime journal (/run/log/journal/4f62df4b7e374c47e98bbc1f6425ae06) is 3.7M, max 30.0M, 26.2M free. Oct 13 17:41:52 volumio-pi systemd-modules-load[308]: Inserted module 'i2c_dev' Oct 13 17:41:52 volumio-pi fake-hwclock[304]: Sun Oct 13 08:41:52 UTC 2024 Oct 13 17:41:52 volumio-pi systemd[1]: Started udev Coldplug all Devices. Oct 13 17:41:53 volumio-pi systemd[1]: Starting Helper to synchronize boot up for ifupdown... Oct 13 17:41:53 volumio-pi systemd[1]: dynamicswap.service: Succeeded. Oct 13 17:41:54 volumio-pi systemd[1]: Started Apply Kernel Variables. Oct 13 17:41:54 volumio-pi systemd[1]: Started Create System Users. Oct 13 17:41:54 volumio-pi systemd[1]: Starting Create Static Device Nodes in /dev... Oct 13 17:41:54 volumio-pi systemd[1]: Started Create Static Device Nodes in /dev. Oct 13 17:41:54 volumio-pi systemd[1]: Reached target Local File Systems (Pre). Oct 13 17:41:54 volumio-pi systemd[1]: Mounting /var/spool/cups... Oct 13 17:41:54 volumio-pi systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Oct 13 17:41:54 volumio-pi systemd[1]: Mounting /tmp... Oct 13 17:41:54 volumio-pi systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Oct 13 17:41:54 volumio-pi systemd[1]: Mounting /var/log... Oct 13 17:41:54 volumio-pi systemd[1]: Starting udev Kernel Device Manager... Oct 13 17:41:54 volumio-pi systemd[1]: Mounted /var/spool/cups. Oct 13 17:41:54 volumio-pi systemd[1]: Mounted /tmp. Oct 13 17:41:54 volumio-pi systemd[1]: Mounting /var/spool/cups/tmp... Oct 13 17:41:54 volumio-pi systemd[1]: Mounted /var/log. Oct 13 17:41:54 volumio-pi systemd[1]: Starting Flush Journal to Persistent Storage... Oct 13 17:41:54 volumio-pi systemd[1]: Mounted /var/spool/cups/tmp. Oct 13 17:41:54 volumio-pi systemd-udevd[346]: Network interface NamePolicy= disabled on kernel command line, ignoring. Oct 13 17:41:54 volumio-pi systemd-journald[311]: Runtime journal (/run/log/journal/4f62df4b7e374c47e98bbc1f6425ae06) is 3.7M, max 30.0M, 26.2M free. Oct 13 17:41:54 volumio-pi systemd[1]: Started Flush Journal to Persistent Storage. Oct 13 17:41:54 volumio-pi systemd[1]: Started udev Kernel Device Manager. Oct 13 17:41:54 volumio-pi systemd[1]: Starting Show Plymouth Boot Screen... Oct 13 17:41:54 volumio-pi systemd[1]: plymouth-start.service: Succeeded. Oct 13 17:41:54 volumio-pi systemd[1]: Started Show Plymouth Boot Screen. Oct 13 17:41:54 volumio-pi systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Oct 13 17:41:54 volumio-pi systemd[1]: Reached target Paths. Oct 13 17:41:54 volumio-pi systemd[1]: Reached target Local Encrypted Volumes. Oct 13 17:41:54 volumio-pi systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Oct 13 17:41:54 volumio-pi kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 Oct 13 17:41:54 volumio-pi kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem Oct 13 17:41:54 volumio-pi kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Oct 13 17:41:54 volumio-pi kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Oct 13 17:41:54 volumio-pi kernel: [vc_sm_connected_init]: start Oct 13 17:41:54 volumio-pi kernel: mc: Linux media interface: v0.10 Oct 13 17:41:54 volumio-pi kernel: [vc_sm_connected_init]: installed successfully Oct 13 17:41:55 volumio-pi kernel: videodev: Linux video capture interface: v2.00 Oct 13 17:41:55 volumio-pi kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Oct 13 17:41:55 volumio-pi kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Oct 13 17:41:55 volumio-pi kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Oct 13 17:41:55 volumio-pi kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Oct 13 17:41:55 volumio-pi kernel: Bluetooth: Core ver 2.22 Oct 13 17:41:55 volumio-pi kernel: NET: Registered PF_BLUETOOTH protocol family Oct 13 17:41:55 volumio-pi kernel: Bluetooth: HCI device and connection manager initialized Oct 13 17:41:55 volumio-pi kernel: Bluetooth: HCI socket layer initialized Oct 13 17:41:55 volumio-pi kernel: Bluetooth: L2CAP socket layer initialized Oct 13 17:41:55 volumio-pi kernel: Bluetooth: SCO socket layer initialized Oct 13 17:41:56 volumio-pi kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Oct 13 17:41:57 volumio-pi kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Oct 13 17:41:57 volumio-pi kernel: Bluetooth: HCI UART driver ver 2.3 Oct 13 17:41:57 volumio-pi kernel: Bluetooth: HCI UART protocol H4 registered Oct 13 17:41:57 volumio-pi kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Oct 13 17:41:57 volumio-pi kernel: Bluetooth: HCI UART protocol Broadcom registered Oct 13 17:41:57 volumio-pi kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Oct 13 17:41:57 volumio-pi kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Oct 13 17:41:57 volumio-pi kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Oct 13 17:41:57 volumio-pi kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Oct 13 17:41:57 volumio-pi kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Oct 13 17:41:57 volumio-pi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Oct 13 17:41:57 volumio-pi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Oct 13 17:41:57 volumio-pi kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Oct 13 17:41:57 volumio-pi kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Oct 13 17:41:57 volumio-pi kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Oct 13 17:41:57 volumio-pi kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Oct 13 17:41:57 volumio-pi kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Oct 13 17:41:57 volumio-pi kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Oct 13 17:41:57 volumio-pi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Oct 13 17:41:57 volumio-pi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Oct 13 17:41:57 volumio-pi kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Oct 13 17:41:57 volumio-pi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Oct 13 17:41:57 volumio-pi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Oct 13 17:41:57 volumio-pi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Oct 13 17:41:57 volumio-pi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Oct 13 17:41:57 volumio-pi kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Oct 13 17:41:57 volumio-pi kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Oct 13 17:41:57 volumio-pi kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Oct 13 17:41:57 volumio-pi kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Oct 13 17:41:57 volumio-pi kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Oct 13 17:41:57 volumio-pi kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Oct 13 17:41:57 volumio-pi kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Oct 13 17:41:57 volumio-pi kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Oct 13 17:41:57 volumio-pi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Oct 13 17:41:57 volumio-pi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Oct 13 17:41:57 volumio-pi kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Oct 13 17:41:57 volumio-pi kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Oct 13 17:41:57 volumio-pi kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Oct 13 17:41:57 volumio-pi kernel: uart-pl011 3f201000.serial: no DMA platform data Oct 13 17:41:57 volumio-pi systemd[1]: Found device /dev/disk/by-uuid/E8CB-9B11. Oct 13 17:41:57 volumio-pi systemd[1]: Starting File System Check on /dev/disk/by-uuid/E8CB-9B11... Oct 13 17:41:57 volumio-pi kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Oct 13 17:41:57 volumio-pi kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Oct 13 17:41:57 volumio-pi systemd[1]: Started File System Check Daemon to report status. Oct 13 17:41:57 volumio-pi kernel: Bluetooth: hci0: BCM: chip id 107 Oct 13 17:41:57 volumio-pi kernel: Bluetooth: hci0: BCM: features 0x2f Oct 13 17:41:57 volumio-pi kernel: Bluetooth: hci0: BCM4345C0 Oct 13 17:41:57 volumio-pi kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Oct 13 17:41:57 volumio-pi kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Oct 13 17:41:57 volumio-pi kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Oct 13 17:41:57 volumio-pi kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Oct 13 17:41:57 volumio-pi kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Oct 13 17:41:57 volumio-pi kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Oct 13 17:41:57 volumio-pi kernel: usbcore: registered new interface driver brcmfmac Oct 13 17:41:57 volumio-pi systemd-udevd[354]: Using default interface naming scheme 'v240'. Oct 13 17:41:57 volumio-pi systemd-udevd[354]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Oct 13 17:41:57 volumio-pi systemd-fsck[412]: fsck.fat 4.1 (2017-01-24) Oct 13 17:41:57 volumio-pi systemd-fsck[412]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Oct 13 17:41:57 volumio-pi systemd-fsck[412]: Automatically removing dirty bit. Oct 13 17:41:57 volumio-pi systemd-fsck[412]: /config.txt Oct 13 17:41:57 volumio-pi systemd-fsck[412]: Contains a free cluster (2336). Assuming EOF. Oct 13 17:41:57 volumio-pi systemd-fsck[412]: /config.txt Oct 13 17:41:57 volumio-pi systemd-fsck[412]: File size is 270 bytes, cluster chain length is 0 bytes. Oct 13 17:41:57 volumio-pi systemd-fsck[412]: Truncating file to 0 bytes. Oct 13 17:41:57 volumio-pi systemd-fsck[412]: Reclaimed 1 unused cluster (2048 bytes) in 1 chain. Oct 13 17:41:57 volumio-pi systemd-fsck[412]: Performing changes. Oct 13 17:41:57 volumio-pi systemd-fsck[412]: /dev/mmcblk0p1: 376 files, 33705/46774 clusters Oct 13 17:41:57 volumio-pi systemd[1]: Started File System Check on /dev/disk/by-uuid/E8CB-9B11. Oct 13 17:41:57 volumio-pi systemd[1]: Mounting /boot... Oct 13 17:41:57 volumio-pi kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov 1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60 Oct 13 17:41:57 volumio-pi systemd[1]: Mounted /boot. Oct 13 17:41:57 volumio-pi systemd[1]: Reached target Local File Systems. Oct 13 17:41:58 volumio-pi systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Oct 13 17:41:58 volumio-pi systemd[1]: Starting Preprocess NFS configuration... Oct 13 17:41:58 volumio-pi systemd[1]: Starting Create Volatile Files and Directories... Oct 13 17:41:58 volumio-pi systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Oct 13 17:41:58 volumio-pi systemd[1]: nfs-config.service: Succeeded. Oct 13 17:41:58 volumio-pi systemd[1]: Started Preprocess NFS configuration. Oct 13 17:41:58 volumio-pi systemd[1]: plymouth-read-write.service: Succeeded. Oct 13 17:41:58 volumio-pi systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Oct 13 17:41:58 volumio-pi systemd[1]: Received SIGRTMIN+20 from PID 191 (plymouthd). Oct 13 17:41:58 volumio-pi systemd[1]: Started Create Volatile Files and Directories. Oct 13 17:41:58 volumio-pi kernel: Bluetooth: hci0: BCM: features 0x2f Oct 13 17:41:58 volumio-pi kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Oct 13 17:41:58 volumio-pi kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Oct 13 17:41:58 volumio-pi kernel: Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac) Oct 13 17:41:58 volumio-pi systemd-udevd[358]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Oct 13 17:41:58 volumio-pi systemd-udevd[355]: Using default interface naming scheme 'v240'. Oct 13 17:41:58 volumio-pi systemd-udevd[355]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Oct 13 17:41:58 volumio-pi systemd[1]: Condition check resulted in 7800 being skipped. Oct 13 17:41:59 volumio-pi systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Oct 13 17:41:59 volumio-pi systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Oct 13 17:41:59 volumio-pi systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Oct 13 17:41:59 volumio-pi systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Oct 13 17:41:59 volumio-pi systemd[1]: Starting Show Plymouth Boot Screen... Oct 13 17:41:59 volumio-pi systemd[1]: Created slice system-bthelper.slice. Oct 13 17:41:59 volumio-pi systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 13 17:41:59 volumio-pi systemd[1]: Condition check resulted in Huge Pages File System being skipped. Oct 13 17:41:59 volumio-pi systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Oct 13 17:41:59 volumio-pi systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Oct 13 17:41:59 volumio-pi systemd[1]: Starting RPC bind portmap service... Oct 13 17:41:59 volumio-pi systemd[1]: Starting Update UTMP about System Boot/Shutdown... Oct 13 17:41:59 volumio-pi systemd[1]: Started ifup for eth0. Oct 13 17:41:59 volumio-pi systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Oct 13 17:41:59 volumio-pi systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Oct 13 17:41:59 volumio-pi systemd[1]: Reached target NFS client services. Oct 13 17:41:59 volumio-pi systemd[1]: plymouth-read-write.service: Succeeded. Oct 13 17:41:59 volumio-pi systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Oct 13 17:41:59 volumio-pi systemd[1]: Received SIGRTMIN+20 from PID 191 (plymouthd). Oct 13 17:41:59 volumio-pi systemd[1]: Starting Load/Save RF Kill Switch Status... Oct 13 17:41:59 volumio-pi systemd[1]: plymouth-start.service: Succeeded. Oct 13 17:41:59 volumio-pi systemd[1]: Started Show Plymouth Boot Screen. Oct 13 17:41:59 volumio-pi systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Oct 13 17:41:59 volumio-pi systemd[1]: Started Update UTMP about System Boot/Shutdown. Oct 13 17:41:59 volumio-pi systemd[1]: Reached target System Initialization. Oct 13 17:41:59 volumio-pi systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Oct 13 17:41:59 volumio-pi systemd[1]: apt-daily.timer: Not using persistent file timestamp Sun 2024-10-13 18:52:40 KST as it is in the future. Oct 13 17:41:59 volumio-pi systemd[1]: Started Daily apt download activities. Oct 13 17:41:59 volumio-pi systemd[1]: Started Daily apt upgrade and clean activities. Oct 13 17:41:59 volumio-pi systemd[1]: Listening on triggerhappy.socket. Oct 13 17:41:59 volumio-pi systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 13 17:41:59 volumio-pi systemd[1]: Reached target Timers. Oct 13 17:41:59 volumio-pi systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 13 17:41:59 volumio-pi systemd[1]: Reached target Sockets. Oct 13 17:41:59 volumio-pi systemd[1]: Reached target Basic System. Oct 13 17:41:59 volumio-pi systemd[1]: Starting triggerhappy global hotkey daemon... Oct 13 17:41:59 volumio-pi systemd[1]: Started Manage Sound Card State (restore and store). Oct 13 17:41:59 volumio-pi systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Oct 13 17:41:59 volumio-pi systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Oct 13 17:41:59 volumio-pi alsactl[508]: alsactl 1.1.8 daemon started Oct 13 17:41:59 volumio-pi systemd[1]: Starting Save/Restore Sound Card State... Oct 13 17:41:59 volumio-pi thd[501]: Unable to parse trigger line: Oct 13 17:41:59 volumio-pi thd[501]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Oct 13 17:41:59 volumio-pi thd[501]: Unable to parse trigger line: Oct 13 17:41:59 volumio-pi thd[501]: Found socket passed from systemd Oct 13 17:41:59 volumio-pi systemd[1]: Started Volumio Log Rotation Service. Oct 13 17:41:59 volumio-pi systemd[1]: Starting Configure Bluetooth Modems connected by UART... Oct 13 17:41:59 volumio-pi systemd[1]: Started UPnP Renderer front-end to MPD. Oct 13 17:41:59 volumio-pi systemd[1]: Started Volumio Iptables Module. Oct 13 17:42:00 volumio-pi systemd[1]: Starting Wireless Services... Oct 13 17:42:00 volumio-pi systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Oct 13 17:42:00 volumio-pi systemd[1]: Starting Login Service... Oct 13 17:42:00 volumio-pi systemd[1]: Started D-Bus System Message Bus. Oct 13 17:42:00 volumio-pi systemd[1]: Starting WPA supplicant... Oct 13 17:42:00 volumio-pi systemd[1]: Started volumio-remote-updater.service. Oct 13 17:42:00 volumio-pi systemd[1]: Started Load/Save RF Kill Switch Status. Oct 13 17:42:00 volumio-pi systemd[1]: Started RPC bind portmap service. Oct 13 17:42:00 volumio-pi systemd[1]: Started triggerhappy global hotkey daemon. Oct 13 17:42:00 volumio-pi systemd[1]: Started Helper to synchronize boot up for ifupdown. Oct 13 17:42:00 volumio-pi systemd[1]: Started Save/Restore Sound Card State. Oct 13 17:42:00 volumio-pi systemd-logind[520]: New seat seat0. Oct 13 17:42:00 volumio-pi avahi-daemon[519]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Oct 13 17:42:00 volumio-pi avahi-daemon[519]: Successfully dropped root privileges. Oct 13 17:42:00 volumio-pi avahi-daemon[519]: avahi-daemon 0.7 starting up. Oct 13 17:42:01 volumio-pi kernel: 8021q: 802.1Q VLAN Support v1.8 Oct 13 17:42:01 volumio-pi systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Oct 13 17:42:01 volumio-pi systemd[1]: hciuart.service: Succeeded. Oct 13 17:42:01 volumio-pi systemd[1]: Started Configure Bluetooth Modems connected by UART. Oct 13 17:42:01 volumio-pi systemd[1]: Starting Raspberry Pi bluetooth helper... Oct 13 17:42:01 volumio-pi systemd[1]: Started ifup for wlan0. Oct 13 17:42:01 volumio-pi systemd[1]: Reached target Sound Card. Oct 13 17:42:01 volumio-pi systemd[1]: Starting Raise network interfaces... Oct 13 17:42:01 volumio-pi systemd[1]: Reached target RPC Port Mapper. Oct 13 17:42:01 volumio-pi systemd[1]: Reached target Remote File Systems (Pre). Oct 13 17:42:01 volumio-pi systemd[1]: Reached target Remote File Systems. Oct 13 17:42:01 volumio-pi systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Oct 13 17:42:01 volumio-pi systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Oct 13 17:42:01 volumio-pi systemd[1]: Starting dhcpcd on all interfaces... Oct 13 17:42:01 volumio-pi dhcpcd[577]: Not running dhcpcd because /etc/network/interfaces Oct 13 17:42:01 volumio-pi dhcpcd[577]: defines some interfaces that will use a Oct 13 17:42:01 volumio-pi dhcpcd[577]: DHCP client or static address Oct 13 17:42:01 volumio-pi bthelper[564]: Raspberry Pi BDADDR already set Oct 13 17:42:01 volumio-pi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 13 17:42:01 volumio-pi systemd[1]: Started Login Service. Oct 13 17:42:01 volumio-pi systemd[1]: Started Raspberry Pi bluetooth helper. Oct 13 17:42:01 volumio-pi systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Oct 13 17:42:01 volumio-pi wpa_supplicant[527]: Successfully initialized wpa_supplicant Oct 13 17:42:01 volumio-pi systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Oct 13 17:42:01 volumio-pi systemd[1]: Failed to start dhcpcd on all interfaces. Oct 13 17:42:01 volumio-pi avahi-daemon[519]: Successfully called chroot(). Oct 13 17:42:01 volumio-pi avahi-daemon[519]: Successfully dropped remaining capabilities. Oct 13 17:42:02 volumio-pi avahi-daemon[519]: Loading service file /services/volumio.service. Oct 13 17:42:02 volumio-pi avahi-daemon[519]: Network interface enumeration completed. Oct 13 17:42:02 volumio-pi avahi-daemon[519]: Server startup complete. Host name is volumio-pi.local. Local service cookie is 988492437. Oct 13 17:42:02 volumio-pi avahi-daemon[519]: Service "Volumio Pi" (/services/volumio.service) successfully established. Oct 13 17:42:02 volumio-pi kernel: 8021q: adding VLAN 0 to HW filter on device eth0 Oct 13 17:42:02 volumio-pi sh[492]: eth0: waiting for carrier Oct 13 17:42:02 volumio-pi dhcpcd[558]: eth0: waiting for carrier Oct 13 17:42:02 volumio-pi kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Down Oct 13 17:42:02 volumio-pi ifup[569]: ifup: waiting for lock on /run/network/ifstate.wlan0 Oct 13 17:42:02 volumio-pi volumio-remote-updater[528]: Error: No active session Oct 13 17:42:02 volumio-pi systemd[1]: Started WPA supplicant. Oct 13 17:42:02 volumio-pi systemd[1]: Started Avahi mDNS/DNS-SD Stack. Oct 13 17:42:02 volumio-pi volumio-remote-updater[528]: [2024-10-13 17:42:02] [info] asio async_connect error: system:111 (Connection refused) Oct 13 17:42:02 volumio-pi volumio-remote-updater[528]: [2024-10-13 17:42:02] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 13 17:42:02 volumio-pi volumio-remote-updater[528]: [2024-10-13 17:42:02] [error] handle_connect error: Underlying Transport Error Oct 13 17:42:02 volumio-pi sh[566]: wlan0=wlan0 Oct 13 17:42:02 volumio-pi systemd[1]: Starting Bluetooth service... Oct 13 17:42:02 volumio-pi systemd[1]: iptables.service: Succeeded. Oct 13 17:42:02 volumio-pi volumio[514]: Could not open config: /tmp/upmpdcli.conf Oct 13 17:42:02 volumio-pi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 13 17:42:02 volumio-pi systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 13 17:42:02 volumio-pi systemd[1]: Started Raise network interfaces. Oct 13 17:42:02 volumio-pi systemd[1]: Reached target Network. Oct 13 17:42:02 volumio-pi systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Oct 13 17:42:02 volumio-pi systemd[1]: Starting Permit User Sessions... Oct 13 17:42:02 volumio-pi systemd[1]: Starting Network Time Service... Oct 13 17:42:02 volumio-pi systemd[1]: Reached target Network is Online. Oct 13 17:42:02 volumio-pi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 13 17:42:02 volumio-pi systemd[1]: Starting Samba NMB Daemon... Oct 13 17:42:02 volumio-pi systemd[1]: Starting /etc/rc.local Compatibility... Oct 13 17:42:02 volumio-pi systemd[1]: Starting LSB: Brings up/down network automatically... Oct 13 17:42:02 volumio-pi systemd[1]: Started Permit User Sessions. Oct 13 17:42:02 volumio-pi systemd[1]: Started /etc/rc.local Compatibility. Oct 13 17:42:02 volumio-pi bluetoothd[673]: Bluetooth daemon 5.50 Oct 13 17:42:03 volumio-pi systemd[1]: Starting Terminate Plymouth Boot Screen... Oct 13 17:42:03 volumio-pi systemd[1]: Starting Hold until boot process finishes up... Oct 13 17:42:03 volumio-pi bluetoothd[673]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Oct 13 17:42:03 volumio-pi systemd[1]: Started Bluetooth service. Oct 13 17:42:03 volumio-pi bluetoothd[673]: Starting SDP server Oct 13 17:42:03 volumio-pi bluetoothd[673]: Excluding (cli) sap Oct 13 17:42:03 volumio-pi systemd[1]: Reached target Bluetooth. Oct 13 17:42:03 volumio-pi loadcpufreq[575]: Loading cpufreq kernel modules...done (none). Oct 13 17:42:03 volumio-pi systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Oct 13 17:42:03 volumio-pi systemd[1]: Received SIGRTMIN+21 from PID 191 (plymouthd). Oct 13 17:42:03 volumio-pi systemd[1]: Starting LSB: set CPUFreq kernel parameters... Oct 13 17:42:03 volumio-pi systemd[1]: plymouth-quit.service: Succeeded. Oct 13 17:42:03 volumio-pi systemd[1]: Started Terminate Plymouth Boot Screen. Oct 13 17:42:03 volumio-pi haveged[489]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Oct 13 17:42:03 volumio-pi haveged[489]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Oct 13 17:42:03 volumio-pi haveged[489]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99766 Oct 13 17:42:03 volumio-pi haveged[489]: haveged: fills: 0, generated: 0 Oct 13 17:42:03 volumio-pi systemd[1]: plymouth-quit-wait.service: Succeeded. Oct 13 17:42:03 volumio-pi systemd[1]: Started Hold until boot process finishes up. Oct 13 17:42:03 volumio-pi systemd[1]: Received SIGRTMIN+21 from PID 191 (plymouthd). Oct 13 17:42:03 volumio-pi kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Oct 13 17:42:03 volumio-pi kernel: Bluetooth: BNEP filters: protocol multicast Oct 13 17:42:03 volumio-pi kernel: Bluetooth: BNEP socket layer initialized Oct 13 17:42:03 volumio-pi bluetoothd[673]: Bluetooth management interface 1.22 initialized Oct 13 17:42:03 volumio-pi dbus-daemon[521]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=673 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Oct 13 17:42:03 volumio-pi kernel: Bluetooth: MGMT ver 1.22 Oct 13 17:42:03 volumio-pi ntpd[708]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 13 17:42:03 volumio-pi ntpd[708]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Oct 13 17:42:03 volumio-pi bluetoothd[673]: Failed to set privacy: Rejected (0x0b) Oct 13 17:42:03 volumio-pi ifplugd(eth0)[764]: ifplugd 0.28 initializing. Oct 13 17:42:03 volumio-pi ifplugd(eth0)[764]: Using interface eth0/B8:27:EB:F0:15:9C with driver (version: 6.1.69-v7+) Oct 13 17:42:03 volumio-pi ntpd[756]: proto: precision = 1.458 usec (-19) Oct 13 17:42:03 volumio-pi ifplugd(eth0)[764]: Using detection mode: SIOCETHTOOL Oct 13 17:42:03 volumio-pi ifplugd(eth0)[764]: Initialization complete, link beat not detected. Oct 13 17:42:03 volumio-pi ntpd[756]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 13 17:42:03 volumio-pi ntpd[756]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 13 17:42:03 volumio-pi ntpd[756]: Listen and drop on 0 v6wildcard [::]:123 Oct 13 17:42:03 volumio-pi ntpd[756]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 13 17:42:03 volumio-pi ntpd[756]: Listen normally on 2 lo 127.0.0.1:123 Oct 13 17:42:03 volumio-pi ntpd[756]: Listening on routing socket on fd #19 for interface updates Oct 13 17:42:03 volumio-pi ntpd[756]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 13 17:42:03 volumio-pi ntpd[756]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 13 17:42:03 volumio-pi ifplugd[718]: Network Interface Plugging Daemon...start eth0...done. Oct 13 17:42:03 volumio-pi cpufrequtils[745]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Oct 13 17:42:03 volumio-pi systemd[1]: Started Network Time Service. Oct 13 17:42:03 volumio-pi systemd[1]: Started LSB: Brings up/down network automatically. Oct 13 17:42:03 volumio-pi systemd[1]: Started LSB: set CPUFreq kernel parameters. Oct 13 17:42:03 volumio-pi systemd[1]: Starting Hostname Service... Oct 13 17:42:03 volumio-pi systemd[1]: Started Getty on tty1. Oct 13 17:42:03 volumio-pi systemd[1]: Reached target Login Prompts. Oct 13 17:42:04 volumio-pi dbus-daemon[521]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 13 17:42:04 volumio-pi systemd[1]: Started Hostname Service. Oct 13 17:42:04 volumio-pi nmbd[725]: [2024/10/13 17:42:04.448990, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Oct 13 17:42:04 volumio-pi nmbd[725]: started asyncdns process 777 Oct 13 17:42:04 volumio-pi nmbd[725]: [2024/10/13 17:42:04.451325, 0] ../lib/util/become_daemon.c:149(daemon_status) Oct 13 17:42:04 volumio-pi nmbd[725]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Oct 13 17:42:04 volumio-pi nmbd[725]: [2024/10/13 17:42:04.451552, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Oct 13 17:42:04 volumio-pi nmbd[725]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Oct 13 17:42:04 volumio-pi ntpd[756]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 13 17:42:04 volumio-pi systemd[1]: systemd-rfkill.service: Succeeded. Oct 13 17:42:04 volumio-pi wireless.js[518]: Cleaning previous... Oct 13 17:42:05 volumio-pi sudo[785]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 13 17:42:05 volumio-pi sudo[785]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:05 volumio-pi sudo[785]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:05 volumio-pi sudo[787]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 13 17:42:05 volumio-pi sudo[787]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:05 volumio-pi ntpd[756]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 13 17:42:05 volumio-pi sudo[787]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:05 volumio-pi wireless.js[518]: Stopped aP Oct 13 17:42:05 volumio-pi sudo[794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 13 17:42:05 volumio-pi sudo[794]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:05 volumio-pi sudo[794]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:05 volumio-pi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 13 17:42:05 volumio-pi sudo[796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Oct 13 17:42:05 volumio-pi sudo[796]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:05 volumio-pi sudo[796]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:05 volumio-pi sudo[803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 13 17:42:05 volumio-pi sudo[803]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:05 volumio-pi sudo[803]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:05 volumio-pi sudo[805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Oct 13 17:42:05 volumio-pi sudo[805]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:06 volumio-pi ntpd[756]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 13 17:42:06 volumio-pi bthelper[564]: Changing power off succeeded Oct 13 17:42:06 volumio-pi bthelper[564]: [CHG] Controller B8:27:EB:5A:BF:36 Class: 0x0000041c Oct 13 17:42:06 volumio-pi bthelper[564]: Changing power on succeeded Oct 13 17:42:07 volumio-pi volumio-remote-updater[528]: [2024-10-13 17:42:07] [info] asio async_connect error: system:111 (Connection refused) Oct 13 17:42:07 volumio-pi volumio-remote-updater[528]: [2024-10-13 17:42:07] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 13 17:42:07 volumio-pi volumio-remote-updater[528]: [2024-10-13 17:42:07] [error] handle_connect error: Underlying Transport Error Oct 13 17:42:07 volumio-pi ntpd[756]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 13 17:42:08 volumio-pi sudo[805]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:08 volumio-pi wireless.js[518]: SETTING APPROPRIATE REG DOMAIN: US Oct 13 17:42:08 volumio-pi sudo[816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 13 17:42:08 volumio-pi sudo[816]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:08 volumio-pi sudo[816]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:08 volumio-pi sudo[818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Oct 13 17:42:08 volumio-pi sudo[818]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:08 volumio-pi sudo[818]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:08 volumio-pi wireless.js[518]: SUCCESSFULLY SET NEW REGDOMAIN: US Oct 13 17:42:08 volumio-pi wireless.js[518]: Start wireless flow Oct 13 17:42:08 volumio-pi wireless.js[518]: Stopped hotspot (if there).. Oct 13 17:42:08 volumio-pi sudo[823]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 13 17:42:08 volumio-pi sudo[823]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:08 volumio-pi sudo[823]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:08 volumio-pi sudo[825]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 13 17:42:08 volumio-pi sudo[825]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:09 volumio-pi sudo[825]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:09 volumio-pi wireless.js[518]: DHCP IP Oct 13 17:42:09 volumio-pi wireless.js[518]: Start ap Oct 13 17:42:09 volumio-pi wpa_supplicant[827]: Successfully initialized wpa_supplicant Oct 13 17:42:09 volumio-pi sudo[828]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Oct 13 17:42:09 volumio-pi sudo[828]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:09 volumio-pi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 13 17:42:09 volumio-pi dhcpcd[829]: dev: loaded udev Oct 13 17:42:09 volumio-pi dhcpcd-run-hooks[847]: wlan0: starting wpa_supplicant Oct 13 17:42:09 volumio-pi dhcpcd-run-hooks[852]: wlan0: failed to start wpa_supplicant Oct 13 17:42:09 volumio-pi dhcpcd-run-hooks[853]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Oct 13 17:42:09 volumio-pi dhcpcd[829]: wlan0: connected to Access Point `' Oct 13 17:42:09 volumio-pi dhcpcd[829]: no interfaces have a carrier Oct 13 17:42:09 volumio-pi dhcpcd[829]: forked to background, child pid 863 Oct 13 17:42:09 volumio-pi dhcpcd[863]: eth0: waiting for carrier Oct 13 17:42:09 volumio-pi dhcpcd[863]: wlan0: waiting for carrier Oct 13 17:42:09 volumio-pi sudo[828]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:10 volumio-pi wireless.js[518]: trying... Oct 13 17:42:10 volumio-pi sudo[865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:42:10 volumio-pi sudo[865]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:10 volumio-pi sudo[865]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:11 volumio-pi wireless.js[518]: trying... Oct 13 17:42:11 volumio-pi sudo[868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:42:11 volumio-pi sudo[868]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:11 volumio-pi sudo[868]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:12 volumio-pi wireless.js[518]: trying... Oct 13 17:42:12 volumio-pi sudo[871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:42:12 volumio-pi sudo[871]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:12 volumio-pi sudo[871]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:12 volumio-pi wpa_supplicant[851]: wlan0: Trying to associate with SSID 'Linksys47057_5GHz' Oct 13 17:42:13 volumio-pi wireless.js[518]: trying... Oct 13 17:42:13 volumio-pi sudo[874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:42:13 volumio-pi sudo[874]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:13 volumio-pi sudo[874]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:14 volumio-pi wireless.js[518]: trying... Oct 13 17:42:14 volumio-pi sudo[877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:42:14 volumio-pi sudo[877]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:14 volumio-pi sudo[877]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:14 volumio-pi volumio-remote-updater[528]: [2024-10-13 17:42:14] [info] asio async_connect error: system:111 (Connection refused) Oct 13 17:42:14 volumio-pi volumio-remote-updater[528]: [2024-10-13 17:42:14] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 13 17:42:14 volumio-pi volumio-remote-updater[528]: [2024-10-13 17:42:14] [error] handle_connect error: Underlying Transport Error Oct 13 17:42:15 volumio-pi wireless.js[518]: trying... Oct 13 17:42:15 volumio-pi dhcpcd[863]: wlan0: carrier acquired Oct 13 17:42:15 volumio-pi dhcpcd[863]: wlan0: connected to Access Point `Linksys47057_5GHz' Oct 13 17:42:15 volumio-pi wpa_supplicant[851]: wlan0: Associated with 60:38:e0:32:6f:9e Oct 13 17:42:15 volumio-pi wpa_supplicant[851]: wlan0: CTRL-EVENT-CONNECTED - Connection to 60:38:e0:32:6f:9e completed [id=0 id_str=] Oct 13 17:42:15 volumio-pi wpa_supplicant[851]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 13 17:42:15 volumio-pi wpa_supplicant[851]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Oct 13 17:42:15 volumio-pi dhcpcd[863]: DUID 00:01:00:01:2b:b8:6d:99:b8:27:eb:f0:15:9c Oct 13 17:42:15 volumio-pi dhcpcd[863]: wlan0: IAID eb:a5:40:c9 Oct 13 17:42:15 volumio-pi dhcpcd[863]: wlan0: adding address fe80::ba27:ebff:fea5:40c9 Oct 13 17:42:15 volumio-pi dhcpcd[863]: ipv6_addaddr1: Permission denied Oct 13 17:42:15 volumio-pi dhcpcd[863]: wlan0: carrier lost Oct 13 17:42:15 volumio-pi sudo[880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:42:15 volumio-pi sudo[880]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:15 volumio-pi sudo[880]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:15 volumio-pi dhcpcd[863]: wlan0: carrier acquired Oct 13 17:42:15 volumio-pi dhcpcd[863]: wlan0: IAID eb:a5:40:c9 Oct 13 17:42:15 volumio-pi dhcpcd[863]: wlan0: rebinding lease of 192.168.1.128 Oct 13 17:42:15 volumio-pi dhcpcd[863]: wlan0: probing address 192.168.1.128/24 Oct 13 17:42:16 volumio-pi wireless.js[518]: trying... Oct 13 17:42:16 volumio-pi sudo[895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:42:16 volumio-pi dhcpcd[863]: wlan0: soliciting an IPv6 router Oct 13 17:42:16 volumio-pi sudo[895]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:16 volumio-pi sudo[895]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:17 volumio-pi wireless.js[518]: trying... Oct 13 17:42:17 volumio-pi sudo[898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:42:17 volumio-pi sudo[898]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:17 volumio-pi sudo[898]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:18 volumio-pi wireless.js[518]: trying... Oct 13 17:42:18 volumio-pi sudo[901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:42:18 volumio-pi sudo[901]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:18 volumio-pi sudo[901]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:19 volumio-pi wireless.js[518]: trying... Oct 13 17:42:19 volumio-pi sudo[904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:42:19 volumio-pi sudo[904]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:19 volumio-pi sudo[904]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:20 volumio-pi wireless.js[518]: trying... Oct 13 17:42:20 volumio-pi sudo[907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:42:20 volumio-pi sudo[907]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:20 volumio-pi sudo[907]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:20 volumio-pi dhcpcd[863]: wlan0: leased 192.168.1.128 for 86400 seconds Oct 13 17:42:20 volumio-pi avahi-daemon[519]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.128. Oct 13 17:42:20 volumio-pi avahi-daemon[519]: New relevant interface wlan0.IPv4 for mDNS. Oct 13 17:42:20 volumio-pi dhcpcd[863]: wlan0: adding route to 192.168.1.0/24 Oct 13 17:42:20 volumio-pi avahi-daemon[519]: Registering new address record for 192.168.1.128 on wlan0.IPv4. Oct 13 17:42:20 volumio-pi dhcpcd[863]: wlan0: adding default route via 192.168.1.1 Oct 13 17:42:21 volumio-pi wireless.js[518]: trying... Oct 13 17:42:21 volumio-pi sudo[931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 13 17:42:21 volumio-pi sudo[931]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:21 volumio-pi sudo[931]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:21 volumio-pi wireless.js[518]: Connected to: ----Linksys47057_5GHz Oct 13 17:42:21 volumio-pi wireless.js[518]: ---- Oct 13 17:42:21 volumio-pi sudo[934]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 17:42:21 volumio-pi sudo[934]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:21 volumio-pi sudo[934]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:21 volumio-pi wireless.js[518]: ... joined AP, wlan0 IPv4 is 192.168.1.128, ipV6 is undefined Oct 13 17:42:21 volumio-pi wireless.js[518]: It's done! AP Oct 13 17:42:21 volumio-pi systemd[1]: Started Wireless Services. Oct 13 17:42:21 volumio-pi systemd[1]: Started Volumio Backend Module. Oct 13 17:42:21 volumio-pi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 13 17:42:21 volumio-pi systemd[1]: Started Volumio Cpu Tweaker. Oct 13 17:42:21 volumio-pi volumio-cpu-tweak[940]: Setting RT Priority for mpd Oct 13 17:42:21 volumio-pi volumio-cpu-tweak[940]: pid 35's current scheduling policy: SCHED_OTHER Oct 13 17:42:21 volumio-pi volumio-cpu-tweak[940]: pid 35's current scheduling priority: 0 Oct 13 17:42:21 volumio-pi volumio-cpu-tweak[940]: Setting MPD Affinity Oct 13 17:42:21 volumio-pi volumio-cpu-tweak[940]: pid 3's current affinity mask: f Oct 13 17:42:21 volumio-pi volumio-cpu-tweak[940]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Oct 13 17:42:21 volumio-pi volumio-cpu-tweak[940]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Oct 13 17:42:21 volumio-pi systemd[1]: volumio_cpu_tweak.service: Succeeded. Oct 13 17:42:22 volumio-pi ntpd[756]: Listen normally on 3 wlan0 192.168.1.128:123 Oct 13 17:42:22 volumio-pi ntpd[756]: new interface(s) found: waking up resolver Oct 13 17:42:24 volumio-pi volumio[939]: info: ------------------------------------------- Oct 13 17:42:24 volumio-pi volumio[939]: info: ----- Volumio3 ---- Oct 13 17:42:24 volumio-pi volumio[939]: info: ------------------------------------------- Oct 13 17:42:24 volumio-pi volumio[939]: info: ----- System startup ---- Oct 13 17:42:24 volumio-pi volumio[939]: info: ------------------------------------------- Oct 13 17:42:24 volumio-pi systemd[1]: Started Samba NMB Daemon. Oct 13 17:42:24 volumio-pi nmbd[725]: [2024/10/13 17:42:24.463042, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 13 17:42:24 volumio-pi systemd[1]: Starting Samba Winbind Daemon... Oct 13 17:42:24 volumio-pi nmbd[725]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 13 17:42:24 volumio-pi winbindd[964]: [2024/10/13 17:42:24.878724, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Oct 13 17:42:24 volumio-pi winbindd[964]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 13 17:42:24 volumio-pi volumio-remote-updater[528]: [2024-10-13 17:42:24] [connect] Successful connection Oct 13 17:42:24 volumio-pi winbindd[964]: [2024/10/13 17:42:24.970571, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 13 17:42:24 volumio-pi systemd[1]: Started Samba Winbind Daemon. Oct 13 17:42:24 volumio-pi winbindd[964]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Oct 13 17:42:25 volumio-pi systemd[1]: Starting Samba SMB Daemon... Oct 13 17:42:25 volumio-pi winbindd[964]: [2024/10/13 17:42:25.588815, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Oct 13 17:42:25 volumio-pi winbindd[964]: res_names->count = 3, expected 4 Oct 13 17:42:25 volumio-pi smbd[969]: [2024/10/13 17:42:25.628528, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 13 17:42:25 volumio-pi systemd[1]: Started Samba SMB Daemon. Oct 13 17:42:25 volumio-pi volumio[939]: info: MYVOLUMIO Environment detected Oct 13 17:42:25 volumio-pi smbd[969]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Oct 13 17:42:25 volumio-pi systemd[1]: Reached target Multi-User System. Oct 13 17:42:25 volumio-pi systemd[1]: Reached target Graphical Interface. Oct 13 17:42:25 volumio-pi systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 13 17:42:25 volumio-pi systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 13 17:42:25 volumio-pi systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 13 17:42:25 volumio-pi systemd[1]: Startup finished in 26.934s (kernel) + 36.270s (userspace) = 1min 3.204s. Oct 13 17:42:25 volumio-pi volumio[939]: info: Plugin folders cleanup Oct 13 17:42:25 volumio-pi volumio[939]: info: Scanning into folder /volumio/app/plugins/ Oct 13 17:42:25 volumio-pi volumio[939]: info: Scanning category audio_interface Oct 13 17:42:25 volumio-pi volumio[939]: info: Scanning category miscellanea Oct 13 17:42:25 volumio-pi volumio[939]: info: Scanning category music_service Oct 13 17:42:25 volumio-pi volumio[939]: info: Scanning category plugins.json Oct 13 17:42:25 volumio-pi volumio[939]: info: Scanning category system_controller Oct 13 17:42:25 volumio-pi volumio[939]: info: Scanning category user_interface Oct 13 17:42:25 volumio-pi volumio[939]: info: Scanning into folder /data/plugins/ Oct 13 17:42:25 volumio-pi volumio[939]: info: Scanning category audio_interface Oct 13 17:42:25 volumio-pi volumio[939]: info: Scanning category music_service Oct 13 17:42:25 volumio-pi volumio[939]: info: Plugin folders cleanup completed Oct 13 17:42:25 volumio-pi volumio[939]: info: ------------------------------------------- Oct 13 17:42:25 volumio-pi volumio[939]: info: ----- Core plugins startup ---- Oct 13 17:42:25 volumio-pi volumio[939]: info: ------------------------------------------- Oct 13 17:42:25 volumio-pi volumio[939]: info: Loading plugins from folder /volumio/app/plugins/ Oct 13 17:42:25 volumio-pi volumio[939]: info: Adding plugin upnp to MyMusic Plugins Oct 13 17:42:25 volumio-pi volumio[939]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 13 17:42:25 volumio-pi volumio[939]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 13 17:42:25 volumio-pi volumio[939]: info: Loading plugins from folder /data/plugins/ Oct 13 17:42:25 volumio-pi volumio[939]: info: Loading plugin "system"... Oct 13 17:42:25 volumio-pi volumio[939]: info: Loading plugin "appearance"... Oct 13 17:42:27 volumio-pi volumio[939]: info: Loading plugin "network"... Oct 13 17:42:27 volumio-pi volumio[939]: info: Refreshing Cached IP Addresses Oct 13 17:42:27 volumio-pi sudo[984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 17:42:27 volumio-pi sudo[984]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:27 volumio-pi volumio[939]: info: Loading plugin "services"... Oct 13 17:42:27 volumio-pi sudo[986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 17:42:27 volumio-pi sudo[986]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:27 volumio-pi volumio[939]: info: Loading plugin "alsa_controller"... Oct 13 17:42:27 volumio-pi sudo[986]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:27 volumio-pi sudo[984]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:27 volumio-pi sudo[989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 13 17:42:27 volumio-pi sudo[989]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:27 volumio-pi volumio[939]: warn: Unable to locate the audio output device FiiO K3. Please configure a valid output device. Oct 13 17:42:27 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 17:42:27 volumio-pi volumio[939]: info: Loading plugin "wizard"... Oct 13 17:42:27 volumio-pi volumio[939]: info: Loading plugin "networkfs"... Oct 13 17:42:27 volumio-pi volumio[939]: info: Starting Udev Watcher for removable devices Oct 13 17:42:27 volumio-pi sudo[995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.115/05_Music /mnt/NAS/05_Music Oct 13 17:42:27 volumio-pi sudo[995]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:27 volumio-pi volumio[939]: info: Ignoring mount for partition: boot Oct 13 17:42:27 volumio-pi volumio[939]: info: Ignoring mount for partition: volumio Oct 13 17:42:27 volumio-pi volumio[939]: info: Ignoring mount for partition: volumio_data Oct 13 17:42:27 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 17:42:27 volumio-pi volumio[939]: info: Loading plugin "volumio_command_line_client"... Oct 13 17:42:27 volumio-pi volumio[939]: info: Loading plugin "upnp"... Oct 13 17:42:27 volumio-pi volumio[939]: info: [1728808947698] Starting Upmpd Daemon Oct 13 17:42:27 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 17:42:27 volumio-pi volumio[939]: info: Loading plugin "my_music"... Oct 13 17:42:27 volumio-pi volumio[939]: info: Loading plugin "mpd"... Oct 13 17:42:27 volumio-pi systemd[1]: systemd-fsckd.service: Succeeded. Oct 13 17:42:27 volumio-pi kernel: Key type cifs.spnego registered Oct 13 17:42:27 volumio-pi kernel: Key type cifs.idmap registered Oct 13 17:42:27 volumio-pi 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. Oct 13 17:42:27 volumio-pi kernel: CIFS: Attempting to mount \\192.168.1.115\05_Music Oct 13 17:42:28 volumio-pi volumio[939]: info: Loading plugin "upnp_browser"... Oct 13 17:42:28 volumio-pi volumio[939]: info: Loading plugin "alarm-clock"... Oct 13 17:42:28 volumio-pi volumio[939]: info: Loading plugin "airplay_emulation"... Oct 13 17:42:28 volumio-pi volumio[939]: info: Starting Shairport Sync Oct 13 17:42:28 volumio-pi volumio[939]: info: Loading plugin "last_100"... Oct 13 17:42:28 volumio-pi volumio[939]: info: Loading plugin "webradio"... Oct 13 17:42:29 volumio-pi volumio[939]: info: Loading plugin "i2s_dacs"... Oct 13 17:42:29 volumio-pi volumio[939]: info: I2S DAC not set, start Auto-detection Oct 13 17:42:29 volumio-pi volumio[939]: info: Loading plugin "volumiodiscovery"... Oct 13 17:42:29 volumio-pi volumio[939]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 13 17:42:29 volumio-pi volumio[939]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 17:42:29 volumio-pi volumio[939]: *** WARNING *** For more information see Oct 13 17:42:29 volumio-pi volumio[939]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 13 17:42:29 volumio-pi node[939]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 13 17:42:29 volumio-pi volumio[939]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 17:42:29 volumio-pi volumio[939]: *** WARNING *** For more information see Oct 13 17:42:29 volumio-pi node[939]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 17:42:29 volumio-pi node[939]: *** WARNING *** For more information see Oct 13 17:42:29 volumio-pi node[939]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 13 17:42:29 volumio-pi node[939]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 17:42:29 volumio-pi node[939]: *** WARNING *** For more information see Oct 13 17:42:29 volumio-pi volumio[939]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 13 17:42:29 volumio-pi volumio[939]: info: Discovery: Started advertising with name: Volumio Pi Oct 13 17:42:29 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 17:42:29 volumio-pi volumio[939]: info: Loading plugin "outputs"... Oct 13 17:42:29 volumio-pi volumio[939]: info: Loading plugin "albumart"... Oct 13 17:42:29 volumio-pi volumio[939]: info: Plugin example_plugin is not enabled Oct 13 17:42:29 volumio-pi volumio[939]: info: Loading plugin "inputs"... Oct 13 17:42:29 volumio-pi volumio[939]: info: Loading plugin "updater_comm"... Oct 13 17:42:29 volumio-pi volumio[939]: info: Plugin mpdemulation is not enabled Oct 13 17:42:29 volumio-pi volumio[939]: info: Loading plugin "rest_api"... Oct 13 17:42:29 volumio-pi volumio[939]: info: Loading plugin "websocket"... Oct 13 17:42:29 volumio-pi volumio[939]: info: Starting Socket.io Server version 2.3.0 Oct 13 17:42:29 volumio-pi volumio[939]: info: Loading plugin "fusiondsp"... Oct 13 17:42:29 volumio-pi volumio[939]: Forking 3 albumart workers Oct 13 17:42:30 volumio-pi volumio[939]: info: Applying required configuration parameters for plugin fusiondsp Oct 13 17:42:30 volumio-pi volumio[939]: info: Loading plugin "80s80s"... Oct 13 17:42:30 volumio-pi volumio[939]: Starting albumart workers Oct 13 17:42:30 volumio-pi volumio[939]: Starting albumart workers Oct 13 17:42:30 volumio-pi volumio[939]: Starting albumart workers Oct 13 17:42:31 volumio-pi volumio[939]: info: Applying required configuration parameters for plugin 80s80s Oct 13 17:42:31 volumio-pi volumio[939]: info: [1728808951333] [80s80s] API delay: 30 Oct 13 17:42:31 volumio-pi volumio[939]: info: Loading plugin "radio_paradise"... Oct 13 17:42:31 volumio-pi sudo[989]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:31 volumio-pi volumio[939]: info: Applying required configuration parameters for plugin radio_paradise Oct 13 17:42:31 volumio-pi volumio[939]: info: [1728808951763] [RadioParadise] API delay: 5 Oct 13 17:42:31 volumio-pi volumio[939]: info: Loading i18n strings for locale ko Oct 13 17:42:31 volumio-pi volumio[939]: Updating browse sources language Oct 13 17:42:31 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreCommandRouter::initPlayerControls Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 17:42:32 volumio-pi volumio[939]: Express server listening on port 3000 Oct 13 17:42:32 volumio-pi volumio[939]: [Metrics] WebUI: 8s 747.10ms Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreStateMachine::resetVolumioState Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreStateMachine::getcurrentVolume Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreCommandRouter::volumioRetrievevolume Oct 13 17:42:32 volumio-pi sh[492]: timed out Oct 13 17:42:32 volumio-pi dhcpcd[558]: timed out Oct 13 17:42:32 volumio-pi sh[492]: dhcpcd exited Oct 13 17:42:32 volumio-pi dhcpcd[558]: dhcpcd exited Oct 13 17:42:32 volumio-pi sh[492]: ifup: failed to bring up eth0 Oct 13 17:42:32 volumio-pi systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Oct 13 17:42:32 volumio-pi systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:42:32 volumio-pi volumio[939]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 13 17:42:32 volumio-pi volumio[939]: info: VolumeController:: Volume=undefined Mute =false Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreStateMachine::pushState Oct 13 17:42:32 volumio-pi volumio[939]: info: CorePlayQueue::getTrack 0 Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreCommandRouter::volumioPushState Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreStateMachine::updateTrackBlock Oct 13 17:42:32 volumio-pi volumio[939]: info: CorePlayQueue::getTrackBlock Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreCommandRouter::volumioRetrievevolume Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 17:42:32 volumio-pi volumio[939]: info: Discovery: Getting this device information Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreCommandRouter::volumioGetState Oct 13 17:42:32 volumio-pi volumio[939]: info: CorePlayQueue::getTrack 0 Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 17:42:32 volumio-pi volumio[939]: info: Reloading queue from file Oct 13 17:42:32 volumio-pi volumio[939]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 13 17:42:32 volumio-pi volumio[939]: info: Setting Device type: Raspberry PI Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreStateMachine::setRepeat true single undefined Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreStateMachine::pushState Oct 13 17:42:32 volumio-pi volumio[939]: info: CorePlayQueue::getTrack 0 Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreCommandRouter::volumioPushState Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreStateMachine::setRandom false Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreStateMachine::pushState Oct 13 17:42:32 volumio-pi volumio[939]: info: CorePlayQueue::getTrack 0 Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreCommandRouter::volumioPushState Oct 13 17:42:32 volumio-pi volumio[939]: info: VolumeController:: Volume=undefined Mute =false Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreStateMachine::pushState Oct 13 17:42:32 volumio-pi volumio[939]: info: CorePlayQueue::getTrack 0 Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreCommandRouter::volumioPushState Oct 13 17:42:32 volumio-pi volumio[939]: info: Discovery: adding 1bdf6a08-5168-44e1-8ec9-05aa93dafdae Oct 13 17:42:32 volumio-pi volumio[939]: info: Discovery: Found device Volumio Pi Oct 13 17:42:32 volumio-pi volumio[939]: info: CoreCommandRouter::volumioGetState Oct 13 17:42:32 volumio-pi volumio[939]: info: CorePlayQueue::getTrack 0 Oct 13 17:42:34 volumio-pi sudo[995]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:34 volumio-pi volumio[939]: info: Cannot mount NAS 05_Music at system boot, trial number 1 ,retrying in 5 seconds Oct 13 17:42:34 volumio-pi volumio[939]: info: Completed loading Core Plugins Oct 13 17:42:34 volumio-pi kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 13 17:42:34 volumio-pi kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Oct 13 17:42:34 volumio-pi volumio[939]: info: Preparing to generate the ALSA configuration file Oct 13 17:42:34 volumio-pi systemd[1]: systemd-hostnamed.service: Succeeded. Oct 13 17:42:34 volumio-pi volumio[939]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 13 17:42:34 volumio-pi volumio[939]: info: Reading ALSA contributions from plugins. Oct 13 17:42:34 volumio-pi volumio[939]: info: Asound.conf file unchanged, so no further update is needed Oct 13 17:42:34 volumio-pi volumio[939]: info: Output device has changed, restarting MPD Oct 13 17:42:34 volumio-pi volumio[939]: info: Output device has changed, restarting Shairport Sync Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:42:34 volumio-pi sudo[1078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 17:42:34 volumio-pi sudo[1078]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:34 volumio-pi sudo[1078]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:34 volumio-pi sudo[1080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 17:42:34 volumio-pi sudo[1080]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:34 volumio-pi volumio[939]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 17:42:34 volumio-pi volumio[939]: info: ___________ START PLUGINS ___________ Oct 13 17:42:34 volumio-pi volumio[939]: info: ControllerMpd::onStart: Initializing MPD Oct 13 17:42:34 volumio-pi volumio[939]: info: Creating MPD Configuration file Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 17:42:34 volumio-pi volumio[939]: info: [1728808954316] CoreMusicLibrary::Adding element 미디어 서버 Oct 13 17:42:34 volumio-pi systemd[1]: Listening on mpd.socket. Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 17:42:34 volumio-pi sudo[1088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 17:42:34 volumio-pi systemd[1]: Starting Music Player Daemon... Oct 13 17:42:34 volumio-pi sudo[1088]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:34 volumio-pi sudo[1088]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:34 volumio-pi sudo[1090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 17:42:34 volumio-pi sudo[1090]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:42:34 volumio-pi systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 13 17:42:34 volumio-pi systemd[1]: mpd.service: Succeeded. Oct 13 17:42:34 volumio-pi systemd[1]: Stopped Music Player Daemon. Oct 13 17:42:34 volumio-pi systemd[1]: Starting Music Player Daemon... Oct 13 17:42:34 volumio-pi volumio[939]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:42:34 volumio-pi volumio[939]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 17:42:34 volumio-pi volumio[939]: info: [1728808954452] CoreMusicLibrary::Adding element Last_100 Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 17:42:34 volumio-pi volumio[939]: info: [1728808954455] CoreMusicLibrary::Adding element Webradio Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 17:42:34 volumio-pi volumio[939]: info: Initializing BBC Radios Oct 13 17:42:34 volumio-pi sudo[1096]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 17:42:34 volumio-pi sudo[1096]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:34 volumio-pi sudo[1096]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 13 17:42:34 volumio-pi sudo[1096]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:42:34 volumio-pi volumio[939]: info: Loading i18n strings for locale ko Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:34 volumio-pi volumio[939]: info: FusionDsp - mixtype--------------------- undefined Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 17:42:34 volumio-pi volumio[939]: info: [1728808954580] CoreMusicLibrary::Adding element 80s80s Radio Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 17:42:34 volumio-pi volumio[939]: Cannot find translation for source 80s80s Radio Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 17:42:34 volumio-pi volumio[939]: info: [1728808954590] CoreMusicLibrary::Adding element Radio Paradise Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 17:42:34 volumio-pi volumio[939]: Cannot find translation for source 80s80s Radio Oct 13 17:42:34 volumio-pi volumio[939]: Cannot find translation for source Radio Paradise Oct 13 17:42:34 volumio-pi volumio[939]: info: Volumio Calling Home Oct 13 17:42:34 volumio-pi volumio[939]: info: Preparing to generate the ALSA configuration file Oct 13 17:42:34 volumio-pi volumio[939]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 13 17:42:34 volumio-pi volumio[939]: info: Reading ALSA contributions from plugins. Oct 13 17:42:34 volumio-pi volumio[939]: info: MPD Permissions set Oct 13 17:42:34 volumio-pi volumio[939]: info: MPD Permissions set Oct 13 17:42:34 volumio-pi volumio[939]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:42:34 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:35 volumio-pi volumio[939]: info: Starting Shairport Sync Oct 13 17:42:35 volumio-pi volumio[939]: info: Starting Shairport Sync Oct 13 17:42:35 volumio-pi volumio[939]: info: Starting Shairport Sync Oct 13 17:42:35 volumio-pi sudo[1125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 17:42:35 volumio-pi sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:35 volumio-pi sudo[1127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 17:42:35 volumio-pi sudo[1127]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:35 volumio-pi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 13 17:42:35 volumio-pi systemd[1]: shairport-sync.service: Succeeded. Oct 13 17:42:35 volumio-pi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 13 17:42:35 volumio-pi volumio[939]: info: CoreCommandRouter::volumioGetState Oct 13 17:42:35 volumio-pi volumio[939]: info: CorePlayQueue::getTrack 0 Oct 13 17:42:35 volumio-pi sudo[1130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 17:42:35 volumio-pi sudo[1130]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:35 volumio-pi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 13 17:42:35 volumio-pi sudo[1125]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:35 volumio-pi sudo[1127]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:35 volumio-pi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 13 17:42:35 volumio-pi systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 13 17:42:35 volumio-pi systemd[1]: shairport-sync.service: Succeeded. Oct 13 17:42:35 volumio-pi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 13 17:42:35 volumio-pi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 13 17:42:35 volumio-pi sudo[1130]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:35 volumio-pi volumio[939]: info: Asound.conf file unchanged, so no further update is needed Oct 13 17:42:35 volumio-pi volumio[939]: info: Output device has changed, restarting MPD Oct 13 17:42:35 volumio-pi volumio[939]: info: Output device has changed, restarting Shairport Sync Oct 13 17:42:35 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:35 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:42:35 volumio-pi sudo[1138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 17:42:35 volumio-pi sudo[1138]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:35 volumio-pi sudo[1142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 17:42:35 volumio-pi sudo[1138]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:35 volumio-pi sudo[1142]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:35 volumio-pi systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 13 17:42:35 volumio-pi systemd[1]: mpd.service: Succeeded. Oct 13 17:42:35 volumio-pi systemd[1]: Stopped Music Player Daemon. Oct 13 17:42:35 volumio-pi volumio[939]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 17:42:35 volumio-pi volumio[939]: info: Shairport-Sync Started Oct 13 17:42:35 volumio-pi volumio[939]: Error adding Membership: Error: addMembership EINVAL Oct 13 17:42:35 volumio-pi volumio[939]: info: Shairport-Sync Started Oct 13 17:42:35 volumio-pi volumio[939]: info: Shairport-Sync Started Oct 13 17:42:35 volumio-pi systemd[1]: Starting Music Player Daemon... Oct 13 17:42:35 volumio-pi volumio[939]: info: MPD Permissions set Oct 13 17:42:35 volumio-pi volumio[939]: info: Volumio called home Oct 13 17:42:35 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:35 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:35 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:35 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:35 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:35 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:35 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:35 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:35 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 17:42:35 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:35 volumio-pi volumio[939]: info: Starting Shairport Sync Oct 13 17:42:35 volumio-pi sudo[1155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 17:42:35 volumio-pi sudo[1155]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:35 volumio-pi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 13 17:42:35 volumio-pi systemd[1]: shairport-sync.service: Succeeded. Oct 13 17:42:35 volumio-pi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 13 17:42:35 volumio-pi sudo[1148]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 17:42:35 volumio-pi sudo[1148]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:35 volumio-pi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 13 17:42:35 volumio-pi sudo[1148]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 13 17:42:35 volumio-pi sudo[1148]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:35 volumio-pi sudo[1155]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:35 volumio-pi volumio[939]: info: Shairport-Sync Started Oct 13 17:42:36 volumio-pi volumio[939]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Oct 13 17:42:36 volumio-pi volumio[939]: info: camilladsp service started and running in background, instance 1 Oct 13 17:42:36 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 17:42:36 volumio-pi volumio[939]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 13 17:42:36 volumio-pi volumio[939]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Oct 13 17:42:36 volumio-pi volumio[939]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 13 17:42:36 volumio-pi volumio[939]: info: FusionDsp loaded Oct 13 17:42:36 volumio-pi volumio[939]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 17:42:36 volumio-pi sudo[1173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 13 17:42:36 volumio-pi sudo[1173]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 17:42:36 volumio-pi volumio[939]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 13 17:42:36 volumio-pi volumio[939]: info: Adding Signal Path Element [object Object] Oct 13 17:42:36 volumio-pi volumio[939]: info: Adding fusiondspeq DSP Signal Path Element Oct 13 17:42:36 volumio-pi volumio[939]: info: FusionDsp - ---- installed callbackRead Oct 13 17:42:36 volumio-pi volumio[939]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 17:42:36 volumio-pi volumio[939]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 13 17:42:36 volumio-pi volumio[939]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Oct 13 17:42:36 volumio-pi volumio[939]: at onErrorNT (internal/child_process.js:465:16) Oct 13 17:42:36 volumio-pi volumio[939]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Oct 13 17:42:36 volumio-pi volumio[939]: errno: -2, Oct 13 17:42:36 volumio-pi volumio[939]: code: 'ENOENT', Oct 13 17:42:36 volumio-pi volumio[939]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 13 17:42:36 volumio-pi volumio[939]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 13 17:42:36 volumio-pi volumio[939]: spawnargs: [ Oct 13 17:42:36 volumio-pi volumio[939]: '-p', Oct 13 17:42:36 volumio-pi volumio[939]: 9876, Oct 13 17:42:36 volumio-pi volumio[939]: '-o', Oct 13 17:42:36 volumio-pi volumio[939]: '/tmp/camilladsp.log', Oct 13 17:42:36 volumio-pi volumio[939]: '-l', Oct 13 17:42:36 volumio-pi volumio[939]: 'warn', Oct 13 17:42:36 volumio-pi volumio[939]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 13 17:42:36 volumio-pi volumio[939]: ] Oct 13 17:42:36 volumio-pi volumio[939]: } Oct 13 17:42:36 volumio-pi volumio[939]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 17:42:36 volumio-pi systemd[1]: Started FusionDsp Daemon. Oct 13 17:42:36 volumio-pi sudo[1173]: pam_unix(sudo:session): session closed for user root Oct 13 17:42:37 volumio-pi sudo[1185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-13 17:41 Oct 13 17:42:37 volumio-pi sudo[1185]: 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"