-- Logs begin at Sun 2025-01-05 08:35:01 UTC, end at Sun 2025-01-05 08:35:37 UTC. -- Jan 05 08:35:01 volumio-pi400 kernel: Booting Linux on physical CPU 0x0 Jan 05 08:35:01 volumio-pi400 kernel: Linux version 5.10.92-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) #1514 SMP Mon Jan 17 17:38:03 GMT 2022 Jan 05 08:35:01 volumio-pi400 kernel: CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d Jan 05 08:35:01 volumio-pi400 kernel: CPU: div instructions available: patching division code Jan 05 08:35:01 volumio-pi400 kernel: CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache Jan 05 08:35:01 volumio-pi400 kernel: OF: fdt: Machine model: Raspberry Pi 400 Rev 1.0 Jan 05 08:35:01 volumio-pi400 kernel: random: fast init done Jan 05 08:35:01 volumio-pi400 kernel: Memory policy: Data cache writealloc Jan 05 08:35:01 volumio-pi400 kernel: Reserved memory: created CMA memory pool at 0x000000002a000000, size 64 MiB Jan 05 08:35:01 volumio-pi400 kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Jan 05 08:35:01 volumio-pi400 kernel: Zone ranges: Jan 05 08:35:01 volumio-pi400 kernel: DMA [mem 0x0000000000000000-0x000000002fffffff] Jan 05 08:35:01 volumio-pi400 kernel: Normal empty Jan 05 08:35:01 volumio-pi400 kernel: HighMem [mem 0x0000000030000000-0x00000000fbffffff] Jan 05 08:35:01 volumio-pi400 kernel: Movable zone start for each node Jan 05 08:35:01 volumio-pi400 kernel: Early memory node ranges Jan 05 08:35:01 volumio-pi400 kernel: node 0: [mem 0x0000000000000000-0x000000003dffffff] Jan 05 08:35:01 volumio-pi400 kernel: node 0: [mem 0x0000000040000000-0x00000000fbffffff] Jan 05 08:35:01 volumio-pi400 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000000fbffffff] Jan 05 08:35:01 volumio-pi400 kernel: On node 0 totalpages: 1024000 Jan 05 08:35:01 volumio-pi400 kernel: DMA zone: 1728 pages used for memmap Jan 05 08:35:01 volumio-pi400 kernel: DMA zone: 0 pages reserved Jan 05 08:35:01 volumio-pi400 kernel: DMA zone: 196608 pages, LIFO batch:63 Jan 05 08:35:01 volumio-pi400 kernel: HighMem zone: 827392 pages, LIFO batch:63 Jan 05 08:35:01 volumio-pi400 kernel: percpu: Embedded 20 pages/cpu s50828 r8192 d22900 u81920 Jan 05 08:35:01 volumio-pi400 kernel: pcpu-alloc: s50828 r8192 d22900 u81920 alloc=20*4096 Jan 05 08:35:01 volumio-pi400 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Jan 05 08:35:01 volumio-pi400 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1022272 Jan 05 08:35:01 volumio-pi400 kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=DC:A6:32:DE:27:50 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=0 snd_bcm2835.enable_hdmi=1 loglevel=0 Jan 05 08:35:01 volumio-pi400 kernel: Kernel parameter elevator= does not have any effect anymore. Please use sysfs to set IO scheduler for individual devices. Jan 05 08:35:01 volumio-pi400 kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Jan 05 08:35:01 volumio-pi400 kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Jan 05 08:35:01 volumio-pi400 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jan 05 08:35:01 volumio-pi400 kernel: software IO TLB: mapped [mem 0x0000000023c90000-0x0000000027c90000] (64MB) Jan 05 08:35:01 volumio-pi400 kernel: Memory: 3895420K/4096000K available (10240K kernel code, 1366K rwdata, 3196K rodata, 2048K init, 882K bss, 135044K reserved, 65536K cma-reserved, 3309568K highmem) Jan 05 08:35:01 volumio-pi400 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Jan 05 08:35:01 volumio-pi400 kernel: ftrace: allocating 34360 entries in 101 pages Jan 05 08:35:01 volumio-pi400 kernel: ftrace: allocated 101 pages with 4 groups Jan 05 08:35:01 volumio-pi400 kernel: rcu: Hierarchical RCU implementation. Jan 05 08:35:01 volumio-pi400 kernel: Rude variant of Tasks RCU enabled. Jan 05 08:35:01 volumio-pi400 kernel: Tracing variant of Tasks RCU enabled. Jan 05 08:35:01 volumio-pi400 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Jan 05 08:35:01 volumio-pi400 kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Jan 05 08:35:01 volumio-pi400 kernel: GIC: Using split EOI/Deactivate mode Jan 05 08:35:01 volumio-pi400 kernel: random: get_random_bytes called from start_kernel+0x3c8/0x59c with crng_init=1 Jan 05 08:35:01 volumio-pi400 kernel: sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns Jan 05 08:35:01 volumio-pi400 kernel: clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns Jan 05 08:35:01 volumio-pi400 kernel: bcm2835: system timer (irq = 25) Jan 05 08:35:01 volumio-pi400 kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Jan 05 08:35:01 volumio-pi400 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Jan 05 08:35:01 volumio-pi400 kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Jan 05 08:35:01 volumio-pi400 kernel: Switching to timer-based delay loop, resolution 18ns Jan 05 08:35:01 volumio-pi400 kernel: Console: colour dummy device 80x30 Jan 05 08:35:01 volumio-pi400 kernel: printk: console [tty1] enabled Jan 05 08:35:01 volumio-pi400 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000) Jan 05 08:35:01 volumio-pi400 kernel: pid_max: default: 32768 minimum: 301 Jan 05 08:35:01 volumio-pi400 kernel: LSM: Security Framework initializing Jan 05 08:35:01 volumio-pi400 kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Jan 05 08:35:01 volumio-pi400 kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Jan 05 08:35:01 volumio-pi400 kernel: cgroup: Disabling memory control group subsystem Jan 05 08:35:01 volumio-pi400 kernel: CPU: Testing write buffer coherency: ok Jan 05 08:35:01 volumio-pi400 kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Jan 05 08:35:01 volumio-pi400 kernel: Setting up static identity map for 0x200000 - 0x20003c Jan 05 08:35:01 volumio-pi400 kernel: rcu: Hierarchical SRCU implementation. Jan 05 08:35:01 volumio-pi400 kernel: smp: Bringing up secondary CPUs ... Jan 05 08:35:01 volumio-pi400 kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Jan 05 08:35:01 volumio-pi400 kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Jan 05 08:35:01 volumio-pi400 kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Jan 05 08:35:01 volumio-pi400 kernel: smp: Brought up 1 node, 4 CPUs Jan 05 08:35:01 volumio-pi400 kernel: SMP: Total of 4 processors activated (432.00 BogoMIPS). Jan 05 08:35:01 volumio-pi400 kernel: CPU: All CPU(s) started in HYP mode. Jan 05 08:35:01 volumio-pi400 kernel: CPU: Virtualization extensions available. Jan 05 08:35:01 volumio-pi400 kernel: devtmpfs: initialized Jan 05 08:35:01 volumio-pi400 kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0 Jan 05 08:35:01 volumio-pi400 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Jan 05 08:35:01 volumio-pi400 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Jan 05 08:35:01 volumio-pi400 kernel: pinctrl core: initialized pinctrl subsystem Jan 05 08:35:01 volumio-pi400 kernel: NET: Registered protocol family 16 Jan 05 08:35:01 volumio-pi400 kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Jan 05 08:35:01 volumio-pi400 kernel: audit: initializing netlink subsys (disabled) Jan 05 08:35:01 volumio-pi400 kernel: audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1 Jan 05 08:35:01 volumio-pi400 kernel: thermal_sys: Registered thermal governor 'step_wise' Jan 05 08:35:01 volumio-pi400 kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Jan 05 08:35:01 volumio-pi400 kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Jan 05 08:35:01 volumio-pi400 kernel: Serial: AMBA PL011 UART driver Jan 05 08:35:01 volumio-pi400 kernel: bcm2835-mbox fe00b880.mailbox: mailbox enabled Jan 05 08:35:01 volumio-pi400 kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2022-01-20T13:56:48, variant start Jan 05 08:35:01 volumio-pi400 kernel: raspberrypi-firmware soc:firmware: Firmware hash is bd88f66f8952d34e4e0613a85c7a6d3da49e13e2 Jan 05 08:35:01 volumio-pi400 kernel: Kprobes globally optimized Jan 05 08:35:01 volumio-pi400 kernel: bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1 Jan 05 08:35:01 volumio-pi400 kernel: vgaarb: loaded Jan 05 08:35:01 volumio-pi400 kernel: SCSI subsystem initialized Jan 05 08:35:01 volumio-pi400 kernel: usbcore: registered new interface driver usbfs Jan 05 08:35:01 volumio-pi400 kernel: usbcore: registered new interface driver hub Jan 05 08:35:01 volumio-pi400 kernel: usbcore: registered new device driver usb Jan 05 08:35:01 volumio-pi400 kernel: usb_phy_generic phy: supply vcc not found, using dummy regulator Jan 05 08:35:01 volumio-pi400 kernel: clocksource: Switched to clocksource arch_sys_counter Jan 05 08:35:01 volumio-pi400 kernel: VFS: Disk quotas dquot_6.6.0 Jan 05 08:35:01 volumio-pi400 kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Jan 05 08:35:01 volumio-pi400 kernel: FS-Cache: Loaded Jan 05 08:35:01 volumio-pi400 kernel: CacheFiles: Loaded Jan 05 08:35:01 volumio-pi400 kernel: NET: Registered protocol family 2 Jan 05 08:35:01 volumio-pi400 kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) Jan 05 08:35:01 volumio-pi400 kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear) Jan 05 08:35:01 volumio-pi400 kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Jan 05 08:35:01 volumio-pi400 kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear) Jan 05 08:35:01 volumio-pi400 kernel: TCP: Hash tables configured (established 8192 bind 8192) Jan 05 08:35:01 volumio-pi400 kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Jan 05 08:35:01 volumio-pi400 kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Jan 05 08:35:01 volumio-pi400 kernel: NET: Registered protocol family 1 Jan 05 08:35:01 volumio-pi400 kernel: RPC: Registered named UNIX socket transport module. Jan 05 08:35:01 volumio-pi400 kernel: RPC: Registered udp transport module. Jan 05 08:35:01 volumio-pi400 kernel: RPC: Registered tcp transport module. Jan 05 08:35:01 volumio-pi400 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jan 05 08:35:01 volumio-pi400 kernel: PCI: CLS 0 bytes, default 64 Jan 05 08:35:01 volumio-pi400 kernel: Trying to unpack rootfs image as initramfs... Jan 05 08:35:01 volumio-pi400 kernel: Freeing initrd memory: 13028K Jan 05 08:35:01 volumio-pi400 kernel: Initialise system trusted keyrings Jan 05 08:35:01 volumio-pi400 kernel: workingset: timestamp_bits=14 max_order=20 bucket_order=6 Jan 05 08:35:01 volumio-pi400 kernel: zbud: loaded Jan 05 08:35:01 volumio-pi400 kernel: FS-Cache: Netfs 'nfs' registered for caching Jan 05 08:35:01 volumio-pi400 kernel: NFS: Registering the id_resolver key type Jan 05 08:35:01 volumio-pi400 kernel: Key type id_resolver registered Jan 05 08:35:01 volumio-pi400 kernel: Key type id_legacy registered Jan 05 08:35:01 volumio-pi400 kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Jan 05 08:35:01 volumio-pi400 kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Jan 05 08:35:01 volumio-pi400 kernel: Key type asymmetric registered Jan 05 08:35:01 volumio-pi400 kernel: Asymmetric key parser 'x509' registered Jan 05 08:35:01 volumio-pi400 kernel: bounce: pool size: 64 pages Jan 05 08:35:01 volumio-pi400 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Jan 05 08:35:01 volumio-pi400 kernel: io scheduler mq-deadline registered Jan 05 08:35:01 volumio-pi400 kernel: io scheduler kyber registered Jan 05 08:35:01 volumio-pi400 kernel: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: Jan 05 08:35:01 volumio-pi400 kernel: brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff] Jan 05 08:35:01 volumio-pi400 kernel: brcm-pcie fd500000.pcie: MEM 0x0600000000..0x063fffffff -> 0x00c0000000 Jan 05 08:35:01 volumio-pi400 kernel: brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x00ffffffff -> 0x0400000000 Jan 05 08:35:01 volumio-pi400 kernel: brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC) Jan 05 08:35:01 volumio-pi400 kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Jan 05 08:35:01 volumio-pi400 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Jan 05 08:35:01 volumio-pi400 kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff]) Jan 05 08:35:01 volumio-pi400 kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 Jan 05 08:35:01 volumio-pi400 kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Jan 05 08:35:01 volumio-pi400 kernel: PCI: bus0: Fast back to back transfers disabled Jan 05 08:35:01 volumio-pi400 kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Jan 05 08:35:01 volumio-pi400 kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 Jan 05 08:35:01 volumio-pi400 kernel: pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit] Jan 05 08:35:01 volumio-pi400 kernel: pci 0000:01:00.0: PME# supported from D0 D3hot Jan 05 08:35:01 volumio-pi400 kernel: PCI: bus1: Fast back to back transfers disabled Jan 05 08:35:01 volumio-pi400 kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Jan 05 08:35:01 volumio-pi400 kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff] Jan 05 08:35:01 volumio-pi400 kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit] Jan 05 08:35:01 volumio-pi400 kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Jan 05 08:35:01 volumio-pi400 kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] Jan 05 08:35:01 volumio-pi400 kernel: pcieport 0000:00:00.0: enabling device (0140 -> 0142) Jan 05 08:35:01 volumio-pi400 kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 62 Jan 05 08:35:01 volumio-pi400 kernel: bcm2708_fb soc:fb: FB found 1 display(s) Jan 05 08:35:01 volumio-pi400 kernel: Console: switching to colour frame buffer device 80x30 Jan 05 08:35:01 volumio-pi400 kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Jan 05 08:35:01 volumio-pi400 kernel: iproc-rng200 fe104000.rng: hwrng registered Jan 05 08:35:01 volumio-pi400 kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Jan 05 08:35:01 volumio-pi400 kernel: gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000 Jan 05 08:35:01 volumio-pi400 kernel: brd: module loaded Jan 05 08:35:01 volumio-pi400 kernel: loop: module loaded Jan 05 08:35:01 volumio-pi400 kernel: Loading iSCSI transport class v2.0-870. Jan 05 08:35:01 volumio-pi400 kernel: libphy: Fixed MDIO Bus: probed Jan 05 08:35:01 volumio-pi400 kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Jan 05 08:35:01 volumio-pi400 kernel: libphy: bcmgenet MII bus: probed Jan 05 08:35:01 volumio-pi400 kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Jan 05 08:35:01 volumio-pi400 kernel: usbcore: registered new interface driver r8152 Jan 05 08:35:01 volumio-pi400 kernel: usbcore: registered new interface driver lan78xx Jan 05 08:35:01 volumio-pi400 kernel: usbcore: registered new interface driver smsc95xx Jan 05 08:35:01 volumio-pi400 kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Jan 05 08:35:01 volumio-pi400 kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Jan 05 08:35:01 volumio-pi400 kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x00000e0000000890 Jan 05 08:35:01 volumio-pi400 kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10 Jan 05 08:35:01 volumio-pi400 kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 05 08:35:01 volumio-pi400 kernel: usb usb1: Product: xHCI Host Controller Jan 05 08:35:01 volumio-pi400 kernel: usb usb1: Manufacturer: Linux 5.10.92-v7l+ xhci-hcd Jan 05 08:35:01 volumio-pi400 kernel: usb usb1: SerialNumber: 0000:01:00.0 Jan 05 08:35:01 volumio-pi400 kernel: hub 1-0:1.0: USB hub found Jan 05 08:35:01 volumio-pi400 kernel: hub 1-0:1.0: 1 port detected Jan 05 08:35:01 volumio-pi400 kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Jan 05 08:35:01 volumio-pi400 kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Jan 05 08:35:01 volumio-pi400 kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Jan 05 08:35:01 volumio-pi400 kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10 Jan 05 08:35:01 volumio-pi400 kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 05 08:35:01 volumio-pi400 kernel: usb usb2: Product: xHCI Host Controller Jan 05 08:35:01 volumio-pi400 kernel: usb usb2: Manufacturer: Linux 5.10.92-v7l+ xhci-hcd Jan 05 08:35:01 volumio-pi400 kernel: usb usb2: SerialNumber: 0000:01:00.0 Jan 05 08:35:01 volumio-pi400 kernel: hub 2-0:1.0: USB hub found Jan 05 08:35:01 volumio-pi400 kernel: hub 2-0:1.0: 4 ports detected Jan 05 08:35:01 volumio-pi400 kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Jan 05 08:35:01 volumio-pi400 kernel: dwc_otg: FIQ enabled Jan 05 08:35:01 volumio-pi400 kernel: dwc_otg: NAK holdoff enabled Jan 05 08:35:01 volumio-pi400 kernel: dwc_otg: FIQ split-transaction FSM enabled Jan 05 08:35:01 volumio-pi400 kernel: Module dwc_common_port init Jan 05 08:35:01 volumio-pi400 kernel: usbcore: registered new interface driver uas Jan 05 08:35:01 volumio-pi400 kernel: usbcore: registered new interface driver usb-storage Jan 05 08:35:01 volumio-pi400 kernel: mousedev: PS/2 mouse device common for all mice Jan 05 08:35:01 volumio-pi400 kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Jan 05 08:35:01 volumio-pi400 kernel: sdhci: Secure Digital Host Controller Interface driver Jan 05 08:35:01 volumio-pi400 kernel: sdhci: Copyright(c) Pierre Ossman Jan 05 08:35:01 volumio-pi400 kernel: mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe Jan 05 08:35:01 volumio-pi400 kernel: sdhci-pltfm: SDHCI platform and OF driver helper Jan 05 08:35:01 volumio-pi400 kernel: ledtrig-cpu: registered to indicate activity on CPUs Jan 05 08:35:01 volumio-pi400 kernel: hid: raw HID events driver (C) Jiri Kosina Jan 05 08:35:01 volumio-pi400 kernel: usbcore: registered new interface driver usbhid Jan 05 08:35:01 volumio-pi400 kernel: usbhid: USB HID core driver Jan 05 08:35:01 volumio-pi400 kernel: Initializing XFRM netlink socket Jan 05 08:35:01 volumio-pi400 kernel: NET: Registered protocol family 17 Jan 05 08:35:01 volumio-pi400 kernel: Key type dns_resolver registered Jan 05 08:35:01 volumio-pi400 kernel: Registering SWP/SWPB emulation handler Jan 05 08:35:01 volumio-pi400 kernel: registered taskstats version 1 Jan 05 08:35:01 volumio-pi400 kernel: Loading compiled-in X.509 certificates Jan 05 08:35:01 volumio-pi400 kernel: Key type ._fscrypt registered Jan 05 08:35:01 volumio-pi400 kernel: Key type .fscrypt registered Jan 05 08:35:01 volumio-pi400 kernel: Key type fscrypt-provisioning registered Jan 05 08:35:01 volumio-pi400 kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Jan 05 08:35:01 volumio-pi400 kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Jan 05 08:35:01 volumio-pi400 kernel: fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 36, base_baud = 0) is a PL011 rev2 Jan 05 08:35:01 volumio-pi400 kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Jan 05 08:35:01 volumio-pi400 kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Jan 05 08:35:01 volumio-pi400 kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Jan 05 08:35:01 volumio-pi400 kernel: of_cfs_init Jan 05 08:35:01 volumio-pi400 kernel: of_cfs_init: OK Jan 05 08:35:01 volumio-pi400 kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes) Jan 05 08:35:01 volumio-pi400 kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Jan 05 08:35:01 volumio-pi400 kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Jan 05 08:35:01 volumio-pi400 kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes) Jan 05 08:35:01 volumio-pi400 kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Jan 05 08:35:01 volumio-pi400 kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Jan 05 08:35:01 volumio-pi400 kernel: Freeing unused kernel memory: 2048K Jan 05 08:35:01 volumio-pi400 kernel: Run /init as init process Jan 05 08:35:01 volumio-pi400 kernel: with arguments: Jan 05 08:35:01 volumio-pi400 kernel: /init Jan 05 08:35:01 volumio-pi400 kernel: splash Jan 05 08:35:01 volumio-pi400 kernel: with environment: Jan 05 08:35:01 volumio-pi400 kernel: HOME=/ Jan 05 08:35:01 volumio-pi400 kernel: TERM=linux Jan 05 08:35:01 volumio-pi400 kernel: imgpart=/dev/mmcblk0p2 Jan 05 08:35:01 volumio-pi400 kernel: imgfile=/volumio_current.sqsh Jan 05 08:35:01 volumio-pi400 kernel: bootdelay=5 Jan 05 08:35:01 volumio-pi400 kernel: mmc1: new high speed SDIO card at address 0001 Jan 05 08:35:01 volumio-pi400 kernel: mmc0: new ultra high speed DDR50 SDHC card at address aaaa Jan 05 08:35:01 volumio-pi400 kernel: mmcblk0: mmc0:aaaa SL16G 14.8 GiB Jan 05 08:35:01 volumio-pi400 kernel: mmcblk0: p1 p2 p3 Jan 05 08:35:01 volumio-pi400 kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Jan 05 08:35:01 volumio-pi400 initramfs: Booting Volumio for BCM2711 Jan 05 08:35:01 volumio-pi400 initramfs: This script mounts rootfs RO with an overlay RW layer. Jan 05 08:35:01 volumio-pi400 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jan 05 08:35:01 volumio-pi400 initramfs: IMGPART=/dev/mmcblk0p2 Jan 05 08:35:01 volumio-pi400 initramfs: IMGFILE=/volumio_current.sqsh Jan 05 08:35:01 volumio-pi400 initramfs: Boot delay (except first time) will be 5 seconds Jan 05 08:35:01 volumio-pi400 initramfs: /dev/mmcblk0p2: Jan 05 08:35:01 volumio-pi400 kernel: EXT4-fs (mmcblk0p2): recovery complete Jan 05 08:35:01 volumio-pi400 kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Jan 05 08:35:01 volumio-pi400 kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Jan 05 08:35:01 volumio-pi400 kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Jan 05 08:35:01 volumio-pi400 kernel: usb 1-1: Product: USB2.0 Hub Jan 05 08:35:01 volumio-pi400 initramfs: Doing a 5 second delay here to give kernel load a headstart Jan 05 08:35:01 volumio-pi400 kernel: hub 1-1:1.0: USB hub found Jan 05 08:35:01 volumio-pi400 kernel: hub 1-1:1.0: 4 ports detected Jan 05 08:35:01 volumio-pi400 kernel: usb 1-1.2: new high-speed USB device number 3 using xhci_hcd Jan 05 08:35:01 volumio-pi400 kernel: usb 1-1.2: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.02 Jan 05 08:35:01 volumio-pi400 kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Jan 05 08:35:01 volumio-pi400 kernel: usb 1-1.2: Product: D10 Jan 05 08:35:01 volumio-pi400 kernel: usb 1-1.2: Manufacturer: Topping Jan 05 08:35:01 volumio-pi400 kernel: usb 1-1.4: new low-speed USB device number 4 using xhci_hcd Jan 05 08:35:01 volumio-pi400 kernel: usb 1-1.4: New USB device found, idVendor=04d9, idProduct=0007, bcdDevice= 1.61 Jan 05 08:35:01 volumio-pi400 kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Jan 05 08:35:01 volumio-pi400 kernel: usb 1-1.4: Product: Raspberry Pi Internal Keyboard Jan 05 08:35:01 volumio-pi400 kernel: usb 1-1.4: Manufacturer: Jan 05 08:35:01 volumio-pi400 kernel: input: Raspberry Pi Internal Keyboard as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.0/0003:04D9:0007.0001/input/input0 Jan 05 08:35:01 volumio-pi400 kernel: hid-generic 0003:04D9:0007.0001: input,hidraw0: USB HID v1.11 Keyboard [ Raspberry Pi Internal Keyboard] on usb-0000:01:00.0-1.4/input0 Jan 05 08:35:01 volumio-pi400 kernel: input: Raspberry Pi Internal Keyboard as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.1/0003:04D9:0007.0002/input/input1 Jan 05 08:35:01 volumio-pi400 kernel: hid-generic 0003:04D9:0007.0002: input,hidraw1: USB HID v1.11 Device [ Raspberry Pi Internal Keyboard] on usb-0000:01:00.0-1.4/input1 Jan 05 08:35:01 volumio-pi400 initramfs: Checking for USB updates Jan 05 08:35:01 volumio-pi400 initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Jan 05 08:35:01 volumio-pi400 kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jan 05 08:35:01 volumio-pi400 kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jan 05 08:35:01 volumio-pi400 kernel: EXT4-fs (mmcblk0p3): recovery complete Jan 05 08:35:01 volumio-pi400 kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Jan 05 08:35:01 volumio-pi400 initramfs: With Option: Jan 05 08:35:01 volumio-pi400 initramfs: VOLUMIO_VERSION="3.378" Jan 05 08:35:01 volumio-pi400 initramfs: Finish initramfs, continue booting Volumio Jan 05 08:35:01 volumio-pi400 systemd[1]: System time before build time, advancing clock. Jan 05 08:35:01 volumio-pi400 kernel: NET: Registered protocol family 10 Jan 05 08:35:01 volumio-pi400 kernel: Segment Routing with IPv6 Jan 05 08:35:01 volumio-pi400 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) Jan 05 08:35:01 volumio-pi400 systemd[1]: Detected architecture arm. Jan 05 08:35:01 volumio-pi400 systemd[1]: Set hostname to . Jan 05 08:35:01 volumio-pi400 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. Jan 05 08:35:01 volumio-pi400 kernel: random: systemd: uninitialized urandom read (16 bytes read) Jan 05 08:35:01 volumio-pi400 kernel: random: systemd: uninitialized urandom read (16 bytes read) Jan 05 08:35:01 volumio-pi400 kernel: random: systemd: uninitialized urandom read (16 bytes read) Jan 05 08:35:01 volumio-pi400 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Jan 05 08:35:01 volumio-pi400 systemd[1]: Created slice system-systemd\x2dfsck.slice. Jan 05 08:35:01 volumio-pi400 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Jan 05 08:35:01 volumio-pi400 systemd[1]: Listening on Journal Socket. Jan 05 08:35:01 volumio-pi400 systemd[1]: Mounting Kernel Debug File System... Jan 05 08:35:01 volumio-pi400 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jan 05 08:35:01 volumio-pi400 kernel: i2c /dev entries driver Jan 05 08:35:01 volumio-pi400 systemd-journald[207]: Journal started Jan 05 08:35:01 volumio-pi400 systemd-journald[207]: Runtime journal (/run/log/journal/f19fe563bf7dcaf9ed2201976245a35f) is 3.7M, max 30.0M, 26.2M free. Jan 05 08:35:01 volumio-pi400 systemd-modules-load[197]: Inserted module 'i2c_dev' Jan 05 08:35:01 volumio-pi400 fake-hwclock[194]: Sun Jan 5 08:35:01 UTC 2025 Jan 05 08:35:01 volumio-pi400 systemd[1]: Started Create Static Device Nodes in /dev. Jan 05 08:35:01 volumio-pi400 systemd[1]: Starting udev Kernel Device Manager... Jan 05 08:35:01 volumio-pi400 systemd[1]: Reached target Local File Systems (Pre). Jan 05 08:35:01 volumio-pi400 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jan 05 08:35:01 volumio-pi400 systemd[1]: Mounting /tmp... Jan 05 08:35:01 volumio-pi400 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jan 05 08:35:01 volumio-pi400 systemd[1]: Mounting /var/log... Jan 05 08:35:01 volumio-pi400 systemd[1]: Mounting /var/spool/cups... Jan 05 08:35:01 volumio-pi400 systemd[1]: Mounted /tmp. Jan 05 08:35:01 volumio-pi400 systemd[1]: Mounted /var/log. Jan 05 08:35:01 volumio-pi400 systemd[1]: Mounted /var/spool/cups. Jan 05 08:35:01 volumio-pi400 systemd-udevd[221]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jan 05 08:35:01 volumio-pi400 systemd[1]: Mounting /var/spool/cups/tmp... Jan 05 08:35:01 volumio-pi400 systemd[1]: Starting Flush Journal to Persistent Storage... Jan 05 08:35:01 volumio-pi400 systemd[1]: Mounted /var/spool/cups/tmp. Jan 05 08:35:01 volumio-pi400 systemd[1]: Started udev Kernel Device Manager. Jan 05 08:35:01 volumio-pi400 systemd-journald[207]: Runtime journal (/run/log/journal/f19fe563bf7dcaf9ed2201976245a35f) is 3.7M, max 30.0M, 26.2M free. Jan 05 08:35:01 volumio-pi400 systemd[1]: Started Flush Journal to Persistent Storage. Jan 05 08:35:01 volumio-pi400 systemd[1]: Started udev Coldplug all Devices. Jan 05 08:35:01 volumio-pi400 systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jan 05 08:35:01 volumio-pi400 systemd[1]: Starting Show Plymouth Boot Screen... Jan 05 08:35:01 volumio-pi400 systemd[1]: Received SIGRTMIN+20 from PID 260 (plymouthd). Jan 05 08:35:01 volumio-pi400 systemd[1]: Started Show Plymouth Boot Screen. Jan 05 08:35:01 volumio-pi400 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 05 08:35:01 volumio-pi400 systemd[1]: Reached target Paths. Jan 05 08:35:01 volumio-pi400 systemd[1]: Reached target Local Encrypted Volumes. Jan 05 08:35:01 volumio-pi400 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jan 05 08:35:01 volumio-pi400 kernel: rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000 Jan 05 08:35:01 volumio-pi400 kernel: rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000 Jan 05 08:35:01 volumio-pi400 kernel: rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000 Jan 05 08:35:01 volumio-pi400 kernel: rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000 Jan 05 08:35:01 volumio-pi400 systemd-udevd[241]: Using default interface naming scheme 'v240'. Jan 05 08:35:02 volumio-pi400 kernel: mc: Linux media interface: v0.10 Jan 05 08:35:02 volumio-pi400 kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jan 05 08:35:02 volumio-pi400 kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jan 05 08:35:02 volumio-pi400 kernel: [vc_sm_connected_init]: start Jan 05 08:35:02 volumio-pi400 kernel: [vc_sm_connected_init]: installed successfully Jan 05 08:35:02 volumio-pi400 systemd-udevd[244]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 05 08:35:02 volumio-pi400 systemd-udevd[241]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 05 08:35:02 volumio-pi400 kernel: videodev: Linux video capture interface: v2.00 Jan 05 08:35:02 volumio-pi400 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 05 08:35:02 volumio-pi400 kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Jan 05 08:35:02 volumio-pi400 kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Jan 05 08:35:02 volumio-pi400 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Jan 05 08:35:02 volumio-pi400 kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jan 05 08:35:03 volumio-pi400 kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jan 05 08:35:03 volumio-pi400 kernel: bcm2835_audio bcm2835_audio: there is not valid maps for state default Jan 05 08:35:03 volumio-pi400 kernel: bcm2835_audio bcm2835_audio: card created with 8 channels Jan 05 08:35:03 volumio-pi400 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jan 05 08:35:03 volumio-pi400 kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jan 05 08:35:03 volumio-pi400 systemd-udevd[256]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 05 08:35:03 volumio-pi400 systemd-udevd[244]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 05 08:35:03 volumio-pi400 kernel: snd_usb_audio: unknown parameter 'nrpacks' ignored Jan 05 08:35:04 volumio-pi400 kernel: brcmfmac: F1 signature read @0x18000000=0x15294345 Jan 05 08:35:04 volumio-pi400 kernel: usb 1-1.2: 1:3 : unsupported format bits 0x100000000 Jan 05 08:35:04 volumio-pi400 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43456-sdio for chip BCM4345/9 Jan 05 08:35:04 volumio-pi400 kernel: usbcore: registered new interface driver brcmfmac Jan 05 08:35:04 volumio-pi400 kernel: usbcore: registered new interface driver snd-usb-audio Jan 05 08:35:04 volumio-pi400 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43456-sdio for chip BCM4345/9 Jan 05 08:35:04 volumio-pi400 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43456-sdio for chip BCM4345/9 Jan 05 08:35:04 volumio-pi400 kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/9 wl0: May 14 2020 17:26:08 version 7.84.17.1 (r871554) FWID 01-3d9e1d87 Jan 05 08:35:04 volumio-pi400 systemd-udevd[233]: Using default interface naming scheme 'v240'. Jan 05 08:35:04 volumio-pi400 systemd-udevd[233]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 05 08:35:04 volumio-pi400 systemd[1]: Found device /dev/mmcblk0p1. Jan 05 08:35:05 volumio-pi400 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jan 05 08:35:05 volumio-pi400 systemd[1]: Condition check resulted in FUSE Control File System being skipped. Jan 05 08:35:05 volumio-pi400 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jan 05 08:35:05 volumio-pi400 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 05 08:35:05 volumio-pi400 systemd[1]: Condition check resulted in Huge Pages File System being skipped. Jan 05 08:35:05 volumio-pi400 systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jan 05 08:35:05 volumio-pi400 systemd[1]: Starting File System Check on /dev/mmcblk0p1... Jan 05 08:35:05 volumio-pi400 systemd[1]: Starting Load/Save RF Kill Switch Status... Jan 05 08:35:05 volumio-pi400 systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Jan 05 08:35:05 volumio-pi400 systemd[1]: Started Load/Save RF Kill Switch Status. Jan 05 08:35:05 volumio-pi400 systemd-fsck[381]: fsck.fat 4.1 (2017-01-24) Jan 05 08:35:05 volumio-pi400 systemd-fsck[381]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Jan 05 08:35:05 volumio-pi400 systemd-fsck[381]: Automatically removing dirty bit. Jan 05 08:35:05 volumio-pi400 systemd-fsck[381]: Performing changes. Jan 05 08:35:05 volumio-pi400 systemd-fsck[381]: /dev/mmcblk0p1: 297 files, 27486/46774 clusters Jan 05 08:35:05 volumio-pi400 systemd[1]: Found device /sys/subsystem/net/devices/eth0. Jan 05 08:35:05 volumio-pi400 systemd[1]: Started File System Check on /dev/mmcblk0p1. Jan 05 08:35:05 volumio-pi400 systemd[1]: Mounting /boot... Jan 05 08:35:05 volumio-pi400 systemd[1]: Started File System Check Daemon to report status. Jan 05 08:35:05 volumio-pi400 systemd-udevd[238]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Jan 05 08:35:05 volumio-pi400 kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jan 05 08:35:05 volumio-pi400 systemd[1]: Mounted /boot. Jan 05 08:35:05 volumio-pi400 systemd[1]: Reached target Local File Systems. Jan 05 08:35:05 volumio-pi400 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jan 05 08:35:05 volumio-pi400 systemd[1]: Started ifup for eth0. Jan 05 08:35:05 volumio-pi400 systemd[1]: Starting Preprocess NFS configuration... Jan 05 08:35:05 volumio-pi400 systemd[1]: Started ifup for wlan0. Jan 05 08:35:05 volumio-pi400 systemd[1]: Starting Create Volatile Files and Directories... Jan 05 08:35:05 volumio-pi400 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jan 05 08:35:05 volumio-pi400 systemd[1]: Started Helper to synchronize boot up for ifupdown. Jan 05 08:35:05 volumio-pi400 systemd[1]: Starting Raise network interfaces... Jan 05 08:35:05 volumio-pi400 systemd[1]: nfs-config.service: Succeeded. Jan 05 08:35:06 volumio-pi400 systemd[1]: Started Preprocess NFS configuration. Jan 05 08:35:06 volumio-pi400 systemd[1]: plymouth-read-write.service: Succeeded. Jan 05 08:35:06 volumio-pi400 systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jan 05 08:35:06 volumio-pi400 systemd[1]: Received SIGRTMIN+20 from PID 260 (plymouthd). Jan 05 08:35:06 volumio-pi400 systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jan 05 08:35:06 volumio-pi400 systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jan 05 08:35:06 volumio-pi400 systemd[1]: Reached target NFS client services. Jan 05 08:35:06 volumio-pi400 systemd[1]: Started Create Volatile Files and Directories. Jan 05 08:35:06 volumio-pi400 systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jan 05 08:35:06 volumio-pi400 systemd[1]: Starting RPC bind portmap service... Jan 05 08:35:06 volumio-pi400 systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jan 05 08:35:06 volumio-pi400 systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jan 05 08:35:06 volumio-pi400 kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Jan 05 08:35:06 volumio-pi400 kernel: bcmgenet fd580000.ethernet eth0: Link is Down Jan 05 08:35:06 volumio-pi400 systemd[1]: Started Update UTMP about System Boot/Shutdown. Jan 05 08:35:06 volumio-pi400 systemd[1]: Reached target System Initialization. Jan 05 08:35:06 volumio-pi400 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 05 08:35:06 volumio-pi400 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jan 05 08:35:06 volumio-pi400 systemd[1]: apt-daily.timer: Not using persistent file timestamp Wed 2025-01-29 22:26:45 UTC as it is in the future. Jan 05 08:35:06 volumio-pi400 systemd[1]: Started Daily apt download activities. Jan 05 08:35:06 volumio-pi400 systemd[1]: Listening on D-Bus System Message Bus Socket. Jan 05 08:35:06 volumio-pi400 systemd[1]: Listening on triggerhappy.socket. Jan 05 08:35:06 volumio-pi400 systemd[1]: Listening on mpd.socket. Jan 05 08:35:06 volumio-pi400 systemd[1]: Reached target Sockets. Jan 05 08:35:06 volumio-pi400 systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Thu 2025-01-30 06:14:45 UTC as it is in the future. Jan 05 08:35:06 volumio-pi400 systemd[1]: Started Daily apt upgrade and clean activities. Jan 05 08:35:06 volumio-pi400 systemd[1]: Reached target Basic System. Jan 05 08:35:06 volumio-pi400 systemd[1]: Started Volumio Iptables Module. Jan 05 08:35:06 volumio-pi400 systemd[1]: Started UPnP Renderer front-end to MPD. Jan 05 08:35:06 volumio-pi400 systemd[1]: Starting Wireless Services... Jan 05 08:35:06 volumio-pi400 systemd[1]: Starting Configure Bluetooth Modems connected by UART... Jan 05 08:35:06 volumio-pi400 systemd[1]: Started D-Bus System Message Bus. Jan 05 08:35:06 volumio-pi400 systemd[1]: Starting WPA supplicant... Jan 05 08:35:06 volumio-pi400 systemd[1]: Starting triggerhappy global hotkey daemon... Jan 05 08:35:06 volumio-pi400 systemd[1]: Started Manage Sound Card State (restore and store). Jan 05 08:35:06 volumio-pi400 alsactl[506]: alsactl 1.1.8 daemon started Jan 05 08:35:06 volumio-pi400 systemd[1]: Starting Login Service... Jan 05 08:35:06 volumio-pi400 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jan 05 08:35:06 volumio-pi400 systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jan 05 08:35:06 volumio-pi400 systemd[1]: Starting Save/Restore Sound Card State... Jan 05 08:35:06 volumio-pi400 thd[505]: Unable to parse trigger line: Jan 05 08:35:06 volumio-pi400 thd[505]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jan 05 08:35:06 volumio-pi400 thd[505]: Unable to parse trigger line: Jan 05 08:35:06 volumio-pi400 thd[505]: Found socket passed from systemd Jan 05 08:35:06 volumio-pi400 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jan 05 08:35:06 volumio-pi400 systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jan 05 08:35:06 volumio-pi400 systemd[1]: Starting dhcpcd on all interfaces... Jan 05 08:35:06 volumio-pi400 systemd[1]: Started volumio-remote-updater.service. Jan 05 08:35:06 volumio-pi400 systemd[1]: Started Daily Cleanup of Temporary Directories. Jan 05 08:35:06 volumio-pi400 systemd[1]: Reached target Timers. Jan 05 08:35:06 volumio-pi400 systemd[1]: Started RPC bind portmap service. Jan 05 08:35:06 volumio-pi400 systemd[1]: Started triggerhappy global hotkey daemon. Jan 05 08:35:06 volumio-pi400 systemd[1]: Started Save/Restore Sound Card State. Jan 05 08:35:06 volumio-pi400 ifup[400]: ifup: waiting for lock on /run/network/ifstate.wlan0 Jan 05 08:35:06 volumio-pi400 sh[387]: eth0=eth0 Jan 05 08:35:06 volumio-pi400 sh[390]: wlan0=wlan0 Jan 05 08:35:06 volumio-pi400 systemd[1]: Reached target Sound Card. Jan 05 08:35:06 volumio-pi400 systemd[1]: Reached target RPC Port Mapper. Jan 05 08:35:06 volumio-pi400 systemd[1]: Reached target Remote File Systems (Pre). Jan 05 08:35:06 volumio-pi400 systemd[1]: Reached target Remote File Systems. Jan 05 08:35:06 volumio-pi400 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jan 05 08:35:07 volumio-pi400 kernel: uart-pl011 fe201000.serial: no DMA platform data Jan 05 08:35:07 volumio-pi400 systemd-logind[508]: Watching system buttons on /dev/input/event0 ( Raspberry Pi Internal Keyboard) Jan 05 08:35:07 volumio-pi400 systemd-logind[508]: New seat seat0. Jan 05 08:35:07 volumio-pi400 systemd[1]: Started Login Service. Jan 05 08:35:07 volumio-pi400 avahi-daemon[513]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jan 05 08:35:07 volumio-pi400 avahi-daemon[513]: Successfully dropped root privileges. Jan 05 08:35:07 volumio-pi400 avahi-daemon[513]: avahi-daemon 0.7 starting up. Jan 05 08:35:07 volumio-pi400 avahi-daemon[513]: Successfully called chroot(). Jan 05 08:35:07 volumio-pi400 avahi-daemon[513]: Successfully dropped remaining capabilities. Jan 05 08:35:07 volumio-pi400 systemd[1]: Started Raise network interfaces. Jan 05 08:35:07 volumio-pi400 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jan 05 08:35:07 volumio-pi400 avahi-daemon[513]: Loading service file /services/volumio.service. Jan 05 08:35:07 volumio-pi400 avahi-daemon[513]: Network interface enumeration completed. Jan 05 08:35:07 volumio-pi400 avahi-daemon[513]: Server startup complete. Host name is volumio-pi400.local. Local service cookie is 1034671576. Jan 05 08:35:07 volumio-pi400 avahi-daemon[513]: Service "Volumio-Pi400" (/services/volumio.service) successfully established. Jan 05 08:35:07 volumio-pi400 dhcpcd[516]: dev: loaded udev Jan 05 08:35:07 volumio-pi400 kernel: 8021q: 802.1Q VLAN Support v1.8 Jan 05 08:35:07 volumio-pi400 dhcpcd[516]: forked to background, child pid 574 Jan 05 08:35:07 volumio-pi400 systemd[1]: Started dhcpcd on all interfaces. Jan 05 08:35:08 volumio-pi400 loadcpufreq[535]: Loading cpufreq kernel modules...done (none). Jan 05 08:35:08 volumio-pi400 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jan 05 08:35:08 volumio-pi400 systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jan 05 08:35:08 volumio-pi400 systemd[1]: iptables.service: Succeeded. Jan 05 08:35:08 volumio-pi400 cpufrequtils[601]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jan 05 08:35:08 volumio-pi400 systemd[1]: Started LSB: set CPUFreq kernel parameters. Jan 05 08:35:08 volumio-pi400 dhcpcd-run-hooks[632]: wlan0: starting wpa_supplicant Jan 05 08:35:08 volumio-pi400 volumio-remote-updater[517]: Error: No active session Jan 05 08:35:08 volumio-pi400 volumio-remote-updater[517]: [2025-01-05 08:35:08] [info] asio async_connect error: system:111 (Connection refused) Jan 05 08:35:08 volumio-pi400 volumio-remote-updater[517]: [2025-01-05 08:35:08] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 05 08:35:08 volumio-pi400 volumio-remote-updater[517]: [2025-01-05 08:35:08] [error] handle_connect error: Underlying Transport Error Jan 05 08:35:08 volumio-pi400 systemd[1]: Started WPA supplicant. Jan 05 08:35:08 volumio-pi400 systemd[1]: Reached target Network. Jan 05 08:35:08 volumio-pi400 wpa_supplicant[503]: Successfully initialized wpa_supplicant Jan 05 08:35:08 volumio-pi400 systemd[1]: Starting Network Time Service... Jan 05 08:35:08 volumio-pi400 systemd[1]: Reached target Network is Online. Jan 05 08:35:08 volumio-pi400 systemd[1]: Starting LSB: Brings up/down network automatically... Jan 05 08:35:08 volumio-pi400 systemd[1]: Starting /etc/rc.local Compatibility... Jan 05 08:35:08 volumio-pi400 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 05 08:35:08 volumio-pi400 volumio[484]: Could not open config: /tmp/upmpdcli.conf Jan 05 08:35:08 volumio-pi400 systemd[1]: Starting OpenBSD Secure Shell server... Jan 05 08:35:08 volumio-pi400 systemd[1]: Starting Music Player Daemon... Jan 05 08:35:08 volumio-pi400 systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jan 05 08:35:08 volumio-pi400 systemd[1]: Starting Permit User Sessions... Jan 05 08:35:08 volumio-pi400 systemd[1]: Starting Samba NMB Daemon... Jan 05 08:35:08 volumio-pi400 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 05 08:35:08 volumio-pi400 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 05 08:35:08 volumio-pi400 systemd[1]: Started /etc/rc.local Compatibility. Jan 05 08:35:08 volumio-pi400 systemd[1]: Started Permit User Sessions. Jan 05 08:35:08 volumio-pi400 systemd[1]: Starting Terminate Plymouth Boot Screen... Jan 05 08:35:08 volumio-pi400 systemd[1]: Starting Hold until boot process finishes up... Jan 05 08:35:08 volumio-pi400 systemd[1]: Received SIGRTMIN+21 from PID 260 (plymouthd). Jan 05 08:35:08 volumio-pi400 systemd[1]: plymouth-start.service: Succeeded. Jan 05 08:35:08 volumio-pi400 systemd[1]: plymouth-quit.service: Succeeded. Jan 05 08:35:08 volumio-pi400 systemd[1]: Started Terminate Plymouth Boot Screen. Jan 05 08:35:08 volumio-pi400 systemd[1]: plymouth-quit-wait.service: Succeeded. Jan 05 08:35:08 volumio-pi400 systemd[1]: Started Hold until boot process finishes up. Jan 05 08:35:08 volumio-pi400 systemd[1]: Received SIGRTMIN+21 from PID 260 (n/a). Jan 05 08:35:08 volumio-pi400 systemd[1]: Started Getty on tty1. Jan 05 08:35:08 volumio-pi400 ifplugd(eth0)[668]: ifplugd 0.28 initializing. Jan 05 08:35:08 volumio-pi400 ifplugd(eth0)[668]: Using interface eth0/DC:A6:32:DE:27:50 with driver (version: 5.10.92-v7l+) Jan 05 08:35:08 volumio-pi400 ifplugd(eth0)[668]: Using detection mode: SIOCETHTOOL Jan 05 08:35:08 volumio-pi400 systemd[1]: Reached target Login Prompts. Jan 05 08:35:08 volumio-pi400 ifplugd(eth0)[668]: Initialization complete, link beat not detected. Jan 05 08:35:08 volumio-pi400 ntpd[636]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 05 08:35:08 volumio-pi400 ntpd[636]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jan 05 08:35:08 volumio-pi400 systemd[1]: Started Network Time Service. Jan 05 08:35:08 volumio-pi400 ntpd[670]: proto: precision = 0.667 usec (-20) Jan 05 08:35:08 volumio-pi400 ntpd[670]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 05 08:35:08 volumio-pi400 ntpd[670]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 05 08:35:08 volumio-pi400 ntpd[670]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 740 days ago Jan 05 08:35:08 volumio-pi400 ntpd[670]: Listen and drop on 0 v6wildcard [::]:123 Jan 05 08:35:08 volumio-pi400 ntpd[670]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 05 08:35:08 volumio-pi400 ntpd[670]: Listen normally on 2 lo 127.0.0.1:123 Jan 05 08:35:08 volumio-pi400 ntpd[670]: Listening on routing socket on fd #19 for interface updates Jan 05 08:35:08 volumio-pi400 ntpd[670]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 05 08:35:08 volumio-pi400 ntpd[670]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 05 08:35:08 volumio-pi400 ifplugd[637]: Network Interface Plugging Daemon...start eth0...done. Jan 05 08:35:08 volumio-pi400 systemd[1]: Started LSB: Brings up/down network automatically. Jan 05 08:35:09 volumio-pi400 haveged[451]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jan 05 08:35:09 volumio-pi400 haveged[451]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jan 05 08:35:09 volumio-pi400 haveged[451]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00317 Jan 05 08:35:09 volumio-pi400 haveged[451]: haveged: fills: 0, generated: 0 Jan 05 08:35:09 volumio-pi400 kernel: random: crng init done Jan 05 08:35:09 volumio-pi400 kernel: random: 7 urandom warning(s) missed due to ratelimiting Jan 05 08:35:09 volumio-pi400 dhcpcd[574]: wlan0: connected to Access Point `' Jan 05 08:35:09 volumio-pi400 sshd[674]: Server listening on 0.0.0.0 port 22. Jan 05 08:35:09 volumio-pi400 sshd[674]: Server listening on :: port 22. Jan 05 08:35:09 volumio-pi400 systemd[1]: Started OpenBSD Secure Shell server. Jan 05 08:35:09 volumio-pi400 dhcpcd[574]: eth0: waiting for carrier Jan 05 08:35:09 volumio-pi400 dhcpcd[574]: wlan0: waiting for carrier Jan 05 08:35:09 volumio-pi400 ntpd[670]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 05 08:35:09 volumio-pi400 nmbd[651]: [2025/01/05 08:35:09.910962, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jan 05 08:35:09 volumio-pi400 nmbd[651]: started asyncdns process 690 Jan 05 08:35:09 volumio-pi400 nmbd[651]: [2025/01/05 08:35:09.918045, 0] ../lib/util/become_daemon.c:149(daemon_status) Jan 05 08:35:09 volumio-pi400 nmbd[651]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jan 05 08:35:09 volumio-pi400 nmbd[651]: [2025/01/05 08:35:09.918148, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jan 05 08:35:09 volumio-pi400 nmbd[651]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jan 05 08:35:10 volumio-pi400 wireless.js[487]: Cleaning previous... Jan 05 08:35:10 volumio-pi400 sudo[699]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 05 08:35:10 volumio-pi400 sudo[699]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:10 volumio-pi400 sudo[699]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:10 volumio-pi400 sudo[701]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 05 08:35:10 volumio-pi400 systemd[1]: systemd-rfkill.service: Succeeded. Jan 05 08:35:10 volumio-pi400 sudo[701]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:10 volumio-pi400 ntpd[670]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 05 08:35:11 volumio-pi400 mpd[669]: Jan 05 08:35 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 05 08:35:11 volumio-pi400 sudo[701]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:11 volumio-pi400 wireless.js[487]: Stopped aP Jan 05 08:35:11 volumio-pi400 sudo[708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 05 08:35:11 volumio-pi400 sudo[708]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:11 volumio-pi400 sudo[708]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:11 volumio-pi400 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 05 08:35:11 volumio-pi400 sudo[710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jan 05 08:35:11 volumio-pi400 sudo[710]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:11 volumio-pi400 sudo[710]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:11 volumio-pi400 sudo[717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 05 08:35:11 volumio-pi400 sudo[717]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:11 volumio-pi400 sudo[717]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:11 volumio-pi400 sudo[719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jan 05 08:35:11 volumio-pi400 sudo[719]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:11 volumio-pi400 mpd[669]: Jan 05 08:35 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 05 08:35:11 volumio-pi400 mpd[669]: Jan 05 08:35 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 05 08:35:11 volumio-pi400 systemd[1]: Started Music Player Daemon. Jan 05 08:35:11 volumio-pi400 ntpd[670]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 05 08:35:12 volumio-pi400 ntpd[670]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 05 08:35:13 volumio-pi400 kernel: Bluetooth: Core ver 2.22 Jan 05 08:35:13 volumio-pi400 kernel: NET: Registered protocol family 31 Jan 05 08:35:13 volumio-pi400 kernel: Bluetooth: HCI device and connection manager initialized Jan 05 08:35:13 volumio-pi400 kernel: Bluetooth: HCI socket layer initialized Jan 05 08:35:13 volumio-pi400 kernel: Bluetooth: L2CAP socket layer initialized Jan 05 08:35:13 volumio-pi400 kernel: Bluetooth: SCO socket layer initialized Jan 05 08:35:13 volumio-pi400 btuart[495]: bcm43xx_init Jan 05 08:35:13 volumio-pi400 btuart[495]: Flash firmware /lib/firmware/brcm/BCM4345C5.hcd Jan 05 08:35:13 volumio-pi400 btuart[495]: Set Controller UART speed to 3000000 bit/s Jan 05 08:35:13 volumio-pi400 btuart[495]: Device setup complete Jan 05 08:35:13 volumio-pi400 systemd[1]: Starting Load/Save RF Kill Switch Status... Jan 05 08:35:13 volumio-pi400 systemd[1]: Started Configure Bluetooth Modems connected by UART. Jan 05 08:35:13 volumio-pi400 kernel: Bluetooth: HCI UART driver ver 2.3 Jan 05 08:35:13 volumio-pi400 kernel: Bluetooth: HCI UART protocol H4 registered Jan 05 08:35:13 volumio-pi400 kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jan 05 08:35:13 volumio-pi400 kernel: Bluetooth: HCI UART protocol Broadcom registered Jan 05 08:35:13 volumio-pi400 systemd[1]: Started Load/Save RF Kill Switch Status. Jan 05 08:35:13 volumio-pi400 systemd[1]: Created slice system-bthelper.slice. Jan 05 08:35:13 volumio-pi400 systemd[1]: Starting Raspberry Pi bluetooth helper... Jan 05 08:35:13 volumio-pi400 bthelper[734]: Raspberry Pi BDADDR already set Jan 05 08:35:13 volumio-pi400 systemd[1]: Started Raspberry Pi bluetooth helper. Jan 05 08:35:13 volumio-pi400 systemd[1]: Starting Bluetooth service... Jan 05 08:35:13 volumio-pi400 bluetoothd[744]: Bluetooth daemon 5.50 Jan 05 08:35:13 volumio-pi400 bluetoothd[744]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jan 05 08:35:13 volumio-pi400 systemd[1]: Started Bluetooth service. Jan 05 08:35:13 volumio-pi400 systemd[1]: Reached target Bluetooth. Jan 05 08:35:13 volumio-pi400 bluetoothd[744]: Starting SDP server Jan 05 08:35:13 volumio-pi400 bluetoothd[744]: Excluding (cli) sap Jan 05 08:35:13 volumio-pi400 bluetoothd[744]: Bluetooth management interface 1.18 initialized Jan 05 08:35:13 volumio-pi400 dbus-daemon[497]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=744 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jan 05 08:35:13 volumio-pi400 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jan 05 08:35:13 volumio-pi400 kernel: Bluetooth: BNEP filters: protocol multicast Jan 05 08:35:13 volumio-pi400 kernel: Bluetooth: BNEP socket layer initialized Jan 05 08:35:13 volumio-pi400 systemd[1]: Starting Hostname Service... Jan 05 08:35:13 volumio-pi400 volumio-remote-updater[517]: [2025-01-05 08:35:13] [info] asio async_connect error: system:111 (Connection refused) Jan 05 08:35:13 volumio-pi400 volumio-remote-updater[517]: [2025-01-05 08:35:13] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 05 08:35:13 volumio-pi400 volumio-remote-updater[517]: [2025-01-05 08:35:13] [error] handle_connect error: Underlying Transport Error Jan 05 08:35:13 volumio-pi400 dbus-daemon[497]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 05 08:35:13 volumio-pi400 systemd[1]: Started Hostname Service. Jan 05 08:35:13 volumio-pi400 bluetoothd[744]: Failed to set privacy: Rejected (0x0b) Jan 05 08:35:14 volumio-pi400 sudo[719]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:14 volumio-pi400 wireless.js[487]: Start wireless flow Jan 05 08:35:14 volumio-pi400 wireless.js[487]: Stopped hotspot (if there).. Jan 05 08:35:14 volumio-pi400 sudo[748]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 05 08:35:14 volumio-pi400 sudo[748]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:14 volumio-pi400 sudo[748]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:14 volumio-pi400 sudo[750]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 05 08:35:14 volumio-pi400 sudo[750]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:14 volumio-pi400 sudo[750]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:14 volumio-pi400 wireless.js[487]: DHCP IP Jan 05 08:35:14 volumio-pi400 wireless.js[487]: Start ap Jan 05 08:35:14 volumio-pi400 wpa_supplicant[752]: Successfully initialized wpa_supplicant Jan 05 08:35:14 volumio-pi400 sudo[753]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jan 05 08:35:14 volumio-pi400 sudo[753]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:14 volumio-pi400 dhcpcd[754]: sending commands to master dhcpcd process Jan 05 08:35:14 volumio-pi400 dhcpcd[574]: control command: /sbin/dhcpcd Jan 05 08:35:14 volumio-pi400 sudo[753]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:14 volumio-pi400 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 05 08:35:14 volumio-pi400 dhcpcd[574]: lo: carrier acquired Jan 05 08:35:14 volumio-pi400 dhcpcd[574]: DUID 00:01:00:01:29:d8:61:9b:dc:a6:32:de:27:50 Jan 05 08:35:14 volumio-pi400 dhcpcd[574]: lo: IAID 6c:6f:00:00 Jan 05 08:35:14 volumio-pi400 dhcpcd[574]: lo: ipv6_start: Operation not supported Jan 05 08:35:14 volumio-pi400 dhcpcd[574]: lo: using static address 127.0.0.1/8 Jan 05 08:35:14 volumio-pi400 dhcpcd[574]: lo: adding route to 127.0.0.0/8 Jan 05 08:35:14 volumio-pi400 dhcpcd[574]: arp_announce: Invalid argument Jan 05 08:35:15 volumio-pi400 wireless.js[487]: trying... Jan 05 08:35:15 volumio-pi400 sudo[784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 05 08:35:15 volumio-pi400 sudo[784]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:15 volumio-pi400 sudo[784]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:16 volumio-pi400 wireless.js[487]: trying... Jan 05 08:35:16 volumio-pi400 sudo[787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 05 08:35:16 volumio-pi400 sudo[787]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:16 volumio-pi400 sudo[787]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:17 volumio-pi400 wpa_supplicant[759]: wlan0: Trying to associate with SSID 'He-Fixed-My-PC' Jan 05 08:35:17 volumio-pi400 wireless.js[487]: trying... Jan 05 08:35:17 volumio-pi400 sudo[790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 05 08:35:17 volumio-pi400 sudo[790]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:17 volumio-pi400 sudo[790]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:18 volumio-pi400 systemd[1]: systemd-rfkill.service: Succeeded. Jan 05 08:35:18 volumio-pi400 bthelper[734]: Changing power off succeeded Jan 05 08:35:18 volumio-pi400 bthelper[734]: [CHG] Controller DC:A6:32:DE:27:52 Class: 0x0000041c Jan 05 08:35:18 volumio-pi400 bthelper[734]: Changing power on succeeded Jan 05 08:35:18 volumio-pi400 wireless.js[487]: trying... Jan 05 08:35:18 volumio-pi400 sudo[800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 05 08:35:18 volumio-pi400 sudo[800]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:18 volumio-pi400 sudo[800]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:19 volumio-pi400 wireless.js[487]: trying... Jan 05 08:35:19 volumio-pi400 sudo[803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 05 08:35:19 volumio-pi400 sudo[803]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:19 volumio-pi400 sudo[803]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:20 volumio-pi400 wpa_supplicant[759]: wlan0: Associated with 50:0f:f5:0a:97:c4 Jan 05 08:35:20 volumio-pi400 wpa_supplicant[759]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 05 08:35:20 volumio-pi400 wpa_supplicant[759]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=GB Jan 05 08:35:20 volumio-pi400 wpa_supplicant[759]: wlan0: WPA: Key negotiation completed with 50:0f:f5:0a:97:c4 [PTK=CCMP GTK=CCMP] Jan 05 08:35:20 volumio-pi400 wpa_supplicant[759]: wlan0: CTRL-EVENT-CONNECTED - Connection to 50:0f:f5:0a:97:c4 completed [id=0 id_str=] Jan 05 08:35:20 volumio-pi400 dhcpcd[574]: wlan0: carrier acquired Jan 05 08:35:20 volumio-pi400 dhcpcd[574]: wlan0: connected to Access Point `He-Fixed-My-PC' Jan 05 08:35:20 volumio-pi400 dhcpcd[574]: wlan0: IAID 32:de:27:51 Jan 05 08:35:20 volumio-pi400 dhcpcd[574]: wlan0: adding address fe80::dea6:32ff:fede:2751 Jan 05 08:35:20 volumio-pi400 dhcpcd[574]: ipv6_addaddr1: Permission denied Jan 05 08:35:20 volumio-pi400 dhcpcd[574]: wlan0: probing address 192.168.1.164/24 Jan 05 08:35:20 volumio-pi400 wireless.js[487]: trying... Jan 05 08:35:20 volumio-pi400 sudo[807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 05 08:35:20 volumio-pi400 sudo[807]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:20 volumio-pi400 sudo[807]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:21 volumio-pi400 volumio-remote-updater[517]: [2025-01-05 08:35:21] [info] asio async_connect error: system:111 (Connection refused) Jan 05 08:35:21 volumio-pi400 volumio-remote-updater[517]: [2025-01-05 08:35:21] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 05 08:35:21 volumio-pi400 volumio-remote-updater[517]: [2025-01-05 08:35:21] [error] handle_connect error: Underlying Transport Error Jan 05 08:35:21 volumio-pi400 dhcpcd[574]: wlan0: soliciting an IPv6 router Jan 05 08:35:21 volumio-pi400 wireless.js[487]: trying... Jan 05 08:35:21 volumio-pi400 sudo[810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 05 08:35:21 volumio-pi400 sudo[810]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:21 volumio-pi400 sudo[810]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:22 volumio-pi400 wireless.js[487]: trying... Jan 05 08:35:22 volumio-pi400 sudo[813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 05 08:35:22 volumio-pi400 sudo[813]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:22 volumio-pi400 sudo[813]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:23 volumio-pi400 kernel: cam1-reg: disabling Jan 05 08:35:23 volumio-pi400 kernel: cam-dummy-reg: disabling Jan 05 08:35:23 volumio-pi400 wireless.js[487]: trying... Jan 05 08:35:23 volumio-pi400 sudo[816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 05 08:35:23 volumio-pi400 sudo[816]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:23 volumio-pi400 sudo[816]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:24 volumio-pi400 wireless.js[487]: trying... Jan 05 08:35:24 volumio-pi400 sudo[819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 05 08:35:24 volumio-pi400 sudo[819]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:24 volumio-pi400 sudo[819]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:24 volumio-pi400 dhcpcd[574]: wlan0: using static address 192.168.1.164/24 Jan 05 08:35:24 volumio-pi400 avahi-daemon[513]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.164. Jan 05 08:35:24 volumio-pi400 avahi-daemon[513]: New relevant interface wlan0.IPv4 for mDNS. Jan 05 08:35:24 volumio-pi400 dhcpcd[574]: wlan0: adding route to 192.168.1.0/24 Jan 05 08:35:24 volumio-pi400 avahi-daemon[513]: Registering new address record for 192.168.1.164 on wlan0.IPv4. Jan 05 08:35:24 volumio-pi400 dhcpcd[574]: wlan0: adding default route via 192.168.1.254 Jan 05 08:35:24 volumio-pi400 nmbd[651]: [2025/01/05 08:35:24.923168, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 05 08:35:24 volumio-pi400 nmbd[651]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jan 05 08:35:24 volumio-pi400 systemd[1]: Started Samba NMB Daemon. Jan 05 08:35:24 volumio-pi400 systemd[1]: Starting Samba Winbind Daemon... Jan 05 08:35:25 volumio-pi400 winbindd[841]: [2025/01/05 08:35:25.095438, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jan 05 08:35:25 volumio-pi400 winbindd[841]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 05 08:35:25 volumio-pi400 winbindd[841]: [2025/01/05 08:35:25.111366, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 05 08:35:25 volumio-pi400 winbindd[841]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 05 08:35:25 volumio-pi400 systemd[1]: Started Samba Winbind Daemon. Jan 05 08:35:25 volumio-pi400 systemd[1]: Starting Samba SMB Daemon... Jan 05 08:35:25 volumio-pi400 smbd[846]: [2025/01/05 08:35:25.423435, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 05 08:35:25 volumio-pi400 smbd[846]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 05 08:35:25 volumio-pi400 systemd[1]: Started Samba SMB Daemon. Jan 05 08:35:25 volumio-pi400 wireless.js[487]: trying... Jan 05 08:35:25 volumio-pi400 sudo[855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 05 08:35:25 volumio-pi400 sudo[855]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:25 volumio-pi400 sudo[855]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:25 volumio-pi400 wireless.js[487]: Connected to: ----He-Fixed-My-PC Jan 05 08:35:25 volumio-pi400 wireless.js[487]: ---- Jan 05 08:35:25 volumio-pi400 sudo[858]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 05 08:35:25 volumio-pi400 sudo[858]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:25 volumio-pi400 sudo[858]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:25 volumio-pi400 wireless.js[487]: ... joined AP, wlan0 IPv4 is 192.168.1.164, ipV6 is undefined Jan 05 08:35:25 volumio-pi400 wireless.js[487]: It's done! AP Jan 05 08:35:25 volumio-pi400 systemd[1]: Started Wireless Services. Jan 05 08:35:25 volumio-pi400 systemd[1]: Started Volumio Backend Module. Jan 05 08:35:25 volumio-pi400 systemd[1]: Started Volumio Streaming Daemon. Jan 05 08:35:25 volumio-pi400 systemd[1]: Reached target Multi-User System. Jan 05 08:35:25 volumio-pi400 systemd[1]: Reached target Graphical Interface. Jan 05 08:35:25 volumio-pi400 systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 05 08:35:25 volumio-pi400 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 05 08:35:25 volumio-pi400 systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 05 08:35:25 volumio-pi400 systemd[1]: Startup finished in 9.398s (kernel) + 26.275s (userspace) = 35.673s. Jan 05 08:35:25 volumio-pi400 volumio-streaming-daemon[863]: ############################ Jan 05 08:35:25 volumio-pi400 volumio-streaming-daemon[863]: # Volumio Streaming Daemon # Jan 05 08:35:25 volumio-pi400 volumio-streaming-daemon[863]: # Running on port 7777 # Jan 05 08:35:25 volumio-pi400 volumio-streaming-daemon[863]: ############################ Jan 05 08:35:25 volumio-pi400 volumio-streaming-daemon[863]: Environment: production Jan 05 08:35:25 volumio-pi400 ntpd[670]: Listen normally on 3 wlan0 192.168.1.164:123 Jan 05 08:35:25 volumio-pi400 ntpd[670]: new interface(s) found: waking up resolver Jan 05 08:35:27 volumio-pi400 volumio[862]: info: ------------------------------------------- Jan 05 08:35:27 volumio-pi400 volumio[862]: info: ----- Volumio3 ---- Jan 05 08:35:27 volumio-pi400 volumio[862]: info: ------------------------------------------- Jan 05 08:35:27 volumio-pi400 volumio[862]: info: ----- System startup ---- Jan 05 08:35:27 volumio-pi400 volumio[862]: info: ------------------------------------------- Jan 05 08:35:27 volumio-pi400 volumio[862]: info: MYVOLUMIO Environment detected Jan 05 08:35:28 volumio-pi400 volumio[862]: info: Plugin folders cleanup Jan 05 08:35:28 volumio-pi400 volumio[862]: info: Scanning into folder /volumio/app/plugins/ Jan 05 08:35:28 volumio-pi400 volumio[862]: info: Scanning category audio_interface Jan 05 08:35:28 volumio-pi400 volumio[862]: info: Scanning category miscellanea Jan 05 08:35:28 volumio-pi400 volumio[862]: info: Scanning category music_service Jan 05 08:35:28 volumio-pi400 volumio[862]: info: Scanning category plugins.json Jan 05 08:35:28 volumio-pi400 volumio[862]: info: Scanning category system_controller Jan 05 08:35:28 volumio-pi400 volumio[862]: info: Scanning category user_interface Jan 05 08:35:28 volumio-pi400 volumio[862]: info: Scanning into folder /data/plugins/ Jan 05 08:35:28 volumio-pi400 volumio[862]: info: Plugin folders cleanup completed Jan 05 08:35:28 volumio-pi400 volumio[862]: info: ------------------------------------------- Jan 05 08:35:28 volumio-pi400 volumio[862]: info: ----- Core plugins startup ---- Jan 05 08:35:28 volumio-pi400 volumio[862]: info: ------------------------------------------- Jan 05 08:35:28 volumio-pi400 volumio[862]: info: Loading plugins from folder /volumio/app/plugins/ Jan 05 08:35:28 volumio-pi400 volumio[862]: info: Adding plugin upnp to MyMusic Plugins Jan 05 08:35:28 volumio-pi400 volumio[862]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 05 08:35:28 volumio-pi400 volumio[862]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 05 08:35:28 volumio-pi400 volumio[862]: info: Loading plugins from folder /data/plugins/ Jan 05 08:35:28 volumio-pi400 volumio[862]: info: Loading plugin "system"... Jan 05 08:35:28 volumio-pi400 volumio[862]: info: Loading plugin "appearance"... Jan 05 08:35:28 volumio-pi400 volumio[862]: info: Loading plugin "network"... Jan 05 08:35:28 volumio-pi400 volumio[862]: info: Refreshing Cached IP Addresses Jan 05 08:35:28 volumio-pi400 sudo[887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 05 08:35:28 volumio-pi400 sudo[887]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:28 volumio-pi400 sudo[887]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:28 volumio-pi400 volumio[862]: info: Loading plugin "services"... Jan 05 08:35:28 volumio-pi400 sudo[889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 05 08:35:28 volumio-pi400 sudo[889]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:28 volumio-pi400 sudo[889]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:28 volumio-pi400 volumio[862]: info: Loading plugin "alsa_controller"... Jan 05 08:35:28 volumio-pi400 sudo[892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 05 08:35:28 volumio-pi400 sudo[892]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:29 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 05 08:35:29 volumio-pi400 volumio[862]: info: Loading plugin "wizard"... Jan 05 08:35:29 volumio-pi400 volumio[862]: info: Loading plugin "volumio_command_line_client"... Jan 05 08:35:29 volumio-pi400 volumio[862]: info: Loading plugin "upnp"... Jan 05 08:35:29 volumio-pi400 volumio[862]: info: [1736066129106] Starting Upmpd Daemon Jan 05 08:35:29 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 05 08:35:29 volumio-pi400 volumio[862]: info: Loading plugin "my_music"... Jan 05 08:35:29 volumio-pi400 volumio[862]: info: Loading plugin "mpd"... Jan 05 08:35:29 volumio-pi400 volumio[862]: info: Creating MPD Configuration file Jan 05 08:35:29 volumio-pi400 volumio[862]: info: Loading plugin "upnp_browser"... Jan 05 08:35:29 volumio-pi400 sudo[900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 05 08:35:29 volumio-pi400 sudo[900]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:29 volumio-pi400 sudo[900]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:29 volumio-pi400 sudo[902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 05 08:35:29 volumio-pi400 sudo[902]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:29 volumio-pi400 systemd[1]: Stopping Music Player Daemon... Jan 05 08:35:29 volumio-pi400 systemd[1]: mpd.service: Succeeded. Jan 05 08:35:29 volumio-pi400 systemd[1]: Stopped Music Player Daemon. Jan 05 08:35:29 volumio-pi400 systemd[1]: Starting Music Player Daemon... Jan 05 08:35:29 volumio-pi400 volumio[862]: info: Loading plugin "networkfs"... Jan 05 08:35:30 volumio-pi400 volumio[862]: info: Starting Udev Watcher for removable devices Jan 05 08:35:30 volumio-pi400 sudo[910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=phillip,password=webster67,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.78/flacs /mnt/NAS/FLACS Jan 05 08:35:30 volumio-pi400 sudo[910]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 05 08:35:30 volumio-pi400 kernel: FS-Cache: Netfs 'cifs' registered for caching Jan 05 08:35:30 volumio-pi400 kernel: Key type cifs.spnego registered Jan 05 08:35:30 volumio-pi400 kernel: Key type cifs.idmap registered Jan 05 08:35:30 volumio-pi400 kernel: CIFS: Attempting to mount //192.168.1.78/flacs Jan 05 08:35:30 volumio-pi400 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. Jan 05 08:35:30 volumio-pi400 mpd[907]: Jan 05 08:35 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 05 08:35:30 volumio-pi400 volumio[862]: info: Ignoring mount for partition: boot Jan 05 08:35:30 volumio-pi400 volumio[862]: info: Ignoring mount for partition: volumio Jan 05 08:35:30 volumio-pi400 volumio[862]: info: Ignoring mount for partition: volumio_data Jan 05 08:35:30 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 05 08:35:30 volumio-pi400 volumio[862]: info: Loading plugin "alarm-clock"... Jan 05 08:35:30 volumio-pi400 volumio[862]: info: Loading plugin "airplay_emulation"... Jan 05 08:35:30 volumio-pi400 volumio[862]: info: Starting Shairport Sync Jan 05 08:35:30 volumio-pi400 volumio[862]: info: Loading plugin "last_100"... Jan 05 08:35:30 volumio-pi400 volumio[862]: info: Loading plugin "webradio"... Jan 05 08:35:30 volumio-pi400 volumio[862]: info: Loading plugin "i2s_dacs"... Jan 05 08:35:30 volumio-pi400 volumio[862]: info: Loading plugin "volumiodiscovery"... Jan 05 08:35:30 volumio-pi400 volumio[862]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 05 08:35:30 volumio-pi400 volumio[862]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 05 08:35:30 volumio-pi400 volumio[862]: *** WARNING *** For more information see Jan 05 08:35:30 volumio-pi400 node[862]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 05 08:35:30 volumio-pi400 volumio[862]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 05 08:35:30 volumio-pi400 volumio[862]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 05 08:35:30 volumio-pi400 volumio[862]: *** WARNING *** For more information see Jan 05 08:35:30 volumio-pi400 node[862]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 05 08:35:30 volumio-pi400 node[862]: *** WARNING *** For more information see Jan 05 08:35:30 volumio-pi400 node[862]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 05 08:35:30 volumio-pi400 node[862]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 05 08:35:30 volumio-pi400 node[862]: *** WARNING *** For more information see Jan 05 08:35:30 volumio-pi400 volumio[862]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 05 08:35:30 volumio-pi400 volumio[862]: info: Discovery: Started advertising with name: Volumio-Pi400 Jan 05 08:35:30 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 05 08:35:30 volumio-pi400 volumio[862]: info: Loading plugin "outputs"... Jan 05 08:35:30 volumio-pi400 volumio[862]: info: Loading plugin "albumart"... Jan 05 08:35:30 volumio-pi400 volumio[862]: info: Plugin example_plugin is not enabled Jan 05 08:35:30 volumio-pi400 volumio[862]: info: Loading plugin "inputs"... Jan 05 08:35:30 volumio-pi400 volumio[862]: info: Loading plugin "updater_comm"... Jan 05 08:35:30 volumio-pi400 mpd[907]: Jan 05 08:35 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 05 08:35:30 volumio-pi400 mpd[907]: Jan 05 08:35 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 05 08:35:30 volumio-pi400 systemd[1]: Started Music Player Daemon. Jan 05 08:35:30 volumio-pi400 sudo[902]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:30 volumio-pi400 volumio[862]: info: Plugin mpdemulation is not enabled Jan 05 08:35:30 volumio-pi400 volumio[862]: info: Loading plugin "rest_api"... Jan 05 08:35:30 volumio-pi400 volumio[862]: info: Loading plugin "websocket"... Jan 05 08:35:30 volumio-pi400 volumio[862]: Forking 3 albumart workers Jan 05 08:35:30 volumio-pi400 volumio[862]: info: Loading i18n strings for locale en Jan 05 08:35:30 volumio-pi400 volumio[862]: Updating browse sources language Jan 05 08:35:30 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 05 08:35:30 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 05 08:35:30 volumio-pi400 volumio[862]: info: CoreCommandRouter::initPlayerControls Jan 05 08:35:30 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:30 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:30 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:30 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:30 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:30 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:30 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:30 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:30 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 05 08:35:30 volumio-pi400 volumio[862]: Express server listening on port 3000 Jan 05 08:35:30 volumio-pi400 volumio[862]: [Metrics] WebUI: 4s 155.75ms Jan 05 08:35:30 volumio-pi400 volumio[862]: info: CoreStateMachine::resetVolumioState Jan 05 08:35:30 volumio-pi400 volumio[862]: info: CoreStateMachine::getcurrentVolume Jan 05 08:35:30 volumio-pi400 volumio[862]: info: CoreCommandRouter::volumioRetrievevolume Jan 05 08:35:31 volumio-pi400 volumio-remote-updater[517]: [2025-01-05 08:35:31] [connect] Successful connection Jan 05 08:35:31 volumio-pi400 volumio[862]: info: MPD Permissions set Jan 05 08:35:31 volumio-pi400 volumio-remote-updater[517]: [2025-01-05 08:35:31] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1736066131 101 Jan 05 08:35:31 volumio-pi400 volumio[862]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Jan 05 08:35:31 volumio-pi400 volumio[862]: info: MPD running with PID907 Jan 05 08:35:31 volumio-pi400 volumio[862]: ,establishing connection Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 05 08:35:31 volumio-pi400 volumio[862]: info: Reloading queue from file Jan 05 08:35:31 volumio-pi400 volumio[862]: error: updateQueue error: null Jan 05 08:35:31 volumio-pi400 volumio[862]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 05 08:35:31 volumio-pi400 volumio[862]: info: VolumeController:: Volume=93 Mute =false Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CoreStateMachine::pushState Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CorePlayQueue::getTrack 0 Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CoreCommandRouter::volumioPushState Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CoreStateMachine::updateTrackBlock Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CorePlayQueue::getTrackBlock Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CoreCommandRouter::volumioRetrievevolume Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CoreStateMachine::setRepeat true single undefined Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CoreStateMachine::pushState Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CorePlayQueue::getTrack 0 Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CoreCommandRouter::volumioPushState Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CoreStateMachine::setRandom null Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CoreStateMachine::pushState Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CorePlayQueue::getTrack 0 Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CoreCommandRouter::volumioPushState Jan 05 08:35:31 volumio-pi400 volumio[862]: info: Setting Device type: Raspberry PI Jan 05 08:35:31 volumio-pi400 volumio[862]: Starting albumart workers Jan 05 08:35:31 volumio-pi400 volumio[862]: info: VolumeController:: Volume=93 Mute =false Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CoreStateMachine::pushState Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CorePlayQueue::getTrack 0 Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CoreCommandRouter::volumioPushState Jan 05 08:35:31 volumio-pi400 volumio[862]: Starting albumart workers Jan 05 08:35:31 volumio-pi400 volumio[862]: Starting albumart workers Jan 05 08:35:31 volumio-pi400 volumio[862]: info: Discovery: adding a2debaa3-0a64-4ef0-a89a-e4255a12bf5b Jan 05 08:35:31 volumio-pi400 volumio[862]: info: Discovery: Found device Volumio-Pi400 Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CoreCommandRouter::volumioGetState Jan 05 08:35:31 volumio-pi400 volumio[862]: info: CorePlayQueue::getTrack 0 Jan 05 08:35:32 volumio-pi400 volumio[862]: error: updateQueue error: null Jan 05 08:35:32 volumio-pi400 sudo[892]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:33 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 05 08:35:33 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 05 08:35:33 volumio-pi400 volumio[862]: info: Discovery: Getting this device information Jan 05 08:35:33 volumio-pi400 volumio[862]: info: CoreCommandRouter::volumioGetState Jan 05 08:35:33 volumio-pi400 volumio[862]: info: CorePlayQueue::getTrack 0 Jan 05 08:35:33 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 05 08:35:34 volumio-pi400 volumio[862]: verbose: New Socket.io Connection to 192.168.1.164:3000 from 192.168.1.221 UA: Dart/3.5 (dart:io) Total Clients: 2 Jan 05 08:35:35 volumio-pi400 volumio[862]: info: CoreCommandRouter::volumioGetState Jan 05 08:35:35 volumio-pi400 volumio[862]: info: CorePlayQueue::getTrack 0 Jan 05 08:35:35 volumio-pi400 systemd[1]: systemd-fsckd.service: Succeeded. Jan 05 08:35:36 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 05 08:35:36 volumio-pi400 volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 05 08:35:36 volumio-pi400 volumio[862]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Dolly%20Parton/Little%20Sparrow/d6c694b5-4394-4da2-83c3-3018ef732741.png' Jan 05 08:35:36 volumio-pi400 sudo[910]: pam_unix(sudo:session): session closed for user root Jan 05 08:35:36 volumio-pi400 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 05 08:35:36 volumio-pi400 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 05 08:35:36 volumio-pi400 volumio[862]: info: CoreCommandRouter::volumioGetState Jan 05 08:35:36 volumio-pi400 volumio[862]: info: CorePlayQueue::getTrack 0 Jan 05 08:35:36 volumio-pi400 volumio[862]: info: Cannot mount NAS FLACS at system boot, trial number 1 ,retrying in 5 seconds Jan 05 08:35:36 volumio-pi400 volumio[862]: info: Completed loading Core Plugins Jan 05 08:35:36 volumio-pi400 volumio[862]: info: Preparing to generate the ALSA configuration file Jan 05 08:35:37 volumio-pi400 volumio[862]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 05 08:35:37 volumio-pi400 volumio[862]: Error: certificate is not yet valid Jan 05 08:35:37 volumio-pi400 volumio[862]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Jan 05 08:35:37 volumio-pi400 volumio[862]: at TLSSocket.emit (events.js:315:20) Jan 05 08:35:37 volumio-pi400 volumio[862]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Jan 05 08:35:37 volumio-pi400 volumio[862]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Jan 05 08:35:37 volumio-pi400 volumio[862]: code: 'CERT_NOT_YET_VALID' Jan 05 08:35:37 volumio-pi400 volumio[862]: } Jan 05 08:35:37 volumio-pi400 volumio[862]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 05 08:35:37 volumio-pi400 sudo[1005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-05 08:34 Jan 05 08:35:37 volumio-pi400 sudo[1005]: 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="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="85acf7bbbceb3e3dec447156a39a88033f921210" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 30 Sep 2022 10:43:40 AM CEST" VOLUMIO_VERSION="3.378" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4bc817297375b72097a1fade15c6518b"