-- Logs begin at Thu 2019-02-14 04:11:59 CST, end at Tue 2024-05-07 19:50:02 CDT. -- May 07 19:33:57 volumio kernel: pci 0000:00:09.0: PCI bridge to [bus 01] (subtractive decode) May 07 19:33:57 volumio kernel: pci 0000:00:09.0: bridge window [mem 0xd3300000-0xd33fffff] May 07 19:33:57 volumio kernel: pci 0000:00:09.0: bridge window [io 0x0000-0x0cf7 window] (subtractive decode) May 07 19:33:57 volumio kernel: pci 0000:00:09.0: bridge window [io 0x0d00-0xffff window] (subtractive decode) May 07 19:33:57 volumio kernel: pci 0000:00:09.0: bridge window [mem 0x000a0000-0x000fffff window] (subtractive decode) May 07 19:33:57 volumio kernel: pci 0000:00:09.0: bridge window [mem 0xc0000000-0xfebfffff window] (subtractive decode) May 07 19:33:57 volumio kernel: pci_bus 0000:02: extended config space not accessible May 07 19:33:57 volumio kernel: pci 0000:02:00.0: [10de:0863] type 00 class 0x030000 May 07 19:33:57 volumio kernel: pci 0000:02:00.0: reg 0x10: [mem 0xd2000000-0xd2ffffff] May 07 19:33:57 volumio kernel: pci 0000:02:00.0: reg 0x14: [mem 0xc0000000-0xcfffffff 64bit pref] May 07 19:33:57 volumio kernel: pci 0000:02:00.0: reg 0x1c: [mem 0xd0000000-0xd1ffffff 64bit pref] May 07 19:33:57 volumio kernel: pci 0000:02:00.0: reg 0x24: [io 0x1000-0x107f] May 07 19:33:57 volumio kernel: pci 0000:02:00.0: reg 0x30: [mem 0xd3000000-0xd301ffff pref] May 07 19:33:57 volumio kernel: pci 0000:02:00.0: BAR 1: assigned to efifb May 07 19:33:57 volumio kernel: pci 0000:00:10.0: PCI bridge to [bus 02] May 07 19:33:57 volumio kernel: pci 0000:00:10.0: bridge window [io 0x1000-0x1fff] May 07 19:33:57 volumio kernel: pci 0000:00:10.0: bridge window [mem 0xd2000000-0xd30fffff] May 07 19:33:57 volumio kernel: pci 0000:00:10.0: bridge window [mem 0xc0000000-0xd1ffffff 64bit pref] May 07 19:33:57 volumio kernel: pci 0000:03:00.0: [14e4:432b] type 00 class 0x028000 May 07 19:33:57 volumio kernel: pci 0000:03:00.0: reg 0x10: [mem 0xd3200000-0xd3203fff 64bit] May 07 19:33:57 volumio kernel: pci 0000:03:00.0: enabling Extended Tags May 07 19:33:57 volumio kernel: pci 0000:03:00.0: supports D1 D2 May 07 19:33:57 volumio kernel: pci 0000:03:00.0: PME# supported from D0 D3hot D3cold May 07 19:33:57 volumio kernel: pci 0000:00:15.0: PCI bridge to [bus 03] May 07 19:33:57 volumio kernel: pci 0000:00:15.0: bridge window [mem 0xd3200000-0xd32fffff] May 07 19:33:57 volumio kernel: pci 0000:04:00.0: [11c1:5901] type 00 class 0x0c0010 May 07 19:33:57 volumio kernel: pci 0000:04:00.0: reg 0x10: [mem 0xd3100000-0xd3100fff 64bit] May 07 19:33:57 volumio kernel: pci 0000:04:00.0: supports D1 D2 May 07 19:33:57 volumio kernel: pci 0000:04:00.0: PME# supported from D0 D1 D2 D3hot D3cold May 07 19:33:57 volumio kernel: pci 0000:00:16.0: PCI bridge to [bus 04] May 07 19:33:57 volumio kernel: pci 0000:00:16.0: bridge window [mem 0xd3100000-0xd31fffff] May 07 19:33:57 volumio kernel: pci_bus 0000:00: on NUMA node 0 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link LNK1 configured for IRQ 16 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link LNK2 configured for IRQ 16 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link LNK3 configured for IRQ 16 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link LNK4 configured for IRQ 16 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z003 configured for IRQ 16 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z004 configured for IRQ 16 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z005 configured for IRQ 16 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z006 configured for IRQ 16 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z007 configured for IRQ 0 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z007 disabled May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z008 configured for IRQ 0 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z008 disabled May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z009 configured for IRQ 0 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z009 disabled May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z00A configured for IRQ 0 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z00A disabled May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z00B configured for IRQ 0 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z00B disabled May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z00C configured for IRQ 0 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z00C disabled May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z00D configured for IRQ 0 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z00D disabled May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z00E configured for IRQ 0 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z00E disabled May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z00F configured for IRQ 21 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z00G configured for IRQ 21 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z00H configured for IRQ 21 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z00I configured for IRQ 21 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z00J configured for IRQ 19 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z00K configured for IRQ 19 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z00L configured for IRQ 19 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z00M configured for IRQ 19 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z00N configured for IRQ 23 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z00O configured for IRQ 23 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z00P configured for IRQ 23 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z00Q configured for IRQ 23 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z00R configured for IRQ 23 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z00S configured for IRQ 23 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z00T configured for IRQ 23 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z00U configured for IRQ 23 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link LSMB configured for IRQ 22 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link LUS0 configured for IRQ 17 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link LUS2 configured for IRQ 22 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link LMAC configured for IRQ 17 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link LAZA configured for IRQ 20 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link LGPU configured for IRQ 16 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link LPID configured for IRQ 0 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link LPID disabled May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link LSI0 configured for IRQ 18 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link LSI1 configured for IRQ 18 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z000 configured for IRQ 18 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link Z001 configured for IRQ 23 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link LPMU configured for IRQ 0 May 07 19:33:57 volumio kernel: ACPI: PCI: Interrupt link LPMU disabled May 07 19:33:57 volumio kernel: ACPI: EC: interrupt unblocked May 07 19:33:57 volumio kernel: ACPI: EC: event unblocked May 07 19:33:57 volumio kernel: ACPI: EC: EC_CMD/EC_SC=0x66, EC_DATA=0x62 May 07 19:33:57 volumio kernel: ACPI: EC: GPE=0x3f May 07 19:33:57 volumio kernel: ACPI: \_SB_.PCI0.LPCB.EC__: Boot ECDT EC initialization complete May 07 19:33:57 volumio kernel: ACPI: \_SB_.PCI0.LPCB.EC__: EC: Used to handle transactions and events May 07 19:33:57 volumio kernel: iommu: Default domain type: Translated May 07 19:33:57 volumio kernel: iommu: DMA domain TLB invalidation policy: lazy mode May 07 19:33:57 volumio kernel: SCSI subsystem initialized May 07 19:33:57 volumio kernel: libata version 3.00 loaded. May 07 19:33:57 volumio kernel: ACPI: bus type USB registered May 07 19:33:57 volumio kernel: usbcore: registered new interface driver usbfs May 07 19:33:57 volumio kernel: usbcore: registered new interface driver hub May 07 19:33:57 volumio kernel: usbcore: registered new device driver usb May 07 19:33:57 volumio kernel: efivars: Registered efivars operations May 07 19:33:57 volumio kernel: PCI: Using ACPI for IRQ routing May 07 19:33:57 volumio kernel: PCI: pci_cache_line_size set to 64 bytes May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0x0008f000-0x0008ffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xad440000-0xafffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xae6f7000-0xafffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xae70c000-0xafffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xae724000-0xafffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xae72b000-0xafffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xaefcb000-0xafffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xaf000000-0xafffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xbf016000-0xbfffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xbf0aa000-0xbfffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xbf0af000-0xbfffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xbf0b3000-0xbfffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xbf0ba000-0xbfffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xbf0c3000-0xbfffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xbf0c7000-0xbfffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xbf10e000-0xbfffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xbf11a000-0xbfffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xbf11f000-0xbfffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xbf225000-0xbfffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xbf227000-0xbfffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xbf242000-0xbfffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xbf265000-0xbfffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xbf270000-0xbfffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xbf277000-0xbfffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xbf279000-0xbfffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xbf280000-0xbfffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xbf70a000-0xbfffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xbf715000-0xbfffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xbf719000-0xbfffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xbfe93000-0xbfffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xbfe9e000-0xbfffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xbfec5000-0xbfffffff] May 07 19:33:57 volumio kernel: e820: reserve RAM buffer [mem 0xbfef9000-0xbfffffff] May 07 19:33:57 volumio kernel: pci 0000:02:00.0: vgaarb: setting as boot VGA device May 07 19:33:57 volumio kernel: pci 0000:02:00.0: vgaarb: bridge control possible May 07 19:33:57 volumio kernel: pci 0000:02:00.0: vgaarb: VGA device added: decodes=io+mem,owns=none,locks=none May 07 19:33:57 volumio kernel: vgaarb: loaded May 07 19:33:57 volumio kernel: hpet: 4 channels of 0 reserved for per-cpu timers May 07 19:33:57 volumio kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 31, 31 May 07 19:33:57 volumio kernel: hpet0: 4 comparators, 64-bit 25.000000 MHz counter May 07 19:33:57 volumio kernel: clocksource: Switched to clocksource tsc-early May 07 19:33:57 volumio kernel: VFS: Disk quotas dquot_6.6.0 May 07 19:33:57 volumio kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 07 19:33:57 volumio kernel: pnp: PnP ACPI init May 07 19:33:57 volumio kernel: system 00:00: [mem 0xf0000000-0xf3ffffff] has been reserved May 07 19:33:57 volumio kernel: system 00:01: [mem 0xfed00000-0xfed003ff] has been reserved May 07 19:33:57 volumio kernel: system 00:02: [io 0x0400-0x047f] has been reserved May 07 19:33:57 volumio kernel: system 00:02: [io 0x0480-0x04ff] has been reserved May 07 19:33:57 volumio kernel: system 00:02: [io 0x0500-0x057f] has been reserved May 07 19:33:57 volumio kernel: system 00:02: [io 0x0580-0x05ff] has been reserved May 07 19:33:57 volumio kernel: system 00:02: [io 0x0800-0x087f] has been reserved May 07 19:33:57 volumio kernel: system 00:02: [io 0x0880-0x08ff] has been reserved May 07 19:33:57 volumio kernel: system 00:02: [io 0x04d0-0x04d1] has been reserved May 07 19:33:57 volumio kernel: system 00:02: [io 0x0295-0x0296] has been reserved May 07 19:33:57 volumio kernel: pnp: PnP ACPI: found 4 devices May 07 19:33:57 volumio kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns May 07 19:33:57 volumio kernel: NET: Registered PF_INET protocol family May 07 19:33:57 volumio kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) May 07 19:33:57 volumio kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) May 07 19:33:57 volumio kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 07 19:33:57 volumio kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) May 07 19:33:57 volumio kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) May 07 19:33:57 volumio kernel: TCP: Hash tables configured (established 32768 bind 32768) May 07 19:33:57 volumio kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) May 07 19:33:57 volumio kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) May 07 19:33:57 volumio kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 07 19:33:57 volumio kernel: pci 0000:00:09.0: PCI bridge to [bus 01] May 07 19:33:57 volumio kernel: pci 0000:00:09.0: bridge window [mem 0xd3300000-0xd33fffff] May 07 19:33:57 volumio kernel: pci 0000:00:10.0: PCI bridge to [bus 02] May 07 19:33:57 volumio kernel: pci 0000:00:10.0: bridge window [io 0x1000-0x1fff] May 07 19:33:57 volumio kernel: pci 0000:00:10.0: bridge window [mem 0xd2000000-0xd30fffff] May 07 19:33:57 volumio kernel: pci 0000:00:10.0: bridge window [mem 0xc0000000-0xd1ffffff 64bit pref] May 07 19:33:57 volumio kernel: pci 0000:00:15.0: PCI bridge to [bus 03] May 07 19:33:57 volumio kernel: pci 0000:00:15.0: bridge window [mem 0xd3200000-0xd32fffff] May 07 19:33:57 volumio kernel: pci 0000:00:16.0: PCI bridge to [bus 04] May 07 19:33:57 volumio kernel: pci 0000:00:16.0: bridge window [mem 0xd3100000-0xd31fffff] May 07 19:33:57 volumio kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] May 07 19:33:57 volumio kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] May 07 19:33:57 volumio kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000fffff window] May 07 19:33:57 volumio kernel: pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] May 07 19:33:57 volumio kernel: pci_bus 0000:01: resource 1 [mem 0xd3300000-0xd33fffff] May 07 19:33:57 volumio kernel: pci_bus 0000:01: resource 4 [io 0x0000-0x0cf7 window] May 07 19:33:57 volumio kernel: pci_bus 0000:01: resource 5 [io 0x0d00-0xffff window] May 07 19:33:57 volumio kernel: pci_bus 0000:01: resource 6 [mem 0x000a0000-0x000fffff window] May 07 19:33:57 volumio kernel: pci_bus 0000:01: resource 7 [mem 0xc0000000-0xfebfffff window] May 07 19:33:57 volumio kernel: pci_bus 0000:02: resource 0 [io 0x1000-0x1fff] May 07 19:33:57 volumio kernel: pci_bus 0000:02: resource 1 [mem 0xd2000000-0xd30fffff] May 07 19:33:57 volumio kernel: pci_bus 0000:02: resource 2 [mem 0xc0000000-0xd1ffffff 64bit pref] May 07 19:33:57 volumio kernel: pci_bus 0000:03: resource 1 [mem 0xd3200000-0xd32fffff] May 07 19:33:57 volumio kernel: pci_bus 0000:04: resource 1 [mem 0xd3100000-0xd31fffff] May 07 19:33:57 volumio kernel: ACPI: \_SB_.PCI0.LUS0: Enabled at IRQ 20 May 07 19:33:57 volumio kernel: ACPI: \_SB_.PCI0.LUS2: Enabled at IRQ 17 May 07 19:33:57 volumio kernel: ACPI: \_SB_.PCI0.Z000: Enabled at IRQ 22 May 07 19:33:57 volumio kernel: ACPI: \_SB_.PCI0.Z001: Enabled at IRQ 22 May 07 19:33:57 volumio kernel: pci 0000:00:08.0: extending delay after power-on from D3hot to 20 msec May 07 19:33:57 volumio kernel: PCI: CLS 256 bytes, default 64 May 07 19:33:57 volumio kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) May 07 19:33:57 volumio kernel: software IO TLB: mapped [mem 0x00000000a8b60000-0x00000000acb60000] (64MB) May 07 19:33:57 volumio kernel: Trying to unpack rootfs image as initramfs... May 07 19:33:57 volumio kernel: Initialise system trusted keyrings May 07 19:33:57 volumio kernel: workingset: timestamp_bits=46 max_order=20 bucket_order=0 May 07 19:33:57 volumio kernel: zbud: loaded May 07 19:33:57 volumio kernel: Key type asymmetric registered May 07 19:33:57 volumio kernel: Asymmetric key parser 'x509' registered May 07 19:33:57 volumio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252) May 07 19:33:57 volumio kernel: io scheduler mq-deadline registered May 07 19:33:57 volumio kernel: io scheduler kyber registered May 07 19:33:57 volumio kernel: ACPI: \_SB_.PCI0.Z00F: Enabled at IRQ 20 May 07 19:33:57 volumio kernel: ACPI: \_SB_.PCI0.Z00J: Enabled at IRQ 18 May 07 19:33:57 volumio kernel: shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 May 07 19:33:57 volumio kernel: Monitor-Mwait will be used to enter C-1 state May 07 19:33:57 volumio kernel: Monitor-Mwait will be used to enter C-2 state May 07 19:33:57 volumio kernel: Monitor-Mwait will be used to enter C-3 state May 07 19:33:57 volumio kernel: ACPI: \_PR_.CPU0: Found 3 idle states May 07 19:33:57 volumio kernel: ACPI: AC: AC Adapter [ADP1] (on-line) May 07 19:33:57 volumio kernel: input: Lid Switch as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0D:00/input/input0 May 07 19:33:57 volumio kernel: ACPI: button: Lid Switch [LID0] May 07 19:33:57 volumio kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input1 May 07 19:33:57 volumio kernel: ACPI: button: Power Button [PWRB] May 07 19:33:57 volumio kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input2 May 07 19:33:57 volumio kernel: ACPI: button: Sleep Button [SLPB] May 07 19:33:57 volumio kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 May 07 19:33:57 volumio kernel: ACPI: button: Power Button [PWRF] May 07 19:33:57 volumio kernel: Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled May 07 19:33:57 volumio kernel: Linux agpgart interface v0.103 May 07 19:33:57 volumio kernel: ohci-pci 0000:00:04.0: OHCI PCI host controller May 07 19:33:57 volumio kernel: ohci-pci 0000:00:04.0: new USB bus registered, assigned bus number 1 May 07 19:33:57 volumio kernel: ohci-pci 0000:00:04.0: irq 20, io mem 0xd3488000 May 07 19:33:57 volumio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.06 May 07 19:33:57 volumio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 May 07 19:33:57 volumio kernel: usb usb1: Product: OHCI PCI host controller May 07 19:33:57 volumio kernel: usb usb1: Manufacturer: Linux 6.6.25-volumio ohci_hcd May 07 19:33:57 volumio kernel: usb usb1: SerialNumber: 0000:00:04.0 May 07 19:33:57 volumio kernel: hub 1-0:1.0: USB hub found May 07 19:33:57 volumio kernel: hub 1-0:1.0: 7 ports detected May 07 19:33:57 volumio kernel: ohci-pci 0000:00:06.0: OHCI PCI host controller May 07 19:33:57 volumio kernel: ohci-pci 0000:00:06.0: new USB bus registered, assigned bus number 2 May 07 19:33:57 volumio kernel: ohci-pci 0000:00:06.0: irq 22, io mem 0xd3487000 May 07 19:33:57 volumio kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.06 May 07 19:33:57 volumio kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 May 07 19:33:57 volumio kernel: usb usb2: Product: OHCI PCI host controller May 07 19:33:57 volumio kernel: usb usb2: Manufacturer: Linux 6.6.25-volumio ohci_hcd May 07 19:33:57 volumio kernel: usb usb2: SerialNumber: 0000:00:06.0 May 07 19:33:57 volumio kernel: hub 2-0:1.0: USB hub found May 07 19:33:57 volumio kernel: hub 2-0:1.0: 5 ports detected May 07 19:33:57 volumio kernel: i8042: PNP: No PS/2 controller found. May 07 19:33:57 volumio kernel: ehci-pci 0000:00:04.1: EHCI Host Controller May 07 19:33:57 volumio kernel: ehci-pci 0000:00:04.1: new USB bus registered, assigned bus number 3 May 07 19:33:57 volumio kernel: ehci-pci 0000:00:04.1: debug port 1 May 07 19:33:57 volumio kernel: ehci-pci 0000:00:04.1: irq 17, io mem 0xd3489200 May 07 19:33:57 volumio kernel: mousedev: PS/2 mouse device common for all mice May 07 19:33:57 volumio kernel: rtc_cmos 00:03: RTC can wake from S4 May 07 19:33:57 volumio kernel: rtc_cmos 00:03: registered as rtc0 May 07 19:33:57 volumio kernel: rtc_cmos 00:03: setting system clock to 2001-01-01T00:25:35 UTC (978308735) May 07 19:33:57 volumio kernel: rtc_cmos 00:03: alarms up to one year, y3k, 242 bytes nvram, hpet irqs May 07 19:33:57 volumio kernel: i2c_dev: i2c /dev entries driver May 07 19:33:57 volumio kernel: platform eisa.0: Probing EISA bus 0 May 07 19:33:57 volumio kernel: platform eisa.0: EISA: Cannot allocate resource for mainboard May 07 19:33:57 volumio kernel: intel_pstate: CPU model not supported May 07 19:33:57 volumio kernel: ledtrig-cpu: registered to indicate activity on CPUs May 07 19:33:57 volumio kernel: efifb: probing for efifb May 07 19:33:57 volumio kernel: efifb: framebuffer at 0xc0010000, using 6400k, total 6400k May 07 19:33:57 volumio kernel: efifb: mode is 1280x800x32, linelength=8192, pages=1 May 07 19:33:57 volumio kernel: efifb: scrolling: redraw May 07 19:33:57 volumio kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 May 07 19:33:57 volumio kernel: Console: switching to colour frame buffer device 160x50 May 07 19:33:57 volumio kernel: fb0: EFI VGA frame buffer device May 07 19:33:57 volumio kernel: NET: Registered PF_INET6 protocol family May 07 19:33:57 volumio kernel: Segment Routing with IPv6 May 07 19:33:57 volumio kernel: In-situ OAM (IOAM) with IPv6 May 07 19:33:57 volumio kernel: mip6: Mobile IPv6 May 07 19:33:57 volumio kernel: NET: Registered PF_PACKET protocol family May 07 19:33:57 volumio kernel: mpls_gso: MPLS GSO support May 07 19:33:57 volumio kernel: microcode: Microcode Update Driver: v2.2. May 07 19:33:57 volumio kernel: IPI shorthand broadcast: enabled May 07 19:33:57 volumio kernel: sched_clock: Marking stable (642253613, 354870)->(647298783, -4690300) May 07 19:33:57 volumio kernel: Loading compiled-in X.509 certificates May 07 19:33:57 volumio kernel: ehci-pci 0000:00:04.1: USB 2.0 started, EHCI 1.00 May 07 19:33:57 volumio kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 May 07 19:33:57 volumio kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 May 07 19:33:57 volumio kernel: usb usb3: Product: EHCI Host Controller May 07 19:33:57 volumio kernel: usb usb3: Manufacturer: Linux 6.6.25-volumio ehci_hcd May 07 19:33:57 volumio kernel: usb usb3: SerialNumber: 0000:00:04.1 May 07 19:33:57 volumio kernel: hub 3-0:1.0: USB hub found May 07 19:33:57 volumio kernel: hub 3-0:1.0: 7 ports detected May 07 19:33:57 volumio kernel: hub 1-0:1.0: USB hub found May 07 19:33:57 volumio kernel: hub 1-0:1.0: 7 ports detected May 07 19:33:57 volumio kernel: ehci-pci 0000:00:06.1: EHCI Host Controller May 07 19:33:57 volumio kernel: ehci-pci 0000:00:06.1: new USB bus registered, assigned bus number 4 May 07 19:33:57 volumio kernel: ehci-pci 0000:00:06.1: debug port 1 May 07 19:33:57 volumio kernel: ehci-pci 0000:00:06.1: irq 22, io mem 0xd3489100 May 07 19:33:57 volumio kernel: ehci-pci 0000:00:06.1: USB 2.0 started, EHCI 1.00 May 07 19:33:57 volumio kernel: usb usb4: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 May 07 19:33:57 volumio kernel: usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 May 07 19:33:57 volumio kernel: usb usb4: Product: EHCI Host Controller May 07 19:33:57 volumio kernel: usb usb4: Manufacturer: Linux 6.6.25-volumio ehci_hcd May 07 19:33:57 volumio kernel: usb usb4: SerialNumber: 0000:00:06.1 May 07 19:33:57 volumio kernel: hub 4-0:1.0: USB hub found May 07 19:33:57 volumio kernel: hub 4-0:1.0: 5 ports detected May 07 19:33:57 volumio kernel: hub 2-0:1.0: USB hub found May 07 19:33:57 volumio kernel: hub 2-0:1.0: 5 ports detected May 07 19:33:57 volumio kernel: clk: Disabling unused clocks May 07 19:33:57 volumio kernel: usb 3-4: new high-speed USB device number 2 using ehci-pci May 07 19:33:57 volumio kernel: usb 3-4: New USB device found, idVendor=05ac, idProduct=8507, bcdDevice= 4.19 May 07 19:33:57 volumio kernel: usb 3-4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 07 19:33:57 volumio kernel: usb 3-4: Product: Built-in iSight May 07 19:33:57 volumio kernel: usb 3-4: Manufacturer: Apple Inc. May 07 19:33:57 volumio kernel: usb 3-4: SerialNumber: 8H97A06EQ6UZ3A00 May 07 19:33:57 volumio kernel: usb 2-1: new full-speed USB device number 2 using ohci-pci May 07 19:33:57 volumio kernel: usb 1-5: new low-speed USB device number 2 using ohci-pci May 07 19:33:57 volumio kernel: tsc: Refined TSC clocksource calibration: 2255.346 MHz May 07 19:33:57 volumio kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x20826e0aaa3, max_idle_ns: 440795233316 ns May 07 19:33:57 volumio kernel: clocksource: Switched to clocksource tsc May 07 19:33:57 volumio kernel: usb 2-1: New USB device found, idVendor=0a5c, idProduct=4500, bcdDevice= 1.00 May 07 19:33:57 volumio kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 May 07 19:33:57 volumio kernel: usb 2-1: Product: BRCM2046 Hub May 07 19:33:57 volumio kernel: usb 2-1: Manufacturer: Apple Inc. May 07 19:33:57 volumio kernel: hub 2-1:1.0: USB hub found May 07 19:33:57 volumio kernel: hub 2-1:1.0: 3 ports detected May 07 19:33:57 volumio kernel: usb 1-5: New USB device found, idVendor=05ac, idProduct=8242, bcdDevice= 0.16 May 07 19:33:57 volumio kernel: usb 1-5: New USB device strings: Mfr=1, Product=2, SerialNumber=0 May 07 19:33:57 volumio kernel: usb 1-5: Product: IR Receiver May 07 19:33:57 volumio kernel: usb 1-5: Manufacturer: Apple Computer, Inc. May 07 19:33:57 volumio kernel: usb 4-2: new high-speed USB device number 3 using ehci-pci May 07 19:33:57 volumio kernel: usb 2-1.1: new full-speed USB device number 3 using ohci-pci May 07 19:33:57 volumio kernel: usb 2-1.1: New USB device found, idVendor=05ac, idProduct=8213, bcdDevice= 2.08 May 07 19:33:57 volumio kernel: usb 2-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 07 19:33:57 volumio kernel: usb 2-1.1: Product: Bluetooth USB Host Controller May 07 19:33:57 volumio kernel: usb 2-1.1: Manufacturer: Apple Inc. May 07 19:33:57 volumio kernel: usb 2-1.1: SerialNumber: 002608C6A56D May 07 19:33:57 volumio kernel: usb 4-2: New USB device found, idVendor=0781, idProduct=5567, bcdDevice= 1.27 May 07 19:33:57 volumio kernel: usb 4-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 07 19:33:57 volumio kernel: usb 4-2: Product: Cruzer Blade May 07 19:33:57 volumio kernel: usb 4-2: Manufacturer: SanDisk May 07 19:33:57 volumio kernel: usb 4-2: SerialNumber: 200608770118A323453E May 07 19:33:57 volumio kernel: usb 2-1.2: new full-speed USB device number 4 using ohci-pci May 07 19:33:57 volumio kernel: usb 4-5: new high-speed USB device number 4 using ehci-pci May 07 19:33:57 volumio kernel: usb 2-1.2: New USB device found, idVendor=05ac, idProduct=820a, bcdDevice= 1.00 May 07 19:33:57 volumio kernel: usb 2-1.2: New USB device strings: Mfr=0, Product=0, SerialNumber=0 May 07 19:33:57 volumio kernel: Freeing initrd memory: 67412K May 07 19:33:57 volumio kernel: usb 1-6: new full-speed USB device number 3 using ohci-pci May 07 19:33:57 volumio kernel: Freeing unused kernel image (initmem) memory: 2976K May 07 19:33:57 volumio kernel: Write protecting the kernel read-only data: 20480k May 07 19:33:57 volumio kernel: Freeing unused kernel image (rodata/data gap) memory: 1500K May 07 19:33:57 volumio kernel: Run /init as init process May 07 19:33:57 volumio kernel: with arguments: May 07 19:33:57 volumio kernel: /init May 07 19:33:57 volumio kernel: splash May 07 19:33:57 volumio kernel: with environment: May 07 19:33:57 volumio kernel: HOME=/ May 07 19:33:57 volumio kernel: TERM=linux May 07 19:33:57 volumio kernel: BOOT_IMAGE=/vmlinuz May 07 19:33:57 volumio kernel: biosdevname=0 May 07 19:33:57 volumio kernel: imgpart=UUID=7ab46900-3cb8-4deb-a4a8-9a1a2f978606 May 07 19:33:57 volumio kernel: bootpart=UUID=E2BB-AF0D May 07 19:33:57 volumio kernel: datapart=UUID=6c12c2b2-7468-42cb-89d5-2730f50d15fd May 07 19:33:57 volumio kernel: imgfile=/volumio_current.sqsh May 07 19:33:57 volumio kernel: use_kmsg=yes May 07 19:33:57 volumio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 07 19:33:57 volumio kernel: fuse: init (API version 7.39) May 07 19:33:57 volumio kernel: usb 4-5: New USB device found, idVendor=05ac, idProduct=8403, bcdDevice=98.33 May 07 19:33:57 volumio kernel: usb 4-5: New USB device strings: Mfr=3, Product=4, SerialNumber=2 May 07 19:33:57 volumio kernel: usb 4-5: Product: Card Reader May 07 19:33:57 volumio kernel: usb 4-5: Manufacturer: Apple May 07 19:33:57 volumio kernel: usb 4-5: SerialNumber: 000000009833 May 07 19:33:57 volumio kernel: usb 2-1.3: new full-speed USB device number 5 using ohci-pci May 07 19:33:57 volumio kernel: sdhci: Secure Digital Host Controller Interface driver May 07 19:33:57 volumio kernel: sdhci: Copyright(c) Pierre Ossman May 07 19:33:57 volumio kernel: hid: raw HID events driver (C) Jiri Kosina May 07 19:33:57 volumio kernel: usbcore: registered new interface driver usbhid May 07 19:33:57 volumio kernel: usbhid: USB HID core driver May 07 19:33:57 volumio kernel: input: HID 05ac:820a as /devices/pci0000:00/0000:00:06.0/usb2/2-1/2-1.2/2-1.2:1.0/0003:05AC:820A.0002/input/input4 May 07 19:33:57 volumio kernel: hid-generic 0003:05AC:820A.0002: input,hidraw0: USB HID v1.11 Keyboard [HID 05ac:820a] on usb-0000:00:06.0-1.2/input0 May 07 19:33:57 volumio kernel: usb 2-1.3: New USB device found, idVendor=05ac, idProduct=820b, bcdDevice= 1.00 May 07 19:33:57 volumio kernel: usb 2-1.3: New USB device strings: Mfr=0, Product=0, SerialNumber=0 May 07 19:33:57 volumio kernel: loop: module loaded May 07 19:33:57 volumio kernel: usb-storage 4-2:1.0: USB Mass Storage device detected May 07 19:33:57 volumio kernel: scsi host0: usb-storage 4-2:1.0 May 07 19:33:57 volumio kernel: usb-storage 4-5:1.0: USB Mass Storage device detected May 07 19:33:57 volumio kernel: scsi host1: usb-storage 4-5:1.0 May 07 19:33:57 volumio kernel: usbcore: registered new interface driver usb-storage May 07 19:33:57 volumio kernel: usbcore: registered new interface driver uas May 07 19:33:57 volumio kernel: input: HID 05ac:820b as /devices/pci0000:00/0000:00:06.0/usb2/2-1/2-1.3/2-1.3:1.0/0003:05AC:820B.0003/input/input5 May 07 19:33:57 volumio kernel: hid-generic 0003:05AC:820B.0003: input,hidraw1: USB HID v1.11 Mouse [HID 05ac:820b] on usb-0000:00:06.0-1.3/input0 May 07 19:33:57 volumio kernel: ahci 0000:00:0b.0: version 3.0 May 07 19:33:57 volumio kernel: ACPI: \_SB_.PCI0.LSI0: Enabled at IRQ 17 May 07 19:33:57 volumio kernel: ahci 0000:00:0b.0: controller can't do PMP, turning off CAP_PMP May 07 19:33:57 volumio kernel: ahci 0000:00:0b.0: AHCI 0001.0200 32 slots 6 ports 3 Gbps 0x3 impl SATA mode May 07 19:33:57 volumio kernel: ahci 0000:00:0b.0: flags: 64bit ncq sntf pm led pio slum part boh May 07 19:33:57 volumio kernel: scsi host2: ahci May 07 19:33:57 volumio kernel: scsi host3: ahci May 07 19:33:57 volumio kernel: scsi host4: ahci May 07 19:33:57 volumio kernel: scsi host5: ahci May 07 19:33:57 volumio kernel: scsi host6: ahci May 07 19:33:57 volumio kernel: scsi host7: ahci May 07 19:33:57 volumio kernel: ata1: SATA max UDMA/133 abar m8192@0xd3484000 port 0xd3484100 irq 26 May 07 19:33:57 volumio kernel: ata2: SATA max UDMA/133 abar m8192@0xd3484000 port 0xd3484180 irq 26 May 07 19:33:57 volumio kernel: ata3: DUMMY May 07 19:33:57 volumio kernel: ata4: DUMMY May 07 19:33:57 volumio kernel: ata5: DUMMY May 07 19:33:57 volumio kernel: ata6: DUMMY May 07 19:33:57 volumio kernel: usb 1-6: New USB device found, idVendor=05ac, idProduct=0237, bcdDevice= 0.81 May 07 19:33:57 volumio kernel: usb 1-6: New USB device strings: Mfr=1, Product=2, SerialNumber=0 May 07 19:33:57 volumio kernel: usb 1-6: Product: Apple Internal Keyboard / Trackpad May 07 19:33:57 volumio kernel: usb 1-6: Manufacturer: Apple Inc. May 07 19:33:57 volumio kernel: clocksource: timekeeping watchdog on CPU0: Marking clocksource 'tsc' as unstable because the skew is too large: May 07 19:33:57 volumio kernel: clocksource: 'hpet' wd_nsec: 0 wd_now: 929dc057 wd_last: 3104f9e mask: ffffffff May 07 19:33:57 volumio kernel: clocksource: 'tsc' cs_nsec: 480009538 cs_now: e502b915c cs_last: e0fa497bf mask: ffffffffffffffff May 07 19:33:57 volumio kernel: clocksource: Clocksource 'tsc' skewed 480009538 ns (480 ms) over watchdog 'hpet' interval of 0 ns (0 ms) May 07 19:33:57 volumio kernel: clocksource: 'tsc' is current clocksource. May 07 19:33:57 volumio kernel: tsc: Marking TSC unstable due to clocksource watchdog May 07 19:33:57 volumio kernel: TSC found unstable after boot, most likely due to broken BIOS. Use 'tsc=unstable'. May 07 19:33:57 volumio kernel: sched_clock: Marking unstable (2740121832, 355220)<-(2745167378, -4690300) May 07 19:33:57 volumio kernel: clocksource: Checking clocksource tsc synchronization from CPU 1 to CPUs 0. May 07 19:33:57 volumio kernel: clocksource: Switched to clocksource hpet May 07 19:33:57 volumio kernel: ata1: SATA link down (SStatus 0 SControl 300) May 07 19:33:57 volumio kernel: ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300) May 07 19:33:57 volumio kernel: ata2.00: ACPI cmd ef/10:03:00:00:00:a0(SET FEATURES) filtered out May 07 19:33:57 volumio kernel: ata2.00: ATAPI: MATSHITADVD-R UJ-868, KB19, max UDMA/66 May 07 19:33:57 volumio kernel: ata2.00: ACPI cmd ef/10:03:00:00:00:a0(SET FEATURES) filtered out May 07 19:33:57 volumio kernel: ata2.00: configured for UDMA/66 May 07 19:33:57 volumio kernel: scsi 3:0:0:0: CD-ROM MATSHITA DVD-R UJ-868 KB19 PQ: 0 ANSI: 5 May 07 19:33:57 volumio kernel: scsi 1:0:0:0: Direct-Access APPLE SD Card Reader 1.00 PQ: 0 ANSI: 0 May 07 19:33:57 volumio kernel: scsi 0:0:0:0: Direct-Access SanDisk Cruzer Blade 1.27 PQ: 0 ANSI: 6 May 07 19:33:57 volumio kernel: sd 0:0:0:0: [sdb] 15330304 512-byte logical blocks: (7.85 GB/7.31 GiB) May 07 19:33:57 volumio kernel: sd 0:0:0:0: [sdb] Write Protect is off May 07 19:33:57 volumio kernel: sd 0:0:0:0: [sdb] Mode Sense: 43 00 00 00 May 07 19:33:57 volumio kernel: sd 0:0:0:0: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA May 07 19:33:57 volumio kernel: sdb: sdb1 sdb2 sdb3 May 07 19:33:57 volumio kernel: sd 0:0:0:0: [sdb] Attached SCSI removable disk May 07 19:33:57 volumio kernel: sd 1:0:0:0: [sda] Media removed, stopped polling May 07 19:33:57 volumio kernel: sd 1:0:0:0: [sda] Attached SCSI removable disk May 07 19:33:57 volumio unknown: [i] initramfs: IMGPART=/dev/sdb2 May 07 19:33:57 volumio unknown: [i] initramfs: IMGFILE=/volumio_current.sqsh May 07 19:33:57 volumio unknown: [i] initramfs: BOOTPART=/dev/sdb1 May 07 19:33:57 volumio unknown: [i] initramfs: DATAPART=/dev/sdb3 May 07 19:33:57 volumio kernel: EXT4-fs (sdb2): recovery complete May 07 19:33:57 volumio kernel: EXT4-fs (sdb2): mounted filesystem 7ab46900-3cb8-4deb-a4a8-9a1a2f978606 r/w with ordered data mode. Quota mode: none. May 07 19:33:57 volumio unknown: [i] initramfs: GPTSIZEMISMATCH: May 07 19:33:57 volumio unknown: [i] initramfs: Doing a 5 second delay here to give kernel load a headstart May 07 19:33:57 volumio unknown: [i] initramfs: Checking for a volumio rootfs update on a USB device May 07 19:33:57 volumio unknown: [i] initramfs: Checking for factory reset on a USB device May 07 19:33:57 volumio unknown: [i] initramfs: Checking for factory or user-data reset on boot device May 07 19:33:57 volumio kernel: FAT-fs (sdb1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! May 07 19:33:57 volumio kernel: FAT-fs (sdb1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! May 07 19:33:57 volumio kernel: loop0: detected capacity change from 0 to 1719296 May 07 19:33:57 volumio unknown: [i] initramfs: Volumio version 3.661 May 07 19:33:57 volumio kernel: EXT4-fs (sdb3): recovery complete May 07 19:33:57 volumio kernel: EXT4-fs (sdb3): mounted filesystem 6c12c2b2-7468-42cb-89d5-2730f50d15fd r/w with ordered data mode. Quota mode: none. May 07 19:33:57 volumio unknown: [i] initramfs: Checking if fstab needs to be updated.. May 07 19:33:57 volumio unknown: [i] initramfs: Current /dev/sdb1 - UUID=E2BB-AF0D May 07 19:33:57 volumio unknown: [i] initramfs: Current fstb \boot May 07 19:33:57 volumio unknown: [i] initramfs: Volumio: 3.661 May 07 19:33:57 volumio unknown: [i] initramfs: Finishing initramfs, switching rootfs and starting the boot process... May 07 19:33:57 volumio systemd[1]: System time before build time, advancing clock. May 07 19:33:57 volumio systemd[1]: Inserted module 'autofs4' May 07 19:33:57 volumio systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) May 07 19:33:57 volumio systemd[1]: Detected architecture x86-64. May 07 19:33:57 volumio systemd[1]: Set hostname to . May 07 19:33:57 volumio systemd[1]: File /lib/systemd/system/systemd-journald.service:12 configures an IP firewall (IPAddressDeny=any), but the local system does not support BPF/cgroup based firewalling. May 07 19:33:57 volumio systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.) May 07 19:33:57 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 07 19:33:57 volumio kernel: random: crng init done May 07 19:33:57 volumio systemd[1]: Created slice system-systemd\x2dfsck.slice. May 07 19:33:57 volumio systemd[1]: Reached target System Time Synchronized. May 07 19:33:57 volumio systemd[1]: Listening on Journal Socket. May 07 19:33:57 volumio systemd[1]: Mounting Huge Pages File System... May 07 19:33:57 volumio kernel: RPC: Registered named UNIX socket transport module. May 07 19:33:57 volumio kernel: RPC: Registered udp transport module. May 07 19:33:57 volumio kernel: RPC: Registered tcp transport module. May 07 19:33:57 volumio kernel: RPC: Registered tcp-with-tls transport module. May 07 19:33:57 volumio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. May 07 19:33:57 volumio systemd-journald[243]: Journal started May 07 19:33:57 volumio systemd-journald[243]: Runtime journal (/run/log/journal/3a523b471f7dae7283b927646628fe0b) is 7.5M, max 30.0M, 22.5M free. May 07 19:33:57 volumio fake-hwclock[241]: Wed May 8 00:33:57 UTC 2024 May 07 19:33:57 volumio systemd[1]: Started Helper to synchronize boot up for ifupdown. May 07 19:33:57 volumio systemd[1]: Started Load/Save Random Seed. May 07 19:33:57 volumio systemd[1]: dynamicswap.service: Succeeded. May 07 19:33:57 volumio systemd[1]: Started Create System Users. May 07 19:33:57 volumio systemd[1]: Starting Create Static Device Nodes in /dev... May 07 19:33:57 volumio systemd[1]: Started Create Static Device Nodes in /dev. May 07 19:33:57 volumio systemd[1]: Starting udev Kernel Device Manager... May 07 19:33:57 volumio systemd[1]: Reached target Local File Systems (Pre). May 07 19:33:57 volumio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. May 07 19:33:57 volumio systemd[1]: Mounting /tmp... May 07 19:33:57 volumio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. May 07 19:33:57 volumio systemd[1]: Mounting /var/log... May 07 19:33:57 volumio systemd[1]: Mounting /var/spool/cups... May 07 19:33:57 volumio systemd[1]: Mounted /tmp. May 07 19:33:57 volumio systemd[1]: Mounted /var/log. May 07 19:33:57 volumio systemd[1]: Starting Flush Journal to Persistent Storage... May 07 19:33:57 volumio systemd-udevd[267]: Network interface NamePolicy= disabled on kernel command line, ignoring. May 07 19:33:57 volumio systemd[1]: Mounted /var/spool/cups. May 07 19:33:57 volumio systemd[1]: Mounting /var/spool/cups/tmp... May 07 19:33:57 volumio systemd[1]: Mounted /var/spool/cups/tmp. May 07 19:33:57 volumio systemd-journald[243]: Runtime journal (/run/log/journal/3a523b471f7dae7283b927646628fe0b) is 7.5M, max 30.0M, 22.5M free. May 07 19:33:57 volumio systemd[1]: Started Flush Journal to Persistent Storage. May 07 19:33:57 volumio systemd[1]: Started udev Kernel Device Manager. May 07 19:33:57 volumio systemd[1]: Starting Show Plymouth Boot Screen... May 07 19:33:57 volumio systemd[1]: Received SIGRTMIN+20 from PID 293 (plymouthd). May 07 19:33:57 volumio systemd[1]: Started Show Plymouth Boot Screen. May 07 19:33:57 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. May 07 19:33:57 volumio systemd[1]: Reached target Paths. May 07 19:33:57 volumio systemd[1]: Reached target Local Encrypted Volumes. May 07 19:33:57 volumio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. May 07 19:33:57 volumio kernel: smbus_hc ACPI0001:00: SBS HC: offset = 0x20, query_bit = 0x10 May 07 19:33:57 volumio kernel: i2c i2c-0: nForce2 SMBus adapter at 0x2140 May 07 19:33:57 volumio kernel: i2c i2c-1: nForce2 SMBus adapter at 0x2100 May 07 19:33:57 volumio kernel: forcedeth: Reverse Engineered nForce ethernet driver. Version 0.64. May 07 19:33:57 volumio kernel: ACPI: \_SB_.PCI0.LMAC: Enabled at IRQ 18 May 07 19:33:57 volumio kernel: scsi 3:0:0:0: Attached scsi generic sg0 type 5 May 07 19:33:57 volumio kernel: sd 1:0:0:0: Attached scsi generic sg1 type 0 May 07 19:33:57 volumio kernel: sd 0:0:0:0: Attached scsi generic sg2 type 0 May 07 19:33:57 volumio kernel: forcedeth 0000:00:0a.0: ifname eth0, PHY OUI 0x732 @ 1, addr 00:26:4a:10:47:88 May 07 19:33:57 volumio kernel: forcedeth 0000:00:0a.0: highdma csum pwrctl gbit lnktim msi desc-v3 May 07 19:33:57 volumio kernel: pstore: Using crash dump compression: deflate May 07 19:33:57 volumio kernel: usbcore: registered new device driver apple-mfi-fastcharge May 07 19:33:57 volumio kernel: pstore: Registered efi_pstore as persistent store backend May 07 19:33:57 volumio kernel: sr 3:0:0:0: [sr0] scsi3-mmc drive: 24x/24x writer cd/rw xa/form2 cdda tray May 07 19:33:57 volumio kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 May 07 19:33:57 volumio kernel: b43-pci-bridge 0000:03:00.0: enabling device (0000 -> 0002) May 07 19:33:57 volumio kernel: nvidiafb 0000:02:00.0: enabling device (0002 -> 0003) May 07 19:33:57 volumio kernel: ACPI: \_SB_.PCI0.LGPU: Enabled at IRQ 17 May 07 19:33:57 volumio kernel: nvidiafb: Device ID: 10de0863 May 07 19:33:57 volumio kernel: nvidiafb: unknown NV_ARCH May 07 19:33:57 volumio kernel: sr 3:0:0:0: Attached scsi CD-ROM sr0 May 07 19:33:58 volumio kernel: ssb: Found chip with id 0x4322, rev 0x01 and package 0x0A May 07 19:33:58 volumio kernel: input: Apple Inc. Apple Internal Keyboard / Trackpad as /devices/pci0000:00/0000:00:04.0/usb1/1-6/1-6:1.2/input/input6 May 07 19:33:58 volumio kernel: usbcore: registered new interface driver usbmouse May 07 19:33:58 volumio kernel: firewire_ohci 0000:04:00.0: added OHCI v1.10 device as card 0, 8 IR + 8 IT contexts, quirks 0x0, physUB May 07 19:33:58 volumio kernel: usbcore: registered new interface driver usbkbd May 07 19:33:58 volumio kernel: input: Apple Inc. Apple Internal Keyboard / Trackpad as /devices/pci0000:00/0000:00:04.0/usb1/1-6/1-6:1.0/0003:05AC:0237.0004/input/input7 May 07 19:33:58 volumio kernel: input: Apple Computer, Inc. IR Receiver as /devices/pci0000:00/0000:00:04.0/usb1/1-5/1-5:1.0/0003:05AC:8242.0001/input/input8 May 07 19:33:58 volumio kernel: b43-pci-bridge 0000:03:00.0: Sonics Silicon Backplane found on PCI device 0000:03:00.0 May 07 19:33:58 volumio kernel: usbcore: registered new interface driver bcm5974 May 07 19:33:58 volumio kernel: cryptd: max_cpu_qlen set to 1000 May 07 19:33:58 volumio systemd-udevd[299]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 07 19:33:58 volumio kernel: apple 0003:05AC:0237.0004: input,hidraw2: USB HID v1.11 Keyboard [Apple Inc. Apple Internal Keyboard / Trackpad] on usb-0000:00:04.0-6/input0 May 07 19:33:58 volumio kernel: apple 0003:05AC:0237.0005: hidraw3: USB HID v1.11 Device [Apple Inc. Apple Internal Keyboard / Trackpad] on usb-0000:00:04.0-6/input1 May 07 19:33:58 volumio kernel: appleir 0003:05AC:8242.0001: input,hiddev0,hidraw4: USB HID v1.11 Device [Apple Computer, Inc. IR Receiver] on usb-0000:00:04.0-5/input0 May 07 19:33:58 volumio kernel: ACPI: bus type drm_connector registered May 07 19:33:58 volumio systemd-udevd[273]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 07 19:33:58 volumio systemd-udevd[277]: Using default interface naming scheme 'v240'. May 07 19:33:58 volumio kernel: firewire_core 0000:04:00.0: created device fw0: GUID 00264afffe104788, S800 May 07 19:33:58 volumio systemd-udevd[277]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 07 19:33:58 volumio systemd-udevd[274]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 07 19:33:58 volumio kernel: snd_hda_intel 0000:00:08.0: enabling device (0000 -> 0002) May 07 19:33:58 volumio kernel: ACPI: \_SB_.PCI0.LAZA: Enabled at IRQ 20 May 07 19:33:58 volumio kernel: snd_hda_intel 0000:00:08.0: Disabling MSI May 07 19:33:58 volumio kernel: applesmc: key=300 fan=1 temp=14 index=14 acc=1 lux=2 kbd=1 May 07 19:33:58 volumio kernel: input: applesmc as /devices/platform/applesmc.768/input/input9 May 07 19:33:58 volumio kernel: applesmc applesmc.768: hwmon_device_register() is deprecated. Please convert the driver to use hwmon_device_register_with_info(). May 07 19:33:59 volumio systemd-udevd[274]: event7: EVDEV_ABS override set but no EV_ABS present on device May 07 19:33:59 volumio systemd-udevd[283]: event6: EVDEV_ABS override set but no EV_ABS present on device May 07 19:33:59 volumio kernel: snd_hda_codec_cirrus hdaudioC0D0: autoconfig for CS4206: line_outs=2 (0xb/0xa/0x0/0x0/0x0) type:speaker May 07 19:33:59 volumio kernel: snd_hda_codec_cirrus hdaudioC0D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) May 07 19:33:59 volumio kernel: snd_hda_codec_cirrus hdaudioC0D0: hp_outs=1 (0x9/0x0/0x0/0x0/0x0) May 07 19:33:59 volumio kernel: snd_hda_codec_cirrus hdaudioC0D0: mono: mono_out=0x0 May 07 19:33:59 volumio kernel: snd_hda_codec_cirrus hdaudioC0D0: dig-out=0x10/0x0 May 07 19:33:59 volumio kernel: snd_hda_codec_cirrus hdaudioC0D0: inputs: May 07 19:33:59 volumio kernel: snd_hda_codec_cirrus hdaudioC0D0: Mic=0xd May 07 19:33:59 volumio systemd-udevd[297]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 07 19:33:59 volumio systemd-udevd[284]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 07 19:33:59 volumio systemd-udevd[288]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 07 19:33:59 volumio kernel: intel_powerclamp: No package C-state available May 07 19:33:59 volumio systemd-udevd[298]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 07 19:33:59 volumio systemd-udevd[279]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 07 19:33:59 volumio systemd-udevd[274]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 07 19:33:59 volumio systemd-udevd[276]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 07 19:33:59 volumio kernel: input: HDA NVidia Headphone as /devices/pci0000:00/0000:00:08.0/sound/card0/input10 May 07 19:33:59 volumio systemd-udevd[296]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 07 19:33:59 volumio systemd-udevd[283]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 07 19:33:59 volumio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database May 07 19:33:59 volumio systemd-udevd[290]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 07 19:33:59 volumio kernel: Bluetooth: Core ver 2.22 May 07 19:33:59 volumio kernel: NET: Registered PF_BLUETOOTH protocol family May 07 19:33:59 volumio kernel: Bluetooth: HCI device and connection manager initialized May 07 19:33:59 volumio kernel: Bluetooth: HCI socket layer initialized May 07 19:33:59 volumio kernel: Bluetooth: L2CAP socket layer initialized May 07 19:33:59 volumio kernel: Bluetooth: SCO socket layer initialized May 07 19:33:59 volumio kernel: usbcore: registered new interface driver btusb May 07 19:33:59 volumio systemd-udevd[294]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 07 19:33:59 volumio kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' May 07 19:33:59 volumio kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' May 07 19:33:59 volumio kernel: usb 2-1.2: USB disconnect, device number 4 May 07 19:33:59 volumio systemd-udevd[292]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 07 19:33:59 volumio kernel: Bluetooth: hci0: BCM: chip id 254 build 0522 May 07 19:33:59 volumio kernel: Bluetooth: hci0: BCM: product 05ac:8213 May 07 19:33:59 volumio kernel: Bluetooth: hci0: BCM: features 0x00 May 07 19:33:59 volumio kernel: Bluetooth: hci0: Bluetooth USB Host Controller May 07 19:33:59 volumio kernel: usb 2-1.3: USB disconnect, device number 5 May 07 19:34:00 volumio systemd[1]: Found device Cruzer_Blade boot. May 07 19:34:00 volumio kernel: ACPI Warning: \_SB.PCI0.IXVE.IGPU._DSM: Argument #4 type mismatch - Found [Buffer], ACPI requires [Package] (20230628/nsarguments-61) May 07 19:34:00 volumio kernel: ACPI: \_SB_.PCI0.IXVE.IGPU: failed to evaluate _DSM May 07 19:34:00 volumio kernel: Console: switching to colour dummy device 80x25 May 07 19:34:00 volumio kernel: nouveau 0000:02:00.0: vgaarb: deactivate vga console May 07 19:34:00 volumio kernel: nouveau 0000:02:00.0: NVIDIA MCP79/MCP7A (0ac180b1) May 07 19:34:00 volumio kernel: nouveau 0000:02:00.0: bios: version 62.79.67.00.00 May 07 19:34:00 volumio kernel: nouveau 0000:02:00.0: fb: 256 MiB stolen system memory May 07 19:34:00 volumio systemd[1]: Condition check resulted in MCP79 Ethernet (Apple iMac 9,1) being skipped. May 07 19:34:00 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. May 07 19:34:00 volumio kernel: b43-phy0: Broadcom 4322 WLAN found (core revision 16) May 07 19:34:00 volumio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. May 07 19:34:00 volumio systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. May 07 19:34:00 volumio systemd[1]: Created slice system-systemd\x2dbacklight.slice. May 07 19:34:00 volumio kernel: nouveau 0000:02:00.0: DRM: VRAM: 256 MiB May 07 19:34:00 volumio kernel: nouveau 0000:02:00.0: DRM: GART: 1048576 MiB May 07 19:34:00 volumio kernel: nouveau 0000:02:00.0: DRM: TMDS table version 2.0 May 07 19:34:00 volumio kernel: nouveau 0000:02:00.0: DRM: DCB version 4.0 May 07 19:34:00 volumio kernel: nouveau 0000:02:00.0: DRM: DCB outp 00: 01000123 00010014 May 07 19:34:00 volumio kernel: nouveau 0000:02:00.0: DRM: DCB outp 01: 02021232 00000010 May 07 19:34:00 volumio kernel: nouveau 0000:02:00.0: DRM: DCB outp 02: 02021286 0f220010 May 07 19:34:00 volumio kernel: nouveau 0000:02:00.0: DRM: DCB conn 00: 00000040 May 07 19:34:00 volumio kernel: nouveau 0000:02:00.0: DRM: DCB conn 01: 0000a146 May 07 19:34:00 volumio kernel: nouveau 0000:02:00.0: DRM: MM: using M2MF for buffer copies May 07 19:34:00 volumio systemd[1]: Starting Load/Save Screen Backlight Brightness of leds:smc::kbd_backlight... May 07 19:34:00 volumio systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. May 07 19:34:00 volumio systemd[1]: Starting File System Check on /dev/disk/by-uuid/E2BB-AF0D... May 07 19:34:00 volumio kernel: nouveau 0000:02:00.0: DRM: Skipping nv_backlight registration May 07 19:34:00 volumio kernel: [drm] Initialized nouveau 1.4.0 20120801 for 0000:02:00.0 on minor 0 May 07 19:34:00 volumio kernel: b43-phy0: Found PHY: Analog 8, Type 4 (N), Revision 4 May 07 19:34:00 volumio kernel: b43-phy0: Found Radio: Manuf 0x17F, ID 0x2056, Revision 3, Version 0 May 07 19:34:00 volumio systemd[1]: Starting Load/Save RF Kill Switch Status... May 07 19:34:00 volumio kernel: Broadcom 43xx driver loaded [ Features: PNLS ] May 07 19:34:00 volumio systemd[1]: Started Load/Save Screen Backlight Brightness of leds:smc::kbd_backlight. May 07 19:34:00 volumio systemd[1]: Started Load/Save RF Kill Switch Status. May 07 19:34:00 volumio kernel: ieee80211 phy0: Selected rate control algorithm 'minstrel_ht' May 07 19:34:00 volumio kernel: fbcon: nouveaudrmfb (fb0) is primary device May 07 19:34:00 volumio systemd-udevd[296]: Using default interface naming scheme 'v240'. May 07 19:34:00 volumio systemd-udevd[296]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 07 19:34:00 volumio systemd[1]: Started File System Check Daemon to report status. May 07 19:34:00 volumio kernel: Console: switching to colour frame buffer device 160x50 May 07 19:34:00 volumio kernel: nouveau 0000:02:00.0: [drm] fb0: nouveaudrmfb frame buffer device May 07 19:34:00 volumio systemd[1]: Found device BCM4322 802.11a/b/g/n Wireless LAN Controller (AirPort Extreme). May 07 19:34:00 volumio systemd-fsck[433]: fsck.fat 4.1 (2017-01-24) May 07 19:34:00 volumio systemd-fsck[433]: /dev/sdb1: 20 files, 19783/43727 clusters May 07 19:34:00 volumio systemd[1]: Started File System Check on /dev/disk/by-uuid/E2BB-AF0D. May 07 19:34:01 volumio systemd[1]: Mounting /boot... May 07 19:34:01 volumio kernel: alsactl[398]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set May 07 19:34:01 volumio kernel: FAT-fs (sdb1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! May 07 19:34:01 volumio systemd-udevd[274]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 0' failed with exit code 99. May 07 19:34:01 volumio systemd[1]: Mounted /boot. May 07 19:34:01 volumio systemd[1]: Reached target Local File Systems. May 07 19:34:01 volumio systemd[1]: Starting Preprocess NFS configuration... May 07 19:34:01 volumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... May 07 19:34:01 volumio systemd[1]: Starting Create Volatile Files and Directories... May 07 19:34:01 volumio systemd[1]: Starting Raise network interfaces... May 07 19:34:01 volumio systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. May 07 19:34:01 volumio systemd[1]: Started ifup for eth0. May 07 19:34:01 volumio systemd[1]: Started ifup for wlan0. May 07 19:34:01 volumio systemd[1]: nfs-config.service: Succeeded. May 07 19:34:01 volumio systemd[1]: Started Preprocess NFS configuration. May 07 19:34:01 volumio systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. May 07 19:34:01 volumio systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. May 07 19:34:01 volumio systemd[1]: Reached target NFS client services. May 07 19:34:01 volumio systemd[1]: Started Create Volatile Files and Directories. May 07 19:34:01 volumio systemd[1]: Condition check resulted in Network Time Synchronization being skipped. May 07 19:34:01 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. May 07 19:34:01 volumio ifup[451]: command failed: Operation not supported (-95) May 07 19:34:01 volumio sh[452]: command failed: Operation not supported (-95) May 07 19:34:01 volumio sh[454]: command failed: Operation not supported (-95) May 07 19:34:01 volumio systemd[1]: Starting RPC bind portmap service... May 07 19:34:01 volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... May 07 19:34:01 volumio kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 May 07 19:34:01 volumio systemd[1]: Started Update UTMP about System Boot/Shutdown. May 07 19:34:01 volumio ifup[451]: command failed: Operation not supported (-95) May 07 19:34:01 volumio kernel: 8021q: 802.1Q VLAN Support v1.8 May 07 19:34:01 volumio kernel: b43-phy0: Loading firmware version 666.2 (2011-02-23 01:15:07) May 07 19:34:01 volumio haveged[477]: haveged: ver: 1.9.1; arch: x86; vend: GenuineIntel; build: (gcc 8.3.0 ITV); collect: 128K May 07 19:34:01 volumio haveged[477]: haveged: cpu: (L4 VC); data: 32K (L2 L4 V); inst: 32K (L2 L4 V); idx: 24/40; sz: 31410/52825 May 07 19:34:01 volumio haveged[477]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00281 May 07 19:34:01 volumio haveged[477]: haveged: fills: 0, generated: 0 May 07 19:34:01 volumio systemd[1]: Received SIGRTMIN+20 from PID 293 (plymouthd). May 07 19:34:01 volumio systemd[1]: plymouth-read-write.service: Succeeded. May 07 19:34:01 volumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. May 07 19:34:01 volumio systemd[1]: Reached target System Initialization. May 07 19:34:01 volumio systemd[1]: Started Volumio Splash. May 07 19:34:01 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. May 07 19:34:01 volumio systemd[519]: volumiosplash.service: Failed to execute command: No such file or directory May 07 19:34:01 volumio systemd[519]: volumiosplash.service: Failed at step EXEC spawning /bin/volumiosplash.sh: No such file or directory May 07 19:34:01 volumio systemd[1]: Listening on triggerhappy.socket. May 07 19:34:01 volumio systemd[1]: Started Daily man-db regeneration. May 07 19:34:01 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. May 07 19:34:01 volumio systemd[1]: Started Daily apt download activities. May 07 19:34:01 volumio systemd[1]: Started Daily apt upgrade and clean activities. May 07 19:34:01 volumio systemd[1]: Reached target Timers. May 07 19:34:01 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. May 07 19:34:01 volumio systemd[1]: Reached target Sockets. May 07 19:34:01 volumio systemd[1]: Reached target Basic System. May 07 19:34:01 volumio systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. May 07 19:34:01 volumio systemd[1]: Started volumio-remote-updater.service. May 07 19:34:01 volumio systemd[1]: Started D-Bus System Message Bus. May 07 19:34:01 volumio systemd[1]: Starting Wireless Services... May 07 19:34:01 volumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. May 07 19:34:01 volumio systemd[1]: Starting WPA supplicant... May 07 19:34:02 volumio systemd[1]: Started Volumio Log Rotation Service. May 07 19:34:02 volumio systemd[1]: Starting Login Service... May 07 19:34:02 volumio systemd[1]: Started Manage Sound Card State (restore and store). May 07 19:34:02 volumio alsactl[526]: alsactl 1.1.8 daemon started May 07 19:34:02 volumio systemd[1]: Starting Bluetooth service... May 07 19:34:02 volumio systemd[1]: Starting Save/Restore Sound Card State... May 07 19:34:02 volumio systemd[1]: Started UPnP Renderer front-end to MPD. May 07 19:34:02 volumio alsactl[526]: No state is present for card NVidia May 07 19:34:02 volumio alsactl[526]: Found hardware: "HDA-Intel" "Cirrus Logic CS4206" "HDA:10134206,106b4d00,00100301" "0x10de" "0xcb79" May 07 19:34:02 volumio alsactl[526]: Hardware is initialized using a generic method May 07 19:34:02 volumio alsactl[526]: No state is present for card NVidia May 07 19:34:02 volumio systemd[1]: Started Volumio Iptables Module. May 07 19:34:02 volumio systemd[1]: Starting triggerhappy global hotkey daemon... May 07 19:34:02 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... May 07 19:34:02 volumio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. May 07 19:34:02 volumio systemd[1]: Starting DHCP Client Daemon... May 07 19:34:02 volumio systemd[1]: volumiosplash.service: Main process exited, code=exited, status=203/EXEC May 07 19:34:02 volumio systemd[1]: volumiosplash.service: Failed with result 'exit-code'. May 07 19:34:02 volumio alsactl[528]: No state is present for card NVidia May 07 19:34:02 volumio alsactl[528]: Found hardware: "HDA-Intel" "Cirrus Logic CS4206" "HDA:10134206,106b4d00,00100301" "0x10de" "0xcb79" May 07 19:34:02 volumio alsactl[528]: Hardware is initialized using a generic method May 07 19:34:02 volumio alsactl[528]: No state is present for card NVidia May 07 19:34:02 volumio thd[532]: Unable to parse trigger line: May 07 19:34:02 volumio thd[532]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle May 07 19:34:02 volumio thd[532]: Unable to parse trigger line: May 07 19:34:02 volumio thd[532]: Found socket passed from systemd May 07 19:34:02 volumio thd[532]: Device /dev/input/event9 not suitable. May 07 19:34:02 volumio systemd-logind[525]: New seat seat0. May 07 19:34:02 volumio systemd-logind[525]: Watching system buttons on /dev/input/event3 (Power Button) May 07 19:34:02 volumio systemd-logind[525]: Watching system buttons on /dev/input/event1 (Power Button) May 07 19:34:02 volumio systemd-logind[525]: Watching system buttons on /dev/input/event0 (Lid Switch) May 07 19:34:02 volumio systemd-logind[525]: Watching system buttons on /dev/input/event2 (Sleep Button) May 07 19:34:02 volumio systemd-logind[525]: Watching system buttons on /dev/input/event7 (Apple Inc. Apple Internal Keyboard / Trackpad) May 07 19:34:02 volumio avahi-daemon[533]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). May 07 19:34:02 volumio avahi-daemon[533]: Successfully dropped root privileges. May 07 19:34:02 volumio avahi-daemon[533]: avahi-daemon 0.7 starting up. May 07 19:34:02 volumio bluetoothd[527]: Bluetooth daemon 5.50 May 07 19:34:02 volumio bluetoothd[527]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf May 07 19:34:02 volumio sh[452]: eth0: waiting for carrier May 07 19:34:02 volumio dhcpcd[504]: eth0: waiting for carrier May 07 19:34:02 volumio kernel: forcedeth 0000:00:0a.0 eth0: MSI enabled May 07 19:34:02 volumio dhcpcd[534]: DUID 00:04:2c:7a:2e:5d:96:8a:c6:4d:a1:e4:92:cd:72:bb:32:56 May 07 19:34:02 volumio dhcpcd[534]: wlan0: IAID 08:e9:dd:5d May 07 19:34:02 volumio dhcpcd[534]: DUID 00:04:2c:7a:2e:5d:96:8a:c6:4d:a1:e4:92:cd:72:bb:32:56 May 07 19:34:02 volumio dhcpcd[534]: wlan0: IAID 08:e9:dd:5d May 07 19:34:02 volumio dhcpcd[534]: wlan0: adding address fe80::54a7:a5d2:b007:3f85 May 07 19:34:02 volumio dhcpcd[534]: ipv6_addaddr1: Permission denied May 07 19:34:02 volumio dhcpcd[534]: eth0: waiting for carrier May 07 19:34:02 volumio dhcpcd[534]: wlan0: carrier lost May 07 19:34:02 volumio dhcpcd[534]: wlan0: adding address fe80::54a7:a5d2:b007:3f85 May 07 19:34:02 volumio dhcpcd[534]: ipv6_addaddr1: Permission denied May 07 19:34:02 volumio dhcpcd[534]: eth0: waiting for carrier May 07 19:34:02 volumio dhcpcd[534]: wlan0: carrier lost May 07 19:34:02 volumio systemd[1]: Started Login Service. May 07 19:34:02 volumio systemd[1]: Started triggerhappy global hotkey daemon. May 07 19:34:02 volumio systemd[1]: Started RPC bind portmap service. May 07 19:34:02 volumio systemd[1]: Started Save/Restore Sound Card State. May 07 19:34:02 volumio avahi-daemon[533]: Successfully called chroot(). May 07 19:34:02 volumio avahi-daemon[533]: Successfully dropped remaining capabilities. May 07 19:34:02 volumio wpa_supplicant[523]: Successfully initialized wpa_supplicant May 07 19:34:02 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. May 07 19:34:02 volumio systemd[1]: Started WPA supplicant. May 07 19:34:02 volumio systemd[1]: Started Bluetooth service. May 07 19:34:02 volumio systemd[1]: Reached target Bluetooth. May 07 19:34:02 volumio systemd[1]: Reached target Sound Card. May 07 19:34:02 volumio systemd[1]: Reached target Remote File Systems (Pre). May 07 19:34:02 volumio systemd[1]: Reached target Remote File Systems. May 07 19:34:02 volumio avahi-daemon[533]: No service file found in /etc/avahi/services. May 07 19:34:02 volumio avahi-daemon[533]: Network interface enumeration completed. May 07 19:34:02 volumio bluetoothd[527]: Starting SDP server May 07 19:34:02 volumio bluetoothd[527]: Excluding (cli) sap May 07 19:34:02 volumio avahi-daemon[533]: Server startup complete. Host name is volumio.local. Local service cookie is 2091875113. May 07 19:34:02 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... May 07 19:34:02 volumio systemd[1]: Reached target RPC Port Mapper. May 07 19:34:02 volumio sh[454]: wlan0=wlan0 May 07 19:34:02 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 May 07 19:34:02 volumio kernel: Bluetooth: BNEP filters: protocol multicast May 07 19:34:02 volumio kernel: Bluetooth: BNEP socket layer initialized May 07 19:34:02 volumio systemd[1]: Started Raise network interfaces. May 07 19:34:02 volumio systemd[1]: Reached target Network. May 07 19:34:02 volumio systemd[1]: Reached target Network is Online. May 07 19:34:02 volumio bluetoothd[527]: Bluetooth management interface 1.22 initialized May 07 19:34:02 volumio dbus-daemon[521]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.3' (uid=0 pid=527 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") May 07 19:34:02 volumio kernel: Bluetooth: MGMT ver 1.22 May 07 19:34:02 volumio systemd[1]: Starting LSB: Brings up/down network automatically... May 07 19:34:02 volumio bluetoothd[527]: Failed to clear UUIDs: Failed (0x03) May 07 19:34:02 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 07 19:34:02 volumio systemd[1]: Starting Permit User Sessions... May 07 19:34:03 volumio systemd[1]: Starting Samba NMB Daemon... May 07 19:34:03 volumio systemd[1]: Starting Network Time Service... May 07 19:34:03 volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. May 07 19:34:03 volumio systemd[1]: Starting /etc/rc.local Compatibility... May 07 19:34:03 volumio sh[452]: eth0: carrier acquired May 07 19:34:03 volumio dhcpcd[504]: eth0: carrier acquired May 07 19:34:03 volumio dhcpcd[534]: eth0: carrier acquired May 07 19:34:03 volumio dhcpcd[534]: eth0: carrier acquired May 07 19:34:03 volumio sh[452]: DUID 00:04:2c:7a:2e:5d:96:8a:c6:4d:a1:e4:92:cd:72:bb:32:56 May 07 19:34:03 volumio sh[452]: eth0: IAID 4a:10:47:88 May 07 19:34:03 volumio sh[452]: eth0: adding address fe80::a351:1144:facd:5ef8 May 07 19:34:03 volumio sh[452]: ipv6_addaddr1: Permission denied May 07 19:34:03 volumio dhcpcd[504]: DUID 00:04:2c:7a:2e:5d:96:8a:c6:4d:a1:e4:92:cd:72:bb:32:56 May 07 19:34:03 volumio dhcpcd[504]: eth0: IAID 4a:10:47:88 May 07 19:34:03 volumio dhcpcd[504]: eth0: adding address fe80::a351:1144:facd:5ef8 May 07 19:34:03 volumio dhcpcd[504]: ipv6_addaddr1: Permission denied May 07 19:34:03 volumio dhcpcd[534]: eth0: IAID 4a:10:47:88 May 07 19:34:03 volumio dhcpcd[534]: eth0: adding address fe80::a351:1144:facd:5ef8 May 07 19:34:03 volumio dhcpcd[534]: ipv6_addaddr1: Permission denied May 07 19:34:03 volumio dhcpcd[534]: eth0: IAID 4a:10:47:88 May 07 19:34:03 volumio dhcpcd[534]: eth0: adding address fe80::a351:1144:facd:5ef8 May 07 19:34:03 volumio dhcpcd[534]: ipv6_addaddr1: Permission denied May 07 19:34:03 volumio volumio-remote-updater[520]: Error: No active session May 07 19:34:03 volumio volumio-remote-updater[520]: [2024-05-07 19:34:03] [info] asio async_connect error: system:111 (Connection refused) May 07 19:34:03 volumio volumio-remote-updater[520]: [2024-05-07 19:34:03] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 07 19:34:03 volumio systemd[1]: Started Permit User Sessions. May 07 19:34:03 volumio systemd[1]: Started /etc/rc.local Compatibility. May 07 19:34:03 volumio volumio-remote-updater[520]: [2024-05-07 19:34:03] [error] handle_connect error: Underlying Transport Error May 07 19:34:03 volumio systemd[1]: iptables.service: Succeeded. May 07 19:34:03 volumio ntpd[644]: ntpd 4.2.8p12@1.3728-o (1): Starting May 07 19:34:03 volumio ntpd[644]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 07 19:34:03 volumio systemd[1]: Started Network Time Service. May 07 19:34:03 volumio volumio[530]: Could not open config: /tmp/upmpdcli.conf May 07 19:34:03 volumio ntpd[661]: proto: precision = 1.800 usec (-19) May 07 19:34:03 volumio systemd[1]: Starting Hold until boot process finishes up... May 07 19:34:03 volumio dhcpcd[534]: eth0: soliciting an IPv6 router May 07 19:34:03 volumio dhcpcd[534]: eth0: soliciting an IPv6 router May 07 19:34:03 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... May 07 19:34:03 volumio systemd[1]: Starting Hostname Service... May 07 19:34:03 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 07 19:34:03 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 07 19:34:03 volumio systemd[1]: Received SIGRTMIN+21 from PID 293 (plymouthd). May 07 19:34:03 volumio ntpd[661]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 07 19:34:03 volumio ntpd[661]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 07 19:34:03 volumio ntpd[661]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 498 days ago May 07 19:34:03 volumio ntpd[661]: Listen and drop on 0 v6wildcard [::]:123 May 07 19:34:03 volumio ntpd[661]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 07 19:34:03 volumio ntpd[661]: Listen normally on 2 lo 127.0.0.1:123 May 07 19:34:03 volumio ntpd[661]: Listening on routing socket on fd #19 for interface updates May 07 19:34:03 volumio ntpd[661]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 07 19:34:03 volumio ntpd[661]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 07 19:34:03 volumio ifplugd[638]: Network Interface Plugging Daemon...error: unexpectedly disconnected from boot status daemon May 07 19:34:03 volumio systemd[1]: plymouth-start.service: Succeeded. May 07 19:34:03 volumio systemd[1]: plymouth-quit-wait.service: Succeeded. May 07 19:34:03 volumio systemd[1]: Started Hold until boot process finishes up. May 07 19:34:03 volumio systemd[1]: plymouth-quit.service: Succeeded. May 07 19:34:03 volumio systemd[1]: Started Terminate Plymouth Boot Screen. May 07 19:34:03 volumio systemd[1]: Received SIGRTMIN+21 from PID 293 (n/a). May 07 19:34:03 volumio systemd[1]: Started Getty on tty1. May 07 19:34:03 volumio systemd[1]: Reached target Login Prompts. May 07 19:34:03 volumio ifplugd(eth0)[691]: ifplugd 0.28 initializing. May 07 19:34:03 volumio ifplugd(eth0)[691]: Using interface eth0/00:26:4A:10:47:88 with driver (version: 0.64) May 07 19:34:03 volumio ifplugd(eth0)[691]: Using detection mode: SIOCETHTOOL May 07 19:34:03 volumio ifplugd(eth0)[691]: Initialization complete, link beat detected. May 07 19:34:03 volumio dhcpcd[534]: eth0: rebinding lease of 192.168.100.86 May 07 19:34:03 volumio dhcpcd[534]: eth0: rebinding lease of 192.168.100.86 May 07 19:34:03 volumio dhcpcd[534]: eth0: NAK: from 192.168.100.1 May 07 19:34:03 volumio dhcpcd[534]: eth0: NAK: from 192.168.100.1 May 07 19:34:03 volumio sh[452]: eth0: soliciting an IPv6 router May 07 19:34:03 volumio dhcpcd[504]: eth0: soliciting an IPv6 router May 07 19:34:03 volumio dhcpcd[534]: eth0: soliciting a DHCP lease May 07 19:34:03 volumio dhcpcd[534]: eth0: soliciting a DHCP lease May 07 19:34:03 volumio ifplugd(eth0)[691]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. May 07 19:34:03 volumio loadcpufreq[609]: Loading cpufreq kernel modules...done (acpi-cpufreq). May 07 19:34:03 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. May 07 19:34:03 volumio dbus-daemon[521]: [system] Successfully activated service 'org.freedesktop.hostname1' May 07 19:34:03 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... May 07 19:34:03 volumio systemd[1]: Started Hostname Service. May 07 19:34:03 volumio ifplugd(eth0)[691]: client: ifup: waiting for lock on /run/network/ifstate.eth0 May 07 19:34:03 volumio cpufrequtils[719]: CPUFreq Utilities: Setting ondemand CPUFreq governor...disabled, governor not available...done. May 07 19:34:03 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. May 07 19:34:04 volumio sh[452]: eth0: soliciting a DHCP lease May 07 19:34:04 volumio dhcpcd[504]: eth0: soliciting a DHCP lease May 07 19:34:04 volumio ntpd[661]: error resolving pool 0.debian.pool.ntp.org: System error (-11) May 07 19:34:04 volumio nmbd[647]: [2024/05/07 19:34:04.529501, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) May 07 19:34:04 volumio nmbd[647]: started asyncdns process 737 May 07 19:34:04 volumio nmbd[647]: [2024/05/07 19:34:04.531305, 0] ../lib/util/become_daemon.c:149(daemon_status) May 07 19:34:04 volumio nmbd[647]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... May 07 19:34:04 volumio nmbd[647]: [2024/05/07 19:34:04.531411, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) May 07 19:34:04 volumio nmbd[647]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). May 07 19:34:04 volumio wireless.js[522]: Cleaning previous... May 07 19:34:04 volumio sudo[745]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 07 19:34:04 volumio sudo[745]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:34:04 volumio sudo[745]: pam_unix(sudo:session): session closed for user root May 07 19:34:04 volumio sudo[747]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 07 19:34:04 volumio sudo[747]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:34:05 volumio sudo[747]: pam_unix(sudo:session): session closed for user root May 07 19:34:05 volumio wireless.js[522]: Stopped aP May 07 19:34:05 volumio sudo[754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 07 19:34:05 volumio sudo[754]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:34:05 volumio kernel: b43-phy0: Loading firmware version 666.2 (2011-02-23 01:15:07) May 07 19:34:05 volumio ntpd[661]: error resolving pool 1.debian.pool.ntp.org: System error (-11) May 07 19:34:05 volumio sh[452]: eth0: offered 192.168.100.98 from 192.168.100.1 May 07 19:34:05 volumio dhcpcd[504]: eth0: offered 192.168.100.98 from 192.168.100.1 May 07 19:34:05 volumio sudo[754]: pam_unix(sudo:session): session closed for user root May 07 19:34:05 volumio sudo[757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 07 19:34:05 volumio sudo[757]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:34:05 volumio sudo[757]: pam_unix(sudo:session): session closed for user root May 07 19:34:05 volumio systemd[1]: systemd-rfkill.service: Succeeded. May 07 19:34:05 volumio sudo[764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 07 19:34:05 volumio sudo[764]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:34:05 volumio sudo[764]: pam_unix(sudo:session): session closed for user root May 07 19:34:05 volumio sudo[766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 07 19:34:05 volumio sudo[766]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:34:05 volumio sh[452]: eth0: probing address 192.168.100.98/24 May 07 19:34:05 volumio dhcpcd[504]: eth0: probing address 192.168.100.98/24 May 07 19:34:06 volumio ntpd[661]: error resolving pool 2.debian.pool.ntp.org: System error (-11) May 07 19:34:07 volumio ntpd[661]: error resolving pool 3.debian.pool.ntp.org: System error (-11) May 07 19:34:08 volumio volumio-remote-updater[520]: [2024-05-07 19:34:08] [info] asio async_connect error: system:111 (Connection refused) May 07 19:34:08 volumio volumio-remote-updater[520]: [2024-05-07 19:34:08] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 07 19:34:08 volumio volumio-remote-updater[520]: [2024-05-07 19:34:08] [error] handle_connect error: Underlying Transport Error May 07 19:34:08 volumio dhcpcd[534]: eth0: probing for an IPv4LL address May 07 19:34:08 volumio dhcpcd[534]: eth0: probing for an IPv4LL address May 07 19:34:09 volumio dhcpcd[534]: eth0: offered 192.168.100.98 from 192.168.100.1 May 07 19:34:09 volumio dhcpcd[534]: eth0: offered 192.168.100.98 from 192.168.100.1 May 07 19:34:09 volumio dhcpcd[534]: eth0: probing address 192.168.100.98/24 May 07 19:34:09 volumio dhcpcd[534]: eth0: probing address 192.168.100.98/24 May 07 19:34:10 volumio sh[452]: eth0: leased 192.168.100.98 for 259200 seconds May 07 19:34:10 volumio sh[452]: eth0: adding route to 192.168.100.0/24 May 07 19:34:10 volumio sh[452]: eth0: adding default route via 192.168.100.1 May 07 19:34:10 volumio dhcpcd[504]: eth0: leased 192.168.100.98 for 259200 seconds May 07 19:34:10 volumio dhcpcd[504]: eth0: adding route to 192.168.100.0/24 May 07 19:34:10 volumio dhcpcd[504]: eth0: adding default route via 192.168.100.1 May 07 19:34:10 volumio avahi-daemon[533]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.100.98. May 07 19:34:10 volumio avahi-daemon[533]: New relevant interface eth0.IPv4 for mDNS. May 07 19:34:10 volumio avahi-daemon[533]: Registering new address record for 192.168.100.98 on eth0.IPv4. May 07 19:34:10 volumio sh[452]: Warning: The unit file, source configuration file or drop-ins of systemd-timesyncd.service changed on disk. Run 'systemctl daemon-reload' to reload units. May 07 19:34:10 volumio ntpd[661]: ntpd exiting on signal 15 (Terminated) May 07 19:34:10 volumio systemd[1]: Stopping Network Time Service... May 07 19:34:10 volumio systemd[1]: ntp.service: Succeeded. May 07 19:34:10 volumio systemd[1]: Stopped Network Time Service. May 07 19:34:10 volumio systemd[1]: Starting Network Time Service... May 07 19:34:10 volumio ntpd[803]: ntpd 4.2.8p12@1.3728-o (1): Starting May 07 19:34:10 volumio ntpd[803]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 07 19:34:10 volumio systemd[1]: Started Network Time Service. May 07 19:34:10 volumio ntpd[809]: proto: precision = 1.800 usec (-19) May 07 19:34:10 volumio ntpd[809]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 07 19:34:10 volumio ntpd[809]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 07 19:34:10 volumio ntpd[809]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 498 days ago May 07 19:34:10 volumio ntpd[809]: Listen and drop on 0 v6wildcard [::]:123 May 07 19:34:10 volumio ntpd[809]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 07 19:34:10 volumio ntpd[809]: Listen normally on 2 lo 127.0.0.1:123 May 07 19:34:10 volumio ntpd[809]: Listen normally on 3 eth0 192.168.100.98:123 May 07 19:34:10 volumio ntpd[809]: Listening on routing socket on fd #20 for interface updates May 07 19:34:10 volumio ntpd[809]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 07 19:34:10 volumio ntpd[809]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 07 19:34:10 volumio sh[452]: forked to background, child pid 810 May 07 19:34:10 volumio dhcpcd[504]: forked to background, child pid 810 May 07 19:34:10 volumio ifplugd(eth0)[691]: client: ifup: interface eth0 already configured May 07 19:34:10 volumio sh[452]: eth0=eth0 May 07 19:34:10 volumio ifplugd(eth0)[691]: Program executed successfully. May 07 19:34:10 volumio ifplugd[638]: start eth0...done. May 07 19:34:10 volumio systemd[1]: Started LSB: Brings up/down network automatically. May 07 19:34:11 volumio ntpd[809]: Soliciting pool server 192.36.143.130 May 07 19:34:12 volumio ntpd[809]: Soliciting pool server 193.182.111.13 May 07 19:34:12 volumio ntpd[809]: Soliciting pool server 216.238.85.87 May 07 19:34:13 volumio ntpd[809]: Soliciting pool server 45.231.168.6 May 07 19:34:13 volumio ntpd[809]: Soliciting pool server 45.231.168.6 May 07 19:34:13 volumio ntpd[809]: Soliciting pool server 45.183.178.195 May 07 19:34:13 volumio dhcpcd[534]: eth0: using IPv4LL address 169.254.203.23 May 07 19:34:13 volumio dhcpcd[534]: eth0: adding route to 169.254.0.0/16 May 07 19:34:13 volumio dhcpcd[534]: eth0: adding default route May 07 19:34:13 volumio dhcpcd[534]: eth0: using IPv4LL address 169.254.203.23 May 07 19:34:13 volumio avahi-daemon[533]: Registering new address record for 169.254.203.23 on eth0.IPv4. May 07 19:34:13 volumio dhcpcd[534]: eth0: adding route to 169.254.0.0/16 May 07 19:34:13 volumio dhcpcd[534]: eth0: adding default route May 07 19:34:13 volumio dhcpcd[810]: eth0: deleted default route via 192.168.100.1 May 07 19:34:13 volumio dhcpcd[534]: forked to background, child pid 853 May 07 19:34:13 volumio dhcpcd[534]: forked to background, child pid 853 May 07 19:34:13 volumio systemd[1]: Started DHCP Client Daemon. May 07 19:34:14 volumio nmbd[647]: [2024/05/07 19:34:14.537972, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 07 19:34:14 volumio systemd[1]: Started Samba NMB Daemon. May 07 19:34:14 volumio nmbd[647]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 07 19:34:14 volumio nmbd[647]: [2024/05/07 19:34:14.560817, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 07 19:34:14 volumio nmbd[647]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.100.98 for name WORKGROUP<1d>. May 07 19:34:14 volumio systemd[1]: Starting Samba Winbind Daemon... May 07 19:34:14 volumio nmbd[647]: This response was from IP 192.168.100.2, reporting an IP address of 192.168.100.2. May 07 19:34:14 volumio dhcpcd[853]: eth0: leased 192.168.100.98 for 259200 seconds May 07 19:34:14 volumio dhcpcd[853]: eth0: adding route to 192.168.100.0/24 May 07 19:34:14 volumio dhcpcd[853]: eth0: changing default route via 192.168.100.1 May 07 19:34:14 volumio ntpd[809]: Soliciting pool server 45.231.168.6 May 07 19:34:14 volumio ntpd[809]: Soliciting pool server 45.231.168.6 May 07 19:34:14 volumio ntpd[809]: ntpd exiting on signal 15 (Terminated) May 07 19:34:14 volumio systemd[1]: Stopping Network Time Service... May 07 19:34:14 volumio ntpd[809]: 192.36.143.130 local addr 192.168.100.98 -> May 07 19:34:14 volumio ntpd[809]: 216.238.85.87 local addr 192.168.100.98 -> May 07 19:34:14 volumio ntpd[809]: 193.182.111.13 local addr 192.168.100.98 -> May 07 19:34:14 volumio ntpd[809]: 45.183.178.195 local addr 192.168.100.98 -> May 07 19:34:14 volumio systemd[1]: ntp.service: Succeeded. May 07 19:34:14 volumio systemd[1]: Stopped Network Time Service. May 07 19:34:14 volumio systemd[1]: Starting Network Time Service... May 07 19:34:14 volumio ntpd[888]: ntpd 4.2.8p12@1.3728-o (1): Starting May 07 19:34:14 volumio ntpd[888]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 07 19:34:14 volumio systemd[1]: Started Network Time Service. May 07 19:34:14 volumio avahi-daemon[533]: Withdrawing address record for 169.254.203.23 on eth0. May 07 19:34:14 volumio dhcpcd[853]: eth0: deleting route to 169.254.0.0/16 May 07 19:34:14 volumio ntpd[894]: proto: precision = 1.080 usec (-20) May 07 19:34:14 volumio ntpd[894]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 07 19:34:14 volumio ntpd[894]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 07 19:34:14 volumio ntpd[894]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 498 days ago May 07 19:34:14 volumio ntpd[894]: Listen and drop on 0 v6wildcard [::]:123 May 07 19:34:14 volumio ntpd[894]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 07 19:34:14 volumio ntpd[894]: Listen normally on 2 lo 127.0.0.1:123 May 07 19:34:14 volumio ntpd[894]: Listen normally on 3 eth0 192.168.100.98:123 May 07 19:34:14 volumio ntpd[894]: Listening on routing socket on fd #20 for interface updates May 07 19:34:14 volumio ntpd[894]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 07 19:34:14 volumio ntpd[894]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 07 19:34:14 volumio winbindd[854]: [2024/05/07 19:34:14.778247, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 07 19:34:14 volumio winbindd[854]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 07 19:34:14 volumio winbindd[854]: [2024/05/07 19:34:14.792412, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 07 19:34:14 volumio systemd[1]: Started Samba Winbind Daemon. May 07 19:34:14 volumio winbindd[854]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 07 19:34:14 volumio systemd[1]: Starting Samba SMB Daemon... May 07 19:34:15 volumio smbd[913]: [2024/05/07 19:34:15.155341, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 07 19:34:15 volumio systemd[1]: Started Samba SMB Daemon. May 07 19:34:15 volumio smbd[913]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 07 19:34:15 volumio ntpd[894]: Soliciting pool server 193.182.111.13 May 07 19:34:15 volumio volumio-remote-updater[520]: [2024-05-07 19:34:15] [info] asio async_connect error: system:111 (Connection refused) May 07 19:34:15 volumio volumio-remote-updater[520]: [2024-05-07 19:34:15] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 07 19:34:15 volumio volumio-remote-updater[520]: [2024-05-07 19:34:15] [error] handle_connect error: Underlying Transport Error May 07 19:34:16 volumio sudo[766]: pam_unix(sudo:session): session closed for user root May 07 19:34:16 volumio wireless.js[522]: SETTING APPROPRIATE REG DOMAIN: MX May 07 19:34:16 volumio sudo[922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 07 19:34:16 volumio sudo[922]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:34:16 volumio sudo[922]: pam_unix(sudo:session): session closed for user root May 07 19:34:16 volumio sudo[924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set MX May 07 19:34:16 volumio sudo[924]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:34:16 volumio sudo[924]: pam_unix(sudo:session): session closed for user root May 07 19:34:16 volumio wireless.js[522]: SUCCESSFULLY SET NEW REGDOMAIN: MX May 07 19:34:16 volumio sudo[931]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 07 19:34:16 volumio sudo[931]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:34:16 volumio sudo[931]: pam_unix(sudo:session): session closed for user root May 07 19:34:16 volumio sudo[933]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 07 19:34:16 volumio sudo[933]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:34:16 volumio nmbd[647]: [2024/05/07 19:34:16.564569, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 07 19:34:16 volumio nmbd[647]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable May 07 19:34:16 volumio nmbd[647]: [2024/05/07 19:34:16.564687, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 07 19:34:16 volumio nmbd[647]: retransmit_or_expire_response_records: Failed to resend packet id 5421 to IP 169.254.255.255 on subnet 169.254.203.23 May 07 19:34:16 volumio nmbd[647]: [2024/05/07 19:34:16.564746, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 07 19:34:16 volumio nmbd[647]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable May 07 19:34:16 volumio nmbd[647]: [2024/05/07 19:34:16.564797, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 07 19:34:16 volumio nmbd[647]: retransmit_or_expire_response_records: Failed to resend packet id 5422 to IP 169.254.255.255 on subnet 169.254.203.23 May 07 19:34:16 volumio nmbd[647]: [2024/05/07 19:34:16.564853, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 07 19:34:16 volumio nmbd[647]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable May 07 19:34:16 volumio nmbd[647]: [2024/05/07 19:34:16.564905, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 07 19:34:16 volumio nmbd[647]: retransmit_or_expire_response_records: Failed to resend packet id 5423 to IP 169.254.255.255 on subnet 169.254.203.23 May 07 19:34:16 volumio nmbd[647]: [2024/05/07 19:34:16.564956, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 07 19:34:16 volumio nmbd[647]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable May 07 19:34:16 volumio nmbd[647]: [2024/05/07 19:34:16.565006, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 07 19:34:16 volumio nmbd[647]: retransmit_or_expire_response_records: Failed to resend packet id 5424 to IP 169.254.255.255 on subnet 169.254.203.23 May 07 19:34:16 volumio nmbd[647]: [2024/05/07 19:34:16.565059, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 07 19:34:16 volumio nmbd[647]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable May 07 19:34:16 volumio nmbd[647]: [2024/05/07 19:34:16.565108, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 07 19:34:16 volumio nmbd[647]: retransmit_or_expire_response_records: Failed to resend packet id 5425 to IP 169.254.255.255 on subnet 169.254.203.23 May 07 19:34:16 volumio nmbd[647]: [2024/05/07 19:34:16.565507, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 07 19:34:16 volumio nmbd[647]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable May 07 19:34:16 volumio nmbd[647]: [2024/05/07 19:34:16.565568, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 07 19:34:16 volumio nmbd[647]: retransmit_or_expire_response_records: Failed to resend packet id 5421 to IP 169.254.255.255 on subnet 169.254.203.23 May 07 19:34:16 volumio nmbd[647]: [2024/05/07 19:34:16.565621, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 07 19:34:16 volumio nmbd[647]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable May 07 19:34:16 volumio nmbd[647]: [2024/05/07 19:34:16.565670, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 07 19:34:16 volumio nmbd[647]: retransmit_or_expire_response_records: Failed to resend packet id 5422 to IP 169.254.255.255 on subnet 169.254.203.23 May 07 19:34:16 volumio nmbd[647]: [2024/05/07 19:34:16.565721, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 07 19:34:16 volumio nmbd[647]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable May 07 19:34:16 volumio nmbd[647]: [2024/05/07 19:34:16.565770, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 07 19:34:16 volumio nmbd[647]: retransmit_or_expire_response_records: Failed to resend packet id 5423 to IP 169.254.255.255 on subnet 169.254.203.23 May 07 19:34:16 volumio nmbd[647]: [2024/05/07 19:34:16.565819, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 07 19:34:16 volumio nmbd[647]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable May 07 19:34:16 volumio nmbd[647]: [2024/05/07 19:34:16.565880, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 07 19:34:16 volumio nmbd[647]: retransmit_or_expire_response_records: Failed to resend packet id 5424 to IP 169.254.255.255 on subnet 169.254.203.23 May 07 19:34:16 volumio nmbd[647]: [2024/05/07 19:34:16.565932, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 07 19:34:16 volumio nmbd[647]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable May 07 19:34:16 volumio nmbd[647]: [2024/05/07 19:34:16.565984, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 07 19:34:16 volumio nmbd[647]: retransmit_or_expire_response_records: Failed to resend packet id 5425 to IP 169.254.255.255 on subnet 169.254.203.23 May 07 19:34:16 volumio sudo[933]: pam_unix(sudo:session): session closed for user root May 07 19:34:16 volumio ntpd[894]: Soliciting pool server 192.36.143.130 May 07 19:34:16 volumio kernel: b43-phy0: Loading firmware version 666.2 (2011-02-23 01:15:07) May 07 19:34:17 volumio avahi-daemon[533]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. May 07 19:34:17 volumio ntpd[894]: Soliciting pool server 45.231.168.6 May 07 19:34:17 volumio avahi-daemon[533]: New relevant interface wlan0.IPv4 for mDNS. May 07 19:34:17 volumio avahi-daemon[533]: Registering new address record for 192.168.211.1 on wlan0.IPv4. May 07 19:34:17 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... May 07 19:34:17 volumio hostapd[940]: Configuration file: /etc/hostapd/hostapd.conf May 07 19:34:17 volumio avahi-daemon[533]: Interface wlan0.IPv4 no longer relevant for mDNS. May 07 19:34:17 volumio avahi-daemon[533]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. May 07 19:34:17 volumio avahi-daemon[533]: Withdrawing address record for 192.168.211.1 on wlan0. May 07 19:34:17 volumio ntpd[894]: Soliciting pool server 45.231.168.6 May 07 19:34:17 volumio kernel: b43-phy0: Loading firmware version 666.2 (2011-02-23 01:15:07) May 07 19:34:18 volumio avahi-daemon[533]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. May 07 19:34:18 volumio avahi-daemon[533]: New relevant interface wlan0.IPv4 for mDNS. May 07 19:34:18 volumio avahi-daemon[533]: Registering new address record for 192.168.211.1 on wlan0.IPv4. May 07 19:34:18 volumio ntpd[894]: Soliciting pool server 45.231.168.6 May 07 19:34:18 volumio hostapd[940]: Using interface wlan0 with hwaddr 00:26:08:e9:dd:5d and ssid "Volumio-FCA2E" May 07 19:34:18 volumio hostapd[940]: wlan0: interface state UNINITIALIZED->ENABLED May 07 19:34:18 volumio hostapd[940]: wlan0: AP-ENABLED May 07 19:34:18 volumio dhcpcd[853]: wlan0: carrier acquired May 07 19:34:18 volumio systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. May 07 19:34:18 volumio dhcpcd[853]: wlan0: IAID 08:e9:dd:5d May 07 19:34:18 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... May 07 19:34:18 volumio iwconfig[946]: Error for wireless request "Set Power Management" (8B2C) : May 07 19:34:18 volumio iwconfig[946]: SET failed on device wlan0 ; Invalid argument. May 07 19:34:18 volumio systemd[1]: wireless.service: Succeeded. May 07 19:34:18 volumio dnsmasq[944]: dnsmasq: syntax check OK. May 07 19:34:18 volumio systemd[1]: Started Wireless Services. May 07 19:34:18 volumio systemd[1]: Started Volumio Backend Module. May 07 19:34:18 volumio systemd[1]: Started Volumio Cpu Tweaker. May 07 19:34:18 volumio systemd[1]: Started Start Volumio Kiosk. May 07 19:34:18 volumio systemd[1]: Started Intel SST and HDA soundcard init service. May 07 19:34:18 volumio systemd[1]: Reached target Multi-User System. May 07 19:34:18 volumio systemd[1]: Reached target Graphical Interface. May 07 19:34:18 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... May 07 19:34:18 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 07 19:34:18 volumio volumio-cpu-tweak[950]: Setting RT Priority for mpd May 07 19:34:18 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. May 07 19:34:18 volumio volumio-cpu-tweak[950]: pid 35's current scheduling policy: SCHED_OTHER May 07 19:34:18 volumio volumio-cpu-tweak[950]: pid 35's current scheduling priority: 0 May 07 19:34:18 volumio volumio-cpu-tweak[950]: Not enough cores to set MPD affinity May 07 19:34:18 volumio volumio-cpu-tweak[950]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance May 07 19:34:18 volumio dnsmasq[986]: started, version 2.80 cachesize 150 May 07 19:34:18 volumio dnsmasq[986]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile May 07 19:34:18 volumio dnsmasq-dhcp[986]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d May 07 19:34:18 volumio dnsmasq[986]: reading /etc/resolv.conf May 07 19:34:18 volumio dnsmasq[986]: using nameserver 192.168.100.1#53 May 07 19:34:18 volumio dnsmasq[986]: using nameserver 208.67.222.222#53 May 07 19:34:18 volumio dnsmasq[986]: using nameserver 208.67.220.220#53 May 07 19:34:18 volumio dnsmasq[986]: read /etc/hosts - 5 addresses May 07 19:34:18 volumio nmbd[647]: [2024/05/07 19:34:18.569693, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 07 19:34:18 volumio nmbd[647]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable May 07 19:34:18 volumio nmbd[647]: [2024/05/07 19:34:18.569807, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 07 19:34:18 volumio nmbd[647]: retransmit_or_expire_response_records: Failed to resend packet id 5421 to IP 169.254.255.255 on subnet 169.254.203.23 May 07 19:34:18 volumio nmbd[647]: [2024/05/07 19:34:18.569867, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 07 19:34:18 volumio nmbd[647]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable May 07 19:34:18 volumio nmbd[647]: [2024/05/07 19:34:18.569917, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 07 19:34:18 volumio nmbd[647]: retransmit_or_expire_response_records: Failed to resend packet id 5422 to IP 169.254.255.255 on subnet 169.254.203.23 May 07 19:34:18 volumio nmbd[647]: [2024/05/07 19:34:18.571808, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 07 19:34:18 volumio nmbd[647]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable May 07 19:34:18 volumio nmbd[647]: [2024/05/07 19:34:18.572079, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 07 19:34:18 volumio nmbd[647]: retransmit_or_expire_response_records: Failed to resend packet id 5423 to IP 169.254.255.255 on subnet 169.254.203.23 May 07 19:34:18 volumio nmbd[647]: [2024/05/07 19:34:18.572133, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 07 19:34:18 volumio nmbd[647]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable May 07 19:34:18 volumio nmbd[647]: [2024/05/07 19:34:18.572184, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 07 19:34:18 volumio nmbd[647]: retransmit_or_expire_response_records: Failed to resend packet id 5424 to IP 169.254.255.255 on subnet 169.254.203.23 May 07 19:34:18 volumio nmbd[647]: [2024/05/07 19:34:18.572236, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 07 19:34:18 volumio nmbd[647]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable May 07 19:34:18 volumio nmbd[647]: [2024/05/07 19:34:18.572285, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 07 19:34:18 volumio nmbd[647]: retransmit_or_expire_response_records: Failed to resend packet id 5425 to IP 169.254.255.255 on subnet 169.254.203.23 May 07 19:34:18 volumio nmbd[647]: [2024/05/07 19:34:18.572762, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 07 19:34:18 volumio nmbd[647]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable May 07 19:34:18 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. May 07 19:34:18 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. May 07 19:34:18 volumio systemd[1]: Reached target Host and Network Name Lookups. May 07 19:34:18 volumio systemd[1]: Startup finished in 22.131s (kernel) + 22.879s (userspace) = 45.011s. May 07 19:34:18 volumio ntpd[894]: Soliciting pool server 45.231.168.6 May 07 19:34:18 volumio startx[951]: X.Org X Server 1.20.4 May 07 19:34:18 volumio startx[951]: X Protocol Version 11, Revision 0 May 07 19:34:18 volumio startx[951]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian May 07 19:34:18 volumio startx[951]: Current Operating System: Linux volumio 6.6.25-volumio #2 SMP PREEMPT_DYNAMIC Sun Apr 7 20:18:18 CEST 2024 x86_64 May 07 19:34:18 volumio startx[951]: Kernel command line: BOOT_IMAGE=/vmlinuz biosdevname=0 splash plymouth.ignore-serial-consoles quiet ro rootwait imgpart=UUID=7ab46900-3cb8-4deb-a4a8-9a1a2f978606 bootpart=UUID=E2BB-AF0D datapart=UUID=6c12c2b2-7468-42cb-89d5-2730f50d15fd imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor use_kmsg=yes net.ifnames=0 loglevel=0 May 07 19:34:18 volumio startx[951]: Build Date: 18 December 2021 09:05:36AM May 07 19:34:18 volumio startx[951]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) May 07 19:34:18 volumio startx[951]: Current version of pixman: 0.36.0 May 07 19:34:18 volumio startx[951]: Before reporting problems, check http://wiki.x.org May 07 19:34:18 volumio startx[951]: to make sure that you have the latest version. May 07 19:34:18 volumio startx[951]: Markers: (--) probed, (**) from config file, (==) default setting, May 07 19:34:18 volumio startx[951]: (++) from command line, (!!) notice, (II) informational, May 07 19:34:18 volumio startx[951]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. May 07 19:34:18 volumio startx[951]: (==) Log file: "/var/log/Xorg.0.log", Time: Tue May 7 19:34:18 2024 May 07 19:34:18 volumio startx[951]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" May 07 19:34:19 volumio dhcpcd[853]: wlan0: soliciting a DHCP lease May 07 19:34:19 volumio dhcpcd[853]: wlan0: soliciting an IPv6 router May 07 19:34:19 volumio systemd[1]: soundcard-init.service: Succeeded. May 07 19:34:19 volumio ntpd[894]: Listen normally on 4 wlan0 192.168.211.1:123 May 07 19:34:19 volumio ntpd[894]: new interface(s) found: waking up resolver May 07 19:34:21 volumio startx[951]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. May 07 19:34:21 volumio volumio[948]: info: ------------------------------------------- May 07 19:34:21 volumio volumio[948]: info: ----- Volumio3 ---- May 07 19:34:21 volumio volumio[948]: info: ------------------------------------------- May 07 19:34:21 volumio volumio[948]: info: ----- System startup ---- May 07 19:34:21 volumio volumio[948]: info: ------------------------------------------- May 07 19:34:23 volumio volumio[948]: info: MYVOLUMIO Environment detected May 07 19:34:23 volumio volumio[948]: info: Plugin folders cleanup May 07 19:34:23 volumio volumio[948]: info: Scanning into folder /volumio/app/plugins/ May 07 19:34:23 volumio volumio[948]: info: Scanning category audio_interface May 07 19:34:23 volumio volumio[948]: info: Scanning category miscellanea May 07 19:34:23 volumio volumio[948]: info: Scanning category music_service May 07 19:34:23 volumio volumio[948]: info: Scanning category plugins.json May 07 19:34:23 volumio volumio[948]: info: Scanning category system_controller May 07 19:34:23 volumio volumio[948]: info: Scanning category user_interface May 07 19:34:23 volumio volumio[948]: info: Scanning into folder /data/plugins/ May 07 19:34:23 volumio volumio[948]: info: Scanning category music_service May 07 19:34:23 volumio volumio[948]: info: Plugin folders cleanup completed May 07 19:34:23 volumio volumio[948]: info: ------------------------------------------- May 07 19:34:23 volumio volumio[948]: info: ----- Core plugins startup ---- May 07 19:34:23 volumio volumio[948]: info: ------------------------------------------- May 07 19:34:23 volumio volumio[948]: info: Loading plugins from folder /volumio/app/plugins/ May 07 19:34:23 volumio volumio[948]: info: Adding plugin upnp to MyMusic Plugins May 07 19:34:23 volumio volumio[948]: info: Adding plugin airplay_emulation to MyMusic Plugins May 07 19:34:23 volumio volumio[948]: info: Adding plugin upnp_browser to MyMusic Plugins May 07 19:34:23 volumio volumio[948]: info: Loading plugins from folder /data/plugins/ May 07 19:34:23 volumio volumio[948]: info: Loading plugin "system"... May 07 19:34:23 volumio volumio[948]: info: Loading plugin "appearance"... May 07 19:43:18 volumio dhcpcd[853]: wlan0: probing for an IPv4LL address May 07 19:43:19 volumio nmbd[647]: [2024/05/07 19:43:19.710660, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 07 19:43:19 volumio nmbd[647]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable May 07 19:43:19 volumio nmbd[647]: [2024/05/07 19:43:19.794555, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 07 19:43:19 volumio nmbd[647]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.100.98 for name WORKGROUP<1d>. May 07 19:43:19 volumio nmbd[647]: This response was from IP 192.168.100.2, reporting an IP address of 192.168.100.2. May 07 19:43:19 volumio volumio-remote-updater[520]: [2024-05-07 19:43:19] [connect] Successful connection May 07 19:43:20 volumio volumio[948]: info: Loading plugin "network"... May 07 19:43:20 volumio volumio[948]: info: Refreshing Cached IP Addresses May 07 19:43:20 volumio sudo[1163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 07 19:43:20 volumio sudo[1163]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:43:20 volumio sudo[1165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 07 19:43:20 volumio sudo[1165]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:43:20 volumio sudo[1165]: pam_unix(sudo:session): session closed for user root May 07 19:43:20 volumio sudo[1163]: pam_unix(sudo:session): session closed for user root May 07 19:43:20 volumio volumio[948]: info: Loading plugin "services"... May 07 19:43:20 volumio volumio[948]: info: Loading plugin "alsa_controller"... May 07 19:43:20 volumio sudo[1169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 07 19:43:20 volumio sudo[1169]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:43:20 volumio sudo[1169]: pam_unix(sudo:session): session closed for user root May 07 19:43:20 volumio volumio[948]: warn: Unable to locate the audio output device Analog Out. Please configure a valid output device. May 07 19:43:20 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 19:43:20 volumio volumio[948]: info: Loading plugin "wizard"... May 07 19:43:20 volumio volumio[948]: info: Loading plugin "networkfs"... May 07 19:43:20 volumio volumio[948]: info: Starting Udev Watcher for removable devices May 07 19:43:20 volumio volumio[948]: info: Ignoring mount for partition: boot May 07 19:43:20 volumio volumio[948]: info: Ignoring mount for partition: volumio May 07 19:43:20 volumio volumio[948]: info: Ignoring mount for partition: volumio_data May 07 19:43:20 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 07 19:43:20 volumio volumio[948]: info: Loading plugin "volumio_command_line_client"... May 07 19:43:20 volumio volumio[948]: info: Loading plugin "upnp"... May 07 19:43:20 volumio volumio[948]: info: [1715129000407] Starting Upmpd Daemon May 07 19:43:20 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 07 19:43:20 volumio volumio[948]: info: Loading plugin "my_music"... May 07 19:43:20 volumio volumio[948]: info: Loading plugin "mpd"... May 07 19:43:21 volumio volumio[948]: info: Loading plugin "upnp_browser"... May 07 19:43:22 volumio volumio[948]: info: Loading plugin "alarm-clock"... May 07 19:43:22 volumio volumio[948]: info: Loading plugin "airplay_emulation"... May 07 19:43:22 volumio volumio[948]: info: Starting Shairport Sync May 07 19:43:22 volumio volumio[948]: info: Loading plugin "last_100"... May 07 19:43:22 volumio volumio[948]: info: Loading plugin "webradio"... May 07 19:43:22 volumio volumio[948]: info: Loading plugin "i2s_dacs"... May 07 19:43:22 volumio volumio[948]: info: I2S DAC not set, start Auto-detection May 07 19:43:22 volumio volumio[948]: info: Loading plugin "volumiodiscovery"... May 07 19:43:22 volumio volumio[948]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 07 19:43:22 volumio node[948]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 07 19:43:22 volumio node[948]: *** WARNING *** Please fix your application to use the native API of Avahi! May 07 19:43:22 volumio volumio[948]: *** WARNING *** Please fix your application to use the native API of Avahi! May 07 19:43:22 volumio volumio[948]: *** WARNING *** For more information see May 07 19:43:22 volumio volumio[948]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 07 19:43:22 volumio volumio[948]: *** WARNING *** Please fix your application to use the native API of Avahi! May 07 19:43:22 volumio volumio[948]: *** WARNING *** For more information see May 07 19:43:22 volumio node[948]: *** WARNING *** For more information see May 07 19:43:22 volumio node[948]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 07 19:43:22 volumio node[948]: *** WARNING *** Please fix your application to use the native API of Avahi! May 07 19:43:22 volumio node[948]: *** WARNING *** For more information see May 07 19:43:22 volumio volumio[948]: info: Applying required configuration parameters for plugin volumiodiscovery May 07 19:43:22 volumio volumio[948]: info: Discovery: Started advertising with name: Volumio May 07 19:43:22 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 07 19:43:22 volumio volumio[948]: info: Loading plugin "outputs"... May 07 19:43:22 volumio volumio[948]: info: Loading plugin "albumart"... May 07 19:43:22 volumio volumio[948]: info: Plugin example_plugin is not enabled May 07 19:43:22 volumio volumio[948]: info: Loading plugin "inputs"... May 07 19:43:22 volumio volumio[948]: info: Loading plugin "updater_comm"... May 07 19:43:23 volumio volumio[948]: Forking 1 albumart workers May 07 19:43:23 volumio volumio[948]: info: Plugin mpdemulation is not enabled May 07 19:43:23 volumio volumio[948]: info: Loading plugin "rest_api"... May 07 19:43:23 volumio volumio[948]: info: Loading plugin "websocket"... May 07 19:43:23 volumio volumio[948]: info: Loading plugin "RoonBridge"... May 07 19:43:23 volumio dhcpcd[853]: wlan0: using IPv4LL address 169.254.113.61 May 07 19:43:23 volumio dhcpcd[853]: wlan0: adding route to 169.254.0.0/16 May 07 19:43:23 volumio avahi-daemon[533]: Registering new address record for 169.254.113.61 on wlan0.IPv4. May 07 19:43:23 volumio volumio[948]: Starting albumart workers May 07 19:43:23 volumio volumio[948]: info: Applying required configuration parameters for plugin RoonBridge May 07 19:43:23 volumio volumio[948]: info: Loading i18n strings for locale es May 07 19:43:23 volumio volumio[948]: Updating browse sources language May 07 19:43:23 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 19:43:24 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:43:24 volumio volumio[948]: info: CoreCommandRouter::initPlayerControls May 07 19:43:24 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:43:24 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:43:24 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:43:24 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:43:24 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:43:24 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:43:24 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:43:24 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:43:24 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:43:24 volumio volumio[948]: Express server listening on port 3000 May 07 19:43:24 volumio volumio[948]: [Metrics] WebUI: 9s 49.57ms May 07 19:43:24 volumio volumio[948]: info: Setting Device type: x86 May 07 19:43:24 volumio volumio[948]: info: CoreStateMachine::resetVolumioState May 07 19:43:24 volumio volumio[948]: info: CoreStateMachine::getcurrentVolume May 07 19:43:24 volumio volumio[948]: info: CoreCommandRouter::volumioRetrievevolume May 07 19:43:24 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:43:24 volumio volumio[948]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 07 19:43:24 volumio volumio[948]: info: Completed loading Core Plugins May 07 19:43:24 volumio volumio[948]: info: Preparing to generate the ALSA configuration file May 07 19:43:24 volumio volumio[948]: info: Cannot read play queue from file May 07 19:43:24 volumio volumio[948]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan May 07 19:43:24 volumio volumio[948]: wlan0 Interface doesn't support scanning : Operation not supported May 07 19:43:24 volumio volumio[948]: info: Cannot use regular scanning, forcing with ap-force May 07 19:43:24 volumio sudo[1229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force May 07 19:43:24 volumio sudo[1229]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:43:24 volumio ntpd[894]: Listen normally on 5 wlan0 169.254.113.61:123 May 07 19:43:24 volumio ntpd[894]: new interface(s) found: waking up resolver May 07 19:43:25 volumio systemd[1]: systemd-fsckd.service: Succeeded. May 07 19:43:26 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:27 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:27 volumio systemd[1]: systemd-hostnamed.service: Succeeded. May 07 19:43:28 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:28 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:29 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:29 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:30 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:31 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:32 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:32 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:33 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:33 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:34 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:34 volumio volumio-remote-updater[520]: [2024-05-07 19:43:34] [connect] Successful connection May 07 19:43:35 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:36 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:36 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:37 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:38 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:38 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:39 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:40 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:40 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:41 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:42 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:42 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:43 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:44 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:44 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:45 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:45 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:46 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:47 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:48 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:48 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:49 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:49 volumio volumio-remote-updater[520]: [2024-05-07 19:43:49] [connect] Successful connection May 07 19:43:50 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:50 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:51 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:52 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:52 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:53 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:53 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:54 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:55 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:55 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:56 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:56 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:57 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:58 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:58 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:43:59 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:44:00 volumio kernel: b43-phy0 ERROR: MAC suspend failed May 07 19:44:00 volumio sudo[1229]: pam_unix(sudo:session): session closed for user root May 07 19:44:00 volumio volumio[948]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision May 07 19:44:00 volumio sudo[1351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 07 19:44:00 volumio sudo[1351]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:44:00 volumio sudo[1351]: pam_unix(sudo:session): session closed for user root May 07 19:44:00 volumio sudo[1354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 07 19:44:00 volumio sudo[1354]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:44:00 volumio sudo[1354]: pam_unix(sudo:session): session closed for user root May 07 19:44:00 volumio volumio[948]: info: VolumeController:: Volume=41 Mute =false May 07 19:44:00 volumio volumio[948]: info: CoreStateMachine::pushState May 07 19:44:00 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:44:00 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 19:44:00 volumio volumio[948]: info: CoreCommandRouter::volumioPushState May 07 19:44:00 volumio volumio[948]: info: CoreStateMachine::updateTrackBlock May 07 19:44:00 volumio volumio[948]: info: CorePlayQueue::getTrackBlock May 07 19:44:00 volumio volumio[948]: info: CoreCommandRouter::volumioRetrievevolume May 07 19:44:00 volumio volumio[948]: info: Asound.conf file unchanged, so no further update is needed May 07 19:44:00 volumio volumio[948]: info: Output device has changed, restarting MPD May 07 19:44:00 volumio sudo[1365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 07 19:44:00 volumio volumio[948]: info: Output device has changed, restarting Shairport Sync May 07 19:44:00 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:44:00 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:44:00 volumio sudo[1365]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:44:00 volumio sudo[1365]: pam_unix(sudo:session): session closed for user root May 07 19:44:00 volumio sudo[1367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 07 19:44:00 volumio sudo[1367]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:44:00 volumio volumio[948]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 07 19:44:00 volumio volumio[948]: info: ___________ START PLUGINS ___________ May 07 19:44:00 volumio systemd[1]: Listening on mpd.socket. May 07 19:44:00 volumio systemd[1]: Starting Music Player Daemon... May 07 19:44:00 volumio volumio[948]: info: ControllerMpd::onStart: Initializing MPD May 07 19:44:00 volumio volumio[948]: info: Creating MPD Configuration file May 07 19:44:00 volumio sudo[1378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 07 19:44:00 volumio sudo[1378]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:44:00 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:44:00 volumio sudo[1378]: pam_unix(sudo:session): session closed for user root May 07 19:44:00 volumio volumio[948]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 07 19:44:00 volumio volumio[948]: info: [1715129040855] CoreMusicLibrary::Adding element Media Servers May 07 19:44:00 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 19:44:00 volumio sudo[1380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 07 19:44:00 volumio sudo[1380]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:44:00 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:44:00 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:44:00 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 07 19:44:00 volumio systemd[1]: mpd.service: Succeeded. May 07 19:44:00 volumio systemd[1]: Stopped Music Player Daemon. May 07 19:44:00 volumio volumio[948]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 07 19:44:00 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:44:00 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:44:00 volumio systemd[1]: Starting Music Player Daemon... May 07 19:44:00 volumio volumio[948]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 07 19:44:00 volumio volumio[948]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 07 19:44:00 volumio volumio[948]: info: [1715129040940] CoreMusicLibrary::Adding element Last_100 May 07 19:44:00 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 19:44:00 volumio volumio[948]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 07 19:44:00 volumio volumio[948]: info: [1715129040942] CoreMusicLibrary::Adding element Webradio May 07 19:44:00 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 19:44:00 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 19:44:00 volumio volumio[948]: info: Volumio Calling Home May 07 19:44:00 volumio sudo[1392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service May 07 19:44:00 volumio sudo[1392]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:44:01 volumio systemd[1]: Started RoonBridge. May 07 19:44:01 volumio sudo[1392]: pam_unix(sudo:session): session closed for user root May 07 19:44:01 volumio volumio[948]: info: CoreStateMachine::setRepeat null single undefined May 07 19:44:01 volumio volumio[948]: info: CoreStateMachine::pushState May 07 19:44:01 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:44:01 volumio volumio[948]: info: CoreCommandRouter::volumioPushState May 07 19:44:01 volumio volumio[948]: info: CoreStateMachine::setRandom null May 07 19:44:01 volumio volumio[948]: info: CoreStateMachine::pushState May 07 19:44:01 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:44:01 volumio volumio[948]: info: CoreCommandRouter::volumioPushState May 07 19:44:01 volumio volumio[948]: info: MPD Permissions set May 07 19:44:01 volumio volumio[948]: info: MPD Permissions set May 07 19:44:01 volumio volumio[948]: info: Discovery: adding cdc21caf-7ec1-4105-860c-fdb98d78e5a6 May 07 19:44:01 volumio volumio[948]: info: Discovery: Found device Volumio May 07 19:44:01 volumio volumio[948]: info: CoreCommandRouter::volumioGetState May 07 19:44:01 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:44:01 volumio volumio[948]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 May 07 19:44:01 volumio volumio[948]: info: Discovery: this is already registered, cdc21caf-7ec1-4105-860c-fdb98d78e5a6 May 07 19:44:01 volumio volumio[948]: info: Discovery: Found device Volumio May 07 19:44:01 volumio volumio[948]: info: CoreCommandRouter::volumioGetState May 07 19:44:01 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:44:01 volumio sudo[1408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 07 19:44:01 volumio sudo[1408]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:44:01 volumio systemd[1]: Started UPnP Renderer front-end to MPD. May 07 19:44:01 volumio sudo[1408]: pam_unix(sudo:session): session closed for user root May 07 19:44:01 volumio volumio[948]: info: VolumeController:: Volume=41 Mute =false May 07 19:44:01 volumio volumio[948]: info: CoreStateMachine::pushState May 07 19:44:01 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:44:01 volumio volumio[948]: info: CoreCommandRouter::volumioPushState May 07 19:44:01 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:44:01 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:44:01 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:44:01 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 07 19:44:01 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:44:01 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:44:01 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:44:01 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:44:01 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:44:01 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:44:01 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:44:01 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:44:01 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:44:01 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:44:01 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 07 19:44:01 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:44:01 volumio volumio[948]: info: Upmpdcli Daemon Started May 07 19:44:01 volumio volumio[948]: info: Volumio called home May 07 19:44:01 volumio volumio[948]: info: Starting Shairport Sync May 07 19:44:01 volumio volumio[948]: info: Starting Shairport Sync May 07 19:44:01 volumio volumio[948]: info: Starting Shairport Sync May 07 19:44:01 volumio sudo[1428]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 07 19:44:01 volumio sudo[1426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 07 19:44:01 volumio sudo[1426]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:44:01 volumio sudo[1428]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:44:01 volumio volumio[948]: info: CoreCommandRouter::volumioGetState May 07 19:44:01 volumio sudo[1430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 07 19:44:01 volumio sudo[1430]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:44:01 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:44:01 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 07 19:44:01 volumio systemd[1]: shairport-sync.service: Succeeded. May 07 19:44:01 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 07 19:44:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 07 19:44:01 volumio sudo[1428]: pam_unix(sudo:session): session closed for user root May 07 19:44:01 volumio volumio[948]: info: Shairport-Sync Started May 07 19:44:01 volumio sudo[1426]: pam_unix(sudo:session): session closed for user root May 07 19:44:01 volumio volumio[948]: Error adding Membership: Error: addMembership EINVAL May 07 19:44:01 volumio sudo[1430]: pam_unix(sudo:session): session closed for user root May 07 19:44:01 volumio volumio[948]: info: Shairport-Sync Started May 07 19:44:01 volumio volumio[948]: info: Shairport-Sync Started May 07 19:44:02 volumio RoonBridge[1396]: 00:00:00.010 Warn: get lock file path: /tmp/.rnbgem0- May 07 19:44:03 volumio mpd[1387]: May 07 19:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 07 19:44:03 volumio volumio[948]: Upnp client error: Error: This socket has been ended by the other party May 07 19:44:03 volumio systemd[1]: Started Music Player Daemon. May 07 19:44:03 volumio sudo[1380]: pam_unix(sudo:session): session closed for user root May 07 19:44:03 volumio sudo[1367]: pam_unix(sudo:session): session closed for user root May 07 19:44:03 volumio RoonBridge[1396]: 00:00:00.531 Trace: [childprocess] using unix child process May 07 19:44:03 volumio volumio[948]: info: Completed starting Core Plugins May 07 19:44:03 volumio volumio[948]: info: ------------------------------------------- May 07 19:44:03 volumio volumio[948]: info: ----- MyVolumio plugins startup ---- May 07 19:44:03 volumio volumio[948]: info: ------------------------------------------- May 07 19:44:03 volumio volumio[948]: info: [MyVolumio PluginManager] Fetching plans data.... May 07 19:44:03 volumio volumio[948]: info: MPD running with PID1387 May 07 19:44:03 volumio volumio[948]: ,establishing connection May 07 19:44:03 volumio RoonBridge[1396]: 00:00:00.634 Debug: PathForResource, filename: ../.update May 07 19:44:03 volumio volumio[948]: error: MPD error: The expression evaluated to a falsy value: May 07 19:44:03 volumio volumio[948]: assert.ok(self.idling) May 07 19:44:03 volumio volumio[948]: error: The expression evaluated to a falsy value: May 07 19:44:03 volumio volumio[948]: assert.ok(self.idling) May 07 19:44:03 volumio volumio[948]: error: MPD error: The expression evaluated to a falsy value: May 07 19:44:03 volumio volumio[948]: assert.ok(self.idling) May 07 19:44:03 volumio volumio[948]: error: The expression evaluated to a falsy value: May 07 19:44:03 volumio volumio[948]: assert.ok(self.idling) May 07 19:44:03 volumio volumio[948]: error: updateQueue error: null May 07 19:44:03 volumio RoonBridge[1396]: 00:00:00.785 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update May 07 19:44:03 volumio RoonBridge[1396]: 00:00:00.794 Debug: PathForResource, filename: RAATServer May 07 19:44:03 volumio RoonBridge[1396]: 00:00:00.794 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer May 07 19:44:03 volumio RoonBridge[1396]: 00:00:00.795 Debug: PathForResource, filename: RoonBridgeHelper May 07 19:44:03 volumio RoonBridge[1396]: 00:00:00.799 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper May 07 19:44:03 volumio RoonBridge[1396]: Initializing May 07 19:44:03 volumio RoonBridge[1396]: 00:00:00.856 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper May 07 19:44:03 volumio RoonBridge[1396]: 00:00:00.903 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer May 07 19:44:03 volumio volumio[948]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 2 May 07 19:44:03 volumio RoonBridge[1396]: Not Running (.o) May 07 19:44:04 volumio RoonBridge[1396]: Running May 07 19:44:04 volumio volumio-remote-updater[520]: [2024-05-07 19:44:04] [connect] Successful connection May 07 19:44:05 volumio volumio-remote-updater[520]: [2024-05-07 19:44:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1715129044 101 May 07 19:44:05 volumio volumio[948]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 May 07 19:44:05 volumio RoonBridge[1396]: 00:00:00.055 Warn: get lock file path: /tmp/.rnbhgem0- May 07 19:44:05 volumio volumio[1410]: Generating RSA private key, 4096 bit long modulus (2 primes) May 07 19:44:07 volumio volumio[1410]: ...................................................++++ May 07 19:44:07 volumio volumio[948]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 4 May 07 19:44:08 volumio volumio[948]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 07 19:44:09 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 07 19:44:09 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 07 19:44:09 volumio volumio[948]: info: CoreCommandRouter::volumioGetVisibleSources May 07 19:44:09 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 19:44:09 volumio volumio[948]: info: CoreCommandRouter::volumioGetState May 07 19:44:09 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:44:09 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 07 19:44:09 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 07 19:44:09 volumio volumio[948]: info: Received Get System Info May 07 19:44:09 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 19:44:09 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 19:44:09 volumio volumio[948]: info: Discovery: Getting this device information May 07 19:44:09 volumio volumio[948]: info: CoreCommandRouter::volumioGetState May 07 19:44:09 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:44:09 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 19:44:09 volumio volumio[948]: info: CoreCommandRouter::volumioGetState May 07 19:44:09 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:44:09 volumio volumio[948]: info: Listing playlists May 07 19:44:09 volumio volumio[948]: info: CoreCommandRouter::volumioGetState May 07 19:44:09 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:44:09 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 19:44:09 volumio volumio[948]: info: Received Get System Info May 07 19:44:09 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 19:44:09 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 19:44:09 volumio volumio[948]: info: Discovery: Getting this device information May 07 19:44:09 volumio volumio[948]: info: CoreCommandRouter::volumioGetState May 07 19:44:09 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:44:09 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 19:44:10 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 07 19:44:10 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 07 19:44:10 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 07 19:44:11 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 19:44:11 volumio volumio[948]: info: Received Get System Info May 07 19:44:11 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 19:44:11 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 19:44:11 volumio volumio[948]: info: Discovery: Getting this device information May 07 19:44:11 volumio volumio[948]: info: CoreCommandRouter::volumioGetState May 07 19:44:11 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:44:11 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 19:44:11 volumio volumio[1410]: .................................................................................................................................................................++++ May 07 19:44:11 volumio volumio[1410]: e is 65537 (0x010001) May 07 19:44:11 volumio volumio[1410]: writing RSA key May 07 19:44:11 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 07 19:44:11 volumio volumio[948]: info: Adding plugin bluetooth to MyMusic Plugins May 07 19:44:11 volumio volumio[948]: info: Adding plugin multiroom to MyMusic Plugins May 07 19:44:11 volumio volumio[948]: info: Adding plugin metavolumio to MyMusic Plugins May 07 19:44:11 volumio volumio[948]: info: Adding plugin cd_controller to MyMusic Plugins May 07 19:44:11 volumio volumio[948]: info: Adding plugin smart_inputs to MyMusic Plugins May 07 19:44:11 volumio volumio[948]: info: Adding plugin tidalconnect to MyMusic Plugins May 07 19:44:11 volumio volumio[948]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 07 19:44:12 volumio volumio[948]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 07 19:44:12 volumio volumio[948]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 07 19:44:12 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:44:12 volumio volumio[948]: info: Starting MyVolumio Remote Streaming Endpoints May 07 19:44:12 volumio volumio[948]: info: MyVolumio login type: Token May 07 19:44:12 volumio volumio[948]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 07 19:44:12 volumio volumio[948]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 07 19:44:13 volumio volumio[948]: info: Starting Streaming Service Transparent Proxy May 07 19:44:13 volumio volumio[948]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 07 19:44:13 volumio volumio[948]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 07 19:44:13 volumio volumio[948]: info: Streaming services startup May 07 19:44:13 volumio volumio[948]: info: Starting Streaming Daemon May 07 19:44:13 volumio sudo[1749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 07 19:44:13 volumio sudo[1749]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:44:13 volumio sudo[1749]: pam_unix(sudo:session): session closed for user root May 07 19:44:13 volumio volumio[948]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 07 19:44:13 volumio volumio[948]: error: Cannot start Volumio Streaming Daemon May 07 19:44:13 volumio volumio[948]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 07 19:44:13 volumio volumio[948]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 07 19:44:13 volumio volumio[948]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 07 19:44:13 volumio volumio[948]: STREAMING PROXY: Starting server on port 3245 May 07 19:44:13 volumio volumio[948]: Node JS runtime: 14 May 07 19:44:15 volumio ntpd[894]: Soliciting pool server 216.238.85.87 May 07 19:44:17 volumio volumio[948]: info: MyVolumio token set successfully May 07 19:44:17 volumio volumio[948]: info: MYVOLUMIO: Adding device May 07 19:44:17 volumio volumio[948]: info: MYVOLUMIO: Evaluating Server May 07 19:44:17 volumio ntpd[894]: Soliciting pool server 45.183.178.195 May 07 19:44:18 volumio volumio[948]: info: MyVolumio status changed May 07 19:44:18 volumio volumio[948]: info: Streaming services startup May 07 19:44:18 volumio volumio[948]: info: Starting Streaming Daemon May 07 19:44:18 volumio volumio[948]: info: Removing browser output: myVolumio user plan is not superstar May 07 19:44:18 volumio volumio[948]: info: Removing audio output: May 07 19:44:18 volumio volumio[948]: info: Stoppping Tunnel 1 May 07 19:44:18 volumio sudo[1774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 07 19:44:18 volumio sudo[1774]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:44:18 volumio sudo[1774]: pam_unix(sudo:session): session closed for user root May 07 19:44:18 volumio sudo[1777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 07 19:44:18 volumio sudo[1777]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:44:18 volumio volumio[948]: error: Cannot start Volumio Streaming Daemon May 07 19:44:18 volumio volumio[948]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 07 19:44:18 volumio volumio[948]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 07 19:44:18 volumio sudo[1777]: pam_unix(sudo:session): session closed for user root May 07 19:44:18 volumio volumio[948]: info: Remote SSH Stopped May 07 19:44:18 volumio volumio[948]: info: Setting Geolocation for MyVolumio to us2 May 07 19:44:18 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:44:18 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:44:18 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:44:18 volumio volumio[948]: info: Successfully Added MyVolumio device May 07 19:44:18 volumio ntpd[894]: Soliciting pool server 2a03:8600::ee May 07 19:44:19 volumio volumio[948]: info: Updating MyVolumio device info May 07 19:44:19 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:44:19 volumio volumio[948]: info: Successfully Updated MyVolumio device May 07 19:44:19 volumio ntpd[894]: Soliciting pool server 132.248.30.3 May 07 19:44:20 volumio ntpd[894]: Soliciting pool server 216.238.85.87 May 07 19:44:20 volumio ntpd[894]: Soliciting pool server 45.231.168.6 May 07 19:44:21 volumio ntpd[894]: Soliciting pool server 45.231.168.6 May 07 19:44:25 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 07 19:44:25 volumio volumio[948]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 07 19:44:25 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 07 19:44:25 volumio volumio[948]: info: Received Get System Version May 07 19:44:25 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 19:44:25 volumio volumio[948]: info: Received Get System Info May 07 19:44:25 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 19:44:25 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 19:44:25 volumio volumio[948]: info: Discovery: Getting this device information May 07 19:44:25 volumio volumio[948]: info: CoreCommandRouter::volumioGetState May 07 19:44:25 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:44:25 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 19:44:27 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 07 19:44:27 volumio volumio[948]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 May 07 19:44:27 volumio volumio[948]: info: CoreCommandRouter::volumioGetState May 07 19:44:27 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:44:30 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 19:44:30 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:44:30 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 07 19:44:31 volumio volumio-remote-updater[520]: No test mode May 07 19:44:31 volumio volumio-remote-updater[520]: No alpha test mode May 07 19:44:31 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 07 19:44:31 volumio volumio[948]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 07 19:44:31 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 07 19:44:32 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:44:32 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:44:32 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 07 19:44:32 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 07 19:44:32 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 07 19:44:32 volumio volumio[948]: info: CoreCommandRouter::volumioGetBrowseSources May 07 19:44:32 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 19:44:33 volumio volumio[948]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 07 19:44:33 volumio volumio[948]: info: Completed starting MyVolumio Plugin May 07 19:44:33 volumio volumio[948]: info: BOOT COMPLETED May 07 19:44:33 volumio volumio[948]: [Metrics] CommandRouter: 77s 397.88ms May 07 19:44:33 volumio volumio[948]: info: CoreCommandRouter::volumiosetStartupVolume May 07 19:44:33 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:44:33 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:44:33 volumio volumio[948]: info: CoreCommandRouter::Close All Modals sent May 07 19:44:33 volumio volumio[948]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav May 07 19:44:33 volumio volumio[948]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card May 07 19:44:33 volumio volumio[948]: aplay: main:828: audio open error: No such device May 07 19:44:34 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 07 19:44:34 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 07 19:44:34 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: raat , onStop May 07 19:44:34 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 07 19:45:24 volumio ntpd[894]: Soliciting pool server 2a03:8600::bb May 07 19:46:02 volumio sudo[1900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 07 19:46:02 volumio sudo[1900]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:46:02 volumio sudo[1900]: pam_unix(sudo:session): session closed for user root May 07 19:46:02 volumio sudo[1903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 07 19:46:02 volumio sudo[1903]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:46:02 volumio sudo[1903]: pam_unix(sudo:session): session closed for user root May 07 19:46:02 volumio volumio[948]: verbose: New Socket.io Connection to 192.168.100.98 from 192.168.100.36 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.4.1 Safari/605.1.15 Total Clients: 5 May 07 19:46:03 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 07 19:46:03 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 07 19:46:03 volumio volumio[948]: info: CoreCommandRouter::volumioGetVisibleSources May 07 19:46:03 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 19:46:03 volumio volumio[948]: info: CoreCommandRouter::volumioGetState May 07 19:46:03 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:46:03 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 07 19:46:03 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 07 19:46:03 volumio volumio[948]: info: Received Get System Info May 07 19:46:03 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 19:46:03 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 19:46:03 volumio volumio[948]: info: Discovery: Getting this device information May 07 19:46:03 volumio volumio[948]: info: CoreCommandRouter::volumioGetState May 07 19:46:03 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:46:03 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 19:46:03 volumio volumio[948]: info: CoreCommandRouter::volumioGetState May 07 19:46:03 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:46:03 volumio volumio[948]: info: Listing playlists May 07 19:46:03 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 07 19:46:03 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 07 19:46:03 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 07 19:46:03 volumio sudo[1906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 07 19:46:03 volumio sudo[1906]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:46:03 volumio sudo[1906]: pam_unix(sudo:session): session closed for user root May 07 19:46:03 volumio sudo[1909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 07 19:46:03 volumio sudo[1909]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:46:03 volumio sudo[1909]: pam_unix(sudo:session): session closed for user root May 07 19:46:03 volumio volumio[948]: verbose: New Socket.io Connection to 192.168.100.98 from 192.168.100.36 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.4.1 Safari/605.1.15 Total Clients: 5 May 07 19:46:04 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 07 19:46:04 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 07 19:46:04 volumio volumio[948]: info: CoreCommandRouter::volumioGetVisibleSources May 07 19:46:04 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 07 19:46:04 volumio volumio[948]: info: CoreCommandRouter::volumioGetState May 07 19:46:04 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:46:04 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 07 19:46:04 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 07 19:46:04 volumio volumio[948]: info: Received Get System Info May 07 19:46:04 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 19:46:04 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 19:46:04 volumio volumio[948]: info: Discovery: Getting this device information May 07 19:46:04 volumio volumio[948]: info: CoreCommandRouter::volumioGetState May 07 19:46:04 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:46:04 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 19:46:04 volumio volumio[948]: info: CoreCommandRouter::volumioGetState May 07 19:46:04 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:46:04 volumio volumio[948]: info: Listing playlists May 07 19:46:04 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 07 19:46:04 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 07 19:46:04 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 07 19:46:04 volumio volumio[948]: info: CoreCommandRouter::volumioGetState May 07 19:46:04 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:46:05 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 19:46:05 volumio volumio[948]: info: Received Get System Info May 07 19:46:05 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 19:46:05 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 19:46:05 volumio volumio[948]: info: Discovery: Getting this device information May 07 19:46:05 volumio volumio[948]: info: CoreCommandRouter::volumioGetState May 07 19:46:05 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:46:05 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 19:46:05 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 07 19:46:08 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 07 19:46:08 volumio volumio[948]: info: Received Get System Info May 07 19:46:08 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 19:46:08 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 19:46:08 volumio volumio[948]: info: Discovery: Getting this device information May 07 19:46:08 volumio volumio[948]: info: CoreCommandRouter::volumioGetState May 07 19:46:08 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:46:08 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 19:46:11 volumio volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 19:46:11 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 07 19:46:11 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 07 19:46:11 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 07 19:46:11 volumio volumio[948]: info: Received Get System Version May 07 19:46:11 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 19:46:22 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 07 19:46:22 volumio volumio[948]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 07 19:46:22 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 07 19:46:22 volumio volumio[948]: info: Received Get System Version May 07 19:46:22 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 07 19:46:22 volumio volumio[948]: info: Received Get System Info May 07 19:46:22 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 07 19:46:22 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 07 19:46:22 volumio volumio[948]: info: Discovery: Getting this device information May 07 19:46:22 volumio volumio[948]: info: CoreCommandRouter::volumioGetState May 07 19:46:22 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:46:22 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 07 19:46:31 volumio ntpd[894]: Soliciting pool server 2a03:8600::aa May 07 19:46:31 volumio ntpd[894]: Soliciting pool server 45.231.168.6 May 07 19:46:38 volumio ntpd[894]: Soliciting pool server 45.231.168.6 May 07 19:47:10 volumio kernel: usb 1-1: new full-speed USB device number 4 using ohci-pci May 07 19:47:10 volumio kernel: usb 1-1: New USB device found, idVendor=045e, idProduct=07fd, bcdDevice= 3.15 May 07 19:47:10 volumio kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 May 07 19:47:10 volumio kernel: usb 1-1: Product: Microsoft Nano Transceiver 1.1 May 07 19:47:10 volumio kernel: usb 1-1: Manufacturer: Microsoft May 07 19:47:10 volumio kernel: input: Microsoft Microsoft Nano Transceiver 1.1 as /devices/pci0000:00/0000:00:04.0/usb1/1-1/1-1:1.0/0003:045E:07FD.0006/input/input11 May 07 19:47:10 volumio kernel: hid-generic 0003:045E:07FD.0006: input,hidraw0: USB HID v1.11 Keyboard [Microsoft Microsoft Nano Transceiver 1.1] on usb-0000:00:04.0-1/input0 May 07 19:47:10 volumio kernel: input: Microsoft Microsoft Nano Transceiver 1.1 Consumer Control as /devices/pci0000:00/0000:00:04.0/usb1/1-1/1-1:1.1/0003:045E:07FD.0007/input/input12 May 07 19:47:10 volumio kernel: input: Microsoft Microsoft Nano Transceiver 1.1 System Control as /devices/pci0000:00/0000:00:04.0/usb1/1-1/1-1:1.1/0003:045E:07FD.0007/input/input13 May 07 19:47:10 volumio kernel: input: Microsoft Microsoft Nano Transceiver 1.1 as /devices/pci0000:00/0000:00:04.0/usb1/1-1/1-1:1.1/0003:045E:07FD.0007/input/input14 May 07 19:47:10 volumio kernel: hid-generic 0003:045E:07FD.0007: input,hiddev1,hidraw1: USB HID v1.11 Device [Microsoft Microsoft Nano Transceiver 1.1] on usb-0000:00:04.0-1/input1 May 07 19:47:10 volumio kernel: input: Microsoft Microsoft Nano Transceiver 1.1 as /devices/pci0000:00/0000:00:04.0/usb1/1-1/1-1:1.2/0003:045E:07FD.0008/input/input15 May 07 19:47:10 volumio kernel: hid-generic 0003:045E:07FD.0008: input,hidraw5: USB HID v1.11 Mouse [Microsoft Microsoft Nano Transceiver 1.1] on usb-0000:00:04.0-1/input2 May 07 19:47:10 volumio systemd-udevd[1969]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 07 19:47:10 volumio systemd-logind[525]: Watching system buttons on /dev/input/event4 (Microsoft Microsoft Nano Transceiver 1.1) May 07 19:47:10 volumio systemd-udevd[1973]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 07 19:47:10 volumio systemd-logind[525]: Watching system buttons on /dev/input/event11 (Microsoft Microsoft Nano Transceiver 1.1 System Control) May 07 19:47:10 volumio systemd-udevd[1971]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 07 19:47:10 volumio systemd-udevd[1972]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 07 19:47:10 volumio systemd-udevd[1981]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 07 19:47:10 volumio systemd-logind[525]: Watching system buttons on /dev/input/event5 (Microsoft Microsoft Nano Transceiver 1.1 Consumer Control) May 07 19:47:10 volumio systemd-udevd[1974]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 07 19:47:31 volumio ntpd[894]: Soliciting pool server 45.231.168.6 May 07 19:47:39 volumio ntpd[894]: Soliciting pool server 2a03:8600::cc May 07 19:47:43 volumio volumio[948]: info: CoreCommandRouter::volumioGetState May 07 19:47:43 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:47:48 volumio volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 19:47:48 volumio volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 19:47:48 volumio volumio[948]: info: Retrieving Cloud Streaming UI May 07 19:47:48 volumio volumio[948]: info: Getting Tidal Cloud Configuration May 07 19:47:48 volumio volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 19:47:48 volumio volumio[948]: info: Getting Qobuz Cloud Configuration May 07 19:47:48 volumio volumio[948]: info: Asking plugin for UI Config May 07 19:47:48 volumio volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 19:47:48 volumio volumio[948]: info: Getting Spotify Cloud Configuration May 07 19:47:48 volumio volumio[948]: info: Asking plugin for UI Config May 07 19:47:48 volumio volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 19:47:48 volumio volumio[948]: info: Saving Spotify Acccount May 07 19:47:48 volumio volumio[948]: info: Got Tidal Cloud Configuration May 07 19:47:48 volumio volumio[948]: info: Got it May 07 19:47:48 volumio volumio[948]: info: Got it May 07 19:47:48 volumio volumio[948]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 07 19:47:48 volumio volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 19:47:48 volumio volumio[948]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 07 19:47:48 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 07 19:47:48 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 07 19:47:48 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 07 19:47:48 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 07 19:47:48 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:47:48 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:47:48 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:47:48 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:47:48 volumio volumio[948]: info: CoreCommandRouter::volumioGetBrowseSources May 07 19:47:48 volumio volumio[948]: info: CoreCommandRouter::volumioGetBrowseSources May 07 19:47:48 volumio volumio[948]: info: CoreCommandRouter::volumioGetBrowseSources May 07 19:47:49 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 19:47:49 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 07 19:47:53 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 19:47:55 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares May 07 19:47:55 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService May 07 19:47:57 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 19:48:01 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 19:48:05 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 19:48:09 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 19:48:13 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 19:48:17 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 19:48:19 volumio nmbd[647]: [2024/05/07 19:48:19.482495, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 07 19:48:19 volumio nmbd[647]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.100.98 for name WORKGROUP<1d>. May 07 19:48:19 volumio nmbd[647]: This response was from IP 192.168.100.2, reporting an IP address of 192.168.100.2. May 07 19:48:21 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 19:48:25 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 19:48:26 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare May 07 19:48:26 volumio volumio[948]: info: Adding a new share May 07 19:48:26 volumio volumio[948]: info: No correspondence found in configuration for share Musica Roon on IP 192.168.100.2 May 07 19:48:26 volumio sudo[2064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=mauricio,password=Rush211253631622,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.100.2/Musica Roon /mnt/NAS/Musica_Roon May 07 19:48:26 volumio sudo[2064]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:48:26 volumio kernel: FS-Cache: Loaded May 07 19:48:26 volumio kernel: Key type dns_resolver registered May 07 19:48:26 volumio kernel: Key type cifs.spnego registered May 07 19:48:26 volumio kernel: Key type cifs.idmap registered May 07 19:48:26 volumio 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. May 07 19:48:26 volumio kernel: CIFS: Attempting to mount //192.168.100.2/Musica Roon May 07 19:48:27 volumio sudo[2064]: pam_unix(sudo:session): session closed for user root May 07 19:48:27 volumio volumio[948]: verbose: ControllerMpd::sendMpdCommand status May 07 19:48:27 volumio volumio[948]: info: May 07 19:48:27 volumio volumio[948]: ---------------------------- MPD announces state update: update May 07 19:48:27 volumio volumio[948]: info: ControllerMpd::getState May 07 19:48:27 volumio volumio[948]: verbose: ControllerMpd::sendMpdCommand status May 07 19:48:27 volumio volumio[948]: verbose: ControllerMpd::sendMpdCommand status May 07 19:48:27 volumio volumio[948]: info: May 07 19:48:27 volumio volumio[948]: ---------------------------- MPD announces state update: update May 07 19:48:27 volumio volumio[948]: info: ControllerMpd::getState May 07 19:48:27 volumio volumio[948]: verbose: ControllerMpd::sendMpdCommand status May 07 19:48:27 volumio volumio[948]: verbose: ControllerMpd::sendMpdCommand status May 07 19:48:27 volumio volumio[948]: info: May 07 19:48:27 volumio volumio[948]: ---------------------------- MPD announces state update: update May 07 19:48:27 volumio volumio[948]: info: ControllerMpd::getState May 07 19:48:27 volumio volumio[948]: verbose: ControllerMpd::sendMpdCommand status May 07 19:48:27 volumio volumio[948]: info: sendMpdCommand status took 6 milliseconds May 07 19:48:27 volumio volumio[948]: info: sendMpdCommand status took 5 milliseconds May 07 19:48:27 volumio volumio[948]: info: sendMpdCommand status took 4 milliseconds May 07 19:48:27 volumio volumio[948]: info: sendMpdCommand status took 3 milliseconds May 07 19:48:27 volumio volumio[948]: info: sendMpdCommand status took 3 milliseconds May 07 19:48:27 volumio volumio[948]: info: sendMpdCommand status took 3 milliseconds May 07 19:48:27 volumio volumio[948]: verbose: ControllerMpd::parseState May 07 19:48:27 volumio volumio[948]: info: Command Router : Notfying DB Updatetrue May 07 19:48:27 volumio volumio[948]: verbose: ControllerMpd::parseState May 07 19:48:27 volumio volumio[948]: info: Command Router : Notfying DB Updatetrue May 07 19:48:27 volumio volumio[948]: info: CoreCommandRouter::Close All Modals sent May 07 19:48:27 volumio volumio[948]: verbose: ControllerMpd::parseState May 07 19:48:27 volumio volumio[948]: verbose: ControllerMpd::parseState May 07 19:48:27 volumio volumio[948]: info: Command Router : Notfying DB Updatetrue May 07 19:48:27 volumio volumio[948]: info: CoreCommandRouter::Close All Modals sent May 07 19:48:27 volumio volumio[948]: verbose: ControllerMpd::parseState May 07 19:48:27 volumio volumio[948]: info: ControllerMpd::pushState May 07 19:48:27 volumio volumio[948]: info: CoreCommandRouter::servicePushState May 07 19:48:27 volumio volumio[948]: info: CoreStateMachine::pushState May 07 19:48:27 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:48:27 volumio volumio[948]: info: CoreCommandRouter::volumioPushState May 07 19:48:27 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:48:27 volumio volumio[948]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 07 19:48:27 volumio volumio[948]: verbose: CURRENT POSITION 0 May 07 19:48:27 volumio volumio[948]: info: CoreStateMachine::syncState stateService stop May 07 19:48:27 volumio volumio[948]: info: CoreStateMachine::syncState currentStatus stop May 07 19:48:27 volumio volumio[948]: info: CoreStateMachine::pushState May 07 19:48:27 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:48:27 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 07 19:48:27 volumio volumio[948]: info: CoreCommandRouter::volumioPushState May 07 19:48:27 volumio volumio[948]: info: No code May 07 19:48:27 volumio volumio[948]: info: CoreStateMachine::pushState May 07 19:48:27 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:48:27 volumio volumio[948]: info: CoreCommandRouter::volumioPushState May 07 19:48:27 volumio volumio[948]: info: ControllerMpd::pushState May 07 19:48:27 volumio volumio[948]: info: CoreCommandRouter::servicePushState May 07 19:48:27 volumio volumio[948]: info: CoreStateMachine::pushState May 07 19:48:27 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:48:27 volumio volumio[948]: info: CoreCommandRouter::volumioPushState May 07 19:48:27 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:48:27 volumio volumio[948]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 07 19:48:27 volumio volumio[948]: verbose: CURRENT POSITION 0 May 07 19:48:27 volumio volumio[948]: info: CoreStateMachine::syncState stateService stop May 07 19:48:27 volumio volumio[948]: info: CoreStateMachine::syncState currentStatus stop May 07 19:48:27 volumio volumio[948]: info: CoreStateMachine::pushState May 07 19:48:27 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:48:27 volumio volumio[948]: info: CoreCommandRouter::volumioPushState May 07 19:48:27 volumio volumio[948]: info: No code May 07 19:48:27 volumio volumio[948]: info: CoreStateMachine::pushState May 07 19:48:27 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:48:27 volumio volumio[948]: info: CoreCommandRouter::volumioPushState May 07 19:48:27 volumio volumio[948]: info: ControllerMpd::pushState May 07 19:48:27 volumio volumio[948]: info: CoreCommandRouter::servicePushState May 07 19:48:27 volumio volumio[948]: info: CoreStateMachine::pushState May 07 19:48:27 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:48:27 volumio volumio[948]: info: CoreCommandRouter::volumioPushState May 07 19:48:27 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:48:27 volumio volumio[948]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 07 19:48:27 volumio volumio[948]: verbose: CURRENT POSITION 0 May 07 19:48:27 volumio volumio[948]: info: CoreStateMachine::syncState stateService stop May 07 19:48:27 volumio volumio[948]: info: CoreStateMachine::syncState currentStatus stop May 07 19:48:27 volumio volumio[948]: info: CoreStateMachine::pushState May 07 19:48:27 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:48:27 volumio volumio[948]: info: CoreCommandRouter::volumioPushState May 07 19:48:27 volumio volumio[948]: info: No code May 07 19:48:27 volumio volumio[948]: info: CoreStateMachine::pushState May 07 19:48:27 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:48:27 volumio volumio[948]: info: CoreCommandRouter::volumioPushState May 07 19:48:27 volumio volumio[948]: info: ------------------------------ 200ms May 07 19:48:27 volumio volumio[948]: info: ------------------------------ 199ms May 07 19:48:27 volumio volumio[948]: info: ------------------------------ 199ms May 07 19:48:27 volumio volumio[948]: info: [1715129307382] Database update started May 07 19:48:28 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 07 19:48:29 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 19:48:33 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 19:48:37 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 19:48:40 volumio ntpd[894]: Soliciting pool server 45.231.168.6 May 07 19:48:41 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 19:48:45 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 19:48:48 volumio ntpd[894]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 07 19:48:49 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 19:48:53 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 19:48:55 volumio volumio[948]: info: CoreCommandRouter::volumioGetState May 07 19:48:55 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:48:58 volumio volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 19:48:58 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 07 19:48:58 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:48:58 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 07 19:48:59 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:48:59 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:48:59 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:48:59 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:48:59 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:48:59 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:48:59 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:48:59 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 07 19:48:59 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 07 19:49:06 volumio volumio[948]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 07 19:49:06 volumio volumio[948]: info: Preparing to save Alsa Options, stopping services first May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::volumioGetState May 07 19:49:06 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::volumioPause May 07 19:49:06 volumio volumio[948]: info: CoreStateMachine::pause May 07 19:49:06 volumio volumio[948]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDA NVidia"}} May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 07 19:49:06 volumio volumio[948]: info: Setting mixer Master for card HDA NVidia May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 07 19:49:06 volumio volumio[948]: info: Updating Volume Controller Parameters: Device: 0 Name: HDA NVidia Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 07 19:49:06 volumio volumio[948]: info: Disabling external Volume Control May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 19:49:06 volumio volumio[948]: info: Preparing to generate the ALSA configuration file May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 07 19:49:06 volumio volumio[948]: info: VolumeController:: Volume=41 Mute =false May 07 19:49:06 volumio volumio[948]: info: CoreStateMachine::pushState May 07 19:49:06 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::volumioPushState May 07 19:49:06 volumio volumio[948]: info: Asound.conf file written May 07 19:49:06 volumio sudo[2174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 07 19:49:06 volumio sudo[2174]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:49:06 volumio sudo[2174]: pam_unix(sudo:session): session closed for user root May 07 19:49:06 volumio volumio[948]: No state is present for card NVidia May 07 19:49:06 volumio volumio[948]: Found hardware: "HDA-Intel" "Cirrus Logic CS4206" "HDA:10134206,106b4d00,00100301" "0x10de" "0xcb79" May 07 19:49:06 volumio volumio[948]: Hardware is initialized using a generic method May 07 19:49:06 volumio volumio[948]: No state is present for card NVidia May 07 19:49:06 volumio volumio[948]: info: Output device has changed, restarting MPD May 07 19:49:06 volumio sudo[2179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 07 19:49:06 volumio sudo[2179]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:49:06 volumio sudo[2179]: pam_unix(sudo:session): session closed for user root May 07 19:49:06 volumio volumio[948]: info: Output device has changed, restarting Shairport Sync May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:49:06 volumio sudo[2182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 07 19:49:06 volumio sudo[2182]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:49:06 volumio systemd[1]: Stopping Music Player Daemon... May 07 19:49:06 volumio volumio[948]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 07 19:49:06 volumio volumio[948]: info: Output device has changed, restarting MPD May 07 19:49:06 volumio volumio[948]: info: Output device has changed, restarting Shairport Sync May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:49:06 volumio sudo[2188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 07 19:49:06 volumio sudo[2188]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:49:06 volumio sudo[2188]: pam_unix(sudo:session): session closed for user root May 07 19:49:06 volumio sudo[2190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 07 19:49:06 volumio sudo[2190]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:49:06 volumio volumio[948]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 07 19:49:06 volumio volumio[948]: info: MPD Permissions set May 07 19:49:06 volumio volumio[948]: info: MPD Permissions set May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:49:06 volumio systemd[1]: mpd.service: Succeeded. May 07 19:49:06 volumio systemd[1]: Stopped Music Player Daemon. May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 07 19:49:06 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 07 19:49:06 volumio systemd[1]: Starting Music Player Daemon... May 07 19:49:06 volumio volumio[948]: info: Starting Shairport Sync May 07 19:49:06 volumio volumio[948]: info: Starting Shairport Sync May 07 19:49:06 volumio sudo[2213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 07 19:49:06 volumio sudo[2213]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:49:06 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 07 19:49:06 volumio sudo[2211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 07 19:49:06 volumio sudo[2211]: pam_unix(sudo:session): session opened for user root by (uid=0) May 07 19:49:06 volumio systemd[1]: shairport-sync.service: Succeeded. May 07 19:49:06 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 07 19:49:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 07 19:49:06 volumio sudo[2213]: pam_unix(sudo:session): session closed for user root May 07 19:49:06 volumio volumio[948]: info: Shairport-Sync Started May 07 19:49:06 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 07 19:49:06 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 07 19:49:06 volumio systemd[1]: shairport-sync.service: Succeeded. May 07 19:49:06 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 07 19:49:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 07 19:49:06 volumio sudo[2211]: pam_unix(sudo:session): session closed for user root May 07 19:49:06 volumio volumio[948]: info: Shairport-Sync Started May 07 19:49:07 volumio mpd[2204]: May 07 19:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 07 19:49:07 volumio systemd[1]: Started Music Player Daemon. May 07 19:49:07 volumio sudo[2182]: pam_unix(sudo:session): session closed for user root May 07 19:49:07 volumio sudo[2190]: pam_unix(sudo:session): session closed for user root May 07 19:49:07 volumio volumio[948]: error: MPD error: The expression evaluated to a falsy value: May 07 19:49:07 volumio volumio[948]: assert.ok(self.idling) May 07 19:49:07 volumio volumio[948]: error: The expression evaluated to a falsy value: May 07 19:49:07 volumio volumio[948]: assert.ok(self.idling) May 07 19:49:07 volumio volumio[948]: error: updateQueue error: null May 07 19:49:10 volumio volumio[948]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 07 19:49:10 volumio volumio[948]: info: CoreStateMachine::getcurrentVolume May 07 19:49:10 volumio volumio[948]: info: CoreCommandRouter::volumioRetrievevolume May 07 19:49:10 volumio volumio[948]: info: VolumeController:: Volume=41 Mute =false May 07 19:49:10 volumio volumio[948]: info: CoreStateMachine::pushState May 07 19:49:10 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:49:10 volumio volumio[948]: info: CoreCommandRouter::volumioPushState May 07 19:49:10 volumio volumio[948]: info: CoreStateMachine::updateTrackBlock May 07 19:49:10 volumio volumio[948]: info: CorePlayQueue::getTrackBlock May 07 19:49:10 volumio volumio[948]: info: CoreCommandRouter::volumioRetrievevolume May 07 19:49:10 volumio volumio[948]: info: VolumeController:: Volume=41 Mute =false May 07 19:49:10 volumio volumio[948]: info: CoreStateMachine::pushState May 07 19:49:10 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:49:10 volumio volumio[948]: info: CoreCommandRouter::volumioPushState May 07 19:49:14 volumio volumio[948]: info: CoreCommandRouter::volumioGetState May 07 19:49:14 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:49:39 volumio volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 19:49:39 volumio volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 19:49:39 volumio volumio[948]: info: Retrieving Cloud Streaming UI May 07 19:49:39 volumio volumio[948]: info: Getting Tidal Cloud Configuration May 07 19:49:39 volumio volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 19:49:39 volumio volumio[948]: info: Getting Qobuz Cloud Configuration May 07 19:49:39 volumio volumio[948]: info: Asking plugin for UI Config May 07 19:49:39 volumio volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 19:49:39 volumio volumio[948]: info: Getting Spotify Cloud Configuration May 07 19:49:39 volumio volumio[948]: info: Asking plugin for UI Config May 07 19:49:39 volumio volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 19:49:39 volumio volumio[948]: info: Saving Spotify Acccount May 07 19:49:39 volumio volumio[948]: info: Got Tidal Cloud Configuration May 07 19:49:39 volumio volumio[948]: info: Got it May 07 19:49:39 volumio volumio[948]: info: Got it May 07 19:49:39 volumio volumio[948]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 07 19:49:39 volumio volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 07 19:49:39 volumio volumio[948]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 07 19:49:39 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 07 19:49:39 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 07 19:49:39 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 07 19:49:39 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 07 19:49:39 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:49:39 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:49:39 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:49:39 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 07 19:49:39 volumio volumio[948]: info: CoreCommandRouter::volumioGetBrowseSources May 07 19:49:39 volumio volumio[948]: info: CoreCommandRouter::volumioGetBrowseSources May 07 19:49:39 volumio volumio[948]: info: CoreCommandRouter::volumioGetBrowseSources May 07 19:49:39 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 19:49:39 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 07 19:49:43 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 19:49:47 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 07 19:49:48 volumio volumio[948]: info: CoreCommandRouter::volumioGetState May 07 19:49:48 volumio volumio[948]: info: CorePlayQueue::getTrack 0 May 07 19:49:50 volumio volumio[948]: info: CoreCommandRouter::volumioGetQueue May 07 19:49:50 volumio volumio[948]: info: CoreStateMachine::getQueue May 07 19:49:50 volumio volumio[948]: info: CorePlayQueue::getQueue May 07 19:49:53 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 07 19:49:53 volumio volumio[948]: info: Preload queue cleared May 07 19:49:55 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 07 19:49:55 volumio volumio[948]: info: Preload queue cleared May 07 19:49:58 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 07 19:49:58 volumio volumio[948]: info: Preload queue cleared May 07 19:50:01 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 07 19:50:01 volumio volumio[948]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 07 19:50:01 volumio volumio[948]: TypeError: Cannot read property 'length' of undefined May 07 19:50:01 volumio volumio[948]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 07 19:50:01 volumio volumio[948]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 07 19:50:01 volumio volumio[948]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 07 19:50:01 volumio volumio[948]: at Parser.emit (events.js:400:28) May 07 19:50:01 volumio volumio[948]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 07 19:50:01 volumio volumio[948]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 07 19:50:01 volumio volumio[948]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 07 19:50:01 volumio volumio[948]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 07 19:50:01 volumio volumio[948]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 07 19:50:01 volumio volumio[948]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 07 19:50:01 volumio volumio[948]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 07 19:50:01 volumio volumio[948]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 07 19:50:01 volumio volumio[948]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 07 19:50:01 volumio volumio[948]: at IncomingMessage.emit (events.js:412:35) May 07 19:50:01 volumio volumio[948]: at endReadableNT (internal/streams/readable.js:1333:12) May 07 19:50:01 volumio volumio[948]: at processTicksAndRejections (internal/process/task_queues.js:82:21) May 07 19:50:01 volumio volumio[948]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 07 19:50:02 volumio sudo[2295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-07 18:49 May 07 19:50:02 volumio sudo[2295]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"