-- Logs begin at Sat 2025-10-18 00:25:13 +03, end at Sat 2025-10-18 00:26:32 +03. -- Oct 18 00:25:13 volumio kernel: Booting Linux on physical CPU 0x0 Oct 18 00:25:13 volumio kernel: Linux version 5.15.93-sunxi (root@thinkcentre) (arm-linux-gnueabihf-gcc (GNU Toolchain for the A-profile Architecture 8.3-2019.03 (arm-rel-8.36)) 8.3.0, GNU ld (GNU Toolchain for the A-profile Architecture 8.3-2019.03 (arm-rel-8.36)) 2.32.0.20190321) #trunk SMP Tue Jul 11 17:28:34 CEST 2023 Oct 18 00:25:13 volumio kernel: CPU: ARMv7 Processor [410fc075] revision 5 (ARMv7), cr=50c5387d Oct 18 00:25:13 volumio kernel: CPU: div instructions available: patching division code Oct 18 00:25:13 volumio kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache Oct 18 00:25:13 volumio kernel: OF: fdt: Machine model: Xunlong Orange Pi PC Oct 18 00:25:13 volumio kernel: Memory policy: Data cache writealloc Oct 18 00:25:13 volumio kernel: cma: Reserved 128 MiB at 0x78000000 Oct 18 00:25:13 volumio kernel: Zone ranges: Oct 18 00:25:13 volumio kernel: Normal [mem 0x0000000040000000-0x000000006fffffff] Oct 18 00:25:13 volumio kernel: HighMem [mem 0x0000000070000000-0x000000007fffffff] Oct 18 00:25:13 volumio kernel: Movable zone start for each node Oct 18 00:25:13 volumio kernel: Early memory node ranges Oct 18 00:25:13 volumio kernel: node 0: [mem 0x0000000040000000-0x000000007fffffff] Oct 18 00:25:13 volumio kernel: Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] Oct 18 00:25:13 volumio kernel: psci: probing for conduit method from DT. Oct 18 00:25:13 volumio kernel: psci: Using PSCI v0.1 Function IDs from DT Oct 18 00:25:13 volumio kernel: percpu: Embedded 17 pages/cpu s38540 r8192 d22900 u69632 Oct 18 00:25:13 volumio kernel: pcpu-alloc: s38540 r8192 d22900 u69632 alloc=17*4096 Oct 18 00:25:13 volumio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 18 00:25:13 volumio kernel: Built 1 zonelists, mobility grouping on. Total pages: 260416 Oct 18 00:25:13 volumio kernel: Kernel command line: root=/dev/mmcblk0p2 rootwait rootfstype=ext4 splash plymouth.ignore-serial-consoles console=ttyS0,115200 console=tty1 hdmi.audio=EDID:0 disp.screen0_output_mode=1920x1080p60 consoleblank=0 loglevel=0 ubootpart=01101494-01 ubootsource=mmc usb-storage.quirks=0x2537:0x1066:u,0x2537:0x1068:u imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh net.ifnames=0 hwver=orangepi sunxi_ve_mem_reserve=0 sunxi_g2d_mem_reserve=0 sunxi_fb_mem_reserve=16 cgroup_enable=memory swapaccount=1 Oct 18 00:25:13 volumio kernel: Unknown kernel command line parameters "splash ubootpart=01101494-01 ubootsource=mmc imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh hwver=orangepi sunxi_ve_mem_reserve=0 sunxi_g2d_mem_reserve=0 sunxi_fb_mem_reserve=16 cgroup_enable=memory", will be passed to user space. Oct 18 00:25:13 volumio kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Oct 18 00:25:13 volumio kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 18 00:25:13 volumio kernel: mem auto-init: stack:off, heap alloc:on, heap free:off Oct 18 00:25:13 volumio kernel: Memory: 875852K/1048576K available (9216K kernel code, 1510K rwdata, 3408K rodata, 1024K init, 326K bss, 41652K reserved, 131072K cma-reserved, 131072K highmem) Oct 18 00:25:13 volumio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 18 00:25:13 volumio kernel: ftrace: allocating 44001 entries in 130 pages Oct 18 00:25:13 volumio kernel: ftrace: allocated 129 pages with 2 groups Oct 18 00:25:13 volumio kernel: trace event string verifier disabled Oct 18 00:25:13 volumio kernel: rcu: Hierarchical RCU implementation. Oct 18 00:25:13 volumio kernel: rcu: RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=4. Oct 18 00:25:13 volumio kernel: Rude variant of Tasks RCU enabled. Oct 18 00:25:13 volumio kernel: Tracing variant of Tasks RCU enabled. Oct 18 00:25:13 volumio kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Oct 18 00:25:13 volumio kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 18 00:25:13 volumio kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Oct 18 00:25:13 volumio kernel: GIC: Using split EOI/Deactivate mode Oct 18 00:25:13 volumio kernel: clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635851949 ns Oct 18 00:25:13 volumio kernel: arch_timer: cp15 timer(s) running at 24.00MHz (phys). Oct 18 00:25:13 volumio kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns Oct 18 00:25:13 volumio kernel: sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns Oct 18 00:25:13 volumio kernel: Switching to timer-based delay loop, resolution 41ns Oct 18 00:25:13 volumio kernel: Console: colour dummy device 80x30 Oct 18 00:25:13 volumio kernel: printk: console [tty1] enabled Oct 18 00:25:13 volumio kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000) Oct 18 00:25:13 volumio kernel: pid_max: default: 32768 minimum: 301 Oct 18 00:25:13 volumio kernel: LSM: Security Framework initializing Oct 18 00:25:13 volumio kernel: Yama: becoming mindful. Oct 18 00:25:13 volumio kernel: AppArmor: AppArmor initialized Oct 18 00:25:13 volumio kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Oct 18 00:25:13 volumio kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Oct 18 00:25:13 volumio kernel: CPU: Testing write buffer coherency: ok Oct 18 00:25:13 volumio kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Oct 18 00:25:13 volumio kernel: Setting up static identity map for 0x40100000 - 0x40100054 Oct 18 00:25:13 volumio kernel: rcu: Hierarchical SRCU implementation. Oct 18 00:25:13 volumio kernel: smp: Bringing up secondary CPUs ... Oct 18 00:25:13 volumio kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Oct 18 00:25:13 volumio kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Oct 18 00:25:13 volumio kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Oct 18 00:25:13 volumio kernel: smp: Brought up 1 node, 4 CPUs Oct 18 00:25:13 volumio kernel: SMP: Total of 4 processors activated (192.00 BogoMIPS). Oct 18 00:25:13 volumio kernel: CPU: All CPU(s) started in HYP mode. Oct 18 00:25:13 volumio kernel: CPU: Virtualization extensions available. Oct 18 00:25:13 volumio kernel: allocated 1048576 bytes of page_ext Oct 18 00:25:13 volumio kernel: devtmpfs: initialized Oct 18 00:25:13 volumio kernel: VFP support v0.3: implementor 41 architecture 2 part 30 variant 7 rev 5 Oct 18 00:25:13 volumio kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Oct 18 00:25:13 volumio kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 18 00:25:13 volumio kernel: pinctrl core: initialized pinctrl subsystem Oct 18 00:25:13 volumio kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 18 00:25:13 volumio kernel: DMA: preallocated 256 KiB pool for atomic coherent allocations Oct 18 00:25:13 volumio kernel: audit: initializing netlink subsys (disabled) Oct 18 00:25:13 volumio kernel: audit: type=2000 audit(0.036:1): state=initialized audit_enabled=0 res=1 Oct 18 00:25:13 volumio kernel: thermal_sys: Registered thermal governor 'fair_share' Oct 18 00:25:13 volumio kernel: thermal_sys: Registered thermal governor 'bang_bang' Oct 18 00:25:13 volumio kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 18 00:25:13 volumio kernel: cpuidle: using governor ladder Oct 18 00:25:13 volumio kernel: cpuidle: using governor menu Oct 18 00:25:13 volumio kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Oct 18 00:25:13 volumio kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Oct 18 00:25:13 volumio kernel: platform 1c0c000.lcd-controller: Fixing up cyclic dependency with 1100000.mixer Oct 18 00:25:13 volumio kernel: platform 1ee0000.hdmi: Fixing up cyclic dependency with 1c0c000.lcd-controller Oct 18 00:25:13 volumio kernel: platform connector: Fixing up cyclic dependency with 1ee0000.hdmi Oct 18 00:25:13 volumio kernel: cryptd: max_cpu_qlen set to 1000 Oct 18 00:25:13 volumio kernel: raid6: neonx8 gen() 777 MB/s Oct 18 00:25:13 volumio kernel: raid6: neonx8 xor() 576 MB/s Oct 18 00:25:13 volumio kernel: raid6: neonx4 gen() 828 MB/s Oct 18 00:25:13 volumio kernel: raid6: neonx4 xor() 631 MB/s Oct 18 00:25:13 volumio kernel: raid6: neonx2 gen() 914 MB/s Oct 18 00:25:13 volumio kernel: raid6: neonx2 xor() 652 MB/s Oct 18 00:25:13 volumio kernel: raid6: neonx1 gen() 814 MB/s Oct 18 00:25:13 volumio kernel: raid6: neonx1 xor() 564 MB/s Oct 18 00:25:13 volumio kernel: raid6: int32x8 gen() 303 MB/s Oct 18 00:25:13 volumio kernel: raid6: int32x8 xor() 180 MB/s Oct 18 00:25:13 volumio kernel: raid6: int32x4 gen() 354 MB/s Oct 18 00:25:13 volumio kernel: raid6: int32x4 xor() 205 MB/s Oct 18 00:25:13 volumio kernel: raid6: int32x2 gen() 399 MB/s Oct 18 00:25:13 volumio kernel: raid6: int32x2 xor() 213 MB/s Oct 18 00:25:13 volumio kernel: raid6: int32x1 gen() 402 MB/s Oct 18 00:25:13 volumio kernel: raid6: int32x1 xor() 192 MB/s Oct 18 00:25:13 volumio kernel: raid6: using algorithm neonx2 gen() 914 MB/s Oct 18 00:25:13 volumio kernel: raid6: .... xor() 652 MB/s, rmw enabled Oct 18 00:25:13 volumio kernel: raid6: using neon recovery algorithm Oct 18 00:25:13 volumio kernel: iommu: Default domain type: Translated Oct 18 00:25:13 volumio kernel: iommu: DMA domain TLB invalidation policy: strict mode Oct 18 00:25:13 volumio kernel: SCSI subsystem initialized Oct 18 00:25:13 volumio kernel: libata version 3.00 loaded. Oct 18 00:25:13 volumio kernel: usbcore: registered new interface driver usbfs Oct 18 00:25:13 volumio kernel: usbcore: registered new interface driver hub Oct 18 00:25:13 volumio kernel: usbcore: registered new device driver usb Oct 18 00:25:13 volumio kernel: mc: Linux media interface: v0.10 Oct 18 00:25:13 volumio kernel: videodev: Linux video capture interface: v2.00 Oct 18 00:25:13 volumio kernel: pps_core: LinuxPPS API ver. 1 registered Oct 18 00:25:13 volumio kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Oct 18 00:25:13 volumio kernel: PTP clock support registered Oct 18 00:25:13 volumio kernel: Advanced Linux Sound Architecture Driver Initialized. Oct 18 00:25:13 volumio kernel: NetLabel: Initializing Oct 18 00:25:13 volumio kernel: NetLabel: domain hash size = 128 Oct 18 00:25:13 volumio kernel: NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO Oct 18 00:25:13 volumio kernel: NetLabel: unlabeled traffic allowed by default Oct 18 00:25:13 volumio kernel: clocksource: Switched to clocksource arch_sys_counter Oct 18 00:25:13 volumio kernel: VFS: Disk quotas dquot_6.6.0 Oct 18 00:25:13 volumio kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Oct 18 00:25:13 volumio kernel: AppArmor: AppArmor Filesystem Enabled Oct 18 00:25:13 volumio kernel: NET: Registered PF_INET protocol family Oct 18 00:25:13 volumio kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) Oct 18 00:25:13 volumio kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear) Oct 18 00:25:13 volumio kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 18 00:25:13 volumio kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Oct 18 00:25:13 volumio kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 18 00:25:13 volumio kernel: TCP: Hash tables configured (established 8192 bind 8192) Oct 18 00:25:13 volumio kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Oct 18 00:25:13 volumio kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Oct 18 00:25:13 volumio kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 18 00:25:13 volumio kernel: Trying to unpack rootfs image as initramfs... Oct 18 00:25:13 volumio kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 5 counters available Oct 18 00:25:13 volumio kernel: Initialise system trusted keyrings Oct 18 00:25:13 volumio kernel: Key type blacklist registered Oct 18 00:25:13 volumio kernel: workingset: timestamp_bits=14 max_order=18 bucket_order=4 Oct 18 00:25:13 volumio kernel: zbud: loaded Oct 18 00:25:13 volumio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 18 00:25:13 volumio kernel: fuse: init (API version 7.34) Oct 18 00:25:13 volumio kernel: integrity: Platform Keyring initialized Oct 18 00:25:13 volumio kernel: xor: measuring software checksum speed Oct 18 00:25:13 volumio kernel: arm4regs : 1300 MB/sec Oct 18 00:25:13 volumio kernel: 8regs : 896 MB/sec Oct 18 00:25:13 volumio kernel: 32regs : 848 MB/sec Oct 18 00:25:13 volumio kernel: neon : 1318 MB/sec Oct 18 00:25:13 volumio kernel: xor: using function: neon (1318 MB/sec) Oct 18 00:25:13 volumio kernel: Key type asymmetric registered Oct 18 00:25:13 volumio kernel: Asymmetric key parser 'x509' registered Oct 18 00:25:13 volumio kernel: bounce: pool size: 64 pages Oct 18 00:25:13 volumio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246) Oct 18 00:25:13 volumio kernel: io scheduler mq-deadline registered Oct 18 00:25:13 volumio kernel: io scheduler kyber registered Oct 18 00:25:13 volumio kernel: io scheduler bfq registered Oct 18 00:25:13 volumio kernel: sun8i-h3-pinctrl 1c20800.pinctrl: initialized sunXi PIO driver Oct 18 00:25:13 volumio kernel: sun8i-h3-r-pinctrl 1f02c00.pinctrl: initialized sunXi PIO driver Oct 18 00:25:13 volumio kernel: Serial: 8250/16550 driver, 8 ports, IRQ sharing disabled Oct 18 00:25:13 volumio kernel: sun8i-h3-pinctrl 1c20800.pinctrl: supply vcc-pa not found, using dummy regulator Oct 18 00:25:13 volumio kernel: printk: console [ttyS0] disabled Oct 18 00:25:13 volumio kernel: 1c28000.serial: ttyS0 at MMIO 0x1c28000 (irq = 51, base_baud = 1500000) is a 16550A Oct 18 00:25:13 volumio kernel: printk: console [ttyS0] enabled Oct 18 00:25:13 volumio kernel: brd: module loaded Oct 18 00:25:13 volumio kernel: loop: module loaded Oct 18 00:25:13 volumio kernel: dwmac-sun8i 1c30000.ethernet: IRQ eth_wake_irq not found Oct 18 00:25:13 volumio kernel: dwmac-sun8i 1c30000.ethernet: IRQ eth_lpi not found Oct 18 00:25:13 volumio kernel: dwmac-sun8i 1c30000.ethernet: supply phy not found, using dummy regulator Oct 18 00:25:13 volumio kernel: dwmac-sun8i 1c30000.ethernet: supply phy-io not found, using dummy regulator Oct 18 00:25:13 volumio kernel: dwmac-sun8i 1c30000.ethernet: PTP uses main clock Oct 18 00:25:13 volumio kernel: dwmac-sun8i 1c30000.ethernet: Current syscon value is not the default 148000 (expect 58000) Oct 18 00:25:13 volumio kernel: dwmac-sun8i 1c30000.ethernet: No HW DMA feature register supported Oct 18 00:25:13 volumio kernel: dwmac-sun8i 1c30000.ethernet: RX Checksum Offload Engine supported Oct 18 00:25:13 volumio kernel: dwmac-sun8i 1c30000.ethernet: COE Type 2 Oct 18 00:25:13 volumio kernel: dwmac-sun8i 1c30000.ethernet: TX Checksum insertion supported Oct 18 00:25:13 volumio kernel: dwmac-sun8i 1c30000.ethernet: Normal descriptors Oct 18 00:25:13 volumio kernel: dwmac-sun8i 1c30000.ethernet: Chain mode enabled Oct 18 00:25:13 volumio kernel: dwmac-sun8i 1c30000.ethernet: Found internal PHY node Oct 18 00:25:13 volumio kernel: dwmac-sun8i 1c30000.ethernet: Switch mux to internal PHY Oct 18 00:25:13 volumio kernel: dwmac-sun8i 1c30000.ethernet: Powering internal PHY Oct 18 00:25:13 volumio kernel: Freeing initrd memory: 15044K Oct 18 00:25:13 volumio kernel: ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver Oct 18 00:25:13 volumio kernel: ehci-platform: EHCI generic platform driver Oct 18 00:25:13 volumio kernel: ehci-platform 1c1a000.usb: EHCI Host Controller Oct 18 00:25:13 volumio kernel: ehci-platform 1c1a000.usb: new USB bus registered, assigned bus number 1 Oct 18 00:25:13 volumio kernel: ehci-platform 1c1a000.usb: irq 35, io mem 0x01c1a000 Oct 18 00:25:13 volumio kernel: ehci-platform 1c1a000.usb: USB 2.0 started, EHCI 1.00 Oct 18 00:25:13 volumio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15 Oct 18 00:25:13 volumio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 18 00:25:13 volumio kernel: usb usb1: Product: EHCI Host Controller Oct 18 00:25:13 volumio kernel: usb usb1: Manufacturer: Linux 5.15.93-sunxi ehci_hcd Oct 18 00:25:13 volumio kernel: usb usb1: SerialNumber: 1c1a000.usb Oct 18 00:25:13 volumio kernel: hub 1-0:1.0: USB hub found Oct 18 00:25:13 volumio kernel: hub 1-0:1.0: 1 port detected Oct 18 00:25:13 volumio kernel: ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver Oct 18 00:25:13 volumio kernel: ohci-platform: OHCI generic platform driver Oct 18 00:25:13 volumio kernel: ohci-platform 1c1a400.usb: Generic Platform OHCI controller Oct 18 00:25:13 volumio kernel: ohci-platform 1c1a400.usb: new USB bus registered, assigned bus number 2 Oct 18 00:25:13 volumio kernel: ohci-platform 1c1a400.usb: irq 36, io mem 0x01c1a400 Oct 18 00:25:13 volumio kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 5.15 Oct 18 00:25:13 volumio kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 18 00:25:13 volumio kernel: usb usb2: Product: Generic Platform OHCI controller Oct 18 00:25:13 volumio kernel: usb usb2: Manufacturer: Linux 5.15.93-sunxi ohci_hcd Oct 18 00:25:13 volumio kernel: usb usb2: SerialNumber: 1c1a400.usb Oct 18 00:25:13 volumio kernel: hub 2-0:1.0: USB hub found Oct 18 00:25:13 volumio kernel: hub 2-0:1.0: 1 port detected Oct 18 00:25:13 volumio kernel: usbcore: registered new interface driver usb-storage Oct 18 00:25:13 volumio kernel: sun6i-rtc 1f00000.rtc: registered as rtc0 Oct 18 00:25:13 volumio kernel: sun6i-rtc 1f00000.rtc: setting system clock to 2025-10-17T21:25:06 UTC (1760736306) Oct 18 00:25:13 volumio kernel: sun6i-rtc 1f00000.rtc: RTC enabled Oct 18 00:25:13 volumio kernel: i2c_dev: i2c /dev entries driver Oct 18 00:25:13 volumio kernel: sun8i-h3-r-pinctrl 1f02c00.pinctrl: supply vcc-pl not found, using dummy regulator Oct 18 00:25:13 volumio kernel: sunxi-wdt 1c20ca0.watchdog: Watchdog enabled (timeout=16 sec, nowayout=0) Oct 18 00:25:13 volumio kernel: sun8i-h3-pinctrl 1c20800.pinctrl: supply vcc-pf not found, using dummy regulator Oct 18 00:25:13 volumio kernel: ledtrig-cpu: registered to indicate activity on CPUs Oct 18 00:25:13 volumio kernel: sunxi-mmc 1c0f000.mmc: Got CD GPIO Oct 18 00:25:13 volumio kernel: sun8i-ce 1c15000.crypto: Set mod clock to 50000000 (50 Mhz) from 24000000 (24 Mhz) Oct 18 00:25:13 volumio kernel: sun8i-ce 1c15000.crypto: will run requests pump with realtime priority Oct 18 00:25:13 volumio kernel: sun8i-ce 1c15000.crypto: will run requests pump with realtime priority Oct 18 00:25:13 volumio kernel: sun8i-ce 1c15000.crypto: will run requests pump with realtime priority Oct 18 00:25:13 volumio kernel: sun8i-ce 1c15000.crypto: will run requests pump with realtime priority Oct 18 00:25:13 volumio kernel: sun8i-ce 1c15000.crypto: Register cbc(aes) Oct 18 00:25:13 volumio kernel: sun8i-ce 1c15000.crypto: Register ecb(aes) Oct 18 00:25:13 volumio kernel: sun8i-ce 1c15000.crypto: Register cbc(des3_ede) Oct 18 00:25:13 volumio kernel: sun8i-ce 1c15000.crypto: Register ecb(des3_ede) Oct 18 00:25:13 volumio kernel: sun8i-ce 1c15000.crypto: Register md5 Oct 18 00:25:13 volumio kernel: sun8i-ce 1c15000.crypto: Register sha1 Oct 18 00:25:13 volumio kernel: sun8i-ce 1c15000.crypto: Register sha224 Oct 18 00:25:13 volumio kernel: sun8i-ce 1c15000.crypto: Register sha256 Oct 18 00:25:13 volumio kernel: sun8i-ce 1c15000.crypto: Register sha384 Oct 18 00:25:13 volumio kernel: sun8i-ce 1c15000.crypto: Register sha512 Oct 18 00:25:13 volumio kernel: sun8i-ce 1c15000.crypto: Register stdrng Oct 18 00:25:13 volumio kernel: sun8i-ce 1c15000.crypto: TRNG not supported Oct 18 00:25:13 volumio kernel: sun8i-ce 1c15000.crypto: CryptoEngine Die ID 1 Oct 18 00:25:13 volumio kernel: hid: raw HID events driver (C) Jiri Kosina Oct 18 00:25:13 volumio kernel: usbcore: registered new interface driver usbhid Oct 18 00:25:13 volumio kernel: usbhid: USB HID core driver Oct 18 00:25:13 volumio kernel: debugfs: Directory '1c22c00.codec' with parent 'H3 Audio Codec' already present! Oct 18 00:25:13 volumio kernel: Initializing XFRM netlink socket Oct 18 00:25:13 volumio kernel: NET: Registered PF_INET6 protocol family Oct 18 00:25:13 volumio kernel: sunxi-mmc 1c0f000.mmc: initialized, max. request size: 16384 KB Oct 18 00:25:13 volumio kernel: mmc0: host does not support reading read-only switch, assuming write-enable Oct 18 00:25:13 volumio kernel: mmc0: new high speed SDXC card at address 59b4 Oct 18 00:25:13 volumio kernel: mmcblk0: mmc0:59b4 SD 58.9 GiB Oct 18 00:25:13 volumio kernel: Segment Routing with IPv6 Oct 18 00:25:13 volumio kernel: In-situ OAM (IOAM) with IPv6 Oct 18 00:25:13 volumio kernel: NET: Registered PF_PACKET protocol family Oct 18 00:25:13 volumio kernel: NET: Registered PF_KEY protocol family Oct 18 00:25:13 volumio kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 18 00:25:13 volumio kernel: 8021q: 802.1Q VLAN Support v1.8 Oct 18 00:25:13 volumio kernel: Key type dns_resolver registered Oct 18 00:25:13 volumio kernel: Registering SWP/SWPB emulation handler Oct 18 00:25:13 volumio kernel: registered taskstats version 1 Oct 18 00:25:13 volumio kernel: Loading compiled-in X.509 certificates Oct 18 00:25:13 volumio kernel: mmcblk0: p1 p2 p3 Oct 18 00:25:13 volumio kernel: Loaded X.509 cert 'Build time autogenerated kernel key: 8f8e38801ad26e1c5752b0545ce65e141a715a7d' Oct 18 00:25:13 volumio kernel: zswap: loaded using pool zstd/z3fold Oct 18 00:25:13 volumio kernel: Key type .fscrypt registered Oct 18 00:25:13 volumio kernel: Key type fscrypt-provisioning registered Oct 18 00:25:13 volumio kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=yes Oct 18 00:25:13 volumio kernel: Key type encrypted registered Oct 18 00:25:13 volumio kernel: AppArmor: AppArmor sha1 policy hashing enabled Oct 18 00:25:13 volumio kernel: sun8i-h3-pinctrl 1c20800.pinctrl: supply vcc-pg not found, using dummy regulator Oct 18 00:25:13 volumio kernel: sun4i-drm display-engine: bound 1100000.mixer (ops 0xc0aa3538) Oct 18 00:25:13 volumio kernel: sun4i-drm display-engine: bound 1c0c000.lcd-controller (ops 0xc0a9f46c) Oct 18 00:25:13 volumio kernel: sun8i-dw-hdmi 1ee0000.hdmi: supply hvcc not found, using dummy regulator Oct 18 00:25:13 volumio kernel: sun8i-dw-hdmi 1ee0000.hdmi: Detected HDMI TX controller v1.32a with HDCP (sun8i_dw_hdmi_phy) Oct 18 00:25:13 volumio kernel: sun8i-dw-hdmi 1ee0000.hdmi: registered DesignWare HDMI I2C bus driver Oct 18 00:25:13 volumio kernel: sun4i-drm display-engine: bound 1ee0000.hdmi (ops 0xc0aa2aec) Oct 18 00:25:13 volumio kernel: [drm] Initialized sun4i-drm 1.0.0 20150629 for display-engine on minor 0 Oct 18 00:25:13 volumio kernel: sun4i-drm display-engine: [drm] Cannot find any crtc or sizes Oct 18 00:25:13 volumio kernel: ehci-platform 1c1b000.usb: EHCI Host Controller Oct 18 00:25:13 volumio kernel: ehci-platform 1c1b000.usb: new USB bus registered, assigned bus number 3 Oct 18 00:25:13 volumio kernel: sun4i-drm display-engine: [drm] Cannot find any crtc or sizes Oct 18 00:25:13 volumio kernel: ehci-platform 1c1b000.usb: irq 37, io mem 0x01c1b000 Oct 18 00:25:13 volumio kernel: ehci-platform 1c1b000.usb: USB 2.0 started, EHCI 1.00 Oct 18 00:25:13 volumio kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15 Oct 18 00:25:13 volumio kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 18 00:25:13 volumio kernel: usb usb3: Product: EHCI Host Controller Oct 18 00:25:13 volumio kernel: usb usb3: Manufacturer: Linux 5.15.93-sunxi ehci_hcd Oct 18 00:25:13 volumio kernel: usb usb3: SerialNumber: 1c1b000.usb Oct 18 00:25:13 volumio kernel: hub 3-0:1.0: USB hub found Oct 18 00:25:13 volumio kernel: hub 3-0:1.0: 1 port detected Oct 18 00:25:13 volumio kernel: ehci-platform 1c1c000.usb: EHCI Host Controller Oct 18 00:25:13 volumio kernel: ehci-platform 1c1c000.usb: new USB bus registered, assigned bus number 4 Oct 18 00:25:13 volumio kernel: ehci-platform 1c1c000.usb: irq 39, io mem 0x01c1c000 Oct 18 00:25:13 volumio kernel: ehci-platform 1c1c000.usb: USB 2.0 started, EHCI 1.00 Oct 18 00:25:13 volumio kernel: usb usb4: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15 Oct 18 00:25:13 volumio kernel: usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 18 00:25:13 volumio kernel: usb usb4: Product: EHCI Host Controller Oct 18 00:25:13 volumio kernel: usb usb4: Manufacturer: Linux 5.15.93-sunxi ehci_hcd Oct 18 00:25:13 volumio kernel: usb usb4: SerialNumber: 1c1c000.usb Oct 18 00:25:13 volumio kernel: hub 4-0:1.0: USB hub found Oct 18 00:25:13 volumio kernel: hub 4-0:1.0: 1 port detected Oct 18 00:25:13 volumio kernel: ehci-platform 1c1d000.usb: EHCI Host Controller Oct 18 00:25:13 volumio kernel: ehci-platform 1c1d000.usb: new USB bus registered, assigned bus number 5 Oct 18 00:25:13 volumio kernel: ehci-platform 1c1d000.usb: irq 41, io mem 0x01c1d000 Oct 18 00:25:13 volumio kernel: ehci-platform 1c1d000.usb: USB 2.0 started, EHCI 1.00 Oct 18 00:25:13 volumio kernel: usb usb5: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15 Oct 18 00:25:13 volumio kernel: usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 18 00:25:13 volumio kernel: usb usb5: Product: EHCI Host Controller Oct 18 00:25:13 volumio kernel: usb usb5: Manufacturer: Linux 5.15.93-sunxi ehci_hcd Oct 18 00:25:13 volumio kernel: usb usb5: SerialNumber: 1c1d000.usb Oct 18 00:25:13 volumio kernel: hub 5-0:1.0: USB hub found Oct 18 00:25:13 volumio kernel: hub 5-0:1.0: 1 port detected Oct 18 00:25:13 volumio kernel: ohci-platform 1c1b400.usb: Generic Platform OHCI controller Oct 18 00:25:13 volumio kernel: ohci-platform 1c1b400.usb: new USB bus registered, assigned bus number 6 Oct 18 00:25:13 volumio kernel: ohci-platform 1c1b400.usb: irq 38, io mem 0x01c1b400 Oct 18 00:25:13 volumio kernel: usb usb6: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 5.15 Oct 18 00:25:13 volumio kernel: usb usb6: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 18 00:25:13 volumio kernel: usb usb6: Product: Generic Platform OHCI controller Oct 18 00:25:13 volumio kernel: usb usb6: Manufacturer: Linux 5.15.93-sunxi ohci_hcd Oct 18 00:25:13 volumio kernel: usb usb6: SerialNumber: 1c1b400.usb Oct 18 00:25:13 volumio kernel: hub 6-0:1.0: USB hub found Oct 18 00:25:13 volumio kernel: hub 6-0:1.0: 1 port detected Oct 18 00:25:13 volumio kernel: ohci-platform 1c1c400.usb: Generic Platform OHCI controller Oct 18 00:25:13 volumio kernel: ohci-platform 1c1c400.usb: new USB bus registered, assigned bus number 7 Oct 18 00:25:13 volumio kernel: ohci-platform 1c1c400.usb: irq 40, io mem 0x01c1c400 Oct 18 00:25:13 volumio kernel: usb usb7: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 5.15 Oct 18 00:25:13 volumio kernel: usb usb7: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 18 00:25:13 volumio kernel: usb usb7: Product: Generic Platform OHCI controller Oct 18 00:25:13 volumio kernel: usb usb7: Manufacturer: Linux 5.15.93-sunxi ohci_hcd Oct 18 00:25:13 volumio kernel: usb usb7: SerialNumber: 1c1c400.usb Oct 18 00:25:13 volumio kernel: hub 7-0:1.0: USB hub found Oct 18 00:25:13 volumio kernel: hub 7-0:1.0: 1 port detected Oct 18 00:25:13 volumio kernel: ohci-platform 1c1d400.usb: Generic Platform OHCI controller Oct 18 00:25:13 volumio kernel: ohci-platform 1c1d400.usb: new USB bus registered, assigned bus number 8 Oct 18 00:25:13 volumio kernel: ohci-platform 1c1d400.usb: irq 42, io mem 0x01c1d400 Oct 18 00:25:13 volumio kernel: usb usb8: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 5.15 Oct 18 00:25:13 volumio kernel: usb usb8: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 18 00:25:13 volumio kernel: usb usb8: Product: Generic Platform OHCI controller Oct 18 00:25:13 volumio kernel: usb usb8: Manufacturer: Linux 5.15.93-sunxi ohci_hcd Oct 18 00:25:13 volumio kernel: usb usb8: SerialNumber: 1c1d400.usb Oct 18 00:25:13 volumio kernel: hub 8-0:1.0: USB hub found Oct 18 00:25:13 volumio kernel: hub 8-0:1.0: 1 port detected Oct 18 00:25:13 volumio kernel: of_cfs_init Oct 18 00:25:13 volumio kernel: of_cfs_init: OK Oct 18 00:25:13 volumio kernel: ALSA device list: Oct 18 00:25:13 volumio kernel: #0: H3 Audio Codec Oct 18 00:25:13 volumio kernel: Freeing unused kernel image (initmem) memory: 1024K Oct 18 00:25:13 volumio kernel: Run /init as init process Oct 18 00:25:13 volumio kernel: with arguments: Oct 18 00:25:13 volumio kernel: /init Oct 18 00:25:13 volumio kernel: splash Oct 18 00:25:13 volumio kernel: with environment: Oct 18 00:25:13 volumio kernel: HOME=/ Oct 18 00:25:13 volumio kernel: TERM=linux Oct 18 00:25:13 volumio kernel: ubootpart=01101494-01 Oct 18 00:25:13 volumio kernel: ubootsource=mmc Oct 18 00:25:13 volumio kernel: imgpart=/dev/mmcblk0p2 Oct 18 00:25:13 volumio kernel: imgfile=/volumio_current.sqsh Oct 18 00:25:13 volumio kernel: hwver=orangepi Oct 18 00:25:13 volumio kernel: sunxi_ve_mem_reserve=0 Oct 18 00:25:13 volumio kernel: sunxi_g2d_mem_reserve=0 Oct 18 00:25:13 volumio kernel: sunxi_fb_mem_reserve=16 Oct 18 00:25:13 volumio kernel: cgroup_enable=memory Oct 18 00:25:13 volumio kernel: input: r_gpio_keys as /devices/platform/r_gpio_keys/input/input0 Oct 18 00:25:13 volumio kernel: usb_phy_generic usb_phy_generic.3.auto: supply vcc not found, using dummy regulator Oct 18 00:25:13 volumio kernel: usb_phy_generic usb_phy_generic.3.auto: dummy supplies not allowed for exclusive requests Oct 18 00:25:13 volumio kernel: musb-hdrc musb-hdrc.4.auto: MUSB HDRC host driver Oct 18 00:25:13 volumio kernel: musb-hdrc musb-hdrc.4.auto: new USB bus registered, assigned bus number 9 Oct 18 00:25:13 volumio kernel: usb usb9: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15 Oct 18 00:25:13 volumio kernel: usb usb9: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 18 00:25:13 volumio kernel: usb usb9: Product: MUSB HDRC host driver Oct 18 00:25:13 volumio kernel: usb usb9: Manufacturer: Linux 5.15.93-sunxi musb-hcd Oct 18 00:25:13 volumio kernel: usb usb9: SerialNumber: musb-hdrc.4.auto Oct 18 00:25:13 volumio kernel: hub 9-0:1.0: USB hub found Oct 18 00:25:13 volumio kernel: hub 9-0:1.0: 1 port detected Oct 18 00:25:13 volumio kernel: lima 1c40000.gpu: gp - mali400 version major 1 minor 1 Oct 18 00:25:13 volumio kernel: lima 1c40000.gpu: pp0 - mali400 version major 1 minor 1 Oct 18 00:25:13 volumio kernel: lima 1c40000.gpu: pp1 - mali400 version major 1 minor 1 Oct 18 00:25:13 volumio kernel: lima 1c40000.gpu: l2 cache 64K, 4-way, 64byte cache line, 64bit external bus Oct 18 00:25:13 volumio kernel: lima 1c40000.gpu: bus rate = 200000000 Oct 18 00:25:13 volumio kernel: lima 1c40000.gpu: mod rate = 297000000 Oct 18 00:25:13 volumio kernel: lima 1c40000.gpu: dev_pm_opp_set_regulators: no regulator (mali) found: -19 Oct 18 00:25:13 volumio kernel: lima 1c40000.gpu: Failed to register cooling device Oct 18 00:25:13 volumio kernel: [drm] Initialized lima 1.2.0 20200215 for 1c40000.gpu on minor 1 Oct 18 00:25:13 volumio kernel: EXT4-fs (mmcblk0p2): recovery complete Oct 18 00:25:13 volumio kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. Oct 18 00:25:13 volumio kernel: loop0: detected capacity change from 0 to 833408 Oct 18 00:25:13 volumio kernel: EXT4-fs (mmcblk0p3): recovery complete Oct 18 00:25:13 volumio kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. Oct 18 00:25:13 volumio kernel: ext4 filesystem being mounted at /mnt/ext supports timestamps until 2038 (0x7fffffff) Oct 18 00:25:13 volumio systemd[1]: Inserted module 'autofs4' Oct 18 00:25:13 volumio 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 18 00:25:13 volumio systemd[1]: Detected architecture arm. Oct 18 00:25:13 volumio systemd[1]: Set hostname to . Oct 18 00:25:13 volumio 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 18 00:25:13 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Oct 18 00:25:13 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Oct 18 00:25:13 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Oct 18 00:25:13 volumio systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 18 00:25:13 volumio systemd[1]: Created slice User and Session Slice. Oct 18 00:25:13 volumio systemd[1]: Reached target Slices. Oct 18 00:25:13 volumio systemd[1]: Listening on udev Kernel Socket. Oct 18 00:25:13 volumio systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 18 00:25:13 volumio systemd[1]: Listening on Journal Audit Socket. Oct 18 00:25:13 volumio kernel: RPC: Registered named UNIX socket transport module. Oct 18 00:25:13 volumio kernel: RPC: Registered udp transport module. Oct 18 00:25:13 volumio kernel: RPC: Registered tcp transport module. Oct 18 00:25:13 volumio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Oct 18 00:25:13 volumio systemd-journald[423]: Journal started Oct 18 00:25:13 volumio systemd-journald[423]: Runtime journal (/run/log/journal/b8e23c24fa1d772592f70e9d6664bf5c) is 3.7M, max 30.0M, 26.2M free. Oct 18 00:25:13 volumio fake-hwclock[415]: Current system time: 2025-10-17 21:25:13 Oct 18 00:25:13 volumio fake-hwclock[415]: fake-hwclock saved clock information is in the past: 2025-10-17 21:24:49 Oct 18 00:25:13 volumio fake-hwclock[415]: To set system time to this saved clock anyway, use "force" Oct 18 00:25:13 volumio systemd[1]: Started Apply Kernel Variables. Oct 18 00:25:13 volumio systemd[1]: Started Create Static Device Nodes in /dev. Oct 18 00:25:13 volumio systemd[1]: Reached target Local File Systems (Pre). Oct 18 00:25:13 volumio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Oct 18 00:25:13 volumio systemd[1]: Mounting /tmp... Oct 18 00:25:13 volumio systemd[1]: Mounting /var/spool/cups... Oct 18 00:25:13 volumio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Oct 18 00:25:13 volumio systemd[1]: Mounting /var/log... Oct 18 00:25:13 volumio systemd[1]: Starting udev Kernel Device Manager... Oct 18 00:25:14 volumio systemd[1]: Mounted /tmp. Oct 18 00:25:14 volumio systemd[1]: Mounted /var/spool/cups. Oct 18 00:25:14 volumio systemd[1]: Started udev Coldplug all Devices. Oct 18 00:25:14 volumio systemd[1]: Mounted /var/log. Oct 18 00:25:14 volumio systemd[1]: Starting Flush Journal to Persistent Storage... Oct 18 00:25:14 volumio systemd[1]: Starting Helper to synchronize boot up for ifupdown... Oct 18 00:25:14 volumio systemd[1]: Mounting /var/spool/cups/tmp... Oct 18 00:25:14 volumio systemd-udevd[457]: Network interface NamePolicy= disabled on kernel command line, ignoring. Oct 18 00:25:14 volumio systemd[1]: Mounted /var/spool/cups/tmp. Oct 18 00:25:14 volumio systemd[1]: Started Helper to synchronize boot up for ifupdown. Oct 18 00:25:14 volumio systemd-journald[423]: Runtime journal (/run/log/journal/b8e23c24fa1d772592f70e9d6664bf5c) is 3.7M, max 30.0M, 26.2M free. Oct 18 00:25:14 volumio systemd[1]: Started Flush Journal to Persistent Storage. Oct 18 00:25:14 volumio systemd[1]: Started udev Kernel Device Manager. Oct 18 00:25:14 volumio systemd[1]: Starting Show Plymouth Boot Screen... Oct 18 00:25:14 volumio kernel: random: systemd-journal: uninitialized urandom read (16 bytes read) Oct 18 00:25:14 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Oct 18 00:25:14 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Oct 18 00:25:14 volumio systemd[1]: plymouth-start.service: Succeeded. Oct 18 00:25:14 volumio systemd[1]: Started Show Plymouth Boot Screen. Oct 18 00:25:14 volumio kernel: sunxi_cedrus: module is from the staging directory, the quality is unknown, you have been warned. Oct 18 00:25:14 volumio kernel: cedrus 1c0e000.video-codec: Device registered as /dev/video0 Oct 18 00:25:14 volumio systemd-udevd[463]: Using default interface naming scheme 'v240'. Oct 18 00:25:14 volumio systemd-udevd[463]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Oct 18 00:25:14 volumio systemd-udevd[463]: link_config: could not get ethtool features for eth0 Oct 18 00:25:14 volumio systemd-udevd[463]: Could not set offload features of eth0: Device or resource busy Oct 18 00:25:14 volumio kernel: debugfs: File 'Capture' in directory 'dapm' already present! Oct 18 00:25:15 volumio kernel: Registered IR keymap rc-empty Oct 18 00:25:15 volumio kernel: rc rc0: sunxi-ir as /devices/platform/soc/1f02000.ir/rc/rc0 Oct 18 00:25:15 volumio kernel: rc rc0: lirc_dev: driver sunxi-ir registered at minor = 0, raw IR receiver, no transmitter Oct 18 00:25:15 volumio kernel: input: sunxi-ir as /devices/platform/soc/1f02000.ir/rc/rc0/input1 Oct 18 00:25:15 volumio kernel: sunxi-ir 1f02000.ir: initialized sunXi IR driver Oct 18 00:25:15 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Oct 18 00:25:15 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Oct 18 00:25:15 volumio systemd-udevd[467]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Oct 18 00:25:15 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Oct 18 00:25:15 volumio systemd-udevd[462]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Oct 18 00:25:15 volumio systemd-udevd[464]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Oct 18 00:25:15 volumio systemd[1]: Found device /dev/ttyS0. Oct 18 00:25:16 volumio systemd[1]: Found device /dev/mmcblk0p1. Oct 18 00:25:16 volumio systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Oct 18 00:25:16 volumio systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 18 00:25:16 volumio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Oct 18 00:25:16 volumio systemd[1]: Starting Show Plymouth Boot Screen... Oct 18 00:25:16 volumio systemd[1]: Condition check resulted in Huge Pages File System being skipped. Oct 18 00:25:16 volumio systemd[1]: Starting File System Check on /dev/mmcblk0p1... Oct 18 00:25:16 volumio systemd[1]: plymouth-start.service: Succeeded. Oct 18 00:25:16 volumio systemd[1]: Started Show Plymouth Boot Screen. Oct 18 00:25:16 volumio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Oct 18 00:25:16 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Oct 18 00:25:16 volumio systemd[1]: Reached target Local Encrypted Volumes. Oct 18 00:25:16 volumio systemd[1]: Reached target Paths. Oct 18 00:25:16 volumio systemd[1]: Started File System Check Daemon to report status. Oct 18 00:25:16 volumio kernel: random: systemd-journal: uninitialized urandom read (16 bytes read) Oct 18 00:25:16 volumio systemd-fsck[665]: fsck.fat 4.1 (2017-01-24) Oct 18 00:25:16 volumio systemd-fsck[665]: /dev/mmcblk0p1: 253 files, 65580/120958 clusters Oct 18 00:25:16 volumio systemd[1]: Started File System Check on /dev/mmcblk0p1. Oct 18 00:25:16 volumio systemd[1]: Mounting /boot... Oct 18 00:25:16 volumio systemd[1]: Mounted /boot. Oct 18 00:25:16 volumio systemd[1]: Reached target Local File Systems. Oct 18 00:25:16 volumio systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Oct 18 00:25:16 volumio systemd[1]: Started ifup for eth0. Oct 18 00:25:16 volumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Oct 18 00:25:16 volumio systemd[1]: Starting Raise network interfaces... Oct 18 00:25:16 volumio systemd[1]: Starting Preprocess NFS configuration... Oct 18 00:25:16 volumio systemd[1]: Starting Create Volatile Files and Directories... Oct 18 00:25:16 volumio systemd[1]: plymouth-read-write.service: Succeeded. Oct 18 00:25:16 volumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Oct 18 00:25:16 volumio systemd[1]: Received SIGRTMIN+20 from PID 326 (plymouthd). Oct 18 00:25:16 volumio systemd[1]: nfs-config.service: Succeeded. Oct 18 00:25:16 volumio systemd[1]: Started Preprocess NFS configuration. Oct 18 00:25:16 volumio systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Oct 18 00:25:16 volumio systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Oct 18 00:25:16 volumio systemd[1]: Reached target NFS client services. Oct 18 00:25:16 volumio systemd[1]: Started Create Volatile Files and Directories. Oct 18 00:25:16 volumio systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Oct 18 00:25:16 volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Oct 18 00:25:16 volumio systemd[1]: Starting RPC bind portmap service... Oct 18 00:25:16 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Oct 18 00:25:16 volumio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Oct 18 00:25:16 volumio systemd[1]: Started Update UTMP about System Boot/Shutdown. Oct 18 00:25:16 volumio kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Oct 18 00:25:16 volumio systemd[1]: Reached target System Initialization. Oct 18 00:25:16 volumio kernel: platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 Oct 18 00:25:16 volumio kernel: platform regulatory.0: Falling back to sysfs fallback for: regulatory.db Oct 18 00:25:16 volumio kernel: cfg80211: failed to load regulatory.db Oct 18 00:25:16 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 18 00:25:16 volumio systemd[1]: Started Daily apt download activities. Oct 18 00:25:16 volumio systemd[1]: Started Daily apt upgrade and clean activities. Oct 18 00:25:16 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 18 00:25:16 volumio systemd[1]: Reached target Timers. Oct 18 00:25:16 volumio systemd[1]: Listening on triggerhappy.socket. Oct 18 00:25:16 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Oct 18 00:25:16 volumio systemd[1]: Reached target Sockets. Oct 18 00:25:16 volumio systemd[1]: Reached target Basic System. Oct 18 00:25:16 volumio systemd[1]: Started D-Bus System Message Bus. Oct 18 00:25:16 volumio ifup[675]: command failed: No such device (-19) Oct 18 00:25:16 volumio sh[672]: command failed: No such device (-19) Oct 18 00:25:16 volumio systemd[1]: Started Volumio Log Rotation Service. Oct 18 00:25:16 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Oct 18 00:25:16 volumio systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Oct 18 00:25:16 volumio systemd[1]: Started volumio-remote-updater.service. Oct 18 00:25:16 volumio systemd[1]: Starting Login Service... Oct 18 00:25:16 volumio systemd[1]: Started Manage Sound Card State (restore and store). Oct 18 00:25:16 volumio systemd[1]: Started MPD Monitor Service. Oct 18 00:25:16 volumio alsactl[714]: alsactl 1.1.8 daemon started Oct 18 00:25:16 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Oct 18 00:25:16 volumio systemd[1]: Starting Wireless Services... Oct 18 00:25:16 volumio systemd[1]: Starting WPA supplicant... Oct 18 00:25:16 volumio systemd[1]: Starting triggerhappy global hotkey daemon... Oct 18 00:25:17 volumio mpd_monitor.sh[718]: MPD Monitor Service: Starting MPD Monitor Service Oct 18 00:25:17 volumio systemd[1]: Starting Save/Restore Sound Card State... Oct 18 00:25:17 volumio systemd[1]: Started Volumio Iptables Module. Oct 18 00:25:17 volumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Oct 18 00:25:17 volumio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Oct 18 00:25:17 volumio systemd[1]: Starting DHCP Client Daemon... Oct 18 00:25:17 volumio systemd[1]: Starting Volumio Time Update Utility... Oct 18 00:25:17 volumio systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Oct 18 00:25:17 volumio systemd[1]: Started RPC bind portmap service. Oct 18 00:25:17 volumio systemd[1]: Reached target Remote File Systems (Pre). Oct 18 00:25:17 volumio systemd[1]: Reached target Remote File Systems. Oct 18 00:25:17 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Oct 18 00:25:17 volumio systemd[1]: Reached target RPC Port Mapper. Oct 18 00:25:17 volumio volumio-time-update[741]: volumio-time-update-util: Fetching time from Volumio... Oct 18 00:25:17 volumio systemd[1]: Started Save/Restore Sound Card State. Oct 18 00:25:17 volumio systemd[1]: Reached target Sound Card. Oct 18 00:25:17 volumio thd[726]: Unable to parse trigger line: Oct 18 00:25:17 volumio thd[726]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Oct 18 00:25:17 volumio thd[726]: Unable to parse trigger line: Oct 18 00:25:17 volumio thd[726]: Unable to parse trigger line: Oct 18 00:25:17 volumio thd[726]: Unable to parse trigger line: Oct 18 00:25:17 volumio thd[726]: Unable to parse trigger line: Oct 18 00:25:17 volumio thd[726]: Unable to parse trigger line: Oct 18 00:25:17 volumio thd[726]: Unable to parse trigger line: Oct 18 00:25:17 volumio thd[726]: Found socket passed from systemd Oct 18 00:25:17 volumio ifup[675]: command failed: No such device (-19) Oct 18 00:25:17 volumio avahi-daemon[709]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). Oct 18 00:25:17 volumio mpd_monitor.sh[718]: MPD error: Cannot assign requested address Oct 18 00:25:17 volumio avahi-daemon[709]: Successfully dropped root privileges. Oct 18 00:25:17 volumio mpd_monitor.sh[718]: MPD Monitor Service: MPD Appears to be inactive, restarting Oct 18 00:25:17 volumio avahi-daemon[709]: avahi-daemon 0.7 starting up. Oct 18 00:25:17 volumio systemd-logind[713]: New seat seat0. Oct 18 00:25:17 volumio mpd_monitor.sh[718]: mpd: no process found Oct 18 00:25:17 volumio systemd-logind[713]: Watching system buttons on /dev/input/event0 (r_gpio_keys) Oct 18 00:25:18 volumio systemd[1]: Started Login Service. Oct 18 00:25:18 volumio systemd[1]: Started triggerhappy global hotkey daemon. Oct 18 00:25:18 volumio avahi-daemon[709]: Successfully called chroot(). Oct 18 00:25:18 volumio avahi-daemon[709]: Successfully dropped remaining capabilities. Oct 18 00:25:18 volumio avahi-daemon[709]: No service file found in /etc/avahi/services. Oct 18 00:25:18 volumio avahi-daemon[709]: Network interface enumeration completed. Oct 18 00:25:18 volumio avahi-daemon[709]: Server startup complete. Host name is volumio.local. Local service cookie is 3024181619. Oct 18 00:25:18 volumio haveged[696]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.3.0 CTV); collect: 128K Oct 18 00:25:18 volumio haveged[696]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 12/40; sz: 15012/57848 Oct 18 00:25:18 volumio haveged[696]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99961 Oct 18 00:25:18 volumio haveged[696]: haveged: fills: 0, generated: 0 Oct 18 00:25:18 volumio kernel: random: crng init done Oct 18 00:25:18 volumio kernel: random: 210 urandom warning(s) missed due to ratelimiting Oct 18 00:25:18 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Oct 18 00:25:18 volumio systemd[1]: Listening on mpd.socket. Oct 18 00:25:18 volumio dhcpcd[734]: no interfaces have a carrier Oct 18 00:25:18 volumio dhcpcd[734]: no interfaces have a carrier Oct 18 00:25:18 volumio kernel: dwmac-sun8i 1c30000.ethernet eth0: PHY [0.1:01] driver [Generic PHY] (irq=POLL) Oct 18 00:25:18 volumio dhcpcd[734]: forked to background, child pid 808 Oct 18 00:25:18 volumio systemd[1]: Started DHCP Client Daemon. Oct 18 00:25:18 volumio dhcpcd[734]: forked to background, child pid 808 Oct 18 00:25:18 volumio kernel: dwmac-sun8i 1c30000.ethernet eth0: Register MEM_TYPE_PAGE_POOL RxQ-0 Oct 18 00:25:18 volumio kernel: dwmac-sun8i 1c30000.ethernet eth0: No Safety Features support found Oct 18 00:25:18 volumio kernel: dwmac-sun8i 1c30000.ethernet eth0: No MAC Management Counters available Oct 18 00:25:18 volumio kernel: dwmac-sun8i 1c30000.ethernet eth0: PTP not supported by HW Oct 18 00:25:18 volumio kernel: dwmac-sun8i 1c30000.ethernet eth0: configuring for phy/mii link mode Oct 18 00:25:18 volumio sh[672]: eth0: waiting for carrier Oct 18 00:25:18 volumio sh[672]: eth0: carrier acquired Oct 18 00:25:18 volumio kernel: dwmac-sun8i 1c30000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx Oct 18 00:25:18 volumio dhcpcd[740]: eth0: waiting for carrier Oct 18 00:25:18 volumio dhcpcd[740]: eth0: carrier acquired Oct 18 00:25:18 volumio dhcpcd[808]: eth0: waiting for carrier Oct 18 00:25:18 volumio dhcpcd[808]: eth0: carrier acquired Oct 18 00:25:18 volumio sh[672]: DUID 00:01:00:01:2d:f7:7c:59:02:81:4c:ac:d3:77 Oct 18 00:25:18 volumio sh[672]: eth0: IAID 4c:ac:d3:77 Oct 18 00:25:18 volumio sh[672]: eth0: adding address fe80::c762:8109:139c:a5fb Oct 18 00:25:18 volumio sh[672]: ipv6_addaddr1: Permission denied Oct 18 00:25:18 volumio dhcpcd[740]: DUID 00:01:00:01:2d:f7:7c:59:02:81:4c:ac:d3:77 Oct 18 00:25:18 volumio dhcpcd[740]: eth0: IAID 4c:ac:d3:77 Oct 18 00:25:18 volumio dhcpcd[740]: eth0: adding address fe80::c762:8109:139c:a5fb Oct 18 00:25:18 volumio dhcpcd[740]: ipv6_addaddr1: Permission denied Oct 18 00:25:18 volumio dhcpcd[808]: DUID 00:01:00:01:2d:f7:7c:59:02:81:4c:ac:d3:77 Oct 18 00:25:18 volumio dhcpcd[808]: eth0: IAID 4c:ac:d3:77 Oct 18 00:25:18 volumio dhcpcd[808]: eth0: adding address fe80::c762:8109:139c:a5fb Oct 18 00:25:18 volumio dhcpcd[808]: ipv6_addaddr1: Permission denied Oct 18 00:25:18 volumio sh[672]: eth0: soliciting an IPv6 router Oct 18 00:25:18 volumio dhcpcd[740]: eth0: soliciting an IPv6 router Oct 18 00:25:18 volumio dhcpcd[808]: eth0: soliciting a DHCP lease Oct 18 00:25:18 volumio systemd[1]: Started Raise network interfaces. Oct 18 00:25:18 volumio loadcpufreq[746]: Loading cpufreq kernel modules...done (none). Oct 18 00:25:18 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Oct 18 00:25:18 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Oct 18 00:25:19 volumio systemd[1]: iptables.service: Succeeded. Oct 18 00:25:19 volumio cpufrequtils[868]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Oct 18 00:25:19 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Oct 18 00:25:19 volumio sh[672]: eth0: soliciting a DHCP lease Oct 18 00:25:19 volumio dhcpcd[740]: eth0: soliciting a DHCP lease Oct 18 00:25:19 volumio dhcpcd[808]: eth0: soliciting an IPv6 router Oct 18 00:25:19 volumio systemd[1]: Started WPA supplicant. Oct 18 00:25:19 volumio wpa_supplicant[724]: Successfully initialized wpa_supplicant Oct 18 00:25:19 volumio systemd[1]: Reached target Network. Oct 18 00:25:19 volumio systemd[1]: Starting Permit User Sessions... Oct 18 00:25:19 volumio systemd[1]: Reached target Network is Online. Oct 18 00:25:19 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Oct 18 00:25:19 volumio systemd[1]: Starting Samba NMB Daemon... Oct 18 00:25:19 volumio systemd[1]: Starting Network Time Service... Oct 18 00:25:19 volumio systemd[1]: Starting /etc/rc.local Compatibility... Oct 18 00:25:20 volumio systemd[1]: Starting Music Player Daemon... Oct 18 00:25:20 volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Oct 18 00:25:20 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 18 00:25:20 volumio systemd[1]: Started Permit User Sessions. Oct 18 00:25:20 volumio systemd[1]: Started /etc/rc.local Compatibility. Oct 18 00:25:20 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Oct 18 00:25:20 volumio volumio-time-update[741]: volumio-time-update-util: Date not found in response Oct 18 00:25:20 volumio volumio-time-update[741]: volumio-time-update-util: Retrying in 5 seconds... Oct 18 00:25:20 volumio systemd[1]: Starting Hold until boot process finishes up... Oct 18 00:25:20 volumio volumio-remote-updater[712]: Error: No active session Oct 18 00:25:20 volumio volumio-remote-updater[712]: [2025-10-18 00:25:20] [info] asio async_connect error: system:111 (Connection refused) Oct 18 00:25:20 volumio volumio-remote-updater[712]: [2025-10-18 00:25:20] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 18 00:25:20 volumio volumio-remote-updater[712]: [2025-10-18 00:25:20] [error] handle_connect error: Underlying Transport Error Oct 18 00:25:20 volumio systemd[1]: Received SIGRTMIN+21 from PID 326 (plymouthd). Oct 18 00:25:20 volumio ifplugd[886]: Network Interface Plugging Daemon...error: unexpectedly disconnected from boot status daemon Oct 18 00:25:20 volumio systemd[1]: plymouth-quit.service: Succeeded. Oct 18 00:25:20 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Oct 18 00:25:20 volumio systemd[1]: plymouth-quit-wait.service: Succeeded. Oct 18 00:25:20 volumio systemd[1]: Started Hold until boot process finishes up. Oct 18 00:25:20 volumio systemd[1]: Received SIGRTMIN+21 from PID 326 (n/a). Oct 18 00:25:20 volumio systemd[1]: Started Getty on tty1. Oct 18 00:25:20 volumio systemd[1]: Started Serial Getty on ttyS0. Oct 18 00:25:20 volumio systemd[1]: Reached target Login Prompts. Oct 18 00:25:20 volumio ifplugd(eth0)[916]: ifplugd 0.28 initializing. Oct 18 00:25:20 volumio ifplugd(eth0)[916]: Using interface eth0/02:81:4C:AC:D3:77 with driver (version: Jan_2016) Oct 18 00:25:20 volumio ifplugd(eth0)[916]: Using detection mode: SIOCETHTOOL Oct 18 00:25:20 volumio ifplugd(eth0)[916]: Initialization complete, link beat detected. Oct 18 00:25:20 volumio ifplugd(eth0)[916]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Oct 18 00:25:20 volumio ntpd[889]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 18 00:25:20 volumio ntpd[889]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 18 00:25:20 volumio systemd[1]: Started Network Time Service. Oct 18 00:25:20 volumio ifplugd(eth0)[916]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Oct 18 00:25:20 volumio sh[672]: eth0: offered 192.168.100.55 from 192.168.100.1 Oct 18 00:25:20 volumio dhcpcd[740]: eth0: offered 192.168.100.55 from 192.168.100.1 Oct 18 00:25:20 volumio sh[672]: eth0: probing address 192.168.100.55/24 Oct 18 00:25:20 volumio dhcpcd[740]: eth0: probing address 192.168.100.55/24 Oct 18 00:25:20 volumio ntpd[922]: proto: precision = 0.791 usec (-20) Oct 18 00:25:20 volumio sudo[891]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 18 00:25:20 volumio sudo[891]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:25:20 volumio sudo[891]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 18 00:25:20 volumio sudo[891]: pam_unix(sudo:session): session closed for user root Oct 18 00:25:20 volumio ntpd[922]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 18 00:25:20 volumio ntpd[922]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 18 00:25:20 volumio ntpd[922]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1025 days ago Oct 18 00:25:20 volumio ntpd[922]: Listen and drop on 0 v6wildcard [::]:123 Oct 18 00:25:20 volumio ntpd[922]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 18 00:25:20 volumio ntpd[922]: Listen normally on 2 lo 127.0.0.1:123 Oct 18 00:25:20 volumio ntpd[922]: Listening on routing socket on fd #19 for interface updates Oct 18 00:25:20 volumio ntpd[922]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 18 00:25:20 volumio ntpd[922]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 18 00:25:21 volumio volumio[722]: Could not open config: /tmp/upmpdcli.conf Oct 18 00:25:21 volumio ntpd[922]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 18 00:25:22 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 18 00:25:22 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 18 00:25:22 volumio ntpd[922]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 18 00:25:23 volumio nmbd[896]: [2025/10/18 00:25:23.317960, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Oct 18 00:25:23 volumio nmbd[896]: started asyncdns process 929 Oct 18 00:25:23 volumio nmbd[896]: [2025/10/18 00:25:23.322274, 0] ../lib/util/become_daemon.c:149(daemon_status) Oct 18 00:25:23 volumio nmbd[896]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Oct 18 00:25:23 volumio nmbd[896]: [2025/10/18 00:25:23.322613, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Oct 18 00:25:23 volumio nmbd[896]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Oct 18 00:25:23 volumio dhcpcd[808]: eth0: probing for an IPv4LL address Oct 18 00:25:23 volumio ntpd[922]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 18 00:25:23 volumio dhcpcd[808]: eth0: offered 192.168.100.55 from 192.168.100.1 Oct 18 00:25:23 volumio dhcpcd[808]: eth0: probing address 192.168.100.55/24 Oct 18 00:25:24 volumio ntpd[922]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 18 00:25:25 volumio volumio-time-update[741]: volumio-time-update-util: Fetching time from Volumio... Oct 18 00:25:25 volumio volumio-remote-updater[712]: [2025-10-18 00:25:25] [info] asio async_connect error: system:111 (Connection refused) Oct 18 00:25:25 volumio volumio-remote-updater[712]: [2025-10-18 00:25:25] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 18 00:25:25 volumio volumio-remote-updater[712]: [2025-10-18 00:25:25] [error] handle_connect error: Underlying Transport Error Oct 18 00:25:25 volumio volumio-time-update[741]: volumio-time-update-util: Date not found in response Oct 18 00:25:25 volumio volumio-time-update[741]: volumio-time-update-util: Retrying in 5 seconds... Oct 18 00:25:26 volumio sh[672]: eth0: leased 192.168.100.55 for 10800 seconds Oct 18 00:25:26 volumio sh[672]: eth0: adding route to 192.168.100.0/24 Oct 18 00:25:26 volumio sh[672]: eth0: adding default route via 192.168.100.1 Oct 18 00:25:26 volumio dhcpcd[740]: eth0: leased 192.168.100.55 for 10800 seconds Oct 18 00:25:26 volumio dhcpcd[740]: eth0: adding route to 192.168.100.0/24 Oct 18 00:25:26 volumio dhcpcd[740]: eth0: adding default route via 192.168.100.1 Oct 18 00:25:26 volumio avahi-daemon[709]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.100.55. Oct 18 00:25:26 volumio avahi-daemon[709]: New relevant interface eth0.IPv4 for mDNS. Oct 18 00:25:26 volumio avahi-daemon[709]: Registering new address record for 192.168.100.55 on eth0.IPv4. Oct 18 00:25:26 volumio ntpd[922]: ntpd exiting on signal 15 (Terminated) Oct 18 00:25:26 volumio systemd[1]: Stopping Network Time Service... Oct 18 00:25:26 volumio systemd[1]: ntp.service: Succeeded. Oct 18 00:25:26 volumio systemd[1]: Stopped Network Time Service. Oct 18 00:25:26 volumio systemd[1]: Starting Network Time Service... Oct 18 00:25:26 volumio ntpd[974]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 18 00:25:26 volumio ntpd[974]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 18 00:25:26 volumio systemd[1]: Started Network Time Service. Oct 18 00:25:26 volumio ntpd[980]: proto: precision = 0.750 usec (-20) Oct 18 00:25:26 volumio ntpd[980]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 18 00:25:26 volumio ntpd[980]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 18 00:25:26 volumio ntpd[980]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1025 days ago Oct 18 00:25:26 volumio ntpd[980]: Listen and drop on 0 v6wildcard [::]:123 Oct 18 00:25:26 volumio ntpd[980]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 18 00:25:26 volumio ntpd[980]: Listen normally on 2 lo 127.0.0.1:123 Oct 18 00:25:26 volumio ntpd[980]: Listen normally on 3 eth0 192.168.100.55:123 Oct 18 00:25:26 volumio ntpd[980]: Listening on routing socket on fd #20 for interface updates Oct 18 00:25:26 volumio ntpd[980]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 18 00:25:26 volumio ntpd[980]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 18 00:25:26 volumio sh[672]: forked to background, child pid 981 Oct 18 00:25:26 volumio dhcpcd[740]: forked to background, child pid 981 Oct 18 00:25:27 volumio ifplugd(eth0)[916]: client: ifup: interface eth0 already configured Oct 18 00:25:27 volumio sh[672]: eth0=eth0 Oct 18 00:25:27 volumio ifplugd(eth0)[916]: Program executed successfully. Oct 18 00:25:27 volumio ifplugd[886]: start eth0...done. Oct 18 00:25:27 volumio systemd[1]: Started LSB: Brings up/down network automatically. Oct 18 00:25:27 volumio wireless.js[723]: WIRELESS: No wireless interface, exiting Oct 18 00:25:27 volumio iw[1012]: command failed: No such device (-19) Oct 18 00:25:27 volumio iwconfig[1015]: Error for wireless request "Set Power Management" (8B2C) : Oct 18 00:25:27 volumio iwconfig[1015]: SET failed on device wlan0 ; No such device. Oct 18 00:25:27 volumio systemd[1]: wireless.service: Succeeded. Oct 18 00:25:27 volumio systemd[1]: Started Wireless Services. Oct 18 00:25:27 volumio systemd[1]: Started Volumio Backend Module. Oct 18 00:25:27 volumio systemd[1]: Started Volumio Cpu Tweaker. Oct 18 00:25:27 volumio volumio-cpu-tweak[1019]: Setting RT Priority for mpd Oct 18 00:25:27 volumio volumio-cpu-tweak[1019]: Setting MPD Affinity Oct 18 00:25:27 volumio volumio-cpu-tweak[1019]: pid 927's current affinity mask: f Oct 18 00:25:27 volumio volumio-cpu-tweak[1019]: pid 927's new affinity mask: 3 Oct 18 00:25:27 volumio ntpd[980]: Soliciting pool server 178.124.134.106 Oct 18 00:25:27 volumio volumio-cpu-tweak[1019]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Oct 18 00:25:27 volumio volumio-cpu-tweak[1019]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Oct 18 00:25:27 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Oct 18 00:25:28 volumio nmbd[896]: [2025/10/18 00:25:28.343875, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 18 00:25:28 volumio systemd[1]: Started Samba NMB Daemon. Oct 18 00:25:28 volumio nmbd[896]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 18 00:25:28 volumio systemd[1]: Starting Samba Winbind Daemon... Oct 18 00:25:28 volumio ntpd[980]: Soliciting pool server 31.130.200.50 Oct 18 00:25:28 volumio ntpd[980]: Soliciting pool server 82.209.210.87 Oct 18 00:25:28 volumio dhcpcd[808]: eth0: using IPv4LL address 169.254.129.71 Oct 18 00:25:28 volumio dhcpcd[808]: eth0: adding route to 169.254.0.0/16 Oct 18 00:25:28 volumio dhcpcd[808]: eth0: adding default route Oct 18 00:25:28 volumio dhcpcd[981]: eth0: deleted default route via 192.168.100.1 Oct 18 00:25:28 volumio avahi-daemon[709]: Registering new address record for 169.254.129.71 on eth0.IPv4. Oct 18 00:25:28 volumio winbindd[1036]: [2025/10/18 00:25:28.967565, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Oct 18 00:25:28 volumio winbindd[1036]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 18 00:25:29 volumio winbindd[1036]: [2025/10/18 00:25:29.098426, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 18 00:25:29 volumio systemd[1]: Started Samba Winbind Daemon. Oct 18 00:25:29 volumio winbindd[1036]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Oct 18 00:25:29 volumio systemd[1]: Starting Samba SMB Daemon... Oct 18 00:25:29 volumio dhcpcd[808]: eth0: leased 192.168.100.55 for 10800 seconds Oct 18 00:25:29 volumio dhcpcd[808]: eth0: adding route to 192.168.100.0/24 Oct 18 00:25:29 volumio dhcpcd[808]: eth0: changing default route via 192.168.100.1 Oct 18 00:25:29 volumio systemd[1]: Stopping Network Time Service... Oct 18 00:25:30 volumio ntpd[980]: ntpd exiting on signal 15 (Terminated) Oct 18 00:25:30 volumio ntpd[980]: 178.124.134.106 local addr 192.168.100.55 -> Oct 18 00:25:30 volumio ntpd[980]: 31.130.200.50 local addr 192.168.100.55 -> Oct 18 00:25:30 volumio ntpd[980]: 82.209.210.87 local addr 192.168.100.55 -> Oct 18 00:25:30 volumio systemd[1]: ntp.service: Succeeded. Oct 18 00:25:30 volumio systemd[1]: Stopped Network Time Service. Oct 18 00:25:30 volumio systemd[1]: Starting Network Time Service... Oct 18 00:25:30 volumio volumio-time-update[741]: volumio-time-update-util: Fetching time from Volumio... Oct 18 00:25:30 volumio mpd[927]: Oct 18 00:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 18 00:25:30 volumio ntpd[1091]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 18 00:25:30 volumio ntpd[1091]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 18 00:25:30 volumio systemd[1]: Started Network Time Service. Oct 18 00:25:30 volumio ntpd[1102]: proto: precision = 0.750 usec (-20) Oct 18 00:25:30 volumio dhcpcd[808]: eth0: deleting route to 169.254.0.0/16 Oct 18 00:25:30 volumio avahi-daemon[709]: Withdrawing address record for 169.254.129.71 on eth0. Oct 18 00:25:30 volumio ntpd[1102]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 18 00:25:30 volumio ntpd[1102]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 18 00:25:30 volumio ntpd[1102]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1025 days ago Oct 18 00:25:30 volumio systemd[1]: Started Music Player Daemon. Oct 18 00:25:30 volumio ntpd[1102]: Listen and drop on 0 v6wildcard [::]:123 Oct 18 00:25:30 volumio ntpd[1102]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 18 00:25:30 volumio ntpd[1102]: Listen normally on 2 lo 127.0.0.1:123 Oct 18 00:25:30 volumio ntpd[1102]: Listen normally on 3 eth0 192.168.100.55:123 Oct 18 00:25:30 volumio ntpd[1102]: Listening on routing socket on fd #20 for interface updates Oct 18 00:25:30 volumio ntpd[1102]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 18 00:25:30 volumio ntpd[1102]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 18 00:25:30 volumio mpd_monitor.sh[718]: MPD Monitor Service: MPD restarted due to no mpc output. Oct 18 00:25:31 volumio volumio-time-update[741]: volumio-time-update-util: Setting system time to: 2025-10-18 00:25:30 Oct 18 00:25:31 volumio sudo[1123]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-10-18 00:25:30 Oct 18 00:25:31 volumio sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:25:31 volumio dbus-daemon[705]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.5' (uid=0 pid=1124 comm="timedatectl set-time 2025-10-18 00:25:30 ") Oct 18 00:25:31 volumio systemd[1]: Starting Time & Date Service... Oct 18 00:25:31 volumio smbd[1104]: [2025/10/18 00:25:31.800041, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 18 00:25:31 volumio systemd[1]: Started Samba SMB Daemon. Oct 18 00:25:31 volumio smbd[1104]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Oct 18 00:25:31 volumio dbus-daemon[705]: [system] Successfully activated service 'org.freedesktop.timedate1' Oct 18 00:25:31 volumio systemd[1]: Started Time & Date Service. Oct 18 00:25:31 volumio ntpd[1102]: Soliciting pool server 178.124.134.106 Oct 18 00:25:30 volumio systemd-timedated[1129]: Changed local time to Sat Oct 18 00:25:30 2025 Oct 18 00:25:30 volumio sudo[1123]: pam_unix(sudo:session): session closed for user root Oct 18 00:25:30 volumio volumio-time-update[741]: volumio-time-update-util: System time updated successfully. Oct 18 00:25:30 volumio systemd[1]: Started Volumio Time Update Utility. Oct 18 00:25:30 volumio systemd[1]: Reached target Multi-User System. Oct 18 00:25:30 volumio systemd[1]: Reached target Graphical Interface. Oct 18 00:25:30 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 18 00:25:30 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 18 00:25:30 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 18 00:25:30 volumio systemd[1]: Startup finished in 7.665s (kernel) + 20.105s (userspace) = 27.770s. Oct 18 00:25:30 volumio ntpd[1102]: Soliciting pool server 31.130.200.50 Oct 18 00:25:31 volumio volumio[1018]: info: ------------------------------------------- Oct 18 00:25:31 volumio volumio[1018]: info: ----- Volumio3 ---- Oct 18 00:25:31 volumio volumio[1018]: info: ------------------------------------------- Oct 18 00:25:31 volumio volumio[1018]: info: ----- System startup ---- Oct 18 00:25:31 volumio volumio[1018]: info: ------------------------------------------- Oct 18 00:25:31 volumio ntpd[1102]: Soliciting pool server 82.209.210.87 Oct 18 00:25:31 volumio ntpd[1102]: Soliciting pool server 178.124.164.106 Oct 18 00:25:32 volumio volumio-remote-updater[712]: [2025-10-18 00:25:32] [connect] Successful connection Oct 18 00:25:32 volumio ntpd[1102]: Soliciting pool server 178.124.164.105 Oct 18 00:25:32 volumio ntpd[1102]: Soliciting pool server 178.159.240.10 Oct 18 00:25:33 volumio volumio[1018]: info: MYVOLUMIO Environment detected Oct 18 00:25:33 volumio volumio[1018]: info: Plugin folders cleanup Oct 18 00:25:33 volumio volumio[1018]: info: Scanning into folder /volumio/app/plugins/ Oct 18 00:25:33 volumio volumio[1018]: info: Scanning category audio_interface Oct 18 00:25:33 volumio volumio[1018]: info: Scanning category miscellanea Oct 18 00:25:33 volumio volumio[1018]: info: Scanning category music_service Oct 18 00:25:33 volumio volumio[1018]: info: Scanning category plugins.json Oct 18 00:25:33 volumio volumio[1018]: info: Scanning category system_controller Oct 18 00:25:33 volumio volumio[1018]: info: Scanning category user_interface Oct 18 00:25:33 volumio volumio[1018]: info: Scanning into folder /data/plugins/ Oct 18 00:25:33 volumio volumio[1018]: info: Scanning category system_controller Oct 18 00:25:33 volumio volumio[1018]: info: Plugin folders cleanup completed Oct 18 00:25:33 volumio volumio[1018]: info: ------------------------------------------- Oct 18 00:25:33 volumio volumio[1018]: info: ----- Core plugins startup ---- Oct 18 00:25:33 volumio volumio[1018]: info: ------------------------------------------- Oct 18 00:25:33 volumio volumio[1018]: info: Loading plugins from folder /volumio/app/plugins/ Oct 18 00:25:33 volumio volumio[1018]: info: Adding plugin upnp to MyMusic Plugins Oct 18 00:25:33 volumio volumio[1018]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 18 00:25:33 volumio volumio[1018]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 18 00:25:33 volumio volumio[1018]: info: Loading plugins from folder /data/plugins/ Oct 18 00:25:33 volumio volumio[1018]: info: Loading plugin "system"... Oct 18 00:25:33 volumio volumio[1018]: info: Loading plugin "ampswitch"... Oct 18 00:25:33 volumio ntpd[1102]: Soliciting pool server 2a02:2208:1:3::12 Oct 18 00:25:34 volumio volumio[1018]: info: Applying required configuration parameters for plugin ampswitch Oct 18 00:25:34 volumio volumio[1018]: info: Loading plugin "appearance"... Oct 18 00:25:36 volumio kernel: vcc3v0: disabling Oct 18 00:25:36 volumio kernel: vcc5v0: disabling Oct 18 00:25:36 volumio volumio[1018]: info: Loading plugin "network"... Oct 18 00:25:36 volumio volumio[1018]: info: Refreshing Cached IP Addresses Oct 18 00:25:36 volumio volumio[1018]: info: Loading plugin "services"... Oct 18 00:25:36 volumio volumio[1018]: info: Loading plugin "alsa_controller"... Oct 18 00:25:36 volumio sudo[1150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 18 00:25:36 volumio sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:25:36 volumio sudo[1152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 18 00:25:36 volumio sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:25:36 volumio sudo[1155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 18 00:25:36 volumio sudo[1155]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:25:36 volumio sudo[1150]: pam_unix(sudo:session): session closed for user root Oct 18 00:25:36 volumio sudo[1152]: pam_unix(sudo:session): session closed for user root Oct 18 00:25:36 volumio sudo[1155]: pam_unix(sudo:session): session closed for user root Oct 18 00:25:36 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 18 00:25:36 volumio volumio[1018]: info: Loading plugin "wizard"... Oct 18 00:25:36 volumio volumio[1018]: info: Loading plugin "networkfs"... Oct 18 00:25:36 volumio volumio[1018]: info: Starting Udev Watcher for removable devices Oct 18 00:25:36 volumio volumio[1018]: info: Ignoring mount for partition: boot Oct 18 00:25:36 volumio volumio[1018]: info: Ignoring mount for partition: volumio Oct 18 00:25:36 volumio volumio[1018]: info: Ignoring mount for partition: volumio_data Oct 18 00:25:36 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 18 00:25:36 volumio volumio[1018]: info: Loading plugin "volumio_command_line_client"... Oct 18 00:25:36 volumio volumio[1018]: info: Loading plugin "upnp"... Oct 18 00:25:36 volumio volumio[1018]: info: [1760736336994] Starting Upmpd Daemon Oct 18 00:25:36 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 18 00:25:36 volumio volumio[1018]: info: Loading plugin "my_music"... Oct 18 00:25:37 volumio volumio[1018]: info: Loading plugin "mpd"... Oct 18 00:25:37 volumio volumio[1018]: info: Loading plugin "upnp_browser"... Oct 18 00:25:39 volumio ntpd[1102]: receive: Unexpected origin timestamp 0xec9d32d1.e9bfdb2b does not match aorg 0000000000.00000000 from server@178.124.164.105 xmt 0xec9d32d3.27695608 Oct 18 00:25:39 volumio ntpd[1102]: receive: Unexpected origin timestamp 0xec9d32d1.e9c5dcf2 does not match aorg 0000000000.00000000 from server@178.124.164.106 xmt 0xec9d32d3.2783639f Oct 18 00:25:39 volumio ntpd[1102]: receive: Unexpected origin timestamp 0xec9d32d1.e9c34597 does not match aorg 0000000000.00000000 from server@82.209.210.87 xmt 0xec9d32d3.28ade834 Oct 18 00:25:39 volumio volumio[1018]: info: Loading plugin "alarm-clock"... Oct 18 00:25:39 volumio volumio[1018]: info: Loading plugin "airplay_emulation"... Oct 18 00:25:39 volumio volumio[1018]: info: Starting Shairport Sync Oct 18 00:25:39 volumio volumio[1018]: info: Loading plugin "last_100"... Oct 18 00:25:40 volumio volumio[1018]: info: Loading plugin "webradio"... Oct 18 00:25:40 volumio volumio[1018]: info: Loading plugin "i2s_dacs"... Oct 18 00:25:40 volumio volumio[1018]: info: I2S DAC not set, start Auto-detection Oct 18 00:25:40 volumio volumio[1018]: info: Loading plugin "volumiodiscovery"... Oct 18 00:25:40 volumio volumio[1018]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 18 00:25:40 volumio volumio[1018]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 18 00:25:40 volumio node[1018]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 18 00:25:40 volumio volumio[1018]: *** WARNING *** For more information see Oct 18 00:25:40 volumio volumio[1018]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 18 00:25:40 volumio volumio[1018]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 18 00:25:40 volumio volumio[1018]: *** WARNING *** For more information see Oct 18 00:25:40 volumio node[1018]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 18 00:25:40 volumio node[1018]: *** WARNING *** For more information see Oct 18 00:25:40 volumio node[1018]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 18 00:25:40 volumio node[1018]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 18 00:25:40 volumio node[1018]: *** WARNING *** For more information see Oct 18 00:25:40 volumio volumio[1018]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 18 00:25:40 volumio volumio[1018]: info: Discovery: Started advertising with name: Volumio Oct 18 00:25:40 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 18 00:25:40 volumio volumio[1018]: info: Loading plugin "autostart"... Oct 18 00:25:40 volumio volumio[1018]: info: Applying required configuration parameters for plugin autostart Oct 18 00:25:40 volumio volumio[1018]: info: AutoStart - onVolumioStart - read config.json Oct 18 00:25:40 volumio volumio[1018]: info: Loading plugin "outputs"... Oct 18 00:25:40 volumio volumio[1018]: info: Loading plugin "albumart"... Oct 18 00:25:40 volumio volumio[1018]: info: Plugin example_plugin is not enabled Oct 18 00:25:40 volumio volumio[1018]: info: Loading plugin "inputs"... Oct 18 00:25:40 volumio volumio[1018]: info: Loading plugin "updater_comm"... Oct 18 00:25:41 volumio volumio[1018]: info: Plugin mpdemulation is not enabled Oct 18 00:25:41 volumio volumio[1018]: info: Loading plugin "rest_api"... Oct 18 00:25:41 volumio volumio[1018]: info: Loading plugin "websocket"... Oct 18 00:25:41 volumio volumio[1018]: info: Starting Socket.io Server version 2.3.0 Oct 18 00:25:41 volumio volumio[1018]: info: Loading plugin "scheduledrestart"... Oct 18 00:25:41 volumio volumio[1018]: Forking 3 albumart workers Oct 18 00:25:41 volumio volumio[1018]: info: Applying required configuration parameters for plugin scheduledrestart Oct 18 00:25:41 volumio volumio[1018]: info: Loading i18n strings for locale ru Oct 18 00:25:41 volumio volumio[1018]: Updating browse sources language Oct 18 00:25:41 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 00:25:42 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 00:25:42 volumio volumio[1018]: info: CoreCommandRouter::initPlayerControls Oct 18 00:25:42 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:42 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:42 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:42 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:42 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:42 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:42 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:42 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:42 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 00:25:42 volumio volumio[1018]: Express server listening on port 3000 Oct 18 00:25:42 volumio volumio[1018]: [Metrics] WebUI: 10s 808.59ms Oct 18 00:25:42 volumio volumio[1018]: info: CoreStateMachine::resetVolumioState Oct 18 00:25:42 volumio volumio[1018]: info: CoreStateMachine::getcurrentVolume Oct 18 00:25:42 volumio volumio[1018]: info: CoreCommandRouter::volumioRetrievevolume Oct 18 00:25:42 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:25:42 volumio volumio[1018]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 18 00:25:42 volumio volumio[1018]: info: Completed loading Core Plugins Oct 18 00:25:42 volumio volumio[1018]: info: Preparing to generate the ALSA configuration file Oct 18 00:25:42 volumio volumio[1018]: info: Volumio Network Manager: Network status updated: 1 Oct 18 00:25:42 volumio volumio[1018]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Oct 18 00:25:42 volumio volumio[1018]: wlan0 Interface doesn't support scanning. Oct 18 00:25:42 volumio volumio[1018]: info: Cannot use regular scanning, forcing with ap-force Oct 18 00:25:42 volumio sudo[1216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Oct 18 00:25:42 volumio sudo[1216]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:25:42 volumio sudo[1216]: pam_unix(sudo:session): session closed for user root Oct 18 00:25:42 volumio volumio[1018]: command failed: No such device (-19) Oct 18 00:25:42 volumio volumio[1018]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Oct 18 00:25:42 volumio volumio[1018]: command failed: No such device (-19) Oct 18 00:25:43 volumio volumio[1018]: verbose: New Socket.io Connection to 192.168.100.55 from 192.168.100.2 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Oct 18 00:25:43 volumio volumio[1018]: verbose: New Socket.io Connection to 192.168.100.55 from 192.168.100.2 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Oct 18 00:25:43 volumio volumio[1018]: verbose: New Socket.io Connection to 192.168.100.55 from 192.168.100.2 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Oct 18 00:25:43 volumio volumio[1018]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 18 00:25:43 volumio volumio[1018]: info: Reloading queue from file Oct 18 00:25:43 volumio volumio[1018]: Starting albumart workers Oct 18 00:25:43 volumio volumio[1018]: Starting albumart workers Oct 18 00:25:43 volumio volumio[1018]: Starting albumart workers Oct 18 00:25:43 volumio volumio[1018]: info: Asound.conf file unchanged, so no further update is needed Oct 18 00:25:43 volumio volumio[1018]: info: Output device has changed, restarting MPD Oct 18 00:25:43 volumio volumio[1018]: info: Output device has changed, restarting Shairport Sync Oct 18 00:25:43 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:43 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:25:43 volumio sudo[1228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 18 00:25:43 volumio sudo[1228]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:25:43 volumio sudo[1229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 18 00:25:43 volumio sudo[1229]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:25:43 volumio sudo[1228]: pam_unix(sudo:session): session closed for user root Oct 18 00:25:43 volumio systemd[1]: Stopping Music Player Daemon... Oct 18 00:25:43 volumio volumio[1018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 18 00:25:43 volumio volumio[1018]: info: ___________ START PLUGINS ___________ Oct 18 00:25:43 volumio volumio[1018]: error: Plugin ampswitch failed to start! Error: UNKNOWN: unknown error, write Oct 18 00:25:43 volumio volumio[1018]: info: ControllerMpd::onStart: Initializing MPD Oct 18 00:25:43 volumio volumio[1018]: info: Creating MPD Configuration file Oct 18 00:25:43 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 18 00:25:43 volumio volumio[1018]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 18 00:25:43 volumio volumio[1018]: info: [1760736343819] CoreMusicLibrary::Adding element Медиа-серверы Oct 18 00:25:43 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 00:25:43 volumio sudo[1242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 18 00:25:43 volumio sudo[1242]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:25:43 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:43 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:25:43 volumio sudo[1242]: pam_unix(sudo:session): session closed for user root Oct 18 00:25:43 volumio sudo[1243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 18 00:25:43 volumio sudo[1243]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:25:44 volumio volumio[1018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 18 00:25:44 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:44 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:25:44 volumio systemd[1]: mpd.service: Succeeded. Oct 18 00:25:44 volumio systemd[1]: Stopped Music Player Daemon. Oct 18 00:25:44 volumio systemd[1]: Starting Music Player Daemon... Oct 18 00:25:44 volumio volumio[1018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 18 00:25:44 volumio volumio[1018]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 18 00:25:44 volumio volumio[1018]: info: [1760736344291] CoreMusicLibrary::Adding element Last_100 Oct 18 00:25:44 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 00:25:44 volumio volumio[1018]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 18 00:25:44 volumio volumio[1018]: info: [1760736344308] CoreMusicLibrary::Adding element Webradio Oct 18 00:25:44 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 00:25:44 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 18 00:25:44 volumio volumio[1018]: info: Initializing BBC Radios Oct 18 00:25:44 volumio sudo[1252]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 18 00:25:44 volumio sudo[1252]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:25:44 volumio sudo[1252]: pam_unix(sudo:session): session closed for user root Oct 18 00:25:44 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 18 00:25:44 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:25:44 volumio volumio[1018]: info: AutoStart - onStart Oct 18 00:25:44 volumio volumio[1018]: info: Scheduling reboots with hours: 7, minutes: 0 in timezone: Europe/Minsk Oct 18 00:25:44 volumio volumio[1018]: info: Scheduling job with cron expression: 0 0 7 * * 0 Oct 18 00:25:44 volumio volumio[1018]: info: Scheduling job with cron expression: 0 0 7 * * 1 Oct 18 00:25:44 volumio volumio[1018]: info: Scheduling job with cron expression: 0 0 7 * * 2 Oct 18 00:25:44 volumio volumio[1018]: info: Scheduling job with cron expression: 0 0 7 * * 3 Oct 18 00:25:44 volumio volumio[1018]: info: Scheduling job with cron expression: 0 0 7 * * 4 Oct 18 00:25:44 volumio volumio[1018]: info: Scheduling job with cron expression: 0 0 7 * * 5 Oct 18 00:25:44 volumio volumio[1018]: info: Scheduling job with cron expression: 0 0 7 * * 6 Oct 18 00:25:44 volumio volumio[1018]: info: Volumio Calling Home Oct 18 00:25:45 volumio volumio[1018]: info: CoreStateMachine::setRepeat null single undefined Oct 18 00:25:45 volumio volumio[1018]: info: CoreStateMachine::pushState Oct 18 00:25:45 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 18 00:25:45 volumio volumio[1018]: info: CoreStateMachine::setRandom null Oct 18 00:25:45 volumio volumio[1018]: info: CoreStateMachine::pushState Oct 18 00:25:45 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 18 00:25:45 volumio volumio[1018]: info: VolumeController:: Volume=0 Mute =false Oct 18 00:25:45 volumio volumio[1018]: info: CoreStateMachine::pushState Oct 18 00:25:45 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 18 00:25:45 volumio volumio[1018]: info: CoreStateMachine::updateTrackBlock Oct 18 00:25:45 volumio volumio[1018]: info: CorePlayQueue::getTrackBlock Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::volumioRetrievevolume Oct 18 00:25:45 volumio volumio[1018]: info: MPD Permissions set Oct 18 00:25:45 volumio volumio[1018]: info: MPD Permissions set Oct 18 00:25:45 volumio volumio[1018]: info: Discovery: adding a9559fd9-9d82-47c3-8f81-007e77ea7049 Oct 18 00:25:45 volumio volumio[1018]: info: Discovery: Found device Volumio Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Oct 18 00:25:45 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 00:25:45 volumio volumio[1018]: info: Volumio called home Oct 18 00:25:45 volumio volumio[1018]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 18 00:25:45 volumio systemd[1]: systemd-fsckd.service: Succeeded. Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 18 00:25:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:25:46 volumio volumio[1018]: info: VolumeController:: Volume=0 Mute =false Oct 18 00:25:46 volumio volumio[1018]: info: CoreStateMachine::pushState Oct 18 00:25:46 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 00:25:46 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 18 00:25:46 volumio volumio[1018]: info: Starting Shairport Sync Oct 18 00:25:46 volumio volumio[1018]: info: Starting Shairport Sync Oct 18 00:25:46 volumio volumio[1018]: info: Starting Shairport Sync Oct 18 00:25:46 volumio sudo[1307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 18 00:25:46 volumio sudo[1307]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:25:46 volumio sudo[1309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 18 00:25:46 volumio sudo[1309]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:25:46 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Oct 18 00:25:46 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 00:25:46 volumio sudo[1313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 18 00:25:46 volumio sudo[1313]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:25:46 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 18 00:25:46 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 18 00:25:46 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 18 00:25:46 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 18 00:25:46 volumio sudo[1313]: pam_unix(sudo:session): session closed for user root Oct 18 00:25:46 volumio sudo[1307]: pam_unix(sudo:session): session closed for user root Oct 18 00:25:46 volumio sudo[1309]: pam_unix(sudo:session): session closed for user root Oct 18 00:25:46 volumio volumio[1018]: info: Shairport-Sync Started Oct 18 00:25:46 volumio volumio[1018]: Error adding Membership: Error: addMembership EINVAL Oct 18 00:25:46 volumio volumio[1018]: info: Shairport-Sync Started Oct 18 00:25:46 volumio volumio[1018]: info: Shairport-Sync Started Oct 18 00:25:47 volumio mpd[1257]: Oct 18 00:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 18 00:25:47 volumio systemd[1]: Started Music Player Daemon. Oct 18 00:25:47 volumio sudo[1243]: pam_unix(sudo:session): session closed for user root Oct 18 00:25:47 volumio sudo[1229]: pam_unix(sudo:session): session closed for user root Oct 18 00:25:47 volumio volumio[1018]: info: Completed starting Core Plugins Oct 18 00:25:47 volumio volumio[1018]: info: ------------------------------------------- Oct 18 00:25:47 volumio volumio[1018]: info: ----- MyVolumio plugins startup ---- Oct 18 00:25:47 volumio volumio[1018]: info: ------------------------------------------- Oct 18 00:25:47 volumio volumio[1018]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 18 00:25:48 volumio volumio[1018]: error: MPD error: The expression evaluated to a falsy value: Oct 18 00:25:48 volumio volumio[1018]: assert.ok(self.idling) Oct 18 00:25:48 volumio volumio[1018]: error: The expression evaluated to a falsy value: Oct 18 00:25:48 volumio volumio[1018]: assert.ok(self.idling) Oct 18 00:25:48 volumio volumio[1018]: error: updateQueue error: null Oct 18 00:25:48 volumio volumio[1018]: info: MPD running with PID1257 Oct 18 00:25:48 volumio volumio[1018]: ,establishing connection Oct 18 00:25:48 volumio volumio[1018]: error: updateQueue error: null Oct 18 00:25:48 volumio volumio[1018]: verbose: New Socket.io Connection to 192.168.100.55 from 192.168.100.2 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Oct 18 00:25:48 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 18 00:25:48 volumio volumio[1018]: info: CoreCommandRouter::volumioGetVisibleSources Oct 18 00:25:48 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 00:25:48 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Oct 18 00:25:48 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 00:25:48 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Oct 18 00:25:48 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 00:25:48 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 18 00:25:48 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 18 00:25:48 volumio volumio[1018]: info: Received Get System Info Oct 18 00:25:48 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 18 00:25:48 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 18 00:25:48 volumio volumio[1018]: info: Discovery: Getting this device information Oct 18 00:25:48 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Oct 18 00:25:48 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 00:25:48 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 18 00:25:48 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Oct 18 00:25:48 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 00:25:48 volumio volumio[1018]: info: Listing playlists Oct 18 00:25:48 volumio sudo[1338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 18 00:25:48 volumio sudo[1338]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:25:48 volumio sudo[1338]: pam_unix(sudo:session): session closed for user root Oct 18 00:25:48 volumio sudo[1340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 18 00:25:48 volumio sudo[1340]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:25:48 volumio sudo[1340]: pam_unix(sudo:session): session closed for user root Oct 18 00:25:48 volumio sudo[1346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 18 00:25:48 volumio sudo[1346]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:25:48 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Oct 18 00:25:48 volumio sudo[1346]: pam_unix(sudo:session): session closed for user root Oct 18 00:25:48 volumio volumio[1018]: info: Upmpdcli Daemon Started Oct 18 00:25:48 volumio volumio[1348]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 18 00:25:48 volumio volumio-remote-updater[712]: [2025-10-18 00:25:48] [connect] Successful connection Oct 18 00:25:48 volumio volumio-remote-updater[712]: [2025-10-18 00:25:48] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1760736348 101 Oct 18 00:25:48 volumio volumio[1018]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 7 Oct 18 00:25:49 volumio volumio[1348]: ........++++ Oct 18 00:25:52 volumio volumio[1018]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 18 00:25:56 volumio volumio[1018]: info: Adding plugin bluetooth to MyMusic Plugins Oct 18 00:25:56 volumio volumio[1018]: info: Adding plugin multiroom to MyMusic Plugins Oct 18 00:25:56 volumio volumio[1018]: info: Adding plugin metavolumio to MyMusic Plugins Oct 18 00:25:56 volumio volumio[1018]: info: Adding plugin cd_controller to MyMusic Plugins Oct 18 00:25:56 volumio volumio[1018]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 18 00:25:56 volumio volumio[1018]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 18 00:25:56 volumio volumio[1018]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 18 00:25:56 volumio volumio[1018]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 18 00:25:58 volumio volumio[1018]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 18 00:25:58 volumio volumio[1018]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 18 00:25:58 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:25:58 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:25:58 volumio volumio[1018]: info: Starting MyVolumio Remote Streaming Endpoints Oct 18 00:25:58 volumio volumio[1018]: info: MyVolumio login type: Token Oct 18 00:25:58 volumio volumio[1018]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 18 00:25:58 volumio volumio[1018]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 18 00:26:00 volumio volumio[1018]: info: Starting Streaming Service Transparent Proxy Oct 18 00:26:00 volumio volumio[1018]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 18 00:26:00 volumio volumio[1018]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 18 00:26:00 volumio volumio[1018]: info: Streaming services startup Oct 18 00:26:00 volumio volumio[1018]: info: Starting Streaming Daemon Oct 18 00:26:00 volumio sudo[1388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 18 00:26:00 volumio sudo[1388]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:26:00 volumio volumio[1018]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 18 00:26:00 volumio sudo[1388]: pam_unix(sudo:session): session closed for user root Oct 18 00:26:00 volumio volumio[1018]: error: Cannot start Volumio Streaming Daemon Oct 18 00:26:00 volumio volumio[1018]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 18 00:26:00 volumio volumio[1018]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 18 00:26:01 volumio systemd[1]: systemd-timedated.service: Succeeded. Oct 18 00:26:01 volumio volumio[1018]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 18 00:26:01 volumio sudo[1400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 18 00:26:01 volumio sudo[1400]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:26:01 volumio sudo[1398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 18 00:26:01 volumio sudo[1398]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:26:01 volumio sudo[1398]: pam_unix(sudo:session): session closed for user root Oct 18 00:26:01 volumio sudo[1400]: pam_unix(sudo:session): session closed for user root Oct 18 00:26:02 volumio volumio[1018]: verbose: New Socket.io Connection to 192.168.100.55 from 192.168.100.2 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Oct 18 00:26:02 volumio volumio[1018]: info: MyVolumio token set successfully Oct 18 00:26:02 volumio volumio[1018]: info: MYVOLUMIO: Adding device Oct 18 00:26:02 volumio volumio[1018]: info: MYVOLUMIO: Evaluating Server Oct 18 00:26:02 volumio volumio[1018]: info: MyVolumio status changed Oct 18 00:26:02 volumio volumio[1018]: info: Streaming services startup Oct 18 00:26:02 volumio volumio[1018]: info: Starting Streaming Daemon Oct 18 00:26:02 volumio volumio[1018]: info: Removing browser output: myVolumio user plan is not superstar Oct 18 00:26:02 volumio volumio[1018]: info: Removing audio output: Oct 18 00:26:02 volumio volumio[1018]: info: Stoppping Tunnel 1 Oct 18 00:26:02 volumio volumio[1348]: ..............................................................................................................................................++++ Oct 18 00:26:02 volumio volumio[1348]: e is 65537 (0x010001) Oct 18 00:26:02 volumio sudo[1423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 18 00:26:02 volumio sudo[1423]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:26:02 volumio volumio[1018]: STREAMING PROXY: Starting server on port 3245 Oct 18 00:26:03 volumio volumio[1018]: Node JS runtime: 14 Oct 18 00:26:03 volumio sudo[1423]: pam_unix(sudo:session): session closed for user root Oct 18 00:26:03 volumio volumio[1348]: writing RSA key Oct 18 00:26:03 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 18 00:26:03 volumio sudo[1427]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 18 00:26:03 volumio sudo[1427]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 00:26:03 volumio volumio[1018]: info: CoreCommandRouter::volumioGetVisibleSources Oct 18 00:26:03 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 00:26:03 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Oct 18 00:26:03 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 00:26:03 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 18 00:26:03 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 18 00:26:03 volumio volumio[1018]: info: Received Get System Info Oct 18 00:26:03 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 18 00:26:03 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 18 00:26:03 volumio volumio[1018]: info: Discovery: Getting this device information Oct 18 00:26:03 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Oct 18 00:26:03 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 00:26:03 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 18 00:26:03 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Oct 18 00:26:03 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 00:26:03 volumio sudo[1427]: pam_unix(sudo:session): session closed for user root Oct 18 00:26:03 volumio volumio[1018]: info: Listing playlists Oct 18 00:26:03 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 18 00:26:03 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 18 00:26:03 volumio volumio[1018]: error: Cannot start Volumio Streaming Daemon Oct 18 00:26:03 volumio volumio[1018]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 18 00:26:03 volumio volumio[1018]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 18 00:26:03 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Oct 18 00:26:03 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 00:26:03 volumio volumio[1018]: info: Remote SSH Stopped Oct 18 00:26:03 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 18 00:26:03 volumio volumio[1018]: info: Setting Geolocation for MyVolumio to eu4 Oct 18 00:26:03 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:26:03 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:26:03 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:26:03 volumio volumio[1018]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 18 00:26:03 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 18 00:26:03 volumio volumio[1018]: info: Received Get System Info Oct 18 00:26:03 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 18 00:26:03 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 18 00:26:03 volumio volumio[1018]: info: Discovery: Getting this device information Oct 18 00:26:03 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Oct 18 00:26:03 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 00:26:03 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 18 00:26:04 volumio volumio[1018]: info: Updating MyVolumio device info Oct 18 00:26:04 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:26:04 volumio volumio[1018]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 18 00:26:04 volumio volumio[1018]: info: AutoStart - Plugin is starting Oct 18 00:26:04 volumio volumio[1018]: info: CoreCommandRouter::volumioGetQueue Oct 18 00:26:04 volumio volumio[1018]: info: CoreStateMachine::getQueue Oct 18 00:26:04 volumio volumio[1018]: info: CorePlayQueue::getQueue Oct 18 00:26:04 volumio volumio[1018]: info: AutoStart - start playing Oct 18 00:26:04 volumio volumio[1018]: info: AutoStart - start playing with no specific position Oct 18 00:26:04 volumio volumio[1018]: info: CoreCommandRouter::volumioPlay Oct 18 00:26:04 volumio volumio[1018]: info: CoreStateMachine::play index 0 Oct 18 00:26:04 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 18 00:26:04 volumio volumio[1018]: info: CoreStateMachine::stop Oct 18 00:26:04 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 18 00:26:04 volumio volumio[1018]: info: CoreStateMachine::play index undefined Oct 18 00:26:04 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 18 00:26:04 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 00:26:04 volumio volumio[1018]: info: CoreStateMachine::startPlaybackTimer Oct 18 00:26:04 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 00:26:04 volumio volumio[1018]: info: [1760736364623] ControllerWebradio::clearAddPlayTrack Oct 18 00:26:04 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand stop Oct 18 00:26:04 volumio volumio[1018]: info: sendMpdCommand stop took 3 milliseconds Oct 18 00:26:04 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand clear Oct 18 00:26:04 volumio volumio[1018]: info: sendMpdCommand clear took 6 milliseconds Oct 18 00:26:04 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand load "https://s35.derstream.net/lounge.mp3" Oct 18 00:26:04 volumio volumio[1018]: info: Oct 18 00:26:04 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Oct 18 00:26:04 volumio volumio[1018]: info: Ignoring MPD Status Update Oct 18 00:26:04 volumio volumio[1018]: info: Oct 18 00:26:04 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Oct 18 00:26:04 volumio volumio[1018]: info: Ignoring MPD Status Update Oct 18 00:26:04 volumio volumio[1018]: info: Oct 18 00:26:04 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Oct 18 00:26:04 volumio volumio[1018]: info: Ignoring MPD Status Update Oct 18 00:26:04 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 18 00:26:05 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 18 00:26:05 volumio volumio[1018]: info: Received Get System Info Oct 18 00:26:05 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 18 00:26:05 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 18 00:26:05 volumio volumio[1018]: info: Discovery: Getting this device information Oct 18 00:26:05 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Oct 18 00:26:05 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 00:26:05 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 18 00:26:07 volumio volumio[1018]: info: MYVOLUMIO: Adding device Oct 18 00:26:07 volumio volumio[1018]: info: MYVOLUMIO: Evaluating Server Oct 18 00:26:08 volumio volumio[1018]: error: updateQueue error: null Oct 18 00:26:08 volumio volumio[1018]: error: updateQueue error: null Oct 18 00:26:08 volumio volumio[1018]: error: updateQueue error: null Oct 18 00:26:08 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand add "https://s35.derstream.net/lounge.mp3" Oct 18 00:26:08 volumio volumio[1018]: info: ------------------------------ 3528ms Oct 18 00:26:08 volumio volumio[1018]: info: ------------------------------ 3523ms Oct 18 00:26:08 volumio volumio[1018]: info: ------------------------------ 3521ms Oct 18 00:26:08 volumio volumio[1018]: Upnp client error: Error: This socket has been ended by the other party Oct 18 00:26:08 volumio volumio[1018]: info: Oct 18 00:26:08 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Oct 18 00:26:08 volumio volumio[1018]: info: Ignoring MPD Status Update Oct 18 00:26:08 volumio volumio[1018]: info: sendMpdCommand add "https://s35.derstream.net/lounge.mp3" took 29 milliseconds Oct 18 00:26:08 volumio volumio[1018]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 18 00:26:08 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand play Oct 18 00:26:08 volumio volumio[1018]: info: Oct 18 00:26:08 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Oct 18 00:26:08 volumio volumio[1018]: info: Ignoring MPD Status Update Oct 18 00:26:08 volumio volumio[1018]: info: Oct 18 00:26:08 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Oct 18 00:26:08 volumio volumio[1018]: info: Ignoring MPD Status Update Oct 18 00:26:08 volumio volumio[1018]: info: ------------------------------ 17ms Oct 18 00:26:08 volumio volumio[1018]: info: sendMpdCommand play took 14 milliseconds Oct 18 00:26:08 volumio volumio[1018]: info: ------------------------------ 13ms Oct 18 00:26:08 volumio volumio[1018]: info: ------------------------------ 10ms Oct 18 00:26:08 volumio volumio[1018]: info: Setting Geolocation for MyVolumio to eu4 Oct 18 00:26:08 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:26:08 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:26:08 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:26:08 volumio volumio[1018]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 18 00:26:09 volumio volumio[1018]: info: Updating MyVolumio device info Oct 18 00:26:09 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:26:09 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:26:09 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:26:09 volumio volumio[1018]: info: Oct 18 00:26:09 volumio volumio[1018]: ---------------------------- MPD announces state update: player Oct 18 00:26:09 volumio volumio[1018]: info: ControllerMpd::getState Oct 18 00:26:09 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Oct 18 00:26:09 volumio volumio[1018]: info: Oct 18 00:26:09 volumio volumio[1018]: ---------------------------- MPD announces state update: player Oct 18 00:26:09 volumio volumio[1018]: info: ControllerMpd::getState Oct 18 00:26:09 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Oct 18 00:26:09 volumio volumio[1018]: info: Oct 18 00:26:09 volumio volumio[1018]: ---------------------------- MPD announces state update: player Oct 18 00:26:09 volumio volumio[1018]: info: ControllerMpd::getState Oct 18 00:26:09 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Oct 18 00:26:09 volumio volumio[1018]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 18 00:26:09 volumio volumio[1018]: info: Oct 18 00:26:09 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Oct 18 00:26:09 volumio volumio[1018]: info: Ignoring MPD Status Update Oct 18 00:26:09 volumio volumio[1018]: info: Oct 18 00:26:09 volumio volumio[1018]: ---------------------------- MPD announces state update: player Oct 18 00:26:09 volumio volumio[1018]: info: sendMpdCommand status took 31 milliseconds Oct 18 00:26:09 volumio volumio[1018]: info: ControllerMpd::getState Oct 18 00:26:09 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Oct 18 00:26:09 volumio volumio[1018]: info: sendMpdCommand status took 30 milliseconds Oct 18 00:26:09 volumio volumio[1018]: info: sendMpdCommand status took 26 milliseconds Oct 18 00:26:09 volumio volumio[1018]: verbose: ControllerMpd::parseState Oct 18 00:26:09 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 18 00:26:09 volumio volumio[1018]: verbose: ControllerMpd::parseState Oct 18 00:26:09 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 18 00:26:09 volumio volumio[1018]: verbose: ControllerMpd::parseState Oct 18 00:26:09 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 18 00:26:09 volumio volumio[1018]: info: Oct 18 00:26:09 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Oct 18 00:26:09 volumio volumio[1018]: info: Ignoring MPD Status Update Oct 18 00:26:09 volumio volumio[1018]: info: Oct 18 00:26:09 volumio volumio[1018]: ---------------------------- MPD announces state update: player Oct 18 00:26:09 volumio volumio[1018]: info: ControllerMpd::getState Oct 18 00:26:09 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Oct 18 00:26:09 volumio volumio[1018]: info: Oct 18 00:26:09 volumio volumio[1018]: ---------------------------- MPD announces system playlist update Oct 18 00:26:09 volumio volumio[1018]: info: Ignoring MPD Status Update Oct 18 00:26:09 volumio volumio[1018]: info: Oct 18 00:26:09 volumio volumio[1018]: ---------------------------- MPD announces state update: player Oct 18 00:26:09 volumio volumio[1018]: info: ControllerMpd::getState Oct 18 00:26:09 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Oct 18 00:26:09 volumio volumio[1018]: info: ------------------------------ 29ms Oct 18 00:26:09 volumio volumio[1018]: info: sendMpdCommand status took 24 milliseconds Oct 18 00:26:09 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 19 milliseconds Oct 18 00:26:09 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 18 00:26:09 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 17 milliseconds Oct 18 00:26:09 volumio volumio[1018]: info: ------------------------------ 15ms Oct 18 00:26:09 volumio volumio[1018]: info: sendMpdCommand status took 13 milliseconds Oct 18 00:26:09 volumio volumio[1018]: info: ------------------------------ 12ms Oct 18 00:26:09 volumio volumio[1018]: info: sendMpdCommand status took 9 milliseconds Oct 18 00:26:09 volumio volumio[1018]: verbose: ControllerMpd::parseState Oct 18 00:26:09 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 18 00:26:09 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Oct 18 00:26:09 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Oct 18 00:26:09 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Oct 18 00:26:09 volumio volumio[1018]: verbose: ControllerMpd::parseState Oct 18 00:26:09 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 18 00:26:09 volumio volumio[1018]: verbose: ControllerMpd::parseState Oct 18 00:26:09 volumio volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 18 00:26:09 volumio volumio[1018]: info: ControllerMpd::pushState Oct 18 00:26:09 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Oct 18 00:26:09 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 00:26:09 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Noise Boyz - A Sunday Session - Slide da Bass Mix","artist":null,"album":null,"uri":"https://s35.derstream.net/lounge.mp3","trackType":"mp3"} Oct 18 00:26:09 volumio volumio[1018]: verbose: CURRENT POSITION 0 Oct 18 00:26:09 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Oct 18 00:26:09 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus stop Oct 18 00:26:09 volumio volumio[1018]: info: ControllerMpd::pushState Oct 18 00:26:09 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Oct 18 00:26:09 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 00:26:09 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":651,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Noise Boyz - A Sunday Session - Slide da Bass Mix","artist":null,"album":null,"uri":"https://s35.derstream.net/lounge.mp3","trackType":"mp3"} Oct 18 00:26:09 volumio volumio[1018]: verbose: CURRENT POSITION 0 Oct 18 00:26:09 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Oct 18 00:26:09 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Oct 18 00:26:09 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Oct 18 00:26:09 volumio volumio[1018]: info: CoreStateMachine::pushState Oct 18 00:26:09 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 00:26:09 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 18 00:26:09 volumio volumio[1018]: info: CoreStateMachine::pushState Oct 18 00:26:09 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 18 00:26:10 volumio volumio[1018]: info: ControllerMpd::pushState Oct 18 00:26:10 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Oct 18 00:26:10 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 00:26:10 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":651,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Noise Boyz - A Sunday Session - Slide da Bass Mix","artist":null,"album":null,"uri":"https://s35.derstream.net/lounge.mp3","trackType":"mp3"} Oct 18 00:26:10 volumio volumio[1018]: verbose: CURRENT POSITION 0 Oct 18 00:26:10 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Oct 18 00:26:10 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Oct 18 00:26:10 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Oct 18 00:26:10 volumio volumio[1018]: info: CoreStateMachine::pushState Oct 18 00:26:10 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 18 00:26:10 volumio volumio[1018]: info: CoreStateMachine::pushState Oct 18 00:26:10 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 18 00:26:10 volumio volumio[1018]: info: ------------------------------ 124ms Oct 18 00:26:10 volumio volumio[1018]: info: ------------------------------ 145ms Oct 18 00:26:10 volumio volumio[1018]: info: ------------------------------ 142ms Oct 18 00:26:10 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 87 milliseconds Oct 18 00:26:10 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 83 milliseconds Oct 18 00:26:10 volumio volumio[1018]: info: sendMpdCommand playlistinfo took 83 milliseconds Oct 18 00:26:10 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Oct 18 00:26:10 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Oct 18 00:26:10 volumio volumio[1018]: verbose: ControllerMpd::parseTrackInfo Oct 18 00:26:10 volumio volumio[1018]: info: ControllerMpd::pushState Oct 18 00:26:10 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Oct 18 00:26:10 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 00:26:10 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1154,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Noise Boyz - A Sunday Session - Slide da Bass Mix","artist":null,"album":null,"uri":"https://s35.derstream.net/lounge.mp3","trackType":"mp3"} Oct 18 00:26:10 volumio volumio[1018]: verbose: CURRENT POSITION 0 Oct 18 00:26:10 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Oct 18 00:26:10 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Oct 18 00:26:10 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Oct 18 00:26:10 volumio volumio[1018]: info: CoreStateMachine::pushState Oct 18 00:26:10 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 18 00:26:10 volumio volumio[1018]: info: CoreStateMachine::pushState Oct 18 00:26:10 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 18 00:26:10 volumio volumio[1018]: info: ControllerMpd::pushState Oct 18 00:26:10 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Oct 18 00:26:10 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 00:26:10 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1154,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Noise Boyz - A Sunday Session - Slide da Bass Mix","artist":null,"album":null,"uri":"https://s35.derstream.net/lounge.mp3","trackType":"mp3"} Oct 18 00:26:10 volumio volumio[1018]: verbose: CURRENT POSITION 0 Oct 18 00:26:10 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Oct 18 00:26:10 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Oct 18 00:26:10 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Oct 18 00:26:10 volumio volumio[1018]: info: CoreStateMachine::pushState Oct 18 00:26:10 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 18 00:26:10 volumio volumio[1018]: info: CoreStateMachine::pushState Oct 18 00:26:10 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 18 00:26:10 volumio volumio[1018]: info: ControllerMpd::pushState Oct 18 00:26:10 volumio volumio[1018]: info: CoreCommandRouter::servicePushState Oct 18 00:26:10 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 00:26:10 volumio volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1154,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Noise Boyz - A Sunday Session - Slide da Bass Mix","artist":null,"album":null,"uri":"https://s35.derstream.net/lounge.mp3","trackType":"mp3"} Oct 18 00:26:10 volumio volumio[1018]: verbose: CURRENT POSITION 0 Oct 18 00:26:10 volumio volumio[1018]: info: CoreStateMachine::syncState stateService play Oct 18 00:26:10 volumio volumio[1018]: info: CoreStateMachine::syncState currentStatus play Oct 18 00:26:10 volumio volumio[1018]: info: Received an update from plugin. extracting info from payload Oct 18 00:26:10 volumio volumio[1018]: info: CoreStateMachine::pushState Oct 18 00:26:10 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 18 00:26:10 volumio volumio[1018]: info: CoreStateMachine::pushState Oct 18 00:26:10 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 18 00:26:10 volumio volumio[1018]: info: ------------------------------ 206ms Oct 18 00:26:10 volumio volumio[1018]: info: ------------------------------ 189ms Oct 18 00:26:10 volumio volumio[1018]: info: ------------------------------ 186ms Oct 18 00:26:12 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 18 00:26:13 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 18 00:26:13 volumio volumio[1018]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 18 00:26:13 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Oct 18 00:26:14 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 18 00:26:14 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:26:14 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 18 00:26:14 volumio volumio-remote-updater[712]: No test mode Oct 18 00:26:14 volumio volumio-remote-updater[712]: No alpha test mode Oct 18 00:26:14 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 18 00:26:14 volumio volumio[1018]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 18 00:26:14 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 18 00:26:18 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:26:18 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:26:18 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 18 00:26:18 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 18 00:26:18 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 18 00:26:18 volumio volumio[1018]: info: CoreCommandRouter::volumioGetBrowseSources Oct 18 00:26:18 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 18 00:26:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 18 00:26:19 volumio volumio[1018]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 18 00:26:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 18 00:26:19 volumio volumio[1018]: info: Received Get System Version Oct 18 00:26:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 18 00:26:19 volumio volumio[1018]: info: Received Get System Info Oct 18 00:26:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 18 00:26:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 18 00:26:19 volumio volumio[1018]: info: Discovery: Getting this device information Oct 18 00:26:19 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Oct 18 00:26:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 18 00:26:20 volumio volumio[1018]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 18 00:26:20 volumio volumio[1018]: info: Completed starting MyVolumio Plugin Oct 18 00:26:20 volumio volumio[1018]: [Metrics] CommandRouter: 47s 774.64ms Oct 18 00:26:20 volumio volumio[1018]: info: CoreCommandRouter::volumiosetStartupVolume Oct 18 00:26:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 00:26:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 18 00:26:20 volumio volumio[1018]: info: CoreCommandRouter::Close All Modals sent Oct 18 00:26:20 volumio volumio[1018]: info: CoreCommandRouter::Close All Modals sent Oct 18 00:26:20 volumio volumio[1018]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Oct 18 00:26:20 volumio volumio[1018]: aplay: main:828: audio open error: Device or resource busy Oct 18 00:26:21 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 18 00:26:21 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 18 00:26:21 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 18 00:26:22 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 18 00:26:22 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 18 00:26:27 volumio volumio[1018]: info: BOOT COMPLETED Oct 18 00:26:31 volumio volumio[1018]: info: Starting Uninstall of plugin system_controller - ampswitch Oct 18 00:26:31 volumio volumio[1018]: info: Uninstalling plugin ampswitch Oct 18 00:26:31 volumio volumio[1018]: info: [ASDebug] Port: 24 Oct 18 00:26:31 volumio volumio[1018]: info: [ASDebug] Inverted: false Oct 18 00:26:31 volumio volumio[1018]: info: [ASDebug] Delay: 720 Oct 18 00:26:31 volumio volumio[1018]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 18 00:26:31 volumio volumio[1018]: TypeError: Cannot read property 'unexport' of undefined Oct 18 00:26:31 volumio volumio[1018]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Oct 18 00:26:31 volumio volumio[1018]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Oct 18 00:26:31 volumio volumio[1018]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Oct 18 00:26:31 volumio volumio[1018]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Oct 18 00:26:31 volumio volumio[1018]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1484:22) Oct 18 00:26:31 volumio volumio[1018]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1260:45) Oct 18 00:26:31 volumio volumio[1018]: at Socket.emit (events.js:400:28) Oct 18 00:26:31 volumio volumio[1018]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 18 00:26:31 volumio volumio[1018]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Oct 18 00:26:31 volumio volumio[1018]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 18 00:26:32 volumio sudo[1619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-18 00:25 Oct 18 00:26:32 volumio sudo[1619]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 29 Jul 2025 12:40:52 PM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="orangepipc" VOLUMIO_DEVICENAME="Orange Pi PC" VOLUMIO_HASH="053d26d2b7f23be800db334519309821"