-- Logs begin at Sat 2025-04-19 19:53:33 CEST, end at Sat 2025-04-19 19:54:15 CEST. -- Apr 19 19:53:33 switje kernel: Booting Linux on physical CPU 0x0 Apr 19 19:53:33 switje kernel: Linux version 6.1.69-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) #1710 SMP Thu Dec 21 13:17:38 GMT 2023 Apr 19 19:53:33 switje kernel: CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d Apr 19 19:53:33 switje kernel: CPU: div instructions available: patching division code Apr 19 19:53:33 switje kernel: CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache Apr 19 19:53:33 switje kernel: OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.5 Apr 19 19:53:33 switje kernel: random: crng init done Apr 19 19:53:33 switje kernel: Memory policy: Data cache writealloc Apr 19 19:53:33 switje kernel: Reserved memory: created CMA memory pool at 0x000000002a000000, size 64 MiB Apr 19 19:53:33 switje kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Apr 19 19:53:33 switje kernel: Zone ranges: Apr 19 19:53:33 switje kernel: DMA [mem 0x0000000000000000-0x000000002fffffff] Apr 19 19:53:33 switje kernel: Normal empty Apr 19 19:53:33 switje kernel: HighMem [mem 0x0000000030000000-0x00000000fbffffff] Apr 19 19:53:33 switje kernel: Movable zone start for each node Apr 19 19:53:33 switje kernel: Early memory node ranges Apr 19 19:53:33 switje kernel: node 0: [mem 0x0000000000000000-0x0000000037ffffff] Apr 19 19:53:33 switje kernel: node 0: [mem 0x0000000040000000-0x00000000fbffffff] Apr 19 19:53:33 switje kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000000fbffffff] Apr 19 19:53:33 switje kernel: percpu: Embedded 17 pages/cpu s37012 r8192 d24428 u69632 Apr 19 19:53:33 switje kernel: pcpu-alloc: s37012 r8192 d24428 u69632 alloc=17*4096 Apr 19 19:53:33 switje kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Apr 19 19:53:33 switje kernel: Built 1 zonelists, mobility grouping on. Total pages: 997696 Apr 19 19:53:33 switje kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=D8:3A:DD:FC:FD:58 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=UUID=8543fa12-4125-4451-a534-cec5480e0606 imgfile=/volumio_current.sqsh bootpart=UUID=9C48-E5A3 datapart=UUID=f7cca1c6-391b-47a8-9c19-3b1948539def uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Apr 19 19:53:33 switje kernel: Unknown kernel command line parameters "splash nodebug imgpart=UUID=8543fa12-4125-4451-a534-cec5480e0606 imgfile=/volumio_current.sqsh bootpart=UUID=9C48-E5A3 datapart=UUID=f7cca1c6-391b-47a8-9c19-3b1948539def uuidconfig=cmdline.txt pcie_aspm=off bootdelay=7 use_kmsg=no", will be passed to user space. Apr 19 19:53:33 switje kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Apr 19 19:53:33 switje kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Apr 19 19:53:33 switje kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Apr 19 19:53:33 switje kernel: software IO TLB: area num 4. Apr 19 19:53:33 switje kernel: software IO TLB: mapped [mem 0x0000000023c90000-0x0000000027c90000] (64MB) Apr 19 19:53:33 switje kernel: Memory: 3796700K/3997696K available (10240K kernel code, 1505K rwdata, 3388K rodata, 2048K init, 637K bss, 135460K reserved, 65536K cma-reserved, 3211264K highmem) Apr 19 19:53:33 switje kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Apr 19 19:53:33 switje kernel: ftrace: allocating 37226 entries in 110 pages Apr 19 19:53:33 switje kernel: ftrace: allocated 110 pages with 5 groups Apr 19 19:53:33 switje kernel: trace event string verifier disabled Apr 19 19:53:33 switje kernel: rcu: Hierarchical RCU implementation. Apr 19 19:53:33 switje kernel: Rude variant of Tasks RCU enabled. Apr 19 19:53:33 switje kernel: Tracing variant of Tasks RCU enabled. Apr 19 19:53:33 switje kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Apr 19 19:53:33 switje kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Apr 19 19:53:33 switje kernel: GIC: Using split EOI/Deactivate mode Apr 19 19:53:33 switje kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Apr 19 19:53:33 switje kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Apr 19 19:53:33 switje kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Apr 19 19:53:33 switje kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Apr 19 19:53:33 switje kernel: Switching to timer-based delay loop, resolution 18ns Apr 19 19:53:33 switje kernel: Console: colour dummy device 80x30 Apr 19 19:53:33 switje kernel: printk: console [tty1] enabled Apr 19 19:53:33 switje kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000) Apr 19 19:53:33 switje kernel: CPU: Testing write buffer coherency: ok Apr 19 19:53:33 switje kernel: CPU0: Spectre BHB: enabling loop workaround for all CPUs Apr 19 19:53:33 switje kernel: pid_max: default: 32768 minimum: 301 Apr 19 19:53:33 switje kernel: LSM: Security Framework initializing Apr 19 19:53:33 switje kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Apr 19 19:53:33 switje kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Apr 19 19:53:33 switje kernel: cgroup: Disabling memory control group subsystem Apr 19 19:53:33 switje kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Apr 19 19:53:33 switje kernel: cblist_init_generic: Setting adjustable number of callback queues. Apr 19 19:53:33 switje kernel: cblist_init_generic: Setting shift to 2 and lim to 1. Apr 19 19:53:33 switje kernel: cblist_init_generic: Setting adjustable number of callback queues. Apr 19 19:53:33 switje kernel: cblist_init_generic: Setting shift to 2 and lim to 1. Apr 19 19:53:33 switje kernel: Setting up static identity map for 0x200000 - 0x20003c Apr 19 19:53:33 switje kernel: rcu: Hierarchical SRCU implementation. Apr 19 19:53:33 switje kernel: rcu: Max phase no-delay instances is 1000. Apr 19 19:53:33 switje kernel: smp: Bringing up secondary CPUs ... Apr 19 19:53:33 switje kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Apr 19 19:53:33 switje kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Apr 19 19:53:33 switje kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Apr 19 19:53:33 switje kernel: smp: Brought up 1 node, 4 CPUs Apr 19 19:53:33 switje kernel: SMP: Total of 4 processors activated (432.00 BogoMIPS). Apr 19 19:53:33 switje kernel: CPU: All CPU(s) started in HYP mode. Apr 19 19:53:33 switje kernel: CPU: Virtualization extensions available. Apr 19 19:53:33 switje kernel: devtmpfs: initialized Apr 19 19:53:33 switje kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0 Apr 19 19:53:33 switje kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Apr 19 19:53:33 switje kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Apr 19 19:53:33 switje kernel: pinctrl core: initialized pinctrl subsystem Apr 19 19:53:33 switje kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Apr 19 19:53:33 switje kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Apr 19 19:53:33 switje kernel: audit: initializing netlink subsys (disabled) Apr 19 19:53:33 switje kernel: audit: type=2000 audit(0.020:1): state=initialized audit_enabled=0 res=1 Apr 19 19:53:33 switje kernel: thermal_sys: Registered thermal governor 'step_wise' Apr 19 19:53:33 switje kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Apr 19 19:53:33 switje kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Apr 19 19:53:33 switje kernel: Serial: AMBA PL011 UART driver Apr 19 19:53:33 switje kernel: bcm2835-mbox fe00b880.mailbox: mailbox enabled Apr 19 19:53:33 switje kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2023-10-17T15:39:16, variant start Apr 19 19:53:33 switje kernel: raspberrypi-firmware soc:firmware: Firmware hash is 30f0c5e4d076da3ab4f341d88e7d505760b93ad7 Apr 19 19:53:33 switje kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Apr 19 19:53:33 switje kernel: bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1 Apr 19 19:53:33 switje kernel: SCSI subsystem initialized Apr 19 19:53:33 switje kernel: usbcore: registered new interface driver usbfs Apr 19 19:53:33 switje kernel: usbcore: registered new interface driver hub Apr 19 19:53:33 switje kernel: usbcore: registered new device driver usb Apr 19 19:53:33 switje kernel: usb_phy_generic phy: supply vcc not found, using dummy regulator Apr 19 19:53:33 switje kernel: pps_core: LinuxPPS API ver. 1 registered Apr 19 19:53:33 switje kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Apr 19 19:53:33 switje kernel: PTP clock support registered Apr 19 19:53:33 switje kernel: vgaarb: loaded Apr 19 19:53:33 switje kernel: clocksource: Switched to clocksource arch_sys_counter Apr 19 19:53:33 switje kernel: VFS: Disk quotas dquot_6.6.0 Apr 19 19:53:33 switje kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Apr 19 19:53:33 switje kernel: FS-Cache: Loaded Apr 19 19:53:33 switje kernel: CacheFiles: Loaded Apr 19 19:53:33 switje kernel: NET: Registered PF_INET protocol family Apr 19 19:53:33 switje kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) Apr 19 19:53:33 switje kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear) Apr 19 19:53:33 switje kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Apr 19 19:53:33 switje kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Apr 19 19:53:33 switje kernel: TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear) Apr 19 19:53:33 switje kernel: TCP: Hash tables configured (established 8192 bind 8192) Apr 19 19:53:33 switje kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Apr 19 19:53:33 switje kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Apr 19 19:53:33 switje kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Apr 19 19:53:33 switje kernel: RPC: Registered named UNIX socket transport module. Apr 19 19:53:33 switje kernel: RPC: Registered udp transport module. Apr 19 19:53:33 switje kernel: RPC: Registered tcp transport module. Apr 19 19:53:33 switje kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Apr 19 19:53:33 switje kernel: PCI: CLS 0 bytes, default 64 Apr 19 19:53:33 switje kernel: Trying to unpack rootfs image as initramfs... Apr 19 19:53:33 switje kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available Apr 19 19:53:33 switje kernel: Initialise system trusted keyrings Apr 19 19:53:33 switje kernel: workingset: timestamp_bits=14 max_order=20 bucket_order=6 Apr 19 19:53:33 switje kernel: zbud: loaded Apr 19 19:53:33 switje kernel: NFS: Registering the id_resolver key type Apr 19 19:53:33 switje kernel: Key type id_resolver registered Apr 19 19:53:33 switje kernel: Key type id_legacy registered Apr 19 19:53:33 switje kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Apr 19 19:53:33 switje kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Apr 19 19:53:33 switje kernel: Key type asymmetric registered Apr 19 19:53:33 switje kernel: Asymmetric key parser 'x509' registered Apr 19 19:53:33 switje kernel: bounce: pool size: 64 pages Apr 19 19:53:33 switje kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Apr 19 19:53:33 switje kernel: io scheduler mq-deadline registered Apr 19 19:53:33 switje kernel: io scheduler kyber registered Apr 19 19:53:33 switje kernel: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: Apr 19 19:53:33 switje kernel: brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff] Apr 19 19:53:33 switje kernel: brcm-pcie fd500000.pcie: MEM 0x0600000000..0x063fffffff -> 0x00c0000000 Apr 19 19:53:33 switje kernel: brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x00ffffffff -> 0x0400000000 Apr 19 19:53:33 switje kernel: brcm-pcie fd500000.pcie: setting SCB_ACCESS_EN, READ_UR_MODE, MAX_BURST_SIZE Apr 19 19:53:33 switje kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Apr 19 19:53:33 switje kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Apr 19 19:53:33 switje kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff]) Apr 19 19:53:33 switje kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 Apr 19 19:53:33 switje kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Apr 19 19:53:33 switje kernel: PCI: bus0: Fast back to back transfers disabled Apr 19 19:53:33 switje kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Apr 19 19:53:33 switje kernel: pci_bus 0000:01: supply vpcie3v3 not found, using dummy regulator Apr 19 19:53:33 switje kernel: pci_bus 0000:01: supply vpcie3v3aux not found, using dummy regulator Apr 19 19:53:33 switje kernel: pci_bus 0000:01: supply vpcie12v not found, using dummy regulator Apr 19 19:53:33 switje kernel: brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC) Apr 19 19:53:33 switje kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 Apr 19 19:53:33 switje kernel: pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit] Apr 19 19:53:33 switje kernel: pci 0000:01:00.0: PME# supported from D0 D3hot Apr 19 19:53:33 switje kernel: PCI: bus1: Fast back to back transfers disabled Apr 19 19:53:33 switje kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Apr 19 19:53:33 switje kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff] Apr 19 19:53:33 switje kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit] Apr 19 19:53:33 switje kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Apr 19 19:53:33 switje kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] Apr 19 19:53:33 switje kernel: pci 0000:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 Apr 19 19:53:33 switje kernel: pci 0000:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512 Apr 19 19:53:33 switje kernel: pcieport 0000:00:00.0: enabling device (0000 -> 0002) Apr 19 19:53:33 switje kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 45 Apr 19 19:53:33 switje kernel: bcm2708_fb soc:fb: FB found 1 display(s) Apr 19 19:53:33 switje kernel: Console: switching to colour frame buffer device 100x30 Apr 19 19:53:33 switje kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 800x480 Apr 19 19:53:33 switje kernel: iproc-rng200 fe104000.rng: hwrng registered Apr 19 19:53:33 switje kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Apr 19 19:53:33 switje kernel: brd: module loaded Apr 19 19:53:33 switje kernel: loop: module loaded Apr 19 19:53:33 switje kernel: Loading iSCSI transport class v2.0-870. Apr 19 19:53:33 switje kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Apr 19 19:53:33 switje kernel: Freeing initrd memory: 14316K Apr 19 19:53:33 switje kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Apr 19 19:53:33 switje kernel: usbcore: registered new device driver r8152-cfgselector Apr 19 19:53:33 switje kernel: usbcore: registered new interface driver r8152 Apr 19 19:53:33 switje kernel: usbcore: registered new interface driver lan78xx Apr 19 19:53:33 switje kernel: usbcore: registered new interface driver smsc95xx Apr 19 19:53:33 switje kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Apr 19 19:53:33 switje kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Apr 19 19:53:33 switje kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0b00040000000890 Apr 19 19:53:33 switje kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Apr 19 19:53:33 switje kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Apr 19 19:53:33 switje kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Apr 19 19:53:33 switje kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.01 Apr 19 19:53:33 switje kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Apr 19 19:53:33 switje kernel: usb usb1: Product: xHCI Host Controller Apr 19 19:53:33 switje kernel: usb usb1: Manufacturer: Linux 6.1.69-v7l+ xhci-hcd Apr 19 19:53:33 switje kernel: usb usb1: SerialNumber: 0000:01:00.0 Apr 19 19:53:33 switje kernel: hub 1-0:1.0: USB hub found Apr 19 19:53:33 switje kernel: hub 1-0:1.0: 1 port detected Apr 19 19:53:33 switje kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.01 Apr 19 19:53:33 switje kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Apr 19 19:53:33 switje kernel: usb usb2: Product: xHCI Host Controller Apr 19 19:53:33 switje kernel: usb usb2: Manufacturer: Linux 6.1.69-v7l+ xhci-hcd Apr 19 19:53:33 switje kernel: usb usb2: SerialNumber: 0000:01:00.0 Apr 19 19:53:33 switje kernel: hub 2-0:1.0: USB hub found Apr 19 19:53:33 switje kernel: hub 2-0:1.0: 4 ports detected Apr 19 19:53:33 switje kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Apr 19 19:53:33 switje kernel: dwc_otg: FIQ enabled Apr 19 19:53:33 switje kernel: dwc_otg: NAK holdoff enabled Apr 19 19:53:33 switje kernel: dwc_otg: FIQ split-transaction FSM enabled Apr 19 19:53:33 switje kernel: Module dwc_common_port init Apr 19 19:53:33 switje kernel: usbcore: registered new interface driver uas Apr 19 19:53:33 switje kernel: usbcore: registered new interface driver usb-storage Apr 19 19:53:33 switje kernel: mousedev: PS/2 mouse device common for all mice Apr 19 19:53:33 switje kernel: sdhci: Secure Digital Host Controller Interface driver Apr 19 19:53:33 switje kernel: sdhci: Copyright(c) Pierre Ossman Apr 19 19:53:33 switje kernel: sdhci-pltfm: SDHCI platform and OF driver helper Apr 19 19:53:33 switje kernel: ledtrig-cpu: registered to indicate activity on CPUs Apr 19 19:53:33 switje kernel: hid: raw HID events driver (C) Jiri Kosina Apr 19 19:53:33 switje kernel: usbcore: registered new interface driver usbhid Apr 19 19:53:33 switje kernel: usbhid: USB HID core driver Apr 19 19:53:33 switje kernel: Initializing XFRM netlink socket Apr 19 19:53:33 switje kernel: NET: Registered PF_PACKET protocol family Apr 19 19:53:33 switje kernel: Key type dns_resolver registered Apr 19 19:53:33 switje kernel: Registering SWP/SWPB emulation handler Apr 19 19:53:33 switje kernel: registered taskstats version 1 Apr 19 19:53:33 switje kernel: Loading compiled-in X.509 certificates Apr 19 19:53:33 switje kernel: Key type .fscrypt registered Apr 19 19:53:33 switje kernel: Key type fscrypt-provisioning registered Apr 19 19:53:33 switje kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Apr 19 19:53:33 switje kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Apr 19 19:53:33 switje kernel: fe201000.serial: ttyAMA1 at MMIO 0xfe201000 (irq = 51, base_baud = 0) is a PL011 rev2 Apr 19 19:53:33 switje kernel: serial serial0: tty port ttyAMA1 registered Apr 19 19:53:33 switje kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Apr 19 19:53:33 switje kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Apr 19 19:53:33 switje kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Apr 19 19:53:33 switje kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Apr 19 19:53:33 switje kernel: of_cfs_init Apr 19 19:53:33 switje kernel: of_cfs_init: OK Apr 19 19:53:33 switje kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Apr 19 19:53:33 switje kernel: Freeing unused kernel image (initmem) memory: 2048K Apr 19 19:53:33 switje kernel: mmc1: new high speed SDIO card at address 0001 Apr 19 19:53:33 switje kernel: Run /init as init process Apr 19 19:53:33 switje kernel: with arguments: Apr 19 19:53:33 switje kernel: /init Apr 19 19:53:33 switje kernel: splash Apr 19 19:53:33 switje kernel: nodebug Apr 19 19:53:33 switje kernel: with environment: Apr 19 19:53:33 switje kernel: HOME=/ Apr 19 19:53:33 switje kernel: TERM=linux Apr 19 19:53:33 switje kernel: imgpart=UUID=8543fa12-4125-4451-a534-cec5480e0606 Apr 19 19:53:33 switje kernel: imgfile=/volumio_current.sqsh Apr 19 19:53:33 switje kernel: bootpart=UUID=9C48-E5A3 Apr 19 19:53:33 switje kernel: datapart=UUID=f7cca1c6-391b-47a8-9c19-3b1948539def Apr 19 19:53:33 switje kernel: uuidconfig=cmdline.txt Apr 19 19:53:33 switje kernel: pcie_aspm=off Apr 19 19:53:33 switje kernel: bootdelay=7 Apr 19 19:53:33 switje kernel: use_kmsg=no Apr 19 19:53:33 switje kernel: mmc0: new ultra high speed DDR50 SDHC card at address 59b4 Apr 19 19:53:33 switje kernel: mmcblk0: mmc0:59b4 SN32G 29.7 GiB Apr 19 19:53:33 switje kernel: mmcblk0: p1 p2 p3 Apr 19 19:53:33 switje kernel: mmcblk0: mmc0:59b4 SN32G 29.7 GiB (quirks 0x00004000) Apr 19 19:53:33 switje kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Apr 19 19:53:33 switje kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Apr 19 19:53:33 switje kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Apr 19 19:53:33 switje kernel: usb 1-1: Product: USB2.0 Hub Apr 19 19:53:33 switje kernel: hub 1-1:1.0: USB hub found Apr 19 19:53:33 switje kernel: hub 1-1:1.0: 4 ports detected Apr 19 19:53:33 switje kernel: fuse: init (API version 7.37) Apr 19 19:53:33 switje kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Apr 19 19:53:33 switje kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Apr 19 19:53:33 switje kernel: EXT4-fs (mmcblk0p2): recovery complete Apr 19 19:53:33 switje kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Quota mode: none. Apr 19 19:53:33 switje kernel: loop0: detected capacity change from 0 to 983416 Apr 19 19:53:33 switje kernel: EXT4-fs (mmcblk0p3): 2 orphan inodes deleted Apr 19 19:53:33 switje kernel: EXT4-fs (mmcblk0p3): recovery complete Apr 19 19:53:33 switje kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Quota mode: none. Apr 19 19:53:33 switje systemd[1]: System time before build time, advancing clock. Apr 19 19:53:33 switje kernel: NET: Registered PF_INET6 protocol family Apr 19 19:53:33 switje kernel: Segment Routing with IPv6 Apr 19 19:53:33 switje kernel: In-situ OAM (IOAM) with IPv6 Apr 19 19:53:33 switje 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) Apr 19 19:53:33 switje systemd[1]: Detected architecture arm. Apr 19 19:53:33 switje systemd[1]: Set hostname to . Apr 19 19:53:33 switje 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. Apr 19 19:53:33 switje systemd[1]: Listening on udev Control Socket. Apr 19 19:53:33 switje systemd[1]: Created slice User and Session Slice. Apr 19 19:53:33 switje systemd[1]: Listening on udev Kernel Socket. Apr 19 19:53:33 switje systemd[1]: Listening on Journal Socket. Apr 19 19:53:33 switje systemd[1]: Mounting RPC Pipe File System... Apr 19 19:53:33 switje systemd[1]: Condition check resulted in Kernel Module supporting RPCSEC_GSS being skipped. Apr 19 19:53:33 switje kernel: i2c_dev: i2c /dev entries driver Apr 19 19:53:33 switje systemd-journald[329]: Journal started Apr 19 19:53:33 switje systemd-journald[329]: Runtime journal (/run/log/journal/d9a3e155a633914cdbe1662066e99934) is 3.7M, max 30.0M, 26.2M free. Apr 19 19:53:33 switje systemd-modules-load[323]: Inserted module 'i2c_dev' Apr 19 19:53:33 switje fake-hwclock[324]: Sat Apr 19 17:53:33 UTC 2025 Apr 19 19:53:33 switje systemd[1]: Started Create Static Device Nodes in /dev. Apr 19 19:53:33 switje systemd[1]: Reached target Local File Systems (Pre). Apr 19 19:53:33 switje systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Apr 19 19:53:33 switje systemd[1]: Mounting /tmp... Apr 19 19:53:33 switje systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Apr 19 19:53:33 switje systemd[1]: Mounting /var/log... Apr 19 19:53:33 switje systemd[1]: Mounting /var/spool/cups... Apr 19 19:53:33 switje systemd[1]: Starting udev Kernel Device Manager... Apr 19 19:53:33 switje systemd[1]: Mounted /tmp. Apr 19 19:53:33 switje systemd[1]: Mounted /var/log. Apr 19 19:53:33 switje systemd[1]: Starting Flush Journal to Persistent Storage... Apr 19 19:53:33 switje systemd[1]: Mounted /var/spool/cups. Apr 19 19:53:33 switje systemd[1]: Mounting /var/spool/cups/tmp... Apr 19 19:53:33 switje systemd[1]: Mounted /var/spool/cups/tmp. Apr 19 19:53:33 switje systemd-udevd[353]: Network interface NamePolicy= disabled on kernel command line, ignoring. Apr 19 19:53:33 switje systemd-journald[329]: Runtime journal (/run/log/journal/d9a3e155a633914cdbe1662066e99934) is 3.7M, max 30.0M, 26.2M free. Apr 19 19:53:33 switje systemd[1]: Started Flush Journal to Persistent Storage. Apr 19 19:53:33 switje systemd[1]: Started udev Coldplug all Devices. Apr 19 19:53:33 switje systemd[1]: Starting Helper to synchronize boot up for ifupdown... Apr 19 19:53:33 switje systemd[1]: Started Helper to synchronize boot up for ifupdown. Apr 19 19:53:33 switje systemd[1]: Started udev Kernel Device Manager. Apr 19 19:53:33 switje systemd[1]: Starting Show Plymouth Boot Screen... Apr 19 19:53:33 switje systemd[1]: plymouth-start.service: Succeeded. Apr 19 19:53:33 switje systemd[1]: Started Show Plymouth Boot Screen. Apr 19 19:53:33 switje systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Apr 19 19:53:33 switje systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Apr 19 19:53:33 switje systemd[1]: Reached target Paths. Apr 19 19:53:33 switje systemd[1]: Reached target Local Encrypted Volumes. Apr 19 19:53:33 switje systemd-udevd[385]: Using default interface naming scheme 'v240'. Apr 19 19:53:33 switje kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Apr 19 19:53:33 switje kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Apr 19 19:53:33 switje kernel: mc: Linux media interface: v0.10 Apr 19 19:53:33 switje systemd-udevd[385]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 19 19:53:33 switje kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Apr 19 19:53:33 switje kernel: [vc_sm_connected_init]: start Apr 19 19:53:33 switje kernel: [vc_sm_connected_init]: installed successfully Apr 19 19:53:33 switje kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Apr 19 19:53:34 switje kernel: videodev: Linux video capture interface: v2.00 Apr 19 19:53:34 switje kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Apr 19 19:53:34 switje kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Apr 19 19:53:34 switje kernel: input: raspberrypi-ts as /devices/platform/soc/soc:firmware/soc:firmware:touchscreen/input/input0 Apr 19 19:53:34 switje kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Apr 19 19:53:34 switje kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Apr 19 19:53:34 switje kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Apr 19 19:53:34 switje kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Apr 19 19:53:34 switje kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Apr 19 19:53:34 switje kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Apr 19 19:53:34 switje kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Apr 19 19:53:34 switje kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Apr 19 19:53:34 switje kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Apr 19 19:53:34 switje kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Apr 19 19:53:34 switje kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Apr 19 19:53:34 switje kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Apr 19 19:53:34 switje kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Apr 19 19:53:34 switje kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Apr 19 19:53:34 switje kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Apr 19 19:53:34 switje kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Apr 19 19:53:34 switje kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Apr 19 19:53:34 switje systemd-udevd[363]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 19 19:53:34 switje kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Apr 19 19:53:34 switje kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Apr 19 19:53:34 switje kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Apr 19 19:53:34 switje kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Apr 19 19:53:34 switje kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Apr 19 19:53:34 switje kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Apr 19 19:53:34 switje kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Apr 19 19:53:34 switje kernel: rpivid feb10000.codec: Device registered as /dev/video19 Apr 19 19:53:34 switje kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Apr 19 19:53:34 switje kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Apr 19 19:53:34 switje kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Apr 19 19:53:34 switje kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Apr 19 19:53:34 switje kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Apr 19 19:53:34 switje kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Apr 19 19:53:34 switje kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Apr 19 19:53:34 switje kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Apr 19 19:53:34 switje kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Apr 19 19:53:34 switje kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Apr 19 19:53:34 switje kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Apr 19 19:53:34 switje kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Apr 19 19:53:34 switje kernel: bcm2835_audio bcm2835_audio: card created with 8 channels Apr 19 19:53:34 switje kernel: Bluetooth: Core ver 2.22 Apr 19 19:53:34 switje kernel: NET: Registered PF_BLUETOOTH protocol family Apr 19 19:53:34 switje kernel: Bluetooth: HCI device and connection manager initialized Apr 19 19:53:34 switje kernel: Bluetooth: HCI socket layer initialized Apr 19 19:53:34 switje kernel: Bluetooth: L2CAP socket layer initialized Apr 19 19:53:34 switje kernel: Bluetooth: SCO socket layer initialized Apr 19 19:53:35 switje kernel: Bluetooth: HCI UART driver ver 2.3 Apr 19 19:53:35 switje kernel: Bluetooth: HCI UART protocol H4 registered Apr 19 19:53:35 switje kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Apr 19 19:53:35 switje kernel: Bluetooth: HCI UART protocol Broadcom registered Apr 19 19:53:35 switje kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Apr 19 19:53:35 switje kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Apr 19 19:53:35 switje kernel: uart-pl011 fe201000.serial: no DMA platform data Apr 19 19:53:35 switje kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Apr 19 19:53:35 switje kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Apr 19 19:53:35 switje kernel: usbcore: registered new interface driver brcmfmac Apr 19 19:53:35 switje systemd[1]: Found device /dev/disk/by-uuid/9C48-E5A3. Apr 19 19:53:35 switje kernel: Bluetooth: hci0: BCM: chip id 107 Apr 19 19:53:35 switje kernel: Bluetooth: hci0: BCM: features 0x2f Apr 19 19:53:35 switje kernel: Bluetooth: hci0: BCM4345C0 Apr 19 19:53:35 switje kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Apr 19 19:53:35 switje kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Apr 19 19:53:35 switje systemd-udevd[381]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 19 19:53:35 switje kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov 1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60 Apr 19 19:53:35 switje systemd-udevd[370]: Using default interface naming scheme 'v240'. Apr 19 19:53:35 switje systemd-udevd[370]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 19 19:53:35 switje systemd-udevd[365]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 19 19:53:36 switje systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Apr 19 19:53:36 switje systemd[1]: Created slice system-bthelper.slice. Apr 19 19:53:36 switje systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Apr 19 19:53:36 switje systemd[1]: Starting Show Plymouth Boot Screen... Apr 19 19:53:36 switje systemd[1]: Condition check resulted in Huge Pages File System being skipped. Apr 19 19:53:36 switje systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Apr 19 19:53:36 switje systemd[1]: Created slice system-systemd\x2dbacklight.slice. Apr 19 19:53:36 switje kernel: Bluetooth: hci0: BCM: features 0x2f Apr 19 19:53:36 switje kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Apr 19 19:53:36 switje kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Apr 19 19:53:36 switje systemd[1]: Starting Load/Save Screen Backlight Brightness of backlight:rpi_backlight... Apr 19 19:53:36 switje systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Apr 19 19:53:36 switje systemd[1]: Starting File System Check on /dev/disk/by-uuid/9C48-E5A3... Apr 19 19:53:36 switje systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Apr 19 19:53:36 switje systemd[1]: Started Load/Save Screen Backlight Brightness of backlight:rpi_backlight. Apr 19 19:53:36 switje systemd[1]: plymouth-start.service: Succeeded. Apr 19 19:53:36 switje systemd[1]: Started Show Plymouth Boot Screen. Apr 19 19:53:36 switje systemd[1]: Started File System Check Daemon to report status. Apr 19 19:53:36 switje systemd[1]: Starting Load/Save RF Kill Switch Status... Apr 19 19:53:36 switje systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Apr 19 19:53:36 switje systemd[1]: Started Load/Save RF Kill Switch Status. Apr 19 19:53:36 switje systemd-fsck[513]: fsck.fat 4.1 (2017-01-24) Apr 19 19:53:36 switje systemd-fsck[513]: 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Apr 19 19:53:36 switje systemd-fsck[513]: Automatically removing dirty bit. Apr 19 19:53:36 switje systemd-fsck[513]: Performing changes. Apr 19 19:53:36 switje systemd-fsck[513]: /dev/mmcblk0p1: 368 files, 134158/184582 clusters Apr 19 19:53:36 switje systemd[1]: Started File System Check on /dev/disk/by-uuid/9C48-E5A3. Apr 19 19:53:36 switje systemd[1]: Mounting /boot... Apr 19 19:53:36 switje systemd[1]: Mounted /boot. Apr 19 19:53:36 switje systemd[1]: Reached target Local File Systems. Apr 19 19:53:36 switje systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Apr 19 19:53:36 switje systemd[1]: Starting Create Volatile Files and Directories... Apr 19 19:53:36 switje systemd[1]: Started ifup for wlan0. Apr 19 19:53:36 switje systemd[1]: Started ifup for eth0. Apr 19 19:53:36 switje systemd[1]: Starting Raise network interfaces... Apr 19 19:53:36 switje systemd[1]: Starting Preprocess NFS configuration... Apr 19 19:53:36 switje systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Apr 19 19:53:36 switje systemd[1]: plymouth-read-write.service: Succeeded. Apr 19 19:53:36 switje systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Apr 19 19:53:36 switje systemd[1]: Received SIGRTMIN+20 from PID 213 (plymouthd). Apr 19 19:53:36 switje systemd[1]: nfs-config.service: Succeeded. Apr 19 19:53:36 switje systemd[1]: Started Preprocess NFS configuration. Apr 19 19:53:36 switje systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Apr 19 19:53:36 switje systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Apr 19 19:53:36 switje systemd[1]: Reached target NFS client services. Apr 19 19:53:36 switje systemd[1]: Started Create Volatile Files and Directories. Apr 19 19:53:36 switje systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Apr 19 19:53:36 switje systemd[1]: Starting RPC bind portmap service... Apr 19 19:53:36 switje systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Apr 19 19:53:36 switje systemd[1]: Starting Update UTMP about System Boot/Shutdown... Apr 19 19:53:36 switje kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 19 19:53:36 switje kernel: 8021q: 802.1Q VLAN Support v1.8 Apr 19 19:53:36 switje systemd[1]: Started Update UTMP about System Boot/Shutdown. Apr 19 19:53:36 switje systemd[1]: Reached target System Initialization. Apr 19 19:53:36 switje systemd[1]: man-db.timer: Not using persistent file timestamp Sat 2025-04-26 18:28:43 CEST as it is in the future. Apr 19 19:53:36 switje systemd[1]: Started Daily man-db regeneration. Apr 19 19:53:36 switje systemd[1]: Listening on triggerhappy.socket. Apr 19 19:53:36 switje systemd[1]: apt-daily.timer: Not using persistent file timestamp Sat 2025-04-26 18:28:43 CEST as it is in the future. Apr 19 19:53:36 switje systemd[1]: Started Daily apt download activities. Apr 19 19:53:36 switje systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Sat 2025-04-26 18:28:43 CEST as it is in the future. Apr 19 19:53:36 switje systemd[1]: Started Daily apt upgrade and clean activities. Apr 19 19:53:36 switje systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Apr 19 19:53:36 switje systemd[1]: Started Daily Cleanup of Temporary Directories. Apr 19 19:53:36 switje systemd[1]: Reached target Timers. Apr 19 19:53:37 switje systemd[1]: Listening on D-Bus System Message Bus Socket. Apr 19 19:53:37 switje systemd[1]: Reached target Sockets. Apr 19 19:53:37 switje systemd[1]: Reached target Basic System. Apr 19 19:53:37 switje systemd[1]: Starting Wireless Services... Apr 19 19:53:37 switje systemd[1]: Started Manage Sound Card State (restore and store). Apr 19 19:53:37 switje systemd[1]: Started UPnP Renderer front-end to MPD. Apr 19 19:53:37 switje alsactl[628]: alsactl 1.1.8 daemon started Apr 19 19:53:37 switje systemd[1]: Starting Save/Restore Sound Card State... Apr 19 19:53:37 switje systemd[1]: Started Volumio Log Rotation Service. Apr 19 19:53:37 switje systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Apr 19 19:53:37 switje systemd[1]: Starting dhcpcd on all interfaces... Apr 19 19:53:37 switje systemd[1]: Starting Configure Bluetooth Modems connected by UART... Apr 19 19:53:37 switje systemd[1]: Started D-Bus System Message Bus. Apr 19 19:53:37 switje dhcpcd[636]: Not running dhcpcd because /etc/network/interfaces Apr 19 19:53:37 switje dhcpcd[636]: defines some interfaces that will use a Apr 19 19:53:37 switje dhcpcd[636]: DHCP client or static address Apr 19 19:53:37 switje systemd[1]: Starting WPA supplicant... Apr 19 19:53:37 switje systemd[1]: Started Volumio Iptables Module. Apr 19 19:53:37 switje systemd[1]: Started volumio-remote-updater.service. Apr 19 19:53:37 switje systemd[1]: Starting Login Service... Apr 19 19:53:37 switje systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Apr 19 19:53:37 switje systemd[1]: Starting triggerhappy global hotkey daemon... Apr 19 19:53:37 switje systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Apr 19 19:53:37 switje systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Apr 19 19:53:37 switje systemd[1]: Reached target Login Prompts. Apr 19 19:53:37 switje systemd[1]: Started RPC bind portmap service. Apr 19 19:53:37 switje systemd[1]: Started Save/Restore Sound Card State. Apr 19 19:53:37 switje systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Apr 19 19:53:37 switje systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Apr 19 19:53:37 switje systemd[1]: Failed to start dhcpcd on all interfaces. Apr 19 19:53:37 switje systemd[1]: Reached target Sound Card. Apr 19 19:53:37 switje systemd[1]: Reached target Remote File Systems (Pre). Apr 19 19:53:37 switje systemd[1]: Reached target Remote File Systems. Apr 19 19:53:37 switje systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Apr 19 19:53:37 switje systemd[1]: Reached target RPC Port Mapper. Apr 19 19:53:37 switje sh[528]: wlan0=wlan0 Apr 19 19:53:37 switje thd[651]: Unable to parse trigger line: Apr 19 19:53:37 switje thd[651]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Apr 19 19:53:37 switje thd[651]: Unable to parse trigger line: Apr 19 19:53:37 switje thd[651]: Found socket passed from systemd Apr 19 19:53:37 switje kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Apr 19 19:53:37 switje sh[529]: eth0: waiting for carrier Apr 19 19:53:37 switje dhcpcd[575]: eth0: waiting for carrier Apr 19 19:53:37 switje kernel: bcmgenet fd580000.ethernet eth0: Link is Down Apr 19 19:53:37 switje systemd-logind[646]: New seat seat0. Apr 19 19:53:37 switje avahi-daemon[648]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Apr 19 19:53:37 switje avahi-daemon[648]: Successfully dropped root privileges. Apr 19 19:53:37 switje avahi-daemon[648]: avahi-daemon 0.7 starting up. Apr 19 19:53:38 switje systemd[1]: Started Login Service. Apr 19 19:53:38 switje systemd[1]: Started triggerhappy global hotkey daemon. Apr 19 19:53:38 switje avahi-daemon[648]: Successfully called chroot(). Apr 19 19:53:38 switje avahi-daemon[648]: Successfully dropped remaining capabilities. Apr 19 19:53:38 switje systemd[1]: hciuart.service: Succeeded. Apr 19 19:53:38 switje wpa_supplicant[642]: Successfully initialized wpa_supplicant Apr 19 19:53:38 switje systemd[1]: Started Configure Bluetooth Modems connected by UART. Apr 19 19:53:38 switje avahi-daemon[648]: Loading service file /services/volumio.service. Apr 19 19:53:38 switje avahi-daemon[648]: Network interface enumeration completed. Apr 19 19:53:38 switje avahi-daemon[648]: Server startup complete. Host name is switje.local. Local service cookie is 3231456833. Apr 19 19:53:38 switje avahi-daemon[648]: Service "Switje" (/services/volumio.service) successfully established. Apr 19 19:53:38 switje systemd[1]: Started Avahi mDNS/DNS-SD Stack. Apr 19 19:53:38 switje systemd[1]: Started WPA supplicant. Apr 19 19:53:38 switje systemd[1]: Starting Raspberry Pi bluetooth helper... Apr 19 19:53:38 switje systemd[1]: Started Raise network interfaces. Apr 19 19:53:38 switje systemd[1]: Reached target Network. Apr 19 19:53:38 switje systemd[1]: Starting Network Time Service... Apr 19 19:53:38 switje systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Apr 19 19:53:38 switje systemd[1]: Starting OpenBSD Secure Shell server... Apr 19 19:53:38 switje systemd[1]: Starting Permit User Sessions... Apr 19 19:53:38 switje systemd[1]: Reached target Network is Online. Apr 19 19:53:38 switje systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 19 19:53:38 switje systemd[1]: Starting Samba NMB Daemon... Apr 19 19:53:38 switje systemd[1]: Starting /etc/rc.local Compatibility... Apr 19 19:53:38 switje systemd[1]: Starting LSB: Brings up/down network automatically... Apr 19 19:53:38 switje bthelper[707]: Raspberry Pi BDADDR already set Apr 19 19:53:38 switje systemd[1]: Started Permit User Sessions. Apr 19 19:53:38 switje volumio-remote-updater[645]: Error: No active session Apr 19 19:53:38 switje volumio-remote-updater[645]: [2025-04-19 19:53:38] [info] asio async_connect error: system:111 (Connection refused) Apr 19 19:53:38 switje volumio-remote-updater[645]: [2025-04-19 19:53:38] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 19 19:53:38 switje systemd[1]: Started Raspberry Pi bluetooth helper. Apr 19 19:53:38 switje volumio-remote-updater[645]: [2025-04-19 19:53:38] [error] handle_connect error: Underlying Transport Error Apr 19 19:53:38 switje systemd[1]: Started /etc/rc.local Compatibility. Apr 19 19:53:38 switje systemd[1]: Starting Bluetooth service... Apr 19 19:53:39 switje systemd[1]: Starting Hold until boot process finishes up... Apr 19 19:53:39 switje systemd[1]: Starting Terminate Plymouth Boot Screen... Apr 19 19:53:39 switje volumio[629]: Could not open config: /tmp/upmpdcli.conf Apr 19 19:53:39 switje systemd[1]: Received SIGRTMIN+21 from PID 213 (plymouthd). Apr 19 19:53:39 switje systemd[1]: Received SIGRTMIN+21 from PID 213 (plymouthd). Apr 19 19:53:39 switje systemd[1]: plymouth-quit.service: Succeeded. Apr 19 19:53:39 switje systemd[1]: Started Terminate Plymouth Boot Screen. Apr 19 19:53:39 switje systemd[1]: plymouth-quit-wait.service: Succeeded. Apr 19 19:53:39 switje systemd[1]: Started Hold until boot process finishes up. Apr 19 19:53:39 switje loadcpufreq[656]: Loading cpufreq kernel modules...done (none). Apr 19 19:53:39 switje systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 19 19:53:39 switje systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 19 19:53:39 switje ntpd[714]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 19 19:53:39 switje ntpd[714]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Apr 19 19:53:39 switje ntpd[770]: proto: precision = 2.148 usec (-19) Apr 19 19:53:39 switje systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Apr 19 19:53:39 switje systemd[1]: Started Network Time Service. Apr 19 19:53:39 switje ntpd[770]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 19 19:53:39 switje ntpd[770]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 19 19:53:39 switje ntpd[770]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 113 days ago Apr 19 19:53:39 switje ntpd[770]: Listen and drop on 0 v6wildcard [::]:123 Apr 19 19:53:39 switje ntpd[770]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 19 19:53:39 switje ntpd[770]: Listen normally on 2 lo 127.0.0.1:123 Apr 19 19:53:39 switje ntpd[770]: Listening on routing socket on fd #19 for interface updates Apr 19 19:53:39 switje ntpd[770]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 19 19:53:39 switje ntpd[770]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 19 19:53:39 switje systemd[1]: iptables.service: Succeeded. Apr 19 19:53:39 switje ifplugd(eth0)[779]: ifplugd 0.28 initializing. Apr 19 19:53:39 switje ifplugd(eth0)[779]: Using interface eth0/D8:3A:DD:FC:FD:58 with driver (version: 6.1.69-v7l+) Apr 19 19:53:39 switje ifplugd(eth0)[779]: Using detection mode: SIOCETHTOOL Apr 19 19:53:39 switje ifplugd(eth0)[779]: Initialization complete, link beat not detected. Apr 19 19:53:39 switje systemd[1]: Starting LSB: set CPUFreq kernel parameters... Apr 19 19:53:39 switje sshd[775]: Server listening on 0.0.0.0 port 22. Apr 19 19:53:39 switje sshd[775]: Server listening on :: port 22. Apr 19 19:53:39 switje systemd[1]: Started OpenBSD Secure Shell server. Apr 19 19:53:39 switje ifplugd[733]: Network Interface Plugging Daemon...start eth0...done. Apr 19 19:53:39 switje systemd[1]: Started LSB: Brings up/down network automatically. Apr 19 19:53:39 switje bluetoothd[757]: Bluetooth daemon 5.50 Apr 19 19:53:39 switje bluetoothd[757]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Apr 19 19:53:39 switje systemd[1]: Started Bluetooth service. Apr 19 19:53:39 switje systemd[1]: Reached target Bluetooth. Apr 19 19:53:39 switje bluetoothd[757]: Starting SDP server Apr 19 19:53:39 switje bluetoothd[757]: Excluding (cli) sap Apr 19 19:53:39 switje cpufrequtils[780]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Apr 19 19:53:39 switje systemd[1]: Started LSB: set CPUFreq kernel parameters. Apr 19 19:53:39 switje kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Apr 19 19:53:39 switje kernel: Bluetooth: BNEP filters: protocol multicast Apr 19 19:53:39 switje kernel: Bluetooth: BNEP socket layer initialized Apr 19 19:53:39 switje dbus-daemon[640]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=757 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Apr 19 19:53:39 switje bluetoothd[757]: Bluetooth management interface 1.22 initialized Apr 19 19:53:39 switje kernel: Bluetooth: MGMT ver 1.22 Apr 19 19:53:39 switje bluetoothd[757]: Failed to set privacy: Rejected (0x0b) Apr 19 19:53:39 switje systemd[1]: Starting Hostname Service... Apr 19 19:53:40 switje dbus-daemon[640]: [system] Successfully activated service 'org.freedesktop.hostname1' Apr 19 19:53:40 switje systemd[1]: Started Hostname Service. Apr 19 19:53:40 switje haveged[565]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Apr 19 19:53:40 switje haveged[565]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Apr 19 19:53:40 switje haveged[565]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99954 Apr 19 19:53:40 switje haveged[565]: haveged: fills: 0, generated: 0 Apr 19 19:53:40 switje nmbd[741]: [2025/04/19 19:53:40.172979, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Apr 19 19:53:40 switje nmbd[741]: started asyncdns process 802 Apr 19 19:53:40 switje nmbd[741]: [2025/04/19 19:53:40.176091, 0] ../lib/util/become_daemon.c:149(daemon_status) Apr 19 19:53:40 switje nmbd[741]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Apr 19 19:53:40 switje nmbd[741]: [2025/04/19 19:53:40.176322, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Apr 19 19:53:40 switje nmbd[741]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Apr 19 19:53:40 switje ntpd[770]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 19 19:53:40 switje wireless.js[617]: Cleaning previous... Apr 19 19:53:40 switje sudo[810]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 19 19:53:40 switje sudo[810]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:53:40 switje sudo[810]: pam_unix(sudo:session): session closed for user root Apr 19 19:53:40 switje sudo[812]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 19 19:53:40 switje sudo[812]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:53:41 switje sudo[812]: pam_unix(sudo:session): session closed for user root Apr 19 19:53:41 switje wireless.js[617]: Stopped aP Apr 19 19:53:41 switje sudo[819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 19 19:53:41 switje sudo[819]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:53:41 switje sudo[819]: pam_unix(sudo:session): session closed for user root Apr 19 19:53:41 switje kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 19 19:53:41 switje sudo[821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Apr 19 19:53:41 switje sudo[821]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:53:41 switje sudo[821]: pam_unix(sudo:session): session closed for user root Apr 19 19:53:41 switje sudo[828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 19 19:53:41 switje ntpd[770]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Apr 19 19:53:41 switje sudo[828]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:53:41 switje sudo[828]: pam_unix(sudo:session): session closed for user root Apr 19 19:53:41 switje sudo[830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Apr 19 19:53:41 switje sudo[830]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:53:41 switje systemd[1]: systemd-rfkill.service: Succeeded. Apr 19 19:53:42 switje ntpd[770]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Apr 19 19:53:43 switje ntpd[770]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Apr 19 19:53:43 switje sudo[830]: pam_unix(sudo:session): session closed for user root Apr 19 19:53:43 switje wireless.js[617]: SETTING APPROPRIATE REG DOMAIN: BE Apr 19 19:53:43 switje sudo[836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 19 19:53:43 switje sudo[836]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:53:43 switje sudo[836]: pam_unix(sudo:session): session closed for user root Apr 19 19:53:43 switje bthelper[707]: Changing power off succeeded Apr 19 19:53:43 switje bthelper[707]: [CHG] Controller D8:3A:DD:FC:FD:5A Powered: no Apr 19 19:53:43 switje bthelper[707]: [CHG] Controller D8:3A:DD:FC:FD:5A Discovering: no Apr 19 19:53:43 switje bthelper[707]: [CHG] Controller D8:3A:DD:FC:FD:5A Class: 0x00000000 Apr 19 19:53:43 switje sudo[838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set BE Apr 19 19:53:43 switje sudo[838]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:53:43 switje sudo[838]: pam_unix(sudo:session): session closed for user root Apr 19 19:53:43 switje wireless.js[617]: SUCCESSFULLY SET NEW REGDOMAIN: BE Apr 19 19:53:43 switje wireless.js[617]: Start wireless flow Apr 19 19:53:43 switje wireless.js[617]: Stopped hotspot (if there).. Apr 19 19:53:43 switje volumio-remote-updater[645]: [2025-04-19 19:53:43] [info] asio async_connect error: system:111 (Connection refused) Apr 19 19:53:43 switje volumio-remote-updater[645]: [2025-04-19 19:53:43] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 19 19:53:43 switje volumio-remote-updater[645]: [2025-04-19 19:53:43] [error] handle_connect error: Underlying Transport Error Apr 19 19:53:43 switje sudo[843]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 19 19:53:43 switje sudo[843]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:53:43 switje sudo[843]: pam_unix(sudo:session): session closed for user root Apr 19 19:53:44 switje sudo[845]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 19 19:53:44 switje sudo[845]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:53:44 switje bthelper[707]: [CHG] Controller D8:3A:DD:FC:FD:5A Class: 0x0000041c Apr 19 19:53:44 switje bthelper[707]: Changing power on succeeded Apr 19 19:53:44 switje sudo[845]: pam_unix(sudo:session): session closed for user root Apr 19 19:53:44 switje wireless.js[617]: DHCP IP Apr 19 19:53:44 switje wireless.js[617]: Start ap Apr 19 19:53:44 switje wpa_supplicant[853]: Successfully initialized wpa_supplicant Apr 19 19:53:44 switje sudo[854]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Apr 19 19:53:44 switje sudo[854]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:53:44 switje kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 19 19:53:44 switje dhcpcd[855]: dev: loaded udev Apr 19 19:53:44 switje dhcpcd-run-hooks[873]: wlan0: starting wpa_supplicant Apr 19 19:53:44 switje dhcpcd-run-hooks[878]: wlan0: failed to start wpa_supplicant Apr 19 19:53:44 switje dhcpcd-run-hooks[879]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Apr 19 19:53:44 switje dhcpcd[855]: wlan0: connected to Access Point `' Apr 19 19:53:44 switje dhcpcd[855]: no interfaces have a carrier Apr 19 19:53:44 switje dhcpcd[855]: forked to background, child pid 889 Apr 19 19:53:44 switje dhcpcd[889]: eth0: waiting for carrier Apr 19 19:53:44 switje dhcpcd[889]: wlan0: waiting for carrier Apr 19 19:53:44 switje sudo[854]: pam_unix(sudo:session): session closed for user root Apr 19 19:53:45 switje wireless.js[617]: trying... Apr 19 19:53:45 switje sudo[891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 19:53:45 switje sudo[891]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:53:45 switje sudo[891]: pam_unix(sudo:session): session closed for user root Apr 19 19:53:46 switje wireless.js[617]: trying... Apr 19 19:53:46 switje sudo[894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 19:53:46 switje sudo[894]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:53:46 switje sudo[894]: pam_unix(sudo:session): session closed for user root Apr 19 19:53:47 switje wpa_supplicant[877]: wlan0: Trying to associate with SSID 'Thuis' Apr 19 19:53:47 switje wireless.js[617]: trying... Apr 19 19:53:47 switje sudo[897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 19:53:47 switje sudo[897]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:53:47 switje sudo[897]: pam_unix(sudo:session): session closed for user root Apr 19 19:53:48 switje wireless.js[617]: trying... Apr 19 19:53:48 switje sudo[900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 19:53:48 switje sudo[900]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:53:48 switje sudo[900]: pam_unix(sudo:session): session closed for user root Apr 19 19:53:49 switje wireless.js[617]: trying... Apr 19 19:53:49 switje sudo[903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 19:53:49 switje sudo[903]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:53:49 switje sudo[903]: pam_unix(sudo:session): session closed for user root Apr 19 19:53:50 switje dhcpcd[889]: wlan0: carrier acquired Apr 19 19:53:50 switje dhcpcd[889]: wlan0: connected to Access Point `Thuis' Apr 19 19:53:50 switje wpa_supplicant[877]: wlan0: Associated with de:b3:70:35:bf:45 Apr 19 19:53:50 switje wpa_supplicant[877]: wlan0: CTRL-EVENT-CONNECTED - Connection to de:b3:70:35:bf:45 completed [id=0 id_str=] Apr 19 19:53:50 switje wpa_supplicant[877]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 19 19:53:50 switje wpa_supplicant[877]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=BE Apr 19 19:53:50 switje dhcpcd[889]: DUID 00:01:00:01:2e:7c:57:97:d8:3a:dd:fc:fd:58 Apr 19 19:53:50 switje dhcpcd[889]: wlan0: IAID dd:fc:fd:59 Apr 19 19:53:50 switje dhcpcd[889]: wlan0: adding address fe80::b795:b5b9:e544:dafc Apr 19 19:53:50 switje dhcpcd[889]: ipv6_addaddr1: Permission denied Apr 19 19:53:50 switje dhcpcd[889]: wlan0: carrier lost Apr 19 19:53:50 switje dhcpcd[889]: wlan0: carrier acquired Apr 19 19:53:50 switje dhcpcd[889]: wlan0: IAID dd:fc:fd:59 Apr 19 19:53:50 switje wireless.js[617]: trying... Apr 19 19:53:50 switje sudo[918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 19:53:50 switje sudo[918]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:53:50 switje sudo[918]: pam_unix(sudo:session): session closed for user root Apr 19 19:53:50 switje dhcpcd[889]: wlan0: rebinding lease of 10.2.10.81 Apr 19 19:53:50 switje dhcpcd[889]: wlan0: probing address 10.2.10.81/24 Apr 19 19:53:50 switje dhcpcd[889]: wlan0: soliciting an IPv6 router Apr 19 19:53:51 switje volumio-remote-updater[645]: [2025-04-19 19:53:51] [info] asio async_connect error: system:111 (Connection refused) Apr 19 19:53:51 switje volumio-remote-updater[645]: [2025-04-19 19:53:51] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 19 19:53:51 switje volumio-remote-updater[645]: [2025-04-19 19:53:51] [error] handle_connect error: Underlying Transport Error Apr 19 19:53:51 switje wireless.js[617]: trying... Apr 19 19:53:51 switje sudo[921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 19:53:51 switje sudo[921]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:53:51 switje sudo[921]: pam_unix(sudo:session): session closed for user root Apr 19 19:53:52 switje wireless.js[617]: trying... Apr 19 19:53:52 switje sudo[924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 19:53:52 switje sudo[924]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:53:52 switje sudo[924]: pam_unix(sudo:session): session closed for user root Apr 19 19:53:53 switje wireless.js[617]: trying... Apr 19 19:53:53 switje sudo[927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 19:53:53 switje sudo[927]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:53:53 switje sudo[927]: pam_unix(sudo:session): session closed for user root Apr 19 19:53:54 switje wireless.js[617]: trying... Apr 19 19:53:54 switje sudo[930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 19:53:54 switje sudo[930]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:53:54 switje sudo[930]: pam_unix(sudo:session): session closed for user root Apr 19 19:53:55 switje wireless.js[617]: trying... Apr 19 19:53:55 switje sudo[933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 19:53:55 switje sudo[933]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:53:55 switje sudo[933]: pam_unix(sudo:session): session closed for user root Apr 19 19:53:55 switje dhcpcd[889]: wlan0: leased 10.2.10.81 for 86400 seconds Apr 19 19:53:55 switje avahi-daemon[648]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.2.10.81. Apr 19 19:53:55 switje avahi-daemon[648]: New relevant interface wlan0.IPv4 for mDNS. Apr 19 19:53:55 switje dhcpcd[889]: wlan0: adding route to 10.2.10.0/24 Apr 19 19:53:55 switje avahi-daemon[648]: Registering new address record for 10.2.10.81 on wlan0.IPv4. Apr 19 19:53:55 switje dhcpcd[889]: wlan0: adding default route via 10.2.10.254 Apr 19 19:53:56 switje wireless.js[617]: trying... Apr 19 19:53:56 switje sudo[957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 19:53:56 switje sudo[957]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:53:56 switje sudo[957]: pam_unix(sudo:session): session closed for user root Apr 19 19:53:56 switje wireless.js[617]: Connected to: ----Thuis Apr 19 19:53:56 switje wireless.js[617]: ---- Apr 19 19:53:56 switje sudo[960]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 19:53:56 switje sudo[960]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:53:56 switje sudo[960]: pam_unix(sudo:session): session closed for user root Apr 19 19:53:56 switje wireless.js[617]: ... joined AP, wlan0 IPv4 is 10.2.10.81, ipV6 is undefined Apr 19 19:53:56 switje wireless.js[617]: It's done! AP Apr 19 19:53:56 switje systemd[1]: Started Wireless Services. Apr 19 19:53:56 switje systemd[1]: Started Volumio Backend Module. Apr 19 19:53:56 switje kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 19 19:53:56 switje systemd[1]: Started Volumio Cpu Tweaker. Apr 19 19:53:56 switje volumio-cpu-tweak[966]: Setting RT Priority for mpd Apr 19 19:53:56 switje volumio-cpu-tweak[966]: pid 35's current scheduling policy: SCHED_OTHER Apr 19 19:53:56 switje volumio-cpu-tweak[966]: pid 35's current scheduling priority: 0 Apr 19 19:53:56 switje volumio-cpu-tweak[966]: Setting MPD Affinity Apr 19 19:53:56 switje volumio-cpu-tweak[966]: pid 3's current affinity mask: f Apr 19 19:53:56 switje volumio-cpu-tweak[966]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Apr 19 19:53:56 switje volumio-cpu-tweak[966]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Apr 19 19:53:56 switje systemd[1]: volumio_cpu_tweak.service: Succeeded. Apr 19 19:53:57 switje ntpd[770]: Listen normally on 3 wlan0 10.2.10.81:123 Apr 19 19:53:57 switje ntpd[770]: new interface(s) found: waking up resolver Apr 19 19:53:58 switje volumio[965]: info: ------------------------------------------- Apr 19 19:53:58 switje volumio[965]: info: ----- Volumio3 ---- Apr 19 19:53:58 switje volumio[965]: info: ------------------------------------------- Apr 19 19:53:58 switje volumio[965]: info: ----- System startup ---- Apr 19 19:53:58 switje volumio[965]: info: ------------------------------------------- Apr 19 19:53:59 switje volumio[965]: info: MYVOLUMIO Environment detected Apr 19 19:53:59 switje volumio[965]: info: Plugin folders cleanup Apr 19 19:53:59 switje volumio[965]: info: Scanning into folder /volumio/app/plugins/ Apr 19 19:53:59 switje volumio[965]: info: Scanning category audio_interface Apr 19 19:53:59 switje volumio[965]: info: Scanning category miscellanea Apr 19 19:53:59 switje volumio[965]: info: Scanning category music_service Apr 19 19:53:59 switje volumio[965]: info: Scanning category plugins.json Apr 19 19:53:59 switje volumio[965]: info: Scanning category system_controller Apr 19 19:53:59 switje volumio[965]: info: Scanning category user_interface Apr 19 19:53:59 switje volumio[965]: info: Scanning into folder /data/plugins/ Apr 19 19:53:59 switje volumio[965]: info: Scanning category music_service Apr 19 19:53:59 switje volumio[965]: info: Scanning category user_interface Apr 19 19:53:59 switje volumio[965]: info: Plugin folders cleanup completed Apr 19 19:53:59 switje volumio[965]: info: ------------------------------------------- Apr 19 19:53:59 switje volumio[965]: info: ----- Core plugins startup ---- Apr 19 19:53:59 switje volumio[965]: info: ------------------------------------------- Apr 19 19:53:59 switje volumio[965]: info: Loading plugins from folder /volumio/app/plugins/ Apr 19 19:53:59 switje volumio[965]: info: Adding plugin upnp to MyMusic Plugins Apr 19 19:53:59 switje volumio[965]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 19 19:53:59 switje volumio[965]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 19 19:53:59 switje volumio[965]: info: Loading plugins from folder /data/plugins/ Apr 19 19:53:59 switje volumio[965]: info: Loading plugin "system"... Apr 19 19:53:59 switje volumio[965]: info: Loading plugin "appearance"... Apr 19 19:54:00 switje nmbd[741]: [2025/04/19 19:54:00.180458, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 19 19:54:00 switje systemd[1]: Started Samba NMB Daemon. Apr 19 19:54:00 switje nmbd[741]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 19 19:54:00 switje systemd[1]: Starting Samba Winbind Daemon... Apr 19 19:54:00 switje winbindd[995]: [2025/04/19 19:54:00.450807, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 19 19:54:00 switje winbindd[995]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 19 19:54:00 switje winbindd[995]: [2025/04/19 19:54:00.472462, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 19 19:54:00 switje systemd[1]: Started Samba Winbind Daemon. Apr 19 19:54:00 switje winbindd[995]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 19 19:54:00 switje systemd[1]: Starting Samba SMB Daemon... Apr 19 19:54:00 switje volumio[965]: info: Loading plugin "network"... Apr 19 19:54:00 switje volumio[965]: info: Refreshing Cached IP Addresses Apr 19 19:54:00 switje sudo[1002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 19:54:00 switje sudo[1002]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:54:00 switje volumio[965]: info: Loading plugin "services"... Apr 19 19:54:00 switje sudo[1002]: pam_unix(sudo:session): session closed for user root Apr 19 19:54:00 switje sudo[1007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 19 19:54:00 switje sudo[1005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 19:54:00 switje sudo[1007]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:54:00 switje sudo[1005]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:54:00 switje volumio[965]: info: Loading plugin "alsa_controller"... Apr 19 19:54:00 switje sudo[1005]: pam_unix(sudo:session): session closed for user root Apr 19 19:54:00 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 19:54:00 switje volumio[965]: info: Loading plugin "wizard"... Apr 19 19:54:00 switje volumio[965]: info: Loading plugin "networkfs"... Apr 19 19:54:00 switje volumio[965]: info: Starting Udev Watcher for removable devices Apr 19 19:54:00 switje sudo[1018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=lodewijk,password=Landmeters.12,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.2.10.110/music /mnt/NAS/switje Apr 19 19:54:00 switje sudo[1018]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:54:00 switje volumio[965]: info: Ignoring mount for partition: boot Apr 19 19:54:00 switje volumio[965]: info: Ignoring mount for partition: volumio Apr 19 19:54:00 switje volumio[965]: info: Ignoring mount for partition: volumio_data Apr 19 19:54:00 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 19 19:54:00 switje volumio[965]: info: Loading plugin "volumio_command_line_client"... Apr 19 19:54:00 switje volumio[965]: info: Loading plugin "upnp"... Apr 19 19:54:00 switje volumio[965]: info: [1745085240878] Starting Upmpd Daemon Apr 19 19:54:00 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 19 19:54:00 switje volumio[965]: info: Loading plugin "my_music"... Apr 19 19:54:00 switje volumio[965]: info: Loading plugin "mpd"... Apr 19 19:54:00 switje winbindd[995]: [2025/04/19 19:54:00.911751, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Apr 19 19:54:00 switje winbindd[995]: res_names->count = 3, expected 4 Apr 19 19:54:00 switje smbd[1000]: [2025/04/19 19:54:00.930817, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 19 19:54:00 switje systemd[1]: Started Samba SMB Daemon. Apr 19 19:54:00 switje smbd[1000]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 19 19:54:00 switje systemd[1]: Reached target Multi-User System. Apr 19 19:54:00 switje systemd[1]: Reached target Graphical Interface. Apr 19 19:54:00 switje systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 19 19:54:01 switje kernel: Key type cifs.spnego registered Apr 19 19:54:01 switje kernel: Key type cifs.idmap registered Apr 19 19:54:01 switje 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. Apr 19 19:54:01 switje kernel: CIFS: Attempting to mount \\10.2.10.110\music Apr 19 19:54:01 switje systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 19 19:54:01 switje systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 19 19:54:01 switje systemd[1]: Startup finished in 14.537s (kernel) + 29.727s (userspace) = 44.265s. Apr 19 19:54:01 switje volumio[965]: info: Loading plugin "upnp_browser"... Apr 19 19:54:01 switje volumio-remote-updater[645]: [2025-04-19 19:54:01] [connect] Successful connection Apr 19 19:54:01 switje volumio[965]: info: Loading plugin "alarm-clock"... Apr 19 19:54:01 switje volumio[965]: info: Loading plugin "airplay_emulation"... Apr 19 19:54:01 switje volumio[965]: info: Starting Shairport Sync Apr 19 19:54:01 switje volumio[965]: info: Loading plugin "last_100"... Apr 19 19:54:01 switje volumio[965]: info: Loading plugin "webradio"... Apr 19 19:54:01 switje volumio[965]: info: Loading plugin "i2s_dacs"... Apr 19 19:54:01 switje volumio[965]: info: I2S DAC not set, start Auto-detection Apr 19 19:54:01 switje volumio[965]: info: Loading plugin "volumiodiscovery"... Apr 19 19:54:01 switje volumio[965]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 19 19:54:01 switje volumio[965]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 19:54:01 switje node[965]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 19 19:54:01 switje volumio[965]: *** WARNING *** For more information see Apr 19 19:54:01 switje volumio[965]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 19 19:54:01 switje volumio[965]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 19:54:01 switje volumio[965]: *** WARNING *** For more information see Apr 19 19:54:01 switje node[965]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 19:54:01 switje node[965]: *** WARNING *** For more information see Apr 19 19:54:01 switje node[965]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 19 19:54:01 switje node[965]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 19 19:54:01 switje node[965]: *** WARNING *** For more information see Apr 19 19:54:01 switje volumio[965]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 19 19:54:01 switje volumio[965]: info: Discovery: Started advertising with name: Switje Apr 19 19:54:01 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 19 19:54:01 switje volumio[965]: info: Loading plugin "spop"... Apr 19 19:54:01 switje sudo[1018]: pam_unix(sudo:session): session closed for user root Apr 19 19:54:02 switje volumio[965]: info: Loading plugin "now_playing"... Apr 19 19:54:03 switje volumio[965]: info: Loading plugin "outputs"... Apr 19 19:54:03 switje volumio[965]: info: Loading plugin "albumart"... Apr 19 19:54:03 switje volumio[965]: info: Plugin example_plugin is not enabled Apr 19 19:54:03 switje volumio[965]: info: Loading plugin "inputs"... Apr 19 19:54:03 switje volumio[965]: info: Loading plugin "updater_comm"... Apr 19 19:54:04 switje volumio[965]: info: Plugin mpdemulation is not enabled Apr 19 19:54:04 switje volumio[965]: info: Loading plugin "rest_api"... Apr 19 19:54:04 switje volumio[965]: info: Loading plugin "websocket"... Apr 19 19:54:04 switje volumio[965]: info: Starting Socket.io Server version 2.3.0 Apr 19 19:54:04 switje volumio[965]: info: Loading plugin "touch_display"... Apr 19 19:54:04 switje volumio[965]: Forking 3 albumart workers Apr 19 19:54:04 switje sudo[1007]: pam_unix(sudo:session): session closed for user root Apr 19 19:54:04 switje volumio[965]: info: Applying required configuration parameters for plugin touch_display Apr 19 19:54:04 switje volumio[965]: info: Loading i18n strings for locale nl Apr 19 19:54:04 switje volumio[965]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Apr 19 19:54:04 switje volumio[965]: Updating browse sources language Apr 19 19:54:04 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 19:54:04 switje volumio[965]: Starting albumart workers Apr 19 19:54:04 switje volumio[965]: Starting albumart workers Apr 19 19:54:04 switje volumio[965]: Starting albumart workers Apr 19 19:54:04 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 19:54:04 switje volumio[965]: info: CoreCommandRouter::initPlayerControls Apr 19 19:54:04 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 19:54:04 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 19:54:04 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 19:54:04 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 19:54:04 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 19:54:04 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 19:54:04 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 19:54:04 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 19:54:04 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 19:54:04 switje volumio[965]: Express server listening on port 3000 Apr 19 19:54:04 switje volumio[965]: [Metrics] WebUI: 6s 893.02ms Apr 19 19:54:04 switje volumio[965]: info: CoreStateMachine::resetVolumioState Apr 19 19:54:04 switje volumio[965]: info: CoreStateMachine::getcurrentVolume Apr 19 19:54:04 switje volumio[965]: info: CoreCommandRouter::volumioRetrievevolume Apr 19 19:54:04 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 19:54:05 switje volumio-remote-updater[645]: [2025-04-19 19:54:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745085241 101 Apr 19 19:54:05 switje volumio[965]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Apr 19 19:54:05 switje volumio[965]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 19 19:54:05 switje volumio[965]: info: Reloading queue from file Apr 19 19:54:05 switje volumio[965]: info: CoreStateMachine::setRepeat true single undefined Apr 19 19:54:05 switje volumio[965]: info: CoreStateMachine::pushState Apr 19 19:54:05 switje volumio[965]: info: CorePlayQueue::getTrack 0 Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::volumioPushState Apr 19 19:54:05 switje volumio[965]: info: CoreStateMachine::setRandom true Apr 19 19:54:05 switje volumio[965]: info: CoreStateMachine::pushState Apr 19 19:54:05 switje volumio[965]: info: CorePlayQueue::getTrack 0 Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::volumioPushState Apr 19 19:54:05 switje volumio[965]: info: Setting Device type: Raspberry PI Apr 19 19:54:05 switje volumio[965]: info: VolumeController:: Volume=33 Mute =false Apr 19 19:54:05 switje volumio[965]: info: CoreStateMachine::pushState Apr 19 19:54:05 switje volumio[965]: info: CorePlayQueue::getTrack 0 Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::volumioPushState Apr 19 19:54:05 switje volumio[965]: info: CoreStateMachine::updateTrackBlock Apr 19 19:54:05 switje volumio[965]: info: CorePlayQueue::getTrackBlock Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::volumioRetrievevolume Apr 19 19:54:05 switje volumio[965]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 19 19:54:05 switje volumio[965]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Apr 19 19:54:05 switje volumio[965]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 19 19:54:05 switje volumio[965]: info: Discovery: adding 7bf9cf3b-d2c0-48d9-9bc9-0a14ff74e840 Apr 19 19:54:05 switje volumio[965]: info: Discovery: Found device Switje Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::volumioGetState Apr 19 19:54:05 switje volumio[965]: info: CorePlayQueue::getTrack 0 Apr 19 19:54:05 switje volumio[965]: info: Completed loading Core Plugins Apr 19 19:54:05 switje volumio[965]: info: Preparing to generate the ALSA configuration file Apr 19 19:54:05 switje volumio[965]: info: VolumeController:: Volume=33 Mute =false Apr 19 19:54:05 switje volumio[965]: info: CoreStateMachine::pushState Apr 19 19:54:05 switje volumio[965]: info: CorePlayQueue::getTrack 0 Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::volumioPushState Apr 19 19:54:05 switje volumio[965]: info: Asound.conf file unchanged, so no further update is needed Apr 19 19:54:05 switje volumio[965]: info: Output device has changed, restarting MPD Apr 19 19:54:05 switje sudo[1115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 19:54:05 switje volumio[965]: info: Output device has changed, restarting Shairport Sync Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 19:54:05 switje sudo[1115]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:54:05 switje sudo[1115]: pam_unix(sudo:session): session closed for user root Apr 19 19:54:05 switje sudo[1117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 19:54:05 switje sudo[1117]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:54:05 switje volumio[965]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 19 19:54:05 switje volumio[965]: info: ___________ START PLUGINS ___________ Apr 19 19:54:05 switje volumio[965]: info: ControllerMpd::onStart: Initializing MPD Apr 19 19:54:05 switje volumio[965]: info: Creating MPD Configuration file Apr 19 19:54:05 switje sudo[1125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 19 19:54:05 switje sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 19:54:05 switje volumio[965]: info: [1745085245556] CoreMusicLibrary::Adding element Media Servers Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 19:54:05 switje sudo[1125]: pam_unix(sudo:session): session closed for user root Apr 19 19:54:05 switje sudo[1127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 19 19:54:05 switje sudo[1127]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:54:05 switje systemd[1]: Listening on mpd.socket. Apr 19 19:54:05 switje systemd[1]: Starting Music Player Daemon... Apr 19 19:54:05 switje systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 19 19:54:05 switje systemd[1]: mpd.service: Succeeded. Apr 19 19:54:05 switje systemd[1]: Stopped Music Player Daemon. Apr 19 19:54:05 switje systemd[1]: Starting Music Player Daemon... Apr 19 19:54:05 switje volumio[965]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 19:54:05 switje volumio[965]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 19:54:05 switje volumio[965]: info: [1745085245655] CoreMusicLibrary::Adding element Last_100 Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 19 19:54:05 switje volumio[965]: info: [1745085245658] CoreMusicLibrary::Adding element Webradio Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 19:54:05 switje volumio[965]: info: Initializing BBC Radios Apr 19 19:54:05 switje sudo[1134]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 19 19:54:05 switje sudo[1134]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:54:05 switje sudo[1134]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Apr 19 19:54:05 switje sudo[1134]: pam_unix(sudo:session): session closed for user root Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 19:54:05 switje volumio[965]: info: Creating Spotify config file Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 19:54:05 switje volumio[965]: info: [now-playing] ConfigUpdater: config is up to date. Apr 19 19:54:05 switje volumio[965]: info: Loading i18n strings for locale nl Apr 19 19:54:05 switje volumio[965]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Apr 19 19:54:05 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 19:54:05 switje volumio[965]: info: Volumio Calling Home Apr 19 19:54:05 switje sudo[1154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Apr 19 19:54:05 switje sudo[1154]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:54:05 switje sudo[1156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Apr 19 19:54:05 switje sudo[1156]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:54:05 switje sudo[1159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Apr 19 19:54:05 switje sudo[1159]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:54:05 switje volumio[965]: info: [now-playing] App is listening on port 4004. Apr 19 19:54:05 switje volumio[965]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Apr 19 19:54:05 switje systemd[1]: Reloading. Apr 19 19:54:06 switje volumio[965]: info: touch_display: Backlight interface detected. Apr 19 19:54:06 switje volumio[965]: info: MPD Permissions set Apr 19 19:54:06 switje volumio[965]: info: Volumio called home Apr 19 19:54:06 switje volumio[965]: info: Spotify config file written Apr 19 19:54:06 switje volumio[965]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Apr 19 19:54:06 switje sudo[1163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 19 19:54:06 switje sudo[1163]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:54:06 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 19:54:06 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 19:54:06 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 19:54:06 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 19:54:06 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 19:54:06 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 19:54:06 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 19:54:06 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 19:54:06 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 19:54:06 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 19:54:06 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 19:54:06 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 19:54:06 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 19:54:06 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 19:54:06 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 19 19:54:06 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 19 19:54:06 switje volumio[965]: info: No need to fix Spotify hosts Apr 19 19:54:06 switje volumio[965]: info: touch_display: Raspberry Pi Foundation touch screen detected. Apr 19 19:54:06 switje sudo[1187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Apr 19 19:54:06 switje sudo[1187]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:54:06 switje 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. Apr 19 19:54:06 switje sudo[1187]: pam_unix(sudo:session): session closed for user root Apr 19 19:54:06 switje volumio[965]: info: Starting Shairport Sync Apr 19 19:54:06 switje sudo[1190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/rpi_backlight/brightness Apr 19 19:54:06 switje sudo[1190]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:54:06 switje sudo[1190]: pam_unix(sudo:session): session closed for user root Apr 19 19:54:06 switje volumio[965]: info: Starting Shairport Sync Apr 19 19:54:06 switje sudo[1193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 19 19:54:06 switje sudo[1193]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:54:06 switje volumio[965]: info: Starting Shairport Sync Apr 19 19:54:06 switje sudo[1198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 19 19:54:06 switje sudo[1198]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:54:06 switje sudo[1196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 19 19:54:06 switje sudo[1196]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:54:06 switje volumio[965]: info: CoreCommandRouter::volumioGetState Apr 19 19:54:06 switje volumio[965]: info: CorePlayQueue::getTrack 0 Apr 19 19:54:06 switje volumio[965]: info: touch_display: File permissions for backlight brightness control set. Apr 19 19:54:06 switje volumio[965]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Apr 19 19:54:06 switje systemd[1]: ifup@eth0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Apr 19 19:54:06 switje systemd[1]: systemd-fsckd.service: Succeeded. Apr 19 19:54:06 switje sudo[1159]: pam_unix(sudo:session): session closed for user root Apr 19 19:54:06 switje volumio[965]: info: touch_display: systemctl daemon-reload succeeded. Apr 19 19:54:06 switje systemd[1]: Reloading. Apr 19 19:54:06 switje sudo[1202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Apr 19 19:54:06 switje sudo[1202]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:54:06 switje 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. Apr 19 19:54:07 switje sudo[1156]: pam_unix(sudo:session): session closed for user root Apr 19 19:54:07 switje volumio[965]: info: touch_display: systemctl disable getty@tty1.service succeeded. Apr 19 19:54:07 switje sudo[1154]: pam_unix(sudo:session): session closed for user root Apr 19 19:54:07 switje volumio[965]: info: touch_display: systemctl stop getty@tty1.service succeeded. Apr 19 19:54:07 switje systemd[1]: Started Volumio Kiosk. Apr 19 19:54:07 switje systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 19 19:54:07 switje sudo[1202]: pam_unix(sudo:session): session closed for user root Apr 19 19:54:07 switje systemd[1]: Started go-librespot Daemon. Apr 19 19:54:07 switje volumio[965]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Apr 19 19:54:07 switje volumio[965]: info: touch_display: Volumio Kiosk started. Apr 19 19:54:07 switje go-librespot[1219]: Librespot-go daemon starting... Apr 19 19:54:07 switje sudo[1163]: pam_unix(sudo:session): session closed for user root Apr 19 19:54:07 switje systemd[1]: shairport-sync.service: Succeeded. Apr 19 19:54:07 switje systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 19 19:54:07 switje volumio[965]: info: CoreCommandRouter::volumioGetState Apr 19 19:54:07 switje volumio[965]: info: CorePlayQueue::getTrack 0 Apr 19 19:54:07 switje systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 19 19:54:07 switje sudo[1193]: pam_unix(sudo:session): session closed for user root Apr 19 19:54:07 switje sudo[1198]: pam_unix(sudo:session): session closed for user root Apr 19 19:54:07 switje sudo[1196]: pam_unix(sudo:session): session closed for user root Apr 19 19:54:07 switje volumio[965]: info: CoreCommandRouter::volumioGetState Apr 19 19:54:07 switje volumio[965]: info: CorePlayQueue::getTrack 0 Apr 19 19:54:07 switje volumio[965]: info: Shairport-Sync Started Apr 19 19:54:07 switje volumio[965]: Error adding Membership: Error: addMembership EINVAL Apr 19 19:54:07 switje volumio[965]: info: Shairport-Sync Started Apr 19 19:54:07 switje volumio[965]: info: Shairport-Sync Started Apr 19 19:54:07 switje volumio[965]: info: touch_display: X display number found: Apr 19 19:54:07 switje sh[529]: timed out Apr 19 19:54:07 switje dhcpcd[575]: timed out Apr 19 19:54:07 switje sh[529]: dhcpcd exited Apr 19 19:54:07 switje dhcpcd[575]: dhcpcd exited Apr 19 19:54:07 switje sh[529]: ifup: failed to bring up eth0 Apr 19 19:54:07 switje systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Apr 19 19:54:07 switje systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Apr 19 19:54:07 switje volumio[965]: info: touch_display: X display number found: Apr 19 19:54:07 switje volumio[965]: info: touch_display: X display number found: 0 Apr 19 19:54:07 switje startx[1218]: X.Org X Server 1.20.4 Apr 19 19:54:07 switje startx[1218]: X Protocol Version 11, Revision 0 Apr 19 19:54:07 switje startx[1218]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Apr 19 19:54:07 switje startx[1218]: Current Operating System: Linux switje 6.1.69-v7l+ #1710 SMP Thu Dec 21 13:17:38 GMT 2023 armv7l Apr 19 19:54:07 switje startx[1218]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=D8:3A:DD:FC:FD:58 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=UUID=8543fa12-4125-4451-a534-cec5480e0606 imgfile=/volumio_current.sqsh bootpart=UUID=9C48-E5A3 datapart=UUID=f7cca1c6-391b-47a8-9c19-3b1948539def uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Apr 19 19:54:07 switje startx[1218]: Build Date: 04 April 2023 07:50:56AM Apr 19 19:54:07 switje startx[1218]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Apr 19 19:54:07 switje startx[1218]: Current version of pixman: 0.36.0 Apr 19 19:54:07 switje startx[1218]: Before reporting problems, check http://wiki.x.org Apr 19 19:54:07 switje startx[1218]: to make sure that you have the latest version. Apr 19 19:54:07 switje startx[1218]: Markers: (--) probed, (**) from config file, (==) default setting, Apr 19 19:54:07 switje startx[1218]: (++) from command line, (!!) notice, (II) informational, Apr 19 19:54:07 switje startx[1218]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Apr 19 19:54:07 switje startx[1218]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Apr 19 19:54:07 2025 Apr 19 19:54:07 switje volumio[965]: info: touch_display: X display number found: 0 Apr 19 19:54:07 switje startx[1218]: (==) Using config directory: "/etc/X11/xorg.conf.d" Apr 19 19:54:07 switje startx[1218]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Apr 19 19:54:07 switje volumio[965]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Apr 19 19:54:07 switje go-librespot[1219]: time="2025-04-19T19:54:07+02:00" level=info msg="generated new device id: eae60f2b90eccc352663785727b52ee0d9ca5c3d" Apr 19 19:54:07 switje volumio[965]: info: touch_display: X display number found: 0 Apr 19 19:54:07 switje mpd[1140]: Apr 19 19:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 19 19:54:08 switje systemd[1]: Started Music Player Daemon. Apr 19 19:54:08 switje sudo[1117]: pam_unix(sudo:session): session closed for user root Apr 19 19:54:08 switje sudo[1127]: pam_unix(sudo:session): session closed for user root Apr 19 19:54:08 switje volumio[965]: info: Completed starting Core Plugins Apr 19 19:54:08 switje volumio[965]: info: ------------------------------------------- Apr 19 19:54:08 switje volumio[965]: info: ----- MyVolumio plugins startup ---- Apr 19 19:54:08 switje volumio[965]: info: ------------------------------------------- Apr 19 19:54:08 switje volumio[965]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 19 19:54:08 switje go-librespot[1219]: time="2025-04-19T19:54:08+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 19 19:54:08 switje go-librespot[1219]: time="2025-04-19T19:54:08+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 19 19:54:08 switje go-librespot[1219]: time="2025-04-19T19:54:08+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 19 19:54:08 switje go-librespot[1219]: time="2025-04-19T19:54:08+02:00" level=debug msg="zeroconf server listening on port 43591" Apr 19 19:54:08 switje volumio[965]: error: MPD error: The expression evaluated to a falsy value: Apr 19 19:54:08 switje volumio[965]: assert.ok(self.idling) Apr 19 19:54:08 switje volumio[965]: error: The expression evaluated to a falsy value: Apr 19 19:54:08 switje volumio[965]: assert.ok(self.idling) Apr 19 19:54:08 switje volumio[965]: info: MPD running with PID1140 Apr 19 19:54:08 switje volumio[965]: ,establishing connection Apr 19 19:54:08 switje volumio[965]: error: MPD error: The expression evaluated to a falsy value: Apr 19 19:54:08 switje volumio[965]: assert.ok(self.idling) Apr 19 19:54:08 switje volumio[965]: error: The expression evaluated to a falsy value: Apr 19 19:54:08 switje volumio[965]: assert.ok(self.idling) Apr 19 19:54:08 switje volumio[965]: error: updateQueue error: null Apr 19 19:54:09 switje volumio[965]: info: CoreCommandRouter::volumioGetState Apr 19 19:54:09 switje volumio[965]: info: CorePlayQueue::getTrack 0 Apr 19 19:54:09 switje volumio[965]: info: touch_display: Setting screensaver timeout to 120 seconds. Apr 19 19:54:10 switje systemd[1]: systemd-hostnamed.service: Succeeded. Apr 19 19:54:10 switje volumio[965]: info: go-librespot daemon successfully initialized Apr 19 19:54:10 switje sudo[1352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 19:54:10 switje sudo[1352]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:54:10 switje sudo[1354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 19:54:10 switje sudo[1354]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:54:10 switje sudo[1354]: pam_unix(sudo:session): session closed for user root Apr 19 19:54:10 switje sudo[1352]: pam_unix(sudo:session): session closed for user root Apr 19 19:54:10 switje sudo[1358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 19 19:54:10 switje sudo[1358]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 19:54:11 switje systemd[1]: Started UPnP Renderer front-end to MPD. Apr 19 19:54:11 switje sudo[1358]: pam_unix(sudo:session): session closed for user root Apr 19 19:54:11 switje volumio[965]: info: Upmpdcli Daemon Started Apr 19 19:54:11 switje volumio[1360]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 19 19:54:13 switje volumio[965]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 19 19:54:13 switje volumio[965]: info: Initializing connection to go-librespot Websocket Apr 19 19:54:13 switje go-librespot[1219]: time="2025-04-19T19:54:13+02:00" level=debug msg="obtained new client token: AABaym8vUA5CbZbKWi8McVEDbU78VoK60lmdJTPnNq7hLChiaB/nL5Tjzf8GzAwwGMnrBXkJTW9KAqLO/R/cpsWvhR88RykvANV7+YuXeiWHk/7nmq8RJsu4qTCAMXr98h9ovUNSs3qRvA5xC7tMIuu7qQ5R58rr2WswonDhKY8zksopuqAiIKyeiOErRFvLVxeqVQTBtHCw2FBRDzme9d9gVfLZwKYl4eXmKNgqaas5M0WOJBzbKDPs1RWOXSo=" Apr 19 19:54:13 switje go-librespot[1219]: time="2025-04-19T19:54:13+02:00" level=debug msg="new websocket client" Apr 19 19:54:13 switje go-librespot[1219]: time="2025-04-19T19:54:13+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070 (error: dial tcp 34.158.1.133:4070: connect: connection refused), retrying with a different AP" Apr 19 19:54:13 switje go-librespot[1219]: time="2025-04-19T19:54:13+02:00" level=info msg="connected to ap-gew4.spotify.com:443" Apr 19 19:54:13 switje go-librespot[1219]: time="2025-04-19T19:54:13+02:00" level=debug msg="completed keyexchange" Apr 19 19:54:13 switje volumio[965]: Cannot compose Albumart path Apr 19 19:54:13 switje volumio[965]: info: Connection to go-librespot Websocket established Apr 19 19:54:14 switje go-librespot[1219]: time="2025-04-19T19:54:14+02:00" level=debug msg="completed challenge" Apr 19 19:54:14 switje go-librespot[1219]: time="2025-04-19T19:54:14+02:00" level=debug msg="authenticated as 1124370079" Apr 19 19:54:14 switje go-librespot[1219]: time="2025-04-19T19:54:14+02:00" level=debug msg="authenticated as 1124370079" Apr 19 19:54:14 switje go-librespot[1219]: time="2025-04-19T19:54:14+02:00" level=debug msg="dealer connection opened" Apr 19 19:54:14 switje go-librespot[1219]: time="2025-04-19T19:54:14+02:00" level=info msg="accepted zeroconf user 1124370079 from iPhone" Apr 19 19:54:14 switje go-librespot[1219]: time="2025-04-19T19:54:14+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Apr 19 19:54:14 switje go-librespot[1219]: time="2025-04-19T19:54:14+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Apr 19 19:54:14 switje go-librespot[1219]: time="2025-04-19T19:54:14+02:00" level=debug msg="autoplay enabled: false" Apr 19 19:54:14 switje go-librespot[1219]: time="2025-04-19T19:54:14+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Apr 19 19:54:14 switje go-librespot[1219]: time="2025-04-19T19:54:14+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Apr 19 19:54:14 switje go-librespot[1219]: time="2025-04-19T19:54:14+02:00" level=debug msg="received connection id: NTY2Y2RhOTQtNmY3MC00ZmEwLThlNWYtMzg4NmYyYTAzNjllK2RlYWxlcit0Y3A6Ly8wYWNhNTk2ZC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArODIyNUNERUE4NDg2RUY3ODY1OTQ5QTE5NEVCQUM0NDA4OUI4MThBMERGNUQ1MDU3N0M4MThFOTIzMURDNDY2Rg==" Apr 19 19:54:14 switje go-librespot[1219]: time="2025-04-19T19:54:14+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Apr 19 19:54:14 switje go-librespot[1219]: time="2025-04-19T19:54:14+02:00" level=debug msg="put connect state because NEW_DEVICE" Apr 19 19:54:14 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 19:54:14 switje volumio[965]: info: Discovery: Getting this device information Apr 19 19:54:14 switje volumio[965]: info: CoreCommandRouter::volumioGetState Apr 19 19:54:14 switje volumio[965]: info: CorePlayQueue::getTrack 0 Apr 19 19:54:14 switje volumio[965]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 19:54:14 switje go-librespot[1219]: time="2025-04-19T19:54:14+02:00" level=debug msg="handling transfer player command from c1bbff570eb5f57fa2e15fe51611e155318e3c2b" Apr 19 19:54:14 switje go-librespot[1219]: time="2025-04-19T19:54:14+02:00" level=debug msg="resolved context of track" uri="spotify:playlist:4VqyPQSmqKPVHBdGiXWCTA" Apr 19 19:54:14 switje go-librespot[1219]: time="2025-04-19T19:54:14+02:00" level=trace msg="fetched new page 0 with 15 items (list: 15)" uri="spotify:playlist:4VqyPQSmqKPVHBdGiXWCTA" Apr 19 19:54:14 switje go-librespot[1219]: time="2025-04-19T19:54:14+02:00" level=debug msg="shuffled context with seed 5640342305076772844 (len: 15, keep: 7)" Apr 19 19:54:14 switje go-librespot[1219]: time="2025-04-19T19:54:14+02:00" level=debug msg="loading track (paused: false, position: -646603337ms)" uri="spotify:track:1blTJAYs2GX8dQDS8xcOzp" Apr 19 19:54:14 switje go-librespot[1219]: time="2025-04-19T19:54:14+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 19 19:54:14 switje go-librespot[1219]: time="2025-04-19T19:54:14+02:00" level=trace msg="emitting websocket event: will_play" Apr 19 19:54:14 switje volumio[965]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1blTJAYs2GX8dQDS8xcOzp","play_origin":"playlist/ondemand"}} Apr 19 19:54:14 switje go-librespot[1219]: time="2025-04-19T19:54:14+02:00" level=debug msg="selected format OGG_VORBIS_320 (96b9b27206d6a7490e22516699100df2fc1a1c0d)" uri="spotify:track:1blTJAYs2GX8dQDS8xcOzp" Apr 19 19:54:14 switje go-librespot[1219]: time="2025-04-19T19:54:14+02:00" level=debug msg="requested aes key for file 96b9b27206d6a7490e22516699100df2fc1a1c0d, gid: 1blTJAYs2GX8dQDS8xcOzp" Apr 19 19:54:15 switje go-librespot[1219]: time="2025-04-19T19:54:15+02:00" level=debug msg="fetched first chunk of 15, total size is 7557064 bytes" uri="spotify:track:1blTJAYs2GX8dQDS8xcOzp" Apr 19 19:54:15 switje go-librespot[1219]: time="2025-04-19T19:54:15+02:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:1blTJAYs2GX8dQDS8xcOzp" Apr 19 19:54:15 switje go-librespot[1219]: time="2025-04-19T19:54:15+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 10068 us, period size = 444 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 49 frames" Apr 19 19:54:15 switje go-librespot[1219]: time="2025-04-19T19:54:15+02:00" level=debug msg="created new output device" Apr 19 19:54:15 switje go-librespot[1219]: time="2025-04-19T19:54:15+02:00" level=info msg="loaded track \"Ik Mis Je Officieel\" (paused: false, position: -646603337ms, duration: 181640ms, prefetched: false)" uri="spotify:track:1blTJAYs2GX8dQDS8xcOzp" Apr 19 19:54:15 switje go-librespot[1219]: time="2025-04-19T19:54:15+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 19 19:54:15 switje go-librespot[1219]: time="2025-04-19T19:54:15+02:00" level=debug msg="fetched chunk 1/14, size: 524288" uri="spotify:track:1blTJAYs2GX8dQDS8xcOzp" Apr 19 19:54:15 switje go-librespot[1219]: time="2025-04-19T19:54:15+02:00" level=trace msg="scheduling prefetch in 151s" Apr 19 19:54:15 switje go-librespot[1219]: time="2025-04-19T19:54:15+02:00" level=trace msg="emitting websocket event: metadata" Apr 19 19:54:15 switje go-librespot[1219]: time="2025-04-19T19:54:15+02:00" level=trace msg="emitting websocket event: active" Apr 19 19:54:15 switje go-librespot[1219]: time="2025-04-19T19:54:15+02:00" level=debug msg="sending successful reply for dealer request" Apr 19 19:54:15 switje volumio[965]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1blTJAYs2GX8dQDS8xcOzp","name":"Ik Mis Je Officieel","artist_names":["Zoë Tauran","LAUF"],"album_name":"Zoë Tauran","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02b11bebdf1dc2d0940c2bb418","position":-646603337,"duration":181640,"release_date":"year:2023 month:5 day:26","track_number":5,"disc_number":1}} Apr 19 19:54:15 switje volumio[965]: SPOTIFY: received: {"type":"active","data":null} Apr 19 19:54:15 switje volumio[965]: info: Aligning Spotify Volume to Volumio Volume Apr 19 19:54:15 switje volumio[965]: info: CoreCommandRouter::volumioGetState Apr 19 19:54:15 switje volumio[965]: info: CorePlayQueue::getTrack 0 Apr 19 19:54:15 switje volumio[965]: info: Setting Spotify Volume from Volumio: 33 Apr 19 19:54:15 switje go-librespot[1219]: time="2025-04-19T19:54:15+02:00" level=debug msg="fetched chunk 2/14, size: 524288" uri="spotify:track:1blTJAYs2GX8dQDS8xcOzp" Apr 19 19:54:15 switje go-librespot[1219]: time="2025-04-19T19:54:15+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 19 19:54:15 switje go-librespot[1219]: time="2025-04-19T19:54:15+02:00" level=trace msg="emitting websocket event: playing" Apr 19 19:54:15 switje volumio[965]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:1blTJAYs2GX8dQDS8xcOzp","play_origin":"playlist/ondemand"}} Apr 19 19:54:15 switje volumio[965]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 19:54:15 switje volumio[965]: TypeError: Cannot read property 'service' of undefined Apr 19 19:54:15 switje volumio[965]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:337:50) Apr 19 19:54:15 switje volumio[965]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:263:18) Apr 19 19:54:15 switje volumio[965]: at WebSocket.message (/data/plugins/music_service/spop/index.js:190:14) Apr 19 19:54:15 switje volumio[965]: at WebSocket.emit (events.js:315:20) Apr 19 19:54:15 switje volumio[965]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Apr 19 19:54:15 switje volumio[965]: at Receiver.emit (events.js:315:20) Apr 19 19:54:15 switje volumio[965]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Apr 19 19:54:15 switje volumio[965]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Apr 19 19:54:15 switje volumio[965]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Apr 19 19:54:15 switje volumio[965]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Apr 19 19:54:15 switje volumio[965]: at writeOrBuffer (internal/streams/writable.js:358:12) Apr 19 19:54:15 switje volumio[965]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Apr 19 19:54:15 switje volumio[965]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Apr 19 19:54:15 switje volumio[965]: at Socket.emit (events.js:315:20) Apr 19 19:54:15 switje volumio[965]: at addChunk (internal/streams/readable.js:309:12) Apr 19 19:54:15 switje volumio[965]: at readableAddChunk (internal/streams/readable.js:284:9) Apr 19 19:54:15 switje volumio[965]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 19:54:15 switje go-librespot[1219]: time="2025-04-19T19:54:15+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Apr 19 19:54:15 switje go-librespot[1219]: time="2025-04-19T19:54:15+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Apr 19 19:54:15 switje go-librespot[1219]: time="2025-04-19T19:54:15+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 3191" Apr 19 19:54:15 switje go-librespot[1219]: time="2025-04-19T19:54:15+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Apr 19 19:54:15 switje go-librespot[1219]: time="2025-04-19T19:54:15+02:00" level=debug msg="fetched chunk 3/14, size: 524288" uri="spotify:track:1blTJAYs2GX8dQDS8xcOzp" Apr 19 19:54:15 switje go-librespot[1219]: time="2025-04-19T19:54:15+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2039" Apr 19 19:54:15 switje go-librespot[1219]: time="2025-04-19T19:54:15+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Apr 19 19:54:15 switje sudo[1495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-19 19:53 Apr 19 19:54:15 switje sudo[1495]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"