-- Logs begin at Fri 2018-05-04 22:05:29 PDT, end at Tue 2025-01-28 13:03:04 PST. -- Feb 14 02:11:59 volumiolv kernel: Booting Linux on physical CPU 0x0 Feb 14 02:11:59 volumiolv kernel: Linux version 6.6.62-v7+ (dom@buildbot) (arm-linux-gnueabihf-gcc (Ubuntu 11.4.0-1ubuntu1~22.04) 11.4.0, GNU ld (GNU Binutils for Ubuntu) 2.38) #1816 SMP Mon Nov 18 15:30:49 GMT 2024 Feb 14 02:11:59 volumiolv kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d Feb 14 02:11:59 volumiolv kernel: CPU: div instructions available: patching division code Feb 14 02:11:59 volumiolv kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache Feb 14 02:11:59 volumiolv kernel: OF: fdt: Machine model: Raspberry Pi Zero 2 W Rev 1.0 Feb 14 02:11:59 volumiolv kernel: random: crng init done Feb 14 02:11:59 volumiolv kernel: Memory policy: Data cache writealloc Feb 14 02:11:59 volumiolv kernel: Reserved memory: created CMA memory pool at 0x18400000, size 64 MiB Feb 14 02:11:59 volumiolv kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Feb 14 02:11:59 volumiolv kernel: OF: reserved mem: 0x18400000..0x1c3fffff (65536 KiB) map reusable linux,cma Feb 14 02:11:59 volumiolv kernel: Zone ranges: Feb 14 02:11:59 volumiolv kernel: DMA [mem 0x0000000000000000-0x000000001dffffff] Feb 14 02:11:59 volumiolv kernel: Normal empty Feb 14 02:11:59 volumiolv kernel: Movable zone start for each node Feb 14 02:11:59 volumiolv kernel: Early memory node ranges Feb 14 02:11:59 volumiolv kernel: node 0: [mem 0x0000000000000000-0x000000001dffffff] Feb 14 02:11:59 volumiolv kernel: Initmem setup node 0 [mem 0x0000000000000000-0x000000001dffffff] Feb 14 02:11:59 volumiolv kernel: percpu: Embedded 18 pages/cpu s41812 r8192 d23724 u73728 Feb 14 02:11:59 volumiolv kernel: pcpu-alloc: s41812 r8192 d23724 u73728 alloc=18*4096 Feb 14 02:11:59 volumiolv kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Feb 14 02:11:59 volumiolv kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=B8:27:EB:6D:70:4B vc_mem.mem_base=0x1ec00000 vc_mem.mem_size=0x20000000 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=fb80cb44-b4d3-4b36-be35-613cba0a09fb imgfile=/volumio_current.sqsh bootpart=UUID=51B1-040D datapart=UUID=2899bccc-8620-4c7a-b56a-43fd2f7a836a 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 Feb 14 02:11:59 volumiolv kernel: cgroup: Disabling memory control group subsystem Feb 14 02:11:59 volumiolv kernel: Unknown kernel command line parameters "splash nodebug imgpart=UUID=fb80cb44-b4d3-4b36-be35-613cba0a09fb imgfile=/volumio_current.sqsh bootpart=UUID=51B1-040D datapart=UUID=2899bccc-8620-4c7a-b56a-43fd2f7a836a uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe bootdelay=7 use_kmsg=no", will be passed to user space. Feb 14 02:11:59 volumiolv kernel: Dentry cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Feb 14 02:11:59 volumiolv kernel: Inode-cache hash table entries: 32768 (order: 5, 131072 bytes, linear) Feb 14 02:11:59 volumiolv kernel: Built 1 zonelists, mobility grouping on. Total pages: 121800 Feb 14 02:11:59 volumiolv kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Feb 14 02:11:59 volumiolv kernel: Memory: 374136K/491520K available (11264K kernel code, 1476K rwdata, 3260K rodata, 1024K init, 606K bss, 51848K reserved, 65536K cma-reserved) Feb 14 02:11:59 volumiolv kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Feb 14 02:11:59 volumiolv kernel: ftrace: allocating 36457 entries in 107 pages Feb 14 02:11:59 volumiolv kernel: ftrace: allocated 107 pages with 5 groups Feb 14 02:11:59 volumiolv kernel: trace event string verifier disabled Feb 14 02:11:59 volumiolv kernel: rcu: Hierarchical RCU implementation. Feb 14 02:11:59 volumiolv kernel: Rude variant of Tasks RCU enabled. Feb 14 02:11:59 volumiolv kernel: Tracing variant of Tasks RCU enabled. Feb 14 02:11:59 volumiolv kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Feb 14 02:11:59 volumiolv kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Feb 14 02:11:59 volumiolv kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Feb 14 02:11:59 volumiolv kernel: arch_timer: cp15 timer(s) running at 19.20MHz (phys). Feb 14 02:11:59 volumiolv kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns Feb 14 02:11:59 volumiolv kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns Feb 14 02:11:59 volumiolv kernel: Switching to timer-based delay loop, resolution 52ns Feb 14 02:11:59 volumiolv kernel: Console: colour dummy device 80x30 Feb 14 02:11:59 volumiolv kernel: printk: console [tty1] enabled Feb 14 02:11:59 volumiolv kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) Feb 14 02:11:59 volumiolv kernel: CPU: Testing write buffer coherency: ok Feb 14 02:11:59 volumiolv kernel: pid_max: default: 32768 minimum: 301 Feb 14 02:11:59 volumiolv kernel: LSM: initializing lsm=capability,integrity Feb 14 02:11:59 volumiolv kernel: Mount-cache hash table entries: 1024 (order: 0, 4096 bytes, linear) Feb 14 02:11:59 volumiolv kernel: Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes, linear) Feb 14 02:11:59 volumiolv kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Feb 14 02:11:59 volumiolv kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Feb 14 02:11:59 volumiolv kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Feb 14 02:11:59 volumiolv kernel: Setting up static identity map for 0x100000 - 0x10003c Feb 14 02:11:59 volumiolv kernel: rcu: Hierarchical SRCU implementation. Feb 14 02:11:59 volumiolv kernel: rcu: Max phase no-delay instances is 1000. Feb 14 02:11:59 volumiolv kernel: smp: Bringing up secondary CPUs ... Feb 14 02:11:59 volumiolv kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Feb 14 02:11:59 volumiolv kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Feb 14 02:11:59 volumiolv kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Feb 14 02:11:59 volumiolv kernel: smp: Brought up 1 node, 4 CPUs Feb 14 02:11:59 volumiolv kernel: SMP: Total of 4 processors activated (153.60 BogoMIPS). Feb 14 02:11:59 volumiolv kernel: CPU: All CPU(s) started in HYP mode. Feb 14 02:11:59 volumiolv kernel: CPU: Virtualization extensions available. Feb 14 02:11:59 volumiolv kernel: devtmpfs: initialized Feb 14 02:11:59 volumiolv kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4 Feb 14 02:11:59 volumiolv kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Feb 14 02:11:59 volumiolv kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Feb 14 02:11:59 volumiolv kernel: pinctrl core: initialized pinctrl subsystem Feb 14 02:11:59 volumiolv kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Feb 14 02:11:59 volumiolv kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Feb 14 02:11:59 volumiolv kernel: audit: initializing netlink subsys (disabled) Feb 14 02:11:59 volumiolv kernel: audit: type=2000 audit(0.020:1): state=initialized audit_enabled=0 res=1 Feb 14 02:11:59 volumiolv kernel: thermal_sys: Registered thermal governor 'step_wise' Feb 14 02:11:59 volumiolv kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Feb 14 02:11:59 volumiolv kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Feb 14 02:11:59 volumiolv kernel: Serial: AMBA PL011 UART driver Feb 14 02:11:59 volumiolv kernel: bcm2835-mbox 3f00b880.mailbox: mailbox enabled Feb 14 02:11:59 volumiolv kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2024-11-11T15:50:32, variant start Feb 14 02:11:59 volumiolv kernel: raspberrypi-firmware soc:firmware: Firmware hash is 903570ba72a9e117f92e5499de439f59dd96e417 Feb 14 02:11:59 volumiolv kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Feb 14 02:11:59 volumiolv kernel: bcm2835-dma 3f007000.dma-controller: DMA legacy API manager, dmachans=0x1 Feb 14 02:11:59 volumiolv kernel: SCSI subsystem initialized Feb 14 02:11:59 volumiolv kernel: usbcore: registered new interface driver usbfs Feb 14 02:11:59 volumiolv kernel: usbcore: registered new interface driver hub Feb 14 02:11:59 volumiolv kernel: usbcore: registered new device driver usb Feb 14 02:11:59 volumiolv kernel: pps_core: LinuxPPS API ver. 1 registered Feb 14 02:11:59 volumiolv kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Feb 14 02:11:59 volumiolv kernel: PTP clock support registered Feb 14 02:11:59 volumiolv kernel: clocksource: Switched to clocksource arch_sys_counter Feb 14 02:11:59 volumiolv kernel: VFS: Disk quotas dquot_6.6.0 Feb 14 02:11:59 volumiolv kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Feb 14 02:11:59 volumiolv kernel: FS-Cache: Loaded Feb 14 02:11:59 volumiolv kernel: CacheFiles: Loaded Feb 14 02:11:59 volumiolv kernel: NET: Registered PF_INET protocol family Feb 14 02:11:59 volumiolv kernel: IP idents hash table entries: 8192 (order: 4, 65536 bytes, linear) Feb 14 02:11:59 volumiolv kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear) Feb 14 02:11:59 volumiolv kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Feb 14 02:11:59 volumiolv kernel: TCP established hash table entries: 4096 (order: 2, 16384 bytes, linear) Feb 14 02:11:59 volumiolv kernel: TCP bind hash table entries: 4096 (order: 4, 65536 bytes, linear) Feb 14 02:11:59 volumiolv kernel: TCP: Hash tables configured (established 4096 bind 4096) Feb 14 02:11:59 volumiolv kernel: UDP hash table entries: 256 (order: 1, 8192 bytes, linear) Feb 14 02:11:59 volumiolv kernel: UDP-Lite hash table entries: 256 (order: 1, 8192 bytes, linear) Feb 14 02:11:59 volumiolv kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Feb 14 02:11:59 volumiolv kernel: RPC: Registered named UNIX socket transport module. Feb 14 02:11:59 volumiolv kernel: RPC: Registered udp transport module. Feb 14 02:11:59 volumiolv kernel: RPC: Registered tcp transport module. Feb 14 02:11:59 volumiolv kernel: RPC: Registered tcp-with-tls transport module. Feb 14 02:11:59 volumiolv kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Feb 14 02:11:59 volumiolv kernel: Trying to unpack rootfs image as initramfs... Feb 14 02:11:59 volumiolv kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available Feb 14 02:11:59 volumiolv kernel: Freeing initrd memory: 28088K Feb 14 02:11:59 volumiolv kernel: Initialise system trusted keyrings Feb 14 02:11:59 volumiolv kernel: workingset: timestamp_bits=14 max_order=17 bucket_order=3 Feb 14 02:11:59 volumiolv kernel: zbud: loaded Feb 14 02:11:59 volumiolv kernel: NFS: Registering the id_resolver key type Feb 14 02:11:59 volumiolv kernel: Key type id_resolver registered Feb 14 02:11:59 volumiolv kernel: Key type id_legacy registered Feb 14 02:11:59 volumiolv kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Feb 14 02:11:59 volumiolv kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Feb 14 02:11:59 volumiolv kernel: Key type asymmetric registered Feb 14 02:11:59 volumiolv kernel: Asymmetric key parser 'x509' registered Feb 14 02:11:59 volumiolv kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Feb 14 02:11:59 volumiolv kernel: io scheduler mq-deadline registered Feb 14 02:11:59 volumiolv kernel: io scheduler kyber registered Feb 14 02:11:59 volumiolv kernel: io scheduler bfq registered Feb 14 02:11:59 volumiolv kernel: pinctrl-bcm2835 3f200000.gpio: GPIO_OUT persistence: yes Feb 14 02:11:59 volumiolv kernel: bcm2708_fb soc:fb: FB found 1 display(s) Feb 14 02:11:59 volumiolv kernel: Console: switching to colour frame buffer device 80x30 Feb 14 02:11:59 volumiolv kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Feb 14 02:11:59 volumiolv kernel: bcm2835-rng 3f104000.rng: hwrng registered Feb 14 02:11:59 volumiolv kernel: vc-mem: phys_addr:0x00000000 mem_base=0x1ec00000 mem_size:0x20000000(512 MiB) Feb 14 02:11:59 volumiolv kernel: brd: module loaded Feb 14 02:11:59 volumiolv kernel: loop: module loaded Feb 14 02:11:59 volumiolv kernel: Loading iSCSI transport class v2.0-870. Feb 14 02:11:59 volumiolv kernel: usbcore: registered new interface driver lan78xx Feb 14 02:11:59 volumiolv kernel: usbcore: registered new interface driver smsc95xx Feb 14 02:11:59 volumiolv kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Feb 14 02:11:59 volumiolv kernel: Core Release: 2.80a Feb 14 02:11:59 volumiolv kernel: Setting default values for core params Feb 14 02:11:59 volumiolv kernel: Finished setting default values for core params Feb 14 02:11:59 volumiolv kernel: Using Buffer DMA mode Feb 14 02:11:59 volumiolv kernel: Periodic Transfer Interrupt Enhancement - disabled Feb 14 02:11:59 volumiolv kernel: Multiprocessor Interrupt Enhancement - disabled Feb 14 02:11:59 volumiolv kernel: OTG VER PARAM: 0, OTG VER FLAG: 0 Feb 14 02:11:59 volumiolv kernel: Dedicated Tx FIFOs mode Feb 14 02:11:59 volumiolv kernel: Feb 14 02:11:59 volumiolv kernel: WARN::dwc_otg_hcd_init:1070: FIQ DMA bounce buffers: virt = 98514000 dma = 0xd8514000 len=9024 Feb 14 02:11:59 volumiolv kernel: FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled Feb 14 02:11:59 volumiolv kernel: dwc_otg: Microframe scheduler enabled Feb 14 02:11:59 volumiolv kernel: Feb 14 02:11:59 volumiolv kernel: WARN::hcd_init_fiq:457: FIQ on core 1 Feb 14 02:11:59 volumiolv kernel: Feb 14 02:11:59 volumiolv kernel: WARN::hcd_init_fiq:458: FIQ ASM at 808886e4 length 36 Feb 14 02:11:59 volumiolv kernel: Feb 14 02:11:59 volumiolv kernel: WARN::hcd_init_fiq:496: MPHI regs_base at 9e810000 Feb 14 02:11:59 volumiolv kernel: dwc_otg 3f980000.usb: DWC OTG Controller Feb 14 02:11:59 volumiolv kernel: dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 Feb 14 02:11:59 volumiolv kernel: dwc_otg 3f980000.usb: irq 89, io mem 0x00000000 Feb 14 02:11:59 volumiolv kernel: Init: Port Power? op_state=1 Feb 14 02:11:59 volumiolv kernel: Init: Power Port (0) Feb 14 02:11:59 volumiolv kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Feb 14 02:11:59 volumiolv kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 14 02:11:59 volumiolv kernel: usb usb1: Product: DWC OTG Controller Feb 14 02:11:59 volumiolv kernel: usb usb1: Manufacturer: Linux 6.6.62-v7+ dwc_otg_hcd Feb 14 02:11:59 volumiolv kernel: usb usb1: SerialNumber: 3f980000.usb Feb 14 02:11:59 volumiolv kernel: hub 1-0:1.0: USB hub found Feb 14 02:11:59 volumiolv kernel: hub 1-0:1.0: 1 port detected Feb 14 02:11:59 volumiolv kernel: dwc_otg: FIQ enabled Feb 14 02:11:59 volumiolv kernel: dwc_otg: NAK holdoff enabled Feb 14 02:11:59 volumiolv kernel: dwc_otg: FIQ split-transaction FSM enabled Feb 14 02:11:59 volumiolv kernel: Module dwc_common_port init Feb 14 02:11:59 volumiolv kernel: usbcore: registered new interface driver usb-storage Feb 14 02:11:59 volumiolv kernel: mousedev: PS/2 mouse device common for all mice Feb 14 02:11:59 volumiolv kernel: sdhci: Secure Digital Host Controller Interface driver Feb 14 02:11:59 volumiolv kernel: sdhci: Copyright(c) Pierre Ossman Feb 14 02:11:59 volumiolv kernel: sdhci-pltfm: SDHCI platform and OF driver helper Feb 14 02:11:59 volumiolv kernel: ledtrig-cpu: registered to indicate activity on CPUs Feb 14 02:11:59 volumiolv kernel: hid: raw HID events driver (C) Jiri Kosina Feb 14 02:11:59 volumiolv kernel: usbcore: registered new interface driver usbhid Feb 14 02:11:59 volumiolv kernel: usbhid: USB HID core driver Feb 14 02:11:59 volumiolv kernel: bcm2835_vchiq 3f00b840.mailbox: there is not valid maps for state default Feb 14 02:11:59 volumiolv kernel: Initializing XFRM netlink socket Feb 14 02:11:59 volumiolv kernel: NET: Registered PF_PACKET protocol family Feb 14 02:11:59 volumiolv kernel: Key type dns_resolver registered Feb 14 02:11:59 volumiolv kernel: Registering SWP/SWPB emulation handler Feb 14 02:11:59 volumiolv kernel: registered taskstats version 1 Feb 14 02:11:59 volumiolv kernel: Loading compiled-in X.509 certificates Feb 14 02:11:59 volumiolv kernel: Key type .fscrypt registered Feb 14 02:11:59 volumiolv kernel: Key type fscrypt-provisioning registered Feb 14 02:11:59 volumiolv kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled Feb 14 02:11:59 volumiolv kernel: 3f201000.serial: ttyAMA1 at MMIO 0x3f201000 (irq = 114, base_baud = 0) is a PL011 rev2 Feb 14 02:11:59 volumiolv kernel: serial serial0: tty port ttyAMA1 registered Feb 14 02:11:59 volumiolv kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Feb 14 02:11:59 volumiolv kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Feb 14 02:11:59 volumiolv kernel: mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0 Feb 14 02:11:59 volumiolv kernel: mmc-bcm2835 3f300000.mmcnr: DMA channel allocated Feb 14 02:11:59 volumiolv kernel: sdhost: log_buf @ 00f5411f (d8513000) Feb 14 02:11:59 volumiolv kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) Feb 14 02:11:59 volumiolv kernel: of_cfs_init Feb 14 02:11:59 volumiolv kernel: of_cfs_init: OK Feb 14 02:11:59 volumiolv kernel: clk: Disabling unused clocks Feb 14 02:11:59 volumiolv kernel: Freeing unused kernel image (initmem) memory: 1024K Feb 14 02:11:59 volumiolv kernel: Run /init as init process Feb 14 02:11:59 volumiolv kernel: with arguments: Feb 14 02:11:59 volumiolv kernel: /init Feb 14 02:11:59 volumiolv kernel: splash Feb 14 02:11:59 volumiolv kernel: nodebug Feb 14 02:11:59 volumiolv kernel: with environment: Feb 14 02:11:59 volumiolv kernel: HOME=/ Feb 14 02:11:59 volumiolv kernel: TERM=linux Feb 14 02:11:59 volumiolv kernel: imgpart=UUID=fb80cb44-b4d3-4b36-be35-613cba0a09fb Feb 14 02:11:59 volumiolv kernel: imgfile=/volumio_current.sqsh Feb 14 02:11:59 volumiolv kernel: bootpart=UUID=51B1-040D Feb 14 02:11:59 volumiolv kernel: datapart=UUID=2899bccc-8620-4c7a-b56a-43fd2f7a836a Feb 14 02:11:59 volumiolv kernel: uuidconfig=cmdline.txt Feb 14 02:11:59 volumiolv kernel: pcie_aspm=off Feb 14 02:11:59 volumiolv kernel: pci=pcie_bus_safe Feb 14 02:11:59 volumiolv kernel: bootdelay=7 Feb 14 02:11:59 volumiolv kernel: use_kmsg=no Feb 14 02:11:59 volumiolv kernel: mmc0: host does not support reading read-only switch, assuming write-enable Feb 14 02:11:59 volumiolv kernel: mmc0: Host Software Queue enabled Feb 14 02:11:59 volumiolv kernel: mmc0: new high speed SDHC card at address aaaa Feb 14 02:11:59 volumiolv kernel: mmcblk0: mmc0:aaaa SC32G 29.7 GiB Feb 14 02:11:59 volumiolv kernel: mmcblk0: p1 p2 p3 Feb 14 02:11:59 volumiolv kernel: mmcblk0: mmc0:aaaa SC32G 29.7 GiB (quirks 0x0000c000) Feb 14 02:11:59 volumiolv kernel: mmc1: new high speed SDIO card at address 0001 Feb 14 02:11:59 volumiolv kernel: fuse: init (API version 7.39) Feb 14 02:11:59 volumiolv kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Feb 14 02:11:59 volumiolv kernel: usbcore: registered new interface driver uas Feb 14 02:11:59 volumiolv kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Feb 14 02:11:59 volumiolv kernel: EXT4-fs (mmcblk0p2): recovery complete Feb 14 02:11:59 volumiolv kernel: EXT4-fs (mmcblk0p2): mounted filesystem fb80cb44-b4d3-4b36-be35-613cba0a09fb r/w with ordered data mode. Quota mode: none. Feb 14 02:11:59 volumiolv kernel: loop0: detected capacity change from 0 to 995064 Feb 14 02:11:59 volumiolv kernel: EXT4-fs (mmcblk0p3): recovery complete Feb 14 02:11:59 volumiolv kernel: EXT4-fs (mmcblk0p3): mounted filesystem 2899bccc-8620-4c7a-b56a-43fd2f7a836a r/w with ordered data mode. Quota mode: none. Feb 14 02:11:59 volumiolv systemd[1]: System time before build time, advancing clock. Feb 14 02:11:59 volumiolv kernel: NET: Registered PF_INET6 protocol family Feb 14 02:11:59 volumiolv kernel: Segment Routing with IPv6 Feb 14 02:11:59 volumiolv kernel: In-situ OAM (IOAM) with IPv6 Feb 14 02:11:59 volumiolv 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) Feb 14 02:11:59 volumiolv systemd[1]: Detected architecture arm. Feb 14 02:11:59 volumiolv systemd[1]: Set hostname to . Feb 14 02:11:59 volumiolv 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. Feb 14 02:11:59 volumiolv systemd[1]: Created slice system-getty.slice. Feb 14 02:11:59 volumiolv systemd[1]: Listening on Journal Socket. Feb 14 02:11:59 volumiolv systemd[1]: Started dynamicswap service. Feb 14 02:11:59 volumiolv systemd[1]: Condition check resulted in Kernel Module supporting RPCSEC_GSS being skipped. Feb 14 02:11:59 volumiolv systemd[1]: Starting Restore / save the current clock... Feb 14 02:11:59 volumiolv systemd[1]: Listening on Journal Audit Socket. Feb 14 02:11:59 volumiolv systemd-journald[281]: Journal started Feb 14 02:11:59 volumiolv systemd-journald[281]: Runtime journal (/run/log/journal/a89b7be63bc86b985c184d84675fe488) is 3.7M, max 30.0M, 26.2M free. Jan 28 13:01:09 volumiolv fake-hwclock[268]: Tue Jan 28 21:01:09 UTC 2025 Jan 28 13:01:09 volumiolv systemd[1]: Started Restore / save the current clock. Jan 28 13:01:09 volumiolv systemd-modules-load[282]: Inserted module 'i2c_dev' Jan 28 13:01:09 volumiolv kernel: i2c_dev: i2c /dev entries driver Jan 28 13:01:09 volumiolv systemd[1]: Started Load Kernel Modules. Jan 28 13:01:09 volumiolv systemd[1]: Mounting FUSE Control File System... Jan 28 13:01:09 volumiolv systemd[1]: Mounting Kernel Configuration File System... Jan 28 13:01:09 volumiolv systemd[1]: Starting Apply Kernel Variables... Jan 28 13:01:09 volumiolv systemd[1]: Started Load/Save Random Seed. Jan 28 13:01:09 volumiolv systemd[1]: Mounted FUSE Control File System. Jan 28 13:01:09 volumiolv systemd[1]: Mounted Kernel Configuration File System. Jan 28 13:01:09 volumiolv systemd[1]: Started udev Coldplug all Devices. Jan 28 13:01:09 volumiolv volumio[267]: 512 MB or less RAM Detected, need to enable swap Jan 28 13:01:09 volumiolv systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jan 28 13:01:09 volumiolv systemd[1]: Started Create System Users. Jan 28 13:01:09 volumiolv systemd[1]: Started Apply Kernel Variables. Jan 28 13:01:09 volumiolv volumio[267]: Enabling Swap Jan 28 13:01:09 volumiolv systemd[1]: Starting Create Static Device Nodes in /dev... Jan 28 13:01:09 volumiolv kernel: ext4 filesystem being mounted at /swap supports timestamps until 2038-01-19 (0x7fffffff) Jan 28 13:01:09 volumiolv systemd[1]: Started Helper to synchronize boot up for ifupdown. Jan 28 13:01:09 volumiolv kernel: Adding 524224k swap on /swap/swapfile. Priority:-2 extents:15 across:24690684k SS Jan 28 13:01:09 volumiolv volumio[267]: Setting swappiness to 40 Jan 28 13:01:09 volumiolv systemd[1]: Started Create Static Device Nodes in /dev. Jan 28 13:01:09 volumiolv systemd[1]: Reached target Local File Systems (Pre). Jan 28 13:01:09 volumiolv systemd[1]: Mounting /var/spool/cups... Jan 28 13:01:09 volumiolv systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jan 28 13:01:09 volumiolv systemd[1]: Mounting /tmp... Jan 28 13:01:09 volumiolv systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jan 28 13:01:09 volumiolv systemd[1]: Mounting /var/log... Jan 28 13:01:09 volumiolv volumio[267]: vm.swappiness = 40 Jan 28 13:01:09 volumiolv systemd[1]: Starting udev Kernel Device Manager... Jan 28 13:01:09 volumiolv systemd[1]: dynamicswap.service: Succeeded. Jan 28 13:01:09 volumiolv systemd[1]: Mounted /var/spool/cups. Jan 28 13:01:09 volumiolv systemd[1]: Mounted /tmp. Jan 28 13:01:09 volumiolv systemd[1]: Mounted /var/log. Jan 28 13:01:09 volumiolv systemd[1]: Starting Flush Journal to Persistent Storage... Jan 28 13:01:09 volumiolv systemd[1]: Mounting /var/spool/cups/tmp... Jan 28 13:01:09 volumiolv systemd[1]: Mounted /var/spool/cups/tmp. Jan 28 13:01:09 volumiolv systemd-udevd[310]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jan 28 13:01:09 volumiolv systemd-journald[281]: Runtime journal (/run/log/journal/a89b7be63bc86b985c184d84675fe488) is 7.5M, max 30.0M, 22.5M free. Jan 28 13:01:09 volumiolv systemd[1]: Started Flush Journal to Persistent Storage. Jan 28 13:01:09 volumiolv systemd[1]: Started udev Kernel Device Manager. Jan 28 13:01:09 volumiolv systemd[1]: Starting Show Plymouth Boot Screen... Jan 28 13:01:09 volumiolv systemd[1]: plymouth-start.service: Succeeded. Jan 28 13:01:09 volumiolv systemd[1]: Started Show Plymouth Boot Screen. Jan 28 13:01:09 volumiolv systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jan 28 13:01:09 volumiolv systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 28 13:01:09 volumiolv systemd[1]: Reached target Local Encrypted Volumes. Jan 28 13:01:09 volumiolv systemd[1]: Reached target Paths. Jan 28 13:01:09 volumiolv kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 Jan 28 13:01:09 volumiolv kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem Jan 28 13:01:09 volumiolv kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jan 28 13:01:09 volumiolv kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jan 28 13:01:09 volumiolv kernel: [vc_sm_connected_init]: start Jan 28 13:01:09 volumiolv kernel: [vc_sm_connected_init]: installed successfully Jan 28 13:01:09 volumiolv kernel: mc: Linux media interface: v0.10 Jan 28 13:01:10 volumiolv kernel: videodev: Linux video capture interface: v2.00 Jan 28 13:01:10 volumiolv kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 28 13:01:10 volumiolv kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Jan 28 13:01:10 volumiolv kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jan 28 13:01:10 volumiolv kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Jan 28 13:01:10 volumiolv kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Jan 28 13:01:10 volumiolv kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Jan 28 13:01:10 volumiolv kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Jan 28 13:01:10 volumiolv kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jan 28 13:01:10 volumiolv kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jan 28 13:01:10 volumiolv kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jan 28 13:01:10 volumiolv kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jan 28 13:01:10 volumiolv kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Jan 28 13:01:10 volumiolv kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Jan 28 13:01:10 volumiolv kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Jan 28 13:01:10 volumiolv kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Jan 28 13:01:10 volumiolv kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jan 28 13:01:10 volumiolv kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jan 28 13:01:10 volumiolv kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jan 28 13:01:10 volumiolv kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jan 28 13:01:10 volumiolv kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Jan 28 13:01:10 volumiolv kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jan 28 13:01:10 volumiolv kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jan 28 13:01:10 volumiolv kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jan 28 13:01:10 volumiolv kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jan 28 13:01:10 volumiolv kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jan 28 13:01:10 volumiolv kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jan 28 13:01:10 volumiolv kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Jan 28 13:01:10 volumiolv kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jan 28 13:01:10 volumiolv kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jan 28 13:01:10 volumiolv kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Jan 28 13:01:10 volumiolv kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Jan 28 13:01:10 volumiolv kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Jan 28 13:01:10 volumiolv kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Jan 28 13:01:10 volumiolv kernel: Bluetooth: Core ver 2.22 Jan 28 13:01:10 volumiolv kernel: NET: Registered PF_BLUETOOTH protocol family Jan 28 13:01:10 volumiolv kernel: Bluetooth: HCI device and connection manager initialized Jan 28 13:01:10 volumiolv kernel: Bluetooth: HCI socket layer initialized Jan 28 13:01:10 volumiolv kernel: Bluetooth: L2CAP socket layer initialized Jan 28 13:01:10 volumiolv kernel: Bluetooth: SCO socket layer initialized Jan 28 13:01:10 volumiolv kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jan 28 13:01:10 volumiolv kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jan 28 13:01:10 volumiolv systemd[1]: Found device /dev/disk/by-uuid/51B1-040D. Jan 28 13:01:10 volumiolv kernel: Bluetooth: HCI UART driver ver 2.3 Jan 28 13:01:10 volumiolv kernel: Bluetooth: HCI UART protocol H4 registered Jan 28 13:01:10 volumiolv kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jan 28 13:01:10 volumiolv kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Jan 28 13:01:10 volumiolv kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Jan 28 13:01:10 volumiolv kernel: Bluetooth: HCI UART protocol Broadcom registered Jan 28 13:01:10 volumiolv kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jan 28 13:01:10 volumiolv kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Jan 28 13:01:10 volumiolv kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Jan 28 13:01:10 volumiolv kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jan 28 13:01:10 volumiolv kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Jan 28 13:01:10 volumiolv kernel: uart-pl011 3f201000.serial: no DMA platform data Jan 28 13:01:10 volumiolv systemd[1]: Starting File System Check on /dev/disk/by-uuid/51B1-040D... Jan 28 13:01:10 volumiolv systemd[1]: Started File System Check Daemon to report status. Jan 28 13:01:10 volumiolv systemd-udevd[323]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 28 13:01:10 volumiolv kernel: Bluetooth: hci0: BCM: chip id 94 Jan 28 13:01:10 volumiolv kernel: Bluetooth: hci0: BCM: features 0x2e Jan 28 13:01:10 volumiolv kernel: Bluetooth: hci0: BCM43430A1 Jan 28 13:01:10 volumiolv kernel: Bluetooth: hci0: BCM43430A1 (001.002.009) build 0000 Jan 28 13:01:10 volumiolv kernel: Bluetooth: hci0: BCM43430A1 'brcm/BCM43430A1.hcd' Patch Jan 28 13:01:11 volumiolv kernel: brcmfmac: F1 signature read @0x18000000=0x1541a9a6 Jan 28 13:01:11 volumiolv kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Jan 28 13:01:11 volumiolv kernel: brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43430-sdio.raspberrypi,model-zero-2-w.bin failed with error -2 Jan 28 13:01:11 volumiolv kernel: usbcore: registered new interface driver brcmfmac Jan 28 13:01:11 volumiolv systemd-fsck[369]: fsck.fat 4.1 (2017-01-24) Jan 28 13:01:11 volumiolv systemd-fsck[369]: 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Jan 28 13:01:11 volumiolv systemd-fsck[369]: Automatically removing dirty bit. Jan 28 13:01:11 volumiolv systemd-fsck[369]: Performing changes. Jan 28 13:01:11 volumiolv systemd-fsck[369]: /dev/mmcblk0p1: 398 files, 166313/184582 clusters Jan 28 13:01:11 volumiolv systemd[1]: Started File System Check on /dev/disk/by-uuid/51B1-040D. Jan 28 13:01:11 volumiolv kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Jan 28 13:01:11 volumiolv kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Jul 19 2021 03:24:18 version 7.45.98 (TOB) (56df937 CY) FWID 01-8e14b897 Jan 28 13:01:11 volumiolv systemd-udevd[321]: Using default interface naming scheme 'v240'. Jan 28 13:01:11 volumiolv kernel: Bluetooth: hci0: BCM: features 0x2e Jan 28 13:01:11 volumiolv kernel: Bluetooth: hci0: BCM43438A1 37.4MHz Raspberry Pi 3-0141 Jan 28 13:01:11 volumiolv kernel: Bluetooth: hci0: BCM43430A1 (001.002.009) build 0508 Jan 28 13:01:11 volumiolv systemd-udevd[321]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 28 13:01:11 volumiolv kernel: alsactl[404]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Jan 28 13:01:11 volumiolv systemd[1]: Created slice system-bthelper.slice. Jan 28 13:01:11 volumiolv systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jan 28 13:01:11 volumiolv systemd[1]: Starting Show Plymouth Boot Screen... Jan 28 13:01:11 volumiolv systemd[1]: Condition check resulted in Huge Pages File System being skipped. Jan 28 13:01:11 volumiolv systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jan 28 13:01:11 volumiolv systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jan 28 13:01:11 volumiolv systemd[1]: Mounting /boot... Jan 28 13:01:11 volumiolv systemd[1]: Starting Load/Save RF Kill Switch Status... Jan 28 13:01:11 volumiolv systemd[1]: Mounted /boot. Jan 28 13:01:11 volumiolv systemd[1]: plymouth-start.service: Succeeded. Jan 28 13:01:11 volumiolv systemd[1]: Started Show Plymouth Boot Screen. Jan 28 13:01:11 volumiolv systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 28 13:01:11 volumiolv systemd[1]: Reached target Local File Systems. Jan 28 13:01:11 volumiolv systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jan 28 13:01:11 volumiolv systemd[1]: Starting Create Volatile Files and Directories... Jan 28 13:01:11 volumiolv systemd[1]: Starting Raise network interfaces... Jan 28 13:01:11 volumiolv systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jan 28 13:01:11 volumiolv systemd[1]: Starting Preprocess NFS configuration... Jan 28 13:01:11 volumiolv systemd[1]: Started Load/Save RF Kill Switch Status. Jan 28 13:01:11 volumiolv systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Jan 28 13:01:11 volumiolv systemd[1]: Started Create Volatile Files and Directories. Jan 28 13:01:11 volumiolv systemd[1]: plymouth-read-write.service: Succeeded. Jan 28 13:01:11 volumiolv systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jan 28 13:01:11 volumiolv systemd[1]: nfs-config.service: Succeeded. Jan 28 13:01:12 volumiolv systemd[1]: Started Preprocess NFS configuration. Jan 28 13:01:12 volumiolv systemd[1]: Received SIGRTMIN+20 from PID 163 (plymouthd). Jan 28 13:01:12 volumiolv systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jan 28 13:01:12 volumiolv systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jan 28 13:01:12 volumiolv systemd[1]: Reached target NFS client services. Jan 28 13:01:12 volumiolv systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jan 28 13:01:12 volumiolv systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jan 28 13:01:12 volumiolv systemd[1]: Starting RPC bind portmap service... Jan 28 13:01:12 volumiolv systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jan 28 13:01:12 volumiolv systemd[1]: Started ifup for wlan0. Jan 28 13:01:12 volumiolv systemd[1]: Started Update UTMP about System Boot/Shutdown. Jan 28 13:01:12 volumiolv systemd[1]: Reached target System Initialization. Jan 28 13:01:12 volumiolv systemd[1]: Started Daily Cleanup of Temporary Directories. Jan 28 13:01:12 volumiolv systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jan 28 13:01:12 volumiolv kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Jan 28 13:01:12 volumiolv systemd[1]: apt-daily.timer: Not using persistent file timestamp Tue 2025-06-03 21:55:32 PDT as it is in the future. Jan 28 13:01:12 volumiolv systemd[1]: Started Daily apt download activities. Jan 28 13:01:12 volumiolv systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Wed 2025-06-04 06:48:32 PDT as it is in the future. Jan 28 13:01:12 volumiolv systemd[1]: Started Daily apt upgrade and clean activities. Jan 28 13:01:12 volumiolv systemd[1]: Reached target Timers. Jan 28 13:01:12 volumiolv systemd[1]: Listening on D-Bus System Message Bus Socket. Jan 28 13:01:12 volumiolv systemd[1]: Listening on triggerhappy.socket. Jan 28 13:01:12 volumiolv systemd[1]: Reached target Sockets. Jan 28 13:01:12 volumiolv systemd[1]: Reached target Basic System. Jan 28 13:01:12 volumiolv systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jan 28 13:01:12 volumiolv systemd[1]: Started MPD Monitor Service. Jan 28 13:01:12 volumiolv systemd[1]: Started Volumio Log Rotation Service. Jan 28 13:01:12 volumiolv systemd[1]: Started Volumio Iptables Module. Jan 28 13:01:12 volumiolv systemd[1]: Started volumio-remote-updater.service. Jan 28 13:01:12 volumiolv systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jan 28 13:01:12 volumiolv systemd[1]: Starting Configure Bluetooth Modems connected by UART... Jan 28 13:01:12 volumiolv systemd[1]: Starting Volumio Time Update Utility... Jan 28 13:01:12 volumiolv systemd[1]: Started Manage Sound Card State (restore and store). Jan 28 13:01:12 volumiolv systemd[1]: Started UPnP Renderer front-end to MPD. Jan 28 13:01:12 volumiolv systemd[1]: Starting Save/Restore Sound Card State... Jan 28 13:01:12 volumiolv systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jan 28 13:01:12 volumiolv alsactl[492]: alsactl 1.1.8 daemon started Jan 28 13:01:12 volumiolv systemd[1]: Starting triggerhappy global hotkey daemon... Jan 28 13:01:12 volumiolv systemd[1]: Started D-Bus System Message Bus. Jan 28 13:01:12 volumiolv volumio-time-update[491]: volumio-time-update-util: Fetching time from Volumio... Jan 28 13:01:12 volumiolv systemd[1]: Starting WPA supplicant... Jan 28 13:01:12 volumiolv systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jan 28 13:01:12 volumiolv systemd-udevd[315]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 2' failed with exit code 99. Jan 28 13:01:12 volumiolv systemd[1]: Starting Wireless Services... Jan 28 13:01:12 volumiolv systemd[1]: Starting dhcpcd on all interfaces... Jan 28 13:01:12 volumiolv systemd[1]: Starting Login Service... Jan 28 13:01:12 volumiolv systemd[1]: Started RPC bind portmap service. Jan 28 13:01:12 volumiolv systemd[1]: Reached target RPC Port Mapper. Jan 28 13:01:12 volumiolv systemd[1]: Reached target Remote File Systems (Pre). Jan 28 13:01:12 volumiolv systemd[1]: Reached target Remote File Systems. Jan 28 13:01:12 volumiolv systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jan 28 13:01:12 volumiolv alsactl[492]: No state is present for card IQaudIODAC Jan 28 13:01:12 volumiolv alsactl[492]: Found hardware: "IQaudIODAC" "" "" "" "" Jan 28 13:01:12 volumiolv alsactl[492]: Hardware is initialized using a generic method Jan 28 13:01:12 volumiolv alsactl[492]: No state is present for card IQaudIODAC Jan 28 13:01:12 volumiolv dhcpcd[513]: Not running dhcpcd because /etc/network/interfaces Jan 28 13:01:12 volumiolv dhcpcd[513]: defines some interfaces that will use a Jan 28 13:01:12 volumiolv dhcpcd[513]: DHCP client or static address Jan 28 13:01:12 volumiolv thd[498]: Unable to parse trigger line: Jan 28 13:01:12 volumiolv thd[498]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jan 28 13:01:12 volumiolv thd[498]: Unable to parse trigger line: Jan 28 13:01:12 volumiolv thd[498]: Found socket passed from systemd Jan 28 13:01:12 volumiolv kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 28 13:01:12 volumiolv mpd_monitor.sh[466]: MPD error: Connection refused Jan 28 13:01:12 volumiolv systemd-logind[514]: New seat seat0. Jan 28 13:01:12 volumiolv mpd_monitor.sh[466]: mpd: no process found Jan 28 13:01:12 volumiolv avahi-daemon[497]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jan 28 13:01:12 volumiolv avahi-daemon[497]: Successfully dropped root privileges. Jan 28 13:01:12 volumiolv avahi-daemon[497]: avahi-daemon 0.7 starting up. Jan 28 13:01:13 volumiolv alsactl[496]: No state is present for card IQaudIODAC Jan 28 13:01:13 volumiolv alsactl[496]: Found hardware: "IQaudIODAC" "" "" "" "" Jan 28 13:01:13 volumiolv alsactl[496]: Hardware is initialized using a generic method Jan 28 13:01:13 volumiolv alsactl[496]: No state is present for card IQaudIODAC Jan 28 13:01:13 volumiolv systemd[1]: Started Login Service. Jan 28 13:01:13 volumiolv systemd[1]: Started triggerhappy global hotkey daemon. Jan 28 13:01:13 volumiolv systemd[1]: hciuart.service: Succeeded. Jan 28 13:01:13 volumiolv avahi-daemon[497]: Successfully called chroot(). Jan 28 13:01:13 volumiolv avahi-daemon[497]: Successfully dropped remaining capabilities. Jan 28 13:01:13 volumiolv systemd[1]: Started Configure Bluetooth Modems connected by UART. Jan 28 13:01:13 volumiolv systemd[1]: Started Save/Restore Sound Card State. Jan 28 13:01:13 volumiolv systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Jan 28 13:01:13 volumiolv systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Jan 28 13:01:13 volumiolv systemd[1]: Failed to start dhcpcd on all interfaces. Jan 28 13:01:13 volumiolv wpa_supplicant[509]: Successfully initialized wpa_supplicant Jan 28 13:01:13 volumiolv avahi-daemon[497]: Loading service file /services/volumio.service. Jan 28 13:01:13 volumiolv avahi-daemon[497]: Network interface enumeration completed. Jan 28 13:01:13 volumiolv avahi-daemon[497]: Server startup complete. Host name is volumiolv.local. Local service cookie is 1981131308. Jan 28 13:01:13 volumiolv avahi-daemon[497]: Service "VolumioLV" (/services/volumio.service) successfully established. Jan 28 13:01:13 volumiolv volumio-remote-updater[485]: Error: No active session Jan 28 13:01:13 volumiolv volumio-remote-updater[485]: [2025-01-28 13:01:13] [info] asio async_connect error: system:111 (Connection refused) Jan 28 13:01:13 volumiolv volumio-remote-updater[485]: [2025-01-28 13:01:13] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 28 13:01:13 volumiolv volumio-remote-updater[485]: [2025-01-28 13:01:13] [error] handle_connect error: Underlying Transport Error Jan 28 13:01:13 volumiolv haveged[448]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jan 28 13:01:13 volumiolv haveged[448]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jan 28 13:01:13 volumiolv haveged[448]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00024 Jan 28 13:01:13 volumiolv haveged[448]: haveged: fills: 0, generated: 0 Jan 28 13:01:13 volumiolv systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jan 28 13:01:13 volumiolv systemd[1]: Started WPA supplicant. Jan 28 13:01:13 volumiolv sh[451]: wlan0=wlan0 Jan 28 13:01:13 volumiolv volumio[495]: Could not open config: /tmp/upmpdcli.conf Jan 28 13:01:13 volumiolv volumio-time-update[491]: volumio-time-update-util: Date not found in response Jan 28 13:01:13 volumiolv volumio-time-update[491]: volumio-time-update-util: Retrying in 5 seconds... Jan 28 13:01:13 volumiolv systemd[1]: Listening on mpd.socket. Jan 28 13:01:13 volumiolv systemd[1]: Reached target Sound Card. Jan 28 13:01:13 volumiolv systemd[1]: Starting Raspberry Pi bluetooth helper... Jan 28 13:01:13 volumiolv systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 28 13:01:13 volumiolv systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 28 13:01:13 volumiolv systemd[1]: Started Raise network interfaces. Jan 28 13:01:13 volumiolv systemd[1]: Reached target Network. Jan 28 13:01:13 volumiolv systemd[1]: Starting Network Time Service... Jan 28 13:01:13 volumiolv systemd[1]: Starting Permit User Sessions... Jan 28 13:01:13 volumiolv systemd[1]: Starting OpenBSD Secure Shell server... Jan 28 13:01:14 volumiolv systemd[1]: Starting Music Player Daemon... Jan 28 13:01:14 volumiolv systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jan 28 13:01:14 volumiolv systemd[1]: Reached target Network is Online. Jan 28 13:01:14 volumiolv bthelper[607]: Raspberry Pi BDADDR already set Jan 28 13:01:14 volumiolv systemd[1]: Starting Samba NMB Daemon... Jan 28 13:01:14 volumiolv systemd[1]: Starting LSB: Brings up/down network automatically... Jan 28 13:01:14 volumiolv systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 28 13:01:14 volumiolv loadcpufreq[516]: Loading cpufreq kernel modules...done (none). Jan 28 13:01:14 volumiolv systemd[1]: Starting /etc/rc.local Compatibility... Jan 28 13:01:14 volumiolv systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jan 28 13:01:14 volumiolv systemd[1]: Started Raspberry Pi bluetooth helper. Jan 28 13:01:14 volumiolv systemd[1]: iptables.service: Succeeded. Jan 28 13:01:14 volumiolv systemd[1]: Started Permit User Sessions. Jan 28 13:01:14 volumiolv systemd[1]: Started /etc/rc.local Compatibility. Jan 28 13:01:14 volumiolv systemd[1]: Starting Terminate Plymouth Boot Screen... Jan 28 13:01:14 volumiolv systemd[1]: Starting Hold until boot process finishes up... Jan 28 13:01:14 volumiolv systemd[1]: Starting Bluetooth service... Jan 28 13:01:14 volumiolv systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jan 28 13:01:14 volumiolv systemd[1]: Received SIGRTMIN+21 from PID 163 (plymouthd). Jan 28 13:01:14 volumiolv ifplugd[649]: Network Interface Plugging Daemon...error: unexpectedly disconnected from boot status daemon Jan 28 13:01:14 volumiolv ifplugd[649]: skip eth0...done. Jan 28 13:01:14 volumiolv systemd[1]: Started LSB: Brings up/down network automatically. Jan 28 13:01:14 volumiolv systemd[1]: plymouth-quit.service: Succeeded. Jan 28 13:01:14 volumiolv systemd[1]: Started Terminate Plymouth Boot Screen. Jan 28 13:01:14 volumiolv systemd[1]: plymouth-quit-wait.service: Succeeded. Jan 28 13:01:14 volumiolv systemd[1]: Started Hold until boot process finishes up. Jan 28 13:01:14 volumiolv systemd[1]: Received SIGRTMIN+21 from PID 163 (n/a). Jan 28 13:01:14 volumiolv systemd[1]: Started Getty on tty1. Jan 28 13:01:14 volumiolv systemd[1]: Reached target Login Prompts. Jan 28 13:01:14 volumiolv ntpd[624]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 28 13:01:14 volumiolv ntpd[624]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jan 28 13:01:14 volumiolv systemd[1]: Started Network Time Service. Jan 28 13:01:14 volumiolv ntpd[677]: proto: precision = 0.833 usec (-20) Jan 28 13:01:14 volumiolv sshd[678]: Server listening on 0.0.0.0 port 22. Jan 28 13:01:14 volumiolv sshd[678]: Server listening on :: port 22. Jan 28 13:01:14 volumiolv systemd[1]: Started OpenBSD Secure Shell server. Jan 28 13:01:14 volumiolv cpufrequtils[662]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jan 28 13:01:14 volumiolv systemd[1]: Started LSB: set CPUFreq kernel parameters. Jan 28 13:01:14 volumiolv ntpd[677]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 28 13:01:14 volumiolv ntpd[677]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 28 13:01:14 volumiolv ntpd[677]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 32 days ago Jan 28 13:01:14 volumiolv ntpd[677]: Listen and drop on 0 v6wildcard [::]:123 Jan 28 13:01:14 volumiolv ntpd[677]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 28 13:01:14 volumiolv ntpd[677]: Listen normally on 2 lo 127.0.0.1:123 Jan 28 13:01:14 volumiolv ntpd[677]: Listening on routing socket on fd #19 for interface updates Jan 28 13:01:14 volumiolv ntpd[677]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 28 13:01:14 volumiolv ntpd[677]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 28 13:01:14 volumiolv bluetoothd[661]: Bluetooth daemon 5.50 Jan 28 13:01:14 volumiolv sudo[641]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 28 13:01:14 volumiolv sudo[641]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:14 volumiolv bluetoothd[661]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jan 28 13:01:14 volumiolv sudo[641]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 28 13:01:14 volumiolv sudo[641]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:14 volumiolv systemd[1]: Started Bluetooth service. Jan 28 13:01:14 volumiolv systemd[1]: Reached target Bluetooth. Jan 28 13:01:14 volumiolv bluetoothd[661]: Starting SDP server Jan 28 13:01:14 volumiolv bluetoothd[661]: Excluding (cli) sap Jan 28 13:01:14 volumiolv bluetoothd[661]: Bluetooth management interface 1.22 initialized Jan 28 13:01:14 volumiolv dbus-daemon[503]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=661 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jan 28 13:01:14 volumiolv kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jan 28 13:01:14 volumiolv kernel: Bluetooth: BNEP filters: protocol multicast Jan 28 13:01:14 volumiolv kernel: Bluetooth: BNEP socket layer initialized Jan 28 13:01:14 volumiolv kernel: Bluetooth: MGMT ver 1.22 Jan 28 13:01:15 volumiolv bluetoothd[661]: Failed to set privacy: Rejected (0x0b) Jan 28 13:01:15 volumiolv systemd[1]: Starting Hostname Service... Jan 28 13:01:15 volumiolv dbus-daemon[503]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 28 13:01:15 volumiolv systemd[1]: Started Hostname Service. Jan 28 13:01:15 volumiolv ntpd[677]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 28 13:01:16 volumiolv nmbd[657]: [2025/01/28 13:01:16.108811, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jan 28 13:01:16 volumiolv nmbd[657]: started asyncdns process 693 Jan 28 13:01:16 volumiolv nmbd[657]: [2025/01/28 13:01:16.111406, 0] ../lib/util/become_daemon.c:149(daemon_status) Jan 28 13:01:16 volumiolv nmbd[657]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jan 28 13:01:16 volumiolv nmbd[657]: [2025/01/28 13:01:16.111589, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jan 28 13:01:16 volumiolv nmbd[657]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jan 28 13:01:16 volumiolv ntpd[677]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 28 13:01:16 volumiolv wireless.js[512]: Cleaning previous... Jan 28 13:01:16 volumiolv sudo[701]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 28 13:01:16 volumiolv sudo[701]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:16 volumiolv sudo[701]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:16 volumiolv sudo[703]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 28 13:01:16 volumiolv sudo[703]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:16 volumiolv systemd[1]: systemd-rfkill.service: Succeeded. Jan 28 13:01:17 volumiolv sudo[703]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:17 volumiolv wireless.js[512]: Stopped aP Jan 28 13:01:17 volumiolv sudo[710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 28 13:01:17 volumiolv sudo[710]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:17 volumiolv sudo[710]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:17 volumiolv kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 28 13:01:17 volumiolv sudo[712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jan 28 13:01:17 volumiolv sudo[712]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:17 volumiolv sudo[712]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:17 volumiolv sudo[719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 28 13:01:17 volumiolv sudo[719]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:17 volumiolv sudo[719]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:17 volumiolv ntpd[677]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 28 13:01:17 volumiolv sudo[721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jan 28 13:01:17 volumiolv sudo[721]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:18 volumiolv sudo[721]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:18 volumiolv wireless.js[512]: SETTING APPROPRIATE REG DOMAIN: US Jan 28 13:01:18 volumiolv sudo[726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 28 13:01:18 volumiolv sudo[726]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:18 volumiolv sudo[726]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:18 volumiolv sudo[728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Jan 28 13:01:18 volumiolv sudo[728]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:18 volumiolv sudo[728]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:18 volumiolv wireless.js[512]: SUCCESSFULLY SET NEW REGDOMAIN: US Jan 28 13:01:18 volumiolv wireless.js[512]: Start wireless flow Jan 28 13:01:18 volumiolv wireless.js[512]: Stopped hotspot (if there).. Jan 28 13:01:18 volumiolv sudo[733]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 28 13:01:18 volumiolv sudo[733]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:18 volumiolv sudo[733]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:18 volumiolv sudo[735]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 28 13:01:18 volumiolv sudo[735]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:18 volumiolv ntpd[677]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 28 13:01:18 volumiolv sudo[735]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:18 volumiolv volumio-remote-updater[485]: [2025-01-28 13:01:18] [info] asio async_connect error: system:111 (Connection refused) Jan 28 13:01:18 volumiolv volumio-remote-updater[485]: [2025-01-28 13:01:18] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 28 13:01:18 volumiolv volumio-remote-updater[485]: [2025-01-28 13:01:18] [error] handle_connect error: Underlying Transport Error Jan 28 13:01:18 volumiolv wireless.js[512]: DHCP IP Jan 28 13:01:18 volumiolv wireless.js[512]: Start ap Jan 28 13:01:18 volumiolv wpa_supplicant[737]: Successfully initialized wpa_supplicant Jan 28 13:01:18 volumiolv sudo[738]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jan 28 13:01:18 volumiolv sudo[738]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:18 volumiolv kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 28 13:01:18 volumiolv volumio-time-update[491]: volumio-time-update-util: Fetching time from Volumio... Jan 28 13:01:18 volumiolv dhcpcd[739]: dev: loaded udev Jan 28 13:01:18 volumiolv volumio-time-update[491]: volumio-time-update-util: Date not found in response Jan 28 13:01:18 volumiolv volumio-time-update[491]: volumio-time-update-util: Retrying in 5 seconds... Jan 28 13:01:18 volumiolv kernel: 8021q: 802.1Q VLAN Support v1.8 Jan 28 13:01:19 volumiolv dhcpcd[739]: wlan0: connected to Access Point `' Jan 28 13:01:19 volumiolv mpd[689]: Jan 28 13:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 13:01:19 volumiolv bthelper[607]: Changing power off succeeded Jan 28 13:01:19 volumiolv bthelper[607]: [CHG] Controller 2C:CF:67:B5:7F:C6 Powered: no Jan 28 13:01:19 volumiolv bthelper[607]: [CHG] Controller 2C:CF:67:B5:7F:C6 Discovering: no Jan 28 13:01:19 volumiolv bthelper[607]: [CHG] Controller 2C:CF:67:B5:7F:C6 Class: 0x00000000 Jan 28 13:01:19 volumiolv dhcpcd[739]: no interfaces have a carrier Jan 28 13:01:19 volumiolv dhcpcd[769]: wlan0: waiting for carrier Jan 28 13:01:19 volumiolv dhcpcd[739]: forked to background, child pid 769 Jan 28 13:01:19 volumiolv sudo[738]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:19 volumiolv systemd[1]: Started Music Player Daemon. Jan 28 13:01:19 volumiolv mpd_monitor.sh[466]: MPD restarted due to no mpc output. Jan 28 13:01:19 volumiolv bthelper[607]: [CHG] Controller 2C:CF:67:B5:7F:C6 Class: 0x0000041c Jan 28 13:01:19 volumiolv bthelper[607]: Changing power on succeeded Jan 28 13:01:19 volumiolv wireless.js[512]: trying... Jan 28 13:01:19 volumiolv sudo[778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 13:01:19 volumiolv sudo[778]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:19 volumiolv sudo[778]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:20 volumiolv wireless.js[512]: trying... Jan 28 13:01:20 volumiolv sudo[781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 13:01:20 volumiolv sudo[781]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:20 volumiolv sudo[781]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:21 volumiolv wireless.js[512]: trying... Jan 28 13:01:21 volumiolv sudo[784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 13:01:21 volumiolv sudo[784]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:21 volumiolv sudo[784]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:22 volumiolv wireless.js[512]: trying... Jan 28 13:01:22 volumiolv sudo[787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 13:01:22 volumiolv sudo[787]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:22 volumiolv sudo[787]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:23 volumiolv wireless.js[512]: trying... Jan 28 13:01:23 volumiolv sudo[790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 13:01:23 volumiolv sudo[790]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:23 volumiolv sudo[790]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:23 volumiolv volumio-time-update[491]: volumio-time-update-util: Fetching time from Volumio... Jan 28 13:01:23 volumiolv volumio-time-update[491]: volumio-time-update-util: Date not found in response Jan 28 13:01:23 volumiolv volumio-time-update[491]: volumio-time-update-util: Retrying in 5 seconds... Jan 28 13:01:24 volumiolv wireless.js[512]: trying... Jan 28 13:01:24 volumiolv sudo[798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 13:01:24 volumiolv sudo[798]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:24 volumiolv sudo[798]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:25 volumiolv wireless.js[512]: trying... Jan 28 13:01:25 volumiolv sudo[801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 13:01:25 volumiolv sudo[801]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:25 volumiolv sudo[801]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:26 volumiolv volumio-remote-updater[485]: [2025-01-28 13:01:26] [info] asio async_connect error: system:111 (Connection refused) Jan 28 13:01:26 volumiolv volumio-remote-updater[485]: [2025-01-28 13:01:26] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 28 13:01:26 volumiolv volumio-remote-updater[485]: [2025-01-28 13:01:26] [error] handle_connect error: Underlying Transport Error Jan 28 13:01:26 volumiolv wireless.js[512]: trying... Jan 28 13:01:26 volumiolv sudo[804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 13:01:26 volumiolv sudo[804]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:26 volumiolv sudo[804]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:27 volumiolv wireless.js[512]: trying... Jan 28 13:01:27 volumiolv sudo[807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 13:01:27 volumiolv sudo[807]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:27 volumiolv sudo[807]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:28 volumiolv wireless.js[512]: trying... Jan 28 13:01:28 volumiolv sudo[810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 13:01:28 volumiolv sudo[810]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:28 volumiolv sudo[810]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:28 volumiolv volumio-time-update[491]: volumio-time-update-util: Fetching time from Volumio... Jan 28 13:01:28 volumiolv volumio-time-update[491]: volumio-time-update-util: Date not found in response Jan 28 13:01:28 volumiolv volumio-time-update[491]: volumio-time-update-util: Retrying in 5 seconds... Jan 28 13:01:29 volumiolv wireless.js[512]: trying... Jan 28 13:01:29 volumiolv sudo[821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 13:01:29 volumiolv sudo[821]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:29 volumiolv sudo[821]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:30 volumiolv wpa_supplicant[754]: wlan0: Failed to initiate sched scan Jan 28 13:01:30 volumiolv wireless.js[512]: trying... Jan 28 13:01:30 volumiolv sudo[824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 13:01:30 volumiolv sudo[824]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:30 volumiolv sudo[824]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:31 volumiolv wireless.js[512]: trying... Jan 28 13:01:31 volumiolv sudo[827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 13:01:31 volumiolv sudo[827]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:31 volumiolv sudo[827]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:32 volumiolv wireless.js[512]: trying... Jan 28 13:01:32 volumiolv sudo[830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 13:01:32 volumiolv sudo[830]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:32 volumiolv sudo[830]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:33 volumiolv wireless.js[512]: trying... Jan 28 13:01:33 volumiolv sudo[833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 13:01:33 volumiolv sudo[833]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:33 volumiolv sudo[833]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:33 volumiolv volumio-time-update[491]: volumio-time-update-util: Fetching time from Volumio... Jan 28 13:01:33 volumiolv volumio-time-update[491]: volumio-time-update-util: Date not found in response Jan 28 13:01:33 volumiolv volumio-time-update[491]: volumio-time-update-util: Retrying in 5 seconds... Jan 28 13:01:34 volumiolv wireless.js[512]: trying... Jan 28 13:01:34 volumiolv sudo[841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 13:01:34 volumiolv sudo[841]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:34 volumiolv sudo[841]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:35 volumiolv wireless.js[512]: trying... Jan 28 13:01:35 volumiolv sudo[844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 13:01:35 volumiolv sudo[844]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:35 volumiolv sudo[844]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:36 volumiolv wpa_supplicant[754]: wlan0: Failed to initiate sched scan Jan 28 13:01:36 volumiolv volumio-remote-updater[485]: [2025-01-28 13:01:36] [info] asio async_connect error: system:111 (Connection refused) Jan 28 13:01:36 volumiolv volumio-remote-updater[485]: [2025-01-28 13:01:36] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 28 13:01:36 volumiolv volumio-remote-updater[485]: [2025-01-28 13:01:36] [error] handle_connect error: Underlying Transport Error Jan 28 13:01:36 volumiolv wireless.js[512]: trying... Jan 28 13:01:36 volumiolv sudo[847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 13:01:36 volumiolv sudo[847]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:36 volumiolv sudo[847]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:37 volumiolv wireless.js[512]: trying... Jan 28 13:01:37 volumiolv sudo[850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 13:01:37 volumiolv sudo[850]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:37 volumiolv sudo[850]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:38 volumiolv wireless.js[512]: trying... Jan 28 13:01:38 volumiolv sudo[853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 13:01:38 volumiolv sudo[853]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:38 volumiolv sudo[853]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:38 volumiolv volumio-time-update[491]: volumio-time-update-util: Fetching time from Volumio... Jan 28 13:01:39 volumiolv volumio-time-update[491]: volumio-time-update-util: Date not found in response Jan 28 13:01:39 volumiolv volumio-time-update[491]: volumio-time-update-util: Retrying in 5 seconds... Jan 28 13:01:39 volumiolv wireless.js[512]: Overtime, starting plan B Jan 28 13:01:39 volumiolv systemd[1]: Started Wireless Services. Jan 28 13:01:39 volumiolv kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 28 13:01:39 volumiolv systemd[1]: Started Volumio Backend Module. Jan 28 13:01:39 volumiolv systemd[1]: Started Volumio Cpu Tweaker. Jan 28 13:01:40 volumiolv volumio-cpu-tweak[867]: Setting RT Priority for mpd Jan 28 13:01:40 volumiolv volumio-cpu-tweak[867]: Setting MPD Affinity Jan 28 13:01:40 volumiolv volumio-cpu-tweak[867]: pid 689's current affinity mask: f Jan 28 13:01:40 volumiolv volumio-cpu-tweak[867]: pid 689's new affinity mask: 3 Jan 28 13:01:40 volumiolv volumio-cpu-tweak[867]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jan 28 13:01:40 volumiolv volumio-cpu-tweak[867]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jan 28 13:01:40 volumiolv systemd[1]: volumio_cpu_tweak.service: Succeeded. Jan 28 13:01:41 volumiolv systemd[1]: systemd-fsckd.service: Succeeded. Jan 28 13:01:41 volumiolv wpa_supplicant[754]: wlan0: Failed to initiate sched scan Jan 28 13:01:43 volumiolv volumio[866]: info: ------------------------------------------- Jan 28 13:01:43 volumiolv volumio[866]: info: ----- Volumio3 ---- Jan 28 13:01:43 volumiolv volumio[866]: info: ------------------------------------------- Jan 28 13:01:43 volumiolv volumio[866]: info: ----- System startup ---- Jan 28 13:01:43 volumiolv volumio[866]: info: ------------------------------------------- Jan 28 13:01:44 volumiolv volumio-time-update[491]: volumio-time-update-util: Fetching time from Volumio... Jan 28 13:01:44 volumiolv volumio-time-update[491]: volumio-time-update-util: Date not found in response Jan 28 13:01:44 volumiolv volumio-time-update[491]: volumio-time-update-util: Retrying in 5 seconds... Jan 28 13:01:44 volumiolv volumio[866]: info: MYVOLUMIO Environment detected Jan 28 13:01:44 volumiolv volumio[866]: info: Plugin folders cleanup Jan 28 13:01:44 volumiolv volumio[866]: info: Scanning into folder /volumio/app/plugins/ Jan 28 13:01:44 volumiolv volumio[866]: info: Scanning category audio_interface Jan 28 13:01:44 volumiolv volumio[866]: info: Scanning category miscellanea Jan 28 13:01:44 volumiolv volumio[866]: info: Scanning category music_service Jan 28 13:01:44 volumiolv volumio[866]: info: Scanning category plugins.json Jan 28 13:01:44 volumiolv volumio[866]: info: Scanning category system_controller Jan 28 13:01:44 volumiolv volumio[866]: info: Scanning category user_interface Jan 28 13:01:44 volumiolv volumio[866]: info: Scanning into folder /data/plugins/ Jan 28 13:01:44 volumiolv volumio[866]: info: Scanning category system_hardware Jan 28 13:01:44 volumiolv volumio[866]: info: Plugin folders cleanup completed Jan 28 13:01:44 volumiolv volumio[866]: info: ------------------------------------------- Jan 28 13:01:44 volumiolv volumio[866]: info: ----- Core plugins startup ---- Jan 28 13:01:44 volumiolv volumio[866]: info: ------------------------------------------- Jan 28 13:01:44 volumiolv volumio[866]: info: Loading plugins from folder /volumio/app/plugins/ Jan 28 13:01:44 volumiolv volumio[866]: info: Adding plugin upnp to MyMusic Plugins Jan 28 13:01:44 volumiolv volumio[866]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 28 13:01:44 volumiolv volumio[866]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 28 13:01:44 volumiolv volumio[866]: info: Loading plugins from folder /data/plugins/ Jan 28 13:01:44 volumiolv volumio[866]: info: Loading plugin "system"... Jan 28 13:01:45 volumiolv volumio[866]: info: Loading plugin "appearance"... Jan 28 13:01:45 volumiolv systemd[1]: systemd-hostnamed.service: Succeeded. Jan 28 13:01:46 volumiolv volumio-remote-updater[485]: [2025-01-28 13:01:46] [connect] Successful connection Jan 28 13:01:46 volumiolv volumio[866]: info: Loading plugin "network"... Jan 28 13:01:46 volumiolv volumio[866]: info: Refreshing Cached IP Addresses Jan 28 13:01:46 volumiolv volumio[866]: info: Loading plugin "services"... Jan 28 13:01:46 volumiolv volumio[866]: info: Loading plugin "alsa_controller"... Jan 28 13:01:47 volumiolv sudo[908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 13:01:47 volumiolv sudo[906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 13:01:47 volumiolv sudo[906]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:47 volumiolv sudo[908]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:47 volumiolv sudo[911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 28 13:01:47 volumiolv sudo[911]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:47 volumiolv sudo[911]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:47 volumiolv sudo[908]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:47 volumiolv sudo[906]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:47 volumiolv wpa_supplicant[754]: wlan0: Failed to initiate sched scan Jan 28 13:01:47 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 13:01:47 volumiolv volumio[866]: info: Loading plugin "wizard"... Jan 28 13:01:47 volumiolv volumio[866]: info: Loading plugin "networkfs"... Jan 28 13:01:47 volumiolv volumio[866]: info: Starting Udev Watcher for removable devices Jan 28 13:01:47 volumiolv sudo[936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=john.egenberger@gmail.com,password=45glock21,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.42.243/Music /mnt/NAS/GarageMusic Jan 28 13:01:47 volumiolv sudo[936]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:47 volumiolv volumio[866]: info: Ignoring mount for partition: boot Jan 28 13:01:47 volumiolv volumio[866]: info: Ignoring mount for partition: volumio Jan 28 13:01:47 volumiolv volumio[866]: info: Ignoring mount for partition: volumio_data Jan 28 13:01:47 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 13:01:47 volumiolv volumio[866]: info: Loading plugin "volumio_command_line_client"... Jan 28 13:01:47 volumiolv volumio[866]: info: Loading plugin "upnp"... Jan 28 13:01:47 volumiolv volumio[866]: info: [1738098107386] Starting Upmpd Daemon Jan 28 13:01:47 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 13:01:47 volumiolv volumio[866]: info: Loading plugin "my_music"... Jan 28 13:01:47 volumiolv volumio[866]: info: Loading plugin "mpd"... Jan 28 13:01:47 volumiolv kernel: Key type cifs.spnego registered Jan 28 13:01:47 volumiolv kernel: Key type cifs.idmap registered Jan 28 13:01:47 volumiolv sudo[936]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:47 volumiolv kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Jan 28 13:01:47 volumiolv kernel: CIFS: Attempting to mount //192.168.42.243/Music Jan 28 13:01:47 volumiolv kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 28 13:01:47 volumiolv kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jan 28 13:01:48 volumiolv volumio[866]: info: Loading plugin "upnp_browser"... Jan 28 13:01:48 volumiolv volumio[866]: info: Loading plugin "alarm-clock"... Jan 28 13:01:48 volumiolv volumio[866]: info: Loading plugin "airplay_emulation"... Jan 28 13:01:48 volumiolv volumio[866]: info: Starting Shairport Sync Jan 28 13:01:48 volumiolv volumio[866]: info: Loading plugin "last_100"... Jan 28 13:01:48 volumiolv volumio[866]: info: Loading plugin "webradio"... Jan 28 13:01:49 volumiolv volumio-time-update[491]: volumio-time-update-util: Fetching time from Volumio... Jan 28 13:01:49 volumiolv volumio-time-update[491]: volumio-time-update-util: Date not found in response Jan 28 13:01:49 volumiolv volumio-time-update[491]: volumio-time-update-util: Retrying in 5 seconds... Jan 28 13:01:49 volumiolv volumio[866]: info: Loading plugin "i2s_dacs"... Jan 28 13:01:49 volumiolv volumio[866]: info: Loading plugin "volumiodiscovery"... Jan 28 13:01:49 volumiolv volumio[866]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 13:01:49 volumiolv volumio[866]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 13:01:49 volumiolv volumio[866]: *** WARNING *** For more information see Jan 28 13:01:49 volumiolv node[866]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 13:01:49 volumiolv volumio[866]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 28 13:01:49 volumiolv volumio[866]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 13:01:49 volumiolv volumio[866]: *** WARNING *** For more information see Jan 28 13:01:49 volumiolv node[866]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 13:01:49 volumiolv node[866]: *** WARNING *** For more information see Jan 28 13:01:49 volumiolv node[866]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 28 13:01:49 volumiolv node[866]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 13:01:49 volumiolv node[866]: *** WARNING *** For more information see Jan 28 13:01:49 volumiolv volumio[866]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 28 13:01:49 volumiolv volumio[866]: info: Discovery: Started advertising with name: VolumioLV Jan 28 13:01:49 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 13:01:49 volumiolv volumio[866]: info: Loading plugin "gpio-buttons"... Jan 28 13:01:50 volumiolv volumio[866]: info: GPIO-Buttons initialized Jan 28 13:01:50 volumiolv volumio[866]: info: Loading plugin "outputs"... Jan 28 13:01:50 volumiolv volumio[866]: info: Loading plugin "albumart"... Jan 28 13:01:50 volumiolv volumio[866]: info: Plugin example_plugin is not enabled Jan 28 13:01:50 volumiolv volumio[866]: info: Loading plugin "inputs"... Jan 28 13:01:50 volumiolv volumio[866]: info: Loading plugin "updater_comm"... Jan 28 13:01:50 volumiolv volumio[866]: info: Plugin mpdemulation is not enabled Jan 28 13:01:50 volumiolv volumio[866]: info: Loading plugin "rest_api"... Jan 28 13:01:50 volumiolv volumio[866]: info: Loading plugin "websocket"... Jan 28 13:01:50 volumiolv volumio[866]: info: Starting Socket.io Server version 2.3.0 Jan 28 13:01:50 volumiolv volumio[866]: info: Loading i18n strings for locale en Jan 28 13:01:50 volumiolv volumio[866]: Updating browse sources language Jan 28 13:01:50 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 13:01:50 volumiolv volumio[866]: Forking 3 albumart workers Jan 28 13:01:51 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 13:01:51 volumiolv volumio[866]: info: CoreCommandRouter::initPlayerControls Jan 28 13:01:51 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 13:01:51 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 13:01:51 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 13:01:51 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 13:01:51 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 13:01:51 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 13:01:51 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 13:01:51 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 13:01:51 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 13:01:51 volumiolv volumio[866]: Express server listening on port 3000 Jan 28 13:01:51 volumiolv volumio[866]: [Metrics] WebUI: 8s 884.41ms Jan 28 13:01:51 volumiolv volumio[866]: info: CoreStateMachine::resetVolumioState Jan 28 13:01:51 volumiolv volumio[866]: info: CoreStateMachine::getcurrentVolume Jan 28 13:01:51 volumiolv volumio[866]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 13:01:51 volumiolv volumio[866]: info: Volumio Network Manager: Network status updated: 0 Jan 28 13:01:51 volumiolv volumio[866]: info: Cannot mount NAS GarageMusic at system boot, trial number 1 ,retrying in 5 seconds Jan 28 13:01:51 volumiolv volumio[866]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jan 28 13:01:51 volumiolv volumio[866]: wlan0 Interface doesn't support scanning : Device or resource busy Jan 28 13:01:51 volumiolv volumio[866]: info: Cannot use regular scanning, forcing with ap-force Jan 28 13:01:51 volumiolv sudo[994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jan 28 13:01:51 volumiolv sudo[994]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:51 volumiolv volumio[866]: Starting albumart workers Jan 28 13:01:51 volumiolv volumio[866]: Starting albumart workers Jan 28 13:01:51 volumiolv volumio[866]: Starting albumart workers Jan 28 13:01:51 volumiolv sudo[994]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:52 volumiolv volumio[866]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jan 28 13:01:52 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 13:01:52 volumiolv volumio[866]: info: Reloading queue from file Jan 28 13:01:52 volumiolv volumio[866]: info: VolumeController:: Volume=100 Mute =false Jan 28 13:01:52 volumiolv volumio[866]: info: CoreStateMachine::pushState Jan 28 13:01:52 volumiolv volumio[866]: info: CorePlayQueue::getTrack 0 Jan 28 13:01:52 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 13:01:52 volumiolv volumio[866]: info: CoreCommandRouter::volumioPushState Jan 28 13:01:52 volumiolv volumio[866]: info: CoreStateMachine::updateTrackBlock Jan 28 13:01:52 volumiolv volumio[866]: info: CorePlayQueue::getTrackBlock Jan 28 13:01:52 volumiolv volumio[866]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 13:01:52 volumiolv volumio[866]: info: CoreStateMachine::setRepeat true single undefined Jan 28 13:01:52 volumiolv volumio[866]: info: CoreStateMachine::pushState Jan 28 13:01:52 volumiolv volumio[866]: info: CorePlayQueue::getTrack 0 Jan 28 13:01:52 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 13:01:52 volumiolv volumio[866]: info: CoreCommandRouter::volumioPushState Jan 28 13:01:52 volumiolv volumio[866]: info: CoreStateMachine::setRandom true Jan 28 13:01:52 volumiolv volumio[866]: info: CoreStateMachine::pushState Jan 28 13:01:52 volumiolv volumio[866]: info: CorePlayQueue::getTrack 0 Jan 28 13:01:52 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 13:01:52 volumiolv volumio[866]: info: CoreCommandRouter::volumioPushState Jan 28 13:01:52 volumiolv volumio[866]: info: Setting Device type: Raspberry PI Jan 28 13:01:52 volumiolv volumio[866]: info: Completed loading Core Plugins Jan 28 13:01:52 volumiolv volumio[866]: info: Preparing to generate the ALSA configuration file Jan 28 13:01:52 volumiolv wpa_supplicant[754]: wlan0: Failed to initiate sched scan Jan 28 13:01:52 volumiolv volumio[866]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 28 13:01:52 volumiolv volumio[866]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Jan 28 13:01:52 volumiolv volumio[866]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 28 13:01:52 volumiolv volumio[866]: info: Asound.conf file unchanged, so no further update is needed Jan 28 13:01:52 volumiolv volumio[866]: info: Output device has changed, restarting MPD Jan 28 13:01:52 volumiolv volumio[866]: info: Output device has changed, restarting Shairport Sync Jan 28 13:01:52 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 13:01:52 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 13:01:52 volumiolv sudo[1028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 13:01:52 volumiolv sudo[1030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 13:01:52 volumiolv sudo[1030]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:52 volumiolv sudo[1028]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:52 volumiolv sudo[1028]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:52 volumiolv systemd[1]: Stopping Music Player Daemon... Jan 28 13:01:52 volumiolv volumio[866]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 13:01:52 volumiolv volumio[866]: info: ___________ START PLUGINS ___________ Jan 28 13:01:52 volumiolv volumio[866]: info: ControllerMpd::onStart: Initializing MPD Jan 28 13:01:52 volumiolv volumio[866]: info: Creating MPD Configuration file Jan 28 13:01:52 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 13:01:52 volumiolv volumio[866]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 13:01:52 volumiolv volumio[866]: info: [1738098112964] CoreMusicLibrary::Adding element Media Servers Jan 28 13:01:52 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 13:01:52 volumiolv sudo[1037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 13:01:52 volumiolv sudo[1037]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:52 volumiolv sudo[1037]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:52 volumiolv sudo[1039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 13:01:52 volumiolv sudo[1039]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:52 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 13:01:52 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 13:01:53 volumiolv volumio[866]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 13:01:53 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 13:01:53 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 13:01:53 volumiolv volumio[866]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 13:01:53 volumiolv volumio[866]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 13:01:53 volumiolv volumio[866]: info: [1738098113103] CoreMusicLibrary::Adding element Last_100 Jan 28 13:01:53 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 13:01:53 volumiolv volumio[866]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 13:01:53 volumiolv volumio[866]: info: [1738098113108] CoreMusicLibrary::Adding element Webradio Jan 28 13:01:53 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 13:01:53 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 13:01:53 volumiolv volumio[866]: info: Initializing BBC Radios Jan 28 13:01:53 volumiolv volumio[866]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jan 28 13:01:53 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 13:01:53 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 13:01:53 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 13:01:53 volumiolv volumio[866]: info: GPIO-Buttons: Reading config and creating triggers... Jan 28 13:01:53 volumiolv volumio[866]: info: GPIO-Buttons: playPause on pin 529 Jan 28 13:01:53 volumiolv systemd[1]: mpd.service: Succeeded. Jan 28 13:01:53 volumiolv systemd[1]: Stopped Music Player Daemon. Jan 28 13:01:53 volumiolv volumio[866]: info: Volumio Calling Home Jan 28 13:01:53 volumiolv systemd[1]: Starting Music Player Daemon... Jan 28 13:01:53 volumiolv volumio[866]: info: GPIO-Buttons started Jan 28 13:01:53 volumiolv sudo[1065]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 28 13:01:53 volumiolv sudo[1065]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:53 volumiolv sudo[1065]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:53 volumiolv volumio[866]: info: VolumeController:: Volume=100 Mute =false Jan 28 13:01:53 volumiolv volumio[866]: info: CoreStateMachine::pushState Jan 28 13:01:53 volumiolv volumio[866]: info: CorePlayQueue::getTrack 0 Jan 28 13:01:53 volumiolv volumio[866]: info: CoreCommandRouter::volumioPushState Jan 28 13:01:53 volumiolv volumio[866]: Unhandled rejection Error: No sockets available, cannot start. Jan 28 13:01:53 volumiolv volumio[866]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 28 13:01:53 volumiolv volumio[866]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 28 13:01:53 volumiolv volumio[866]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 28 13:01:53 volumiolv volumio[866]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 28 13:01:53 volumiolv volumio[866]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 28 13:01:53 volumiolv volumio[866]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 28 13:01:53 volumiolv volumio[866]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 28 13:01:53 volumiolv volumio[866]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 28 13:01:53 volumiolv volumio[866]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jan 28 13:01:53 volumiolv volumio[866]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 28 13:01:53 volumiolv volumio[866]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 28 13:01:53 volumiolv volumio[866]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 28 13:01:53 volumiolv volumio[866]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 28 13:01:53 volumiolv volumio[866]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 28 13:01:53 volumiolv volumio[866]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 28 13:01:53 volumiolv volumio[866]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 28 13:01:53 volumiolv volumio[866]: info: MPD Permissions set Jan 28 13:01:53 volumiolv volumio[866]: info: MPD Permissions set Jan 28 13:01:53 volumiolv volumio[866]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 28 13:01:53 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 13:01:53 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 13:01:53 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 13:01:53 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 13:01:53 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 13:01:53 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 13:01:53 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 13:01:53 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 13:01:53 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 13:01:53 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 13:01:53 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 13:01:53 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 13:01:53 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 13:01:53 volumiolv volumio[866]: info: Starting Shairport Sync Jan 28 13:01:53 volumiolv volumio[866]: info: Starting Shairport Sync Jan 28 13:01:53 volumiolv volumio[866]: info: Starting Shairport Sync Jan 28 13:01:53 volumiolv sudo[1085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 13:01:53 volumiolv sudo[1085]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:53 volumiolv sudo[1087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 13:01:53 volumiolv sudo[1087]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:53 volumiolv sudo[1090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 13:01:53 volumiolv sudo[1090]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:53 volumiolv volumio[866]: info: CoreCommandRouter::volumioGetState Jan 28 13:01:53 volumiolv volumio[866]: info: CorePlayQueue::getTrack 0 Jan 28 13:01:53 volumiolv systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 28 13:01:53 volumiolv systemd[1]: shairport-sync.service: Succeeded. Jan 28 13:01:53 volumiolv systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 28 13:01:53 volumiolv systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 28 13:01:53 volumiolv sudo[1087]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:53 volumiolv sudo[1090]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:53 volumiolv volumio[866]: info: Shairport-Sync Started Jan 28 13:01:53 volumiolv sudo[1085]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:53 volumiolv volumio[866]: Error adding Membership: Error: addMembership EINVAL Jan 28 13:01:53 volumiolv volumio[866]: info: Shairport-Sync Started Jan 28 13:01:53 volumiolv volumio[866]: info: Shairport-Sync Started Jan 28 13:01:54 volumiolv volumio-time-update[491]: volumio-time-update-util: Fetching time from Volumio... Jan 28 13:01:54 volumiolv volumio-time-update[491]: volumio-time-update-util: Date not found in response Jan 28 13:01:54 volumiolv volumio-time-update[491]: volumio-time-update-util: Retrying in 5 seconds... Jan 28 13:01:55 volumiolv mpd[1075]: Jan 28 13:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 13:01:55 volumiolv systemd[1]: Started Music Player Daemon. Jan 28 13:01:55 volumiolv sudo[1030]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:55 volumiolv sudo[1039]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:55 volumiolv volumio[866]: info: Completed starting Core Plugins Jan 28 13:01:55 volumiolv volumio[866]: info: ------------------------------------------- Jan 28 13:01:55 volumiolv volumio[866]: info: ----- MyVolumio plugins startup ---- Jan 28 13:01:55 volumiolv volumio[866]: info: ------------------------------------------- Jan 28 13:01:55 volumiolv volumio[866]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 28 13:01:55 volumiolv volumio[866]: info: MPD running with PID1075 Jan 28 13:01:55 volumiolv volumio[866]: ,establishing connection Jan 28 13:01:55 volumiolv volumio[866]: error: MPD error: The expression evaluated to a falsy value: Jan 28 13:01:55 volumiolv volumio[866]: assert.ok(self.idling) Jan 28 13:01:55 volumiolv volumio[866]: error: The expression evaluated to a falsy value: Jan 28 13:01:55 volumiolv volumio[866]: assert.ok(self.idling) Jan 28 13:01:55 volumiolv volumio[866]: error: MPD error: The expression evaluated to a falsy value: Jan 28 13:01:55 volumiolv volumio[866]: assert.ok(self.idling) Jan 28 13:01:55 volumiolv volumio[866]: error: The expression evaluated to a falsy value: Jan 28 13:01:55 volumiolv volumio[866]: assert.ok(self.idling) Jan 28 13:01:55 volumiolv volumio[866]: error: updateQueue error: null Jan 28 13:01:56 volumiolv sudo[1106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=john.egenberger@gmail.com,password=45glock21,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.42.243/Music /mnt/NAS/GarageMusic Jan 28 13:01:56 volumiolv sudo[1106]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:56 volumiolv sudo[1106]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:56 volumiolv kernel: CIFS: Attempting to mount //192.168.42.243/Music Jan 28 13:01:56 volumiolv kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 28 13:01:56 volumiolv kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jan 28 13:01:56 volumiolv volumio[866]: info: Cannot mount NAS GarageMusic at system boot, trial number 2 ,retrying in 5 seconds Jan 28 13:01:57 volumiolv sudo[1125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 13:01:57 volumiolv sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:57 volumiolv sudo[1127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 13:01:57 volumiolv sudo[1127]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:57 volumiolv sudo[1127]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:57 volumiolv sudo[1125]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:57 volumiolv sudo[1132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 28 13:01:57 volumiolv sudo[1132]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:01:57 volumiolv systemd[1]: Started UPnP Renderer front-end to MPD. Jan 28 13:01:57 volumiolv sudo[1132]: pam_unix(sudo:session): session closed for user root Jan 28 13:01:57 volumiolv volumio[866]: info: Upmpdcli Daemon Started Jan 28 13:01:58 volumiolv wpa_supplicant[754]: wlan0: Failed to initiate sched scan Jan 28 13:01:59 volumiolv volumio-time-update[491]: volumio-time-update-util: Fetching time from Volumio... Jan 28 13:01:59 volumiolv volumio-time-update[491]: volumio-time-update-util: Date not found in response Jan 28 13:01:59 volumiolv volumio-time-update[491]: volumio-time-update-util: Retrying in 5 seconds... Jan 28 13:02:00 volumiolv volumio[866]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 28 13:02:01 volumiolv volumio-remote-updater[485]: [2025-01-28 13:02:01] [connect] Successful connection Jan 28 13:02:01 volumiolv volumio-remote-updater[485]: [2025-01-28 13:02:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1738098121 101 Jan 28 13:02:01 volumiolv volumio[866]: 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: 3 Jan 28 13:02:01 volumiolv sudo[1157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=john.egenberger@gmail.com,password=45glock21,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.42.243/Music /mnt/NAS/GarageMusic Jan 28 13:02:01 volumiolv sudo[1157]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:02:01 volumiolv kernel: CIFS: Attempting to mount //192.168.42.243/Music Jan 28 13:02:01 volumiolv kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 28 13:02:01 volumiolv kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jan 28 13:02:01 volumiolv sudo[1157]: pam_unix(sudo:session): session closed for user root Jan 28 13:02:01 volumiolv volumio[866]: info: Cannot mount NAS GarageMusic at system boot, trial number 3 ,retrying in 5 seconds Jan 28 13:02:03 volumiolv volumio[866]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.785&uuid=127ac163b56baf9da624e87ec7b38442" http://updates.volumio.org/downloader-v1/track-device Jan 28 13:02:03 volumiolv volumio[866]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 28 13:02:03 volumiolv volumio[866]: Dload Upload Total Spent Left Speed Jan 28 13:02:03 volumiolv volumio[866]: [132B blob data] Jan 28 13:02:03 volumiolv volumio[866]: retrying in 5 seconds, trial 0 Jan 28 13:02:03 volumiolv volumio[866]: info: Volumio Calling Home Jan 28 13:02:03 volumiolv wpa_supplicant[754]: wlan0: Failed to initiate sched scan Jan 28 13:02:04 volumiolv volumio-time-update[491]: volumio-time-update-util: Fetching time from Volumio... Jan 28 13:02:04 volumiolv volumio-time-update[491]: volumio-time-update-util: Date not found in response Jan 28 13:02:04 volumiolv volumio-time-update[491]: volumio-time-update-util: Retrying in 5 seconds... Jan 28 13:02:06 volumiolv sudo[1170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=john.egenberger@gmail.com,password=45glock21,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.42.243/Music /mnt/NAS/GarageMusic Jan 28 13:02:06 volumiolv sudo[1170]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:02:06 volumiolv sudo[1170]: pam_unix(sudo:session): session closed for user root Jan 28 13:02:06 volumiolv kernel: CIFS: Attempting to mount //192.168.42.243/Music Jan 28 13:02:06 volumiolv kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 28 13:02:06 volumiolv kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jan 28 13:02:06 volumiolv volumio[866]: info: Cannot mount NAS GarageMusic at system boot, trial number 4 ,retrying in 5 seconds Jan 28 13:02:09 volumiolv volumio-time-update[491]: volumio-time-update-util: Fetching time from Volumio... Jan 28 13:02:09 volumiolv volumio-time-update[491]: volumio-time-update-util: Date not found in response Jan 28 13:02:09 volumiolv volumio-time-update[491]: volumio-time-update-util: Retrying in 5 seconds... Jan 28 13:02:09 volumiolv wpa_supplicant[754]: wlan0: Failed to initiate sched scan Jan 28 13:02:11 volumiolv sudo[1197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=john.egenberger@gmail.com,password=45glock21,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.42.243/Music /mnt/NAS/GarageMusic Jan 28 13:02:11 volumiolv sudo[1197]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:02:11 volumiolv sudo[1197]: pam_unix(sudo:session): session closed for user root Jan 28 13:02:11 volumiolv volumio[866]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jan 28 13:02:11 volumiolv kernel: CIFS: Attempting to mount //192.168.42.243/Music Jan 28 13:02:11 volumiolv kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 28 13:02:11 volumiolv kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jan 28 13:02:13 volumiolv volumio[866]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.785&uuid=127ac163b56baf9da624e87ec7b38442" http://updates.volumio.org/downloader-v1/track-device Jan 28 13:02:13 volumiolv volumio[866]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 28 13:02:13 volumiolv volumio[866]: Dload Upload Total Spent Left Speed Jan 28 13:02:13 volumiolv volumio[866]: [132B blob data] Jan 28 13:02:13 volumiolv volumio[866]: retrying in 5 seconds, trial 1 Jan 28 13:02:13 volumiolv volumio[866]: info: Volumio Calling Home Jan 28 13:02:14 volumiolv volumio-time-update[491]: volumio-time-update-util: Fetching time from Volumio... Jan 28 13:02:14 volumiolv volumio-time-update[491]: volumio-time-update-util: Date not found in response Jan 28 13:02:14 volumiolv volumio-time-update[491]: volumio-time-update-util: Retrying in 5 seconds... Jan 28 13:02:14 volumiolv wpa_supplicant[754]: wlan0: Failed to initiate sched scan Jan 28 13:02:15 volumiolv volumio[866]: error: MyVolumio Plugin failed to start in a timely fashion Jan 28 13:02:15 volumiolv volumio[866]: [Metrics] CommandRouter: 32s 512.61ms Jan 28 13:02:15 volumiolv volumio[866]: info: CoreCommandRouter::volumiosetStartupVolume Jan 28 13:02:15 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 13:02:15 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 13:02:15 volumiolv volumio[866]: info: CoreCommandRouter::Close All Modals sent Jan 28 13:02:15 volumiolv volumio[866]: info: CoreCommandRouter::Close All Modals sent Jan 28 13:02:15 volumiolv kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jan 28 13:02:16 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 28 13:02:16 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 13:02:16 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 28 13:02:19 volumiolv volumio-time-update[491]: volumio-time-update-util: Fetching time from Volumio... Jan 28 13:02:19 volumiolv volumio-time-update[491]: volumio-time-update-util: Date not found in response Jan 28 13:02:19 volumiolv volumio-time-update[491]: volumio-time-update-util: Retrying in 5 seconds... Jan 28 13:02:20 volumiolv wpa_supplicant[754]: wlan0: Failed to initiate sched scan Jan 28 13:02:20 volumiolv ntpd[677]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 28 13:02:20 volumiolv ntpd[677]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 28 13:02:22 volumiolv ntpd[677]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 28 13:02:22 volumiolv volumio[866]: info: BOOT COMPLETED Jan 28 13:02:23 volumiolv volumio[866]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jan 28 13:02:23 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 13:02:23 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 13:02:23 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 13:02:23 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 28 13:02:23 volumiolv ntpd[677]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 28 13:02:23 volumiolv volumio[866]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.785&uuid=127ac163b56baf9da624e87ec7b38442" http://updates.volumio.org/downloader-v1/track-device Jan 28 13:02:23 volumiolv volumio[866]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 28 13:02:23 volumiolv volumio[866]: Dload Upload Total Spent Left Speed Jan 28 13:02:23 volumiolv volumio[866]: [132B blob data] Jan 28 13:02:23 volumiolv volumio[866]: retrying in 5 seconds, trial 2 Jan 28 13:02:23 volumiolv volumio[866]: info: Volumio Calling Home Jan 28 13:02:24 volumiolv volumio-time-update[491]: volumio-time-update-util: Fetching time from Volumio... Jan 28 13:02:24 volumiolv volumio-time-update[491]: volumio-time-update-util: Date not found in response Jan 28 13:02:24 volumiolv volumio-time-update[491]: volumio-time-update-util: Retrying in 5 seconds... Jan 28 13:02:25 volumiolv wpa_supplicant[754]: wlan0: Failed to initiate sched scan Jan 28 13:02:29 volumiolv volumio-time-update[491]: volumio-time-update-util: Fetching time from Volumio... Jan 28 13:02:29 volumiolv volumio-time-update[491]: volumio-time-update-util: Date not found in response Jan 28 13:02:29 volumiolv volumio-time-update[491]: volumio-time-update-util: Retrying in 5 seconds... Jan 28 13:02:31 volumiolv wpa_supplicant[754]: wlan0: Failed to initiate sched scan Jan 28 13:02:34 volumiolv volumio-time-update[491]: volumio-time-update-util: Fetching time from Volumio... Jan 28 13:02:34 volumiolv volumio-time-update[491]: volumio-time-update-util: Date not found in response Jan 28 13:02:34 volumiolv volumio-time-update[491]: volumio-time-update-util: Retrying in 5 seconds... Jan 28 13:02:36 volumiolv wpa_supplicant[754]: wlan0: Failed to initiate sched scan Jan 28 13:02:39 volumiolv volumio-time-update[491]: volumio-time-update-util: Fetching time from Volumio... Jan 28 13:02:39 volumiolv volumio-time-update[491]: volumio-time-update-util: Date not found in response Jan 28 13:02:39 volumiolv volumio-time-update[491]: volumio-time-update-util: Retrying in 5 seconds... Jan 28 13:02:42 volumiolv wpa_supplicant[754]: wlan0: Failed to initiate sched scan Jan 28 13:02:44 volumiolv systemd[1]: nmbd.service: Start operation timed out. Terminating. Jan 28 13:02:44 volumiolv systemd[1]: nmbd.service: Main process exited, code=killed, status=15/TERM Jan 28 13:02:44 volumiolv systemd[1]: nmbd.service: Failed with result 'timeout'. Jan 28 13:02:44 volumiolv systemd[1]: Failed to start Samba NMB Daemon. Jan 28 13:02:44 volumiolv volumio-time-update[491]: volumio-time-update-util: Fetching time from Volumio... Jan 28 13:02:44 volumiolv systemd[1]: Starting Samba Winbind Daemon... Jan 28 13:02:44 volumiolv volumio-time-update[491]: volumio-time-update-util: Date not found in response Jan 28 13:02:44 volumiolv volumio-time-update[491]: volumio-time-update-util: Retrying in 5 seconds... Jan 28 13:02:44 volumiolv winbindd[1315]: [2025/01/28 13:02:44.753792, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jan 28 13:02:44 volumiolv winbindd[1315]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 28 13:02:44 volumiolv winbindd[1315]: [2025/01/28 13:02:44.778371, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 28 13:02:44 volumiolv systemd[1]: Started Samba Winbind Daemon. Jan 28 13:02:44 volumiolv winbindd[1315]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 28 13:02:44 volumiolv systemd[1]: Starting Samba SMB Daemon... Jan 28 13:02:45 volumiolv winbindd[1315]: [2025/01/28 13:02:45.398399, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Jan 28 13:02:45 volumiolv winbindd[1315]: res_names->count = 3, expected 4 Jan 28 13:02:45 volumiolv smbd[1324]: [2025/01/28 13:02:45.426667, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 28 13:02:45 volumiolv systemd[1]: Started Samba SMB Daemon. Jan 28 13:02:45 volumiolv smbd[1324]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 28 13:02:47 volumiolv wpa_supplicant[754]: wlan0: Failed to initiate sched scan Jan 28 13:02:49 volumiolv volumio-time-update[491]: volumio-time-update-util: Fetching time from Volumio... Jan 28 13:02:49 volumiolv volumio-time-update[491]: volumio-time-update-util: Date not found in response Jan 28 13:02:49 volumiolv volumio-time-update[491]: volumio-time-update-util: Retrying in 5 seconds... Jan 28 13:02:53 volumiolv wpa_supplicant[754]: wlan0: Trying to associate with SSID 'JohnsPlace' Jan 28 13:02:53 volumiolv wpa_supplicant[754]: wlan0: Associated with 3c:7c:3f:90:dd:80 Jan 28 13:02:53 volumiolv wpa_supplicant[754]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3c:7c:3f:90:dd:80 completed [id=0 id_str=] Jan 28 13:02:53 volumiolv dhcpcd[769]: wlan0: carrier acquired Jan 28 13:02:53 volumiolv wpa_supplicant[754]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 28 13:02:53 volumiolv dhcpcd[769]: wlan0: connected to Access Point `JohnsPlace' Jan 28 13:02:53 volumiolv wpa_supplicant[754]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Jan 28 13:02:53 volumiolv dhcpcd[769]: DUID 00:01:00:01:2e:f2:a3:ac:2c:cf:67:b5:7f:c4 Jan 28 13:02:53 volumiolv dhcpcd[769]: wlan0: IAID 67:b5:7f:c4 Jan 28 13:02:53 volumiolv dhcpcd[769]: wlan0: adding address fe80::7193:dfb:8461:84d5 Jan 28 13:02:53 volumiolv dhcpcd[769]: ipv6_addaddr1: Permission denied Jan 28 13:02:53 volumiolv dhcpcd[769]: wlan0: soliciting an IPv6 router Jan 28 13:02:53 volumiolv dhcpcd[769]: wlan0: rebinding lease of 192.168.42.196 Jan 28 13:02:54 volumiolv volumio-time-update[491]: volumio-time-update-util: Fetching time from Volumio... Jan 28 13:02:54 volumiolv volumio-time-update[491]: volumio-time-update-util: Date not found in response Jan 28 13:02:54 volumiolv volumio-time-update[491]: volumio-time-update-util: Retrying in 5 seconds... Jan 28 13:02:56 volumiolv dhcpcd[769]: wlan0: probing address 192.168.42.196/24 Jan 28 13:02:57 volumiolv systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 28 13:02:57 volumiolv systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 28 13:02:59 volumiolv volumio-time-update[491]: volumio-time-update-util: Fetching time from Volumio... Jan 28 13:02:59 volumiolv volumio-time-update[491]: volumio-time-update-util: Date not found in response Jan 28 13:02:59 volumiolv volumio-time-update[491]: volumio-time-update-util: Retrying in 5 seconds... Jan 28 13:03:02 volumiolv dhcpcd[769]: wlan0: leased 192.168.42.196 for 86400 seconds Jan 28 13:03:02 volumiolv avahi-daemon[497]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.42.196. Jan 28 13:03:02 volumiolv avahi-daemon[497]: New relevant interface wlan0.IPv4 for mDNS. Jan 28 13:03:02 volumiolv dhcpcd[769]: wlan0: adding route to 192.168.42.0/24 Jan 28 13:03:02 volumiolv avahi-daemon[497]: Registering new address record for 192.168.42.196 on wlan0.IPv4. Jan 28 13:03:02 volumiolv dhcpcd[769]: wlan0: adding default route via 192.168.42.1 Jan 28 13:03:03 volumiolv volumio[866]: info: Discovery: adding d8d6cfe7-9fa9-42a6-9c99-ebfeca4e6a18 Jan 28 13:03:03 volumiolv volumio[866]: info: Discovery: Found device VolumioLV Jan 28 13:03:03 volumiolv volumio[866]: info: CoreCommandRouter::volumioGetState Jan 28 13:03:03 volumiolv volumio[866]: info: CorePlayQueue::getTrack 0 Jan 28 13:03:03 volumiolv volumio[866]: info: Discovery: adding 145b1163-276f-4d67-9cdd-071f402f2774 Jan 28 13:03:03 volumiolv volumio[866]: info: Discovery: Found device VolumioBR Jan 28 13:03:03 volumiolv volumio[866]: info: Discovery: Connecting to remote: 10.0.0.1 Jan 28 13:03:03 volumiolv ntpd[677]: Listen normally on 3 wlan0 192.168.42.196:123 Jan 28 13:03:03 volumiolv ntpd[677]: new interface(s) found: waking up resolver Jan 28 13:03:04 volumiolv volumio-time-update[491]: volumio-time-update-util: Fetching time from Volumio... Jan 28 13:03:04 volumiolv volumio-time-update[491]: volumio-time-update-util: Setting system time to: 2018-05-04 22:05:29 Jan 28 13:03:04 volumiolv sudo[1410]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2018-05-04 22:05:29 Jan 28 13:03:04 volumiolv sudo[1410]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 13:03:04 volumiolv dbus-daemon[503]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.15' (uid=0 pid=1411 comm="timedatectl set-time 2018-05-04 22:05:29 ") Jan 28 13:03:04 volumiolv systemd[1]: Starting Time & Date Service... Jan 28 13:03:04 volumiolv dbus-daemon[503]: [system] Successfully activated service 'org.freedesktop.timedate1' Jan 28 13:03:04 volumiolv systemd[1]: Started Time & Date Service. May 06 20:56:35 volumiolv sudo[6498]: pam_unix(sudo:account): account volumio has password changed in future May 06 20:56:35 volumiolv sudo[6498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 06 20:56:35 volumiolv sudo[6498]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:56:35 volumiolv sudo[6500]: pam_unix(sudo:account): account volumio has password changed in future May 06 20:56:35 volumiolv sudo[6500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 06 20:56:35 volumiolv sudo[6500]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:56:35 volumiolv sudo[6498]: pam_unix(sudo:session): session closed for user root May 06 20:56:35 volumiolv sudo[6500]: pam_unix(sudo:session): session closed for user root May 06 20:56:35 volumiolv volumio[866]: verbose: New Socket.io Connection to 192.168.42.196 from 192.168.42.212 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:136.0) Gecko/20100101 Firefox/136.0 Engine version: 3 Transport: polling Total Clients: 5 May 06 20:56:36 volumiolv sudo[6509]: pam_unix(sudo:account): account volumio has password changed in future May 06 20:56:36 volumiolv sudo[6509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 06 20:56:36 volumiolv sudo[6509]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:56:36 volumiolv sudo[6511]: pam_unix(sudo:account): account volumio has password changed in future May 06 20:56:36 volumiolv sudo[6511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 06 20:56:36 volumiolv sudo[6511]: pam_unix(sudo:session): session opened for user root by (uid=0) May 06 20:56:36 volumiolv sudo[6509]: pam_unix(sudo:session): session closed for user root May 06 20:56:36 volumiolv sudo[6511]: pam_unix(sudo:session): session closed for user root May 06 20:56:36 volumiolv volumio[866]: verbose: New Socket.io Connection to 192.168.42.196 from 192.168.42.212 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:136.0) Gecko/20100101 Firefox/136.0 Engine version: 3 Transport: polling Total Clients: 5 May 06 20:56:37 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 06 20:56:37 volumiolv volumio[866]: info: CoreCommandRouter::volumioGetVisibleSources May 06 20:56:37 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 06 20:56:37 volumiolv volumio[866]: info: CoreCommandRouter::volumioGetState May 06 20:56:37 volumiolv volumio[866]: info: CorePlayQueue::getTrack 0 May 06 20:56:37 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 06 20:56:37 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 06 20:56:37 volumiolv volumio[866]: info: Received Get System Info May 06 20:56:37 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 20:56:37 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 20:56:37 volumiolv volumio[866]: info: Discovery: Getting this device information May 06 20:56:37 volumiolv volumio[866]: info: CoreCommandRouter::volumioGetState May 06 20:56:37 volumiolv volumio[866]: info: CorePlayQueue::getTrack 0 May 06 20:56:37 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 20:56:37 volumiolv volumio[866]: info: CoreCommandRouter::volumioGetState May 06 20:56:37 volumiolv volumio[866]: info: CorePlayQueue::getTrack 0 May 06 20:56:37 volumiolv volumio[866]: info: Listing playlists May 06 20:56:37 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 06 20:56:37 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 06 20:56:37 volumiolv volumio[866]: info: CoreCommandRouter::volumioGetState May 06 20:56:37 volumiolv volumio[866]: info: CorePlayQueue::getTrack 0 May 06 20:56:38 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 06 20:56:38 volumiolv volumio[866]: info: Received Get System Info May 06 20:56:38 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 20:56:38 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 20:56:38 volumiolv volumio[866]: info: Discovery: Getting this device information May 06 20:56:38 volumiolv volumio[866]: info: CoreCommandRouter::volumioGetState May 06 20:56:38 volumiolv volumio[866]: info: CorePlayQueue::getTrack 0 May 06 20:56:38 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 20:56:39 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 06 20:56:41 volumiolv volumio[866]: info: CoreCommandRouter::volumioGetQueue May 06 20:56:41 volumiolv volumio[866]: info: CoreStateMachine::getQueue May 06 20:56:41 volumiolv volumio[866]: info: CorePlayQueue::getQueue May 06 20:56:43 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 06 20:56:43 volumiolv volumio[866]: info: CURURI: music-library May 06 20:56:43 volumiolv volumio[866]: info: Preload queue cleared May 06 20:56:46 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 06 20:56:52 volumiolv volumio[866]: info: CoreCommandRouter::volumioGetState May 06 20:56:52 volumiolv volumio[866]: info: CorePlayQueue::getTrack 0 May 06 20:56:55 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 06 20:56:55 volumiolv volumio[866]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 06 20:56:55 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 06 20:56:55 volumiolv volumio[866]: info: Received Get System Version May 06 20:56:55 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 06 20:56:55 volumiolv volumio[866]: info: Received Get System Info May 06 20:56:55 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 06 20:56:55 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 06 20:56:55 volumiolv volumio[866]: info: Discovery: Getting this device information May 06 20:56:55 volumiolv volumio[866]: info: CoreCommandRouter::volumioGetState May 06 20:56:55 volumiolv volumio[866]: info: CorePlayQueue::getTrack 0 May 06 20:56:55 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 06 20:57:01 volumiolv volumio[866]: info: CoreCommandRouter::volumioGetState May 06 20:57:01 volumiolv volumio[866]: info: CorePlayQueue::getTrack 0 May 06 20:57:08 volumiolv volumio[866]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 06 20:57:08 volumiolv volumio[866]: info: CURURI: albums:// May 06 20:57:08 volumiolv volumio[866]: info: listAlbums - loading Albums from cache May 06 20:57:08 volumiolv volumio[866]: info: Preload queue cleared May 06 20:57:08 volumiolv volumio[866]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 06 20:57:08 volumiolv volumio[866]: Error: certificate is not yet valid May 06 20:57:08 volumiolv volumio[866]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) May 06 20:57:08 volumiolv volumio[866]: at TLSSocket.emit (events.js:315:20) May 06 20:57:08 volumiolv volumio[866]: at TLSSocket._finishInit (_tls_wrap.js:932:8) May 06 20:57:08 volumiolv volumio[866]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { May 06 20:57:08 volumiolv volumio[866]: code: 'CERT_NOT_YET_VALID' May 06 20:57:08 volumiolv volumio[866]: } May 06 20:57:08 volumiolv volumio[866]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 06 20:57:09 volumiolv sudo[6577]: pam_unix(sudo:account): account volumio has password changed in future May 06 20:57:09 volumiolv sudo[6577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2018-05-06 20:56 May 06 20:57:09 volumiolv sudo[6577]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"