-- Logs begin at Sat 2023-12-02 17:17:57 CET, end at Sat 2023-12-02 17:18:15 CET. -- Dec 02 17:17:57 volumior222 kernel: Booting Linux on physical CPU 0x0 Dec 02 17:17:57 volumior222 kernel: Linux version 6.1.58-v7l+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1690 SMP Tue Oct 17 15:36:02 BST 2023 Dec 02 17:17:57 volumior222 kernel: CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d Dec 02 17:17:57 volumior222 kernel: CPU: div instructions available: patching division code Dec 02 17:17:57 volumior222 kernel: CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache Dec 02 17:17:57 volumior222 kernel: OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.1 Dec 02 17:17:57 volumior222 kernel: random: crng init done Dec 02 17:17:57 volumior222 kernel: Memory policy: Data cache writealloc Dec 02 17:17:57 volumior222 kernel: Reserved memory: created CMA memory pool at 0x000000002a000000, size 64 MiB Dec 02 17:17:57 volumior222 kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Dec 02 17:17:57 volumior222 kernel: Zone ranges: Dec 02 17:17:57 volumior222 kernel: DMA [mem 0x0000000000000000-0x000000002fffffff] Dec 02 17:17:57 volumior222 kernel: Normal empty Dec 02 17:17:57 volumior222 kernel: HighMem [mem 0x0000000030000000-0x000000003dffffff] Dec 02 17:17:57 volumior222 kernel: Movable zone start for each node Dec 02 17:17:57 volumior222 kernel: Early memory node ranges Dec 02 17:17:57 volumior222 kernel: node 0: [mem 0x0000000000000000-0x000000003dffffff] Dec 02 17:17:57 volumior222 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x000000003dffffff] Dec 02 17:17:57 volumior222 kernel: percpu: Embedded 17 pages/cpu s37012 r8192 d24428 u69632 Dec 02 17:17:57 volumior222 kernel: pcpu-alloc: s37012 r8192 d24428 u69632 alloc=17*4096 Dec 02 17:17:57 volumior222 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Dec 02 17:17:57 volumior222 kernel: Built 1 zonelists, mobility grouping on. Total pages: 252224 Dec 02 17:17:57 volumior222 kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=DC:A6:32:2E:35:F8 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 Dec 02 17:17:57 volumior222 kernel: Kernel parameter elevator= does not have any effect anymore. Please use sysfs to set IO scheduler for individual devices. Dec 02 17:17:57 volumior222 kernel: Unknown kernel command line parameters "splash imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh bootdelay=5", will be passed to user space. Dec 02 17:17:57 volumior222 kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Dec 02 17:17:57 volumior222 kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Dec 02 17:17:57 volumior222 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Dec 02 17:17:57 volumior222 kernel: Memory: 907764K/1015808K available (10240K kernel code, 1504K rwdata, 3472K rodata, 2048K init, 638K bss, 42508K reserved, 65536K cma-reserved, 229376K highmem) Dec 02 17:17:57 volumior222 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Dec 02 17:17:57 volumior222 kernel: ftrace: allocating 37220 entries in 110 pages Dec 02 17:17:57 volumior222 kernel: ftrace: allocated 110 pages with 5 groups Dec 02 17:17:57 volumior222 kernel: trace event string verifier disabled Dec 02 17:17:57 volumior222 kernel: rcu: Hierarchical RCU implementation. Dec 02 17:17:57 volumior222 kernel: Rude variant of Tasks RCU enabled. Dec 02 17:17:57 volumior222 kernel: Tracing variant of Tasks RCU enabled. Dec 02 17:17:57 volumior222 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Dec 02 17:17:57 volumior222 kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Dec 02 17:17:57 volumior222 kernel: GIC: Using split EOI/Deactivate mode Dec 02 17:17:57 volumior222 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Dec 02 17:17:57 volumior222 kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Dec 02 17:17:57 volumior222 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Dec 02 17:17:57 volumior222 kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Dec 02 17:17:57 volumior222 kernel: Switching to timer-based delay loop, resolution 18ns Dec 02 17:17:57 volumior222 kernel: Console: colour dummy device 80x30 Dec 02 17:17:57 volumior222 kernel: printk: console [tty1] enabled Dec 02 17:17:57 volumior222 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000) Dec 02 17:17:57 volumior222 kernel: CPU: Testing write buffer coherency: ok Dec 02 17:17:57 volumior222 kernel: CPU0: Spectre BHB: enabling loop workaround for all CPUs Dec 02 17:17:57 volumior222 kernel: pid_max: default: 32768 minimum: 301 Dec 02 17:17:57 volumior222 kernel: LSM: Security Framework initializing Dec 02 17:17:57 volumior222 kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Dec 02 17:17:57 volumior222 kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Dec 02 17:17:57 volumior222 kernel: cgroup: Disabling memory control group subsystem Dec 02 17:17:57 volumior222 kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Dec 02 17:17:57 volumior222 kernel: cblist_init_generic: Setting adjustable number of callback queues. Dec 02 17:17:57 volumior222 kernel: cblist_init_generic: Setting shift to 2 and lim to 1. Dec 02 17:17:57 volumior222 kernel: cblist_init_generic: Setting adjustable number of callback queues. Dec 02 17:17:57 volumior222 kernel: cblist_init_generic: Setting shift to 2 and lim to 1. Dec 02 17:17:57 volumior222 kernel: Setting up static identity map for 0x200000 - 0x20003c Dec 02 17:17:57 volumior222 kernel: rcu: Hierarchical SRCU implementation. Dec 02 17:17:57 volumior222 kernel: rcu: Max phase no-delay instances is 1000. Dec 02 17:17:57 volumior222 kernel: smp: Bringing up secondary CPUs ... Dec 02 17:17:57 volumior222 kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Dec 02 17:17:57 volumior222 kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Dec 02 17:17:57 volumior222 kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Dec 02 17:17:57 volumior222 kernel: smp: Brought up 1 node, 4 CPUs Dec 02 17:17:57 volumior222 kernel: SMP: Total of 4 processors activated (432.00 BogoMIPS). Dec 02 17:17:57 volumior222 kernel: CPU: All CPU(s) started in HYP mode. Dec 02 17:17:57 volumior222 kernel: CPU: Virtualization extensions available. Dec 02 17:17:57 volumior222 kernel: devtmpfs: initialized Dec 02 17:17:57 volumior222 kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0 Dec 02 17:17:57 volumior222 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Dec 02 17:17:57 volumior222 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Dec 02 17:17:57 volumior222 kernel: pinctrl core: initialized pinctrl subsystem Dec 02 17:17:57 volumior222 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Dec 02 17:17:57 volumior222 kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Dec 02 17:17:57 volumior222 kernel: audit: initializing netlink subsys (disabled) Dec 02 17:17:57 volumior222 kernel: audit: type=2000 audit(0.020:1): state=initialized audit_enabled=0 res=1 Dec 02 17:17:57 volumior222 kernel: thermal_sys: Registered thermal governor 'step_wise' Dec 02 17:17:57 volumior222 kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Dec 02 17:17:57 volumior222 kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Dec 02 17:17:57 volumior222 kernel: Serial: AMBA PL011 UART driver Dec 02 17:17:57 volumior222 kernel: bcm2835-mbox fe00b880.mailbox: mailbox enabled Dec 02 17:17:57 volumior222 kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2023-10-17T15:39:16, variant start Dec 02 17:17:57 volumior222 kernel: raspberrypi-firmware soc:firmware: Firmware hash is 30f0c5e4d076da3ab4f341d88e7d505760b93ad7 Dec 02 17:17:57 volumior222 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Dec 02 17:17:57 volumior222 kernel: bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1 Dec 02 17:17:57 volumior222 kernel: SCSI subsystem initialized Dec 02 17:17:57 volumior222 kernel: usbcore: registered new interface driver usbfs Dec 02 17:17:57 volumior222 kernel: usbcore: registered new interface driver hub Dec 02 17:17:57 volumior222 kernel: usbcore: registered new device driver usb Dec 02 17:17:57 volumior222 kernel: usb_phy_generic phy: supply vcc not found, using dummy regulator Dec 02 17:17:57 volumior222 kernel: pps_core: LinuxPPS API ver. 1 registered Dec 02 17:17:57 volumior222 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Dec 02 17:17:57 volumior222 kernel: PTP clock support registered Dec 02 17:17:57 volumior222 kernel: vgaarb: loaded Dec 02 17:17:57 volumior222 kernel: clocksource: Switched to clocksource arch_sys_counter Dec 02 17:17:57 volumior222 kernel: VFS: Disk quotas dquot_6.6.0 Dec 02 17:17:57 volumior222 kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Dec 02 17:17:57 volumior222 kernel: FS-Cache: Loaded Dec 02 17:17:57 volumior222 kernel: CacheFiles: Loaded Dec 02 17:17:57 volumior222 kernel: NET: Registered PF_INET protocol family Dec 02 17:17:57 volumior222 kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) Dec 02 17:17:57 volumior222 kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear) Dec 02 17:17:57 volumior222 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Dec 02 17:17:57 volumior222 kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Dec 02 17:17:57 volumior222 kernel: TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear) Dec 02 17:17:57 volumior222 kernel: TCP: Hash tables configured (established 8192 bind 8192) Dec 02 17:17:57 volumior222 kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Dec 02 17:17:57 volumior222 kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Dec 02 17:17:57 volumior222 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Dec 02 17:17:57 volumior222 kernel: RPC: Registered named UNIX socket transport module. Dec 02 17:17:57 volumior222 kernel: RPC: Registered udp transport module. Dec 02 17:17:57 volumior222 kernel: RPC: Registered tcp transport module. Dec 02 17:17:57 volumior222 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Dec 02 17:17:57 volumior222 kernel: PCI: CLS 0 bytes, default 64 Dec 02 17:17:57 volumior222 kernel: Trying to unpack rootfs image as initramfs... Dec 02 17:17:57 volumior222 kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available Dec 02 17:17:57 volumior222 kernel: Initialise system trusted keyrings Dec 02 17:17:57 volumior222 kernel: workingset: timestamp_bits=14 max_order=18 bucket_order=4 Dec 02 17:17:57 volumior222 kernel: zbud: loaded Dec 02 17:17:57 volumior222 kernel: NFS: Registering the id_resolver key type Dec 02 17:17:57 volumior222 kernel: Key type id_resolver registered Dec 02 17:17:57 volumior222 kernel: Key type id_legacy registered Dec 02 17:17:57 volumior222 kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Dec 02 17:17:57 volumior222 kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Dec 02 17:17:57 volumior222 kernel: Key type asymmetric registered Dec 02 17:17:57 volumior222 kernel: Asymmetric key parser 'x509' registered Dec 02 17:17:57 volumior222 kernel: bounce: pool size: 64 pages Dec 02 17:17:57 volumior222 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Dec 02 17:17:57 volumior222 kernel: io scheduler mq-deadline registered Dec 02 17:17:57 volumior222 kernel: io scheduler kyber registered Dec 02 17:17:57 volumior222 kernel: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: Dec 02 17:17:57 volumior222 kernel: brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff] Dec 02 17:17:57 volumior222 kernel: brcm-pcie fd500000.pcie: MEM 0x0600000000..0x063fffffff -> 0x00c0000000 Dec 02 17:17:57 volumior222 kernel: brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x003fffffff -> 0x0400000000 Dec 02 17:17:57 volumior222 kernel: brcm-pcie fd500000.pcie: setting SCB_ACCESS_EN, READ_UR_MODE, MAX_BURST_SIZE Dec 02 17:17:57 volumior222 kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Dec 02 17:17:57 volumior222 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Dec 02 17:17:57 volumior222 kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff]) Dec 02 17:17:57 volumior222 kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 Dec 02 17:17:57 volumior222 kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Dec 02 17:17:57 volumior222 kernel: PCI: bus0: Fast back to back transfers disabled Dec 02 17:17:57 volumior222 kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Dec 02 17:17:57 volumior222 kernel: pci_bus 0000:01: supply vpcie3v3 not found, using dummy regulator Dec 02 17:17:57 volumior222 kernel: pci_bus 0000:01: supply vpcie3v3aux not found, using dummy regulator Dec 02 17:17:57 volumior222 kernel: pci_bus 0000:01: supply vpcie12v not found, using dummy regulator Dec 02 17:17:57 volumior222 kernel: brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC) Dec 02 17:17:57 volumior222 kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 Dec 02 17:17:57 volumior222 kernel: pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit] Dec 02 17:17:57 volumior222 kernel: pci 0000:01:00.0: PME# supported from D0 D3cold Dec 02 17:17:57 volumior222 kernel: PCI: bus1: Fast back to back transfers disabled Dec 02 17:17:57 volumior222 kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Dec 02 17:17:57 volumior222 kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff] Dec 02 17:17:57 volumior222 kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit] Dec 02 17:17:57 volumior222 kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Dec 02 17:17:57 volumior222 kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] Dec 02 17:17:57 volumior222 kernel: pcieport 0000:00:00.0: enabling device (0000 -> 0002) Dec 02 17:17:57 volumior222 kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 45 Dec 02 17:17:57 volumior222 kernel: bcm2708_fb soc:fb: FB found 1 display(s) Dec 02 17:17:57 volumior222 kernel: Console: switching to colour frame buffer device 80x30 Dec 02 17:17:57 volumior222 kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Dec 02 17:17:57 volumior222 kernel: iproc-rng200 fe104000.rng: hwrng registered Dec 02 17:17:57 volumior222 kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Dec 02 17:17:57 volumior222 kernel: brd: module loaded Dec 02 17:17:57 volumior222 kernel: loop: module loaded Dec 02 17:17:57 volumior222 kernel: Loading iSCSI transport class v2.0-870. Dec 02 17:17:57 volumior222 kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Dec 02 17:17:57 volumior222 kernel: Freeing initrd memory: 13620K Dec 02 17:17:57 volumior222 kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Dec 02 17:17:57 volumior222 kernel: usbcore: registered new interface driver r8152 Dec 02 17:17:57 volumior222 kernel: usbcore: registered new interface driver lan78xx Dec 02 17:17:57 volumior222 kernel: usbcore: registered new interface driver smsc95xx Dec 02 17:17:57 volumior222 kernel: xhci_hcd 0000:01:00.0: enabling device (0140 -> 0142) Dec 02 17:17:57 volumior222 kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Dec 02 17:17:57 volumior222 kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Dec 02 17:17:57 volumior222 kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0f00040000000890 Dec 02 17:17:57 volumior222 kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Dec 02 17:17:57 volumior222 kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Dec 02 17:17:57 volumior222 kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Dec 02 17:17:57 volumior222 kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.01 Dec 02 17:17:57 volumior222 kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Dec 02 17:17:57 volumior222 kernel: usb usb1: Product: xHCI Host Controller Dec 02 17:17:57 volumior222 kernel: usb usb1: Manufacturer: Linux 6.1.58-v7l+ xhci-hcd Dec 02 17:17:57 volumior222 kernel: usb usb1: SerialNumber: 0000:01:00.0 Dec 02 17:17:57 volumior222 kernel: hub 1-0:1.0: USB hub found Dec 02 17:17:57 volumior222 kernel: hub 1-0:1.0: 1 port detected Dec 02 17:17:57 volumior222 kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.01 Dec 02 17:17:57 volumior222 kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Dec 02 17:17:57 volumior222 kernel: usb usb2: Product: xHCI Host Controller Dec 02 17:17:57 volumior222 kernel: usb usb2: Manufacturer: Linux 6.1.58-v7l+ xhci-hcd Dec 02 17:17:57 volumior222 kernel: usb usb2: SerialNumber: 0000:01:00.0 Dec 02 17:17:57 volumior222 kernel: hub 2-0:1.0: USB hub found Dec 02 17:17:57 volumior222 kernel: hub 2-0:1.0: 4 ports detected Dec 02 17:17:57 volumior222 kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Dec 02 17:17:57 volumior222 kernel: dwc_otg: FIQ enabled Dec 02 17:17:57 volumior222 kernel: dwc_otg: NAK holdoff enabled Dec 02 17:17:57 volumior222 kernel: dwc_otg: FIQ split-transaction FSM enabled Dec 02 17:17:57 volumior222 kernel: Module dwc_common_port init Dec 02 17:17:57 volumior222 kernel: usbcore: registered new interface driver uas Dec 02 17:17:57 volumior222 kernel: usbcore: registered new interface driver usb-storage Dec 02 17:17:57 volumior222 kernel: mousedev: PS/2 mouse device common for all mice Dec 02 17:17:57 volumior222 kernel: sdhci: Secure Digital Host Controller Interface driver Dec 02 17:17:57 volumior222 kernel: sdhci: Copyright(c) Pierre Ossman Dec 02 17:17:57 volumior222 kernel: sdhci-pltfm: SDHCI platform and OF driver helper Dec 02 17:17:57 volumior222 kernel: ledtrig-cpu: registered to indicate activity on CPUs Dec 02 17:17:57 volumior222 kernel: hid: raw HID events driver (C) Jiri Kosina Dec 02 17:17:57 volumior222 kernel: usbcore: registered new interface driver usbhid Dec 02 17:17:57 volumior222 kernel: usbhid: USB HID core driver Dec 02 17:17:57 volumior222 kernel: Initializing XFRM netlink socket Dec 02 17:17:57 volumior222 kernel: NET: Registered PF_PACKET protocol family Dec 02 17:17:57 volumior222 kernel: Key type dns_resolver registered Dec 02 17:17:57 volumior222 kernel: Registering SWP/SWPB emulation handler Dec 02 17:17:57 volumior222 kernel: registered taskstats version 1 Dec 02 17:17:57 volumior222 kernel: Loading compiled-in X.509 certificates Dec 02 17:17:57 volumior222 kernel: Key type .fscrypt registered Dec 02 17:17:57 volumior222 kernel: Key type fscrypt-provisioning registered Dec 02 17:17:57 volumior222 kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Dec 02 17:17:57 volumior222 kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Dec 02 17:17:57 volumior222 kernel: fe201000.serial: ttyAMA1 at MMIO 0xfe201000 (irq = 51, base_baud = 0) is a PL011 rev2 Dec 02 17:17:57 volumior222 kernel: serial serial0: tty port ttyAMA1 registered Dec 02 17:17:57 volumior222 kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Dec 02 17:17:57 volumior222 kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Dec 02 17:17:57 volumior222 kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Dec 02 17:17:57 volumior222 kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Dec 02 17:17:57 volumior222 kernel: of_cfs_init Dec 02 17:17:57 volumior222 kernel: of_cfs_init: OK Dec 02 17:17:57 volumior222 kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Dec 02 17:17:57 volumior222 kernel: Freeing unused kernel image (initmem) memory: 2048K Dec 02 17:17:57 volumior222 kernel: Run /init as init process Dec 02 17:17:57 volumior222 kernel: with arguments: Dec 02 17:17:57 volumior222 kernel: /init Dec 02 17:17:57 volumior222 kernel: splash Dec 02 17:17:57 volumior222 kernel: with environment: Dec 02 17:17:57 volumior222 kernel: HOME=/ Dec 02 17:17:57 volumior222 kernel: TERM=linux Dec 02 17:17:57 volumior222 kernel: imgpart=/dev/mmcblk0p2 Dec 02 17:17:57 volumior222 kernel: imgfile=/volumio_current.sqsh Dec 02 17:17:57 volumior222 kernel: bootdelay=5 Dec 02 17:17:57 volumior222 kernel: mmc1: new high speed SDIO card at address 0001 Dec 02 17:17:57 volumior222 kernel: mmc0: new ultra high speed DDR50 SDHC card at address 5048 Dec 02 17:17:57 volumior222 kernel: mmcblk0: mmc0:5048 SD16G 14.4 GiB Dec 02 17:17:57 volumior222 kernel: mmcblk0: p1 p2 p3 Dec 02 17:17:57 volumior222 kernel: mmcblk0: mmc0:5048 SD16G 14.4 GiB Dec 02 17:17:57 volumior222 kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Dec 02 17:17:57 volumior222 initramfs: Booting Volumio for BCM2711 Dec 02 17:17:57 volumior222 initramfs: This script mounts rootfs RO with an overlay RW layer. Dec 02 17:17:57 volumior222 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Dec 02 17:17:57 volumior222 initramfs: IMGPART=/dev/mmcblk0p2 Dec 02 17:17:57 volumior222 initramfs: IMGFILE=/volumio_current.sqsh Dec 02 17:17:57 volumior222 initramfs: Boot delay (except first time) will be 5 seconds Dec 02 17:17:57 volumior222 initramfs: /dev/mmcblk0p2: Dec 02 17:17:57 volumior222 kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.20 Dec 02 17:17:57 volumior222 kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Dec 02 17:17:57 volumior222 kernel: usb 1-1: Product: USB2.0 Hub Dec 02 17:17:57 volumior222 kernel: hub 1-1:1.0: USB hub found Dec 02 17:17:57 volumior222 kernel: hub 1-1:1.0: 4 ports detected Dec 02 17:17:57 volumior222 kernel: EXT4-fs (mmcblk0p2): recovery complete Dec 02 17:17:57 volumior222 kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Quota mode: none. Dec 02 17:17:57 volumior222 initramfs: Doing a 5 second delay here to give kernel load a headstart Dec 02 17:17:57 volumior222 initramfs: Checking for USB updates Dec 02 17:17:57 volumior222 initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Dec 02 17:17:57 volumior222 kernel: loop0: detected capacity change from 0 to 962048 Dec 02 17:17:57 volumior222 kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Dec 02 17:17:57 volumior222 kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Dec 02 17:17:57 volumior222 kernel: EXT4-fs (mmcblk0p3): recovery complete Dec 02 17:17:57 volumior222 kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Quota mode: none. Dec 02 17:17:57 volumior222 initramfs: With Option: Dec 02 17:17:57 volumior222 initramfs: VOLUMIO_VERSION="3.569" Dec 02 17:17:57 volumior222 initramfs: Finish initramfs, continue booting Volumio Dec 02 17:17:57 volumior222 systemd[1]: System time before build time, advancing clock. Dec 02 17:17:57 volumior222 kernel: NET: Registered PF_INET6 protocol family Dec 02 17:17:57 volumior222 kernel: Segment Routing with IPv6 Dec 02 17:17:57 volumior222 kernel: In-situ OAM (IOAM) with IPv6 Dec 02 17:17:57 volumior222 systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) Dec 02 17:17:57 volumior222 systemd[1]: Detected architecture arm. Dec 02 17:17:57 volumior222 systemd[1]: Set hostname to . Dec 02 17:17:57 volumior222 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 02 17:17:57 volumior222 systemd[1]: Created slice User and Session Slice. Dec 02 17:17:57 volumior222 systemd[1]: Listening on initctl Compatibility Named Pipe. Dec 02 17:17:57 volumior222 systemd[1]: Reached target System Time Synchronized. Dec 02 17:17:57 volumior222 systemd[1]: Listening on Journal Socket. Dec 02 17:17:57 volumior222 systemd[1]: Mounting Kernel Debug File System... Dec 02 17:17:57 volumior222 systemd[1]: Mounting POSIX Message Queue File System... Dec 02 17:17:57 volumior222 kernel: i2c_dev: i2c /dev entries driver Dec 02 17:17:57 volumior222 systemd-journald[216]: Journal started Dec 02 17:17:57 volumior222 systemd-journald[216]: Runtime journal (/run/log/journal/4ceeebcde4a5f04b591e77826532825c) is 3.7M, max 30.0M, 26.2M free. Dec 02 17:17:57 volumior222 systemd-modules-load[202]: Inserted module 'i2c_dev' Dec 02 17:17:57 volumior222 fake-hwclock[200]: Sat Dec 2 16:17:57 UTC 2023 Dec 02 17:17:57 volumior222 systemd[1]: Started Create Static Device Nodes in /dev. Dec 02 17:17:57 volumior222 systemd[1]: Starting udev Kernel Device Manager... Dec 02 17:17:57 volumior222 systemd[1]: Reached target Local File Systems (Pre). Dec 02 17:17:57 volumior222 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Dec 02 17:17:57 volumior222 systemd[1]: Mounting /var/log... Dec 02 17:17:57 volumior222 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Dec 02 17:17:57 volumior222 systemd[1]: Mounting /tmp... Dec 02 17:17:57 volumior222 systemd[1]: Mounting /var/spool/cups... Dec 02 17:17:57 volumior222 systemd[1]: Mounted /var/log. Dec 02 17:17:57 volumior222 systemd[1]: Starting Flush Journal to Persistent Storage... Dec 02 17:17:57 volumior222 systemd[1]: Started udev Coldplug all Devices. Dec 02 17:17:57 volumior222 systemd[1]: Mounted /tmp. Dec 02 17:17:57 volumior222 systemd[1]: Mounted /var/spool/cups. Dec 02 17:17:57 volumior222 systemd[1]: Mounting /var/spool/cups/tmp... Dec 02 17:17:57 volumior222 systemd-udevd[235]: Network interface NamePolicy= disabled on kernel command line, ignoring. Dec 02 17:17:57 volumior222 systemd[1]: Starting Helper to synchronize boot up for ifupdown... Dec 02 17:17:57 volumior222 systemd[1]: Mounted /var/spool/cups/tmp. Dec 02 17:17:57 volumior222 systemd-journald[216]: Runtime journal (/run/log/journal/4ceeebcde4a5f04b591e77826532825c) is 3.7M, max 30.0M, 26.2M free. Dec 02 17:17:57 volumior222 systemd[1]: Started Flush Journal to Persistent Storage. Dec 02 17:17:57 volumior222 systemd[1]: Started udev Kernel Device Manager. Dec 02 17:17:57 volumior222 systemd[1]: Starting Show Plymouth Boot Screen... Dec 02 17:17:57 volumior222 systemd[1]: Received SIGRTMIN+20 from PID 255 (plymouthd). Dec 02 17:17:57 volumior222 systemd[1]: Started Show Plymouth Boot Screen. Dec 02 17:17:57 volumior222 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Dec 02 17:17:57 volumior222 systemd[1]: Reached target Local Encrypted Volumes. Dec 02 17:17:57 volumior222 systemd[1]: Reached target Paths. Dec 02 17:17:57 volumior222 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Dec 02 17:17:57 volumior222 systemd-udevd[254]: Using default interface naming scheme 'v240'. Dec 02 17:17:57 volumior222 kernel: mc: Linux media interface: v0.10 Dec 02 17:17:57 volumior222 systemd-udevd[254]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 02 17:17:57 volumior222 kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Dec 02 17:17:57 volumior222 kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Dec 02 17:17:57 volumior222 kernel: [vc_sm_connected_init]: start Dec 02 17:17:57 volumior222 kernel: [vc_sm_connected_init]: installed successfully Dec 02 17:17:57 volumior222 kernel: videodev: Linux video capture interface: v2.00 Dec 02 17:17:58 volumior222 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Dec 02 17:17:58 volumior222 kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Dec 02 17:17:58 volumior222 kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Dec 02 17:17:58 volumior222 kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Dec 02 17:17:58 volumior222 kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Dec 02 17:17:58 volumior222 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Dec 02 17:17:58 volumior222 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Dec 02 17:17:58 volumior222 kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Dec 02 17:17:58 volumior222 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Dec 02 17:17:58 volumior222 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Dec 02 17:17:58 volumior222 kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Dec 02 17:17:58 volumior222 kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Dec 02 17:17:58 volumior222 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Dec 02 17:17:58 volumior222 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Dec 02 17:17:58 volumior222 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Dec 02 17:17:58 volumior222 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Dec 02 17:17:58 volumior222 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Dec 02 17:17:58 volumior222 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Dec 02 17:17:58 volumior222 kernel: rpivid feb10000.codec: Device registered as /dev/video19 Dec 02 17:17:58 volumior222 kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Dec 02 17:17:58 volumior222 kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Dec 02 17:17:58 volumior222 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Dec 02 17:17:58 volumior222 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Dec 02 17:17:58 volumior222 systemd[1]: Found device /dev/mmcblk0p1. Dec 02 17:17:58 volumior222 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Dec 02 17:17:58 volumior222 kernel: Bluetooth: Core ver 2.22 Dec 02 17:17:58 volumior222 kernel: NET: Registered PF_BLUETOOTH protocol family Dec 02 17:17:58 volumior222 kernel: Bluetooth: HCI device and connection manager initialized Dec 02 17:17:58 volumior222 kernel: Bluetooth: HCI socket layer initialized Dec 02 17:17:58 volumior222 kernel: Bluetooth: L2CAP socket layer initialized Dec 02 17:17:58 volumior222 kernel: Bluetooth: SCO socket layer initialized Dec 02 17:17:58 volumior222 systemd[1]: Starting File System Check on /dev/mmcblk0p1... Dec 02 17:17:58 volumior222 kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Dec 02 17:17:58 volumior222 kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Dec 02 17:17:58 volumior222 kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Dec 02 17:17:58 volumior222 kernel: Bluetooth: HCI UART driver ver 2.3 Dec 02 17:17:58 volumior222 kernel: Bluetooth: HCI UART protocol H4 registered Dec 02 17:17:58 volumior222 kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Dec 02 17:17:58 volumior222 kernel: Bluetooth: HCI UART protocol Broadcom registered Dec 02 17:17:58 volumior222 kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Dec 02 17:17:58 volumior222 kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Dec 02 17:17:59 volumior222 kernel: uart-pl011 fe201000.serial: no DMA platform data Dec 02 17:17:59 volumior222 systemd-fsck[309]: fsck.fat 4.1 (2017-01-24) Dec 02 17:17:59 volumior222 systemd-fsck[309]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Dec 02 17:17:59 volumior222 systemd-fsck[309]: Automatically removing dirty bit. Dec 02 17:17:59 volumior222 systemd-fsck[309]: Performing changes. Dec 02 17:17:59 volumior222 systemd-fsck[309]: /dev/mmcblk0p1: 363 files, 33267/46774 clusters Dec 02 17:17:59 volumior222 kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Dec 02 17:17:59 volumior222 systemd[1]: Started File System Check Daemon to report status. Dec 02 17:17:59 volumior222 systemd[1]: Started File System Check on /dev/mmcblk0p1. Dec 02 17:17:59 volumior222 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Dec 02 17:17:59 volumior222 kernel: usbcore: registered new interface driver brcmfmac Dec 02 17:17:59 volumior222 systemd[1]: Found device /sys/subsystem/net/devices/eth0. Dec 02 17:17:59 volumior222 kernel: Bluetooth: hci0: BCM: chip id 107 Dec 02 17:17:59 volumior222 kernel: Bluetooth: hci0: BCM: features 0x2f Dec 02 17:17:59 volumior222 kernel: Bluetooth: hci0: BCM4345C0 Dec 02 17:17:59 volumior222 kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Dec 02 17:17:59 volumior222 kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Dec 02 17:17:59 volumior222 systemd-udevd[251]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 02 17:17:59 volumior222 kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov 1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60 Dec 02 17:17:59 volumior222 systemd-udevd[252]: Using default interface naming scheme 'v240'. Dec 02 17:17:59 volumior222 systemd-udevd[252]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 02 17:18:00 volumior222 kernel: Bluetooth: hci0: BCM: features 0x2f Dec 02 17:18:00 volumior222 kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Dec 02 17:18:00 volumior222 kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Dec 02 17:18:00 volumior222 systemd-udevd[253]: Process '/sbin/crda' failed with exit code 255. Dec 02 17:18:00 volumior222 systemd-udevd[254]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 0' failed with exit code 17. Dec 02 17:18:00 volumior222 systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. Dec 02 17:18:00 volumior222 systemd[1]: Created slice system-bthelper.slice. Dec 02 17:18:00 volumior222 systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Dec 02 17:18:00 volumior222 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Dec 02 17:18:00 volumior222 systemd[1]: Condition check resulted in Huge Pages File System being skipped. Dec 02 17:18:00 volumior222 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Dec 02 17:18:00 volumior222 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Dec 02 17:18:00 volumior222 systemd[1]: Condition check resulted in FUSE Control File System being skipped. Dec 02 17:18:00 volumior222 systemd[1]: Mounting /boot... Dec 02 17:18:00 volumior222 systemd[1]: Starting Load/Save RF Kill Switch Status... Dec 02 17:18:00 volumior222 systemd[1]: Mounted /boot. Dec 02 17:18:00 volumior222 systemd[1]: Reached target Local File Systems. Dec 02 17:18:00 volumior222 systemd[1]: Started ifup for wlan0. Dec 02 17:18:00 volumior222 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Dec 02 17:18:00 volumior222 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Dec 02 17:18:00 volumior222 systemd[1]: Started ifup for eth0. Dec 02 17:18:00 volumior222 systemd[1]: Starting Create Volatile Files and Directories... Dec 02 17:18:00 volumior222 systemd[1]: Starting Preprocess NFS configuration... Dec 02 17:18:00 volumior222 systemd[1]: Started Load/Save RF Kill Switch Status. Dec 02 17:18:00 volumior222 systemd[1]: plymouth-read-write.service: Succeeded. Dec 02 17:18:00 volumior222 systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Dec 02 17:18:00 volumior222 systemd[1]: Received SIGRTMIN+20 from PID 255 (plymouthd). Dec 02 17:18:00 volumior222 systemd[1]: nfs-config.service: Succeeded. Dec 02 17:18:00 volumior222 systemd[1]: Started Preprocess NFS configuration. Dec 02 17:18:00 volumior222 systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Dec 02 17:18:00 volumior222 systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Dec 02 17:18:00 volumior222 systemd[1]: Reached target NFS client services. Dec 02 17:18:00 volumior222 systemd[1]: Started Create Volatile Files and Directories. Dec 02 17:18:00 volumior222 systemd[1]: Starting Update UTMP about System Boot/Shutdown... Dec 02 17:18:00 volumior222 systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Dec 02 17:18:00 volumior222 systemd[1]: Starting RPC bind portmap service... Dec 02 17:18:00 volumior222 systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Dec 02 17:18:00 volumior222 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 02 17:18:00 volumior222 systemd[1]: Started Helper to synchronize boot up for ifupdown. Dec 02 17:18:00 volumior222 kernel: 8021q: 802.1Q VLAN Support v1.8 Dec 02 17:18:00 volumior222 systemd[1]: Starting Raise network interfaces... Dec 02 17:18:00 volumior222 systemd[1]: Started Update UTMP about System Boot/Shutdown. Dec 02 17:18:00 volumior222 systemd[1]: Reached target System Initialization. Dec 02 17:18:00 volumior222 systemd[1]: Listening on D-Bus System Message Bus Socket. Dec 02 17:18:01 volumior222 systemd[1]: Listening on mpd.socket. Dec 02 17:18:01 volumior222 systemd[1]: Started Daily Cleanup of Temporary Directories. Dec 02 17:18:01 volumior222 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Dec 02 17:18:01 volumior222 systemd[1]: Listening on triggerhappy.socket. Dec 02 17:18:01 volumior222 systemd[1]: Reached target Sockets. Dec 02 17:18:01 volumior222 systemd[1]: Reached target Basic System. Dec 02 17:18:01 volumior222 systemd[1]: Started UPnP Renderer front-end to MPD. Dec 02 17:18:01 volumior222 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Dec 02 17:18:01 volumior222 systemd[1]: Starting Login Service... Dec 02 17:18:01 volumior222 systemd[1]: Starting Wireless Services... Dec 02 17:18:01 volumior222 systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Dec 02 17:18:01 volumior222 systemd[1]: Started Volumio Iptables Module. Dec 02 17:18:01 volumior222 systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Dec 02 17:18:01 volumior222 systemd[1]: Starting dhcpcd on all interfaces... Dec 02 17:18:01 volumior222 systemd[1]: Starting Configure Bluetooth Modems connected by UART... Dec 02 17:18:01 volumior222 systemd[1]: Started D-Bus System Message Bus. Dec 02 17:18:01 volumior222 dhcpcd[479]: Not running dhcpcd because /etc/network/interfaces Dec 02 17:18:01 volumior222 dhcpcd[479]: defines some interfaces that will use a Dec 02 17:18:01 volumior222 dhcpcd[479]: DHCP client or static address Dec 02 17:18:01 volumior222 systemd[1]: Starting WPA supplicant... Dec 02 17:18:01 volumior222 systemd[1]: Started Manage Sound Card State (restore and store). Dec 02 17:18:01 volumior222 alsactl[498]: alsactl 1.1.8 daemon started Dec 02 17:18:01 volumior222 systemd[1]: Starting Save/Restore Sound Card State... Dec 02 17:18:01 volumior222 systemd[1]: apt-daily.timer: Not using persistent file timestamp Sun 2026-02-08 16:02:02 CET as it is in the future. Dec 02 17:18:01 volumior222 systemd[1]: Started Daily apt download activities. Dec 02 17:18:01 volumior222 systemd[1]: Starting triggerhappy global hotkey daemon... Dec 02 17:18:01 volumior222 avahi-daemon[470]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Dec 02 17:18:01 volumior222 avahi-daemon[470]: Successfully dropped root privileges. Dec 02 17:18:01 volumior222 avahi-daemon[470]: avahi-daemon 0.7 starting up. Dec 02 17:18:01 volumior222 sh[388]: eth0: waiting for carrier Dec 02 17:18:01 volumior222 dhcpcd[422]: eth0: waiting for carrier Dec 02 17:18:01 volumior222 kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Dec 02 17:18:01 volumior222 kernel: bcmgenet fd580000.ethernet eth0: Link is Down Dec 02 17:18:01 volumior222 systemd[1]: Started volumio-remote-updater.service. Dec 02 17:18:01 volumior222 systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Sun 2026-02-08 16:02:02 CET as it is in the future. Dec 02 17:18:01 volumior222 systemd[1]: Started Daily apt upgrade and clean activities. Dec 02 17:18:01 volumior222 systemd[1]: Reached target Timers. Dec 02 17:18:01 volumior222 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Dec 02 17:18:01 volumior222 systemd[1]: Started RPC bind portmap service. Dec 02 17:18:01 volumior222 systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Dec 02 17:18:01 volumior222 systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Dec 02 17:18:01 volumior222 systemd[1]: Failed to start dhcpcd on all interfaces. Dec 02 17:18:01 volumior222 systemd-logind[473]: New seat seat0. Dec 02 17:18:01 volumior222 systemd[1]: Started Save/Restore Sound Card State. Dec 02 17:18:01 volumior222 thd[507]: Unable to parse trigger line: Dec 02 17:18:01 volumior222 thd[507]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Dec 02 17:18:01 volumior222 thd[507]: Unable to parse trigger line: Dec 02 17:18:01 volumior222 thd[507]: Found socket passed from systemd Dec 02 17:18:01 volumior222 systemd[1]: Started triggerhappy global hotkey daemon. Dec 02 17:18:01 volumior222 systemd[1]: hciuart.service: Succeeded. Dec 02 17:18:01 volumior222 systemd[1]: Started Configure Bluetooth Modems connected by UART. Dec 02 17:18:01 volumior222 ifup[431]: ifup: waiting for lock on /run/network/ifstate.wlan0 Dec 02 17:18:01 volumior222 sh[384]: wlan0=wlan0 Dec 02 17:18:01 volumior222 systemd[1]: Starting Raspberry Pi bluetooth helper... Dec 02 17:18:01 volumior222 systemd[1]: Reached target Sound Card. Dec 02 17:18:01 volumior222 systemd[1]: Reached target RPC Port Mapper. Dec 02 17:18:01 volumior222 systemd[1]: Reached target Remote File Systems (Pre). Dec 02 17:18:01 volumior222 systemd[1]: Reached target Remote File Systems. Dec 02 17:18:01 volumior222 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Dec 02 17:18:01 volumior222 bthelper[520]: Raspberry Pi BDADDR already set Dec 02 17:18:02 volumior222 systemd[1]: Started Login Service. Dec 02 17:18:02 volumior222 systemd[1]: Started Raspberry Pi bluetooth helper. Dec 02 17:18:02 volumior222 wpa_supplicant[491]: Successfully initialized wpa_supplicant Dec 02 17:18:02 volumior222 avahi-daemon[470]: Successfully called chroot(). Dec 02 17:18:02 volumior222 avahi-daemon[470]: Successfully dropped remaining capabilities. Dec 02 17:18:02 volumior222 avahi-daemon[470]: Loading service file /services/volumio.service. Dec 02 17:18:02 volumior222 avahi-daemon[470]: Network interface enumeration completed. Dec 02 17:18:02 volumior222 avahi-daemon[470]: Server startup complete. Host name is volumior222.local. Local service cookie is 3559296337. Dec 02 17:18:02 volumior222 avahi-daemon[470]: Service "Volumior222" (/services/volumio.service) successfully established. Dec 02 17:18:02 volumior222 systemd[1]: Started Raise network interfaces. Dec 02 17:18:02 volumior222 systemd[1]: Started WPA supplicant. Dec 02 17:18:02 volumior222 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Dec 02 17:18:02 volumior222 systemd[1]: Reached target Network. Dec 02 17:18:02 volumior222 systemd[1]: Starting Permit User Sessions... Dec 02 17:18:02 volumior222 systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Dec 02 17:18:02 volumior222 systemd[1]: Reached target Network is Online. Dec 02 17:18:02 volumior222 systemd[1]: Starting LSB: Brings up/down network automatically... Dec 02 17:18:02 volumior222 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 02 17:18:02 volumior222 systemd[1]: Starting Samba NMB Daemon... Dec 02 17:18:02 volumior222 systemd[1]: Starting /etc/rc.local Compatibility... Dec 02 17:18:02 volumior222 systemd[1]: Starting Network Time Service... Dec 02 17:18:02 volumior222 volumio-remote-updater[515]: Error: No active session Dec 02 17:18:02 volumior222 volumio-remote-updater[515]: [2023-12-02 17:18:02] [info] asio async_connect error: system:111 (Connection refused) Dec 02 17:18:02 volumior222 volumio-remote-updater[515]: [2023-12-02 17:18:02] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 02 17:18:02 volumior222 volumio-remote-updater[515]: [2023-12-02 17:18:02] [error] handle_connect error: Underlying Transport Error Dec 02 17:18:02 volumior222 volumio[468]: Could not open config: /tmp/upmpdcli.conf Dec 02 17:18:02 volumior222 systemd[1]: Starting Music Player Daemon... Dec 02 17:18:02 volumior222 systemd[1]: Starting Bluetooth service... Dec 02 17:18:02 volumior222 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 02 17:18:02 volumior222 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 02 17:18:02 volumior222 systemd[1]: Started Permit User Sessions. Dec 02 17:18:02 volumior222 systemd[1]: Started /etc/rc.local Compatibility. Dec 02 17:18:02 volumior222 systemd[1]: iptables.service: Succeeded. Dec 02 17:18:02 volumior222 systemd[1]: Starting Terminate Plymouth Boot Screen... Dec 02 17:18:02 volumior222 systemd[1]: Starting Hold until boot process finishes up... Dec 02 17:18:02 volumior222 systemd[1]: plymouth-start.service: Succeeded. Dec 02 17:18:02 volumior222 loadcpufreq[530]: Loading cpufreq kernel modules...done (none). Dec 02 17:18:02 volumior222 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Dec 02 17:18:02 volumior222 ntpd[572]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 02 17:18:02 volumior222 ntpd[572]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Dec 02 17:18:02 volumior222 systemd[1]: Started Network Time Service. Dec 02 17:18:03 volumior222 systemd[1]: plymouth-quit.service: Succeeded. Dec 02 17:18:03 volumior222 ntpd[614]: proto: precision = 2.111 usec (-19) Dec 02 17:18:03 volumior222 systemd[1]: Started Terminate Plymouth Boot Screen. Dec 02 17:18:03 volumior222 bluetoothd[587]: Bluetooth daemon 5.50 Dec 02 17:18:03 volumior222 systemd[1]: plymouth-quit-wait.service: Succeeded. Dec 02 17:18:03 volumior222 systemd[1]: Started Hold until boot process finishes up. Dec 02 17:18:03 volumior222 systemd[1]: Received SIGRTMIN+21 from PID 255 (n/a). Dec 02 17:18:03 volumior222 systemd[1]: Received SIGRTMIN+21 from PID 255 (n/a). Dec 02 17:18:03 volumior222 bluetoothd[587]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Dec 02 17:18:03 volumior222 ntpd[614]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 02 17:18:03 volumior222 systemd[1]: Started Getty on tty1. Dec 02 17:18:03 volumior222 ntpd[614]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2023-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 02 17:18:03 volumior222 ntpd[614]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): will expire in less than 26 days Dec 02 17:18:03 volumior222 systemd[1]: Reached target Login Prompts. Dec 02 17:18:03 volumior222 ntpd[614]: Listen and drop on 0 v6wildcard [::]:123 Dec 02 17:18:03 volumior222 ntpd[614]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 02 17:18:03 volumior222 ntpd[614]: Listen normally on 2 lo 127.0.0.1:123 Dec 02 17:18:03 volumior222 systemd[1]: Starting LSB: set CPUFreq kernel parameters... Dec 02 17:18:03 volumior222 ntpd[614]: Listening on routing socket on fd #19 for interface updates Dec 02 17:18:03 volumior222 ntpd[614]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 02 17:18:03 volumior222 ntpd[614]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 02 17:18:03 volumior222 ifplugd(eth0)[626]: ifplugd 0.28 initializing. Dec 02 17:18:03 volumior222 ifplugd(eth0)[626]: Using interface eth0/DC:A6:32:2E:35:F8 with driver (version: 6.1.58-v7l+) Dec 02 17:18:03 volumior222 ifplugd(eth0)[626]: Using detection mode: SIOCETHTOOL Dec 02 17:18:03 volumior222 systemd[1]: Started Bluetooth service. Dec 02 17:18:03 volumior222 ifplugd(eth0)[626]: Initialization complete, link beat not detected. Dec 02 17:18:03 volumior222 systemd[1]: Reached target Bluetooth. Dec 02 17:18:03 volumior222 bluetoothd[587]: Starting SDP server Dec 02 17:18:03 volumior222 bluetoothd[587]: Excluding (cli) sap Dec 02 17:18:03 volumior222 ifplugd[563]: Network Interface Plugging Daemon...start eth0...done. Dec 02 17:18:03 volumior222 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Dec 02 17:18:03 volumior222 kernel: Bluetooth: BNEP filters: protocol multicast Dec 02 17:18:03 volumior222 kernel: Bluetooth: BNEP socket layer initialized Dec 02 17:18:03 volumior222 bluetoothd[587]: Bluetooth management interface 1.22 initialized Dec 02 17:18:03 volumior222 kernel: Bluetooth: MGMT ver 1.22 Dec 02 17:18:03 volumior222 dbus-daemon[484]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=587 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Dec 02 17:18:03 volumior222 systemd[1]: Started LSB: Brings up/down network automatically. Dec 02 17:18:03 volumior222 cpufrequtils[625]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Dec 02 17:18:03 volumior222 bluetoothd[587]: Failed to set privacy: Rejected (0x0b) Dec 02 17:18:03 volumior222 systemd[1]: Started LSB: set CPUFreq kernel parameters. Dec 02 17:18:03 volumior222 systemd[1]: Starting Hostname Service... Dec 02 17:18:03 volumior222 dbus-daemon[484]: [system] Successfully activated service 'org.freedesktop.hostname1' Dec 02 17:18:03 volumior222 systemd[1]: Started Hostname Service. Dec 02 17:18:03 volumior222 haveged[425]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Dec 02 17:18:03 volumior222 haveged[425]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Dec 02 17:18:03 volumior222 haveged[425]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99872 Dec 02 17:18:03 volumior222 haveged[425]: haveged: fills: 0, generated: 0 Dec 02 17:18:03 volumior222 nmbd[594]: [2023/12/02 17:18:03.958366, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Dec 02 17:18:03 volumior222 nmbd[594]: started asyncdns process 648 Dec 02 17:18:03 volumior222 nmbd[594]: [2023/12/02 17:18:03.964021, 0] ../lib/util/become_daemon.c:149(daemon_status) Dec 02 17:18:03 volumior222 nmbd[594]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Dec 02 17:18:03 volumior222 nmbd[594]: [2023/12/02 17:18:03.964992, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Dec 02 17:18:03 volumior222 nmbd[594]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Dec 02 17:18:03 volumior222 ntpd[614]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 02 17:18:04 volumior222 wireless.js[475]: Cleaning previous... Dec 02 17:18:04 volumior222 sudo[656]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 02 17:18:04 volumior222 sudo[656]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:04 volumior222 sudo[656]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:04 volumior222 sudo[658]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 02 17:18:04 volumior222 sudo[658]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:04 volumior222 ntpd[614]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 02 17:18:05 volumior222 sudo[658]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:05 volumior222 mpd[645]: Dec 02 17:18 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 02 17:18:05 volumior222 mpd[645]: Dec 02 17:18 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 02 17:18:05 volumior222 mpd[645]: Dec 02 17:18 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 02 17:18:05 volumior222 wireless.js[475]: Stopped aP Dec 02 17:18:05 volumior222 wireless.js[475]: Wireless Networking DISABLED, not starting wireless flow Dec 02 17:18:05 volumior222 systemd[1]: Started Music Player Daemon. Dec 02 17:18:05 volumior222 systemd[1]: wireless.service: Succeeded. Dec 02 17:18:05 volumior222 systemd[1]: Started Wireless Services. Dec 02 17:18:05 volumior222 systemd[1]: Started Volumio Backend Module. Dec 02 17:18:05 volumior222 systemd[1]: Started Volumio Cpu Tweaker. Dec 02 17:18:05 volumior222 volumio-cpu-tweak[669]: Setting RT Priority for mpd Dec 02 17:18:05 volumior222 volumio-cpu-tweak[669]: Setting MPD Affinity Dec 02 17:18:05 volumior222 volumio-cpu-tweak[669]: pid 645's current affinity mask: f Dec 02 17:18:05 volumior222 volumio-cpu-tweak[669]: pid 645's new affinity mask: 3 Dec 02 17:18:05 volumior222 volumio-cpu-tweak[669]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Dec 02 17:18:05 volumior222 volumio-cpu-tweak[669]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Dec 02 17:18:05 volumior222 systemd[1]: volumio_cpu_tweak.service: Succeeded. Dec 02 17:18:05 volumior222 sh[388]: eth0: carrier acquired Dec 02 17:18:05 volumior222 dhcpcd[422]: eth0: carrier acquired Dec 02 17:18:05 volumior222 sh[388]: DUID 00:01:00:01:2c:c5:40:e8:dc:a6:32:2e:35:f8 Dec 02 17:18:05 volumior222 sh[388]: eth0: IAID 32:2e:35:f8 Dec 02 17:18:05 volumior222 sh[388]: eth0: adding address fe80::dea6:32ff:fe2e:35f8 Dec 02 17:18:05 volumior222 sh[388]: ipv6_addaddr1: Permission denied Dec 02 17:18:05 volumior222 dhcpcd[422]: DUID 00:01:00:01:2c:c5:40:e8:dc:a6:32:2e:35:f8 Dec 02 17:18:05 volumior222 dhcpcd[422]: eth0: IAID 32:2e:35:f8 Dec 02 17:18:05 volumior222 dhcpcd[422]: eth0: adding address fe80::dea6:32ff:fe2e:35f8 Dec 02 17:18:05 volumior222 dhcpcd[422]: ipv6_addaddr1: Permission denied Dec 02 17:18:05 volumior222 kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Dec 02 17:18:05 volumior222 sh[388]: eth0: rebinding lease of 192.168.0.52 Dec 02 17:18:05 volumior222 dhcpcd[422]: eth0: rebinding lease of 192.168.0.52 Dec 02 17:18:05 volumior222 systemd[1]: systemd-rfkill.service: Succeeded. Dec 02 17:18:05 volumior222 sh[388]: eth0: probing address 192.168.0.52/24 Dec 02 17:18:05 volumior222 dhcpcd[422]: eth0: probing address 192.168.0.52/24 Dec 02 17:18:05 volumior222 ntpd[614]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 02 17:18:06 volumior222 sh[388]: eth0: soliciting an IPv6 router Dec 02 17:18:06 volumior222 dhcpcd[422]: eth0: soliciting an IPv6 router Dec 02 17:18:06 volumior222 ifplugd(eth0)[626]: Link beat detected. Dec 02 17:18:06 volumior222 ifplugd(eth0)[626]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Dec 02 17:18:06 volumior222 ifplugd(eth0)[626]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Dec 02 17:18:06 volumior222 volumio[668]: info: ------------------------------------------- Dec 02 17:18:06 volumior222 volumio[668]: info: ----- Volumio3 ---- Dec 02 17:18:06 volumior222 volumio[668]: info: ------------------------------------------- Dec 02 17:18:06 volumior222 volumio[668]: info: ----- System startup ---- Dec 02 17:18:06 volumior222 volumio[668]: info: ------------------------------------------- Dec 02 17:18:06 volumior222 ntpd[614]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 02 17:18:07 volumior222 bthelper[520]: Changing power off succeeded Dec 02 17:18:07 volumior222 bthelper[520]: [CHG] Controller DC:A6:32:2E:35:FF Powered: no Dec 02 17:18:07 volumior222 bthelper[520]: [CHG] Controller DC:A6:32:2E:35:FF Discovering: no Dec 02 17:18:07 volumior222 bthelper[520]: [CHG] Controller DC:A6:32:2E:35:FF Class: 0x00000000 Dec 02 17:18:07 volumior222 bthelper[520]: [CHG] Controller DC:A6:32:2E:35:FF Class: 0x0000041c Dec 02 17:18:07 volumior222 bthelper[520]: Changing power on succeeded Dec 02 17:18:07 volumior222 bthelper[520]: [CHG] Controller DC:A6:32:2E:35:FF Powered: yes Dec 02 17:18:07 volumior222 volumio-remote-updater[515]: [2023-12-02 17:18:07] [connect] Successful connection Dec 02 17:18:07 volumior222 volumio[668]: info: MYVOLUMIO Environment detected Dec 02 17:18:07 volumior222 volumio[668]: info: Plugin folders cleanup Dec 02 17:18:07 volumior222 volumio[668]: info: Scanning into folder /volumio/app/plugins/ Dec 02 17:18:07 volumior222 volumio[668]: info: Scanning category audio_interface Dec 02 17:18:07 volumior222 volumio[668]: info: Scanning category miscellanea Dec 02 17:18:07 volumior222 volumio[668]: info: Scanning category music_service Dec 02 17:18:07 volumior222 volumio[668]: info: Scanning category plugins.json Dec 02 17:18:07 volumior222 volumio[668]: info: Scanning category system_controller Dec 02 17:18:07 volumior222 volumio[668]: info: Scanning category user_interface Dec 02 17:18:07 volumior222 volumio[668]: info: Scanning into folder /data/plugins/ Dec 02 17:18:07 volumior222 volumio[668]: info: Plugin folders cleanup completed Dec 02 17:18:07 volumior222 volumio[668]: info: ------------------------------------------- Dec 02 17:18:07 volumior222 volumio[668]: info: ----- Core plugins startup ---- Dec 02 17:18:07 volumior222 volumio[668]: info: ------------------------------------------- Dec 02 17:18:07 volumior222 volumio[668]: info: Loading plugins from folder /volumio/app/plugins/ Dec 02 17:18:07 volumior222 volumio[668]: info: Adding plugin upnp to MyMusic Plugins Dec 02 17:18:07 volumior222 volumio[668]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 02 17:18:07 volumior222 volumio[668]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 02 17:18:07 volumior222 volumio[668]: info: Loading plugins from folder /data/plugins/ Dec 02 17:18:07 volumior222 volumio[668]: info: Loading plugin "system"... Dec 02 17:18:07 volumior222 volumio[668]: info: Loading plugin "appearance"... Dec 02 17:18:08 volumior222 volumio[668]: info: Loading plugin "network"... Dec 02 17:18:08 volumior222 volumio[668]: info: Refreshing Cached IP Addresses Dec 02 17:18:08 volumior222 volumio[668]: info: Loading plugin "services"... Dec 02 17:18:08 volumior222 sudo[709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 02 17:18:08 volumior222 sudo[709]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:08 volumior222 volumio[668]: info: Loading plugin "alsa_controller"... Dec 02 17:18:08 volumior222 sudo[709]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:08 volumior222 sudo[711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 02 17:18:08 volumior222 sudo[711]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:08 volumior222 sudo[711]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:08 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 02 17:18:08 volumior222 volumio[668]: info: Loading plugin "wizard"... Dec 02 17:18:08 volumior222 volumio[668]: info: Loading plugin "volumio_command_line_client"... Dec 02 17:18:08 volumior222 volumio[668]: info: Loading plugin "upnp"... Dec 02 17:18:08 volumior222 volumio[668]: info: [1701533888812] Starting Upmpd Daemon Dec 02 17:18:08 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 02 17:18:08 volumior222 volumio[668]: info: Loading plugin "my_music"... Dec 02 17:18:08 volumior222 volumio[668]: info: Loading plugin "mpd"... Dec 02 17:18:09 volumior222 volumio[668]: info: Creating MPD Configuration file Dec 02 17:18:09 volumior222 volumio[668]: info: Loading plugin "upnp_browser"... Dec 02 17:18:09 volumior222 sudo[717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 02 17:18:09 volumior222 sudo[717]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:09 volumior222 sudo[717]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:09 volumior222 sudo[719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 02 17:18:09 volumior222 sudo[719]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:09 volumior222 systemd[1]: Stopping Music Player Daemon... Dec 02 17:18:09 volumior222 systemd[1]: mpd.service: Succeeded. Dec 02 17:18:09 volumior222 systemd[1]: Stopped Music Player Daemon. Dec 02 17:18:09 volumior222 systemd[1]: Starting Music Player Daemon... Dec 02 17:18:09 volumior222 volumio[668]: info: Loading plugin "networkfs"... Dec 02 17:18:09 volumior222 volumio[668]: info: Starting Udev Watcher for removable devices Dec 02 17:18:09 volumior222 sudo[727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Radek,password=Myszka_1,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.22/Multimedia /mnt/NAS/Qnap222 Dec 02 17:18:09 volumior222 sudo[727]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:09 volumior222 kernel: Key type cifs.spnego registered Dec 02 17:18:09 volumior222 kernel: Key type cifs.idmap registered Dec 02 17:18:09 volumior222 sudo[727]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:09 volumior222 kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Dec 02 17:18:09 volumior222 kernel: CIFS: Attempting to mount \\192.168.0.22\Multimedia Dec 02 17:18:09 volumior222 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Dec 02 17:18:09 volumior222 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Dec 02 17:18:09 volumior222 volumio[668]: info: Ignoring mount for partition: boot Dec 02 17:18:09 volumior222 volumio[668]: info: Ignoring mount for partition: volumio Dec 02 17:18:09 volumior222 volumio[668]: info: Ignoring mount for partition: volumio_data Dec 02 17:18:09 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 02 17:18:09 volumior222 volumio[668]: info: Loading plugin "alarm-clock"... Dec 02 17:18:09 volumior222 mpd[724]: Dec 02 17:18 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 02 17:18:10 volumior222 mpd[724]: Dec 02 17:18 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 02 17:18:10 volumior222 mpd[724]: Dec 02 17:18 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 02 17:18:10 volumior222 systemd[1]: Started Music Player Daemon. Dec 02 17:18:10 volumior222 sudo[719]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:10 volumior222 volumio[668]: info: Loading plugin "airplay_emulation"... Dec 02 17:18:10 volumior222 volumio[668]: info: Starting Shairport Sync Dec 02 17:18:10 volumior222 volumio[668]: info: Loading plugin "last_100"... Dec 02 17:18:10 volumior222 volumio[668]: info: Loading plugin "webradio"... Dec 02 17:18:10 volumior222 volumio[668]: info: Loading plugin "i2s_dacs"... Dec 02 17:18:10 volumior222 volumio[668]: info: Loading plugin "volumiodiscovery"... Dec 02 17:18:10 volumior222 volumio[668]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 02 17:18:10 volumior222 node[668]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 02 17:18:10 volumior222 node[668]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 02 17:18:10 volumior222 volumio[668]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 02 17:18:10 volumior222 volumio[668]: *** WARNING *** For more information see Dec 02 17:18:10 volumior222 volumio[668]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 02 17:18:10 volumior222 volumio[668]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 02 17:18:10 volumior222 volumio[668]: *** WARNING *** For more information see Dec 02 17:18:10 volumior222 node[668]: *** WARNING *** For more information see Dec 02 17:18:10 volumior222 node[668]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 02 17:18:10 volumior222 node[668]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 02 17:18:10 volumior222 node[668]: *** WARNING *** For more information see Dec 02 17:18:10 volumior222 volumio[668]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 02 17:18:10 volumior222 volumio[668]: info: Discovery: Started advertising with name: Volumior222 Dec 02 17:18:10 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 02 17:18:10 volumior222 volumio[668]: info: Loading plugin "outputs"... Dec 02 17:18:10 volumior222 volumio[668]: info: Loading plugin "albumart"... Dec 02 17:18:10 volumior222 volumio[668]: info: Plugin example_plugin is not enabled Dec 02 17:18:10 volumior222 volumio[668]: info: Loading plugin "inputs"... Dec 02 17:18:10 volumior222 volumio[668]: info: Loading plugin "updater_comm"... Dec 02 17:18:10 volumior222 volumio[668]: info: Plugin mpdemulation is not enabled Dec 02 17:18:10 volumior222 volumio[668]: info: Loading plugin "rest_api"... Dec 02 17:18:10 volumior222 volumio[668]: info: Loading plugin "websocket"... Dec 02 17:18:10 volumior222 volumio[668]: info: Loading i18n strings for locale pl Dec 02 17:18:10 volumior222 volumio[668]: Updating browse sources language Dec 02 17:18:10 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 17:18:10 volumior222 volumio[668]: Forking 3 albumart workers Dec 02 17:18:10 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 17:18:10 volumior222 volumio[668]: info: CoreCommandRouter::initPlayerControls Dec 02 17:18:10 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 17:18:10 volumior222 volumio[668]: Express server listening on port 3000 Dec 02 17:18:10 volumior222 volumio[668]: [Metrics] WebUI: 4s 450.72ms Dec 02 17:18:10 volumior222 volumio[668]: info: CoreStateMachine::resetVolumioState Dec 02 17:18:10 volumior222 volumio[668]: info: CoreStateMachine::getcurrentVolume Dec 02 17:18:10 volumior222 volumio[668]: info: CoreCommandRouter::volumioRetrievevolume Dec 02 17:18:10 volumior222 volumio[668]: info: CoreStateMachine::pushState Dec 02 17:18:10 volumior222 volumio[668]: info: CorePlayQueue::getTrack 0 Dec 02 17:18:10 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 17:18:10 volumior222 volumio[668]: info: CoreCommandRouter::volumioPushState Dec 02 17:18:10 volumior222 volumio[668]: info: Cannot mount NAS Qnap222 at system boot, trial number 1 ,retrying in 5 seconds Dec 02 17:18:10 volumior222 volumio[668]: info: MPD Permissions set Dec 02 17:18:10 volumior222 volumio-remote-updater[515]: [2023-12-02 17:18:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1701533887 101 Dec 02 17:18:10 volumior222 volumio[668]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Dec 02 17:18:10 volumior222 volumio[668]: info: MPD running with PID724 Dec 02 17:18:10 volumior222 volumio[668]: ,establishing connection Dec 02 17:18:10 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:10 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 17:18:11 volumior222 volumio[668]: info: Reloading queue from file Dec 02 17:18:11 volumior222 volumio[668]: error: updateQueue error: null Dec 02 17:18:11 volumior222 volumio[668]: info: CoreStateMachine::setRepeat false single undefined Dec 02 17:18:11 volumior222 volumio[668]: info: CoreStateMachine::pushState Dec 02 17:18:11 volumior222 volumio[668]: info: CorePlayQueue::getTrack 0 Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::volumioPushState Dec 02 17:18:11 volumior222 volumio[668]: info: CoreStateMachine::setRandom null Dec 02 17:18:11 volumior222 volumio[668]: info: CoreStateMachine::pushState Dec 02 17:18:11 volumior222 volumio[668]: info: CorePlayQueue::getTrack 0 Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::volumioPushState Dec 02 17:18:11 volumior222 volumio[668]: info: Setting Device type: Raspberry PI Dec 02 17:18:11 volumior222 volumio[668]: info: Completed loading Core Plugins Dec 02 17:18:11 volumior222 volumio[668]: info: Preparing to generate the ALSA configuration file Dec 02 17:18:11 volumior222 volumio[668]: error: updateQueue error: null Dec 02 17:18:11 volumior222 volumio[668]: info: Asound.conf file unchanged, so no further update is needed Dec 02 17:18:11 volumior222 volumio[668]: info: Output device has changed, restarting MPD Dec 02 17:18:11 volumior222 volumio[668]: info: Output device has changed, restarting Shairport Sync Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 17:18:11 volumior222 sudo[786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 02 17:18:11 volumior222 sudo[786]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:11 volumior222 sudo[787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 02 17:18:11 volumior222 volumio[668]: Starting albumart workers Dec 02 17:18:11 volumior222 sudo[786]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:11 volumior222 sudo[787]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:11 volumior222 systemd[1]: Stopping Music Player Daemon... Dec 02 17:18:11 volumior222 volumio[668]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 02 17:18:11 volumior222 volumio[668]: info: ___________ START PLUGINS ___________ Dec 02 17:18:11 volumior222 volumio[668]: Starting albumart workers Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 02 17:18:11 volumior222 volumio[668]: info: [1701533891177] CoreMusicLibrary::Adding element Media Servers Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 17:18:11 volumior222 systemd[1]: mpd.service: Succeeded. Dec 02 17:18:11 volumior222 systemd[1]: Stopped Music Player Daemon. Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 17:18:11 volumior222 volumio[668]: Starting albumart workers Dec 02 17:18:11 volumior222 volumio[668]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 17:18:11 volumior222 systemd[1]: Starting Music Player Daemon... Dec 02 17:18:11 volumior222 sh[388]: eth0: leased 192.168.0.52 for 86400 seconds Dec 02 17:18:11 volumior222 dhcpcd[422]: eth0: leased 192.168.0.52 for 86400 seconds Dec 02 17:18:11 volumior222 sh[388]: eth0: adding route to 192.168.0.0/24 Dec 02 17:18:11 volumior222 sh[388]: eth0: adding default route via 192.168.0.1 Dec 02 17:18:11 volumior222 dhcpcd[422]: eth0: adding route to 192.168.0.0/24 Dec 02 17:18:11 volumior222 dhcpcd[422]: eth0: adding default route via 192.168.0.1 Dec 02 17:18:11 volumior222 avahi-daemon[470]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.52. Dec 02 17:18:11 volumior222 avahi-daemon[470]: New relevant interface eth0.IPv4 for mDNS. Dec 02 17:18:11 volumior222 avahi-daemon[470]: Registering new address record for 192.168.0.52 on eth0.IPv4. Dec 02 17:18:11 volumior222 volumio[668]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 02 17:18:11 volumior222 volumio[668]: info: [1701533891365] CoreMusicLibrary::Adding element Last_100 Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 02 17:18:11 volumior222 volumio[668]: info: [1701533891368] CoreMusicLibrary::Adding element Webradio Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 02 17:18:11 volumior222 volumio[668]: info: Volumio Calling Home Dec 02 17:18:11 volumior222 sh[388]: forked to background, child pid 821 Dec 02 17:18:11 volumior222 dhcpcd[422]: forked to background, child pid 821 Dec 02 17:18:11 volumior222 volumio[668]: info: Completed starting Core Plugins Dec 02 17:18:11 volumior222 volumio[668]: info: ------------------------------------------- Dec 02 17:18:11 volumior222 volumio[668]: info: ----- MyVolumio plugins startup ---- Dec 02 17:18:11 volumior222 volumio[668]: info: ------------------------------------------- Dec 02 17:18:11 volumior222 volumio[668]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 02 17:18:11 volumior222 sh[388]: eth0=eth0 Dec 02 17:18:11 volumior222 ifplugd(eth0)[626]: client: ifup: interface eth0 already configured Dec 02 17:18:11 volumior222 volumio[668]: Unhandled rejection Error: No sockets available, cannot start. Dec 02 17:18:11 volumior222 volumio[668]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Dec 02 17:18:11 volumior222 volumio[668]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Dec 02 17:18:11 volumior222 volumio[668]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Dec 02 17:18:11 volumior222 volumio[668]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Dec 02 17:18:11 volumior222 volumio[668]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Dec 02 17:18:11 volumior222 volumio[668]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Dec 02 17:18:11 volumior222 volumio[668]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Dec 02 17:18:11 volumior222 volumio[668]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Dec 02 17:18:11 volumior222 volumio[668]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Dec 02 17:18:11 volumior222 volumio[668]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Dec 02 17:18:11 volumior222 volumio[668]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Dec 02 17:18:11 volumior222 volumio[668]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 02 17:18:11 volumior222 volumio[668]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 02 17:18:11 volumior222 volumio[668]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Dec 02 17:18:11 volumior222 volumio[668]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Dec 02 17:18:11 volumior222 volumio[668]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 02 17:18:11 volumior222 volumio[668]: info: MPD Permissions set Dec 02 17:18:11 volumior222 volumio[668]: verbose: New Socket.io Connection to 192.168.0.52:3000 from 192.168.0.126 UA: Dart/3.10 (dart:io) Total Clients: 2 Dec 02 17:18:11 volumior222 ifplugd(eth0)[626]: Program executed successfully. Dec 02 17:18:11 volumior222 volumio[668]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Dec 02 17:18:11 volumior222 volumio[668]: info: Volumio called home Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 17:18:11 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:18:11 volumior222 volumio[668]: info: Starting Shairport Sync Dec 02 17:18:11 volumior222 volumio[668]: info: Starting Shairport Sync Dec 02 17:18:11 volumior222 sudo[870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 02 17:18:11 volumior222 sudo[870]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:11 volumior222 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 02 17:18:11 volumior222 systemd[1]: shairport-sync.service: Succeeded. Dec 02 17:18:11 volumior222 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 02 17:18:11 volumior222 volumio[668]: info: Starting Shairport Sync Dec 02 17:18:11 volumior222 sudo[877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 02 17:18:11 volumior222 sudo[877]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:11 volumior222 sudo[880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 02 17:18:11 volumior222 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 02 17:18:11 volumior222 sudo[880]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:11 volumior222 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 02 17:18:11 volumior222 sudo[870]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:11 volumior222 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 02 17:18:11 volumior222 systemd[1]: shairport-sync.service: Succeeded. Dec 02 17:18:11 volumior222 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 02 17:18:11 volumior222 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 02 17:18:11 volumior222 sudo[877]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:11 volumior222 sudo[880]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:11 volumior222 volumio[668]: info: Shairport-Sync Started Dec 02 17:18:11 volumior222 volumio[668]: Error adding Membership: Error: addMembership EINVAL Dec 02 17:18:12 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 17:18:12 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 17:18:12 volumior222 volumio[668]: info: Discovery: Getting this device information Dec 02 17:18:12 volumior222 volumio[668]: info: CoreCommandRouter::volumioGetState Dec 02 17:18:12 volumior222 volumio[668]: info: CorePlayQueue::getTrack 0 Dec 02 17:18:12 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 17:18:12 volumior222 volumio[668]: info: Shairport-Sync Started Dec 02 17:18:12 volumior222 volumio[668]: info: Shairport-Sync Started Dec 02 17:18:12 volumior222 volumio[668]: info: CoreCommandRouter::volumioGetState Dec 02 17:18:12 volumior222 volumio[668]: info: CorePlayQueue::getTrack 0 Dec 02 17:18:12 volumior222 volumio[668]: info: Discovery: adding f0bbaaae-89e0-4ce7-9c12-15b7f65c2e5b Dec 02 17:18:12 volumior222 volumio[668]: info: Discovery: Found device Volumior222 Dec 02 17:18:12 volumior222 volumio[668]: info: CoreCommandRouter::volumioGetState Dec 02 17:18:12 volumior222 volumio[668]: info: CorePlayQueue::getTrack 0 Dec 02 17:18:12 volumior222 mpd[824]: Dec 02 17:18 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 02 17:18:12 volumior222 mpd[824]: Dec 02 17:18 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 02 17:18:12 volumior222 mpd[824]: Dec 02 17:18 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 02 17:18:12 volumior222 systemd[1]: Started Music Player Daemon. Dec 02 17:18:12 volumior222 sudo[787]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:12 volumior222 volumio[668]: error: updateQueue error: null Dec 02 17:18:12 volumior222 ntpd[614]: Listen normally on 3 eth0 192.168.0.52:123 Dec 02 17:18:12 volumior222 ntpd[614]: new interface(s) found: waking up resolver Dec 02 17:18:13 volumior222 volumio[668]: verbose: New Socket.io Connection to 192.168.0.52 from 192.168.0.126 UA: Mozilla/5.0 (Linux; Android 10; BLA-L29 Build/HUAWEIBLA-L29S; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Total Clients: 4 Dec 02 17:18:13 volumior222 sudo[896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 02 17:18:13 volumior222 sudo[896]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:13 volumior222 sudo[896]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:13 volumior222 sudo[898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 02 17:18:13 volumior222 sudo[898]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:13 volumior222 sudo[898]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:13 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 02 17:18:13 volumior222 volumio[668]: verbose: New Socket.io Connection to 192.168.0.52 from 192.168.0.126 UA: Mozilla/5.0 (Linux; Android 10; BLA-L29 Build/HUAWEIBLA-L29S; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Total Clients: 5 Dec 02 17:18:13 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 02 17:18:13 volumior222 volumio[668]: info: CoreCommandRouter::volumioGetVisibleSources Dec 02 17:18:13 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 17:18:13 volumior222 volumio[668]: info: CoreCommandRouter::volumioGetState Dec 02 17:18:13 volumior222 volumio[668]: info: CorePlayQueue::getTrack 0 Dec 02 17:18:13 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 02 17:18:13 volumior222 volumio[668]: info: CoreCommandRouter::volumioGetQueue Dec 02 17:18:13 volumior222 volumio[668]: info: CoreStateMachine::getQueue Dec 02 17:18:13 volumior222 volumio[668]: info: CorePlayQueue::getQueue Dec 02 17:18:13 volumior222 volumio[668]: info: Listing playlists Dec 02 17:18:13 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 02 17:18:13 volumior222 volumio[668]: info: Received Get System Info Dec 02 17:18:13 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 17:18:13 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 17:18:13 volumior222 volumio[668]: info: Discovery: Getting this device information Dec 02 17:18:13 volumior222 volumio[668]: info: CoreCommandRouter::volumioGetState Dec 02 17:18:13 volumior222 volumio[668]: info: CorePlayQueue::getTrack 0 Dec 02 17:18:13 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 17:18:13 volumior222 volumio[668]: info: CoreCommandRouter::volumioGetState Dec 02 17:18:13 volumior222 volumio[668]: info: CorePlayQueue::getTrack 0 Dec 02 17:18:13 volumior222 nmbd[594]: [2023/12/02 17:18:13.986690, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 02 17:18:13 volumior222 nmbd[594]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Dec 02 17:18:13 volumior222 systemd[1]: Started Samba NMB Daemon. Dec 02 17:18:14 volumior222 nmbd[594]: [2023/12/02 17:18:14.007468, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 02 17:18:14 volumior222 nmbd[594]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.52 for name WORKGROUP<1d>. Dec 02 17:18:14 volumior222 nmbd[594]: This response was from IP 192.168.0.1, reporting an IP address of 192.168.0.1. Dec 02 17:18:14 volumior222 systemd[1]: Starting Samba Winbind Daemon... Dec 02 17:18:14 volumior222 sudo[904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 02 17:18:14 volumior222 sudo[904]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:14 volumior222 sudo[904]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:14 volumior222 sudo[906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 02 17:18:14 volumior222 sudo[906]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:18:14 volumior222 sudo[906]: pam_unix(sudo:session): session closed for user root Dec 02 17:18:14 volumior222 winbindd[901]: [2023/12/02 17:18:14.227881, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Dec 02 17:18:14 volumior222 winbindd[901]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 02 17:18:14 volumior222 winbindd[901]: [2023/12/02 17:18:14.244630, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 02 17:18:14 volumior222 winbindd[901]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Dec 02 17:18:14 volumior222 systemd[1]: Started Samba Winbind Daemon. Dec 02 17:18:14 volumior222 systemd[1]: Starting Samba SMB Daemon... Dec 02 17:18:14 volumior222 volumio[668]: verbose: New Socket.io Connection to 192.168.0.52 from 192.168.0.126 UA: Mozilla/5.0 (Linux; Android 10; BLA-L29 Build/HUAWEIBLA-L29S; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Total Clients: 6 Dec 02 17:18:14 volumior222 winbindd[901]: [2023/12/02 17:18:14.593801, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Dec 02 17:18:14 volumior222 winbindd[901]: res_names->count = 3, expected 4 Dec 02 17:18:14 volumior222 smbd[912]: [2023/12/02 17:18:14.609349, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 02 17:18:14 volumior222 smbd[912]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Dec 02 17:18:14 volumior222 systemd[1]: Started Samba SMB Daemon. Dec 02 17:18:14 volumior222 systemd[1]: Reached target Multi-User System. Dec 02 17:18:14 volumior222 systemd[1]: Reached target Graphical Interface. Dec 02 17:18:14 volumior222 systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 02 17:18:14 volumior222 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 02 17:18:14 volumior222 systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 02 17:18:14 volumior222 systemd[1]: Startup finished in 9.119s (kernel) + 19.368s (userspace) = 28.488s. Dec 02 17:18:14 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 02 17:18:14 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 02 17:18:14 volumior222 volumio[668]: info: CoreCommandRouter::volumioGetVisibleSources Dec 02 17:18:14 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 17:18:14 volumior222 volumio[668]: info: CoreCommandRouter::volumioGetState Dec 02 17:18:14 volumior222 volumio[668]: info: CorePlayQueue::getTrack 0 Dec 02 17:18:14 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 02 17:18:14 volumior222 volumio[668]: info: CoreCommandRouter::volumioGetQueue Dec 02 17:18:14 volumior222 volumio[668]: info: CoreStateMachine::getQueue Dec 02 17:18:14 volumior222 volumio[668]: info: CorePlayQueue::getQueue Dec 02 17:18:14 volumior222 volumio[668]: info: Listing playlists Dec 02 17:18:14 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 02 17:18:14 volumior222 volumio[668]: info: Received Get System Info Dec 02 17:18:14 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 02 17:18:14 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 02 17:18:14 volumior222 volumio[668]: info: Discovery: Getting this device information Dec 02 17:18:14 volumior222 volumio[668]: info: CoreCommandRouter::volumioGetState Dec 02 17:18:14 volumior222 volumio[668]: info: CorePlayQueue::getTrack 0 Dec 02 17:18:14 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 02 17:18:14 volumior222 volumio[668]: info: CoreCommandRouter::volumioGetState Dec 02 17:18:14 volumior222 volumio[668]: info: CorePlayQueue::getTrack 0 Dec 02 17:18:14 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 02 17:18:14 volumior222 volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 02 17:18:15 volumior222 volumio[668]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 17:18:15 volumior222 volumio[668]: Error: certificate is not yet valid Dec 02 17:18:15 volumior222 volumio[668]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Dec 02 17:18:15 volumior222 volumio[668]: at TLSSocket.emit (events.js:315:20) Dec 02 17:18:15 volumior222 volumio[668]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Dec 02 17:18:15 volumior222 volumio[668]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Dec 02 17:18:15 volumior222 volumio[668]: code: 'CERT_NOT_YET_VALID' Dec 02 17:18:15 volumior222 volumio[668]: } Dec 02 17:18:15 volumior222 volumio[668]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 17:18:15 volumior222 sudo[930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-12-02 17:17 Dec 02 17:18:15 volumior222 sudo[930]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 03:38:28 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4163d3756b55b3bf7c480d7285f68954"