-- Logs begin at Fri 2025-02-28 17:16:08 CST, end at Fri 2025-02-28 17:17:32 CST. -- Feb 28 17:16:08 volumio-43-dsi kernel: Booting Linux on physical CPU 0x0000000000 [0x414fd0b1] Feb 28 17:16:08 volumio-43-dsi kernel: Linux version 6.6.62-v8+ (dom@buildbot) (aarch64-linux-gnu-gcc (Ubuntu 11.4.0-1ubuntu1~22.04) 11.4.0, GNU ld (GNU Binutils for Ubuntu) 2.38) #1816 SMP PREEMPT Mon Nov 18 15:35:52 GMT 2024 Feb 28 17:16:08 volumio-43-dsi kernel: KASLR enabled Feb 28 17:16:08 volumio-43-dsi kernel: random: crng init done Feb 28 17:16:08 volumio-43-dsi kernel: Machine model: Raspberry Pi 5 Model B Rev 1.0 Feb 28 17:16:08 volumio-43-dsi kernel: efi: UEFI not found. Feb 28 17:16:08 volumio-43-dsi kernel: Reserved memory: created CMA memory pool at 0x0000000001c00000, size 64 MiB Feb 28 17:16:08 volumio-43-dsi kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Feb 28 17:16:08 volumio-43-dsi kernel: OF: reserved mem: 0x0000000001c00000..0x0000000005bfffff (65536 KiB) map reusable linux,cma Feb 28 17:16:08 volumio-43-dsi kernel: OF: reserved mem: 0x0000000000000000..0x000000000007ffff (512 KiB) nomap non-reusable atf@0 Feb 28 17:16:08 volumio-43-dsi kernel: OF: reserved mem: 0x000000003fd16240..0x000000003fd16284 (0 KiB) nomap non-reusable nvram@0 Feb 28 17:16:08 volumio-43-dsi kernel: NUMA: No NUMA configuration found Feb 28 17:16:08 volumio-43-dsi kernel: NUMA: Faking a node at [mem 0x0000000000000000-0x00000001ffffffff] Feb 28 17:16:08 volumio-43-dsi kernel: NUMA: NODE_DATA [mem 0x1fef953c0-0x1fef97fff] Feb 28 17:16:08 volumio-43-dsi kernel: Zone ranges: Feb 28 17:16:08 volumio-43-dsi kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Feb 28 17:16:08 volumio-43-dsi kernel: DMA32 empty Feb 28 17:16:08 volumio-43-dsi kernel: Normal [mem 0x0000000100000000-0x00000001ffffffff] Feb 28 17:16:08 volumio-43-dsi kernel: Movable zone start for each node Feb 28 17:16:08 volumio-43-dsi kernel: Early memory node ranges Feb 28 17:16:08 volumio-43-dsi kernel: node 0: [mem 0x0000000000000000-0x000000000007ffff] Feb 28 17:16:08 volumio-43-dsi kernel: node 0: [mem 0x0000000000080000-0x000000003fbfffff] Feb 28 17:16:08 volumio-43-dsi kernel: node 0: [mem 0x0000000040000000-0x00000001ffffffff] Feb 28 17:16:08 volumio-43-dsi kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001ffffffff] Feb 28 17:16:08 volumio-43-dsi kernel: On node 0, zone DMA: 1024 pages in unavailable ranges Feb 28 17:16:08 volumio-43-dsi kernel: psci: probing for conduit method from DT. Feb 28 17:16:08 volumio-43-dsi kernel: psci: PSCIv1.1 detected in firmware. Feb 28 17:16:08 volumio-43-dsi kernel: psci: Using standard PSCI v0.2 function IDs Feb 28 17:16:08 volumio-43-dsi kernel: psci: MIGRATE_INFO_TYPE not supported. Feb 28 17:16:08 volumio-43-dsi kernel: psci: SMC Calling Convention v1.2 Feb 28 17:16:08 volumio-43-dsi kernel: percpu: Embedded 30 pages/cpu s85928 r8192 d28760 u122880 Feb 28 17:16:08 volumio-43-dsi kernel: pcpu-alloc: s85928 r8192 d28760 u122880 alloc=30*4096 Feb 28 17:16:08 volumio-43-dsi kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Feb 28 17:16:08 volumio-43-dsi kernel: Detected PIPT I-cache on CPU0 Feb 28 17:16:08 volumio-43-dsi kernel: CPU features: detected: Virtualization Host Extensions Feb 28 17:16:08 volumio-43-dsi kernel: CPU features: detected: Hardware dirty bit management Feb 28 17:16:08 volumio-43-dsi kernel: CPU features: detected: Spectre-v4 Feb 28 17:16:08 volumio-43-dsi kernel: CPU features: detected: Spectre-BHB Feb 28 17:16:08 volumio-43-dsi kernel: CPU features: kernel page table isolation forced ON by KASLR Feb 28 17:16:08 volumio-43-dsi kernel: CPU features: detected: Kernel page table isolation (KPTI) Feb 28 17:16:08 volumio-43-dsi kernel: CPU features: detected: SSBS not fully self-synchronizing Feb 28 17:16:08 volumio-43-dsi kernel: alternatives: applying boot alternatives Feb 28 17:16:08 volumio-43-dsi kernel: Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe cgroup_disable=memory numa_policy=interleave smsc95xx.macaddr=D8:3A:DD:D2:CE:EA vc_mem.mem_base=0x3fc00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyAMA10,115200 console=tty1 imgpart=UUID=fb80cb44-b4d3-4b36-be35-613cba0a09fb imgfile=/volumio_current.sqsh bootpart=UUID=51B1-040D datapart=UUID=2899bccc-8620-4c7a-b56a-43fd2f7a836a uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Feb 28 17:16:08 volumio-43-dsi kernel: cgroup: Disabling memory control group subsystem Feb 28 17:16:08 volumio-43-dsi kernel: mempolicy: NUMA default policy overridden to 'interleave:0' Feb 28 17:16:08 volumio-43-dsi kernel: PCIe ASPM is disabled Feb 28 17:16:08 volumio-43-dsi kernel: Unknown kernel command line parameters "splash nodebug imgpart=UUID=fb80cb44-b4d3-4b36-be35-613cba0a09fb imgfile=/volumio_current.sqsh bootpart=UUID=51B1-040D datapart=UUID=2899bccc-8620-4c7a-b56a-43fd2f7a836a uuidconfig=cmdline.txt bootdelay=7 use_kmsg=no", will be passed to user space. Feb 28 17:16:08 volumio-43-dsi kernel: Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear) Feb 28 17:16:08 volumio-43-dsi kernel: Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Feb 28 17:16:08 volumio-43-dsi kernel: Fallback order for Node 0: 0 Feb 28 17:16:08 volumio-43-dsi kernel: Built 1 zonelists, mobility grouping on. Total pages: 2063360 Feb 28 17:16:08 volumio-43-dsi kernel: Policy zone: Normal Feb 28 17:16:08 volumio-43-dsi kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Feb 28 17:16:08 volumio-43-dsi kernel: software IO TLB: area num 4. Feb 28 17:16:08 volumio-43-dsi kernel: software IO TLB: mapped [mem 0x00000000fbfff000-0x00000000fffff000] (64MB) Feb 28 17:16:08 volumio-43-dsi kernel: Memory: 8037284K/8384512K available (13440K kernel code, 2208K rwdata, 4284K rodata, 4864K init, 1086K bss, 281692K reserved, 65536K cma-reserved) Feb 28 17:16:08 volumio-43-dsi kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Feb 28 17:16:08 volumio-43-dsi kernel: ftrace: allocating 43555 entries in 171 pages Feb 28 17:16:08 volumio-43-dsi kernel: ftrace: allocated 171 pages with 5 groups Feb 28 17:16:08 volumio-43-dsi kernel: trace event string verifier disabled Feb 28 17:16:08 volumio-43-dsi kernel: rcu: Preemptible hierarchical RCU implementation. Feb 28 17:16:08 volumio-43-dsi kernel: rcu: RCU event tracing is enabled. Feb 28 17:16:08 volumio-43-dsi kernel: rcu: RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4. Feb 28 17:16:08 volumio-43-dsi kernel: Trampoline variant of Tasks RCU enabled. Feb 28 17:16:08 volumio-43-dsi kernel: Rude variant of Tasks RCU enabled. Feb 28 17:16:08 volumio-43-dsi kernel: Tracing variant of Tasks RCU enabled. Feb 28 17:16:08 volumio-43-dsi kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Feb 28 17:16:08 volumio-43-dsi kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Feb 28 17:16:08 volumio-43-dsi kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Feb 28 17:16:08 volumio-43-dsi kernel: Root IRQ handler: gic_handle_irq Feb 28 17:16:08 volumio-43-dsi kernel: GIC: Using split EOI/Deactivate mode Feb 28 17:16:08 volumio-43-dsi kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Feb 28 17:16:08 volumio-43-dsi kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Feb 28 17:16:08 volumio-43-dsi kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Feb 28 17:16:08 volumio-43-dsi kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Feb 28 17:16:08 volumio-43-dsi kernel: Console: colour dummy device 80x25 Feb 28 17:16:08 volumio-43-dsi kernel: printk: console [tty1] enabled Feb 28 17:16:08 volumio-43-dsi kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000) Feb 28 17:16:08 volumio-43-dsi kernel: pid_max: default: 32768 minimum: 301 Feb 28 17:16:08 volumio-43-dsi kernel: LSM: initializing lsm=capability,integrity Feb 28 17:16:08 volumio-43-dsi kernel: Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) Feb 28 17:16:08 volumio-43-dsi kernel: Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) Feb 28 17:16:08 volumio-43-dsi kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Feb 28 17:16:08 volumio-43-dsi kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Feb 28 17:16:08 volumio-43-dsi kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Feb 28 17:16:08 volumio-43-dsi kernel: rcu: Hierarchical SRCU implementation. Feb 28 17:16:08 volumio-43-dsi kernel: rcu: Max phase no-delay instances is 1000. Feb 28 17:16:08 volumio-43-dsi kernel: EFI services will not be available. Feb 28 17:16:08 volumio-43-dsi kernel: smp: Bringing up secondary CPUs ... Feb 28 17:16:08 volumio-43-dsi kernel: Detected PIPT I-cache on CPU1 Feb 28 17:16:08 volumio-43-dsi kernel: CPU1: Booted secondary processor 0x0000000100 [0x414fd0b1] Feb 28 17:16:08 volumio-43-dsi kernel: Detected PIPT I-cache on CPU2 Feb 28 17:16:08 volumio-43-dsi kernel: CPU2: Booted secondary processor 0x0000000200 [0x414fd0b1] Feb 28 17:16:08 volumio-43-dsi kernel: Detected PIPT I-cache on CPU3 Feb 28 17:16:08 volumio-43-dsi kernel: CPU3: Booted secondary processor 0x0000000300 [0x414fd0b1] Feb 28 17:16:08 volumio-43-dsi kernel: smp: Brought up 1 node, 4 CPUs Feb 28 17:16:08 volumio-43-dsi kernel: SMP: Total of 4 processors activated. Feb 28 17:16:08 volumio-43-dsi kernel: CPU features: detected: 32-bit EL0 Support Feb 28 17:16:08 volumio-43-dsi kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Feb 28 17:16:08 volumio-43-dsi kernel: CPU features: detected: Common not Private translations Feb 28 17:16:08 volumio-43-dsi kernel: CPU features: detected: CRC32 instructions Feb 28 17:16:08 volumio-43-dsi kernel: CPU features: detected: RCpc load-acquire (LDAPR) Feb 28 17:16:08 volumio-43-dsi kernel: CPU features: detected: LSE atomic instructions Feb 28 17:16:08 volumio-43-dsi kernel: CPU features: detected: Privileged Access Never Feb 28 17:16:08 volumio-43-dsi kernel: CPU features: detected: RAS Extension Support Feb 28 17:16:08 volumio-43-dsi kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Feb 28 17:16:08 volumio-43-dsi kernel: CPU: All CPU(s) started at EL2 Feb 28 17:16:08 volumio-43-dsi kernel: alternatives: applying system-wide alternatives Feb 28 17:16:08 volumio-43-dsi kernel: devtmpfs: initialized Feb 28 17:16:08 volumio-43-dsi kernel: Enabled cp15_barrier support Feb 28 17:16:08 volumio-43-dsi kernel: Enabled setend support Feb 28 17:16:08 volumio-43-dsi kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Feb 28 17:16:08 volumio-43-dsi kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Feb 28 17:16:08 volumio-43-dsi kernel: pinctrl core: initialized pinctrl subsystem Feb 28 17:16:08 volumio-43-dsi kernel: DMI not present or invalid. Feb 28 17:16:08 volumio-43-dsi kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Feb 28 17:16:08 volumio-43-dsi kernel: DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations Feb 28 17:16:08 volumio-43-dsi kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Feb 28 17:16:08 volumio-43-dsi kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Feb 28 17:16:08 volumio-43-dsi kernel: audit: initializing netlink subsys (disabled) Feb 28 17:16:08 volumio-43-dsi kernel: audit: type=2000 audit(0.004:1): state=initialized audit_enabled=0 res=1 Feb 28 17:16:08 volumio-43-dsi kernel: thermal_sys: Registered thermal governor 'step_wise' Feb 28 17:16:08 volumio-43-dsi kernel: cpuidle: using governor menu Feb 28 17:16:08 volumio-43-dsi kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Feb 28 17:16:08 volumio-43-dsi kernel: ASID allocator initialised with 32768 entries Feb 28 17:16:08 volumio-43-dsi kernel: Serial: AMBA PL011 UART driver Feb 28 17:16:08 volumio-43-dsi kernel: bcm2835-mbox 107c013880.mailbox: mailbox enabled Feb 28 17:16:08 volumio-43-dsi kernel: 107d001000.serial: ttyAMA10 at MMIO 0x107d001000 (irq = 15, base_baud = 0) is a PL011 rev2 Feb 28 17:16:08 volumio-43-dsi kernel: printk: console [ttyAMA10] enabled Feb 28 17:16:08 volumio-43-dsi kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2024-11-12T16:10:44, variant start_cd Feb 28 17:16:08 volumio-43-dsi kernel: raspberrypi-firmware soc:firmware: Firmware hash is 4b01994600000000000000000000000000000000 Feb 28 17:16:08 volumio-43-dsi kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Feb 28 17:16:08 volumio-43-dsi kernel: Modules: 0 pages in range for non-PLT usage Feb 28 17:16:08 volumio-43-dsi kernel: Modules: 517776 pages in range for PLT usage Feb 28 17:16:08 volumio-43-dsi kernel: bcm2835-dma 1000010000.dma: DMA legacy API manager, dmachans=0x1 Feb 28 17:16:08 volumio-43-dsi kernel: iommu: Default domain type: Translated Feb 28 17:16:08 volumio-43-dsi kernel: iommu: DMA domain TLB invalidation policy: strict mode Feb 28 17:16:08 volumio-43-dsi kernel: SCSI subsystem initialized Feb 28 17:16:08 volumio-43-dsi kernel: usbcore: registered new interface driver usbfs Feb 28 17:16:08 volumio-43-dsi kernel: usbcore: registered new interface driver hub Feb 28 17:16:08 volumio-43-dsi kernel: usbcore: registered new device driver usb Feb 28 17:16:08 volumio-43-dsi kernel: pps_core: LinuxPPS API ver. 1 registered Feb 28 17:16:08 volumio-43-dsi kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Feb 28 17:16:08 volumio-43-dsi kernel: PTP clock support registered Feb 28 17:16:08 volumio-43-dsi kernel: vgaarb: loaded Feb 28 17:16:08 volumio-43-dsi kernel: clocksource: Switched to clocksource arch_sys_counter Feb 28 17:16:08 volumio-43-dsi kernel: VFS: Disk quotas dquot_6.6.0 Feb 28 17:16:08 volumio-43-dsi kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Feb 28 17:16:08 volumio-43-dsi kernel: FS-Cache: Loaded Feb 28 17:16:08 volumio-43-dsi kernel: CacheFiles: Loaded Feb 28 17:16:08 volumio-43-dsi kernel: NET: Registered PF_INET protocol family Feb 28 17:16:08 volumio-43-dsi kernel: IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear) Feb 28 17:16:08 volumio-43-dsi kernel: tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear) Feb 28 17:16:08 volumio-43-dsi kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Feb 28 17:16:08 volumio-43-dsi kernel: TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear) Feb 28 17:16:08 volumio-43-dsi kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Feb 28 17:16:08 volumio-43-dsi kernel: TCP: Hash tables configured (established 65536 bind 65536) Feb 28 17:16:08 volumio-43-dsi kernel: MPTCP token hash table entries: 8192 (order: 5, 196608 bytes, linear) Feb 28 17:16:08 volumio-43-dsi kernel: UDP hash table entries: 4096 (order: 5, 131072 bytes, linear) Feb 28 17:16:08 volumio-43-dsi kernel: UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear) Feb 28 17:16:08 volumio-43-dsi kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Feb 28 17:16:08 volumio-43-dsi kernel: RPC: Registered named UNIX socket transport module. Feb 28 17:16:08 volumio-43-dsi kernel: RPC: Registered udp transport module. Feb 28 17:16:08 volumio-43-dsi kernel: RPC: Registered tcp transport module. Feb 28 17:16:08 volumio-43-dsi kernel: RPC: Registered tcp-with-tls transport module. Feb 28 17:16:08 volumio-43-dsi kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Feb 28 17:16:08 volumio-43-dsi kernel: PCI: CLS 0 bytes, default 64 Feb 28 17:16:08 volumio-43-dsi kernel: Trying to unpack rootfs image as initramfs... Feb 28 17:16:08 volumio-43-dsi kernel: kvm [1]: IPA Size Limit: 40 bits Feb 28 17:16:08 volumio-43-dsi kernel: kvm [1]: vgic interrupt IRQ9 Feb 28 17:16:08 volumio-43-dsi kernel: kvm [1]: VHE mode initialized successfully Feb 28 17:16:08 volumio-43-dsi kernel: Initialise system trusted keyrings Feb 28 17:16:08 volumio-43-dsi kernel: workingset: timestamp_bits=42 max_order=21 bucket_order=0 Feb 28 17:16:08 volumio-43-dsi kernel: zbud: loaded Feb 28 17:16:08 volumio-43-dsi kernel: NFS: Registering the id_resolver key type Feb 28 17:16:08 volumio-43-dsi kernel: Key type id_resolver registered Feb 28 17:16:08 volumio-43-dsi kernel: Key type id_legacy registered Feb 28 17:16:08 volumio-43-dsi kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Feb 28 17:16:08 volumio-43-dsi kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Feb 28 17:16:08 volumio-43-dsi kernel: Key type asymmetric registered Feb 28 17:16:08 volumio-43-dsi kernel: Asymmetric key parser 'x509' registered Feb 28 17:16:08 volumio-43-dsi kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Feb 28 17:16:08 volumio-43-dsi kernel: io scheduler mq-deadline registered Feb 28 17:16:08 volumio-43-dsi kernel: io scheduler kyber registered Feb 28 17:16:08 volumio-43-dsi kernel: io scheduler bfq registered Feb 28 17:16:08 volumio-43-dsi kernel: irq_brcmstb_l2: registered L2 intc (/soc/interrupt-controller@7c502000, parent irq: 26) Feb 28 17:16:08 volumio-43-dsi kernel: irq_brcmstb_l2: registered L2 intc (/soc/intc@7d503000, parent irq: 27) Feb 28 17:16:08 volumio-43-dsi kernel: irq_brcmstb_l2: registered L2 intc (/soc/intc@7d508380, parent irq: 28) Feb 28 17:16:08 volumio-43-dsi kernel: irq_brcmstb_l2: registered L2 intc (/soc/intc@7d508400, parent irq: 29) Feb 28 17:16:08 volumio-43-dsi kernel: irq_brcmstb_l2: registered L2 intc (/soc/interrupt-controller@7d510600, parent irq: 30) Feb 28 17:16:08 volumio-43-dsi kernel: irq_brcmstb_l2: registered L2 intc (/soc/intc@7d517b00, parent irq: 31) Feb 28 17:16:08 volumio-43-dsi kernel: Freeing initrd memory: 28084K Feb 28 17:16:08 volumio-43-dsi kernel: Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled Feb 28 17:16:08 volumio-43-dsi kernel: 107d50c000.serial: ttyS0 at MMIO 0x107d50c000 (irq = 33, base_baud = 6000000) is a Broadcom BCM7271 UART Feb 28 17:16:08 volumio-43-dsi kernel: serial serial0: tty port ttyS0 registered Feb 28 17:16:08 volumio-43-dsi kernel: iproc-rng200 107d208000.rng: hwrng registered Feb 28 17:16:08 volumio-43-dsi kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3fc00000 mem_size:0x40000000(1024 MiB) Feb 28 17:16:08 volumio-43-dsi kernel: bcm2712-iommu-cache 1000005b00.iommuc: bcm2712_iommu_cache_probe Feb 28 17:16:08 volumio-43-dsi kernel: brd: module loaded Feb 28 17:16:08 volumio-43-dsi kernel: loop: module loaded Feb 28 17:16:08 volumio-43-dsi kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Feb 28 17:16:08 volumio-43-dsi kernel: Loading iSCSI transport class v2.0-870. Feb 28 17:16:08 volumio-43-dsi kernel: usbcore: registered new device driver r8152-cfgselector Feb 28 17:16:08 volumio-43-dsi kernel: usbcore: registered new interface driver r8152 Feb 28 17:16:08 volumio-43-dsi kernel: usbcore: registered new interface driver lan78xx Feb 28 17:16:08 volumio-43-dsi kernel: usbcore: registered new interface driver smsc95xx Feb 28 17:16:08 volumio-43-dsi kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Feb 28 17:16:08 volumio-43-dsi kernel: dwc_otg: FIQ enabled Feb 28 17:16:08 volumio-43-dsi kernel: dwc_otg: NAK holdoff enabled Feb 28 17:16:08 volumio-43-dsi kernel: dwc_otg: FIQ split-transaction FSM enabled Feb 28 17:16:08 volumio-43-dsi kernel: Module dwc_common_port init Feb 28 17:16:08 volumio-43-dsi kernel: usbcore: registered new interface driver uas Feb 28 17:16:08 volumio-43-dsi kernel: usbcore: registered new interface driver usb-storage Feb 28 17:16:08 volumio-43-dsi kernel: mousedev: PS/2 mouse device common for all mice Feb 28 17:16:08 volumio-43-dsi kernel: rpi-rtc soc:rpi_rtc: registered as rtc0 Feb 28 17:16:08 volumio-43-dsi kernel: rpi-rtc soc:rpi_rtc: setting system clock to 2025-02-28T09:15:57 UTC (1740734157) Feb 28 17:16:08 volumio-43-dsi kernel: bcm2835-wdt bcm2835-wdt: Poweroff handler already present! Feb 28 17:16:08 volumio-43-dsi kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Feb 28 17:16:08 volumio-43-dsi kernel: sdhci: Secure Digital Host Controller Interface driver Feb 28 17:16:08 volumio-43-dsi kernel: sdhci: Copyright(c) Pierre Ossman Feb 28 17:16:08 volumio-43-dsi kernel: sdhci-pltfm: SDHCI platform and OF driver helper Feb 28 17:16:08 volumio-43-dsi kernel: ledtrig-cpu: registered to indicate activity on CPUs Feb 28 17:16:08 volumio-43-dsi kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Feb 28 17:16:08 volumio-43-dsi kernel: hid: raw HID events driver (C) Jiri Kosina Feb 28 17:16:08 volumio-43-dsi kernel: usbcore: registered new interface driver usbhid Feb 28 17:16:08 volumio-43-dsi kernel: usbhid: USB HID core driver Feb 28 17:16:08 volumio-43-dsi kernel: hw perfevents: enabled with armv8_cortex_a76 PMU driver, 7 counters available Feb 28 17:16:08 volumio-43-dsi kernel: NET: Registered PF_PACKET protocol family Feb 28 17:16:08 volumio-43-dsi kernel: Key type dns_resolver registered Feb 28 17:16:08 volumio-43-dsi kernel: registered taskstats version 1 Feb 28 17:16:08 volumio-43-dsi kernel: Loading compiled-in X.509 certificates Feb 28 17:16:08 volumio-43-dsi kernel: Key type .fscrypt registered Feb 28 17:16:08 volumio-43-dsi kernel: Key type fscrypt-provisioning registered Feb 28 17:16:08 volumio-43-dsi kernel: brcm-pcie 1000110000.pcie: host bridge /axi/pcie@110000 ranges: Feb 28 17:16:08 volumio-43-dsi kernel: brcm-pcie 1000110000.pcie: No bus range found for /axi/pcie@110000, using [bus 00-ff] Feb 28 17:16:08 volumio-43-dsi kernel: brcm-pcie 1000110000.pcie: MEM 0x1b80000000..0x1bffffffff -> 0x0080000000 Feb 28 17:16:08 volumio-43-dsi kernel: brcm-pcie 1000110000.pcie: MEM 0x1800000000..0x1b7fffffff -> 0x0400000000 Feb 28 17:16:08 volumio-43-dsi kernel: brcm-pcie 1000110000.pcie: IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000 Feb 28 17:16:08 volumio-43-dsi kernel: brcm-pcie 1000110000.pcie: Forcing gen 2 Feb 28 17:16:08 volumio-43-dsi kernel: brcm-pcie 1000110000.pcie: PCI host bridge to bus 0000:00 Feb 28 17:16:08 volumio-43-dsi kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Feb 28 17:16:08 volumio-43-dsi kernel: pci_bus 0000:00: root bus resource [mem 0x1b80000000-0x1bffffffff] (bus address [0x80000000-0xffffffff]) Feb 28 17:16:08 volumio-43-dsi kernel: pci_bus 0000:00: root bus resource [mem 0x1800000000-0x1b7fffffff pref] (bus address [0x400000000-0x77fffffff]) Feb 28 17:16:08 volumio-43-dsi kernel: pci 0000:00:00.0: [14e4:2712] type 01 class 0x060400 Feb 28 17:16:08 volumio-43-dsi kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Feb 28 17:16:08 volumio-43-dsi kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Feb 28 17:16:08 volumio-43-dsi kernel: brcm-pcie 1000110000.pcie: link down Feb 28 17:16:08 volumio-43-dsi kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Feb 28 17:16:08 volumio-43-dsi kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Feb 28 17:16:08 volumio-43-dsi kernel: pci 0000:00:00.0: Max Payload Size set to 512/ 512 (was 128), Max Read Rq 512 Feb 28 17:16:08 volumio-43-dsi kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 38 Feb 28 17:16:08 volumio-43-dsi kernel: pcieport 0000:00:00.0: AER: enabled with IRQ 38 Feb 28 17:16:08 volumio-43-dsi kernel: pci_bus 0000:01: busn_res: [bus 01] is released Feb 28 17:16:08 volumio-43-dsi kernel: pci_bus 0000:00: busn_res: [bus 00-ff] is released Feb 28 17:16:08 volumio-43-dsi kernel: brcm-pcie 1000120000.pcie: host bridge /axi/pcie@120000 ranges: Feb 28 17:16:08 volumio-43-dsi kernel: brcm-pcie 1000120000.pcie: No bus range found for /axi/pcie@120000, using [bus 00-ff] Feb 28 17:16:08 volumio-43-dsi kernel: brcm-pcie 1000120000.pcie: MEM 0x1f00000000..0x1ffffffffb -> 0x0000000000 Feb 28 17:16:08 volumio-43-dsi kernel: brcm-pcie 1000120000.pcie: MEM 0x1c00000000..0x1effffffff -> 0x0400000000 Feb 28 17:16:08 volumio-43-dsi kernel: brcm-pcie 1000120000.pcie: IB MEM 0x1f00000000..0x1f003fffff -> 0x0000000000 Feb 28 17:16:08 volumio-43-dsi kernel: brcm-pcie 1000120000.pcie: IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000 Feb 28 17:16:08 volumio-43-dsi kernel: brcm-pcie 1000120000.pcie: Forcing gen 2 Feb 28 17:16:08 volumio-43-dsi kernel: brcm-pcie 1000120000.pcie: PCI host bridge to bus 0000:00 Feb 28 17:16:08 volumio-43-dsi kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Feb 28 17:16:08 volumio-43-dsi kernel: pci_bus 0000:00: root bus resource [mem 0x1f00000000-0x1ffffffffb] (bus address [0x00000000-0xfffffffb]) Feb 28 17:16:08 volumio-43-dsi kernel: pci_bus 0000:00: root bus resource [mem 0x1c00000000-0x1effffffff pref] (bus address [0x400000000-0x6ffffffff]) Feb 28 17:16:08 volumio-43-dsi kernel: pci 0000:00:00.0: [14e4:2712] type 01 class 0x060400 Feb 28 17:16:08 volumio-43-dsi kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Feb 28 17:16:08 volumio-43-dsi kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Feb 28 17:16:08 volumio-43-dsi kernel: brcm-pcie 1000120000.pcie: link up, 5.0 GT/s PCIe x4 (!SSC) Feb 28 17:16:08 volumio-43-dsi kernel: pci 0000:01:00.0: [1de4:0001] type 00 class 0x020000 Feb 28 17:16:08 volumio-43-dsi kernel: pci 0000:01:00.0: reg 0x10: [mem 0xffffc000-0xffffffff] Feb 28 17:16:08 volumio-43-dsi kernel: pci 0000:01:00.0: reg 0x14: [mem 0xffc00000-0xffffffff] Feb 28 17:16:08 volumio-43-dsi kernel: pci 0000:01:00.0: reg 0x18: [mem 0xffff0000-0xffffffff] Feb 28 17:16:08 volumio-43-dsi kernel: pci 0000:01:00.0: supports D1 Feb 28 17:16:08 volumio-43-dsi kernel: pci 0000:01:00.0: PME# supported from D0 D1 D3hot D3cold Feb 28 17:16:08 volumio-43-dsi kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Feb 28 17:16:08 volumio-43-dsi kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x1f00000000-0x1f005fffff] Feb 28 17:16:08 volumio-43-dsi kernel: pci 0000:01:00.0: BAR 1: assigned [mem 0x1f00000000-0x1f003fffff] Feb 28 17:16:08 volumio-43-dsi kernel: pci 0000:01:00.0: BAR 2: assigned [mem 0x1f00400000-0x1f0040ffff] Feb 28 17:16:08 volumio-43-dsi kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x1f00410000-0x1f00413fff] Feb 28 17:16:08 volumio-43-dsi kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Feb 28 17:16:08 volumio-43-dsi kernel: pci 0000:00:00.0: bridge window [mem 0x1f00000000-0x1f005fffff] Feb 28 17:16:08 volumio-43-dsi kernel: pci 0000:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 Feb 28 17:16:08 volumio-43-dsi kernel: pci 0000:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512 Feb 28 17:16:08 volumio-43-dsi kernel: pcieport 0000:00:00.0: enabling device (0000 -> 0002) Feb 28 17:16:08 volumio-43-dsi kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 39 Feb 28 17:16:08 volumio-43-dsi kernel: pcieport 0000:00:00.0: AER: enabled with IRQ 39 Feb 28 17:16:08 volumio-43-dsi kernel: rp1 0000:01:00.0: bar0 len 0x4000, start 0x1f00410000, end 0x1f00413fff, flags, 0x40200 Feb 28 17:16:08 volumio-43-dsi kernel: rp1 0000:01:00.0: bar1 len 0x400000, start 0x1f00000000, end 0x1f003fffff, flags, 0x40200 Feb 28 17:16:08 volumio-43-dsi kernel: rp1 0000:01:00.0: enabling device (0000 -> 0002) Feb 28 17:16:08 volumio-43-dsi kernel: rp1 0000:01:00.0: chip_id 0x20001927 Feb 28 17:16:08 volumio-43-dsi kernel: genirq: irq_chip rp1_irq_chip did not update eff. affinity mask of irq 101 Feb 28 17:16:08 volumio-43-dsi kernel: macb 1f00100000.ethernet eth0: Cadence GEM rev 0x00070109 at 0x1f00100000 irq 107 (d8:3a:dd:d2:ce:ea) Feb 28 17:16:08 volumio-43-dsi kernel: dw_axi_dmac_platform 1f00188000.dma: DesignWare AXI DMA Controller, 8 channels Feb 28 17:16:08 volumio-43-dsi kernel: xhci-hcd xhci-hcd.0: xHCI Host Controller Feb 28 17:16:08 volumio-43-dsi kernel: xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 1 Feb 28 17:16:08 volumio-43-dsi kernel: xhci-hcd xhci-hcd.0: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000008000000810 Feb 28 17:16:08 volumio-43-dsi kernel: xhci-hcd xhci-hcd.0: irq 132, io mem 0x1f00200000 Feb 28 17:16:08 volumio-43-dsi kernel: xhci-hcd xhci-hcd.0: xHCI Host Controller Feb 28 17:16:08 volumio-43-dsi kernel: xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 2 Feb 28 17:16:08 volumio-43-dsi kernel: xhci-hcd xhci-hcd.0: Host supports USB 3.0 SuperSpeed Feb 28 17:16:08 volumio-43-dsi kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Feb 28 17:16:08 volumio-43-dsi kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 28 17:16:08 volumio-43-dsi kernel: usb usb1: Product: xHCI Host Controller Feb 28 17:16:08 volumio-43-dsi kernel: usb usb1: Manufacturer: Linux 6.6.62-v8+ xhci-hcd Feb 28 17:16:08 volumio-43-dsi kernel: usb usb1: SerialNumber: xhci-hcd.0 Feb 28 17:16:08 volumio-43-dsi kernel: hub 1-0:1.0: USB hub found Feb 28 17:16:08 volumio-43-dsi kernel: hub 1-0:1.0: 2 ports detected Feb 28 17:16:08 volumio-43-dsi kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06 Feb 28 17:16:08 volumio-43-dsi kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 28 17:16:08 volumio-43-dsi kernel: usb usb2: Product: xHCI Host Controller Feb 28 17:16:08 volumio-43-dsi kernel: usb usb2: Manufacturer: Linux 6.6.62-v8+ xhci-hcd Feb 28 17:16:08 volumio-43-dsi kernel: usb usb2: SerialNumber: xhci-hcd.0 Feb 28 17:16:08 volumio-43-dsi kernel: hub 2-0:1.0: USB hub found Feb 28 17:16:08 volumio-43-dsi kernel: hub 2-0:1.0: 1 port detected Feb 28 17:16:08 volumio-43-dsi kernel: xhci-hcd xhci-hcd.1: xHCI Host Controller Feb 28 17:16:08 volumio-43-dsi kernel: xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 3 Feb 28 17:16:08 volumio-43-dsi kernel: xhci-hcd xhci-hcd.1: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000008000000810 Feb 28 17:16:08 volumio-43-dsi kernel: xhci-hcd xhci-hcd.1: irq 137, io mem 0x1f00300000 Feb 28 17:16:08 volumio-43-dsi kernel: xhci-hcd xhci-hcd.1: xHCI Host Controller Feb 28 17:16:08 volumio-43-dsi kernel: xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 4 Feb 28 17:16:08 volumio-43-dsi kernel: xhci-hcd xhci-hcd.1: Host supports USB 3.0 SuperSpeed Feb 28 17:16:08 volumio-43-dsi kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Feb 28 17:16:08 volumio-43-dsi kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 28 17:16:08 volumio-43-dsi kernel: usb usb3: Product: xHCI Host Controller Feb 28 17:16:08 volumio-43-dsi kernel: usb usb3: Manufacturer: Linux 6.6.62-v8+ xhci-hcd Feb 28 17:16:08 volumio-43-dsi kernel: usb usb3: SerialNumber: xhci-hcd.1 Feb 28 17:16:08 volumio-43-dsi kernel: hub 3-0:1.0: USB hub found Feb 28 17:16:08 volumio-43-dsi kernel: hub 3-0:1.0: 2 ports detected Feb 28 17:16:08 volumio-43-dsi kernel: usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06 Feb 28 17:16:08 volumio-43-dsi kernel: usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 28 17:16:08 volumio-43-dsi kernel: usb usb4: Product: xHCI Host Controller Feb 28 17:16:08 volumio-43-dsi kernel: usb usb4: Manufacturer: Linux 6.6.62-v8+ xhci-hcd Feb 28 17:16:08 volumio-43-dsi kernel: usb usb4: SerialNumber: xhci-hcd.1 Feb 28 17:16:08 volumio-43-dsi kernel: hub 4-0:1.0: USB hub found Feb 28 17:16:08 volumio-43-dsi kernel: hub 4-0:1.0: 1 port detected Feb 28 17:16:08 volumio-43-dsi kernel: bcm2712-iommu 1000005100.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Feb 28 17:16:08 volumio-43-dsi kernel: platform 1000800000.codec: bcm2712_iommu_probe_device: MMU 1000005100.iommu Feb 28 17:16:08 volumio-43-dsi kernel: platform 1000800000.codec: bcm2712_iommu_device_group: MMU 1000005100.iommu Feb 28 17:16:08 volumio-43-dsi kernel: platform 1000800000.codec: Adding to iommu group 0 Feb 28 17:16:08 volumio-43-dsi kernel: platform 1000880000.pisp_be: bcm2712_iommu_probe_device: MMU 1000005100.iommu Feb 28 17:16:08 volumio-43-dsi kernel: platform 1000880000.pisp_be: bcm2712_iommu_device_group: MMU 1000005100.iommu Feb 28 17:16:08 volumio-43-dsi kernel: platform 1000880000.pisp_be: Adding to iommu group 0 Feb 28 17:16:08 volumio-43-dsi kernel: platform 1000800000.codec: bcm2712_iommu_attach_dev: MMU 1000005100.iommu Feb 28 17:16:08 volumio-43-dsi kernel: platform 1000880000.pisp_be: bcm2712_iommu_attach_dev: MMU 1000005100.iommu Feb 28 17:16:08 volumio-43-dsi kernel: bcm2712-iommu 1000005100.iommu: bcm2712_iommu_probe: Success Feb 28 17:16:08 volumio-43-dsi kernel: bcm2712-iommu 1000005200.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Feb 28 17:16:08 volumio-43-dsi kernel: platform axi:gpu: bcm2712_iommu_probe_device: MMU 1000005200.iommu Feb 28 17:16:08 volumio-43-dsi kernel: platform axi:gpu: bcm2712_iommu_device_group: MMU 1000005200.iommu Feb 28 17:16:08 volumio-43-dsi kernel: platform axi:gpu: Adding to iommu group 1 Feb 28 17:16:08 volumio-43-dsi kernel: platform axi:gpu: bcm2712_iommu_attach_dev: MMU 1000005200.iommu Feb 28 17:16:08 volumio-43-dsi kernel: bcm2712-iommu 1000005200.iommu: bcm2712_iommu_probe: Success Feb 28 17:16:08 volumio-43-dsi kernel: bcm2712-iommu 1000005280.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Feb 28 17:16:08 volumio-43-dsi kernel: bcm2712-iommu 1000005280.iommu: bcm2712_iommu_probe: Success Feb 28 17:16:08 volumio-43-dsi kernel: sdhci-brcmstb 1000fff000.mmc: Got CD GPIO Feb 28 17:16:08 volumio-43-dsi kernel: mmc0: CQHCI version 5.10 Feb 28 17:16:08 volumio-43-dsi kernel: mmc1: CQHCI version 5.10 Feb 28 17:16:08 volumio-43-dsi kernel: of_cfs_init Feb 28 17:16:08 volumio-43-dsi kernel: of_cfs_init: OK Feb 28 17:16:08 volumio-43-dsi kernel: clk: Disabling unused clocks Feb 28 17:16:08 volumio-43-dsi kernel: mmc0: SDHCI controller on 1000fff000.mmc [1000fff000.mmc] using ADMA 64-bit Feb 28 17:16:08 volumio-43-dsi kernel: mmc0: new ultra high speed SDR104 SDHC card at address b369 Feb 28 17:16:08 volumio-43-dsi kernel: mmcblk0: mmc0:b369 SDABC 7.50 GiB Feb 28 17:16:08 volumio-43-dsi kernel: mmcblk0: p1 p2 p3 Feb 28 17:16:08 volumio-43-dsi kernel: mmcblk0: mmc0:b369 SDABC 7.50 GiB Feb 28 17:16:08 volumio-43-dsi kernel: mmc1: SDHCI controller on 1001100000.mmc [1001100000.mmc] using ADMA 64-bit Feb 28 17:16:08 volumio-43-dsi kernel: Freeing unused kernel memory: 4864K Feb 28 17:16:08 volumio-43-dsi kernel: Run /init as init process Feb 28 17:16:08 volumio-43-dsi kernel: with arguments: Feb 28 17:16:08 volumio-43-dsi kernel: /init Feb 28 17:16:08 volumio-43-dsi kernel: splash Feb 28 17:16:08 volumio-43-dsi kernel: nodebug Feb 28 17:16:08 volumio-43-dsi kernel: with environment: Feb 28 17:16:08 volumio-43-dsi kernel: HOME=/ Feb 28 17:16:08 volumio-43-dsi kernel: TERM=linux Feb 28 17:16:08 volumio-43-dsi kernel: imgpart=UUID=fb80cb44-b4d3-4b36-be35-613cba0a09fb Feb 28 17:16:08 volumio-43-dsi kernel: imgfile=/volumio_current.sqsh Feb 28 17:16:08 volumio-43-dsi kernel: bootpart=UUID=51B1-040D Feb 28 17:16:08 volumio-43-dsi kernel: datapart=UUID=2899bccc-8620-4c7a-b56a-43fd2f7a836a Feb 28 17:16:08 volumio-43-dsi kernel: uuidconfig=cmdline.txt Feb 28 17:16:08 volumio-43-dsi kernel: bootdelay=7 Feb 28 17:16:08 volumio-43-dsi kernel: use_kmsg=no Feb 28 17:16:08 volumio-43-dsi kernel: mmc1: new ultra high speed DDR50 SDIO card at address 0001 Feb 28 17:16:08 volumio-43-dsi kernel: input: pwr_button as /devices/platform/pwr_button/input/input0 Feb 28 17:16:08 volumio-43-dsi kernel: snd_timer: Unknown symbol snd_info_register (err -2) Feb 28 17:16:08 volumio-43-dsi kernel: snd_timer: Unknown symbol snd_info_create_module_entry (err -2) Feb 28 17:16:08 volumio-43-dsi kernel: snd_timer: Unknown symbol snd_info_free_entry (err -2) Feb 28 17:16:08 volumio-43-dsi kernel: snd_timer: Unknown symbol snd_fasync_helper (err -2) Feb 28 17:16:08 volumio-43-dsi kernel: snd_timer: Unknown symbol snd_ecards_limit (err -2) Feb 28 17:16:08 volumio-43-dsi kernel: snd_timer: Unknown symbol snd_oss_info_register (err -2) Feb 28 17:16:08 volumio-43-dsi kernel: snd_timer: Unknown symbol snd_unregister_device (err -2) Feb 28 17:16:08 volumio-43-dsi kernel: snd_timer: Unknown symbol snd_device_alloc (err -2) Feb 28 17:16:08 volumio-43-dsi kernel: snd_timer: Unknown symbol snd_device_new (err -2) Feb 28 17:16:08 volumio-43-dsi kernel: snd_timer: Unknown symbol snd_fasync_free (err -2) Feb 28 17:16:08 volumio-43-dsi kernel: snd_timer: Unknown symbol snd_kill_fasync (err -2) Feb 28 17:16:08 volumio-43-dsi kernel: snd_timer: Unknown symbol snd_register_device (err -2) Feb 28 17:16:08 volumio-43-dsi kernel: brcmstb-i2c 107d508200.i2c: @97500hz registered in interrupt mode Feb 28 17:16:08 volumio-43-dsi kernel: brcmstb-i2c 107d508280.i2c: @97500hz registered in interrupt mode Feb 28 17:16:08 volumio-43-dsi kernel: [drm] Initialized v3d 1.0.0 20180419 for 1002000000.v3d on minor 0 Feb 28 17:16:08 volumio-43-dsi kernel: fuse: init (API version 7.39) Feb 28 17:16:08 volumio-43-dsi kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Feb 28 17:16:08 volumio-43-dsi kernel: EXT4-fs (mmcblk0p2): recovery complete Feb 28 17:16:08 volumio-43-dsi kernel: EXT4-fs (mmcblk0p2): mounted filesystem fb80cb44-b4d3-4b36-be35-613cba0a09fb r/w with ordered data mode. Quota mode: none. Feb 28 17:16:08 volumio-43-dsi kernel: loop0: detected capacity change from 0 to 995064 Feb 28 17:16:08 volumio-43-dsi kernel: EXT4-fs (mmcblk0p3): recovery complete Feb 28 17:16:08 volumio-43-dsi kernel: EXT4-fs (mmcblk0p3): mounted filesystem 2899bccc-8620-4c7a-b56a-43fd2f7a836a r/w with ordered data mode. Quota mode: none. Feb 28 17:16:08 volumio-43-dsi kernel: ext4 filesystem being mounted at /mnt/ext supports timestamps until 2038-01-19 (0x7fffffff) Feb 28 17:16:08 volumio-43-dsi kernel: NET: Registered PF_INET6 protocol family Feb 28 17:16:08 volumio-43-dsi kernel: Segment Routing with IPv6 Feb 28 17:16:08 volumio-43-dsi kernel: In-situ OAM (IOAM) with IPv6 Feb 28 17:16:08 volumio-43-dsi 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) Feb 28 17:16:08 volumio-43-dsi systemd[1]: Detected architecture arm64. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Set hostname to . Feb 28 17:16:08 volumio-43-dsi kernel: uart-pl011 107d001000.serial: no DMA platform data Feb 28 17:16:08 volumio-43-dsi 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. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Listening on RPCbind Server Activation Socket. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Listening on udev Control Socket. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Listening on Journal Audit Socket. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Listening on Journal Socket (/dev/log). Feb 28 17:16:08 volumio-43-dsi systemd[1]: Listening on fsck to fsckd communication Socket. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Listening on udev Kernel Socket. Feb 28 17:16:08 volumio-43-dsi systemd-journald[387]: Journal started Feb 28 17:16:08 volumio-43-dsi systemd-journald[387]: Runtime journal (/run/log/journal/a89b7be63bc86b985c184d84675fe488) is 3.7M, max 30.0M, 26.2M free. Feb 28 17:16:08 volumio-43-dsi fake-hwclock[396]: Current system time: 2025-02-28 09:16:08 Feb 28 17:16:08 volumio-43-dsi fake-hwclock[396]: fake-hwclock saved clock information is in the past: 2025-02-28 09:15:46 Feb 28 17:16:08 volumio-43-dsi fake-hwclock[396]: To set system time to this saved clock anyway, use "force" Feb 28 17:16:08 volumio-43-dsi systemd[1]: dynamicswap.service: Succeeded. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Mounted POSIX Message Queue File System. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Started Remount Root and Kernel File Systems. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Started Create list of required static device nodes for the current kernel. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Mounted Kernel Debug File System. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Mounted RPC Pipe File System. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Started Restore / save the current clock. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Starting Load/Save Random Seed... Feb 28 17:16:08 volumio-43-dsi systemd[1]: Starting Create System Users... Feb 28 17:16:08 volumio-43-dsi systemd[1]: Started Load/Save Random Seed. Feb 28 17:16:08 volumio-43-dsi systemd-modules-load[399]: Inserted module 'i2c_dev' Feb 28 17:16:08 volumio-43-dsi systemd[1]: Started Load Kernel Modules. Feb 28 17:16:08 volumio-43-dsi kernel: i2c_dev: i2c /dev entries driver Feb 28 17:16:08 volumio-43-dsi systemd[1]: Starting Apply Kernel Variables... Feb 28 17:16:08 volumio-43-dsi systemd[1]: Mounting FUSE Control File System... Feb 28 17:16:08 volumio-43-dsi systemd[1]: Mounting Kernel Configuration File System... Feb 28 17:16:08 volumio-43-dsi systemd[1]: Mounted FUSE Control File System. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Mounted Kernel Configuration File System. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Started Create System Users. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Starting Create Static Device Nodes in /dev... Feb 28 17:16:08 volumio-43-dsi systemd[1]: Started Apply Kernel Variables. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Started udev Coldplug all Devices. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Starting Helper to synchronize boot up for ifupdown... Feb 28 17:16:08 volumio-43-dsi systemd[1]: Started Helper to synchronize boot up for ifupdown. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Started Create Static Device Nodes in /dev. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Starting udev Kernel Device Manager... Feb 28 17:16:08 volumio-43-dsi systemd[1]: Reached target Local File Systems (Pre). Feb 28 17:16:08 volumio-43-dsi systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Mounting /var/log... Feb 28 17:16:08 volumio-43-dsi systemd[1]: Mounting /var/spool/cups... Feb 28 17:16:08 volumio-43-dsi systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Mounting /tmp... Feb 28 17:16:08 volumio-43-dsi systemd[1]: Mounted /var/log. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Mounted /var/spool/cups. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Mounted /tmp. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Mounting /var/spool/cups/tmp... Feb 28 17:16:08 volumio-43-dsi systemd[1]: Starting Flush Journal to Persistent Storage... Feb 28 17:16:08 volumio-43-dsi systemd-udevd[418]: Network interface NamePolicy= disabled on kernel command line, ignoring. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Mounted /var/spool/cups/tmp. Feb 28 17:16:08 volumio-43-dsi systemd-journald[387]: Runtime journal (/run/log/journal/a89b7be63bc86b985c184d84675fe488) is 3.7M, max 30.0M, 26.2M free. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Started udev Kernel Device Manager. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Starting Show Plymouth Boot Screen... Feb 28 17:16:08 volumio-43-dsi systemd[1]: Started Flush Journal to Persistent Storage. Feb 28 17:16:08 volumio-43-dsi systemd[1]: plymouth-start.service: Succeeded. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Started Show Plymouth Boot Screen. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Reached target Local Encrypted Volumes. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Reached target Paths. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Feb 28 17:16:08 volumio-43-dsi kernel: rpi-gpiomem 107d508500.gpiomem: window base 0x107d508500 size 0x00000040 Feb 28 17:16:08 volumio-43-dsi kernel: rpi-gpiomem 107d508500.gpiomem: initialised 1 regions as /dev/gpiomem1 Feb 28 17:16:08 volumio-43-dsi kernel: rpi-gpiomem 107d517c00.gpiomem: window base 0x107d517c00 size 0x00000040 Feb 28 17:16:08 volumio-43-dsi kernel: rpi-gpiomem 107d517c00.gpiomem: initialised 1 regions as /dev/gpiomem2 Feb 28 17:16:08 volumio-43-dsi kernel: rpi-gpiomem 107d504100.gpiomem: window base 0x107d504100 size 0x00000020 Feb 28 17:16:08 volumio-43-dsi kernel: rpi-gpiomem 107d504100.gpiomem: initialised 1 regions as /dev/gpiomem3 Feb 28 17:16:08 volumio-43-dsi kernel: rpi-gpiomem 107d510700.gpiomem: window base 0x107d510700 size 0x00000020 Feb 28 17:16:08 volumio-43-dsi kernel: rpi-gpiomem 107d510700.gpiomem: initialised 1 regions as /dev/gpiomem4 Feb 28 17:16:08 volumio-43-dsi kernel: rpi-gpiomem 1f000d0000.gpiomem: window base 0x1f000d0000 size 0x00030000 Feb 28 17:16:08 volumio-43-dsi kernel: rpi-gpiomem 1f000d0000.gpiomem: initialised 1 regions as /dev/gpiomem0 Feb 28 17:16:08 volumio-43-dsi kernel: rp1_pio: Unknown symbol rp1_firmware_message (err -2) Feb 28 17:16:08 volumio-43-dsi kernel: rp1_pio: Unknown symbol devm_rp1_firmware_get (err -2) Feb 28 17:16:08 volumio-43-dsi kernel: rp1_pio: Unknown symbol rp1_firmware_get_feature (err -2) Feb 28 17:16:08 volumio-43-dsi systemd-udevd[425]: Using default interface naming scheme 'v240'. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Found device /dev/ttyAMA10. Feb 28 17:16:08 volumio-43-dsi systemd-udevd[425]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 28 17:16:08 volumio-43-dsi kernel: mc: Linux media interface: v0.10 Feb 28 17:16:08 volumio-43-dsi kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Feb 28 17:16:08 volumio-43-dsi kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Feb 28 17:16:08 volumio-43-dsi kernel: videodev: Linux video capture interface: v2.00 Feb 28 17:16:08 volumio-43-dsi kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Feb 28 17:16:08 volumio-43-dsi kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Feb 28 17:16:08 volumio-43-dsi kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Feb 28 17:16:08 volumio-43-dsi systemd-udevd[454]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 28 17:16:08 volumio-43-dsi systemd-udevd[430]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 28 17:16:08 volumio-43-dsi kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_of_xlate: MMU 1000005100.iommu Feb 28 17:16:08 volumio-43-dsi kernel: pispbe 1000880000.pisp_be: Runtime PM usage count underflow! Feb 28 17:16:08 volumio-43-dsi kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Feb 28 17:16:08 volumio-43-dsi kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Feb 28 17:16:08 volumio-43-dsi kernel: usbcore: registered new interface driver brcmfmac Feb 28 17:16:08 volumio-43-dsi kernel: brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,5-model-b.bin failed with error -2 Feb 28 17:16:08 volumio-43-dsi kernel: Bluetooth: Core ver 2.22 Feb 28 17:16:08 volumio-43-dsi kernel: NET: Registered PF_BLUETOOTH protocol family Feb 28 17:16:08 volumio-43-dsi kernel: Bluetooth: HCI device and connection manager initialized Feb 28 17:16:08 volumio-43-dsi kernel: Bluetooth: HCI socket layer initialized Feb 28 17:16:08 volumio-43-dsi kernel: Bluetooth: L2CAP socket layer initialized Feb 28 17:16:08 volumio-43-dsi kernel: Bluetooth: SCO socket layer initialized Feb 28 17:16:08 volumio-43-dsi kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Feb 28 17:16:08 volumio-43-dsi kernel: Bluetooth: HCI UART driver ver 2.3 Feb 28 17:16:08 volumio-43-dsi kernel: Bluetooth: HCI UART protocol H4 registered Feb 28 17:16:08 volumio-43-dsi kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Feb 28 17:16:08 volumio-43-dsi kernel: Bluetooth: HCI UART protocol Broadcom registered Feb 28 17:16:08 volumio-43-dsi kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Feb 28 17:16:08 volumio-43-dsi kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Feb 28 17:16:08 volumio-43-dsi kernel: rpivid 1000800000.codec: bcm2712_iommu_of_xlate: MMU 1000005100.iommu Feb 28 17:16:08 volumio-43-dsi kernel: wm8804 1-003b: revision E Feb 28 17:16:08 volumio-43-dsi kernel: rpivid 1000800000.codec: Device registered as /dev/video19 Feb 28 17:16:08 volumio-43-dsi systemd[1]: Found device /dev/disk/by-uuid/51B1-040D. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Feb 28 17:16:08 volumio-43-dsi systemd-fsck[652]: fsck.fat 4.1 (2017-01-24) Feb 28 17:16:08 volumio-43-dsi systemd-fsck[652]: /dev/mmcblk0p1: 398 files, 166313/184582 clusters Feb 28 17:16:08 volumio-43-dsi systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Starting File System Check on /dev/disk/by-uuid/51B1-040D... Feb 28 17:16:08 volumio-43-dsi systemd[1]: Started File System Check Daemon to report status. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Started File System Check on /dev/disk/by-uuid/51B1-040D. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Mounting /boot... Feb 28 17:16:08 volumio-43-dsi ifup[661]: command failed: No such device (-19) Feb 28 17:16:08 volumio-43-dsi systemd[1]: Mounted /boot. Feb 28 17:16:08 volumio-43-dsi sh[662]: command failed: No such device (-19) Feb 28 17:16:08 volumio-43-dsi systemd[1]: Reached target Local File Systems. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Starting Preprocess NFS configuration... Feb 28 17:16:08 volumio-43-dsi systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Feb 28 17:16:08 volumio-43-dsi systemd[1]: Starting Raise network interfaces... Feb 28 17:16:08 volumio-43-dsi systemd[1]: Started ifup for eth0. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Starting Create Volatile Files and Directories... Feb 28 17:16:08 volumio-43-dsi systemd[1]: nfs-config.service: Succeeded. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Started Preprocess NFS configuration. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Reached target NFS client services. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Received SIGRTMIN+20 from PID 280 (plymouthd). Feb 28 17:16:08 volumio-43-dsi systemd[1]: plymouth-read-write.service: Succeeded. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Started Create Volatile Files and Directories. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Starting Update UTMP about System Boot/Shutdown... Feb 28 17:16:08 volumio-43-dsi systemd[1]: Starting RPC bind portmap service... Feb 28 17:16:08 volumio-43-dsi systemd[1]: Started Update UTMP about System Boot/Shutdown. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Reached target System Initialization. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Listening on D-Bus System Message Bus Socket. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Listening on triggerhappy.socket. Feb 28 17:16:08 volumio-43-dsi kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Feb 28 17:16:08 volumio-43-dsi kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov 1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60 Feb 28 17:16:08 volumio-43-dsi systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Reached target Sockets. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Started Daily apt download activities. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Started Daily man-db regeneration. Feb 28 17:16:08 volumio-43-dsi systemd[1]: Reached target Basic System. Feb 28 17:16:08 volumio-43-dsi ifup[661]: command failed: No such device (-19) Feb 28 17:16:09 volumio-43-dsi systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started Volumio Iptables Module. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Starting Wireless Services... Feb 28 17:16:09 volumio-43-dsi systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Starting triggerhappy global hotkey daemon... Feb 28 17:16:09 volumio-43-dsi systemd[1]: Condition check resulted in Configure Bluetooth Modems connected by UART being skipped. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Starting dhcpcd on all interfaces... Feb 28 17:16:09 volumio-43-dsi systemd[1]: Starting Login Service... Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started Volumio Log Rotation Service. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started MPD Monitor Service. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started D-Bus System Message Bus. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Starting WPA supplicant... Feb 28 17:16:09 volumio-43-dsi systemd-udevd[443]: Using default interface naming scheme 'v240'. Feb 28 17:16:09 volumio-43-dsi systemd-udevd[443]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Starting Volumio Time Update Utility... Feb 28 17:16:09 volumio-43-dsi kernel: vc4-drm axi:gpu: bcm2712_iommu_of_xlate: MMU 1000005200.iommu Feb 28 17:16:09 volumio-43-dsi kernel: vc4-drm axi:gpu: bound 107c580000.hvs (ops vc4_hvs_ops [vc4]) Feb 28 17:16:09 volumio-43-dsi kernel: Registered IR keymap rc-cec Feb 28 17:16:09 volumio-43-dsi kernel: rc rc0: vc4-hdmi-0 as /devices/platform/soc/107c701400.hdmi/rc/rc0 Feb 28 17:16:09 volumio-43-dsi kernel: input: vc4-hdmi-0 as /devices/platform/soc/107c701400.hdmi/rc/rc0/input1 Feb 28 17:16:09 volumio-43-dsi kernel: input: vc4-hdmi-0 HDMI Jack as /devices/platform/soc/107c701400.hdmi/sound/card1/input2 Feb 28 17:16:09 volumio-43-dsi kernel: vc4-drm axi:gpu: bound 107c701400.hdmi (ops vc4_hdmi_ops [vc4]) Feb 28 17:16:09 volumio-43-dsi kernel: Registered IR keymap rc-cec Feb 28 17:16:09 volumio-43-dsi kernel: rc rc1: vc4-hdmi-1 as /devices/platform/soc/107c706400.hdmi/rc/rc1 Feb 28 17:16:09 volumio-43-dsi kernel: input: vc4-hdmi-1 as /devices/platform/soc/107c706400.hdmi/rc/rc1/input3 Feb 28 17:16:09 volumio-43-dsi kernel: input: vc4-hdmi-1 HDMI Jack as /devices/platform/soc/107c706400.hdmi/sound/card2/input4 Feb 28 17:16:09 volumio-43-dsi kernel: vc4-drm axi:gpu: bound 107c706400.hdmi (ops vc4_hdmi_ops [vc4]) Feb 28 17:16:09 volumio-43-dsi kernel: vc4-drm axi:gpu: bound 107c500000.mop (ops vc4_txp_ops [vc4]) Feb 28 17:16:09 volumio-43-dsi kernel: vc4-drm axi:gpu: bound 107c501000.moplet (ops vc4_txp_ops [vc4]) Feb 28 17:16:09 volumio-43-dsi kernel: vc4-drm axi:gpu: bound 107c410000.pixelvalve (ops vc4_crtc_ops [vc4]) Feb 28 17:16:09 volumio-43-dsi kernel: vc4-drm axi:gpu: bound 107c411000.pixelvalve (ops vc4_crtc_ops [vc4]) Feb 28 17:16:09 volumio-43-dsi kernel: [drm] Initialized vc4 0.0.0 20140616 for axi:gpu on minor 1 Feb 28 17:16:09 volumio-43-dsi kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Feb 28 17:16:09 volumio-43-dsi kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started UPnP Renderer front-end to MPD. Feb 28 17:16:09 volumio-43-dsi thd[736]: Unable to parse trigger line: Feb 28 17:16:09 volumio-43-dsi thd[736]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Feb 28 17:16:09 volumio-43-dsi thd[736]: Unable to parse trigger line: Feb 28 17:16:09 volumio-43-dsi thd[736]: Found socket passed from systemd Feb 28 17:16:09 volumio-43-dsi dhcpcd[737]: Not running dhcpcd because /etc/network/interfaces Feb 28 17:16:09 volumio-43-dsi dhcpcd[737]: defines some interfaces that will use a Feb 28 17:16:09 volumio-43-dsi dhcpcd[737]: DHCP client or static address Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started Daily Cleanup of Temporary Directories. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started Daily apt upgrade and clean activities. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Reached target Timers. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started volumio-remote-updater.service. Feb 28 17:16:09 volumio-43-dsi systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Feb 28 17:16:09 volumio-43-dsi systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Failed to start dhcpcd on all interfaces. Feb 28 17:16:09 volumio-43-dsi systemd-logind[738]: New seat seat0. Feb 28 17:16:09 volumio-43-dsi systemd-logind[738]: Watching system buttons on /dev/input/event0 (pwr_button) Feb 28 17:16:09 volumio-43-dsi kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Feb 28 17:16:09 volumio-43-dsi kernel: Bluetooth: hci0: BCM: chip id 107 Feb 28 17:16:09 volumio-43-dsi kernel: Bluetooth: hci0: BCM: features 0x2f Feb 28 17:16:09 volumio-43-dsi kernel: Bluetooth: hci0: BCM4345C0 Feb 28 17:16:09 volumio-43-dsi kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Feb 28 17:16:09 volumio-43-dsi volumio-time-update[748]: volumio-time-update-util: Fetching time from Volumio... Feb 28 17:16:09 volumio-43-dsi kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Feb 28 17:16:09 volumio-43-dsi kernel: 8021q: 802.1Q VLAN Support v1.8 Feb 28 17:16:09 volumio-43-dsi systemd-udevd[449]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 28 17:16:09 volumio-43-dsi systemd-logind[738]: Watching system buttons on /dev/input/event1 (vc4-hdmi-0) Feb 28 17:16:09 volumio-43-dsi systemd-udevd[446]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 28 17:16:09 volumio-43-dsi systemd-udevd[454]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 28 17:16:09 volumio-43-dsi systemd-udevd[438]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 28 17:16:09 volumio-43-dsi systemd-logind[738]: Watching system buttons on /dev/input/event3 (vc4-hdmi-1) Feb 28 17:16:09 volumio-43-dsi avahi-daemon[724]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Feb 28 17:16:09 volumio-43-dsi avahi-daemon[724]: Successfully dropped root privileges. Feb 28 17:16:09 volumio-43-dsi mpd_monitor.sh[740]: MPD error: Connection refused Feb 28 17:16:09 volumio-43-dsi avahi-daemon[724]: avahi-daemon 0.7 starting up. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started triggerhappy global hotkey daemon. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started RPC bind portmap service. Feb 28 17:16:09 volumio-43-dsi mpd_monitor.sh[740]: mpd: no process found Feb 28 17:16:09 volumio-43-dsi kernel: macb 1f00100000.ethernet eth0: PHY [1f00100000.ethernet-ffffffff:01] driver [Broadcom BCM54213PE] (irq=POLL) Feb 28 17:16:09 volumio-43-dsi kernel: macb 1f00100000.ethernet eth0: configuring for phy/rgmii-id link mode Feb 28 17:16:09 volumio-43-dsi sh[662]: eth0: waiting for carrier Feb 28 17:16:09 volumio-43-dsi dhcpcd[711]: eth0: waiting for carrier Feb 28 17:16:09 volumio-43-dsi kernel: pps pps0: new PPS source ptp0 Feb 28 17:16:09 volumio-43-dsi kernel: macb 1f00100000.ethernet: gem-ptp-timer ptp clock registered. Feb 28 17:16:09 volumio-43-dsi volumio-remote-updater[752]: Error: No active session Feb 28 17:16:09 volumio-43-dsi volumio-remote-updater[752]: [2025-02-28 17:16:09] [info] asio async_connect error: system:111 (Connection refused) Feb 28 17:16:09 volumio-43-dsi volumio-remote-updater[752]: [2025-02-28 17:16:09] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 28 17:16:09 volumio-43-dsi volumio-remote-updater[752]: [2025-02-28 17:16:09] [error] handle_connect error: Underlying Transport Error Feb 28 17:16:09 volumio-43-dsi volumio[751]: Could not open config: /tmp/upmpdcli.conf Feb 28 17:16:09 volumio-43-dsi haveged[689]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Feb 28 17:16:09 volumio-43-dsi haveged[689]: haveged: cpu: (VC); data: 64K (V); inst: 64K (V); idx: 39/40; sz: 63488/63488 Feb 28 17:16:09 volumio-43-dsi haveged[689]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99976 Feb 28 17:16:09 volumio-43-dsi haveged[689]: haveged: fills: 0, generated: 0 Feb 28 17:16:09 volumio-43-dsi wpa_supplicant[744]: Successfully initialized wpa_supplicant Feb 28 17:16:09 volumio-43-dsi avahi-daemon[724]: Successfully called chroot(). Feb 28 17:16:09 volumio-43-dsi avahi-daemon[724]: Successfully dropped remaining capabilities. Feb 28 17:16:09 volumio-43-dsi avahi-daemon[724]: Loading service file /services/volumio.service. Feb 28 17:16:09 volumio-43-dsi systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 28 17:16:09 volumio-43-dsi systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 28 17:16:09 volumio-43-dsi volumio-time-update[748]: volumio-time-update-util: Date not found in response Feb 28 17:16:09 volumio-43-dsi volumio-time-update[748]: volumio-time-update-util: Retrying in 5 seconds... Feb 28 17:16:09 volumio-43-dsi avahi-daemon[724]: Network interface enumeration completed. Feb 28 17:16:09 volumio-43-dsi avahi-daemon[724]: Server startup complete. Host name is volumio-43-dsi.local. Local service cookie is 3157354644. Feb 28 17:16:09 volumio-43-dsi avahi-daemon[724]: Service "Volumio-43-dsi" (/services/volumio.service) successfully established. Feb 28 17:16:09 volumio-43-dsi systemd[1]: iptables.service: Succeeded. Feb 28 17:16:09 volumio-43-dsi dbus-daemon[742]: [system] Successfully activated service 'org.freedesktop.systemd1' Feb 28 17:16:09 volumio-43-dsi kernel: alsactl[763]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Feb 28 17:16:09 volumio-43-dsi systemd[1]: Listening on mpd.socket. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Condition check resulted in Configure Bluetooth Modems connected by UART being skipped. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Created slice system-bthelper.slice. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Starting Raspberry Pi bluetooth helper... Feb 28 17:16:09 volumio-43-dsi systemd[1]: Starting Load/Save RF Kill Switch Status... Feb 28 17:16:09 volumio-43-dsi systemd[1]: Reached target Remote File Systems (Pre). Feb 28 17:16:09 volumio-43-dsi systemd[1]: Reached target Remote File Systems. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Feb 28 17:16:09 volumio-43-dsi systemd[1]: Reached target RPC Port Mapper. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started Manage Sound Card State (restore and store). Feb 28 17:16:09 volumio-43-dsi systemd[1]: Starting Save/Restore Sound Card State... Feb 28 17:16:09 volumio-43-dsi alsactl[863]: alsactl 1.1.8 daemon started Feb 28 17:16:09 volumio-43-dsi systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started Load/Save RF Kill Switch Status. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started Save/Restore Sound Card State. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started Raise network interfaces. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Reached target Sound Card. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started ifup for wlan0. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started Login Service. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started Avahi mDNS/DNS-SD Stack. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started WPA supplicant. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Reached target Network. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Starting Music Player Daemon... Feb 28 17:16:09 volumio-43-dsi kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Feb 28 17:16:09 volumio-43-dsi systemd[1]: Starting OpenBSD Secure Shell server... Feb 28 17:16:09 volumio-43-dsi systemd[1]: Starting Permit User Sessions... Feb 28 17:16:09 volumio-43-dsi systemd[1]: Reached target Network is Online. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Starting Samba NMB Daemon... Feb 28 17:16:09 volumio-43-dsi systemd[1]: Starting /etc/rc.local Compatibility... Feb 28 17:16:09 volumio-43-dsi systemd[1]: Starting LSB: Brings up/down network automatically... Feb 28 17:16:09 volumio-43-dsi systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Feb 28 17:16:09 volumio-43-dsi kernel: Bluetooth: hci0: BCM: features 0x2f Feb 28 17:16:09 volumio-43-dsi systemd[1]: Starting Network Time Service... Feb 28 17:16:09 volumio-43-dsi kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Feb 28 17:16:09 volumio-43-dsi kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Feb 28 17:16:09 volumio-43-dsi kernel: Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac) Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started Permit User Sessions. Feb 28 17:16:09 volumio-43-dsi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started /etc/rc.local Compatibility. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Starting Hold until boot process finishes up... Feb 28 17:16:09 volumio-43-dsi systemd[1]: Starting Terminate Plymouth Boot Screen... Feb 28 17:16:09 volumio-43-dsi systemd[1]: Received SIGRTMIN+21 from PID 280 (plymouthd). Feb 28 17:16:09 volumio-43-dsi systemd[1]: Received SIGRTMIN+21 from PID 280 (plymouthd). Feb 28 17:16:09 volumio-43-dsi systemd[1]: plymouth-quit-wait.service: Succeeded. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started Hold until boot process finishes up. Feb 28 17:16:09 volumio-43-dsi systemd[1]: plymouth-quit.service: Succeeded. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started Terminate Plymouth Boot Screen. Feb 28 17:16:09 volumio-43-dsi ifplugd(eth0)[965]: ifplugd 0.28 initializing. Feb 28 17:16:09 volumio-43-dsi ifplugd(eth0)[965]: Using interface eth0/D8:3A:DD:D2:CE:EA with driver (version: 6.6.62-v8+) Feb 28 17:16:09 volumio-43-dsi loadcpufreq[862]: Loading cpufreq kernel modules...done (none). Feb 28 17:16:09 volumio-43-dsi bthelper[860]: Raspberry Pi BDADDR already set Feb 28 17:16:09 volumio-43-dsi ifplugd(eth0)[965]: Using detection mode: SIOCETHTOOL Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started Serial Getty on ttyAMA10. Feb 28 17:16:09 volumio-43-dsi ifplugd(eth0)[965]: Initialization complete, link beat not detected. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started Getty on tty1. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Reached target Login Prompts. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started Raspberry Pi bluetooth helper. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Starting LSB: set CPUFreq kernel parameters... Feb 28 17:16:09 volumio-43-dsi systemd[1]: Starting Bluetooth service... Feb 28 17:16:09 volumio-43-dsi sh[873]: wlan0=wlan0 Feb 28 17:16:09 volumio-43-dsi ntpd[902]: ntpd 4.2.8p12@1.3728-o (1): Starting Feb 28 17:16:09 volumio-43-dsi cpufrequtils[975]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Feb 28 17:16:09 volumio-43-dsi ntpd[902]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Feb 28 17:16:09 volumio-43-dsi ifplugd[897]: Network Interface Plugging Daemon...start eth0...done. Feb 28 17:16:09 volumio-43-dsi ntpd[993]: proto: precision = 0.370 usec (-21) Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started LSB: Brings up/down network automatically. Feb 28 17:16:09 volumio-43-dsi ntpd[993]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Feb 28 17:16:09 volumio-43-dsi ntpd[993]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Feb 28 17:16:09 volumio-43-dsi ntpd[993]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 63 days ago Feb 28 17:16:09 volumio-43-dsi ntpd[993]: Listen and drop on 0 v6wildcard [::]:123 Feb 28 17:16:09 volumio-43-dsi ntpd[993]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 28 17:16:09 volumio-43-dsi ntpd[993]: Listen normally on 2 lo 127.0.0.1:123 Feb 28 17:16:09 volumio-43-dsi ntpd[993]: Listening on routing socket on fd #19 for interface updates Feb 28 17:16:09 volumio-43-dsi ntpd[993]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started Network Time Service. Feb 28 17:16:09 volumio-43-dsi ntpd[993]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started LSB: set CPUFreq kernel parameters. Feb 28 17:16:09 volumio-43-dsi sshd[992]: Server listening on 0.0.0.0 port 22. Feb 28 17:16:09 volumio-43-dsi sshd[992]: Server listening on :: port 22. Feb 28 17:16:09 volumio-43-dsi systemd[1]: Started OpenBSD Secure Shell server. Feb 28 17:16:10 volumio-43-dsi bluetoothd[976]: Bluetooth daemon 5.50 Feb 28 17:16:10 volumio-43-dsi sudo[881]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 28 17:16:10 volumio-43-dsi sudo[881]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:10 volumio-43-dsi sudo[881]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 28 17:16:10 volumio-43-dsi sudo[881]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:10 volumio-43-dsi bluetoothd[976]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Feb 28 17:16:10 volumio-43-dsi systemd[1]: Started Bluetooth service. Feb 28 17:16:10 volumio-43-dsi systemd[1]: Reached target Bluetooth. Feb 28 17:16:10 volumio-43-dsi bluetoothd[976]: Starting SDP server Feb 28 17:16:10 volumio-43-dsi bluetoothd[976]: Excluding (cli) sap Feb 28 17:16:10 volumio-43-dsi kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Feb 28 17:16:10 volumio-43-dsi kernel: Bluetooth: BNEP filters: protocol multicast Feb 28 17:16:10 volumio-43-dsi kernel: Bluetooth: BNEP socket layer initialized Feb 28 17:16:10 volumio-43-dsi bluetoothd[976]: Bluetooth management interface 1.22 initialized Feb 28 17:16:10 volumio-43-dsi dbus-daemon[742]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=976 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Feb 28 17:16:10 volumio-43-dsi kernel: Bluetooth: MGMT ver 1.22 Feb 28 17:16:10 volumio-43-dsi systemd[1]: Starting Hostname Service... Feb 28 17:16:10 volumio-43-dsi dbus-daemon[742]: [system] Successfully activated service 'org.freedesktop.hostname1' Feb 28 17:16:10 volumio-43-dsi systemd[1]: Started Hostname Service. Feb 28 17:16:10 volumio-43-dsi bluetoothd[976]: Failed to set privacy: Rejected (0x0b) Feb 28 17:16:10 volumio-43-dsi wireless.js[735]: Cleaning previous... Feb 28 17:16:10 volumio-43-dsi nmbd[906]: [2025/02/28 17:16:10.274999, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Feb 28 17:16:10 volumio-43-dsi nmbd[906]: started asyncdns process 1008 Feb 28 17:16:10 volumio-43-dsi nmbd[906]: [2025/02/28 17:16:10.275612, 0] ../lib/util/become_daemon.c:149(daemon_status) Feb 28 17:16:10 volumio-43-dsi nmbd[906]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Feb 28 17:16:10 volumio-43-dsi nmbd[906]: [2025/02/28 17:16:10.275647, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Feb 28 17:16:10 volumio-43-dsi nmbd[906]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Feb 28 17:16:10 volumio-43-dsi sudo[1010]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 28 17:16:10 volumio-43-dsi sudo[1010]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:10 volumio-43-dsi sudo[1010]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:10 volumio-43-dsi sudo[1012]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 28 17:16:10 volumio-43-dsi sudo[1012]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:10 volumio-43-dsi sudo[1012]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:10 volumio-43-dsi mpd[999]: Feb 28 17:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 28 17:16:10 volumio-43-dsi systemd[1]: Started Music Player Daemon. Feb 28 17:16:10 volumio-43-dsi mpd_monitor.sh[740]: MPD restarted due to no mpc output. Feb 28 17:16:10 volumio-43-dsi wireless.js[735]: Stopped aP Feb 28 17:16:10 volumio-43-dsi sudo[1022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 28 17:16:10 volumio-43-dsi sudo[1022]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:10 volumio-43-dsi sudo[1022]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:10 volumio-43-dsi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 28 17:16:10 volumio-43-dsi sudo[1024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 28 17:16:10 volumio-43-dsi sudo[1024]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:10 volumio-43-dsi sudo[1024]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:10 volumio-43-dsi sudo[1031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 28 17:16:10 volumio-43-dsi sudo[1031]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:10 volumio-43-dsi sudo[1031]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:10 volumio-43-dsi sudo[1033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 28 17:16:10 volumio-43-dsi sudo[1033]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:10 volumio-43-dsi ntpd[993]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 28 17:16:11 volumio-43-dsi sudo[1033]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:11 volumio-43-dsi wireless.js[735]: SETTING APPROPRIATE REG DOMAIN: CN Feb 28 17:16:11 volumio-43-dsi sudo[1038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 28 17:16:11 volumio-43-dsi sudo[1038]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:11 volumio-43-dsi sudo[1038]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:11 volumio-43-dsi sudo[1040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set CN Feb 28 17:16:11 volumio-43-dsi sudo[1040]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:11 volumio-43-dsi sudo[1040]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:11 volumio-43-dsi wireless.js[735]: SUCCESSFULLY SET NEW REGDOMAIN: CN Feb 28 17:16:11 volumio-43-dsi wireless.js[735]: Start wireless flow Feb 28 17:16:11 volumio-43-dsi wireless.js[735]: Stopped hotspot (if there).. Feb 28 17:16:11 volumio-43-dsi sudo[1045]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 28 17:16:11 volumio-43-dsi sudo[1045]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:11 volumio-43-dsi sudo[1045]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:11 volumio-43-dsi sudo[1047]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 28 17:16:11 volumio-43-dsi sudo[1047]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:11 volumio-43-dsi ntpd[993]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 28 17:16:12 volumio-43-dsi sudo[1047]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:12 volumio-43-dsi wireless.js[735]: DHCP IP Feb 28 17:16:12 volumio-43-dsi wireless.js[735]: Start ap Feb 28 17:16:12 volumio-43-dsi wpa_supplicant[1049]: Successfully initialized wpa_supplicant Feb 28 17:16:12 volumio-43-dsi sudo[1050]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Feb 28 17:16:12 volumio-43-dsi sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:12 volumio-43-dsi dhcpcd[1051]: dev: loaded udev Feb 28 17:16:12 volumio-43-dsi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 28 17:16:12 volumio-43-dsi dhcpcd-run-hooks[1069]: wlan0: starting wpa_supplicant Feb 28 17:16:12 volumio-43-dsi dhcpcd-run-hooks[1074]: wlan0: failed to start wpa_supplicant Feb 28 17:16:12 volumio-43-dsi dhcpcd-run-hooks[1075]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Feb 28 17:16:12 volumio-43-dsi dhcpcd[1051]: wlan0: connected to Access Point `' Feb 28 17:16:12 volumio-43-dsi dhcpcd[1051]: no interfaces have a carrier Feb 28 17:16:12 volumio-43-dsi dhcpcd[1051]: forked to background, child pid 1085 Feb 28 17:16:12 volumio-43-dsi dhcpcd[1085]: eth0: waiting for carrier Feb 28 17:16:12 volumio-43-dsi dhcpcd[1085]: wlan0: waiting for carrier Feb 28 17:16:12 volumio-43-dsi sudo[1050]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:12 volumio-43-dsi ntpd[993]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 28 17:16:13 volumio-43-dsi wireless.js[735]: trying... Feb 28 17:16:13 volumio-43-dsi sudo[1087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 17:16:13 volumio-43-dsi sudo[1087]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:13 volumio-43-dsi sudo[1087]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:13 volumio-43-dsi ntpd[993]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Feb 28 17:16:14 volumio-43-dsi wireless.js[735]: trying... Feb 28 17:16:14 volumio-43-dsi sudo[1090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 17:16:14 volumio-43-dsi sudo[1090]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:14 volumio-43-dsi sudo[1090]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:14 volumio-43-dsi volumio-remote-updater[752]: [2025-02-28 17:16:14] [info] asio async_connect error: system:111 (Connection refused) Feb 28 17:16:14 volumio-43-dsi volumio-remote-updater[752]: [2025-02-28 17:16:14] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 28 17:16:14 volumio-43-dsi volumio-remote-updater[752]: [2025-02-28 17:16:14] [error] handle_connect error: Underlying Transport Error Feb 28 17:16:14 volumio-43-dsi volumio-time-update[748]: volumio-time-update-util: Fetching time from Volumio... Feb 28 17:16:14 volumio-43-dsi volumio-time-update[748]: volumio-time-update-util: Date not found in response Feb 28 17:16:14 volumio-43-dsi volumio-time-update[748]: volumio-time-update-util: Retrying in 5 seconds... Feb 28 17:16:14 volumio-43-dsi systemd[1]: systemd-rfkill.service: Succeeded. Feb 28 17:16:14 volumio-43-dsi bthelper[860]: Changing power off succeeded Feb 28 17:16:14 volumio-43-dsi bthelper[860]: [CHG] Controller D8:3A:DD:D2:CE:EE Powered: no Feb 28 17:16:14 volumio-43-dsi bthelper[860]: [CHG] Controller D8:3A:DD:D2:CE:EE Discovering: no Feb 28 17:16:14 volumio-43-dsi bthelper[860]: [CHG] Controller D8:3A:DD:D2:CE:EE Class: 0x00000000 Feb 28 17:16:15 volumio-43-dsi bthelper[860]: [CHG] Controller D8:3A:DD:D2:CE:EE Class: 0x0000041c Feb 28 17:16:15 volumio-43-dsi bthelper[860]: Changing power on succeeded Feb 28 17:16:15 volumio-43-dsi wireless.js[735]: trying... Feb 28 17:16:15 volumio-43-dsi sudo[1099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 17:16:15 volumio-43-dsi sudo[1099]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:15 volumio-43-dsi sudo[1099]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:15 volumio-43-dsi wpa_supplicant[1073]: wlan0: Trying to associate with SSID 'S.K.1' Feb 28 17:16:15 volumio-43-dsi dhcpcd[1085]: wlan0: carrier acquired Feb 28 17:16:15 volumio-43-dsi dhcpcd[1085]: wlan0: connected to Access Point `S.K.1' Feb 28 17:16:15 volumio-43-dsi wpa_supplicant[1073]: wlan0: Associated with 04:f9:f8:cf:ea:06 Feb 28 17:16:15 volumio-43-dsi wpa_supplicant[1073]: wlan0: CTRL-EVENT-CONNECTED - Connection to 04:f9:f8:cf:ea:06 completed [id=0 id_str=] Feb 28 17:16:15 volumio-43-dsi wpa_supplicant[1073]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 28 17:16:15 volumio-43-dsi wpa_supplicant[1073]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Feb 28 17:16:15 volumio-43-dsi dhcpcd[1085]: DUID 00:01:00:01:2e:f2:a3:70:dc:a6:32:80:05:ba Feb 28 17:16:15 volumio-43-dsi dhcpcd[1085]: wlan0: IAID dd:d2:ce:ec Feb 28 17:16:15 volumio-43-dsi dhcpcd[1085]: wlan0: adding address fe80::a5d2:5328:4391:b939 Feb 28 17:16:15 volumio-43-dsi dhcpcd[1085]: ipv6_addaddr1: Permission denied Feb 28 17:16:15 volumio-43-dsi dhcpcd[1085]: wlan0: carrier lost Feb 28 17:16:15 volumio-43-dsi dhcpcd[1085]: wlan0: carrier acquired Feb 28 17:16:15 volumio-43-dsi dhcpcd[1085]: wlan0: IAID dd:d2:ce:ec Feb 28 17:16:16 volumio-43-dsi dhcpcd[1085]: wlan0: soliciting an IPv6 router Feb 28 17:16:16 volumio-43-dsi wireless.js[735]: trying... Feb 28 17:16:16 volumio-43-dsi sudo[1115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 17:16:16 volumio-43-dsi sudo[1115]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:16 volumio-43-dsi sudo[1115]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:16 volumio-43-dsi dhcpcd[1085]: wlan0: rebinding lease of 192.168.5.131 Feb 28 17:16:16 volumio-43-dsi dhcpcd[1085]: wlan0: probing address 192.168.5.131/24 Feb 28 17:16:17 volumio-43-dsi wireless.js[735]: trying... Feb 28 17:16:17 volumio-43-dsi sudo[1118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 17:16:17 volumio-43-dsi sudo[1118]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:17 volumio-43-dsi sudo[1118]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:18 volumio-43-dsi wireless.js[735]: trying... Feb 28 17:16:18 volumio-43-dsi sudo[1121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 17:16:18 volumio-43-dsi sudo[1121]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:18 volumio-43-dsi sudo[1121]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:19 volumio-43-dsi wireless.js[735]: trying... Feb 28 17:16:19 volumio-43-dsi sudo[1124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 17:16:19 volumio-43-dsi sudo[1124]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:19 volumio-43-dsi sudo[1124]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:19 volumio-43-dsi volumio-time-update[748]: volumio-time-update-util: Fetching time from Volumio... Feb 28 17:16:19 volumio-43-dsi volumio-time-update[748]: volumio-time-update-util: Date not found in response Feb 28 17:16:19 volumio-43-dsi volumio-time-update[748]: volumio-time-update-util: Retrying in 5 seconds... Feb 28 17:16:20 volumio-43-dsi wireless.js[735]: trying... Feb 28 17:16:20 volumio-43-dsi sudo[1132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 17:16:20 volumio-43-dsi sudo[1132]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:20 volumio-43-dsi sudo[1132]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:21 volumio-43-dsi wireless.js[735]: trying... Feb 28 17:16:21 volumio-43-dsi sudo[1138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 17:16:21 volumio-43-dsi sudo[1138]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:21 volumio-43-dsi sudo[1138]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:21 volumio-43-dsi volumio-remote-updater[752]: [2025-02-28 17:16:21] [info] asio async_connect error: system:111 (Connection refused) Feb 28 17:16:21 volumio-43-dsi volumio-remote-updater[752]: [2025-02-28 17:16:21] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 28 17:16:21 volumio-43-dsi volumio-remote-updater[752]: [2025-02-28 17:16:21] [error] handle_connect error: Underlying Transport Error Feb 28 17:16:22 volumio-43-dsi dhcpcd[1085]: wlan0: leased 192.168.5.131 for 86400 seconds Feb 28 17:16:22 volumio-43-dsi avahi-daemon[724]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.5.131. Feb 28 17:16:22 volumio-43-dsi avahi-daemon[724]: New relevant interface wlan0.IPv4 for mDNS. Feb 28 17:16:22 volumio-43-dsi avahi-daemon[724]: Registering new address record for 192.168.5.131 on wlan0.IPv4. Feb 28 17:16:22 volumio-43-dsi dhcpcd[1085]: wlan0: adding route to 192.168.5.0/24 Feb 28 17:16:22 volumio-43-dsi dhcpcd[1085]: wlan0: adding default route via 192.168.5.1 Feb 28 17:16:22 volumio-43-dsi wireless.js[735]: trying... Feb 28 17:16:22 volumio-43-dsi sudo[1161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 17:16:22 volumio-43-dsi sudo[1161]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:22 volumio-43-dsi sudo[1161]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:22 volumio-43-dsi wireless.js[735]: Connected to: ----S.K.1 Feb 28 17:16:22 volumio-43-dsi wireless.js[735]: ---- Feb 28 17:16:22 volumio-43-dsi sudo[1164]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 28 17:16:22 volumio-43-dsi sudo[1164]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:22 volumio-43-dsi sudo[1164]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:22 volumio-43-dsi wireless.js[735]: ... joined AP, wlan0 IPv4 is 192.168.5.131, ipV6 is undefined Feb 28 17:16:22 volumio-43-dsi wireless.js[735]: It's done! AP Feb 28 17:16:22 volumio-43-dsi systemd[1]: Started Wireless Services. Feb 28 17:16:22 volumio-43-dsi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 28 17:16:22 volumio-43-dsi systemd[1]: Started Volumio Backend Module. Feb 28 17:16:22 volumio-43-dsi systemd[1]: Started Volumio Cpu Tweaker. Feb 28 17:16:22 volumio-43-dsi volumio-cpu-tweak[1170]: Setting RT Priority for mpd Feb 28 17:16:22 volumio-43-dsi volumio-cpu-tweak[1170]: Setting MPD Affinity Feb 28 17:16:22 volumio-43-dsi volumio-cpu-tweak[1170]: pid 999's current affinity mask: f Feb 28 17:16:22 volumio-43-dsi volumio-cpu-tweak[1170]: pid 999's new affinity mask: 3 Feb 28 17:16:22 volumio-43-dsi volumio-cpu-tweak[1170]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Feb 28 17:16:22 volumio-43-dsi volumio-cpu-tweak[1170]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Feb 28 17:16:22 volumio-43-dsi systemd[1]: volumio_cpu_tweak.service: Succeeded. Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: ------------------------------------------- Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: ----- Volumio3 ---- Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: ------------------------------------------- Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: ----- System startup ---- Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: ------------------------------------------- Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: MYVOLUMIO Environment detected Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Plugin folders cleanup Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Scanning into folder /volumio/app/plugins/ Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Scanning category audio_interface Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Scanning category miscellanea Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Scanning category music_service Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Scanning category plugins.json Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Scanning category system_controller Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Scanning category user_interface Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Scanning into folder /data/plugins/ Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Plugin folders cleanup completed Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: ------------------------------------------- Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: ----- Core plugins startup ---- Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: ------------------------------------------- Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Loading plugins from folder /volumio/app/plugins/ Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Adding plugin upnp to MyMusic Plugins Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Loading plugins from folder /data/plugins/ Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Loading plugin "system"... Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Loading plugin "appearance"... Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Loading plugin "network"... Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Refreshing Cached IP Addresses Feb 28 17:16:23 volumio-43-dsi sudo[1200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 28 17:16:23 volumio-43-dsi sudo[1200]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:23 volumio-43-dsi sudo[1200]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:23 volumio-43-dsi sudo[1202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 28 17:16:23 volumio-43-dsi sudo[1202]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:23 volumio-43-dsi sudo[1202]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Loading plugin "services"... Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Loading plugin "alsa_controller"... Feb 28 17:16:23 volumio-43-dsi sudo[1215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 28 17:16:23 volumio-43-dsi sudo[1215]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Loading plugin "wizard"... Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Loading plugin "networkfs"... Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Starting Udev Watcher for removable devices Feb 28 17:16:23 volumio-43-dsi sudo[1226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //127.0.0.1/Internal Storage/musics /mnt/NAS/local Feb 28 17:16:23 volumio-43-dsi sudo[1226]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Ignoring mount for partition: boot Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Ignoring mount for partition: volumio Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Ignoring mount for partition: volumio_data Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Loading plugin "volumio_command_line_client"... Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Loading plugin "upnp"... Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: [1740734183797] Starting Upmpd Daemon Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Loading plugin "my_music"... Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Loading plugin "mpd"... Feb 28 17:16:23 volumio-43-dsi kernel: Key type cifs.spnego registered Feb 28 17:16:23 volumio-43-dsi kernel: Key type cifs.idmap registered Feb 28 17:16:23 volumio-43-dsi kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Feb 28 17:16:23 volumio-43-dsi kernel: CIFS: Attempting to mount //127.0.0.1/Internal Storage/musics Feb 28 17:16:23 volumio-43-dsi kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Feb 28 17:16:23 volumio-43-dsi kernel: CIFS: VFS: cifs_mount failed w/return code = -111 Feb 28 17:16:23 volumio-43-dsi sudo[1226]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Loading plugin "upnp_browser"... Feb 28 17:16:23 volumio-43-dsi ntpd[993]: Listen normally on 3 wlan0 192.168.5.131:123 Feb 28 17:16:23 volumio-43-dsi ntpd[993]: new interface(s) found: waking up resolver Feb 28 17:16:23 volumio-43-dsi volumio[1169]: info: Loading plugin "alarm-clock"... Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Loading plugin "airplay_emulation"... Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Starting Shairport Sync Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Loading plugin "last_100"... Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Loading plugin "webradio"... Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Loading plugin "i2s_dacs"... Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Loading plugin "volumiodiscovery"... Feb 28 17:16:24 volumio-43-dsi volumio[1169]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 28 17:16:24 volumio-43-dsi node[1169]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 28 17:16:24 volumio-43-dsi volumio[1169]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 28 17:16:24 volumio-43-dsi node[1169]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 28 17:16:24 volumio-43-dsi volumio[1169]: *** WARNING *** For more information see Feb 28 17:16:24 volumio-43-dsi node[1169]: *** WARNING *** For more information see Feb 28 17:16:24 volumio-43-dsi volumio[1169]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 28 17:16:24 volumio-43-dsi node[1169]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 28 17:16:24 volumio-43-dsi volumio[1169]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 28 17:16:24 volumio-43-dsi node[1169]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 28 17:16:24 volumio-43-dsi volumio[1169]: *** WARNING *** For more information see Feb 28 17:16:24 volumio-43-dsi node[1169]: *** WARNING *** For more information see Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Discovery: Started advertising with name: Volumio-43-dsi Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Loading plugin "outputs"... Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Loading plugin "albumart"... Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Plugin example_plugin is not enabled Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Loading plugin "inputs"... Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Loading plugin "updater_comm"... Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Plugin mpdemulation is not enabled Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Loading plugin "rest_api"... Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Loading plugin "websocket"... Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Starting Socket.io Server version 2.3.0 Feb 28 17:16:24 volumio-43-dsi volumio[1169]: Forking 3 albumart workers Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Loading i18n strings for locale zh Feb 28 17:16:24 volumio-43-dsi volumio[1169]: Updating browse sources language Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::initPlayerControls Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: Express server listening on port 3000 Feb 28 17:16:24 volumio-43-dsi volumio[1169]: [Metrics] WebUI: 1s 485.60ms Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreStateMachine::resetVolumioState Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreStateMachine::getcurrentVolume Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioRetrievevolume Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Volumio Network Manager: Network status updated: 2 Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Cannot mount NAS local at system boot, trial number 1 ,retrying in 5 seconds Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Reloading queue from file Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreStateMachine::setRepeat null single undefined Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreStateMachine::setRandom null Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d04170 Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Setting Device type: Raspberry PI Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Completed loading Core Plugins Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Preparing to generate the ALSA configuration file Feb 28 17:16:24 volumio-43-dsi volumio[1169]: Starting albumart workers Feb 28 17:16:24 volumio-43-dsi volumio[1169]: Starting albumart workers Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Asound.conf file unchanged, so no further update is needed Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Output device has changed, restarting MPD Feb 28 17:16:24 volumio-43-dsi volumio[1169]: Starting albumart workers Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Output device has changed, restarting Shairport Sync Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:16:24 volumio-43-dsi sudo[1281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 28 17:16:24 volumio-43-dsi sudo[1281]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:24 volumio-43-dsi sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 28 17:16:24 volumio-43-dsi sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:24 volumio-43-dsi sudo[1281]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: ___________ START PLUGINS ___________ Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: ControllerMpd::onStart: Initializing MPD Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Creating MPD Configuration file Feb 28 17:16:24 volumio-43-dsi systemd[1]: Stopping Music Player Daemon... Feb 28 17:16:24 volumio-43-dsi sudo[1290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 28 17:16:24 volumio-43-dsi sudo[1290]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: [1740734184464] CoreMusicLibrary::Adding element 媒体服务器 Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 17:16:24 volumio-43-dsi sudo[1290]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:16:24 volumio-43-dsi sudo[1292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 28 17:16:24 volumio-43-dsi sudo[1292]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:24 volumio-43-dsi systemd[1]: mpd.service: Succeeded. Feb 28 17:16:24 volumio-43-dsi systemd[1]: Stopped Music Player Daemon. Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:16:24 volumio-43-dsi systemd[1]: Starting Music Player Daemon... Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: [1740734184533] CoreMusicLibrary::Adding element Last_100 Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: [1740734184534] CoreMusicLibrary::Adding element Webradio Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Initializing BBC Radios Feb 28 17:16:24 volumio-43-dsi sudo[1298]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 28 17:16:24 volumio-43-dsi sudo[1298]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:24 volumio-43-dsi sudo[1298]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Volumio Calling Home Feb 28 17:16:24 volumio-43-dsi volumio-time-update[748]: volumio-time-update-util: Fetching time from Volumio... Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: MPD Permissions set Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: MPD Permissions set Feb 28 17:16:24 volumio-43-dsi volumio[1169]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Starting Shairport Sync Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Starting Shairport Sync Feb 28 17:16:24 volumio-43-dsi sudo[1354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Starting Shairport Sync Feb 28 17:16:24 volumio-43-dsi sudo[1354]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:24 volumio-43-dsi sudo[1356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 28 17:16:24 volumio-43-dsi sudo[1356]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:24 volumio-43-dsi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 28 17:16:24 volumio-43-dsi systemd[1]: shairport-sync.service: Succeeded. Feb 28 17:16:24 volumio-43-dsi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 28 17:16:24 volumio-43-dsi sudo[1360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 28 17:16:24 volumio-43-dsi sudo[1360]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioGetState Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:24 volumio-43-dsi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 28 17:16:24 volumio-43-dsi sudo[1354]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:24 volumio-43-dsi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 28 17:16:24 volumio-43-dsi systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 28 17:16:24 volumio-43-dsi systemd[1]: shairport-sync.service: Succeeded. Feb 28 17:16:24 volumio-43-dsi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Shairport-Sync Started Feb 28 17:16:24 volumio-43-dsi volumio[1169]: Error adding Membership: Error: addMembership EINVAL Feb 28 17:16:24 volumio-43-dsi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 28 17:16:24 volumio-43-dsi sudo[1356]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:24 volumio-43-dsi sudo[1360]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Shairport-Sync Started Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Shairport-Sync Started Feb 28 17:16:24 volumio-43-dsi mpd[1310]: Feb 28 17:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 28 17:16:24 volumio-43-dsi systemd[1]: Started Music Player Daemon. Feb 28 17:16:24 volumio-43-dsi sudo[1283]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:24 volumio-43-dsi sudo[1292]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: Completed starting Core Plugins Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: ------------------------------------------- Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: ----- MyVolumio plugins startup ---- Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: ------------------------------------------- Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 28 17:16:24 volumio-43-dsi volumio[1169]: info: MPD running with PID1310 Feb 28 17:16:24 volumio-43-dsi volumio[1169]: ,establishing connection Feb 28 17:16:24 volumio-43-dsi volumio[1169]: error: MPD error: The expression evaluated to a falsy value: Feb 28 17:16:24 volumio-43-dsi volumio[1169]: assert.ok(self.idling) Feb 28 17:16:24 volumio-43-dsi volumio[1169]: error: The expression evaluated to a falsy value: Feb 28 17:16:24 volumio-43-dsi volumio[1169]: assert.ok(self.idling) Feb 28 17:16:24 volumio-43-dsi volumio[1169]: error: MPD error: The expression evaluated to a falsy value: Feb 28 17:16:24 volumio-43-dsi volumio[1169]: assert.ok(self.idling) Feb 28 17:16:24 volumio-43-dsi volumio[1169]: error: The expression evaluated to a falsy value: Feb 28 17:16:24 volumio-43-dsi volumio[1169]: assert.ok(self.idling) Feb 28 17:16:24 volumio-43-dsi volumio[1169]: error: updateQueue error: null Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: Discovery: adding 14565d33-5d59-46a6-adec-47c1379cbd05 Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: Discovery: Found device Volumio-43-dsi Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioGetState Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:25 volumio-43-dsi sudo[1371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 28 17:16:25 volumio-43-dsi sudo[1371]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:25 volumio-43-dsi sudo[1371]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:25 volumio-43-dsi sudo[1373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 28 17:16:25 volumio-43-dsi sudo[1373]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:25 volumio-43-dsi sudo[1373]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:25 volumio-43-dsi sudo[1215]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:25 volumio-43-dsi volumio[1169]: verbose: New Socket.io Connection to 192.168.5.131 from 192.168.5.102 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/130.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Feb 28 17:16:25 volumio-43-dsi nmbd[906]: [2025/02/28 17:16:25.278280, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 28 17:16:25 volumio-43-dsi nmbd[906]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Feb 28 17:16:25 volumio-43-dsi systemd[1]: Started Samba NMB Daemon. Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: Volumio called home Feb 28 17:16:25 volumio-43-dsi systemd[1]: Starting Samba Winbind Daemon... Feb 28 17:16:25 volumio-43-dsi volumio-time-update[748]: volumio-time-update-util: Setting system time to: 2025-02-28 17:16:25 Feb 28 17:16:25 volumio-43-dsi sudo[1379]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-02-28 17:16:25 Feb 28 17:16:25 volumio-43-dsi sudo[1379]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:25 volumio-43-dsi dbus-daemon[742]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.13' (uid=0 pid=1380 comm="timedatectl set-time 2025-02-28 17:16:25 ") Feb 28 17:16:25 volumio-43-dsi systemd[1]: Starting Time & Date Service... Feb 28 17:16:25 volumio-43-dsi dbus-daemon[742]: [system] Successfully activated service 'org.freedesktop.timedate1' Feb 28 17:16:25 volumio-43-dsi systemd[1]: Started Time & Date Service. Feb 28 17:16:25 volumio-43-dsi winbindd[1376]: [2025/02/28 17:16:25.003172, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Feb 28 17:16:25 volumio-43-dsi winbindd[1376]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 28 17:16:25 volumio-43-dsi systemd-timedated[1381]: Changed local time to Fri Feb 28 17:16:25 2025 Feb 28 17:16:25 volumio-43-dsi sudo[1379]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:25 volumio-43-dsi volumio-time-update[748]: volumio-time-update-util: System time updated successfully. Feb 28 17:16:25 volumio-43-dsi systemd[1]: Started Volumio Time Update Utility. Feb 28 17:16:25 volumio-43-dsi winbindd[1376]: [2025/02/28 17:16:25.010740, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 28 17:16:25 volumio-43-dsi winbindd[1376]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 28 17:16:25 volumio-43-dsi systemd[1]: Started Samba Winbind Daemon. Feb 28 17:16:25 volumio-43-dsi systemd[1]: Starting Samba SMB Daemon... Feb 28 17:16:25 volumio-43-dsi winbindd[1376]: [2025/02/28 17:16:25.133081, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Feb 28 17:16:25 volumio-43-dsi winbindd[1376]: res_names->count = 3, expected 4 Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 28 17:16:25 volumio-43-dsi smbd[1386]: [2025/02/28 17:16:25.151964, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 28 17:16:25 volumio-43-dsi systemd[1]: Started Samba SMB Daemon. Feb 28 17:16:25 volumio-43-dsi smbd[1386]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 28 17:16:25 volumio-43-dsi systemd[1]: Reached target Multi-User System. Feb 28 17:16:25 volumio-43-dsi systemd[1]: Reached target Graphical Interface. Feb 28 17:16:25 volumio-43-dsi systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 28 17:16:25 volumio-43-dsi systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 28 17:16:25 volumio-43-dsi systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 28 17:16:25 volumio-43-dsi systemd[1]: Startup finished in 10.660s (kernel) + 17.663s (userspace) = 28.324s. Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioGetVisibleSources Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioGetState Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: Received Get System Info Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: Discovery: Getting this device information Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioGetState Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioGetState Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: Listing playlists Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: Getting Alsa Cards List without I2S DAC Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 28 17:16:25 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 28 17:16:26 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 28 17:16:26 volumio-43-dsi volumio[1169]: info: Received Get System Info Feb 28 17:16:26 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 28 17:16:26 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 28 17:16:26 volumio-43-dsi volumio[1169]: info: Discovery: Getting this device information Feb 28 17:16:26 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioGetState Feb 28 17:16:26 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:26 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 28 17:16:27 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 28 17:16:28 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 28 17:16:28 volumio-43-dsi volumio[1169]: info: Received Get System Info Feb 28 17:16:28 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 28 17:16:28 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 28 17:16:28 volumio-43-dsi volumio[1169]: info: Discovery: Getting this device information Feb 28 17:16:28 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioGetState Feb 28 17:16:28 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:28 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 28 17:16:29 volumio-43-dsi sudo[1405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //127.0.0.1/Internal Storage/musics /mnt/NAS/local Feb 28 17:16:29 volumio-43-dsi sudo[1405]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:29 volumio-43-dsi kernel: CIFS: Attempting to mount //127.0.0.1/Internal Storage/musics Feb 28 17:16:29 volumio-43-dsi sudo[1405]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:29 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Feb 28 17:16:29 volumio-43-dsi volumio[1169]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 28 17:16:31 volumio-43-dsi volumio-remote-updater[752]: [2025-02-28 17:16:31] [connect] Successful connection Feb 28 17:16:31 volumio-43-dsi volumio-remote-updater[752]: [2025-02-28 17:16:31] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1740734191 101 Feb 28 17:16:31 volumio-43-dsi volumio[1169]: 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: 3 Feb 28 17:16:32 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioGetState Feb 28 17:16:32 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:32 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioGetQueue Feb 28 17:16:32 volumio-43-dsi volumio[1169]: info: CoreStateMachine::getQueue Feb 28 17:16:32 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getQueue Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: Adding plugin bluetooth to MyMusic Plugins Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: Adding plugin multiroom to MyMusic Plugins Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: Adding plugin metavolumio to MyMusic Plugins Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: Adding plugin cd_controller to MyMusic Plugins Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: Starting MyVolumio Remote Streaming Endpoints Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: MyVolumio login type: Token Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: Starting Streaming Service Transparent Proxy Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: Streaming services startup Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: Starting Streaming Daemon Feb 28 17:16:33 volumio-43-dsi sudo[1448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 28 17:16:33 volumio-43-dsi sudo[1448]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:33 volumio-43-dsi volumio[1169]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 28 17:16:33 volumio-43-dsi sudo[1448]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:33 volumio-43-dsi sudo[1456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 28 17:16:33 volumio-43-dsi sudo[1456]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:33 volumio-43-dsi sudo[1456]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:33 volumio-43-dsi sudo[1459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 28 17:16:33 volumio-43-dsi volumio[1169]: error: Cannot start Volumio Streaming Daemon Feb 28 17:16:33 volumio-43-dsi sudo[1459]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:33 volumio-43-dsi volumio[1169]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 28 17:16:33 volumio-43-dsi volumio[1169]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 28 17:16:33 volumio-43-dsi sudo[1459]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:34 volumio-43-dsi sudo[1462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 28 17:16:34 volumio-43-dsi sudo[1462]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:34 volumio-43-dsi systemd[1]: Started UPnP Renderer front-end to MPD. Feb 28 17:16:34 volumio-43-dsi sudo[1462]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Upmpdcli Daemon Started Feb 28 17:16:34 volumio-43-dsi volumio[1464]: Generating RSA private key, 4096 bit long modulus (2 primes) Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Preload queue cleared Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::ClearQueue Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::stop Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CorePlayQueue::clearPlayQueue Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CorePlayQueue::saveQueue Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushQueue Feb 28 17:16:34 volumio-43-dsi volumio[1169]: STREAMING PROXY: Starting server on port 3245 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: Node JS runtime: 14 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::addQueueItems Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CorePlayQueue::addQueueItems Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Preload queue cleared Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Adding Item to queue: music-library/NAS/local/20-BEYOND专辑 42首 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Exploding uri music-library/NAS/local/20-BEYOND专辑 42首 in service mpd Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ALBUMART /albumart?cacheid=582&web=BEYOND/%E4%B9%90%E4%B8%8E%E6%80%92/extralarge&path=%2Fmnt%2FNAS%2Flocal%2F20-BEYOND%E4%B8%93%E8%BE%91%2042%E9%A6%96%2FBEYOND%20-%20%E6%88%91%E6%98%AF%E6%84%A4%E6%80%92.mp3&metadata=false Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: URI /mnt/NAS/local/20-BEYOND专辑 42首/BEYOND - 我是愤怒.mp3 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ALBUMART /albumart?cacheid=582&web=BEYOND/%E7%BB%A7%E7%BB%AD%E9%9D%A9%E5%91%BD/extralarge&path=%2Fmnt%2FNAS%2Flocal%2F20-BEYOND%E4%B8%93%E8%BE%91%2042%E9%A6%96%2FBEYOND%20-%20%E6%97%A9%E7%8F%AD%E7%81%AB%E8%BD%A6.mp3&metadata=false Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: URI /mnt/NAS/local/20-BEYOND专辑 42首/BEYOND - 早班火车.mp3 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ALBUMART /albumart?cacheid=582&web=BEYOND/Recognition/extralarge&path=%2Fmnt%2FNAS%2Flocal%2F20-BEYOND%E4%B8%93%E8%BE%91%2042%E9%A6%96%2FBEYOND%20-%20%E6%98%AF%E9%94%99%E4%B9%9F%E5%86%8D%E4%B8%8D%E5%88%86.mp3&metadata=false Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: URI /mnt/NAS/local/20-BEYOND专辑 42首/BEYOND - 是错也再不分.mp3 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushQueue Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CorePlayQueue::saveQueue Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::updateTrackBlock Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrackBlock Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPlay Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::play index 0 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::stop Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::play index undefined Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::startPlaybackTimer Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::clearAddPlayTracks NAS/local/20-BEYOND专辑 42首/BEYOND - 我是愤怒.mp3 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand stop Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: sendMpdCommand stop took 1 milliseconds Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand clear Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: sendMpdCommand clear took 0 milliseconds Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand add "NAS/local/20-BEYOND专辑 42首/BEYOND - 我是愤怒.mp3" Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Feb 28 17:16:34 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces system playlist update Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Ignoring MPD Status Update Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Feb 28 17:16:34 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces system playlist update Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Ignoring MPD Status Update Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: sendMpdCommand add "NAS/local/20-BEYOND专辑 42首/BEYOND - 我是愤怒.mp3" took 0 milliseconds Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand play Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Feb 28 17:16:34 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces system playlist update Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Ignoring MPD Status Update Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Feb 28 17:16:34 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces system playlist update Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Ignoring MPD Status Update Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ------------------------------ 2ms Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ------------------------------ 2ms Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: sendMpdCommand play took 1 milliseconds Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ------------------------------ 1ms Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ------------------------------ 1ms Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Feb 28 17:16:34 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces system playlist update Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Ignoring MPD Status Update Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Feb 28 17:16:34 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces system playlist update Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Ignoring MPD Status Update Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ------------------------------ 1ms Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ------------------------------ 1ms Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Feb 28 17:16:34 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces state update: player Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ControllerMpd::getState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Feb 28 17:16:34 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces state update: player Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ControllerMpd::getState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Feb 28 17:16:34 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces state update: player Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ControllerMpd::getState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Feb 28 17:16:34 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces state update: player Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: sendMpdCommand status took 4 milliseconds Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ControllerMpd::getState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: sendMpdCommand status took 4 milliseconds Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: sendMpdCommand status took 5 milliseconds Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: sendMpdCommand status took 2 milliseconds Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseTrackInfo Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ControllerMpd::pushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::servicePushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"我是愤怒","artist":"BEYOND","album":"乐与怒","uri":"NAS/local/20-BEYOND专辑 42首/BEYOND - 我是愤怒.mp3","trackType":"mp3"} Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: CURRENT POSITION 0 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState stateService play Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState currentStatus stop Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ------------------------------ 9ms Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Feb 28 17:16:34 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces state update: player Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ControllerMpd::getState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Feb 28 17:16:34 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces state update: player Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ControllerMpd::getState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: sendMpdCommand status took 1 milliseconds Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseTrackInfo Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ControllerMpd::pushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::servicePushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"我是愤怒","artist":"BEYOND","album":"乐与怒","uri":"NAS/local/20-BEYOND专辑 42首/BEYOND - 我是愤怒.mp3","trackType":"mp3"} Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: CURRENT POSITION 0 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState stateService play Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState currentStatus play Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Received an update from plugin. extracting info from payload Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ControllerMpd::pushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::servicePushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"我是愤怒","artist":"BEYOND","album":"乐与怒","uri":"NAS/local/20-BEYOND专辑 42首/BEYOND - 我是愤怒.mp3","trackType":"mp3"} Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: CURRENT POSITION 0 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState stateService play Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState currentStatus play Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Received an update from plugin. extracting info from payload Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ControllerMpd::pushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::servicePushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: STATE SERVICE {"status":"play","position":0,"seek":377,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"我是愤怒","artist":"BEYOND","album":"乐与怒","uri":"NAS/local/20-BEYOND专辑 42首/BEYOND - 我是愤怒.mp3","trackType":"mp3"} Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: CURRENT POSITION 0 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState stateService play Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState currentStatus play Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Received an update from plugin. extracting info from payload Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ------------------------------ 20ms Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ------------------------------ 21ms Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ------------------------------ 19ms Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseTrackInfo Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ControllerMpd::pushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::servicePushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"我是愤怒","artist":"BEYOND","album":"乐与怒","uri":"NAS/local/20-BEYOND专辑 42首/BEYOND - 我是愤怒.mp3","trackType":"mp3"} Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: CURRENT POSITION 0 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState stateService play Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState currentStatus play Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Received an update from plugin. extracting info from payload Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ControllerMpd::pushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::servicePushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"我是愤怒","artist":"BEYOND","album":"乐与怒","uri":"NAS/local/20-BEYOND专辑 42首/BEYOND - 我是愤怒.mp3","trackType":"mp3"} Feb 28 17:16:34 volumio-43-dsi volumio[1169]: verbose: CURRENT POSITION 0 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState stateService play Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState currentStatus play Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: Received an update from plugin. extracting info from payload Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ------------------------------ 21ms Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: ------------------------------ 22ms Feb 28 17:16:34 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 28 17:16:35 volumio-43-dsi volumio[1169]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 28 17:16:36 volumio-43-dsi volumio[1169]: info: MyVolumio token set successfully Feb 28 17:16:36 volumio-43-dsi volumio[1169]: info: MYVOLUMIO: Adding device Feb 28 17:16:36 volumio-43-dsi volumio[1169]: info: MYVOLUMIO: Evaluating Server Feb 28 17:16:36 volumio-43-dsi volumio[1169]: info: MyVolumio status changed Feb 28 17:16:36 volumio-43-dsi volumio[1169]: info: Streaming services startup Feb 28 17:16:36 volumio-43-dsi volumio[1169]: info: Starting Streaming Daemon Feb 28 17:16:36 volumio-43-dsi volumio[1169]: info: Removing browser output: myVolumio user plan is not superstar Feb 28 17:16:36 volumio-43-dsi volumio[1169]: info: Removing audio output: Feb 28 17:16:36 volumio-43-dsi volumio[1169]: info: Stoppping Tunnel 1 Feb 28 17:16:36 volumio-43-dsi sudo[1513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 28 17:16:36 volumio-43-dsi sudo[1513]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:36 volumio-43-dsi sudo[1515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 28 17:16:36 volumio-43-dsi sudo[1515]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:16:36 volumio-43-dsi sudo[1513]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:36 volumio-43-dsi sudo[1515]: pam_unix(sudo:session): session closed for user root Feb 28 17:16:36 volumio-43-dsi volumio[1169]: info: Remote SSH Stopped Feb 28 17:16:36 volumio-43-dsi volumio[1169]: error: Cannot start Volumio Streaming Daemon Feb 28 17:16:36 volumio-43-dsi volumio[1169]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 28 17:16:36 volumio-43-dsi volumio[1169]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 28 17:16:38 volumio-43-dsi systemd[1]: systemd-fsckd.service: Succeeded. Feb 28 17:16:39 volumio-43-dsi sh[662]: timed out Feb 28 17:16:39 volumio-43-dsi dhcpcd[711]: timed out Feb 28 17:16:39 volumio-43-dsi sh[662]: dhcpcd exited Feb 28 17:16:39 volumio-43-dsi dhcpcd[711]: dhcpcd exited Feb 28 17:16:39 volumio-43-dsi sh[662]: ifup: failed to bring up eth0 Feb 28 17:16:39 volumio-43-dsi systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Feb 28 17:16:39 volumio-43-dsi systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Feb 28 17:16:39 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 28 17:16:39 volumio-43-dsi volumio[1169]: info: CURURI: music-library/NAS/local/20-BEYOND专辑 42首 Feb 28 17:16:39 volumio-43-dsi volumio[1169]: info: Preload queue cleared Feb 28 17:16:39 volumio-43-dsi volumio[1169]: info: Preloading song: music-library/NAS/local/20-BEYOND专辑 42首/BEYOND - 是错也再不分.mp3 Feb 28 17:16:39 volumio-43-dsi volumio[1169]: info: Preloading song: music-library/NAS/local/20-BEYOND专辑 42首/BEYOND - 我是愤怒.mp3 Feb 28 17:16:39 volumio-43-dsi volumio[1169]: info: Preloading song: music-library/NAS/local/20-BEYOND专辑 42首/BEYOND - 早班火车.mp3 Feb 28 17:16:39 volumio-43-dsi volumio[1169]: info: Exploding uri music-library/NAS/local/20-BEYOND专辑 42首/BEYOND - 是错也再不分.mp3 in service mpd Feb 28 17:16:39 volumio-43-dsi volumio[1169]: info: ALBUMART /albumart?cacheid=582&web=BEYOND/Recognition/extralarge&path=%2Fmnt%2FNAS%2Flocal%2F20-BEYOND%E4%B8%93%E8%BE%91%2042%E9%A6%96%2FBEYOND%20-%20%E6%98%AF%E9%94%99%E4%B9%9F%E5%86%8D%E4%B8%8D%E5%88%86.mp3&metadata=false Feb 28 17:16:39 volumio-43-dsi volumio[1169]: info: URI /mnt/NAS/local/20-BEYOND专辑 42首/BEYOND - 是错也再不分.mp3 Feb 28 17:16:39 volumio-43-dsi volumio[1169]: info: Exploding uri music-library/NAS/local/20-BEYOND专辑 42首/BEYOND - 我是愤怒.mp3 in service mpd Feb 28 17:16:39 volumio-43-dsi volumio[1169]: info: ALBUMART /albumart?cacheid=582&web=BEYOND/%E4%B9%90%E4%B8%8E%E6%80%92/extralarge&path=%2Fmnt%2FNAS%2Flocal%2F20-BEYOND%E4%B8%93%E8%BE%91%2042%E9%A6%96%2FBEYOND%20-%20%E6%88%91%E6%98%AF%E6%84%A4%E6%80%92.mp3&metadata=false Feb 28 17:16:39 volumio-43-dsi volumio[1169]: info: URI /mnt/NAS/local/20-BEYOND专辑 42首/BEYOND - 我是愤怒.mp3 Feb 28 17:16:39 volumio-43-dsi volumio[1169]: info: Exploding uri music-library/NAS/local/20-BEYOND专辑 42首/BEYOND - 早班火车.mp3 in service mpd Feb 28 17:16:39 volumio-43-dsi volumio[1169]: info: ALBUMART /albumart?cacheid=582&web=BEYOND/%E7%BB%A7%E7%BB%AD%E9%9D%A9%E5%91%BD/extralarge&path=%2Fmnt%2FNAS%2Flocal%2F20-BEYOND%E4%B8%93%E8%BE%91%2042%E9%A6%96%2FBEYOND%20-%20%E6%97%A9%E7%8F%AD%E7%81%AB%E8%BD%A6.mp3&metadata=false Feb 28 17:16:39 volumio-43-dsi volumio[1169]: info: URI /mnt/NAS/local/20-BEYOND专辑 42首/BEYOND - 早班火车.mp3 Feb 28 17:16:39 volumio-43-dsi systemd[1]: systemd-hostnamed.service: Succeeded. Feb 28 17:16:41 volumio-43-dsi volumio[1464]: ..................................................................................................................................................++++ Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: Preload queue cleared Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::ClearQueue Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::stop Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::stPlaybackTimer Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::updateTrackBlock Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrackBlock Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::serviceStop Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::serviceStop Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: ControllerMpd::stop Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand stop Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::clearPlayQueue Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::saveQueue Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushQueue Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::addQueueItems Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::addQueueItems Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: Preload queue cleared Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: Adding Item to queue: music-library/NAS/local/20-BEYOND专辑 42首/BEYOND - 是错也再不分.mp3 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: Using cached record of: music-library/NAS/local/20-BEYOND专辑 42首/BEYOND - 是错也再不分.mp3 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: Adding Item to queue: music-library/NAS/local/20-BEYOND专辑 42首/BEYOND - 我是愤怒.mp3 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: Using cached record of: music-library/NAS/local/20-BEYOND专辑 42首/BEYOND - 我是愤怒.mp3 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: Adding Item to queue: music-library/NAS/local/20-BEYOND专辑 42首/BEYOND - 早班火车.mp3 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: Using cached record of: music-library/NAS/local/20-BEYOND专辑 42首/BEYOND - 早班火车.mp3 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushQueue Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::saveQueue Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::updateTrackBlock Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrackBlock Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPlay Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::play index 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::stop Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::play index undefined Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::startPlaybackTimer Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::clearAddPlayTracks NAS/local/20-BEYOND专辑 42首/BEYOND - 早班火车.mp3 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand stop Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: Feb 28 17:16:41 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces state update: player Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: sendMpdCommand stop took 5 milliseconds Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: ControllerMpd::getState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: sendMpdCommand stop took 3 milliseconds Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand clear Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: Feb 28 17:16:41 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces system playlist update Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: Ignoring MPD Status Update Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: sendMpdCommand status took 0 milliseconds Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: sendMpdCommand clear took 0 milliseconds Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand add "NAS/local/20-BEYOND专辑 42首/BEYOND - 早班火车.mp3" Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: Feb 28 17:16:41 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces state update: player Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: ControllerMpd::getState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: Feb 28 17:16:41 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces state update: player Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: ControllerMpd::getState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Feb 28 17:16:41 volumio-43-dsi volumio[1169]: error: updateQueue error: null Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: Feb 28 17:16:41 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces system playlist update Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: Ignoring MPD Status Update Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: ------------------------------ 1ms Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: sendMpdCommand add "NAS/local/20-BEYOND专辑 42首/BEYOND - 早班火车.mp3" took 2 milliseconds Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: sendMpdCommand status took 1 milliseconds Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: sendMpdCommand status took 1 milliseconds Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseTrackInfo Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand play Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: ControllerMpd::pushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::servicePushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: CURRENT POSITION 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState stateService stop Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState currentStatus stop Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: No code Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: ControllerMpd::pushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::servicePushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: CURRENT POSITION 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState stateService stop Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState currentStatus stop Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: No code Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: ------------------------------ 8ms Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: ------------------------------ 20ms Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: ------------------------------ 21ms Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: Feb 28 17:16:41 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces system playlist update Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: Ignoring MPD Status Update Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: Feb 28 17:16:41 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces system playlist update Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: Ignoring MPD Status Update Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: Feb 28 17:16:41 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces state update: player Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: Feb 28 17:16:41 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces state update: player Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: ------------------------------ 22ms Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: sendMpdCommand play took 21 milliseconds Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: ControllerMpd::getState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: ------------------------------ 1ms Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: ControllerMpd::getState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: ------------------------------ 1ms Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: Feb 28 17:16:41 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces state update: player Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: ControllerMpd::getState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: Feb 28 17:16:41 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces state update: player Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: ControllerMpd::getState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: sendMpdCommand status took 2 milliseconds Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: sendMpdCommand status took 1 milliseconds Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseTrackInfo Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseTrackInfo Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseTrackInfo Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseTrackInfo Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: ControllerMpd::pushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::servicePushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":348,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"早班火车","artist":"BEYOND","album":"继续革命","uri":"NAS/local/20-BEYOND专辑 42首/BEYOND - 早班火车.mp3","trackType":"mp3"} Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: CURRENT POSITION 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState stateService play Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState currentStatus stop Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: ControllerMpd::pushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::servicePushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":348,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"早班火车","artist":"BEYOND","album":"继续革命","uri":"NAS/local/20-BEYOND专辑 42首/BEYOND - 早班火车.mp3","trackType":"mp3"} Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: CURRENT POSITION 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState stateService play Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState currentStatus play Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: Received an update from plugin. extracting info from payload Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: ControllerMpd::pushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::servicePushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":348,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"早班火车","artist":"BEYOND","album":"继续革命","uri":"NAS/local/20-BEYOND专辑 42首/BEYOND - 早班火车.mp3","trackType":"mp3"} Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: CURRENT POSITION 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState stateService play Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState currentStatus play Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: Received an update from plugin. extracting info from payload Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: ControllerMpd::pushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::servicePushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":348,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"早班火车","artist":"BEYOND","album":"继续革命","uri":"NAS/local/20-BEYOND专辑 42首/BEYOND - 早班火车.mp3","trackType":"mp3"} Feb 28 17:16:41 volumio-43-dsi volumio[1169]: verbose: CURRENT POSITION 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState stateService play Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState currentStatus play Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: Received an update from plugin. extracting info from payload Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: ------------------------------ 15ms Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: ------------------------------ 21ms Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: ------------------------------ 21ms Feb 28 17:16:41 volumio-43-dsi volumio[1169]: info: ------------------------------ 21ms Feb 28 17:16:42 volumio-43-dsi volumio[1464]: ......................++++ Feb 28 17:16:42 volumio-43-dsi volumio[1464]: e is 65537 (0x010001) Feb 28 17:16:42 volumio-43-dsi volumio[1464]: writing RSA key Feb 28 17:16:42 volumio-43-dsi volumio[1169]: info: Setting Geolocation for MyVolumio to as2 Feb 28 17:16:42 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:16:42 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:16:42 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:16:42 volumio-43-dsi kernel: smbd(1411): Attempt to set a LOCK_MAND lock via flock(2). This support has been removed and the request ignored. Feb 28 17:16:43 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 28 17:16:43 volumio-43-dsi volumio[1169]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 28 17:16:43 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 28 17:16:43 volumio-43-dsi volumio[1169]: info: Received Get System Version Feb 28 17:16:43 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 28 17:16:43 volumio-43-dsi volumio[1169]: info: Received Get System Info Feb 28 17:16:43 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 28 17:16:43 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 28 17:16:43 volumio-43-dsi volumio[1169]: info: Discovery: Getting this device information Feb 28 17:16:43 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioGetState Feb 28 17:16:43 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:16:43 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 28 17:16:43 volumio-43-dsi volumio[1169]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 28 17:16:43 volumio-43-dsi volumio[1169]: info: Updating MyVolumio device info Feb 28 17:16:43 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:16:43 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:16:43 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:16:44 volumio-43-dsi volumio[1169]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 28 17:16:47 volumio-43-dsi volumio[1169]: info: MYVOLUMIO: Adding device Feb 28 17:16:47 volumio-43-dsi volumio[1169]: info: MYVOLUMIO: Evaluating Server Feb 28 17:16:48 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 28 17:16:48 volumio-43-dsi volumio[1169]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 28 17:16:48 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioGetState Feb 28 17:16:48 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:16:52 volumio-43-dsi volumio[1169]: info: Setting Geolocation for MyVolumio to as2 Feb 28 17:16:52 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:16:53 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:53 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:53 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 28 17:16:53 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 28 17:16:53 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 28 17:16:53 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 28 17:16:53 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioGetBrowseSources Feb 28 17:16:53 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 28 17:16:53 volumio-43-dsi volumio[1169]: info: Updating MyVolumio device info Feb 28 17:16:53 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:16:53 volumio-43-dsi volumio[1169]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 28 17:16:53 volumio-43-dsi volumio[1169]: info: Completed starting MyVolumio Plugin Feb 28 17:16:53 volumio-43-dsi volumio[1169]: [Metrics] CommandRouter: 31s 273.19ms Feb 28 17:16:53 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumiosetStartupVolume Feb 28 17:16:53 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:16:53 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:16:53 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::Close All Modals sent Feb 28 17:16:53 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::Close All Modals sent Feb 28 17:16:53 volumio-43-dsi volumio[1169]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Feb 28 17:16:53 volumio-43-dsi volumio[1169]: aplay: main:828: audio open error: Device or resource busy Feb 28 17:16:54 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 28 17:16:54 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:16:54 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 28 17:16:54 volumio-43-dsi volumio-remote-updater[752]: No test mode Feb 28 17:16:54 volumio-43-dsi volumio-remote-updater[752]: No alpha test mode Feb 28 17:16:54 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 28 17:16:54 volumio-43-dsi volumio[1169]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 28 17:16:54 volumio-43-dsi volumio[1169]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 28 17:16:54 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 28 17:16:54 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 28 17:16:54 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 28 17:16:55 volumio-43-dsi systemd[1]: systemd-timedated.service: Succeeded. Feb 28 17:16:55 volumio-43-dsi volumio[1169]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 28 17:16:55 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 28 17:16:57 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioGetState Feb 28 17:16:57 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:16:59 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 28 17:16:59 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 28 17:16:59 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:16:59 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 28 17:16:59 volumio-43-dsi volumio[1169]: info: Getting Alsa Cards List without I2S DAC Feb 28 17:16:59 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Feb 28 17:16:59 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:16:59 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 17:16:59 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 17:16:59 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 17:16:59 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 17:16:59 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 17:16:59 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 17:16:59 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 17:16:59 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 28 17:16:59 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 28 17:17:00 volumio-43-dsi volumio[1169]: info: BOOT COMPLETED Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioGetState Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: Restoring Previous Volume level: 100 false true Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: VolumeController::SetAlsaVolume100 Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: Enable softmixer device for audio device number 0 Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioStop Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreStateMachine::stop Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreStateMachine::stPlaybackTimer Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreStateMachine::updateTrackBlock Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrackBlock Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreStateMachine::serviceStop Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::serviceStop Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: ControllerMpd::stop Feb 28 17:17:04 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand stop Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: Enable softmixer device for audio device undefined Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: Updating Volume Controller Parameters: Device: 0 Name: HiFiBerry Digi Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: Disabling external Volume Control Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: Output device has changed, restarting MPD Feb 28 17:17:04 volumio-43-dsi sudo[1680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: Output device has changed, restarting Shairport Sync Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:17:04 volumio-43-dsi sudo[1680]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:17:04 volumio-43-dsi sudo[1680]: pam_unix(sudo:session): session closed for user root Feb 28 17:17:04 volumio-43-dsi sudo[1682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 28 17:17:04 volumio-43-dsi sudo[1682]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:17:04 volumio-43-dsi systemd[1]: Stopping Music Player Daemon... Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: Volume configurations have been set Feb 28 17:17:04 volumio-43-dsi systemd[1]: mpd.service: Succeeded. Feb 28 17:17:04 volumio-43-dsi systemd[1]: Stopped Music Player Daemon. Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: Updating Volume Controller Parameters: Device: 0 Name: HiFiBerry Digi Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: Disabling external Volume Control Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:17:04 volumio-43-dsi systemd[1]: Starting Music Player Daemon... Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:17:04 volumio-43-dsi volumio[1169]: error: MPD error: Error: write EPIPE Feb 28 17:17:04 volumio-43-dsi volumio[1169]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: MPD Permissions set Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: Software Volume ALSA configuration written Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: Preparing to generate the ALSA configuration file Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: Reading ALSA contributions from plugins. Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:17:04 volumio-43-dsi sudo[1689]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:17:04 volumio-43-dsi sudo[1689]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:17:04 volumio-43-dsi sudo[1689]: pam_unix(sudo:session): session closed for user root Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: Getting Alsa Cards List without I2S DAC Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: Starting Shairport Sync Feb 28 17:17:04 volumio-43-dsi sudo[1712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 28 17:17:04 volumio-43-dsi sudo[1712]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: Asound.conf file written Feb 28 17:17:04 volumio-43-dsi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 28 17:17:04 volumio-43-dsi systemd[1]: shairport-sync.service: Succeeded. Feb 28 17:17:04 volumio-43-dsi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 28 17:17:04 volumio-43-dsi sudo[1715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 28 17:17:04 volumio-43-dsi sudo[1715]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:17:04 volumio-43-dsi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: Output device has changed, restarting MPD Feb 28 17:17:04 volumio-43-dsi sudo[1715]: pam_unix(sudo:session): session closed for user root Feb 28 17:17:04 volumio-43-dsi sudo[1721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 28 17:17:04 volumio-43-dsi sudo[1712]: pam_unix(sudo:session): session closed for user root Feb 28 17:17:04 volumio-43-dsi sudo[1721]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:17:04 volumio-43-dsi sudo[1721]: pam_unix(sudo:session): session closed for user root Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: Output device has changed, restarting Shairport Sync Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:17:04 volumio-43-dsi sudo[1724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 28 17:17:04 volumio-43-dsi sudo[1724]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:17:04 volumio-43-dsi volumio[1169]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 28 17:17:04 volumio-43-dsi systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 28 17:17:04 volumio-43-dsi systemd[1]: mpd.service: Succeeded. Feb 28 17:17:04 volumio-43-dsi systemd[1]: Stopped Music Player Daemon. Feb 28 17:17:04 volumio-43-dsi systemd[1]: Starting Music Player Daemon... Feb 28 17:17:04 volumio-43-dsi sudo[1732]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 28 17:17:04 volumio-43-dsi sudo[1732]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:17:04 volumio-43-dsi sudo[1732]: pam_unix(sudo:session): session closed for user root Feb 28 17:17:04 volumio-43-dsi mpd[1735]: Feb 28 17:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 28 17:17:04 volumio-43-dsi systemd[1]: Started Music Player Daemon. Feb 28 17:17:04 volumio-43-dsi sudo[1682]: pam_unix(sudo:session): session closed for user root Feb 28 17:17:04 volumio-43-dsi sudo[1724]: pam_unix(sudo:session): session closed for user root Feb 28 17:17:05 volumio-43-dsi volumio[1169]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: Output device has changed, restarting MPD Feb 28 17:17:05 volumio-43-dsi sudo[1741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 28 17:17:05 volumio-43-dsi sudo[1741]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: Output device has changed, restarting Shairport Sync Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:17:05 volumio-43-dsi sudo[1741]: pam_unix(sudo:session): session closed for user root Feb 28 17:17:05 volumio-43-dsi sudo[1744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 28 17:17:05 volumio-43-dsi sudo[1744]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:17:05 volumio-43-dsi systemd[1]: Stopping Music Player Daemon... Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 28 17:17:05 volumio-43-dsi systemd[1]: mpd.service: Succeeded. Feb 28 17:17:05 volumio-43-dsi systemd[1]: Stopped Music Player Daemon. Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: Updating Volume Controller Parameters: Device: 0 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: Disabling external Volume Control Feb 28 17:17:05 volumio-43-dsi volumio[1169]: error: Upnp client error: Error: This socket has been ended by the other party Feb 28 17:17:05 volumio-43-dsi systemd[1]: Starting Music Player Daemon... Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: VolumeController::SetAlsaVolume0 Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: MPD Permissions set Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: MPD Permissions set Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: Shairport-Sync Started Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:17:05 volumio-43-dsi volumio[1169]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:17:05 volumio-43-dsi sudo[1750]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 28 17:17:05 volumio-43-dsi sudo[1750]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:17:05 volumio-43-dsi sudo[1750]: pam_unix(sudo:session): session closed for user root Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: Starting Shairport Sync Feb 28 17:17:05 volumio-43-dsi volumio[1169]: info: Starting Shairport Sync Feb 28 17:17:05 volumio-43-dsi sudo[1771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 28 17:17:05 volumio-43-dsi sudo[1771]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:17:05 volumio-43-dsi sudo[1774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 28 17:17:05 volumio-43-dsi sudo[1774]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:17:05 volumio-43-dsi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 28 17:17:05 volumio-43-dsi systemd[1]: shairport-sync.service: Succeeded. Feb 28 17:17:05 volumio-43-dsi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 28 17:17:06 volumio-43-dsi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 28 17:17:06 volumio-43-dsi sudo[1771]: pam_unix(sudo:session): session closed for user root Feb 28 17:17:06 volumio-43-dsi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 28 17:17:06 volumio-43-dsi systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 28 17:17:06 volumio-43-dsi volumio[1169]: info: Shairport-Sync Started Feb 28 17:17:06 volumio-43-dsi systemd[1]: shairport-sync.service: Succeeded. Feb 28 17:17:06 volumio-43-dsi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 28 17:17:06 volumio-43-dsi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 28 17:17:06 volumio-43-dsi sudo[1774]: pam_unix(sudo:session): session closed for user root Feb 28 17:17:06 volumio-43-dsi volumio[1169]: info: Shairport-Sync Started Feb 28 17:17:06 volumio-43-dsi mpd[1762]: Feb 28 17:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 28 17:17:06 volumio-43-dsi systemd[1]: Started Music Player Daemon. Feb 28 17:17:06 volumio-43-dsi volumio[1169]: error: MPD error: The expression evaluated to a falsy value: Feb 28 17:17:06 volumio-43-dsi volumio[1169]: assert.ok(self.idling) Feb 28 17:17:06 volumio-43-dsi volumio[1169]: error: The expression evaluated to a falsy value: Feb 28 17:17:06 volumio-43-dsi volumio[1169]: assert.ok(self.idling) Feb 28 17:17:06 volumio-43-dsi sudo[1744]: pam_unix(sudo:session): session closed for user root Feb 28 17:17:06 volumio-43-dsi volumio[1169]: error: updateQueue error: null Feb 28 17:17:06 volumio-43-dsi volumio[1169]: error: updateQueue error: null Feb 28 17:17:07 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Feb 28 17:17:07 volumio-43-dsi sudo[1785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 28 17:17:07 volumio-43-dsi sudo[1785]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:17:07 volumio-43-dsi systemd[1]: Stopping Music Player Daemon... Feb 28 17:17:07 volumio-43-dsi systemd[1]: mpd.service: Succeeded. Feb 28 17:17:07 volumio-43-dsi systemd[1]: Stopped Music Player Daemon. Feb 28 17:17:07 volumio-43-dsi systemd[1]: Starting Music Player Daemon... Feb 28 17:17:07 volumio-43-dsi sudo[1787]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 28 17:17:07 volumio-43-dsi sudo[1787]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 17:17:07 volumio-43-dsi sudo[1787]: pam_unix(sudo:session): session closed for user root Feb 28 17:17:07 volumio-43-dsi volumio[1169]: error: Upnp client error: Error: This socket has been ended by the other party Feb 28 17:17:07 volumio-43-dsi mpd[1789]: Feb 28 17:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 28 17:17:07 volumio-43-dsi systemd[1]: Started Music Player Daemon. Feb 28 17:17:07 volumio-43-dsi sudo[1785]: pam_unix(sudo:session): session closed for user root Feb 28 17:17:07 volumio-43-dsi volumio[1169]: error: updateQueue error: null Feb 28 17:17:08 volumio-43-dsi volumio[1169]: info: VolumeController::SetAlsaVolume100 Feb 28 17:17:08 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:17:08 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:17:08 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 17:17:08 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:17:08 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioGetState Feb 28 17:17:08 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 2 Feb 28 17:17:09 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: Preload queue cleared Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreStateMachine::ClearQueue Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreStateMachine::stop Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CorePlayQueue::clearPlayQueue Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CorePlayQueue::saveQueue Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushQueue Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreStateMachine::addQueueItems Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CorePlayQueue::addQueueItems Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: Preload queue cleared Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: Adding Item to queue: music-library/NAS/local/20-BEYOND专辑 42首/BEYOND - 是错也再不分.mp3 Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: Using cached record of: music-library/NAS/local/20-BEYOND专辑 42首/BEYOND - 是错也再不分.mp3 Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: Adding Item to queue: music-library/NAS/local/20-BEYOND专辑 42首/BEYOND - 我是愤怒.mp3 Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: Using cached record of: music-library/NAS/local/20-BEYOND专辑 42首/BEYOND - 我是愤怒.mp3 Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushQueue Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CorePlayQueue::saveQueue Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreStateMachine::updateTrackBlock Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrackBlock Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPlay Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreStateMachine::play index 1 Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreStateMachine::addQueueItems Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CorePlayQueue::addQueueItems Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: Preload queue cleared Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: Adding Item to queue: music-library/NAS/local/20-BEYOND专辑 42首/BEYOND - 早班火车.mp3 Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: Using cached record of: music-library/NAS/local/20-BEYOND专辑 42首/BEYOND - 早班火车.mp3 Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreStateMachine::stop Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushQueue Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CorePlayQueue::saveQueue Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreStateMachine::play index undefined Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreStateMachine::updateTrackBlock Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrackBlock Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 1 Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreStateMachine::startPlaybackTimer Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 1 Feb 28 17:17:12 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::clearAddPlayTracks NAS/local/20-BEYOND专辑 42首/BEYOND - 我是愤怒.mp3 Feb 28 17:17:12 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand stop Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: sendMpdCommand stop took 1 milliseconds Feb 28 17:17:12 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand clear Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: Feb 28 17:17:12 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces system playlist update Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: Ignoring MPD Status Update Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: sendMpdCommand clear took 0 milliseconds Feb 28 17:17:12 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand add "NAS/local/20-BEYOND专辑 42首/BEYOND - 我是愤怒.mp3" Feb 28 17:17:12 volumio-43-dsi volumio[1169]: error: updateQueue error: null Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: Feb 28 17:17:12 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces system playlist update Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: Ignoring MPD Status Update Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: ------------------------------ 1ms Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: sendMpdCommand add "NAS/local/20-BEYOND专辑 42首/BEYOND - 我是愤怒.mp3" took 0 milliseconds Feb 28 17:17:12 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand play Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: ------------------------------ 0ms Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: sendMpdCommand play took 1 milliseconds Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: Feb 28 17:17:12 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces state update: player Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: ControllerMpd::getState Feb 28 17:17:12 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: Feb 28 17:17:12 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces state update: player Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: sendMpdCommand status took 8 milliseconds Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: ControllerMpd::getState Feb 28 17:17:12 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Feb 28 17:17:12 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseState Feb 28 17:17:12 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: sendMpdCommand status took 0 milliseconds Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 28 17:17:12 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseState Feb 28 17:17:12 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 17:17:12 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseTrackInfo Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: ControllerMpd::pushState Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::servicePushState Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 1 Feb 28 17:17:12 volumio-43-dsi volumio[1169]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"我是愤怒","artist":"BEYOND","album":"乐与怒","uri":"NAS/local/20-BEYOND专辑 42首/BEYOND - 我是愤怒.mp3","trackType":"mp3"} Feb 28 17:17:12 volumio-43-dsi volumio[1169]: verbose: CURRENT POSITION 1 Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState stateService play Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState currentStatus stop Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: ------------------------------ 9ms Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 28 17:17:12 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseTrackInfo Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: ControllerMpd::pushState Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::servicePushState Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 1 Feb 28 17:17:12 volumio-43-dsi volumio[1169]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"我是愤怒","artist":"BEYOND","album":"乐与怒","uri":"NAS/local/20-BEYOND专辑 42首/BEYOND - 我是愤怒.mp3","trackType":"mp3"} Feb 28 17:17:12 volumio-43-dsi volumio[1169]: verbose: CURRENT POSITION 1 Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState stateService play Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState currentStatus play Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: Received an update from plugin. extracting info from payload Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 1 Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 1 Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:17:12 volumio-43-dsi volumio[1169]: info: ------------------------------ 10ms Feb 28 17:17:14 volumio-43-dsi ntpd[993]: Soliciting pool server 139.199.215.251 Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: Preload queue cleared Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreStateMachine::ClearQueue Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreStateMachine::stop Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreStateMachine::stPlaybackTimer Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreStateMachine::updateTrackBlock Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrackBlock Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 1 Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreStateMachine::serviceStop Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 1 Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::serviceStop Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: ControllerMpd::stop Feb 28 17:17:14 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand stop Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CorePlayQueue::clearPlayQueue Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CorePlayQueue::saveQueue Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushQueue Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreStateMachine::addQueueItems Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CorePlayQueue::addQueueItems Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: Preload queue cleared Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: Adding Item to queue: music-library/NAS/local/20-BEYOND专辑 42首/BEYOND - 是错也再不分.mp3 Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: Using cached record of: music-library/NAS/local/20-BEYOND专辑 42首/BEYOND - 是错也再不分.mp3 Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushQueue Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CorePlayQueue::saveQueue Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreStateMachine::updateTrackBlock Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrackBlock Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPlay Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreStateMachine::play index 0 Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreStateMachine::addQueueItems Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CorePlayQueue::addQueueItems Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: Preload queue cleared Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: Adding Item to queue: music-library/NAS/local/20-BEYOND专辑 42首/BEYOND - 我是愤怒.mp3 Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: Using cached record of: music-library/NAS/local/20-BEYOND专辑 42首/BEYOND - 我是愤怒.mp3 Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: Adding Item to queue: music-library/NAS/local/20-BEYOND专辑 42首/BEYOND - 早班火车.mp3 Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: Using cached record of: music-library/NAS/local/20-BEYOND专辑 42首/BEYOND - 早班火车.mp3 Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreStateMachine::stop Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushQueue Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CorePlayQueue::saveQueue Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreStateMachine::play index undefined Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreStateMachine::updateTrackBlock Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrackBlock Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreStateMachine::startPlaybackTimer Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:17:14 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::clearAddPlayTracks NAS/local/20-BEYOND专辑 42首/BEYOND - 是错也再不分.mp3 Feb 28 17:17:14 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand stop Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: Feb 28 17:17:14 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces state update: player Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: sendMpdCommand stop took 8 milliseconds Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: ControllerMpd::getState Feb 28 17:17:14 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: sendMpdCommand stop took 2 milliseconds Feb 28 17:17:14 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand clear Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: Feb 28 17:17:14 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces system playlist update Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: Ignoring MPD Status Update Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: sendMpdCommand status took 1 milliseconds Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: sendMpdCommand clear took 1 milliseconds Feb 28 17:17:14 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseState Feb 28 17:17:14 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 17:17:14 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand add "NAS/local/20-BEYOND专辑 42首/BEYOND - 是错也再不分.mp3" Feb 28 17:17:14 volumio-43-dsi volumio[1169]: error: updateQueue error: null Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: Feb 28 17:17:14 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces system playlist update Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: Ignoring MPD Status Update Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: ------------------------------ 1ms Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: sendMpdCommand add "NAS/local/20-BEYOND专辑 42首/BEYOND - 是错也再不分.mp3" took 0 milliseconds Feb 28 17:17:14 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseTrackInfo Feb 28 17:17:14 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand play Feb 28 17:17:14 volumio-43-dsi volumio[1169]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: ------------------------------ 2ms Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: ------------------------------ 1ms Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: sendMpdCommand play took 0 milliseconds Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: Feb 28 17:17:14 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces state update: player Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: ControllerMpd::getState Feb 28 17:17:14 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: Feb 28 17:17:14 volumio-43-dsi volumio[1169]: ---------------------------- MPD announces state update: player Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: sendMpdCommand status took 6 milliseconds Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: ControllerMpd::getState Feb 28 17:17:14 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Feb 28 17:17:14 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseState Feb 28 17:17:14 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: sendMpdCommand status took 1 milliseconds Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 28 17:17:14 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseState Feb 28 17:17:14 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 28 17:17:14 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseTrackInfo Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: ControllerMpd::pushState Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::servicePushState Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:17:14 volumio-43-dsi volumio[1169]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":266,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"是错也再不分","artist":"BEYOND","album":"Recognition","uri":"NAS/local/20-BEYOND专辑 42首/BEYOND - 是错也再不分.mp3","trackType":"mp3"} Feb 28 17:17:14 volumio-43-dsi volumio[1169]: verbose: CURRENT POSITION 0 Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState stateService play Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState currentStatus stop Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: ------------------------------ 8ms Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 28 17:17:14 volumio-43-dsi volumio[1169]: verbose: ControllerMpd::parseTrackInfo Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: ControllerMpd::pushState Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::servicePushState Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:17:14 volumio-43-dsi volumio[1169]: verbose: STATE SERVICE {"status":"play","position":0,"seek":754,"duration":266,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"是错也再不分","artist":"BEYOND","album":"Recognition","uri":"NAS/local/20-BEYOND专辑 42首/BEYOND - 是错也再不分.mp3","trackType":"mp3"} Feb 28 17:17:14 volumio-43-dsi volumio[1169]: verbose: CURRENT POSITION 0 Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState stateService play Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreStateMachine::syncState currentStatus play Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: Received an update from plugin. extracting info from payload Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:17:14 volumio-43-dsi volumio[1169]: info: ------------------------------ 9ms Feb 28 17:17:16 volumio-43-dsi ntpd[993]: Soliciting pool server 202.112.31.197 Feb 28 17:17:17 volumio-43-dsi ntpd[993]: Soliciting pool server 202.112.29.82 Feb 28 17:17:17 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioGetState Feb 28 17:17:17 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:17:18 volumio-43-dsi ntpd[993]: Soliciting pool server 202.118.1.130 Feb 28 17:17:18 volumio-43-dsi ntpd[993]: Soliciting pool server 193.182.111.14 Feb 28 17:17:19 volumio-43-dsi volumio[1169]: info: VolumeController::SetAlsaVolume89 Feb 28 17:17:19 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:17:19 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:17:19 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 17:17:19 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:17:19 volumio-43-dsi ntpd[993]: Soliciting pool server 162.159.200.123 Feb 28 17:17:19 volumio-43-dsi ntpd[993]: Soliciting pool server 162.159.200.1 Feb 28 17:17:19 volumio-43-dsi ntpd[993]: Soliciting pool server 193.182.111.142 Feb 28 17:17:20 volumio-43-dsi ntpd[993]: Soliciting pool server 116.203.151.74 Feb 28 17:17:20 volumio-43-dsi ntpd[993]: Soliciting pool server 84.16.67.12 Feb 28 17:17:20 volumio-43-dsi ntpd[993]: Soliciting pool server 139.199.215.251 Feb 28 17:17:20 volumio-43-dsi volumio[1169]: info: VolumeController::SetAlsaVolume96 Feb 28 17:17:20 volumio-43-dsi volumio[1169]: info: CoreStateMachine::pushState Feb 28 17:17:20 volumio-43-dsi volumio[1169]: info: CorePlayQueue::getTrack 0 Feb 28 17:17:20 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 17:17:20 volumio-43-dsi volumio[1169]: info: CoreCommandRouter::volumioPushState Feb 28 17:17:21 volumio-43-dsi ntpd[993]: Soliciting pool server 119.28.183.184 Feb 28 17:17:21 volumio-43-dsi ntpd[993]: Soliciting pool server 119.28.206.193 Feb 28 17:17:22 volumio-43-dsi ntpd[993]: Soliciting pool server 2a03:8600::cc Feb 28 17:17:25 volumio-43-dsi ntpd[993]: receive: Unexpected origin timestamp 0xeb6bfda4.95ee399b does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xeb6bfda5.35355cd0 Feb 28 17:17:25 volumio-43-dsi ntpd[993]: receive: Unexpected origin timestamp 0xeb6bfda4.95edc043 does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xeb6bfda5.351a68ce Feb 28 17:17:25 volumio-43-dsi ntpd[993]: receive: Unexpected origin timestamp 0xeb6bfda4.95ed32f5 does not match aorg 0000000000.00000000 from server@193.182.111.142 xmt 0xeb6bfda5.374c88da Feb 28 17:17:25 volumio-43-dsi ntpd[993]: receive: Unexpected origin timestamp 0xeb6bfda4.95ea8814 does not match aorg 0000000000.00000000 from server@119.28.206.193 xmt 0xeb6bfda5.22f27209 Feb 28 17:17:25 volumio-43-dsi ntpd[993]: receive: Unexpected origin timestamp 0xeb6bfda4.95ec748f does not match aorg 0000000000.00000000 from server@116.203.151.74 xmt 0xeb6bfda5.3423ae7d Feb 28 17:17:32 volumio-43-dsi volumio[1169]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 28 17:17:32 volumio-43-dsi volumio[1169]: Error: Client network socket disconnected before secure TLS connection was established Feb 28 17:17:32 volumio-43-dsi volumio[1169]: at connResetException (internal/errors.js:607:14) Feb 28 17:17:32 volumio-43-dsi volumio[1169]: at TLSSocket.onConnectEnd (_tls_wrap.js:1544:19) Feb 28 17:17:32 volumio-43-dsi volumio[1169]: at TLSSocket.emit (events.js:327:22) Feb 28 17:17:32 volumio-43-dsi volumio[1169]: at endReadableNT (internal/streams/readable.js:1327:12) Feb 28 17:17:32 volumio-43-dsi volumio[1169]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Feb 28 17:17:32 volumio-43-dsi volumio[1169]: code: 'ECONNRESET', Feb 28 17:17:32 volumio-43-dsi volumio[1169]: path: null, Feb 28 17:17:32 volumio-43-dsi volumio[1169]: host: 'lastfm.freetls.fastly.net', Feb 28 17:17:32 volumio-43-dsi volumio[1169]: port: 443, Feb 28 17:17:32 volumio-43-dsi volumio[1169]: localAddress: undefined Feb 28 17:17:32 volumio-43-dsi volumio[1169]: } Feb 28 17:17:32 volumio-43-dsi volumio[1169]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 28 17:17:32 volumio-43-dsi sudo[1928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-28 17:16 Feb 28 17:17:32 volumio-43-dsi sudo[1928]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"