-- Logs begin at Sun 2024-12-01 20:17:36 CET, end at Sun 2024-12-01 20:18:45 CET. -- Dec 01 20:17:36 volumio3 kernel: Booting Linux on physical CPU 0x0000000000 [0x414fd0b1] Dec 01 20:17:36 volumio3 kernel: Linux version 6.6.56-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) #1803 SMP PREEMPT Thu Oct 10 14:12:44 BST 2024 Dec 01 20:17:36 volumio3 kernel: KASLR enabled Dec 01 20:17:36 volumio3 kernel: random: crng init done Dec 01 20:17:36 volumio3 kernel: Machine model: Raspberry Pi 5 Model B Rev 1.0 Dec 01 20:17:36 volumio3 kernel: efi: UEFI not found. Dec 01 20:17:36 volumio3 kernel: Reserved memory: created CMA memory pool at 0x0000000001c00000, size 64 MiB Dec 01 20:17:36 volumio3 kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Dec 01 20:17:36 volumio3 kernel: OF: reserved mem: 0x0000000001c00000..0x0000000005bfffff (65536 KiB) map reusable linux,cma Dec 01 20:17:36 volumio3 kernel: OF: reserved mem: 0x0000000000000000..0x000000000007ffff (512 KiB) nomap non-reusable atf@0 Dec 01 20:17:36 volumio3 kernel: OF: reserved mem: 0x000000003fd16240..0x000000003fd16277 (0 KiB) nomap non-reusable nvram@0 Dec 01 20:17:36 volumio3 kernel: Zone ranges: Dec 01 20:17:36 volumio3 kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Dec 01 20:17:36 volumio3 kernel: DMA32 empty Dec 01 20:17:36 volumio3 kernel: Normal empty Dec 01 20:17:36 volumio3 kernel: Movable zone start for each node Dec 01 20:17:36 volumio3 kernel: Early memory node ranges Dec 01 20:17:36 volumio3 kernel: node 0: [mem 0x0000000000000000-0x000000000007ffff] Dec 01 20:17:36 volumio3 kernel: node 0: [mem 0x0000000000080000-0x000000003fbfffff] Dec 01 20:17:36 volumio3 kernel: node 0: [mem 0x0000000040000000-0x00000000ffffffff] Dec 01 20:17:36 volumio3 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000000ffffffff] Dec 01 20:17:36 volumio3 kernel: On node 0, zone DMA: 1024 pages in unavailable ranges Dec 01 20:17:36 volumio3 kernel: psci: probing for conduit method from DT. Dec 01 20:17:36 volumio3 kernel: psci: PSCIv1.1 detected in firmware. Dec 01 20:17:36 volumio3 kernel: psci: Using standard PSCI v0.2 function IDs Dec 01 20:17:36 volumio3 kernel: psci: MIGRATE_INFO_TYPE not supported. Dec 01 20:17:36 volumio3 kernel: psci: SMC Calling Convention v1.2 Dec 01 20:17:36 volumio3 kernel: percpu: Embedded 30 pages/cpu s85672 r8192 d29016 u122880 Dec 01 20:17:36 volumio3 kernel: pcpu-alloc: s85672 r8192 d29016 u122880 alloc=30*4096 Dec 01 20:17:36 volumio3 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Dec 01 20:17:36 volumio3 kernel: Detected PIPT I-cache on CPU0 Dec 01 20:17:36 volumio3 kernel: CPU features: detected: Virtualization Host Extensions Dec 01 20:17:36 volumio3 kernel: CPU features: detected: Hardware dirty bit management Dec 01 20:17:36 volumio3 kernel: CPU features: detected: Spectre-v4 Dec 01 20:17:36 volumio3 kernel: CPU features: detected: Spectre-BHB Dec 01 20:17:36 volumio3 kernel: CPU features: kernel page table isolation forced ON by KASLR Dec 01 20:17:36 volumio3 kernel: CPU features: detected: Kernel page table isolation (KPTI) Dec 01 20:17:36 volumio3 kernel: CPU features: detected: SSBS not fully self-synchronizing Dec 01 20:17:36 volumio3 kernel: alternatives: applying boot alternatives Dec 01 20:17:36 volumio3 kernel: Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe smsc95xx.macaddr=2C:CF:67:40:3E:C3 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=ed280c2b-b4b4-43e6-87d7-b67377eacab2 imgfile=/volumio_current.sqsh bootpart=UUID=FCA2-3F9A datapart=UUID=0367e456-8c2f-458a-8465-47f124ad9fdd 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 Dec 01 20:17:36 volumio3 kernel: PCIe ASPM is disabled Dec 01 20:17:36 volumio3 kernel: Unknown kernel command line parameters "splash nodebug imgpart=UUID=ed280c2b-b4b4-43e6-87d7-b67377eacab2 imgfile=/volumio_current.sqsh bootpart=UUID=FCA2-3F9A datapart=UUID=0367e456-8c2f-458a-8465-47f124ad9fdd uuidconfig=cmdline.txt bootdelay=7 use_kmsg=no", will be passed to user space. Dec 01 20:17:36 volumio3 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Dec 01 20:17:36 volumio3 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Dec 01 20:17:36 volumio3 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1031168 Dec 01 20:17:36 volumio3 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Dec 01 20:17:36 volumio3 kernel: Memory: 3989272K/4190208K available (13376K kernel code, 2212K rwdata, 4264K rodata, 4864K init, 1083K bss, 135400K reserved, 65536K cma-reserved) Dec 01 20:17:36 volumio3 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Dec 01 20:17:36 volumio3 kernel: ftrace: allocating 43308 entries in 170 pages Dec 01 20:17:36 volumio3 kernel: ftrace: allocated 170 pages with 4 groups Dec 01 20:17:36 volumio3 kernel: trace event string verifier disabled Dec 01 20:17:36 volumio3 kernel: rcu: Preemptible hierarchical RCU implementation. Dec 01 20:17:36 volumio3 kernel: rcu: RCU event tracing is enabled. Dec 01 20:17:36 volumio3 kernel: rcu: RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4. Dec 01 20:17:36 volumio3 kernel: Trampoline variant of Tasks RCU enabled. Dec 01 20:17:36 volumio3 kernel: Rude variant of Tasks RCU enabled. Dec 01 20:17:36 volumio3 kernel: Tracing variant of Tasks RCU enabled. Dec 01 20:17:36 volumio3 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. Dec 01 20:17:36 volumio3 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Dec 01 20:17:36 volumio3 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Dec 01 20:17:36 volumio3 kernel: Root IRQ handler: gic_handle_irq Dec 01 20:17:36 volumio3 kernel: GIC: Using split EOI/Deactivate mode Dec 01 20:17:36 volumio3 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Dec 01 20:17:36 volumio3 kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Dec 01 20:17:36 volumio3 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Dec 01 20:17:36 volumio3 kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Dec 01 20:17:36 volumio3 kernel: Console: colour dummy device 80x25 Dec 01 20:17:36 volumio3 kernel: printk: console [tty1] enabled Dec 01 20:17:36 volumio3 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000) Dec 01 20:17:36 volumio3 kernel: pid_max: default: 32768 minimum: 301 Dec 01 20:17:36 volumio3 kernel: LSM: initializing lsm=capability,integrity Dec 01 20:17:36 volumio3 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Dec 01 20:17:36 volumio3 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Dec 01 20:17:36 volumio3 kernel: cgroup: Disabling memory control group subsystem Dec 01 20:17:36 volumio3 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Dec 01 20:17:36 volumio3 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Dec 01 20:17:36 volumio3 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Dec 01 20:17:36 volumio3 kernel: rcu: Hierarchical SRCU implementation. Dec 01 20:17:36 volumio3 kernel: rcu: Max phase no-delay instances is 1000. Dec 01 20:17:36 volumio3 kernel: EFI services will not be available. Dec 01 20:17:36 volumio3 kernel: smp: Bringing up secondary CPUs ... Dec 01 20:17:36 volumio3 kernel: Detected PIPT I-cache on CPU1 Dec 01 20:17:36 volumio3 kernel: CPU1: Booted secondary processor 0x0000000100 [0x414fd0b1] Dec 01 20:17:36 volumio3 kernel: Detected PIPT I-cache on CPU2 Dec 01 20:17:36 volumio3 kernel: CPU2: Booted secondary processor 0x0000000200 [0x414fd0b1] Dec 01 20:17:36 volumio3 kernel: Detected PIPT I-cache on CPU3 Dec 01 20:17:36 volumio3 kernel: CPU3: Booted secondary processor 0x0000000300 [0x414fd0b1] Dec 01 20:17:36 volumio3 kernel: smp: Brought up 1 node, 4 CPUs Dec 01 20:17:36 volumio3 kernel: SMP: Total of 4 processors activated. Dec 01 20:17:36 volumio3 kernel: CPU features: detected: 32-bit EL0 Support Dec 01 20:17:36 volumio3 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Dec 01 20:17:36 volumio3 kernel: CPU features: detected: Common not Private translations Dec 01 20:17:36 volumio3 kernel: CPU features: detected: CRC32 instructions Dec 01 20:17:36 volumio3 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Dec 01 20:17:36 volumio3 kernel: CPU features: detected: LSE atomic instructions Dec 01 20:17:36 volumio3 kernel: CPU features: detected: Privileged Access Never Dec 01 20:17:36 volumio3 kernel: CPU features: detected: RAS Extension Support Dec 01 20:17:36 volumio3 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Dec 01 20:17:36 volumio3 kernel: CPU: All CPU(s) started at EL2 Dec 01 20:17:36 volumio3 kernel: alternatives: applying system-wide alternatives Dec 01 20:17:36 volumio3 kernel: devtmpfs: initialized Dec 01 20:17:36 volumio3 kernel: Enabled cp15_barrier support Dec 01 20:17:36 volumio3 kernel: Enabled setend support Dec 01 20:17:36 volumio3 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Dec 01 20:17:36 volumio3 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Dec 01 20:17:36 volumio3 kernel: pinctrl core: initialized pinctrl subsystem Dec 01 20:17:36 volumio3 kernel: DMI not present or invalid. Dec 01 20:17:36 volumio3 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Dec 01 20:17:36 volumio3 kernel: DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations Dec 01 20:17:36 volumio3 kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Dec 01 20:17:36 volumio3 kernel: DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Dec 01 20:17:36 volumio3 kernel: audit: initializing netlink subsys (disabled) Dec 01 20:17:36 volumio3 kernel: audit: type=2000 audit(0.008:1): state=initialized audit_enabled=0 res=1 Dec 01 20:17:36 volumio3 kernel: thermal_sys: Registered thermal governor 'step_wise' Dec 01 20:17:36 volumio3 kernel: cpuidle: using governor menu Dec 01 20:17:36 volumio3 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Dec 01 20:17:36 volumio3 kernel: ASID allocator initialised with 32768 entries Dec 01 20:17:36 volumio3 kernel: Serial: AMBA PL011 UART driver Dec 01 20:17:36 volumio3 kernel: bcm2835-mbox 107c013880.mailbox: mailbox enabled Dec 01 20:17:36 volumio3 kernel: 107d001000.serial: ttyAMA10 at MMIO 0x107d001000 (irq = 15, base_baud = 0) is a PL011 rev2 Dec 01 20:17:36 volumio3 kernel: printk: console [ttyAMA10] enabled Dec 01 20:17:36 volumio3 kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2024-02-16T15:28:41, variant start_cd Dec 01 20:17:36 volumio3 kernel: raspberrypi-firmware soc:firmware: Firmware hash is 4c845bd300000000000000000000000000000000 Dec 01 20:17:36 volumio3 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Dec 01 20:17:36 volumio3 kernel: Modules: 0 pages in range for non-PLT usage Dec 01 20:17:36 volumio3 kernel: Modules: 517792 pages in range for PLT usage Dec 01 20:17:36 volumio3 kernel: bcm2835-dma 1000010000.dma: DMA legacy API manager, dmachans=0x1 Dec 01 20:17:36 volumio3 kernel: iommu: Default domain type: Translated Dec 01 20:17:36 volumio3 kernel: iommu: DMA domain TLB invalidation policy: strict mode Dec 01 20:17:36 volumio3 kernel: SCSI subsystem initialized Dec 01 20:17:36 volumio3 kernel: usbcore: registered new interface driver usbfs Dec 01 20:17:36 volumio3 kernel: usbcore: registered new interface driver hub Dec 01 20:17:36 volumio3 kernel: usbcore: registered new device driver usb Dec 01 20:17:36 volumio3 kernel: pps_core: LinuxPPS API ver. 1 registered Dec 01 20:17:36 volumio3 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Dec 01 20:17:36 volumio3 kernel: PTP clock support registered Dec 01 20:17:36 volumio3 kernel: vgaarb: loaded Dec 01 20:17:36 volumio3 kernel: clocksource: Switched to clocksource arch_sys_counter Dec 01 20:17:36 volumio3 kernel: VFS: Disk quotas dquot_6.6.0 Dec 01 20:17:36 volumio3 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Dec 01 20:17:36 volumio3 kernel: FS-Cache: Loaded Dec 01 20:17:36 volumio3 kernel: CacheFiles: Loaded Dec 01 20:17:36 volumio3 kernel: NET: Registered PF_INET protocol family Dec 01 20:17:36 volumio3 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Dec 01 20:17:36 volumio3 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Dec 01 20:17:36 volumio3 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Dec 01 20:17:36 volumio3 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Dec 01 20:17:36 volumio3 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Dec 01 20:17:36 volumio3 kernel: TCP: Hash tables configured (established 32768 bind 32768) Dec 01 20:17:36 volumio3 kernel: MPTCP token hash table entries: 4096 (order: 4, 98304 bytes, linear) Dec 01 20:17:36 volumio3 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Dec 01 20:17:36 volumio3 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Dec 01 20:17:36 volumio3 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Dec 01 20:17:36 volumio3 kernel: RPC: Registered named UNIX socket transport module. Dec 01 20:17:36 volumio3 kernel: RPC: Registered udp transport module. Dec 01 20:17:36 volumio3 kernel: RPC: Registered tcp transport module. Dec 01 20:17:36 volumio3 kernel: RPC: Registered tcp-with-tls transport module. Dec 01 20:17:36 volumio3 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Dec 01 20:17:36 volumio3 kernel: PCI: CLS 0 bytes, default 64 Dec 01 20:17:36 volumio3 kernel: Trying to unpack rootfs image as initramfs... Dec 01 20:17:36 volumio3 kernel: kvm [1]: IPA Size Limit: 40 bits Dec 01 20:17:36 volumio3 kernel: kvm [1]: vgic interrupt IRQ9 Dec 01 20:17:36 volumio3 kernel: kvm [1]: VHE mode initialized successfully Dec 01 20:17:36 volumio3 kernel: Initialise system trusted keyrings Dec 01 20:17:36 volumio3 kernel: workingset: timestamp_bits=46 max_order=20 bucket_order=0 Dec 01 20:17:36 volumio3 kernel: zbud: loaded Dec 01 20:17:36 volumio3 kernel: NFS: Registering the id_resolver key type Dec 01 20:17:36 volumio3 kernel: Key type id_resolver registered Dec 01 20:17:36 volumio3 kernel: Key type id_legacy registered Dec 01 20:17:36 volumio3 kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Dec 01 20:17:36 volumio3 kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Dec 01 20:17:36 volumio3 kernel: Key type asymmetric registered Dec 01 20:17:36 volumio3 kernel: Asymmetric key parser 'x509' registered Dec 01 20:17:36 volumio3 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Dec 01 20:17:36 volumio3 kernel: io scheduler mq-deadline registered Dec 01 20:17:36 volumio3 kernel: io scheduler kyber registered Dec 01 20:17:36 volumio3 kernel: io scheduler bfq registered Dec 01 20:17:36 volumio3 kernel: irq_brcmstb_l2: registered L2 intc (/soc/interrupt-controller@7c502000, parent irq: 26) Dec 01 20:17:36 volumio3 kernel: irq_brcmstb_l2: registered L2 intc (/soc/intc@7d503000, parent irq: 27) Dec 01 20:17:36 volumio3 kernel: irq_brcmstb_l2: registered L2 intc (/soc/intc@7d508380, parent irq: 28) Dec 01 20:17:36 volumio3 kernel: irq_brcmstb_l2: registered L2 intc (/soc/intc@7d508400, parent irq: 29) Dec 01 20:17:36 volumio3 kernel: irq_brcmstb_l2: registered L2 intc (/soc/interrupt-controller@7d510600, parent irq: 30) Dec 01 20:17:36 volumio3 kernel: irq_brcmstb_l2: registered L2 intc (/soc/intc@7d517b00, parent irq: 31) Dec 01 20:17:36 volumio3 kernel: Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled Dec 01 20:17:36 volumio3 kernel: 107d50c000.serial: ttyS0 at MMIO 0x107d50c000 (irq = 33, base_baud = 6000000) is a Broadcom BCM7271 UART Dec 01 20:17:36 volumio3 kernel: serial serial0: tty port ttyS0 registered Dec 01 20:17:36 volumio3 kernel: iproc-rng200 107d208000.rng: hwrng registered Dec 01 20:17:36 volumio3 kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3fc00000 mem_size:0x40000000(1024 MiB) Dec 01 20:17:36 volumio3 kernel: bcm2712-iommu-cache 1000005b00.iommuc: bcm2712_iommu_cache_probe Dec 01 20:17:36 volumio3 kernel: Freeing initrd memory: 28064K Dec 01 20:17:36 volumio3 kernel: brd: module loaded Dec 01 20:17:36 volumio3 kernel: loop: module loaded Dec 01 20:17:36 volumio3 kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Dec 01 20:17:36 volumio3 kernel: Loading iSCSI transport class v2.0-870. Dec 01 20:17:36 volumio3 kernel: usbcore: registered new device driver r8152-cfgselector Dec 01 20:17:36 volumio3 kernel: usbcore: registered new interface driver r8152 Dec 01 20:17:36 volumio3 kernel: usbcore: registered new interface driver lan78xx Dec 01 20:17:36 volumio3 kernel: usbcore: registered new interface driver smsc95xx Dec 01 20:17:36 volumio3 kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Dec 01 20:17:36 volumio3 kernel: dwc_otg: FIQ enabled Dec 01 20:17:36 volumio3 kernel: dwc_otg: NAK holdoff enabled Dec 01 20:17:36 volumio3 kernel: dwc_otg: FIQ split-transaction FSM enabled Dec 01 20:17:36 volumio3 kernel: Module dwc_common_port init Dec 01 20:17:36 volumio3 kernel: usbcore: registered new interface driver uas Dec 01 20:17:36 volumio3 kernel: usbcore: registered new interface driver usb-storage Dec 01 20:17:36 volumio3 kernel: mousedev: PS/2 mouse device common for all mice Dec 01 20:17:36 volumio3 kernel: rpi-rtc soc:rpi_rtc: registered as rtc0 Dec 01 20:17:36 volumio3 kernel: rpi-rtc soc:rpi_rtc: setting system clock to 2024-12-01T19:17:24 UTC (1733080644) Dec 01 20:17:36 volumio3 kernel: bcm2835-wdt bcm2835-wdt: Poweroff handler already present! Dec 01 20:17:36 volumio3 kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Dec 01 20:17:36 volumio3 kernel: sdhci: Secure Digital Host Controller Interface driver Dec 01 20:17:36 volumio3 kernel: sdhci: Copyright(c) Pierre Ossman Dec 01 20:17:36 volumio3 kernel: sdhci-pltfm: SDHCI platform and OF driver helper Dec 01 20:17:36 volumio3 kernel: ledtrig-cpu: registered to indicate activity on CPUs Dec 01 20:17:36 volumio3 kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Dec 01 20:17:36 volumio3 kernel: hid: raw HID events driver (C) Jiri Kosina Dec 01 20:17:36 volumio3 kernel: usbcore: registered new interface driver usbhid Dec 01 20:17:36 volumio3 kernel: usbhid: USB HID core driver Dec 01 20:17:36 volumio3 kernel: hw perfevents: enabled with armv8_cortex_a76 PMU driver, 7 counters available Dec 01 20:17:36 volumio3 kernel: NET: Registered PF_PACKET protocol family Dec 01 20:17:36 volumio3 kernel: Key type dns_resolver registered Dec 01 20:17:36 volumio3 kernel: registered taskstats version 1 Dec 01 20:17:36 volumio3 kernel: Loading compiled-in X.509 certificates Dec 01 20:17:36 volumio3 kernel: Key type .fscrypt registered Dec 01 20:17:36 volumio3 kernel: Key type fscrypt-provisioning registered Dec 01 20:17:36 volumio3 kernel: brcm-pcie 1000110000.pcie: host bridge /axi/pcie@110000 ranges: Dec 01 20:17:36 volumio3 kernel: brcm-pcie 1000110000.pcie: No bus range found for /axi/pcie@110000, using [bus 00-ff] Dec 01 20:17:36 volumio3 kernel: brcm-pcie 1000110000.pcie: MEM 0x1b80000000..0x1bffffffff -> 0x0080000000 Dec 01 20:17:36 volumio3 kernel: brcm-pcie 1000110000.pcie: MEM 0x1800000000..0x1b7fffffff -> 0x0400000000 Dec 01 20:17:36 volumio3 kernel: brcm-pcie 1000110000.pcie: IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000 Dec 01 20:17:36 volumio3 kernel: brcm-pcie 1000110000.pcie: Forcing gen 2 Dec 01 20:17:36 volumio3 kernel: brcm-pcie 1000110000.pcie: PCI host bridge to bus 0000:00 Dec 01 20:17:36 volumio3 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Dec 01 20:17:36 volumio3 kernel: pci_bus 0000:00: root bus resource [mem 0x1b80000000-0x1bffffffff] (bus address [0x80000000-0xffffffff]) Dec 01 20:17:36 volumio3 kernel: pci_bus 0000:00: root bus resource [mem 0x1800000000-0x1b7fffffff pref] (bus address [0x400000000-0x77fffffff]) Dec 01 20:17:36 volumio3 kernel: pci 0000:00:00.0: [14e4:2712] type 01 class 0x060400 Dec 01 20:17:36 volumio3 kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Dec 01 20:17:36 volumio3 kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Dec 01 20:17:36 volumio3 kernel: brcm-pcie 1000110000.pcie: link down Dec 01 20:17:36 volumio3 kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Dec 01 20:17:36 volumio3 kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Dec 01 20:17:36 volumio3 kernel: pci 0000:00:00.0: Max Payload Size set to 512/ 512 (was 128), Max Read Rq 512 Dec 01 20:17:36 volumio3 kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 38 Dec 01 20:17:36 volumio3 kernel: pcieport 0000:00:00.0: AER: enabled with IRQ 38 Dec 01 20:17:36 volumio3 kernel: pci_bus 0000:01: busn_res: [bus 01] is released Dec 01 20:17:36 volumio3 kernel: pci_bus 0000:00: busn_res: [bus 00-ff] is released Dec 01 20:17:36 volumio3 kernel: brcm-pcie 1000120000.pcie: host bridge /axi/pcie@120000 ranges: Dec 01 20:17:36 volumio3 kernel: brcm-pcie 1000120000.pcie: No bus range found for /axi/pcie@120000, using [bus 00-ff] Dec 01 20:17:36 volumio3 kernel: brcm-pcie 1000120000.pcie: MEM 0x1f00000000..0x1ffffffffb -> 0x0000000000 Dec 01 20:17:36 volumio3 kernel: brcm-pcie 1000120000.pcie: MEM 0x1c00000000..0x1effffffff -> 0x0400000000 Dec 01 20:17:36 volumio3 kernel: brcm-pcie 1000120000.pcie: IB MEM 0x1f00000000..0x1f003fffff -> 0x0000000000 Dec 01 20:17:36 volumio3 kernel: brcm-pcie 1000120000.pcie: IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000 Dec 01 20:17:36 volumio3 kernel: brcm-pcie 1000120000.pcie: Forcing gen 2 Dec 01 20:17:36 volumio3 kernel: brcm-pcie 1000120000.pcie: PCI host bridge to bus 0000:00 Dec 01 20:17:36 volumio3 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Dec 01 20:17:36 volumio3 kernel: pci_bus 0000:00: root bus resource [mem 0x1f00000000-0x1ffffffffb] (bus address [0x00000000-0xfffffffb]) Dec 01 20:17:36 volumio3 kernel: pci_bus 0000:00: root bus resource [mem 0x1c00000000-0x1effffffff pref] (bus address [0x400000000-0x6ffffffff]) Dec 01 20:17:36 volumio3 kernel: pci 0000:00:00.0: [14e4:2712] type 01 class 0x060400 Dec 01 20:17:36 volumio3 kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Dec 01 20:17:36 volumio3 kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Dec 01 20:17:36 volumio3 kernel: brcm-pcie 1000120000.pcie: link up, 5.0 GT/s PCIe x4 (!SSC) Dec 01 20:17:36 volumio3 kernel: pci 0000:01:00.0: [1de4:0001] type 00 class 0x020000 Dec 01 20:17:36 volumio3 kernel: pci 0000:01:00.0: reg 0x10: [mem 0xffffc000-0xffffffff] Dec 01 20:17:36 volumio3 kernel: pci 0000:01:00.0: reg 0x14: [mem 0xffc00000-0xffffffff] Dec 01 20:17:36 volumio3 kernel: pci 0000:01:00.0: reg 0x18: [mem 0xffff0000-0xffffffff] Dec 01 20:17:36 volumio3 kernel: pci 0000:01:00.0: supports D1 Dec 01 20:17:36 volumio3 kernel: pci 0000:01:00.0: PME# supported from D0 D1 D3hot D3cold Dec 01 20:17:36 volumio3 kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Dec 01 20:17:36 volumio3 kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x1f00000000-0x1f005fffff] Dec 01 20:17:36 volumio3 kernel: pci 0000:01:00.0: BAR 1: assigned [mem 0x1f00000000-0x1f003fffff] Dec 01 20:17:36 volumio3 kernel: pci 0000:01:00.0: BAR 2: assigned [mem 0x1f00400000-0x1f0040ffff] Dec 01 20:17:36 volumio3 kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x1f00410000-0x1f00413fff] Dec 01 20:17:36 volumio3 kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Dec 01 20:17:36 volumio3 kernel: pci 0000:00:00.0: bridge window [mem 0x1f00000000-0x1f005fffff] Dec 01 20:17:36 volumio3 kernel: pci 0000:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 Dec 01 20:17:36 volumio3 kernel: pci 0000:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512 Dec 01 20:17:36 volumio3 kernel: pcieport 0000:00:00.0: enabling device (0000 -> 0002) Dec 01 20:17:36 volumio3 kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 39 Dec 01 20:17:36 volumio3 kernel: pcieport 0000:00:00.0: AER: enabled with IRQ 39 Dec 01 20:17:36 volumio3 kernel: rp1 0000:01:00.0: bar0 len 0x4000, start 0x1f00410000, end 0x1f00413fff, flags, 0x40200 Dec 01 20:17:36 volumio3 kernel: rp1 0000:01:00.0: bar1 len 0x400000, start 0x1f00000000, end 0x1f003fffff, flags, 0x40200 Dec 01 20:17:36 volumio3 kernel: rp1 0000:01:00.0: enabling device (0000 -> 0002) Dec 01 20:17:36 volumio3 kernel: rp1 0000:01:00.0: chip_id 0x20001927 Dec 01 20:17:36 volumio3 kernel: genirq: irq_chip rp1_irq_chip did not update eff. affinity mask of irq 101 Dec 01 20:17:36 volumio3 kernel: macb 1f00100000.ethernet eth0: Cadence GEM rev 0x00070109 at 0x1f00100000 irq 107 (2c:cf:67:40:3e:c3) Dec 01 20:17:36 volumio3 kernel: dw_axi_dmac_platform 1f00188000.dma: DesignWare AXI DMA Controller, 8 channels Dec 01 20:17:36 volumio3 kernel: xhci-hcd xhci-hcd.0: xHCI Host Controller Dec 01 20:17:36 volumio3 kernel: xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 1 Dec 01 20:17:36 volumio3 kernel: xhci-hcd xhci-hcd.0: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000008000000810 Dec 01 20:17:36 volumio3 kernel: xhci-hcd xhci-hcd.0: irq 132, io mem 0x1f00200000 Dec 01 20:17:36 volumio3 kernel: xhci-hcd xhci-hcd.0: xHCI Host Controller Dec 01 20:17:36 volumio3 kernel: xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 2 Dec 01 20:17:36 volumio3 kernel: xhci-hcd xhci-hcd.0: Host supports USB 3.0 SuperSpeed Dec 01 20:17:36 volumio3 kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Dec 01 20:17:36 volumio3 kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Dec 01 20:17:36 volumio3 kernel: usb usb1: Product: xHCI Host Controller Dec 01 20:17:36 volumio3 kernel: usb usb1: Manufacturer: Linux 6.6.56-v8+ xhci-hcd Dec 01 20:17:36 volumio3 kernel: usb usb1: SerialNumber: xhci-hcd.0 Dec 01 20:17:36 volumio3 kernel: hub 1-0:1.0: USB hub found Dec 01 20:17:36 volumio3 kernel: hub 1-0:1.0: 2 ports detected Dec 01 20:17:36 volumio3 kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06 Dec 01 20:17:36 volumio3 kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Dec 01 20:17:36 volumio3 kernel: usb usb2: Product: xHCI Host Controller Dec 01 20:17:36 volumio3 kernel: usb usb2: Manufacturer: Linux 6.6.56-v8+ xhci-hcd Dec 01 20:17:36 volumio3 kernel: usb usb2: SerialNumber: xhci-hcd.0 Dec 01 20:17:36 volumio3 kernel: hub 2-0:1.0: USB hub found Dec 01 20:17:36 volumio3 kernel: hub 2-0:1.0: 1 port detected Dec 01 20:17:36 volumio3 kernel: xhci-hcd xhci-hcd.1: xHCI Host Controller Dec 01 20:17:36 volumio3 kernel: xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 3 Dec 01 20:17:36 volumio3 kernel: xhci-hcd xhci-hcd.1: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000008000000810 Dec 01 20:17:36 volumio3 kernel: xhci-hcd xhci-hcd.1: irq 137, io mem 0x1f00300000 Dec 01 20:17:36 volumio3 kernel: xhci-hcd xhci-hcd.1: xHCI Host Controller Dec 01 20:17:36 volumio3 kernel: xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 4 Dec 01 20:17:36 volumio3 kernel: xhci-hcd xhci-hcd.1: Host supports USB 3.0 SuperSpeed Dec 01 20:17:36 volumio3 kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Dec 01 20:17:36 volumio3 kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Dec 01 20:17:36 volumio3 kernel: usb usb3: Product: xHCI Host Controller Dec 01 20:17:36 volumio3 kernel: usb usb3: Manufacturer: Linux 6.6.56-v8+ xhci-hcd Dec 01 20:17:36 volumio3 kernel: usb usb3: SerialNumber: xhci-hcd.1 Dec 01 20:17:36 volumio3 kernel: hub 3-0:1.0: USB hub found Dec 01 20:17:36 volumio3 kernel: hub 3-0:1.0: 2 ports detected Dec 01 20:17:36 volumio3 kernel: usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06 Dec 01 20:17:36 volumio3 kernel: usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Dec 01 20:17:36 volumio3 kernel: usb usb4: Product: xHCI Host Controller Dec 01 20:17:36 volumio3 kernel: usb usb4: Manufacturer: Linux 6.6.56-v8+ xhci-hcd Dec 01 20:17:36 volumio3 kernel: usb usb4: SerialNumber: xhci-hcd.1 Dec 01 20:17:36 volumio3 kernel: hub 4-0:1.0: USB hub found Dec 01 20:17:36 volumio3 kernel: hub 4-0:1.0: 1 port detected Dec 01 20:17:36 volumio3 kernel: bcm2712-iommu 1000005100.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Dec 01 20:17:36 volumio3 kernel: platform 1000800000.codec: bcm2712_iommu_probe_device: MMU 1000005100.iommu Dec 01 20:17:36 volumio3 kernel: platform 1000800000.codec: bcm2712_iommu_device_group: MMU 1000005100.iommu Dec 01 20:17:36 volumio3 kernel: platform 1000800000.codec: Adding to iommu group 0 Dec 01 20:17:36 volumio3 kernel: platform 1000880000.pisp_be: bcm2712_iommu_probe_device: MMU 1000005100.iommu Dec 01 20:17:36 volumio3 kernel: platform 1000880000.pisp_be: bcm2712_iommu_device_group: MMU 1000005100.iommu Dec 01 20:17:36 volumio3 kernel: platform 1000880000.pisp_be: Adding to iommu group 0 Dec 01 20:17:36 volumio3 kernel: platform 1000800000.codec: bcm2712_iommu_attach_dev: MMU 1000005100.iommu Dec 01 20:17:36 volumio3 kernel: platform 1000880000.pisp_be: bcm2712_iommu_attach_dev: MMU 1000005100.iommu Dec 01 20:17:36 volumio3 kernel: bcm2712-iommu 1000005100.iommu: bcm2712_iommu_probe: Success Dec 01 20:17:36 volumio3 kernel: bcm2712-iommu 1000005200.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Dec 01 20:17:36 volumio3 kernel: platform axi:gpu: bcm2712_iommu_probe_device: MMU 1000005200.iommu Dec 01 20:17:36 volumio3 kernel: platform axi:gpu: bcm2712_iommu_device_group: MMU 1000005200.iommu Dec 01 20:17:36 volumio3 kernel: platform axi:gpu: Adding to iommu group 1 Dec 01 20:17:36 volumio3 kernel: platform axi:gpu: bcm2712_iommu_attach_dev: MMU 1000005200.iommu Dec 01 20:17:36 volumio3 kernel: bcm2712-iommu 1000005200.iommu: bcm2712_iommu_probe: Success Dec 01 20:17:36 volumio3 kernel: bcm2712-iommu 1000005280.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 Dec 01 20:17:36 volumio3 kernel: bcm2712-iommu 1000005280.iommu: bcm2712_iommu_probe: Success Dec 01 20:17:36 volumio3 kernel: mmc1: CQHCI version 5.10 Dec 01 20:17:36 volumio3 kernel: sdhci-brcmstb 1000fff000.mmc: Got CD GPIO Dec 01 20:17:36 volumio3 kernel: of_cfs_init Dec 01 20:17:36 volumio3 kernel: of_cfs_init: OK Dec 01 20:17:36 volumio3 kernel: clk: Disabling unused clocks Dec 01 20:17:36 volumio3 kernel: mmc0: SDHCI controller on 1000fff000.mmc [1000fff000.mmc] using ADMA 64-bit Dec 01 20:17:36 volumio3 kernel: mmc0: new ultra high speed SDR25 SDHC card at address b368 Dec 01 20:17:36 volumio3 kernel: mmcblk0: mmc0:b368 NCard 29.1 GiB Dec 01 20:17:36 volumio3 kernel: mmcblk0: p1 p2 p3 Dec 01 20:17:36 volumio3 kernel: mmcblk0: mmc0:b368 NCard 29.1 GiB Dec 01 20:17:36 volumio3 kernel: mmc1: SDHCI controller on 1001100000.mmc [1001100000.mmc] using ADMA 64-bit Dec 01 20:17:36 volumio3 kernel: Freeing unused kernel memory: 4864K Dec 01 20:17:36 volumio3 kernel: Run /init as init process Dec 01 20:17:36 volumio3 kernel: with arguments: Dec 01 20:17:36 volumio3 kernel: /init Dec 01 20:17:36 volumio3 kernel: splash Dec 01 20:17:36 volumio3 kernel: nodebug Dec 01 20:17:36 volumio3 kernel: with environment: Dec 01 20:17:36 volumio3 kernel: HOME=/ Dec 01 20:17:36 volumio3 kernel: TERM=linux Dec 01 20:17:36 volumio3 kernel: imgpart=UUID=ed280c2b-b4b4-43e6-87d7-b67377eacab2 Dec 01 20:17:36 volumio3 kernel: imgfile=/volumio_current.sqsh Dec 01 20:17:36 volumio3 kernel: bootpart=UUID=FCA2-3F9A Dec 01 20:17:36 volumio3 kernel: datapart=UUID=0367e456-8c2f-458a-8465-47f124ad9fdd Dec 01 20:17:36 volumio3 kernel: uuidconfig=cmdline.txt Dec 01 20:17:36 volumio3 kernel: bootdelay=7 Dec 01 20:17:36 volumio3 kernel: use_kmsg=no Dec 01 20:17:36 volumio3 kernel: mmc1: new ultra high speed DDR50 SDIO card at address 0001 Dec 01 20:17:36 volumio3 kernel: snd_timer: Unknown symbol snd_info_register (err -2) Dec 01 20:17:36 volumio3 kernel: snd_timer: Unknown symbol snd_info_create_module_entry (err -2) Dec 01 20:17:36 volumio3 kernel: snd_timer: Unknown symbol snd_info_free_entry (err -2) Dec 01 20:17:36 volumio3 kernel: snd_timer: Unknown symbol snd_fasync_helper (err -2) Dec 01 20:17:36 volumio3 kernel: snd_timer: Unknown symbol snd_ecards_limit (err -2) Dec 01 20:17:36 volumio3 kernel: snd_timer: Unknown symbol snd_oss_info_register (err -2) Dec 01 20:17:36 volumio3 kernel: snd_timer: Unknown symbol snd_unregister_device (err -2) Dec 01 20:17:36 volumio3 kernel: snd_timer: Unknown symbol snd_device_alloc (err -2) Dec 01 20:17:36 volumio3 kernel: snd_timer: Unknown symbol snd_device_new (err -2) Dec 01 20:17:36 volumio3 kernel: snd_timer: Unknown symbol snd_fasync_free (err -2) Dec 01 20:17:36 volumio3 kernel: snd_timer: Unknown symbol snd_kill_fasync (err -2) Dec 01 20:17:36 volumio3 kernel: snd_timer: Unknown symbol snd_register_device (err -2) Dec 01 20:17:36 volumio3 kernel: input: pwr_button as /devices/platform/pwr_button/input/input0 Dec 01 20:17:36 volumio3 kernel: brcmstb-i2c 107d508200.i2c: @97500hz registered in interrupt mode Dec 01 20:17:36 volumio3 kernel: brcmstb-i2c 107d508280.i2c: @97500hz registered in interrupt mode Dec 01 20:17:36 volumio3 kernel: [drm] Initialized v3d 1.0.0 20180419 for 1002000000.v3d on minor 0 Dec 01 20:17:36 volumio3 kernel: fuse: init (API version 7.39) Dec 01 20:17:36 volumio3 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Dec 01 20:17:36 volumio3 kernel: EXT4-fs (mmcblk0p2): recovery complete Dec 01 20:17:36 volumio3 kernel: EXT4-fs (mmcblk0p2): mounted filesystem ed280c2b-b4b4-43e6-87d7-b67377eacab2 r/w with ordered data mode. Quota mode: none. Dec 01 20:17:36 volumio3 kernel: loop0: detected capacity change from 0 to 994880 Dec 01 20:17:36 volumio3 kernel: EXT4-fs (mmcblk0p3): recovery complete Dec 01 20:17:36 volumio3 kernel: EXT4-fs (mmcblk0p3): mounted filesystem 0367e456-8c2f-458a-8465-47f124ad9fdd r/w with ordered data mode. Quota mode: none. Dec 01 20:17:36 volumio3 kernel: ext4 filesystem being mounted at /mnt/ext supports timestamps until 2038-01-19 (0x7fffffff) Dec 01 20:17:36 volumio3 kernel: NET: Registered PF_INET6 protocol family Dec 01 20:17:36 volumio3 kernel: Segment Routing with IPv6 Dec 01 20:17:36 volumio3 kernel: In-situ OAM (IOAM) with IPv6 Dec 01 20:17:36 volumio3 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) Dec 01 20:17:36 volumio3 systemd[1]: Detected architecture arm64. Dec 01 20:17:36 volumio3 systemd[1]: Set hostname to . Dec 01 20:17:36 volumio3 kernel: uart-pl011 107d001000.serial: no DMA platform data Dec 01 20:17:36 volumio3 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. Dec 01 20:17:36 volumio3 systemd[1]: Listening on RPCbind Server Activation Socket. Dec 01 20:17:36 volumio3 systemd[1]: Reached target System Time Synchronized. Dec 01 20:17:36 volumio3 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Dec 01 20:17:36 volumio3 systemd[1]: Listening on fsck to fsckd communication Socket. Dec 01 20:17:36 volumio3 systemd[1]: Listening on Journal Socket. Dec 01 20:17:36 volumio3 systemd[1]: Started dynamicswap service. Dec 01 20:17:36 volumio3 kernel: i2c_dev: i2c /dev entries driver Dec 01 20:17:36 volumio3 systemd-journald[380]: Journal started Dec 01 20:17:36 volumio3 systemd-journald[380]: Runtime journal (/run/log/journal/3b9f41731e901aee8cf8cb2a672e4f3b) is 3.7M, max 30.0M, 26.2M free. Dec 01 20:17:36 volumio3 systemd-modules-load[378]: Inserted module 'i2c_dev' Dec 01 20:17:36 volumio3 fake-hwclock[373]: Current system time: 2024-12-01 19:17:36 Dec 01 20:17:36 volumio3 fake-hwclock[373]: fake-hwclock saved clock information is in the past: 2024-12-01 19:17:12 Dec 01 20:17:36 volumio3 fake-hwclock[373]: To set system time to this saved clock anyway, use "force" Dec 01 20:17:36 volumio3 systemd[1]: Started Restore / save the current clock. Dec 01 20:17:36 volumio3 systemd[1]: Started Load Kernel Modules. Dec 01 20:17:36 volumio3 systemd[1]: Mounting Kernel Configuration File System... Dec 01 20:17:36 volumio3 systemd[1]: Starting Apply Kernel Variables... Dec 01 20:17:36 volumio3 systemd[1]: Mounting FUSE Control File System... Dec 01 20:17:36 volumio3 systemd[1]: Mounted FUSE Control File System. Dec 01 20:17:36 volumio3 systemd[1]: Mounted Kernel Configuration File System. Dec 01 20:17:36 volumio3 systemd[1]: Started Load/Save Random Seed. Dec 01 20:17:36 volumio3 systemd[1]: Started udev Coldplug all Devices. Dec 01 20:17:36 volumio3 systemd[1]: Starting Helper to synchronize boot up for ifupdown... Dec 01 20:17:36 volumio3 systemd[1]: dynamicswap.service: Succeeded. Dec 01 20:17:36 volumio3 systemd[1]: Started Create System Users. Dec 01 20:17:36 volumio3 systemd[1]: Started Apply Kernel Variables. Dec 01 20:17:36 volumio3 systemd[1]: Starting Create Static Device Nodes in /dev... Dec 01 20:17:36 volumio3 systemd[1]: Started Helper to synchronize boot up for ifupdown. Dec 01 20:17:36 volumio3 systemd[1]: Started Create Static Device Nodes in /dev. Dec 01 20:17:36 volumio3 systemd[1]: Reached target Local File Systems (Pre). Dec 01 20:17:36 volumio3 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Dec 01 20:17:36 volumio3 systemd[1]: Mounting /tmp... Dec 01 20:17:36 volumio3 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Dec 01 20:17:36 volumio3 systemd[1]: Mounting /var/log... Dec 01 20:17:36 volumio3 systemd[1]: Mounting /var/spool/cups... Dec 01 20:17:36 volumio3 systemd[1]: Starting udev Kernel Device Manager... Dec 01 20:17:36 volumio3 systemd[1]: Mounted /tmp. Dec 01 20:17:36 volumio3 systemd[1]: Mounted /var/log. Dec 01 20:17:36 volumio3 systemd[1]: Mounted /var/spool/cups. Dec 01 20:17:36 volumio3 systemd[1]: Mounting /var/spool/cups/tmp... Dec 01 20:17:36 volumio3 systemd[1]: Starting Flush Journal to Persistent Storage... Dec 01 20:17:36 volumio3 systemd[1]: Mounted /var/spool/cups/tmp. Dec 01 20:17:36 volumio3 systemd-udevd[408]: Network interface NamePolicy= disabled on kernel command line, ignoring. Dec 01 20:17:36 volumio3 systemd-journald[380]: Runtime journal (/run/log/journal/3b9f41731e901aee8cf8cb2a672e4f3b) is 3.7M, max 30.0M, 26.2M free. Dec 01 20:17:36 volumio3 systemd[1]: Started udev Kernel Device Manager. Dec 01 20:17:36 volumio3 systemd[1]: Starting Show Plymouth Boot Screen... Dec 01 20:17:36 volumio3 systemd[1]: Started Flush Journal to Persistent Storage. Dec 01 20:17:36 volumio3 systemd[1]: plymouth-start.service: Succeeded. Dec 01 20:17:36 volumio3 systemd[1]: Started Show Plymouth Boot Screen. Dec 01 20:17:36 volumio3 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Dec 01 20:17:36 volumio3 systemd[1]: Reached target Local Encrypted Volumes. Dec 01 20:17:36 volumio3 systemd[1]: Reached target Paths. Dec 01 20:17:36 volumio3 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Dec 01 20:17:37 volumio3 systemd-udevd[438]: Using default interface naming scheme 'v240'. Dec 01 20:17:37 volumio3 systemd-udevd[438]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 01 20:17:37 volumio3 kernel: rpi-gpiomem 107d508500.gpiomem: window base 0x107d508500 size 0x00000040 Dec 01 20:17:37 volumio3 kernel: rpi-gpiomem 107d508500.gpiomem: initialised 1 regions as /dev/gpiomem1 Dec 01 20:17:37 volumio3 kernel: rpi-gpiomem 107d517c00.gpiomem: window base 0x107d517c00 size 0x00000040 Dec 01 20:17:37 volumio3 kernel: rpi-gpiomem 107d517c00.gpiomem: initialised 1 regions as /dev/gpiomem2 Dec 01 20:17:37 volumio3 kernel: rpi-gpiomem 107d504100.gpiomem: window base 0x107d504100 size 0x00000020 Dec 01 20:17:37 volumio3 kernel: rpi-gpiomem 107d504100.gpiomem: initialised 1 regions as /dev/gpiomem3 Dec 01 20:17:37 volumio3 kernel: rpi-gpiomem 107d510700.gpiomem: window base 0x107d510700 size 0x00000020 Dec 01 20:17:37 volumio3 kernel: rpi-gpiomem 107d510700.gpiomem: initialised 1 regions as /dev/gpiomem4 Dec 01 20:17:37 volumio3 kernel: rpi-gpiomem 1f000d0000.gpiomem: window base 0x1f000d0000 size 0x00030000 Dec 01 20:17:37 volumio3 kernel: rpi-gpiomem 1f000d0000.gpiomem: initialised 1 regions as /dev/gpiomem0 Dec 01 20:17:37 volumio3 systemd[1]: Found device /dev/ttyAMA10. Dec 01 20:17:37 volumio3 systemd-udevd[443]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 01 20:17:37 volumio3 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Dec 01 20:17:37 volumio3 kernel: mc: Linux media interface: v0.10 Dec 01 20:17:37 volumio3 kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Dec 01 20:17:37 volumio3 kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Dec 01 20:17:37 volumio3 kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Dec 01 20:17:37 volumio3 kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Dec 01 20:17:37 volumio3 kernel: Bluetooth: Core ver 2.22 Dec 01 20:17:37 volumio3 kernel: NET: Registered PF_BLUETOOTH protocol family Dec 01 20:17:37 volumio3 kernel: Bluetooth: HCI device and connection manager initialized Dec 01 20:17:37 volumio3 kernel: Bluetooth: HCI socket layer initialized Dec 01 20:17:37 volumio3 kernel: Bluetooth: L2CAP socket layer initialized Dec 01 20:17:37 volumio3 kernel: Bluetooth: SCO socket layer initialized Dec 01 20:17:37 volumio3 systemd-udevd[442]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Dec 01 20:17:37 volumio3 kernel: videodev: Linux video capture interface: v2.00 Dec 01 20:17:37 volumio3 kernel: Bluetooth: HCI UART driver ver 2.3 Dec 01 20:17:37 volumio3 kernel: Bluetooth: HCI UART protocol H4 registered Dec 01 20:17:37 volumio3 kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Dec 01 20:17:37 volumio3 kernel: Bluetooth: HCI UART protocol Broadcom registered Dec 01 20:17:37 volumio3 kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Dec 01 20:17:37 volumio3 kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Dec 01 20:17:37 volumio3 kernel: pispbe 1000880000.pisp_be: bcm2712_iommu_of_xlate: MMU 1000005100.iommu Dec 01 20:17:37 volumio3 kernel: pispbe 1000880000.pisp_be: Runtime PM usage count underflow! Dec 01 20:17:37 volumio3 kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Dec 01 20:17:37 volumio3 kernel: rpivid 1000800000.codec: bcm2712_iommu_of_xlate: MMU 1000005100.iommu Dec 01 20:17:37 volumio3 kernel: rpivid 1000800000.codec: Device registered as /dev/video19 Dec 01 20:17:37 volumio3 kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Dec 01 20:17:37 volumio3 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Dec 01 20:17:37 volumio3 kernel: brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,5-model-b.bin failed with error -2 Dec 01 20:17:37 volumio3 kernel: usbcore: registered new interface driver brcmfmac Dec 01 20:17:37 volumio3 systemd[1]: Found device /dev/disk/by-uuid/FCA2-3F9A. Dec 01 20:17:37 volumio3 systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Dec 01 20:17:37 volumio3 kernel: vc4-drm axi:gpu: bcm2712_iommu_of_xlate: MMU 1000005200.iommu Dec 01 20:17:37 volumio3 kernel: pcm179x 1-004c: No cache used with register defaults set! Dec 01 20:17:37 volumio3 kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Dec 01 20:17:37 volumio3 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 Dec 01 20:17:37 volumio3 kernel: vc4-drm axi:gpu: bound 107c580000.hvs (ops vc4_hvs_ops [vc4]) Dec 01 20:17:37 volumio3 kernel: Registered IR keymap rc-cec Dec 01 20:17:37 volumio3 kernel: rc rc0: vc4-hdmi-0 as /devices/platform/soc/107c701400.hdmi/rc/rc0 Dec 01 20:17:37 volumio3 kernel: input: vc4-hdmi-0 as /devices/platform/soc/107c701400.hdmi/rc/rc0/input1 Dec 01 20:17:37 volumio3 kernel: input: vc4-hdmi-0 HDMI Jack as /devices/platform/soc/107c701400.hdmi/sound/card0/input2 Dec 01 20:17:37 volumio3 systemd[1]: Created slice system-bthelper.slice. Dec 01 20:17:37 volumio3 systemd[1]: Condition check resulted in Huge Pages File System being skipped. Dec 01 20:17:37 volumio3 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Dec 01 20:17:37 volumio3 kernel: vc4-drm axi:gpu: bound 107c701400.hdmi (ops vc4_hdmi_ops [vc4]) Dec 01 20:17:37 volumio3 kernel: Registered IR keymap rc-cec Dec 01 20:17:37 volumio3 kernel: rc rc1: vc4-hdmi-1 as /devices/platform/soc/107c706400.hdmi/rc/rc1 Dec 01 20:17:37 volumio3 kernel: input: vc4-hdmi-1 as /devices/platform/soc/107c706400.hdmi/rc/rc1/input3 Dec 01 20:17:37 volumio3 kernel: input: vc4-hdmi-1 HDMI Jack as /devices/platform/soc/107c706400.hdmi/sound/card1/input4 Dec 01 20:17:37 volumio3 kernel: vc4-drm axi:gpu: bound 107c706400.hdmi (ops vc4_hdmi_ops [vc4]) Dec 01 20:17:37 volumio3 kernel: vc4-drm axi:gpu: bound 107c500000.mop (ops vc4_txp_ops [vc4]) Dec 01 20:17:37 volumio3 kernel: vc4-drm axi:gpu: bound 107c501000.moplet (ops vc4_txp_ops [vc4]) Dec 01 20:17:37 volumio3 kernel: vc4-drm axi:gpu: bound 107c410000.pixelvalve (ops vc4_crtc_ops [vc4]) Dec 01 20:17:37 volumio3 kernel: vc4-drm axi:gpu: bound 107c411000.pixelvalve (ops vc4_crtc_ops [vc4]) Dec 01 20:17:37 volumio3 kernel: snd-rpi-hifiberry-dacplushd soc:sound: ASoC: driver name too long 'HifiberryDacplusHD' -> 'HifiberryDacplu' Dec 01 20:17:37 volumio3 kernel: [drm] Initialized vc4 0.0.0 20140616 for axi:gpu on minor 1 Dec 01 20:17:37 volumio3 kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Dec 01 20:17:37 volumio3 systemd[1]: Starting Show Plymouth Boot Screen... Dec 01 20:17:37 volumio3 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Dec 01 20:17:37 volumio3 systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Dec 01 20:17:37 volumio3 kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Dec 01 20:17:37 volumio3 kernel: vc4-drm axi:gpu: [drm] Cannot find any crtc or sizes Dec 01 20:17:37 volumio3 systemd[1]: Starting File System Check on /dev/disk/by-uuid/FCA2-3F9A... Dec 01 20:17:37 volumio3 systemd-udevd[439]: Using default interface naming scheme 'v240'. Dec 01 20:17:37 volumio3 systemd-udevd[439]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 01 20:17:38 volumio3 kernel: Bluetooth: hci0: BCM: chip id 107 Dec 01 20:17:38 volumio3 kernel: Bluetooth: hci0: BCM: features 0x2f Dec 01 20:17:38 volumio3 kernel: Bluetooth: hci0: BCM4345C0 Dec 01 20:17:38 volumio3 kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Dec 01 20:17:38 volumio3 kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Dec 01 20:17:38 volumio3 systemd-udevd[431]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Dec 01 20:17:38 volumio3 systemd-udevd[422]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Dec 01 20:17:38 volumio3 systemd-udevd[444]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Dec 01 20:17:38 volumio3 systemd-udevd[442]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Dec 01 20:17:38 volumio3 systemd[1]: plymouth-start.service: Succeeded. Dec 01 20:17:38 volumio3 systemd[1]: Started Show Plymouth Boot Screen. Dec 01 20:17:38 volumio3 systemd[1]: Starting Show Plymouth Boot Screen... Dec 01 20:17:38 volumio3 systemd[1]: Started File System Check Daemon to report status. Dec 01 20:17:38 volumio3 systemd[1]: Starting Load/Save RF Kill Switch Status... Dec 01 20:17:38 volumio3 systemd[1]: Condition check resulted in Huge Pages File System being skipped. Dec 01 20:17:38 volumio3 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Dec 01 20:17:38 volumio3 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Dec 01 20:17:38 volumio3 systemd[1]: plymouth-start.service: Succeeded. Dec 01 20:17:38 volumio3 systemd[1]: Started Show Plymouth Boot Screen. Dec 01 20:17:38 volumio3 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Dec 01 20:17:38 volumio3 systemd[1]: Started Load/Save RF Kill Switch Status. Dec 01 20:17:38 volumio3 systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Dec 01 20:17:38 volumio3 kernel: alsactl[675]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Dec 01 20:17:38 volumio3 systemd-fsck[671]: fsck.fat 4.1 (2017-01-24) Dec 01 20:17:38 volumio3 systemd-fsck[671]: /dev/mmcblk0p1: 484 files, 167807/184582 clusters Dec 01 20:17:38 volumio3 systemd[1]: Started File System Check on /dev/disk/by-uuid/FCA2-3F9A. Dec 01 20:17:38 volumio3 systemd[1]: Mounting /boot... Dec 01 20:17:38 volumio3 kernel: Bluetooth: hci0: BCM: features 0x2f Dec 01 20:17:38 volumio3 kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Dec 01 20:17:38 volumio3 kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Dec 01 20:17:38 volumio3 kernel: Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac) Dec 01 20:17:38 volumio3 systemd[1]: Mounted /boot. Dec 01 20:17:38 volumio3 systemd[1]: Reached target Local File Systems. Dec 01 20:17:38 volumio3 systemd[1]: Started ifup for eth0. Dec 01 20:17:38 volumio3 systemd[1]: Starting Create Volatile Files and Directories... Dec 01 20:17:38 volumio3 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Dec 01 20:17:38 volumio3 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Dec 01 20:17:38 volumio3 systemd[1]: Starting Raise network interfaces... Dec 01 20:17:38 volumio3 systemd[1]: Starting Preprocess NFS configuration... Dec 01 20:17:38 volumio3 systemd[1]: Started ifup for wlan0. Dec 01 20:17:38 volumio3 systemd[1]: Received SIGRTMIN+20 from PID 263 (plymouthd). Dec 01 20:17:38 volumio3 systemd[1]: plymouth-read-write.service: Succeeded. Dec 01 20:17:38 volumio3 systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Dec 01 20:17:38 volumio3 systemd[1]: nfs-config.service: Succeeded. Dec 01 20:17:38 volumio3 systemd[1]: Started Preprocess NFS configuration. Dec 01 20:17:38 volumio3 systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Dec 01 20:17:38 volumio3 systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Dec 01 20:17:38 volumio3 systemd[1]: Reached target NFS client services. Dec 01 20:17:38 volumio3 systemd[1]: Started Create Volatile Files and Directories. Dec 01 20:17:38 volumio3 kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Dec 01 20:17:38 volumio3 systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Dec 01 20:17:38 volumio3 systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Dec 01 20:17:38 volumio3 systemd[1]: Starting RPC bind portmap service... Dec 01 20:17:38 volumio3 systemd[1]: Starting Update UTMP about System Boot/Shutdown... Dec 01 20:17:38 volumio3 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 01 20:17:38 volumio3 systemd[1]: Started Update UTMP about System Boot/Shutdown. Dec 01 20:17:38 volumio3 systemd[1]: Reached target System Initialization. Dec 01 20:17:38 volumio3 systemd[1]: Started Daily Cleanup of Temporary Directories. Dec 01 20:17:38 volumio3 systemd[1]: Listening on D-Bus System Message Bus Socket. Dec 01 20:17:38 volumio3 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Dec 01 20:17:38 volumio3 systemd[1]: Listening on triggerhappy.socket. Dec 01 20:17:38 volumio3 systemd[1]: Reached target Sockets. Dec 01 20:17:38 volumio3 systemd[1]: Reached target Basic System. Dec 01 20:17:38 volumio3 kernel: 8021q: 802.1Q VLAN Support v1.8 Dec 01 20:17:38 volumio3 systemd[1]: Starting Wireless Services... Dec 01 20:17:38 volumio3 systemd[1]: Starting Volumio Time Update Utility... Dec 01 20:17:38 volumio3 systemd[1]: Started Volumio Log Rotation Service. Dec 01 20:17:38 volumio3 systemd[1]: Started UPnP Renderer front-end to MPD. Dec 01 20:17:38 volumio3 systemd[1]: Started Volumio Iptables Module. Dec 01 20:17:38 volumio3 systemd[1]: Starting triggerhappy global hotkey daemon... Dec 01 20:17:38 volumio3 systemd[1]: Started D-Bus System Message Bus. Dec 01 20:17:38 volumio3 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Dec 01 20:17:38 volumio3 volumio-time-update[787]: volumio-time-update-util: Fetching time from Volumio... Dec 01 20:17:38 volumio3 systemd[1]: Started Daily apt download activities. Dec 01 20:17:38 volumio3 systemd[1]: Started Daily apt upgrade and clean activities. Dec 01 20:17:38 volumio3 systemd[1]: Reached target Timers. Dec 01 20:17:38 volumio3 systemd[1]: Started volumio-remote-updater.service. Dec 01 20:17:38 volumio3 systemd[1]: Started MPD Monitor Service. Dec 01 20:17:38 volumio3 systemd[1]: Starting Login Service... Dec 01 20:17:38 volumio3 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Dec 01 20:17:38 volumio3 systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Dec 01 20:17:38 volumio3 systemd[1]: Condition check resulted in Configure Bluetooth Modems connected by UART being skipped. Dec 01 20:17:38 volumio3 systemd[1]: Starting Raspberry Pi bluetooth helper... Dec 01 20:17:38 volumio3 systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Dec 01 20:17:38 volumio3 systemd[1]: Starting dhcpcd on all interfaces... Dec 01 20:17:38 volumio3 systemd[1]: Started Manage Sound Card State (restore and store). Dec 01 20:17:38 volumio3 systemd[1]: Starting Save/Restore Sound Card State... Dec 01 20:17:38 volumio3 systemd[1]: Starting WPA supplicant... Dec 01 20:17:38 volumio3 alsactl[820]: alsactl 1.1.8 daemon started Dec 01 20:17:39 volumio3 dhcpcd[819]: Not running dhcpcd because /etc/network/interfaces Dec 01 20:17:39 volumio3 dhcpcd[819]: defines some interfaces that will use a Dec 01 20:17:39 volumio3 dhcpcd[819]: DHCP client or static address Dec 01 20:17:39 volumio3 thd[791]: Unable to parse trigger line: Dec 01 20:17:39 volumio3 thd[791]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Dec 01 20:17:39 volumio3 thd[791]: Unable to parse trigger line: Dec 01 20:17:39 volumio3 thd[791]: Found socket passed from systemd Dec 01 20:17:39 volumio3 systemd-logind[817]: New seat seat0. Dec 01 20:17:39 volumio3 alsactl[821]: No state is present for card sndrpihifiberry Dec 01 20:17:39 volumio3 systemd-logind[817]: Watching system buttons on /dev/input/event0 (pwr_button) Dec 01 20:17:39 volumio3 systemd-logind[817]: Watching system buttons on /dev/input/event1 (vc4-hdmi-0) Dec 01 20:17:39 volumio3 systemd-logind[817]: Watching system buttons on /dev/input/event3 (vc4-hdmi-1) Dec 01 20:17:39 volumio3 alsactl[821]: Found hardware: "HifiberryDacplu" "" "" "" "" Dec 01 20:17:39 volumio3 alsactl[821]: Hardware is initialized using a generic method Dec 01 20:17:39 volumio3 alsactl[821]: No state is present for card sndrpihifiberry Dec 01 20:17:39 volumio3 bthelper[818]: Raspberry Pi BDADDR already set Dec 01 20:17:39 volumio3 mpd_monitor.sh[816]: MPD error: Connection refused Dec 01 20:17:39 volumio3 mpd_monitor.sh[816]: mpd: no process found Dec 01 20:17:39 volumio3 avahi-daemon[795]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Dec 01 20:17:39 volumio3 avahi-daemon[795]: Successfully dropped root privileges. Dec 01 20:17:39 volumio3 avahi-daemon[795]: avahi-daemon 0.7 starting up. Dec 01 20:17:39 volumio3 haveged[742]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Dec 01 20:17:39 volumio3 haveged[742]: haveged: cpu: (VC); data: 64K (V); inst: 64K (V); idx: 39/40; sz: 63488/63488 Dec 01 20:17:39 volumio3 haveged[742]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00213 Dec 01 20:17:39 volumio3 haveged[742]: haveged: fills: 0, generated: 0 Dec 01 20:17:39 volumio3 kernel: macb 1f00100000.ethernet eth0: PHY [1f00100000.ethernet-ffffffff:01] driver [Broadcom BCM54213PE] (irq=POLL) Dec 01 20:17:39 volumio3 kernel: macb 1f00100000.ethernet eth0: configuring for phy/rgmii-id link mode Dec 01 20:17:39 volumio3 sh[703]: eth0: waiting for carrier Dec 01 20:17:39 volumio3 dhcpcd[753]: eth0: waiting for carrier Dec 01 20:17:39 volumio3 kernel: pps pps0: new PPS source ptp0 Dec 01 20:17:39 volumio3 kernel: macb 1f00100000.ethernet: gem-ptp-timer ptp clock registered. Dec 01 20:17:40 volumio3 alsactl[820]: No state is present for card sndrpihifiberry Dec 01 20:17:40 volumio3 alsactl[820]: Found hardware: "HifiberryDacplu" "" "" "" "" Dec 01 20:17:40 volumio3 alsactl[820]: Hardware is initialized using a generic method Dec 01 20:17:40 volumio3 alsactl[820]: No state is present for card sndrpihifiberry Dec 01 20:17:40 volumio3 systemd-udevd[443]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 2' failed with exit code 99. Dec 01 20:17:40 volumio3 systemd[1]: Started triggerhappy global hotkey daemon. Dec 01 20:17:40 volumio3 systemd[1]: Started RPC bind portmap service. Dec 01 20:17:40 volumio3 systemd[1]: Started Raspberry Pi bluetooth helper. Dec 01 20:17:40 volumio3 systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Dec 01 20:17:40 volumio3 systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Dec 01 20:17:40 volumio3 systemd[1]: Failed to start dhcpcd on all interfaces. Dec 01 20:17:40 volumio3 systemd[1]: Started Save/Restore Sound Card State. Dec 01 20:17:40 volumio3 avahi-daemon[795]: Successfully called chroot(). Dec 01 20:17:40 volumio3 avahi-daemon[795]: Successfully dropped remaining capabilities. Dec 01 20:17:40 volumio3 wpa_supplicant[822]: Successfully initialized wpa_supplicant Dec 01 20:17:40 volumio3 systemd[1]: Started Login Service. Dec 01 20:17:40 volumio3 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Dec 01 20:17:40 volumio3 systemd[1]: Started WPA supplicant. Dec 01 20:17:40 volumio3 sh[713]: wlan0=wlan0 Dec 01 20:17:40 volumio3 avahi-daemon[795]: Loading service file /services/volumio.service. Dec 01 20:17:40 volumio3 systemd[1]: Listening on mpd.socket. Dec 01 20:17:40 volumio3 systemd[1]: Reached target Sound Card. Dec 01 20:17:40 volumio3 systemd[1]: Starting Bluetooth service... Dec 01 20:17:40 volumio3 systemd[1]: Reached target RPC Port Mapper. Dec 01 20:17:40 volumio3 systemd[1]: Reached target Remote File Systems (Pre). Dec 01 20:17:40 volumio3 systemd[1]: Reached target Remote File Systems. Dec 01 20:17:40 volumio3 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Dec 01 20:17:40 volumio3 systemd[1]: Started Raise network interfaces. Dec 01 20:17:40 volumio3 systemd[1]: Reached target Network. Dec 01 20:17:40 volumio3 systemd[1]: Starting Music Player Daemon... Dec 01 20:17:40 volumio3 systemd[1]: Starting Permit User Sessions... Dec 01 20:17:40 volumio3 avahi-daemon[795]: Network interface enumeration completed. Dec 01 20:17:40 volumio3 systemd[1]: Starting Network Time Service... Dec 01 20:17:40 volumio3 systemd[1]: Reached target Network is Online. Dec 01 20:17:40 volumio3 systemd[1]: Starting LSB: Brings up/down network automatically... Dec 01 20:17:40 volumio3 systemd[1]: Starting /etc/rc.local Compatibility... Dec 01 20:17:40 volumio3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 01 20:17:40 volumio3 avahi-daemon[795]: Server startup complete. Host name is volumio3.local. Local service cookie is 29935618. Dec 01 20:17:40 volumio3 avahi-daemon[795]: Service "Volumio3" (/services/volumio.service) successfully established. Dec 01 20:17:40 volumio3 systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Dec 01 20:17:40 volumio3 systemd[1]: Starting Samba NMB Daemon... Dec 01 20:17:40 volumio3 systemd[1]: Started Permit User Sessions. Dec 01 20:17:40 volumio3 systemd[1]: Started /etc/rc.local Compatibility. Dec 01 20:17:40 volumio3 systemd[1]: Starting Hold until boot process finishes up... Dec 01 20:17:40 volumio3 systemd[1]: Starting Terminate Plymouth Boot Screen... Dec 01 20:17:40 volumio3 volumio-remote-updater[812]: Error: No active session Dec 01 20:17:40 volumio3 volumio-remote-updater[812]: [2024-12-01 20:17:40] [info] asio async_connect error: system:111 (Connection refused) Dec 01 20:17:40 volumio3 volumio-remote-updater[812]: [2024-12-01 20:17:40] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 01 20:17:40 volumio3 volumio-remote-updater[812]: [2024-12-01 20:17:40] [error] handle_connect error: Underlying Transport Error Dec 01 20:17:40 volumio3 systemd[1]: Received SIGRTMIN+21 from PID 263 (plymouthd). Dec 01 20:17:40 volumio3 systemd[1]: Received SIGRTMIN+21 from PID 263 (plymouthd). Dec 01 20:17:40 volumio3 systemd[1]: plymouth-quit-wait.service: Succeeded. Dec 01 20:17:40 volumio3 systemd[1]: Started Hold until boot process finishes up. Dec 01 20:17:40 volumio3 systemd[1]: plymouth-quit.service: Succeeded. Dec 01 20:17:40 volumio3 systemd[1]: Started Terminate Plymouth Boot Screen. Dec 01 20:17:40 volumio3 systemd[1]: Started Serial Getty on ttyAMA10. Dec 01 20:17:40 volumio3 systemd[1]: Started Getty on tty1. Dec 01 20:17:40 volumio3 systemd[1]: Reached target Login Prompts. Dec 01 20:17:40 volumio3 volumio[789]: Could not open config: /tmp/upmpdcli.conf Dec 01 20:17:40 volumio3 volumio-time-update[787]: volumio-time-update-util: Date not found in response Dec 01 20:17:40 volumio3 volumio-time-update[787]: volumio-time-update-util: Retrying in 5 seconds... Dec 01 20:17:40 volumio3 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 01 20:17:40 volumio3 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 01 20:17:41 volumio3 systemd[1]: iptables.service: Succeeded. Dec 01 20:17:41 volumio3 ifplugd(eth0)[936]: ifplugd 0.28 initializing. Dec 01 20:17:41 volumio3 ifplugd(eth0)[936]: Using interface eth0/2C:CF:67:40:3E:C3 with driver (version: 6.6.56-v8+) Dec 01 20:17:41 volumio3 ifplugd(eth0)[936]: Using detection mode: SIOCETHTOOL Dec 01 20:17:41 volumio3 ifplugd(eth0)[936]: Initialization complete, link beat not detected. Dec 01 20:17:41 volumio3 ntpd[899]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 01 20:17:41 volumio3 ntpd[899]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Dec 01 20:17:41 volumio3 ntpd[938]: proto: precision = 0.555 usec (-21) Dec 01 20:17:41 volumio3 systemd[1]: Started Network Time Service. Dec 01 20:17:41 volumio3 ntpd[938]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 01 20:17:41 volumio3 ntpd[938]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 01 20:17:41 volumio3 ntpd[938]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): will expire in less than 27 days Dec 01 20:17:41 volumio3 ifplugd[900]: Network Interface Plugging Daemon...start eth0...done. Dec 01 20:17:41 volumio3 systemd[1]: Started LSB: Brings up/down network automatically. Dec 01 20:17:41 volumio3 ntpd[938]: Listen and drop on 0 v6wildcard [::]:123 Dec 01 20:17:41 volumio3 ntpd[938]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 01 20:17:41 volumio3 ntpd[938]: Listen normally on 2 lo 127.0.0.1:123 Dec 01 20:17:41 volumio3 ntpd[938]: Listening on routing socket on fd #19 for interface updates Dec 01 20:17:41 volumio3 ntpd[938]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 01 20:17:41 volumio3 ntpd[938]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 01 20:17:41 volumio3 bluetoothd[892]: Bluetooth daemon 5.50 Dec 01 20:17:41 volumio3 loadcpufreq[893]: Loading cpufreq kernel modules...done (none). Dec 01 20:17:41 volumio3 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Dec 01 20:17:41 volumio3 systemd[1]: Starting LSB: set CPUFreq kernel parameters... Dec 01 20:17:41 volumio3 bluetoothd[892]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Dec 01 20:17:41 volumio3 bluetoothd[892]: Starting SDP server Dec 01 20:17:41 volumio3 bluetoothd[892]: Excluding (cli) sap Dec 01 20:17:41 volumio3 systemd[1]: Started Bluetooth service. Dec 01 20:17:41 volumio3 systemd[1]: Reached target Bluetooth. Dec 01 20:17:41 volumio3 bluetoothd[892]: Bluetooth management interface 1.22 initialized Dec 01 20:17:41 volumio3 dbus-daemon[792]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=892 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Dec 01 20:17:41 volumio3 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Dec 01 20:17:41 volumio3 kernel: Bluetooth: BNEP filters: protocol multicast Dec 01 20:17:41 volumio3 kernel: Bluetooth: BNEP socket layer initialized Dec 01 20:17:41 volumio3 kernel: Bluetooth: MGMT ver 1.22 Dec 01 20:17:41 volumio3 cpufrequtils[963]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Dec 01 20:17:41 volumio3 bluetoothd[892]: Failed to set privacy: Rejected (0x0b) Dec 01 20:17:41 volumio3 sudo[897]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 01 20:17:41 volumio3 sudo[897]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:41 volumio3 sudo[897]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 01 20:17:41 volumio3 sudo[897]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:41 volumio3 systemd[1]: Started LSB: set CPUFreq kernel parameters. Dec 01 20:17:41 volumio3 systemd[1]: Starting Hostname Service... Dec 01 20:17:41 volumio3 dbus-daemon[792]: [system] Successfully activated service 'org.freedesktop.hostname1' Dec 01 20:17:41 volumio3 systemd[1]: Started Hostname Service. Dec 01 20:17:42 volumio3 ntpd[938]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 01 20:17:42 volumio3 nmbd[906]: [2024/12/01 20:17:42.558668, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Dec 01 20:17:42 volumio3 nmbd[906]: started asyncdns process 985 Dec 01 20:17:42 volumio3 nmbd[906]: [2024/12/01 20:17:42.559440, 0] ../lib/util/become_daemon.c:149(daemon_status) Dec 01 20:17:42 volumio3 nmbd[906]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Dec 01 20:17:42 volumio3 nmbd[906]: [2024/12/01 20:17:42.559481, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Dec 01 20:17:42 volumio3 nmbd[906]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Dec 01 20:17:42 volumio3 wireless.js[782]: Cleaning previous... Dec 01 20:17:42 volumio3 sudo[993]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 01 20:17:42 volumio3 sudo[993]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:42 volumio3 sudo[993]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:42 volumio3 sudo[995]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 01 20:17:42 volumio3 sudo[995]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:43 volumio3 ntpd[938]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 01 20:17:43 volumio3 systemd[1]: systemd-rfkill.service: Succeeded. Dec 01 20:17:43 volumio3 sudo[995]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:43 volumio3 wireless.js[782]: Stopped aP Dec 01 20:17:43 volumio3 sudo[1002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 01 20:17:43 volumio3 sudo[1002]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:43 volumio3 sudo[1002]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:43 volumio3 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 01 20:17:43 volumio3 sudo[1004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 01 20:17:43 volumio3 sudo[1004]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:43 volumio3 sudo[1004]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:43 volumio3 sudo[1011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 01 20:17:43 volumio3 sudo[1011]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:43 volumio3 sudo[1011]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:43 volumio3 sudo[1013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 01 20:17:43 volumio3 sudo[1013]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:44 volumio3 kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control tx Dec 01 20:17:44 volumio3 sh[703]: eth0: carrier acquired Dec 01 20:17:44 volumio3 dhcpcd[753]: eth0: carrier acquired Dec 01 20:17:44 volumio3 sh[703]: DUID 00:01:00:01:2e:c1:0d:88:2c:cf:67:40:3e:c3 Dec 01 20:17:44 volumio3 sh[703]: eth0: IAID 67:40:3e:c3 Dec 01 20:17:44 volumio3 dhcpcd[753]: DUID 00:01:00:01:2e:c1:0d:88:2c:cf:67:40:3e:c3 Dec 01 20:17:44 volumio3 dhcpcd[753]: eth0: IAID 67:40:3e:c3 Dec 01 20:17:44 volumio3 sh[703]: eth0: adding address fe80::f1e7:ccfe:c1e0:6c8a Dec 01 20:17:44 volumio3 dhcpcd[753]: eth0: adding address fe80::f1e7:ccfe:c1e0:6c8a Dec 01 20:17:44 volumio3 dhcpcd[753]: ipv6_addaddr1: Permission denied Dec 01 20:17:44 volumio3 sh[703]: ipv6_addaddr1: Permission denied Dec 01 20:17:44 volumio3 ntpd[938]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 01 20:17:44 volumio3 mpd[982]: Dec 01 20:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 01 20:17:44 volumio3 systemd[1]: Started Music Player Daemon. Dec 01 20:17:44 volumio3 mpd_monitor.sh[816]: MPD restarted due to no mpc output. Dec 01 20:17:44 volumio3 ifplugd(eth0)[936]: Link beat detected. Dec 01 20:17:44 volumio3 bthelper[818]: Changing power off succeeded Dec 01 20:17:44 volumio3 ifplugd(eth0)[936]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Dec 01 20:17:44 volumio3 ifplugd(eth0)[936]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Dec 01 20:17:44 volumio3 bthelper[818]: [CHG] Controller 2C:CF:67:40:3E:C5 Class: 0x0000041c Dec 01 20:17:44 volumio3 bthelper[818]: Changing power on succeeded Dec 01 20:17:44 volumio3 sh[703]: eth0: soliciting an IPv6 router Dec 01 20:17:44 volumio3 dhcpcd[753]: eth0: soliciting an IPv6 router Dec 01 20:17:44 volumio3 sh[703]: eth0: soliciting a DHCP lease Dec 01 20:17:44 volumio3 dhcpcd[753]: eth0: soliciting a DHCP lease Dec 01 20:17:44 volumio3 sh[703]: eth0: offered 10.0.112.105 from 10.0.112.1 Dec 01 20:17:44 volumio3 dhcpcd[753]: eth0: offered 10.0.112.105 from 10.0.112.1 Dec 01 20:17:44 volumio3 sh[703]: eth0: probing address 10.0.112.105/24 Dec 01 20:17:44 volumio3 dhcpcd[753]: eth0: probing address 10.0.112.105/24 Dec 01 20:17:45 volumio3 ntpd[938]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 01 20:17:45 volumio3 volumio-remote-updater[812]: [2024-12-01 20:17:45] [info] asio async_connect error: system:111 (Connection refused) Dec 01 20:17:45 volumio3 volumio-remote-updater[812]: [2024-12-01 20:17:45] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 01 20:17:45 volumio3 volumio-remote-updater[812]: [2024-12-01 20:17:45] [error] handle_connect error: Underlying Transport Error Dec 01 20:17:45 volumio3 volumio-time-update[787]: volumio-time-update-util: Fetching time from Volumio... Dec 01 20:17:45 volumio3 volumio-time-update[787]: volumio-time-update-util: Date not found in response Dec 01 20:17:45 volumio3 volumio-time-update[787]: volumio-time-update-util: Retrying in 5 seconds... Dec 01 20:17:46 volumio3 sudo[1013]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:46 volumio3 wireless.js[782]: SETTING APPROPRIATE REG DOMAIN: US Dec 01 20:17:46 volumio3 sudo[1033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 01 20:17:46 volumio3 sudo[1033]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:46 volumio3 sudo[1033]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:46 volumio3 sudo[1035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Dec 01 20:17:46 volumio3 sudo[1035]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:46 volumio3 sudo[1035]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:46 volumio3 wireless.js[782]: SUCCESSFULLY SET NEW REGDOMAIN: US Dec 01 20:17:46 volumio3 sudo[1042]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 01 20:17:46 volumio3 sudo[1042]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:46 volumio3 sudo[1042]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:46 volumio3 sudo[1044]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 01 20:17:46 volumio3 sudo[1044]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:46 volumio3 sudo[1044]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:46 volumio3 avahi-daemon[795]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 01 20:17:46 volumio3 avahi-daemon[795]: New relevant interface wlan0.IPv4 for mDNS. Dec 01 20:17:46 volumio3 avahi-daemon[795]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Dec 01 20:17:46 volumio3 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 01 20:17:46 volumio3 systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Dec 01 20:17:46 volumio3 systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Dec 01 20:17:46 volumio3 dnsmasq[1051]: dnsmasq: syntax check OK. Dec 01 20:17:46 volumio3 hostapd[1050]: Configuration file: /etc/hostapd/hostapd.conf Dec 01 20:17:46 volumio3 hostapd[1050]: wlan0: Could not connect to kernel driver Dec 01 20:17:46 volumio3 hostapd[1050]: Using interface wlan0 with hwaddr 2c:cf:67:40:3e:c4 and ssid "Volumio-B4EBA" Dec 01 20:17:46 volumio3 dnsmasq[1057]: started, version 2.80 cachesize 150 Dec 01 20:17:46 volumio3 dnsmasq[1057]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Dec 01 20:17:46 volumio3 dnsmasq-dhcp[1057]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Dec 01 20:17:46 volumio3 dnsmasq[1057]: reading /etc/resolv.conf Dec 01 20:17:46 volumio3 dnsmasq[1057]: using nameserver 208.67.222.222#53 Dec 01 20:17:46 volumio3 dnsmasq[1057]: using nameserver 208.67.220.220#53 Dec 01 20:17:46 volumio3 dnsmasq[1057]: read /etc/hosts - 1 addresses Dec 01 20:17:46 volumio3 systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Dec 01 20:17:46 volumio3 systemd[1]: Reached target Host and Network Name Lookups. Dec 01 20:17:46 volumio3 hostapd[1050]: wlan0: interface state UNINITIALIZED->ENABLED Dec 01 20:17:46 volumio3 hostapd[1050]: wlan0: AP-ENABLED Dec 01 20:17:46 volumio3 systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Dec 01 20:17:46 volumio3 iwconfig[1065]: Error for wireless request "Set Power Management" (8B2C) : Dec 01 20:17:46 volumio3 iwconfig[1065]: SET failed on device wlan0 ; Invalid argument. Dec 01 20:17:46 volumio3 systemd[1]: wireless.service: Succeeded. Dec 01 20:17:46 volumio3 systemd[1]: Started Wireless Services. Dec 01 20:17:46 volumio3 systemd[1]: Started Volumio Backend Module. Dec 01 20:17:46 volumio3 systemd[1]: Started Volumio Cpu Tweaker. Dec 01 20:17:46 volumio3 volumio-cpu-tweak[1067]: Setting RT Priority for mpd Dec 01 20:17:46 volumio3 volumio-cpu-tweak[1067]: Setting MPD Affinity Dec 01 20:17:46 volumio3 volumio-cpu-tweak[1067]: pid 982's current affinity mask: f Dec 01 20:17:46 volumio3 volumio-cpu-tweak[1067]: pid 982's new affinity mask: 3 Dec 01 20:17:46 volumio3 volumio-cpu-tweak[1067]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Dec 01 20:17:46 volumio3 volumio-cpu-tweak[1067]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Dec 01 20:17:46 volumio3 systemd[1]: volumio_cpu_tweak.service: Succeeded. Dec 01 20:17:47 volumio3 nmbd[906]: [2024/12/01 20:17:47.562038, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 01 20:17:47 volumio3 nmbd[906]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Dec 01 20:17:47 volumio3 systemd[1]: Started Samba NMB Daemon. Dec 01 20:17:47 volumio3 systemd[1]: Starting Samba Winbind Daemon... Dec 01 20:17:47 volumio3 winbindd[1083]: [2024/12/01 20:17:47.723766, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Dec 01 20:17:47 volumio3 winbindd[1083]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 01 20:17:47 volumio3 winbindd[1083]: [2024/12/01 20:17:47.828048, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 01 20:17:47 volumio3 systemd[1]: Started Samba Winbind Daemon. Dec 01 20:17:47 volumio3 winbindd[1083]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Dec 01 20:17:47 volumio3 systemd[1]: Starting Samba SMB Daemon... Dec 01 20:17:48 volumio3 ntpd[938]: Listen normally on 3 wlan0 192.168.211.1:123 Dec 01 20:17:48 volumio3 ntpd[938]: new interface(s) found: waking up resolver Dec 01 20:17:48 volumio3 winbindd[1083]: [2024/12/01 20:17:48.258782, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Dec 01 20:17:48 volumio3 winbindd[1083]: res_names->count = 3, expected 4 Dec 01 20:17:48 volumio3 smbd[1088]: [2024/12/01 20:17:48.296462, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 01 20:17:48 volumio3 systemd[1]: Started Samba SMB Daemon. Dec 01 20:17:48 volumio3 smbd[1088]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Dec 01 20:17:48 volumio3 volumio[1066]: info: ------------------------------------------- Dec 01 20:17:48 volumio3 volumio[1066]: info: ----- Volumio3 ---- Dec 01 20:17:48 volumio3 volumio[1066]: info: ------------------------------------------- Dec 01 20:17:48 volumio3 volumio[1066]: info: ----- System startup ---- Dec 01 20:17:48 volumio3 volumio[1066]: info: ------------------------------------------- Dec 01 20:17:48 volumio3 volumio[1066]: info: MYVOLUMIO Environment detected Dec 01 20:17:48 volumio3 volumio[1066]: info: Plugin folders cleanup Dec 01 20:17:48 volumio3 volumio[1066]: info: Scanning into folder /volumio/app/plugins/ Dec 01 20:17:48 volumio3 volumio[1066]: info: Scanning category audio_interface Dec 01 20:17:48 volumio3 volumio[1066]: info: Scanning category miscellanea Dec 01 20:17:48 volumio3 volumio[1066]: info: Scanning category music_service Dec 01 20:17:48 volumio3 volumio[1066]: info: Scanning category plugins.json Dec 01 20:17:48 volumio3 volumio[1066]: info: Scanning category system_controller Dec 01 20:17:48 volumio3 volumio[1066]: info: Scanning category user_interface Dec 01 20:17:48 volumio3 volumio[1066]: info: Scanning into folder /data/plugins/ Dec 01 20:17:48 volumio3 volumio[1066]: info: Plugin folders cleanup completed Dec 01 20:17:48 volumio3 volumio[1066]: info: ------------------------------------------- Dec 01 20:17:48 volumio3 volumio[1066]: info: ----- Core plugins startup ---- Dec 01 20:17:48 volumio3 volumio[1066]: info: ------------------------------------------- Dec 01 20:17:48 volumio3 volumio[1066]: info: Loading plugins from folder /volumio/app/plugins/ Dec 01 20:17:48 volumio3 volumio[1066]: info: Adding plugin upnp to MyMusic Plugins Dec 01 20:17:48 volumio3 volumio[1066]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 01 20:17:48 volumio3 volumio[1066]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 01 20:17:48 volumio3 volumio[1066]: info: Loading plugins from folder /data/plugins/ Dec 01 20:17:48 volumio3 volumio[1066]: info: Loading plugin "system"... Dec 01 20:17:48 volumio3 volumio[1066]: info: Loading plugin "appearance"... Dec 01 20:17:49 volumio3 volumio[1066]: info: Loading plugin "network"... Dec 01 20:17:49 volumio3 volumio[1066]: info: Refreshing Cached IP Addresses Dec 01 20:17:49 volumio3 sudo[1110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 01 20:17:49 volumio3 sudo[1110]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:49 volumio3 sudo[1110]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:49 volumio3 sudo[1112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 01 20:17:49 volumio3 sudo[1112]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:49 volumio3 sudo[1112]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:49 volumio3 volumio[1066]: info: Loading plugin "services"... Dec 01 20:17:49 volumio3 volumio[1066]: info: Loading plugin "alsa_controller"... Dec 01 20:17:49 volumio3 sudo[1125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 01 20:17:49 volumio3 sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:49 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 20:17:49 volumio3 volumio[1066]: info: Loading plugin "wizard"... Dec 01 20:17:49 volumio3 volumio[1066]: info: Loading plugin "networkfs"... Dec 01 20:17:49 volumio3 volumio[1066]: info: Starting Udev Watcher for removable devices Dec 01 20:17:49 volumio3 sudo[1138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=Fortnite2!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.0.112.41/music /mnt/NAS/Bento Dec 01 20:17:49 volumio3 sudo[1138]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:49 volumio3 volumio[1066]: info: Ignoring mount for partition: boot Dec 01 20:17:49 volumio3 volumio[1066]: info: Ignoring mount for partition: volumio Dec 01 20:17:49 volumio3 volumio[1066]: info: Ignoring mount for partition: volumio_data Dec 01 20:17:49 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 01 20:17:49 volumio3 volumio[1066]: info: Loading plugin "volumio_command_line_client"... Dec 01 20:17:49 volumio3 volumio[1066]: info: Loading plugin "upnp"... Dec 01 20:17:49 volumio3 volumio[1066]: info: [1733080669514] Starting Upmpd Daemon Dec 01 20:17:49 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 01 20:17:49 volumio3 volumio[1066]: info: Loading plugin "my_music"... Dec 01 20:17:49 volumio3 volumio[1066]: info: Loading plugin "mpd"... Dec 01 20:17:49 volumio3 kernel: Key type cifs.spnego registered Dec 01 20:17:49 volumio3 kernel: Key type cifs.idmap registered Dec 01 20:17:49 volumio3 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. Dec 01 20:17:49 volumio3 kernel: CIFS: Attempting to mount //10.0.112.41/music Dec 01 20:17:49 volumio3 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Dec 01 20:17:49 volumio3 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Dec 01 20:17:49 volumio3 sudo[1138]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:49 volumio3 volumio[1066]: info: Loading plugin "upnp_browser"... Dec 01 20:17:49 volumio3 volumio[1066]: info: Loading plugin "alarm-clock"... Dec 01 20:17:49 volumio3 volumio[1066]: info: Loading plugin "airplay_emulation"... Dec 01 20:17:49 volumio3 volumio[1066]: info: Starting Shairport Sync Dec 01 20:17:49 volumio3 volumio[1066]: info: Loading plugin "last_100"... Dec 01 20:17:49 volumio3 volumio[1066]: info: Loading plugin "webradio"... Dec 01 20:17:49 volumio3 volumio[1066]: info: Loading plugin "i2s_dacs"... Dec 01 20:17:49 volumio3 volumio[1066]: info: Loading plugin "volumiodiscovery"... Dec 01 20:17:49 volumio3 volumio[1066]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 01 20:17:49 volumio3 volumio[1066]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 01 20:17:49 volumio3 volumio[1066]: *** WARNING *** For more information see Dec 01 20:17:49 volumio3 volumio[1066]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 01 20:17:49 volumio3 volumio[1066]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 01 20:17:49 volumio3 volumio[1066]: *** WARNING *** For more information see Dec 01 20:17:49 volumio3 node[1066]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 01 20:17:49 volumio3 node[1066]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 01 20:17:49 volumio3 node[1066]: *** WARNING *** For more information see Dec 01 20:17:49 volumio3 node[1066]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 01 20:17:49 volumio3 node[1066]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 01 20:17:49 volumio3 node[1066]: *** WARNING *** For more information see Dec 01 20:17:49 volumio3 volumio[1066]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 01 20:17:49 volumio3 volumio[1066]: info: Discovery: Started advertising with name: Volumio3 Dec 01 20:17:49 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 01 20:17:49 volumio3 volumio[1066]: info: Loading plugin "outputs"... Dec 01 20:17:49 volumio3 volumio[1066]: info: Loading plugin "albumart"... Dec 01 20:17:50 volumio3 volumio[1066]: info: Plugin example_plugin is not enabled Dec 01 20:17:50 volumio3 volumio[1066]: info: Loading plugin "inputs"... Dec 01 20:17:50 volumio3 volumio[1066]: info: Loading plugin "updater_comm"... Dec 01 20:17:50 volumio3 volumio[1066]: Forking 3 albumart workers Dec 01 20:17:50 volumio3 volumio[1066]: info: Plugin mpdemulation is not enabled Dec 01 20:17:50 volumio3 volumio[1066]: info: Loading plugin "rest_api"... Dec 01 20:17:50 volumio3 volumio[1066]: info: Loading plugin "websocket"... Dec 01 20:17:50 volumio3 volumio[1066]: info: Starting Socket.io Server version 2.3.0 Dec 01 20:17:50 volumio3 volumio[1066]: info: Loading i18n strings for locale en Dec 01 20:17:50 volumio3 volumio[1066]: Updating browse sources language Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::initPlayerControls Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: Express server listening on port 3000 Dec 01 20:17:50 volumio3 volumio[1066]: [Metrics] WebUI: 2s 558.32ms Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreStateMachine::resetVolumioState Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreStateMachine::getcurrentVolume Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::volumioRetrievevolume Dec 01 20:17:50 volumio3 volumio[1066]: info: Volumio Network Manager: Network status updated: 0 Dec 01 20:17:50 volumio3 volumio[1066]: info: Cannot mount NAS Bento at system boot, trial number 1 ,retrying in 5 seconds Dec 01 20:17:50 volumio3 volumio[1066]: Starting albumart workers Dec 01 20:17:50 volumio3 sh[703]: eth0: leased 10.0.112.105 for 4000 seconds Dec 01 20:17:50 volumio3 dhcpcd[753]: eth0: leased 10.0.112.105 for 4000 seconds Dec 01 20:17:50 volumio3 avahi-daemon[795]: Joining mDNS multicast group on interface eth0.IPv4 with address 10.0.112.105. Dec 01 20:17:50 volumio3 sh[703]: eth0: adding route to 10.0.112.0/24 Dec 01 20:17:50 volumio3 sh[703]: eth0: adding default route via 10.0.112.1 Dec 01 20:17:50 volumio3 dhcpcd[753]: eth0: adding route to 10.0.112.0/24 Dec 01 20:17:50 volumio3 avahi-daemon[795]: New relevant interface eth0.IPv4 for mDNS. Dec 01 20:17:50 volumio3 dhcpcd[753]: eth0: adding default route via 10.0.112.1 Dec 01 20:17:50 volumio3 avahi-daemon[795]: Registering new address record for 10.0.112.105 on eth0.IPv4. Dec 01 20:17:50 volumio3 volumio[1066]: Starting albumart workers Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: Reloading queue from file Dec 01 20:17:50 volumio3 volumio[1066]: Starting albumart workers Dec 01 20:17:50 volumio3 dnsmasq[1057]: reading /etc/resolv.conf Dec 01 20:17:50 volumio3 dnsmasq[1057]: using nameserver 10.0.112.12#53 Dec 01 20:17:50 volumio3 dnsmasq[1057]: using nameserver 208.67.222.222#53 Dec 01 20:17:50 volumio3 dnsmasq[1057]: using nameserver 208.67.220.220#53 Dec 01 20:17:50 volumio3 volumio[1066]: info: VolumeController:: Volume=46 Mute =false Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:50 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreStateMachine::updateTrackBlock Dec 01 20:17:50 volumio3 volumio[1066]: info: CorePlayQueue::getTrackBlock Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::volumioRetrievevolume Dec 01 20:17:50 volumio3 sh[703]: forked to background, child pid 1217 Dec 01 20:17:50 volumio3 dhcpcd[753]: forked to background, child pid 1217 Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreStateMachine::setRepeat null single undefined Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:50 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreStateMachine::setRandom null Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:50 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:50 volumio3 volumio[1066]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 01 20:17:50 volumio3 volumio[1066]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Dec 01 20:17:50 volumio3 volumio[1066]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 01 20:17:50 volumio3 volumio[1066]: info: Setting Device type: Raspberry PI Dec 01 20:17:50 volumio3 volumio[1066]: info: Completed loading Core Plugins Dec 01 20:17:50 volumio3 volumio[1066]: info: Preparing to generate the ALSA configuration file Dec 01 20:17:50 volumio3 volumio[1066]: info: Asound.conf file unchanged, so no further update is needed Dec 01 20:17:50 volumio3 volumio[1066]: info: Output device has changed, restarting MPD Dec 01 20:17:50 volumio3 volumio[1066]: info: Output device has changed, restarting Shairport Sync Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 20:17:50 volumio3 sudo[1240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 01 20:17:50 volumio3 sudo[1238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 01 20:17:50 volumio3 sudo[1238]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:50 volumio3 sudo[1240]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:50 volumio3 sudo[1238]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:50 volumio3 systemd[1]: Stopping Music Player Daemon... Dec 01 20:17:50 volumio3 volumio[1066]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 01 20:17:50 volumio3 volumio[1066]: info: ___________ START PLUGINS ___________ Dec 01 20:17:50 volumio3 volumio[1066]: info: ControllerMpd::onStart: Initializing MPD Dec 01 20:17:50 volumio3 volumio[1066]: info: Creating MPD Configuration file Dec 01 20:17:50 volumio3 ifplugd(eth0)[936]: client: ifup: interface eth0 already configured Dec 01 20:17:50 volumio3 sh[703]: eth0=eth0 Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 20:17:50 volumio3 volumio[1066]: info: [1733080670439] CoreMusicLibrary::Adding element Media Servers Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 20:17:50 volumio3 sudo[1254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 01 20:17:50 volumio3 sudo[1254]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 20:17:50 volumio3 sudo[1254]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:50 volumio3 sudo[1256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 01 20:17:50 volumio3 sudo[1256]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:50 volumio3 volumio[1066]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 20:17:50 volumio3 volumio[1066]: info: [1733080670502] CoreMusicLibrary::Adding element Last_100 Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 20:17:50 volumio3 volumio[1066]: info: [1733080670503] CoreMusicLibrary::Adding element Webradio Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 20:17:50 volumio3 volumio[1066]: info: Initializing BBC Radios Dec 01 20:17:50 volumio3 ifplugd(eth0)[936]: Program executed successfully. Dec 01 20:17:50 volumio3 systemd[1]: mpd.service: Succeeded. Dec 01 20:17:50 volumio3 systemd[1]: Stopped Music Player Daemon. Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: Volumio Calling Home Dec 01 20:17:50 volumio3 systemd[1]: Starting Music Player Daemon... Dec 01 20:17:50 volumio3 sudo[1296]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 01 20:17:50 volumio3 sudo[1296]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:50 volumio3 volumio[1066]: info: MPD Permissions set Dec 01 20:17:50 volumio3 volumio[1066]: info: VolumeController:: Volume=46 Mute =false Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:50 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:50 volumio3 sudo[1296]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 1 Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 2 Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 3 Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 4 Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 5 Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 6 Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 7 Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 8 Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 9 Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 10 Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 11 Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 12 Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 13 Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 14 Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 15 Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 16 Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Dec 01 20:17:50 volumio3 volumio[1066]: info: Volumio called home Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 18 Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 19 Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::volumioGetState Dec 01 20:17:50 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:50 volumio3 volumio[1066]: info: Starting Shairport Sync Dec 01 20:17:50 volumio3 volumio[1066]: info: Starting Shairport Sync Dec 01 20:17:50 volumio3 volumio[1066]: info: Starting Shairport Sync Dec 01 20:17:50 volumio3 sudo[1316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 01 20:17:50 volumio3 sudo[1316]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:50 volumio3 sudo[1318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 01 20:17:50 volumio3 sudo[1318]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:50 volumio3 volumio[1066]: info: Discovery: adding f15db130-c0f7-457c-8156-6a48cebf95e8 Dec 01 20:17:50 volumio3 volumio[1066]: info: Discovery: Found device Volumio1 Dec 01 20:17:50 volumio3 volumio[1066]: info: Discovery: Connecting to remote: 192.168.10.2 Dec 01 20:17:50 volumio3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 01 20:17:50 volumio3 sudo[1321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 01 20:17:50 volumio3 sudo[1321]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::volumioGetState Dec 01 20:17:50 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:50 volumio3 systemd[1]: shairport-sync.service: Succeeded. Dec 01 20:17:50 volumio3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::volumioGetVisibleSources Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 20:17:50 volumio3 volumio[1066]: info: Listing playlists Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreCommandRouter::volumioGetQueue Dec 01 20:17:50 volumio3 volumio[1066]: info: CoreStateMachine::getQueue Dec 01 20:17:50 volumio3 volumio[1066]: info: CorePlayQueue::getQueue Dec 01 20:17:50 volumio3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 01 20:17:50 volumio3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 01 20:17:50 volumio3 sudo[1318]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:50 volumio3 sudo[1316]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:50 volumio3 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 01 20:17:50 volumio3 systemd[1]: shairport-sync.service: Succeeded. Dec 01 20:17:50 volumio3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 01 20:17:50 volumio3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 01 20:17:50 volumio3 sudo[1321]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:50 volumio3 volumio-time-update[787]: volumio-time-update-util: Fetching time from Volumio... Dec 01 20:17:50 volumio3 volumio[1066]: info: Discovery: Connected to remote: 192.168.10.2 Dec 01 20:17:50 volumio3 volumio[1066]: info: Shairport-Sync Started Dec 01 20:17:50 volumio3 volumio[1066]: Error adding Membership: Error: addMembership EINVAL Dec 01 20:17:50 volumio3 volumio[1066]: info: Shairport-Sync Started Dec 01 20:17:50 volumio3 volumio[1066]: info: Shairport-Sync Started Dec 01 20:17:50 volumio3 mpd[1305]: Dec 01 20:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 01 20:17:50 volumio3 systemd[1]: Started Music Player Daemon. Dec 01 20:17:50 volumio3 sudo[1256]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:50 volumio3 sudo[1240]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:50 volumio3 volumio[1066]: info: Completed starting Core Plugins Dec 01 20:17:50 volumio3 volumio[1066]: info: ------------------------------------------- Dec 01 20:17:50 volumio3 volumio[1066]: info: ----- MyVolumio plugins startup ---- Dec 01 20:17:50 volumio3 volumio[1066]: info: ------------------------------------------- Dec 01 20:17:50 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 01 20:17:50 volumio3 volumio[1066]: error: MPD error: The expression evaluated to a falsy value: Dec 01 20:17:50 volumio3 volumio[1066]: assert.ok(self.idling) Dec 01 20:17:50 volumio3 volumio[1066]: error: The expression evaluated to a falsy value: Dec 01 20:17:50 volumio3 volumio[1066]: assert.ok(self.idling) Dec 01 20:17:50 volumio3 volumio[1066]: info: MPD running with PID1305 Dec 01 20:17:50 volumio3 volumio[1066]: ,establishing connection Dec 01 20:17:50 volumio3 volumio[1066]: error: updateQueue error: null Dec 01 20:17:50 volumio3 volumio[1066]: error: updateQueue error: null Dec 01 20:17:50 volumio3 volumio-time-update[787]: volumio-time-update-util: Setting system time to: 2024-12-01 20:17:50 Dec 01 20:17:50 volumio3 sudo[1337]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2024-12-01 20:17:50 Dec 01 20:17:50 volumio3 sudo[1337]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:50 volumio3 dbus-daemon[792]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.16' (uid=0 pid=1338 comm="timedatectl set-time 2024-12-01 20:17:50 ") Dec 01 20:17:51 volumio3 systemd[1]: Starting Time & Date Service... Dec 01 20:17:51 volumio3 dbus-daemon[792]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 01 20:17:51 volumio3 systemd[1]: Started Time & Date Service. Dec 01 20:17:50 volumio3 systemd-timedated[1339]: Changed local time to Sun Dec 1 20:17:50 2024 Dec 01 20:17:50 volumio3 sudo[1337]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:50 volumio3 volumio-time-update[787]: volumio-time-update-util: System time updated successfully. Dec 01 20:17:50 volumio3 systemd[1]: Started Volumio Time Update Utility. Dec 01 20:17:50 volumio3 systemd[1]: Reached target Multi-User System. Dec 01 20:17:50 volumio3 systemd[1]: Reached target Graphical Interface. Dec 01 20:17:50 volumio3 systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 01 20:17:50 volumio3 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 01 20:17:50 volumio3 systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 01 20:17:50 volumio3 systemd[1]: Startup finished in 11.848s (kernel) + 15.083s (userspace) = 26.932s. Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 20 Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 21 Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 22 Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 23 Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 24 Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 25 Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 26 Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 27 Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 28 Dec 01 20:17:50 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 29 Dec 01 20:17:51 volumio3 volumio[1066]: info: Discovery: adding 6549009b-c726-4b70-9781-47424ecae9fb Dec 01 20:17:51 volumio3 volumio[1066]: info: Discovery: Found device Volumio3 Dec 01 20:17:51 volumio3 volumio[1066]: info: CoreCommandRouter::volumioGetState Dec 01 20:17:51 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:51 volumio3 volumio[1066]: info: Discovery: this is already registered, 6549009b-c726-4b70-9781-47424ecae9fb Dec 01 20:17:51 volumio3 volumio[1066]: info: Discovery: Found device Volumio3 Dec 01 20:17:51 volumio3 volumio[1066]: info: CoreCommandRouter::volumioGetState Dec 01 20:17:51 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:51 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105:3000 from 192.168.10.2 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 30 Dec 01 20:17:51 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 01 20:17:51 volumio3 volumio[1066]: info: CoreCommandRouter::volumioGetState Dec 01 20:17:51 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:51 volumio3 ntpd[938]: Listen normally on 4 eth0 10.0.112.105:123 Dec 01 20:17:51 volumio3 ntpd[938]: new interface(s) found: waking up resolver Dec 01 20:17:52 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 31 Dec 01 20:17:52 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 32 Dec 01 20:17:52 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 33 Dec 01 20:17:52 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 34 Dec 01 20:17:52 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 35 Dec 01 20:17:52 volumio3 sudo[1125]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:52 volumio3 volumio-remote-updater[812]: [2024-12-01 20:17:52] [connect] Successful connection Dec 01 20:17:52 volumio3 volumio-remote-updater[812]: [2024-12-01 20:17:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1733080672 101 Dec 01 20:17:52 volumio3 volumio[1066]: 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: 36 Dec 01 20:17:53 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 37 Dec 01 20:17:53 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 38 Dec 01 20:17:53 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 39 Dec 01 20:17:53 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 40 Dec 01 20:17:53 volumio3 volumio[1066]: verbose: New Socket.io Connection to 10.0.112.105 from 10.0.112.109 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.1.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 41 Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPlay Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::play index undefined Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 20:17:54 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::startPlaybackTimer Dec 01 20:17:54 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:54 volumio3 volumio[1066]: info: [1733080674025] ControllerWebradio::clearAddPlayTrack Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand stop Dec 01 20:17:54 volumio3 volumio[1066]: info: sendMpdCommand stop took 1 milliseconds Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand clear Dec 01 20:17:54 volumio3 volumio[1066]: info: Dec 01 20:17:54 volumio3 volumio[1066]: ---------------------------- MPD announces system playlist update Dec 01 20:17:54 volumio3 volumio[1066]: info: Ignoring MPD Status Update Dec 01 20:17:54 volumio3 volumio[1066]: info: sendMpdCommand clear took 1 milliseconds Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand load "https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3" Dec 01 20:17:54 volumio3 volumio[1066]: info: Dec 01 20:17:54 volumio3 volumio[1066]: ---------------------------- MPD announces system playlist update Dec 01 20:17:54 volumio3 volumio[1066]: info: Ignoring MPD Status Update Dec 01 20:17:54 volumio3 volumio[1066]: info: Dec 01 20:17:54 volumio3 volumio[1066]: ---------------------------- MPD announces system playlist update Dec 01 20:17:54 volumio3 volumio[1066]: info: Ignoring MPD Status Update Dec 01 20:17:54 volumio3 volumio[1066]: error: updateQueue error: null Dec 01 20:17:54 volumio3 volumio[1066]: info: ------------------------------ 3ms Dec 01 20:17:54 volumio3 sudo[1348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=Fortnite2!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.0.112.41/music /mnt/NAS/Bento Dec 01 20:17:54 volumio3 sudo[1348]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:54 volumio3 kernel: CIFS: Attempting to mount //10.0.112.41/music Dec 01 20:17:54 volumio3 sudo[1348]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:54 volumio3 volumio[1066]: error: updateQueue error: null Dec 01 20:17:54 volumio3 volumio[1066]: error: updateQueue error: null Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand add "https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3" Dec 01 20:17:54 volumio3 volumio[1066]: info: ------------------------------ 493ms Dec 01 20:17:54 volumio3 volumio[1066]: info: ------------------------------ 493ms Dec 01 20:17:54 volumio3 volumio[1066]: info: Dec 01 20:17:54 volumio3 volumio[1066]: ---------------------------- MPD announces system playlist update Dec 01 20:17:54 volumio3 volumio[1066]: info: Ignoring MPD Status Update Dec 01 20:17:54 volumio3 volumio[1066]: info: sendMpdCommand add "https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3" took 1 milliseconds Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand play Dec 01 20:17:54 volumio3 volumio[1066]: info: Dec 01 20:17:54 volumio3 volumio[1066]: ---------------------------- MPD announces system playlist update Dec 01 20:17:54 volumio3 volumio[1066]: info: Ignoring MPD Status Update Dec 01 20:17:54 volumio3 volumio[1066]: info: Dec 01 20:17:54 volumio3 volumio[1066]: ---------------------------- MPD announces system playlist update Dec 01 20:17:54 volumio3 volumio[1066]: info: Ignoring MPD Status Update Dec 01 20:17:54 volumio3 volumio[1066]: info: ------------------------------ 5ms Dec 01 20:17:54 volumio3 volumio[1066]: info: sendMpdCommand play took 4 milliseconds Dec 01 20:17:54 volumio3 volumio[1066]: info: ------------------------------ 4ms Dec 01 20:17:54 volumio3 volumio[1066]: info: ------------------------------ 4ms Dec 01 20:17:54 volumio3 volumio[1066]: info: Dec 01 20:17:54 volumio3 volumio[1066]: ---------------------------- MPD announces state update: player Dec 01 20:17:54 volumio3 volumio[1066]: info: ControllerMpd::getState Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:17:54 volumio3 volumio[1066]: info: Dec 01 20:17:54 volumio3 volumio[1066]: ---------------------------- MPD announces state update: player Dec 01 20:17:54 volumio3 volumio[1066]: info: ControllerMpd::getState Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:17:54 volumio3 volumio[1066]: info: Dec 01 20:17:54 volumio3 volumio[1066]: ---------------------------- MPD announces state update: player Dec 01 20:17:54 volumio3 volumio[1066]: info: ControllerMpd::getState Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:17:54 volumio3 volumio[1066]: info: Dec 01 20:17:54 volumio3 volumio[1066]: ---------------------------- MPD announces state update: player Dec 01 20:17:54 volumio3 volumio[1066]: info: sendMpdCommand status took 5 milliseconds Dec 01 20:17:54 volumio3 volumio[1066]: info: ControllerMpd::getState Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 20:17:54 volumio3 volumio[1066]: info: Dec 01 20:17:54 volumio3 volumio[1066]: ---------------------------- MPD announces system playlist update Dec 01 20:17:54 volumio3 volumio[1066]: info: Ignoring MPD Status Update Dec 01 20:17:54 volumio3 volumio[1066]: info: Dec 01 20:17:54 volumio3 volumio[1066]: ---------------------------- MPD announces state update: player Dec 01 20:17:54 volumio3 volumio[1066]: info: Dec 01 20:17:54 volumio3 volumio[1066]: ---------------------------- MPD announces system playlist update Dec 01 20:17:54 volumio3 volumio[1066]: info: Ignoring MPD Status Update Dec 01 20:17:54 volumio3 volumio[1066]: info: Dec 01 20:17:54 volumio3 volumio[1066]: ---------------------------- MPD announces state update: player Dec 01 20:17:54 volumio3 volumio[1066]: info: sendMpdCommand status took 9 milliseconds Dec 01 20:17:54 volumio3 volumio[1066]: info: sendMpdCommand status took 10 milliseconds Dec 01 20:17:54 volumio3 volumio[1066]: info: ControllerMpd::getState Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:17:54 volumio3 volumio[1066]: info: sendMpdCommand status took 5 milliseconds Dec 01 20:17:54 volumio3 volumio[1066]: info: ControllerMpd::getState Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:17:54 volumio3 volumio[1066]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::parseTrackInfo Dec 01 20:17:54 volumio3 volumio[1066]: info: ControllerMpd::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::servicePushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:54 volumio3 volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"THE SCRIPT - RAIN","artist":"Absolut relax","album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Dec 01 20:17:54 volumio3 volumio[1066]: verbose: CURRENT POSITION 0 Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::syncState stateService play Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::syncState currentStatus stop Dec 01 20:17:54 volumio3 volumio[1066]: info: ------------------------------ 15ms Dec 01 20:17:54 volumio3 volumio[1066]: info: Dec 01 20:17:54 volumio3 volumio[1066]: ---------------------------- MPD announces state update: player Dec 01 20:17:54 volumio3 volumio[1066]: info: ControllerMpd::getState Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:17:54 volumio3 volumio[1066]: info: Dec 01 20:17:54 volumio3 volumio[1066]: ---------------------------- MPD announces state update: player Dec 01 20:17:54 volumio3 volumio[1066]: info: ControllerMpd::getState Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:17:54 volumio3 volumio[1066]: info: ------------------------------ 9ms Dec 01 20:17:54 volumio3 volumio[1066]: info: sendMpdCommand status took 5 milliseconds Dec 01 20:17:54 volumio3 volumio[1066]: info: ------------------------------ 9ms Dec 01 20:17:54 volumio3 volumio[1066]: info: sendMpdCommand status took 5 milliseconds Dec 01 20:17:54 volumio3 volumio[1066]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 01 20:17:54 volumio3 volumio[1066]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 01 20:17:54 volumio3 volumio[1066]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 01 20:17:54 volumio3 volumio[1066]: info: sendMpdCommand status took 1 milliseconds Dec 01 20:17:54 volumio3 volumio[1066]: info: sendMpdCommand status took 1 milliseconds Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::parseTrackInfo Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 20:17:54 volumio3 volumio[1066]: info: ControllerMpd::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::servicePushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:54 volumio3 volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"THE SCRIPT - RAIN","artist":"Absolut relax","album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Dec 01 20:17:54 volumio3 volumio[1066]: verbose: CURRENT POSITION 0 Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::syncState stateService play Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::syncState currentStatus play Dec 01 20:17:54 volumio3 volumio[1066]: info: Received an update from plugin. extracting info from payload Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:54 volumio3 volumio[1066]: info: ControllerMpd::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::servicePushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:54 volumio3 volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"THE SCRIPT - RAIN","artist":"Absolut relax","album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Dec 01 20:17:54 volumio3 volumio[1066]: verbose: CURRENT POSITION 0 Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::syncState stateService play Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::syncState currentStatus play Dec 01 20:17:54 volumio3 volumio[1066]: info: Received an update from plugin. extracting info from payload Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:54 volumio3 volumio[1066]: info: ControllerMpd::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::servicePushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:54 volumio3 volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"THE SCRIPT - RAIN","artist":"Absolut relax","album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Dec 01 20:17:54 volumio3 volumio[1066]: verbose: CURRENT POSITION 0 Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::syncState stateService play Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::syncState currentStatus play Dec 01 20:17:54 volumio3 volumio[1066]: info: Received an update from plugin. extracting info from payload Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:54 volumio3 volumio[1066]: info: ------------------------------ 32ms Dec 01 20:17:54 volumio3 volumio[1066]: info: ------------------------------ 32ms Dec 01 20:17:54 volumio3 volumio[1066]: info: ------------------------------ 28ms Dec 01 20:17:54 volumio3 volumio[1066]: info: Dec 01 20:17:54 volumio3 volumio[1066]: ---------------------------- MPD announces system playlist update Dec 01 20:17:54 volumio3 volumio[1066]: info: Ignoring MPD Status Update Dec 01 20:17:54 volumio3 volumio[1066]: info: Dec 01 20:17:54 volumio3 volumio[1066]: ---------------------------- MPD announces state update: player Dec 01 20:17:54 volumio3 volumio[1066]: info: ControllerMpd::getState Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:17:54 volumio3 volumio[1066]: info: Dec 01 20:17:54 volumio3 volumio[1066]: ---------------------------- MPD announces system playlist update Dec 01 20:17:54 volumio3 volumio[1066]: info: Ignoring MPD Status Update Dec 01 20:17:54 volumio3 volumio[1066]: info: Dec 01 20:17:54 volumio3 volumio[1066]: ---------------------------- MPD announces state update: player Dec 01 20:17:54 volumio3 volumio[1066]: info: ControllerMpd::getState Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:17:54 volumio3 volumio[1066]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 01 20:17:54 volumio3 volumio[1066]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 01 20:17:54 volumio3 volumio[1066]: info: sendMpdCommand playlistinfo took 18 milliseconds Dec 01 20:17:54 volumio3 volumio[1066]: info: sendMpdCommand playlistinfo took 18 milliseconds Dec 01 20:17:54 volumio3 volumio[1066]: info: ------------------------------ 2ms Dec 01 20:17:54 volumio3 volumio[1066]: info: sendMpdCommand status took 1 milliseconds Dec 01 20:17:54 volumio3 volumio[1066]: info: ------------------------------ 1ms Dec 01 20:17:54 volumio3 volumio[1066]: info: sendMpdCommand status took 1 milliseconds Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::parseTrackInfo Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::parseTrackInfo Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::parseTrackInfo Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::parseTrackInfo Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 20:17:54 volumio3 volumio[1066]: info: ControllerMpd::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::servicePushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:54 volumio3 volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"THE SCRIPT - RAIN","artist":"Absolut relax","album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Dec 01 20:17:54 volumio3 volumio[1066]: verbose: CURRENT POSITION 0 Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::syncState stateService play Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::syncState currentStatus play Dec 01 20:17:54 volumio3 volumio[1066]: info: Received an update from plugin. extracting info from payload Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:54 volumio3 volumio[1066]: info: ControllerMpd::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::servicePushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:54 volumio3 volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"THE SCRIPT - RAIN","artist":"Absolut relax","album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Dec 01 20:17:54 volumio3 volumio[1066]: verbose: CURRENT POSITION 0 Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::syncState stateService play Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::syncState currentStatus play Dec 01 20:17:54 volumio3 volumio[1066]: info: Received an update from plugin. extracting info from payload Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:54 volumio3 volumio[1066]: info: ControllerMpd::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::servicePushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:54 volumio3 volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"THE SCRIPT - RAIN","artist":"Absolut relax","album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Dec 01 20:17:54 volumio3 volumio[1066]: verbose: CURRENT POSITION 0 Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::syncState stateService play Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::syncState currentStatus play Dec 01 20:17:54 volumio3 volumio[1066]: info: Received an update from plugin. extracting info from payload Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:54 volumio3 volumio[1066]: info: ControllerMpd::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::servicePushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:54 volumio3 volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"THE SCRIPT - RAIN","artist":"Absolut relax","album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Dec 01 20:17:54 volumio3 volumio[1066]: verbose: CURRENT POSITION 0 Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::syncState stateService play Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::syncState currentStatus play Dec 01 20:17:54 volumio3 volumio[1066]: info: Received an update from plugin. extracting info from payload Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:54 volumio3 volumio[1066]: info: ------------------------------ 50ms Dec 01 20:17:54 volumio3 volumio[1066]: info: ------------------------------ 50ms Dec 01 20:17:54 volumio3 volumio[1066]: info: ------------------------------ 43ms Dec 01 20:17:54 volumio3 volumio[1066]: info: ------------------------------ 42ms Dec 01 20:17:54 volumio3 volumio[1066]: info: sendMpdCommand playlistinfo took 25 milliseconds Dec 01 20:17:54 volumio3 volumio[1066]: info: sendMpdCommand playlistinfo took 24 milliseconds Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::parseTrackInfo Dec 01 20:17:54 volumio3 volumio[1066]: verbose: ControllerMpd::parseTrackInfo Dec 01 20:17:54 volumio3 volumio[1066]: info: ControllerMpd::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::servicePushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:54 volumio3 volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"THE SCRIPT - RAIN","artist":"Absolut relax","album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Dec 01 20:17:54 volumio3 volumio[1066]: verbose: CURRENT POSITION 0 Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::syncState stateService play Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::syncState currentStatus play Dec 01 20:17:54 volumio3 volumio[1066]: info: Received an update from plugin. extracting info from payload Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:54 volumio3 volumio[1066]: info: ControllerMpd::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::servicePushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:54 volumio3 volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"THE SCRIPT - RAIN","artist":"Absolut relax","album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Dec 01 20:17:54 volumio3 volumio[1066]: verbose: CURRENT POSITION 0 Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::syncState stateService play Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::syncState currentStatus play Dec 01 20:17:54 volumio3 volumio[1066]: info: Received an update from plugin. extracting info from payload Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:54 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:54 volumio3 volumio[1066]: info: ------------------------------ 35ms Dec 01 20:17:54 volumio3 volumio[1066]: info: ------------------------------ 34ms Dec 01 20:17:54 volumio3 volumio[1066]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 01 20:17:56 volumio3 volumio[1066]: info: Dec 01 20:17:56 volumio3 volumio[1066]: ---------------------------- MPD announces system playlist update Dec 01 20:17:56 volumio3 volumio[1066]: info: Ignoring MPD Status Update Dec 01 20:17:56 volumio3 volumio[1066]: info: Dec 01 20:17:56 volumio3 volumio[1066]: ---------------------------- MPD announces state update: player Dec 01 20:17:56 volumio3 volumio[1066]: info: ControllerMpd::getState Dec 01 20:17:56 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:17:56 volumio3 volumio[1066]: info: Dec 01 20:17:56 volumio3 volumio[1066]: ---------------------------- MPD announces system playlist update Dec 01 20:17:56 volumio3 volumio[1066]: info: Ignoring MPD Status Update Dec 01 20:17:56 volumio3 volumio[1066]: info: Dec 01 20:17:56 volumio3 volumio[1066]: ---------------------------- MPD announces state update: player Dec 01 20:17:56 volumio3 volumio[1066]: info: ControllerMpd::getState Dec 01 20:17:56 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:17:56 volumio3 volumio[1066]: info: ------------------------------ 1ms Dec 01 20:17:56 volumio3 volumio[1066]: info: sendMpdCommand status took 1 milliseconds Dec 01 20:17:56 volumio3 volumio[1066]: info: ------------------------------ 1ms Dec 01 20:17:56 volumio3 volumio[1066]: info: sendMpdCommand status took 0 milliseconds Dec 01 20:17:56 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:17:56 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 20:17:56 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:17:56 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 20:17:56 volumio3 volumio[1066]: info: Dec 01 20:17:56 volumio3 volumio[1066]: ---------------------------- MPD announces system playlist update Dec 01 20:17:56 volumio3 volumio[1066]: info: Ignoring MPD Status Update Dec 01 20:17:56 volumio3 volumio[1066]: info: Dec 01 20:17:56 volumio3 volumio[1066]: ---------------------------- MPD announces state update: player Dec 01 20:17:56 volumio3 volumio[1066]: info: ControllerMpd::getState Dec 01 20:17:56 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:17:56 volumio3 volumio[1066]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 01 20:17:56 volumio3 volumio[1066]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 01 20:17:56 volumio3 volumio[1066]: info: ------------------------------ 1ms Dec 01 20:17:56 volumio3 volumio[1066]: info: sendMpdCommand status took 1 milliseconds Dec 01 20:17:56 volumio3 volumio[1066]: verbose: ControllerMpd::parseTrackInfo Dec 01 20:17:56 volumio3 volumio[1066]: verbose: ControllerMpd::parseTrackInfo Dec 01 20:17:56 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:17:56 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 20:17:56 volumio3 volumio[1066]: info: ControllerMpd::pushState Dec 01 20:17:56 volumio3 volumio[1066]: info: CoreCommandRouter::servicePushState Dec 01 20:17:56 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:56 volumio3 volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2383,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"THE SCRIPT - RAIN","artist":"Absolut relax","album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Dec 01 20:17:56 volumio3 volumio[1066]: verbose: CURRENT POSITION 0 Dec 01 20:17:56 volumio3 volumio[1066]: info: CoreStateMachine::syncState stateService play Dec 01 20:17:56 volumio3 volumio[1066]: info: CoreStateMachine::syncState currentStatus play Dec 01 20:17:56 volumio3 volumio[1066]: info: Received an update from plugin. extracting info from payload Dec 01 20:17:56 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:56 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 20:17:56 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:56 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:56 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:56 volumio3 volumio[1066]: info: ControllerMpd::pushState Dec 01 20:17:56 volumio3 volumio[1066]: info: CoreCommandRouter::servicePushState Dec 01 20:17:56 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:56 volumio3 volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2383,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"THE SCRIPT - RAIN","artist":"Absolut relax","album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Dec 01 20:17:56 volumio3 volumio[1066]: verbose: CURRENT POSITION 0 Dec 01 20:17:56 volumio3 volumio[1066]: info: CoreStateMachine::syncState stateService play Dec 01 20:17:56 volumio3 volumio[1066]: info: CoreStateMachine::syncState currentStatus play Dec 01 20:17:56 volumio3 volumio[1066]: info: Received an update from plugin. extracting info from payload Dec 01 20:17:56 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:56 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:56 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:56 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:56 volumio3 volumio[1066]: info: ------------------------------ 14ms Dec 01 20:17:56 volumio3 volumio[1066]: info: ------------------------------ 14ms Dec 01 20:17:56 volumio3 volumio[1066]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 01 20:17:56 volumio3 volumio[1066]: verbose: ControllerMpd::parseTrackInfo Dec 01 20:17:56 volumio3 volumio[1066]: info: ControllerMpd::pushState Dec 01 20:17:56 volumio3 volumio[1066]: info: CoreCommandRouter::servicePushState Dec 01 20:17:56 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:56 volumio3 volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2383,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"THE SCRIPT - RAIN","artist":"Absolut relax","album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Dec 01 20:17:56 volumio3 volumio[1066]: verbose: CURRENT POSITION 0 Dec 01 20:17:56 volumio3 volumio[1066]: info: CoreStateMachine::syncState stateService play Dec 01 20:17:56 volumio3 volumio[1066]: info: CoreStateMachine::syncState currentStatus play Dec 01 20:17:56 volumio3 volumio[1066]: info: Received an update from plugin. extracting info from payload Dec 01 20:17:56 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:56 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:56 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:56 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:56 volumio3 volumio[1066]: info: ------------------------------ 18ms Dec 01 20:17:57 volumio3 volumio[1066]: info: Dec 01 20:17:57 volumio3 volumio[1066]: ---------------------------- MPD announces system playlist update Dec 01 20:17:57 volumio3 volumio[1066]: info: Ignoring MPD Status Update Dec 01 20:17:57 volumio3 volumio[1066]: info: Dec 01 20:17:57 volumio3 volumio[1066]: ---------------------------- MPD announces state update: player Dec 01 20:17:57 volumio3 volumio[1066]: info: ControllerMpd::getState Dec 01 20:17:57 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:17:57 volumio3 volumio[1066]: info: Dec 01 20:17:57 volumio3 volumio[1066]: ---------------------------- MPD announces system playlist update Dec 01 20:17:57 volumio3 volumio[1066]: info: Ignoring MPD Status Update Dec 01 20:17:57 volumio3 volumio[1066]: info: Dec 01 20:17:57 volumio3 volumio[1066]: ---------------------------- MPD announces state update: player Dec 01 20:17:57 volumio3 volumio[1066]: info: ControllerMpd::getState Dec 01 20:17:57 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:17:57 volumio3 volumio[1066]: info: Dec 01 20:17:57 volumio3 volumio[1066]: ---------------------------- MPD announces system playlist update Dec 01 20:17:57 volumio3 volumio[1066]: info: Ignoring MPD Status Update Dec 01 20:17:57 volumio3 volumio[1066]: info: Dec 01 20:17:57 volumio3 volumio[1066]: ---------------------------- MPD announces state update: player Dec 01 20:17:57 volumio3 volumio[1066]: info: ControllerMpd::getState Dec 01 20:17:57 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:17:57 volumio3 volumio[1066]: info: ------------------------------ 3ms Dec 01 20:17:57 volumio3 volumio[1066]: info: sendMpdCommand status took 3 milliseconds Dec 01 20:17:57 volumio3 volumio[1066]: info: ------------------------------ 2ms Dec 01 20:17:57 volumio3 volumio[1066]: info: sendMpdCommand status took 2 milliseconds Dec 01 20:17:57 volumio3 volumio[1066]: info: ------------------------------ 1ms Dec 01 20:17:57 volumio3 volumio[1066]: info: sendMpdCommand status took 1 milliseconds Dec 01 20:17:57 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:17:57 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 20:17:57 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:17:57 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 20:17:57 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:17:57 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 20:17:57 volumio3 volumio[1066]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 01 20:17:57 volumio3 volumio[1066]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 01 20:17:57 volumio3 volumio[1066]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 01 20:17:57 volumio3 volumio[1066]: verbose: ControllerMpd::parseTrackInfo Dec 01 20:17:57 volumio3 volumio[1066]: verbose: ControllerMpd::parseTrackInfo Dec 01 20:17:57 volumio3 volumio[1066]: verbose: ControllerMpd::parseTrackInfo Dec 01 20:17:57 volumio3 volumio[1066]: info: ControllerMpd::pushState Dec 01 20:17:57 volumio3 volumio[1066]: info: CoreCommandRouter::servicePushState Dec 01 20:17:57 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:57 volumio3 volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"THE SCRIPT - RAIN","artist":"Absolut relax","album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Dec 01 20:17:57 volumio3 volumio[1066]: verbose: CURRENT POSITION 0 Dec 01 20:17:57 volumio3 volumio[1066]: info: CoreStateMachine::syncState stateService play Dec 01 20:17:57 volumio3 volumio[1066]: info: CoreStateMachine::syncState currentStatus play Dec 01 20:17:57 volumio3 volumio[1066]: info: Received an update from plugin. extracting info from payload Dec 01 20:17:57 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:57 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 20:17:57 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:57 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:57 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:57 volumio3 volumio[1066]: info: ControllerMpd::pushState Dec 01 20:17:57 volumio3 volumio[1066]: info: CoreCommandRouter::servicePushState Dec 01 20:17:57 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:57 volumio3 volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"THE SCRIPT - RAIN","artist":"Absolut relax","album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Dec 01 20:17:57 volumio3 volumio[1066]: verbose: CURRENT POSITION 0 Dec 01 20:17:57 volumio3 volumio[1066]: info: CoreStateMachine::syncState stateService play Dec 01 20:17:57 volumio3 volumio[1066]: info: CoreStateMachine::syncState currentStatus play Dec 01 20:17:57 volumio3 volumio[1066]: info: Received an update from plugin. extracting info from payload Dec 01 20:17:57 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:57 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:57 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:57 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:57 volumio3 volumio[1066]: info: ControllerMpd::pushState Dec 01 20:17:57 volumio3 volumio[1066]: info: CoreCommandRouter::servicePushState Dec 01 20:17:57 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:17:57 volumio3 volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3986,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"THE SCRIPT - RAIN","artist":"Absolut relax","album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Dec 01 20:17:57 volumio3 volumio[1066]: verbose: CURRENT POSITION 0 Dec 01 20:17:57 volumio3 volumio[1066]: info: CoreStateMachine::syncState stateService play Dec 01 20:17:57 volumio3 volumio[1066]: info: CoreStateMachine::syncState currentStatus play Dec 01 20:17:57 volumio3 volumio[1066]: info: Received an update from plugin. extracting info from payload Dec 01 20:17:57 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:57 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:57 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:17:57 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:17:57 volumio3 volumio[1066]: info: ------------------------------ 19ms Dec 01 20:17:57 volumio3 volumio[1066]: info: ------------------------------ 18ms Dec 01 20:17:57 volumio3 volumio[1066]: info: ------------------------------ 17ms Dec 01 20:17:58 volumio3 volumio[1066]: info: Volumio Network Manager: Network status updated: 1 Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 01 20:17:58 volumio3 volumio[1066]: info: Adding plugin bluetooth to MyMusic Plugins Dec 01 20:17:58 volumio3 volumio[1066]: info: Adding plugin multiroom to MyMusic Plugins Dec 01 20:17:58 volumio3 volumio[1066]: info: Adding plugin metavolumio to MyMusic Plugins Dec 01 20:17:58 volumio3 volumio[1066]: info: Adding plugin cd_controller to MyMusic Plugins Dec 01 20:17:58 volumio3 volumio[1066]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 01 20:17:58 volumio3 volumio[1066]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 01 20:17:58 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 20:17:58 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 20:17:58 volumio3 volumio[1066]: info: Starting MyVolumio Remote Streaming Endpoints Dec 01 20:17:58 volumio3 volumio[1066]: info: MyVolumio login type: Token Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 01 20:17:58 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 01 20:17:59 volumio3 volumio[1066]: info: Starting Streaming Service Transparent Proxy Dec 01 20:17:59 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 01 20:17:59 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 01 20:17:59 volumio3 volumio[1066]: info: Streaming services startup Dec 01 20:17:59 volumio3 volumio[1066]: info: Starting Streaming Daemon Dec 01 20:17:59 volumio3 sudo[1388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 01 20:17:59 volumio3 sudo[1388]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:59 volumio3 volumio[1066]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 01 20:17:59 volumio3 sudo[1388]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:59 volumio3 sudo[1396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 01 20:17:59 volumio3 sudo[1396]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:59 volumio3 sudo[1396]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:59 volumio3 volumio[1066]: error: Cannot start Volumio Streaming Daemon Dec 01 20:17:59 volumio3 volumio[1066]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 01 20:17:59 volumio3 volumio[1066]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 01 20:17:59 volumio3 sudo[1399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 01 20:17:59 volumio3 sudo[1399]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:59 volumio3 sudo[1399]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:59 volumio3 sudo[1402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 01 20:17:59 volumio3 sudo[1402]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:17:59 volumio3 systemd[1]: Started UPnP Renderer front-end to MPD. Dec 01 20:17:59 volumio3 sudo[1402]: pam_unix(sudo:session): session closed for user root Dec 01 20:17:59 volumio3 volumio[1066]: info: Upmpdcli Daemon Started Dec 01 20:17:59 volumio3 volumio[1404]: Generating RSA private key, 4096 bit long modulus (2 primes) Dec 01 20:17:59 volumio3 volumio[1066]: STREAMING PROXY: Starting server on port 3245 Dec 01 20:17:59 volumio3 volumio[1066]: Node JS runtime: 14 Dec 01 20:17:59 volumio3 volumio[1066]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 01 20:18:00 volumio3 volumio[1066]: info: MyVolumio token set successfully Dec 01 20:18:00 volumio3 volumio[1066]: info: MYVOLUMIO: Adding device Dec 01 20:18:00 volumio3 volumio[1066]: info: MYVOLUMIO: Evaluating Server Dec 01 20:18:00 volumio3 volumio[1066]: info: MyVolumio status changed Dec 01 20:18:00 volumio3 volumio[1066]: info: Streaming services startup Dec 01 20:18:00 volumio3 volumio[1066]: info: Starting Streaming Daemon Dec 01 20:18:00 volumio3 volumio[1066]: info: Removing browser output: myVolumio user plan is not superstar Dec 01 20:18:00 volumio3 volumio[1066]: info: Removing audio output: Dec 01 20:18:00 volumio3 volumio[1066]: info: Stoppping Tunnel 1 Dec 01 20:18:00 volumio3 sudo[1441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 01 20:18:00 volumio3 sudo[1441]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:18:00 volumio3 sudo[1444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 01 20:18:00 volumio3 sudo[1444]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:18:00 volumio3 sudo[1444]: pam_unix(sudo:session): session closed for user root Dec 01 20:18:00 volumio3 sudo[1441]: pam_unix(sudo:session): session closed for user root Dec 01 20:18:00 volumio3 volumio[1066]: info: Remote SSH Stopped Dec 01 20:18:00 volumio3 volumio[1066]: error: Cannot start Volumio Streaming Daemon Dec 01 20:18:00 volumio3 volumio[1066]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 01 20:18:00 volumio3 volumio[1066]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 01 20:18:03 volumio3 volumio[1404]: ...........................................................................++++ Dec 01 20:18:05 volumio3 volumio[1066]: info: Setting Geolocation for MyVolumio to eu6 Dec 01 20:18:05 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 20:18:06 volumio3 volumio[1066]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 01 20:18:06 volumio3 volumio[1066]: info: Updating MyVolumio device info Dec 01 20:18:06 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 20:18:07 volumio3 systemd[1]: systemd-fsckd.service: Succeeded. Dec 01 20:18:07 volumio3 volumio[1404]: ..............................................................................................++++ Dec 01 20:18:07 volumio3 volumio[1404]: e is 65537 (0x010001) Dec 01 20:18:07 volumio3 volumio[1404]: writing RSA key Dec 01 20:18:08 volumio3 volumio[1066]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 01 20:18:10 volumio3 volumio[1066]: info: MYVOLUMIO: Adding device Dec 01 20:18:10 volumio3 volumio[1066]: info: MYVOLUMIO: Evaluating Server Dec 01 20:18:10 volumio3 systemd[1]: systemd-hostnamed.service: Succeeded. Dec 01 20:18:13 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 01 20:18:13 volumio3 volumio[1066]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 42 Dec 01 20:18:13 volumio3 volumio[1066]: info: CoreCommandRouter::volumioGetState Dec 01 20:18:15 volumio3 volumio[1066]: info: Setting Geolocation for MyVolumio to eu6 Dec 01 20:18:15 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 20:18:15 volumio3 volumio[1066]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 01 20:18:16 volumio3 volumio[1066]: info: Updating MyVolumio device info Dec 01 20:18:16 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 20:18:17 volumio3 volumio[1066]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 01 20:18:18 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:18:18 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:18:18 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 01 20:18:18 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 01 20:18:18 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 01 20:18:18 volumio3 volumio[1066]: info: CoreCommandRouter::volumioGetBrowseSources Dec 01 20:18:18 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 20:18:19 volumio3 volumio[1066]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 01 20:18:19 volumio3 volumio[1066]: info: Completed starting MyVolumio Plugin Dec 01 20:18:19 volumio3 volumio[1066]: [Metrics] CommandRouter: 31s 992.61ms Dec 01 20:18:19 volumio3 volumio[1066]: info: CoreCommandRouter::volumiosetStartupVolume Dec 01 20:18:19 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 20:18:19 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 20:18:19 volumio3 volumio[1066]: info: CoreCommandRouter::Close All Modals sent Dec 01 20:18:19 volumio3 volumio[1066]: info: CoreCommandRouter::Close All Modals sent Dec 01 20:18:19 volumio3 volumio[1066]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Dec 01 20:18:19 volumio3 volumio[1066]: aplay: main:828: audio open error: Device or resource busy Dec 01 20:18:19 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 20:18:19 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 20:18:19 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 01 20:18:19 volumio3 volumio-remote-updater[812]: No test mode Dec 01 20:18:19 volumio3 volumio-remote-updater[812]: No alpha test mode Dec 01 20:18:19 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 01 20:18:19 volumio3 volumio[1066]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 01 20:18:19 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 01 20:18:20 volumio3 systemd[1]: systemd-timedated.service: Succeeded. Dec 01 20:18:20 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 01 20:18:20 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 01 20:18:20 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 01 20:18:23 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 20:18:23 volumio3 volumio[1066]: info: CURURI: music-library Dec 01 20:18:23 volumio3 volumio[1066]: error: Failed LSINFO: null Dec 01 20:18:23 volumio3 volumio[1066]: info: Preload queue cleared Dec 01 20:18:25 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 20:18:25 volumio3 volumio[1066]: info: CURURI: artists:// Dec 01 20:18:25 volumio3 volumio[1066]: info: Preload queue cleared Dec 01 20:18:26 volumio3 volumio[1066]: info: BOOT COMPLETED Dec 01 20:18:30 volumio3 volumio[1066]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 20:18:30 volumio3 volumio[1066]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 20:18:30 volumio3 volumio[1066]: info: Retrieving Cloud Streaming UI Dec 01 20:18:30 volumio3 volumio[1066]: info: Getting Tidal Cloud Configuration Dec 01 20:18:30 volumio3 volumio[1066]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 20:18:30 volumio3 volumio[1066]: info: Getting Qobuz Cloud Configuration Dec 01 20:18:30 volumio3 volumio[1066]: info: Asking plugin for UI Config Dec 01 20:18:30 volumio3 volumio[1066]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 20:18:30 volumio3 volumio[1066]: info: Getting Spotify Cloud Configuration Dec 01 20:18:30 volumio3 volumio[1066]: info: Asking plugin for UI Config Dec 01 20:18:30 volumio3 volumio[1066]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 20:18:30 volumio3 volumio[1066]: info: Saving Spotify Acccount Dec 01 20:18:30 volumio3 volumio[1066]: info: Got Tidal Cloud Configuration Dec 01 20:18:30 volumio3 volumio[1066]: info: Got it Dec 01 20:18:30 volumio3 volumio[1066]: info: Got it Dec 01 20:18:30 volumio3 volumio[1066]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 01 20:18:30 volumio3 volumio[1066]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 20:18:30 volumio3 volumio[1066]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 01 20:18:30 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 01 20:18:30 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 01 20:18:30 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 01 20:18:30 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 01 20:18:30 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 20:18:30 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 20:18:30 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 20:18:30 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 20:18:30 volumio3 volumio[1066]: info: CoreCommandRouter::volumioGetBrowseSources Dec 01 20:18:30 volumio3 volumio[1066]: info: CoreCommandRouter::volumioGetBrowseSources Dec 01 20:18:30 volumio3 volumio[1066]: info: CoreCommandRouter::volumioGetBrowseSources Dec 01 20:18:30 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 01 20:18:30 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 01 20:18:34 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 01 20:18:38 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 01 20:18:40 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Dec 01 20:18:40 volumio3 sudo[1677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/Bento Dec 01 20:18:40 volumio3 sudo[1677]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 20:18:40 volumio3 systemd[1]: mnt-NAS-Bento.mount: Succeeded. Dec 01 20:18:40 volumio3 sudo[1677]: pam_unix(sudo:session): session closed for user root Dec 01 20:18:40 volumio3 volumio[1066]: info: Share Bento successfully unmounted Dec 01 20:18:41 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 01 20:18:42 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:18:43 volumio3 volumio[1066]: info: Dec 01 20:18:43 volumio3 volumio[1066]: ---------------------------- MPD announces state update: update Dec 01 20:18:43 volumio3 volumio[1066]: info: ControllerMpd::getState Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:18:43 volumio3 volumio[1066]: info: Dec 01 20:18:43 volumio3 volumio[1066]: ---------------------------- MPD announces state update: update Dec 01 20:18:43 volumio3 volumio[1066]: info: ControllerMpd::getState Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:18:43 volumio3 volumio[1066]: info: Dec 01 20:18:43 volumio3 volumio[1066]: ---------------------------- MPD announces state update: update Dec 01 20:18:43 volumio3 volumio[1066]: info: ControllerMpd::getState Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:18:43 volumio3 volumio[1066]: info: sendMpdCommand status took 3 milliseconds Dec 01 20:18:43 volumio3 volumio[1066]: info: sendMpdCommand status took 2 milliseconds Dec 01 20:18:43 volumio3 volumio[1066]: info: sendMpdCommand status took 3 milliseconds Dec 01 20:18:43 volumio3 volumio[1066]: info: sendMpdCommand status took 2 milliseconds Dec 01 20:18:43 volumio3 volumio[1066]: info: sendMpdCommand status took 2 milliseconds Dec 01 20:18:43 volumio3 volumio[1066]: info: sendMpdCommand status took 2 milliseconds Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:18:43 volumio3 volumio[1066]: info: Command Router : Notfying DB Updatetrue Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:18:43 volumio3 volumio[1066]: info: Command Router : Notfying DB Updatetrue Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreCommandRouter::Close All Modals sent Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:18:43 volumio3 volumio[1066]: info: Command Router : Notfying DB Updatetrue Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreCommandRouter::Close All Modals sent Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 20:18:43 volumio3 volumio[1066]: info: [1733080723653] Database update started Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:18:43 volumio3 volumio[1066]: info: Dec 01 20:18:43 volumio3 volumio[1066]: ---------------------------- MPD announces state update: update Dec 01 20:18:43 volumio3 volumio[1066]: info: ControllerMpd::getState Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:18:43 volumio3 volumio[1066]: info: sendMpdCommand playlistinfo took 41 milliseconds Dec 01 20:18:43 volumio3 volumio[1066]: info: sendMpdCommand playlistinfo took 32 milliseconds Dec 01 20:18:43 volumio3 volumio[1066]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::parseTrackInfo Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::parseTrackInfo Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::parseTrackInfo Dec 01 20:18:43 volumio3 volumio[1066]: info: ControllerMpd::pushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreCommandRouter::servicePushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:18:43 volumio3 volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49680,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"THE SCRIPT - RAIN","artist":"Absolut relax","album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Dec 01 20:18:43 volumio3 volumio[1066]: verbose: CURRENT POSITION 0 Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreStateMachine::syncState stateService play Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreStateMachine::syncState currentStatus play Dec 01 20:18:43 volumio3 volumio[1066]: info: Received an update from plugin. extracting info from payload Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:18:43 volumio3 volumio[1066]: info: ControllerMpd::pushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreCommandRouter::servicePushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:18:43 volumio3 volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49680,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"THE SCRIPT - RAIN","artist":"Absolut relax","album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Dec 01 20:18:43 volumio3 volumio[1066]: verbose: CURRENT POSITION 0 Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreStateMachine::syncState stateService play Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreStateMachine::syncState currentStatus play Dec 01 20:18:43 volumio3 volumio[1066]: info: Received an update from plugin. extracting info from payload Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:18:43 volumio3 volumio[1066]: info: ControllerMpd::pushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreCommandRouter::servicePushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:18:43 volumio3 volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49680,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"THE SCRIPT - RAIN","artist":"Absolut relax","album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Dec 01 20:18:43 volumio3 volumio[1066]: verbose: CURRENT POSITION 0 Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreStateMachine::syncState stateService play Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreStateMachine::syncState currentStatus play Dec 01 20:18:43 volumio3 volumio[1066]: info: Received an update from plugin. extracting info from payload Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:18:43 volumio3 volumio[1066]: info: ------------------------------ 126ms Dec 01 20:18:43 volumio3 volumio[1066]: info: ------------------------------ 125ms Dec 01 20:18:43 volumio3 volumio[1066]: info: ------------------------------ 124ms Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:18:43 volumio3 volumio[1066]: info: Dec 01 20:18:43 volumio3 volumio[1066]: ---------------------------- MPD announces state update: update Dec 01 20:18:43 volumio3 volumio[1066]: info: ControllerMpd::getState Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:18:43 volumio3 volumio[1066]: info: Dec 01 20:18:43 volumio3 volumio[1066]: ---------------------------- MPD announces state update: update Dec 01 20:18:43 volumio3 volumio[1066]: info: ControllerMpd::getState Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Dec 01 20:18:43 volumio3 volumio[1066]: info: sendMpdCommand status took 26 milliseconds Dec 01 20:18:43 volumio3 volumio[1066]: info: sendMpdCommand status took 26 milliseconds Dec 01 20:18:43 volumio3 volumio[1066]: info: sendMpdCommand status took 2 milliseconds Dec 01 20:18:43 volumio3 volumio[1066]: info: sendMpdCommand status took 1 milliseconds Dec 01 20:18:43 volumio3 volumio[1066]: info: sendMpdCommand status took 1 milliseconds Dec 01 20:18:43 volumio3 volumio[1066]: info: sendMpdCommand status took 1 milliseconds Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:18:43 volumio3 volumio[1066]: info: Command Router : Notfying DB Updatefalse Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreCommandRouter::Close All Modals sent Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:18:43 volumio3 volumio[1066]: info: Command Router : Notfying DB Updatefalse Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreCommandRouter::Close All Modals sent Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:18:43 volumio3 volumio[1066]: info: Command Router : Notfying DB Updatefalse Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreCommandRouter::Close All Modals sent Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::parseState Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 20:18:43 volumio3 volumio[1066]: info: sendMpdCommand playlistinfo took 21 milliseconds Dec 01 20:18:43 volumio3 volumio[1066]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 01 20:18:43 volumio3 volumio[1066]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::parseTrackInfo Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::parseTrackInfo Dec 01 20:18:43 volumio3 volumio[1066]: verbose: ControllerMpd::parseTrackInfo Dec 01 20:18:43 volumio3 volumio[1066]: info: ControllerMpd::pushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreCommandRouter::servicePushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:18:43 volumio3 volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49782,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"THE SCRIPT - RAIN","artist":"Absolut relax","album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Dec 01 20:18:43 volumio3 volumio[1066]: verbose: CURRENT POSITION 0 Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreStateMachine::syncState stateService play Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreStateMachine::syncState currentStatus play Dec 01 20:18:43 volumio3 volumio[1066]: info: Received an update from plugin. extracting info from payload Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:18:43 volumio3 volumio[1066]: info: ControllerMpd::pushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreCommandRouter::servicePushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:18:43 volumio3 volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49885,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"THE SCRIPT - RAIN","artist":"Absolut relax","album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Dec 01 20:18:43 volumio3 volumio[1066]: verbose: CURRENT POSITION 0 Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreStateMachine::syncState stateService play Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreStateMachine::syncState currentStatus play Dec 01 20:18:43 volumio3 volumio[1066]: info: Received an update from plugin. extracting info from payload Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:18:43 volumio3 volumio[1066]: info: ControllerMpd::pushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreCommandRouter::servicePushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CorePlayQueue::getTrack 0 Dec 01 20:18:43 volumio3 volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49885,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"THE SCRIPT - RAIN","artist":"Absolut relax","album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Dec 01 20:18:43 volumio3 volumio[1066]: verbose: CURRENT POSITION 0 Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreStateMachine::syncState stateService play Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreStateMachine::syncState currentStatus play Dec 01 20:18:43 volumio3 volumio[1066]: info: Received an update from plugin. extracting info from payload Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreStateMachine::pushState Dec 01 20:18:43 volumio3 volumio[1066]: info: CoreCommandRouter::volumioPushState Dec 01 20:18:43 volumio3 volumio[1066]: info: ------------------------------ 70ms Dec 01 20:18:43 volumio3 volumio[1066]: info: ------------------------------ 46ms Dec 01 20:18:43 volumio3 volumio[1066]: info: ------------------------------ 45ms Dec 01 20:18:44 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Dec 01 20:18:44 volumio3 volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Dec 01 20:18:44 volumio3 systemd[1]: avahi-daemon.service: Main process exited, code=killed, status=11/SEGV Dec 01 20:18:44 volumio3 systemd[1]: avahi-daemon.service: Failed with result 'signal'. Dec 01 20:18:44 volumio3 volumio[1066]: error: Discovery: Advertisement error: Error: dns service error: unknown Dec 01 20:18:44 volumio3 volumio[1066]: error: Discovery: advertisement error: Error: dns service error: unknown Dec 01 20:18:44 volumio3 volumio[1066]: info: Discovery: Advertisement raised the following error Error: dns service error: unknown Dec 01 20:18:44 volumio3 volumio[1066]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 20:18:44 volumio3 dbus-daemon[792]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service' requested by ':1.24' (uid=112 pid=1325 comm="/usr/bin/shairport-sync --configfile=/tmp/shairpor") Dec 01 20:18:44 volumio3 volumio[1066]: Error: dns service error: unknown Dec 01 20:18:44 volumio3 volumio[1066]: at Browser.on_service_changed (/volumio/node_modules/mdns/lib/browser.js:84:33) Dec 01 20:18:44 volumio3 volumio[1066]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 01 20:18:44 volumio3 volumio[1066]: errorCode: -65537 Dec 01 20:18:44 volumio3 volumio[1066]: } Dec 01 20:18:44 volumio3 volumio[1066]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 20:18:44 volumio3 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Dec 01 20:18:44 volumio3 avahi-daemon[1714]: Process 795 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Dec 01 20:18:44 volumio3 avahi-daemon[1714]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Dec 01 20:18:44 volumio3 avahi-daemon[1714]: Successfully dropped root privileges. Dec 01 20:18:44 volumio3 avahi-daemon[1714]: avahi-daemon 0.7 starting up. Dec 01 20:18:44 volumio3 dbus-daemon[792]: [system] Successfully activated service 'org.freedesktop.Avahi' Dec 01 20:18:44 volumio3 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Dec 01 20:18:44 volumio3 avahi-daemon[1714]: Successfully called chroot(). Dec 01 20:18:44 volumio3 avahi-daemon[1714]: Successfully dropped remaining capabilities. Dec 01 20:18:44 volumio3 avahi-daemon[1714]: Loading service file /services/volumio.service. Dec 01 20:18:44 volumio3 avahi-daemon[1714]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Dec 01 20:18:44 volumio3 avahi-daemon[1714]: New relevant interface wlan0.IPv4 for mDNS. Dec 01 20:18:44 volumio3 avahi-daemon[1714]: Joining mDNS multicast group on interface eth0.IPv4 with address 10.0.112.105. Dec 01 20:18:44 volumio3 avahi-daemon[1714]: New relevant interface eth0.IPv4 for mDNS. Dec 01 20:18:44 volumio3 avahi-daemon[1714]: Network interface enumeration completed. Dec 01 20:18:44 volumio3 avahi-daemon[1714]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Dec 01 20:18:44 volumio3 avahi-daemon[1714]: Registering new address record for 10.0.112.105 on eth0.IPv4. Dec 01 20:18:45 volumio3 sudo[1725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-01 20:17 Dec 01 20:18:45 volumio3 sudo[1725]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"