-- Logs begin at Fri 2025-05-16 21:20:42 CST, end at Fri 2025-05-16 23:36:17 CST. -- May 16 23:35:47 volumio-x86 kernel: Freeing unused kernel image (rodata/data gap) memory: 1504K May 16 23:35:47 volumio-x86 kernel: Run /init as init process May 16 23:35:47 volumio-x86 kernel: with arguments: May 16 23:35:47 volumio-x86 kernel: /init May 16 23:35:47 volumio-x86 kernel: splash May 16 23:35:47 volumio-x86 kernel: with environment: May 16 23:35:47 volumio-x86 kernel: HOME=/ May 16 23:35:47 volumio-x86 kernel: TERM=linux May 16 23:35:47 volumio-x86 kernel: BOOT_IMAGE=vmlinuz May 16 23:35:47 volumio-x86 kernel: biosdevname=0 May 16 23:35:47 volumio-x86 kernel: imgpart=UUID=d35eb915-f24a-437a-a43b-4afe991304f7 May 16 23:35:47 volumio-x86 kernel: bootpart=UUID=79FB-2BA3 May 16 23:35:47 volumio-x86 kernel: datapart=UUID=2fc5abe1-9579-4377-a3f7-b00c1ebaeecd May 16 23:35:47 volumio-x86 kernel: hwdevice=x86 May 16 23:35:47 volumio-x86 kernel: uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg May 16 23:35:47 volumio-x86 kernel: imgfile=/volumio_current.sqsh May 16 23:35:47 volumio-x86 kernel: use_kmsg=no May 16 23:35:47 volumio-x86 kernel: hid: raw HID events driver (C) Jiri Kosina May 16 23:35:47 volumio-x86 kernel: ahci 0000:00:1f.2: version 3.0 May 16 23:35:47 volumio-x86 kernel: ACPI: bus type drm_connector registered May 16 23:35:47 volumio-x86 kernel: ahci 0000:00:1f.2: AHCI 0001.0300 32 slots 4 ports 6 Gbps 0x2 impl SATA mode May 16 23:35:47 volumio-x86 kernel: ahci 0000:00:1f.2: flags: 64bit ncq pm led clo pio slum part ems May 16 23:35:47 volumio-x86 kernel: i801_smbus 0000:00:1f.3: SPD Write Disable is set May 16 23:35:47 volumio-x86 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt May 16 23:35:47 volumio-x86 kernel: scsi host0: ahci May 16 23:35:47 volumio-x86 kernel: usbcore: registered new interface driver usbhid May 16 23:35:47 volumio-x86 kernel: usbhid: USB HID core driver May 16 23:35:47 volumio-x86 kernel: i2c i2c-0: 1/4 memory slots populated (from DMI) May 16 23:35:47 volumio-x86 kernel: scsi host1: ahci May 16 23:35:47 volumio-x86 kernel: i2c i2c-0: Successfully instantiated SPD at 0x50 May 16 23:35:47 volumio-x86 kernel: scsi host2: ahci May 16 23:35:47 volumio-x86 kernel: scsi host3: ahci May 16 23:35:47 volumio-x86 kernel: ata1: DUMMY May 16 23:35:47 volumio-x86 kernel: ata2: SATA max UDMA/133 abar m2048@0xf7c1a000 port 0xf7c1a180 irq 27 May 16 23:35:47 volumio-x86 kernel: ata3: DUMMY May 16 23:35:47 volumio-x86 kernel: ata4: DUMMY May 16 23:35:47 volumio-x86 kernel: input: wch.cn USB2IIC_CTP_CONTROL as /devices/pci0000:00/0000:00:14.0/usb2/2-4/2-4:1.0/0003:1A86:E5E3.0001/input/input5 May 16 23:35:47 volumio-x86 kernel: hid-generic 0003:1A86:E5E3.0001: input,hidraw0: USB HID v1.00 Device [wch.cn USB2IIC_CTP_CONTROL] on usb-0000:00:14.0-4/input0 May 16 23:35:47 volumio-x86 kernel: tsc: Refined TSC clocksource calibration: 3192.608 MHz May 16 23:35:47 volumio-x86 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2e0502f3f6b, max_idle_ns: 440795217409 ns May 16 23:35:47 volumio-x86 kernel: clocksource: Switched to clocksource tsc May 16 23:35:47 volumio-x86 kernel: ata2: SATA link up 6.0 Gbps (SStatus 133 SControl 300) May 16 23:35:47 volumio-x86 kernel: ata2.00: ATA-11: SAMSWEET SSD SM851 128GB, 1.00, max UDMA/133 May 16 23:35:47 volumio-x86 kernel: ata2.00: 250069680 sectors, multi 16: LBA48 NCQ (depth 32), AA May 16 23:35:47 volumio-x86 kernel: ata2.00: configured for UDMA/133 May 16 23:35:47 volumio-x86 kernel: scsi 1:0:0:0: Direct-Access ATA SAMSWEET SSD SM8 1.00 PQ: 0 ANSI: 5 May 16 23:35:47 volumio-x86 kernel: sd 1:0:0:0: [sda] 250069680 512-byte logical blocks: (128 GB/119 GiB) May 16 23:35:47 volumio-x86 kernel: sd 1:0:0:0: [sda] Write Protect is off May 16 23:35:47 volumio-x86 kernel: sd 1:0:0:0: [sda] Mode Sense: 00 3a 00 00 May 16 23:35:47 volumio-x86 kernel: sd 1:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA May 16 23:35:47 volumio-x86 kernel: sd 1:0:0:0: [sda] Preferred minimum I/O size 512 bytes May 16 23:35:47 volumio-x86 kernel: sda: sda1 sda2 sda3 May 16 23:35:47 volumio-x86 kernel: sd 1:0:0:0: [sda] Attached SCSI disk May 16 23:35:47 volumio-x86 kernel: r8169 0000:02:00.0 eth0: RTL8168evl/8111evl, 00:e0:66:fe:d4:54, XID 2c9, IRQ 28 May 16 23:35:47 volumio-x86 kernel: r8169 0000:02:00.0 eth0: jumbo features [frames: 9194 bytes, tx checksumming: ko] May 16 23:35:47 volumio-x86 kernel: i915 0000:00:02.0: [drm] PipeC fused off May 16 23:35:47 volumio-x86 kernel: i915 0000:00:02.0: vgaarb: deactivate vga console May 16 23:35:47 volumio-x86 kernel: Console: switching to colour dummy device 80x25 May 16 23:35:47 volumio-x86 kernel: i915 0000:00:02.0: vgaarb: VGA decodes changed: olddecodes=io+mem,decodes=io+mem:owns=io+mem May 16 23:35:47 volumio-x86 kernel: [drm] Initialized i915 1.6.0 20201103 for 0000:00:02.0 on minor 0 May 16 23:35:47 volumio-x86 kernel: ACPI: video: Video Device [GFX0] (multi-head: yes rom: no post: no) May 16 23:35:47 volumio-x86 kernel: input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:00/input/input6 May 16 23:35:47 volumio-x86 kernel: fbcon: i915drmfb (fb0) is primary device May 16 23:35:47 volumio-x86 kernel: Console: switching to colour frame buffer device 50x80 May 16 23:35:47 volumio-x86 kernel: i915 0000:00:02.0: [drm] fb0: i915drmfb frame buffer device May 16 23:35:47 volumio-x86 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 16 23:35:47 volumio-x86 kernel: sdhci: Secure Digital Host Controller Interface driver May 16 23:35:47 volumio-x86 kernel: sdhci: Copyright(c) Pierre Ossman May 16 23:35:47 volumio-x86 kernel: fuse: init (API version 7.39) May 16 23:35:47 volumio-x86 kernel: usbcore: registered new interface driver usb-storage May 16 23:35:47 volumio-x86 kernel: usbcore: registered new interface driver uas May 16 23:35:47 volumio-x86 kernel: [drm] radeon kernel modesetting enabled. May 16 23:35:47 volumio-x86 kernel: scsi host4: pata_legacy May 16 23:35:47 volumio-x86 kernel: ata5: PATA max PIO4 cmd 0x1f0 ctl 0x3f6 irq 14 May 16 23:35:47 volumio-x86 kernel: scsi host4: pata_legacy May 16 23:35:47 volumio-x86 kernel: ata6: PATA max PIO4 cmd 0x170 ctl 0x376 irq 15 May 16 23:35:47 volumio-x86 kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! May 16 23:35:47 volumio-x86 kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. May 16 23:35:47 volumio-x86 kernel: EXT4-fs (sda2): recovery complete May 16 23:35:47 volumio-x86 kernel: EXT4-fs (sda2): mounted filesystem d35eb915-f24a-437a-a43b-4afe991304f7 r/w with ordered data mode. Quota mode: none. May 16 23:35:47 volumio-x86 kernel: loop: module loaded May 16 23:35:47 volumio-x86 kernel: block device autoloading is deprecated and will be removed. May 16 23:35:47 volumio-x86 kernel: loop0: detected capacity change from 0 to 1725144 May 16 23:35:47 volumio-x86 kernel: EXT4-fs (sda3): 2 orphan inodes deleted May 16 23:35:47 volumio-x86 kernel: EXT4-fs (sda3): recovery complete May 16 23:35:47 volumio-x86 kernel: EXT4-fs (sda3): mounted filesystem 2fc5abe1-9579-4377-a3f7-b00c1ebaeecd r/w with ordered data mode. Quota mode: none. May 16 23:35:47 volumio-x86 kernel: ext4 filesystem being mounted at /mnt/ext supports timestamps until 2038-01-19 (0x7fffffff) May 16 23:35:47 volumio-x86 systemd[1]: Inserted module 'autofs4' May 16 23:35:47 volumio-x86 systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) May 16 23:35:47 volumio-x86 systemd[1]: Detected architecture x86-64. May 16 23:35:47 volumio-x86 systemd[1]: Set hostname to . May 16 23:35:47 volumio-x86 systemd[1]: File /lib/systemd/system/systemd-journald.service:12 configures an IP firewall (IPAddressDeny=any), but the local system does not support BPF/cgroup based firewalling. May 16 23:35:47 volumio-x86 systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.) May 16 23:35:47 volumio-x86 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 16 23:35:47 volumio-x86 systemd[1]: Reached target System Time Synchronized. May 16 23:35:47 volumio-x86 systemd[1]: Listening on fsck to fsckd communication Socket. May 16 23:35:47 volumio-x86 systemd[1]: Listening on RPCbind Server Activation Socket. May 16 23:35:47 volumio-x86 systemd[1]: Listening on Journal Socket. May 16 23:35:47 volumio-x86 systemd-journald[471]: Journal started May 16 23:35:47 volumio-x86 systemd-journald[471]: Runtime journal (/run/log/journal/724bcbf01eebf9d6210f389b672e577a) is 3.7M, max 30.0M, 26.2M free. May 16 23:35:47 volumio-x86 fake-hwclock[477]: Fri May 16 15:35:47 UTC 2025 May 16 23:35:47 volumio-x86 systemd[1]: Started Restore / save the current clock. May 16 23:35:47 volumio-x86 systemd[1]: Started Load/Save Random Seed. May 16 23:35:47 volumio-x86 systemd[1]: Started Create System Users. May 16 23:35:47 volumio-x86 systemd[1]: Starting Create Static Device Nodes in /dev... May 16 23:35:47 volumio-x86 systemd[1]: dynamicswap.service: Succeeded. May 16 23:35:47 volumio-x86 systemd[1]: Started Create Static Device Nodes in /dev. May 16 23:35:47 volumio-x86 systemd[1]: Starting udev Kernel Device Manager... May 16 23:35:47 volumio-x86 systemd[1]: Reached target Local File Systems (Pre). May 16 23:35:47 volumio-x86 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. May 16 23:35:47 volumio-x86 systemd[1]: Mounting /tmp... May 16 23:35:47 volumio-x86 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. May 16 23:35:47 volumio-x86 systemd[1]: Mounting /var/log... May 16 23:35:47 volumio-x86 systemd[1]: Mounting /var/spool/cups... May 16 23:35:47 volumio-x86 systemd[1]: Mounted /tmp. May 16 23:35:47 volumio-x86 systemd[1]: Mounted RPC Pipe File System. May 16 23:35:47 volumio-x86 systemd[1]: Mounted /var/log. May 16 23:35:47 volumio-x86 systemd[1]: Mounted /var/spool/cups. May 16 23:35:47 volumio-x86 kernel: RPC: Registered named UNIX socket transport module. May 16 23:35:47 volumio-x86 kernel: RPC: Registered udp transport module. May 16 23:35:47 volumio-x86 kernel: RPC: Registered tcp transport module. May 16 23:35:47 volumio-x86 kernel: RPC: Registered tcp-with-tls transport module. May 16 23:35:47 volumio-x86 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. May 16 23:35:47 volumio-x86 systemd[1]: Mounting /var/spool/cups/tmp... May 16 23:35:47 volumio-x86 systemd[1]: Starting Flush Journal to Persistent Storage... May 16 23:35:47 volumio-x86 systemd[1]: Mounted /var/spool/cups/tmp. May 16 23:35:47 volumio-x86 systemd[1]: Started udev Coldplug all Devices. May 16 23:35:47 volumio-x86 systemd[1]: Starting Helper to synchronize boot up for ifupdown... May 16 23:35:47 volumio-x86 systemd-udevd[498]: Network interface NamePolicy= disabled on kernel command line, ignoring. May 16 23:35:47 volumio-x86 systemd-journald[471]: Runtime journal (/run/log/journal/724bcbf01eebf9d6210f389b672e577a) is 3.7M, max 30.0M, 26.2M free. May 16 23:35:47 volumio-x86 systemd[1]: Started udev Kernel Device Manager. May 16 23:35:47 volumio-x86 systemd[1]: Starting Show Plymouth Boot Screen... May 16 23:35:47 volumio-x86 systemd[1]: Started Flush Journal to Persistent Storage. May 16 23:35:47 volumio-x86 systemd[1]: plymouth-start.service: Succeeded. May 16 23:35:47 volumio-x86 systemd[1]: Started Show Plymouth Boot Screen. May 16 23:35:47 volumio-x86 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. May 16 23:35:47 volumio-x86 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. May 16 23:35:47 volumio-x86 systemd[1]: Reached target Local Encrypted Volumes. May 16 23:35:47 volumio-x86 systemd[1]: Reached target Paths. May 16 23:35:47 volumio-x86 kernel: at24 0-0050: supply vcc not found, using dummy regulator May 16 23:35:47 volumio-x86 systemd-udevd[509]: Using default interface naming scheme 'v240'. May 16 23:35:47 volumio-x86 kernel: at24 0-0050: 256 byte spd EEPROM, read-only May 16 23:35:47 volumio-x86 systemd-udevd[509]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 16 23:35:47 volumio-x86 kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0 May 16 23:35:47 volumio-x86 kernel: cryptd: max_cpu_qlen set to 1000 May 16 23:35:47 volumio-x86 systemd-udevd[520]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 16 23:35:47 volumio-x86 kernel: AVX2 version of gcm_enc/dec engaged. May 16 23:35:47 volumio-x86 kernel: AES CTR mode by8 optimization enabled May 16 23:35:47 volumio-x86 kernel: input: wch.cn USB2IIC_CTP_CONTROL as /devices/pci0000:00/0000:00:14.0/usb2/2-4/2-4:1.0/0003:1A86:E5E3.0001/input/input7 May 16 23:35:47 volumio-x86 kernel: hid-multitouch 0003:1A86:E5E3.0001: input,hidraw0: USB HID v1.00 Device [wch.cn USB2IIC_CTP_CONTROL] on usb-0000:00:14.0-4/input0 May 16 23:35:47 volumio-x86 kernel: snd_hda_intel 0000:00:03.0: bound 0000:00:02.0 (ops __SCT__tp_func_intel_frontbuffer_flush [i915]) May 16 23:35:47 volumio-x86 kernel: input: HDA Intel HDMI HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:03.0/sound/card0/input8 May 16 23:35:47 volumio-x86 kernel: input: HDA Intel HDMI HDMI/DP,pcm=7 as /devices/pci0000:00/0000:00:03.0/sound/card0/input9 May 16 23:35:47 volumio-x86 kernel: input: HDA Intel HDMI HDMI/DP,pcm=8 as /devices/pci0000:00/0000:00:03.0/sound/card0/input10 May 16 23:35:47 volumio-x86 kernel: usbcore: registered new interface driver snd-usb-audio May 16 23:35:47 volumio-x86 kernel: snd_hda_codec_realtek hdaudioC1D0: autoconfig for ALC662 rev3: line_outs=2 (0x1b/0x14/0x0/0x0/0x0) type:hp May 16 23:35:47 volumio-x86 kernel: snd_hda_codec_realtek hdaudioC1D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) May 16 23:35:47 volumio-x86 kernel: snd_hda_codec_realtek hdaudioC1D0: hp_outs=0 (0x0/0x0/0x0/0x0/0x0) May 16 23:35:47 volumio-x86 kernel: snd_hda_codec_realtek hdaudioC1D0: mono: mono_out=0x0 May 16 23:35:47 volumio-x86 kernel: snd_hda_codec_realtek hdaudioC1D0: inputs: May 16 23:35:47 volumio-x86 kernel: snd_hda_codec_realtek hdaudioC1D0: Front Mic=0x19 May 16 23:35:47 volumio-x86 kernel: snd_hda_codec_realtek hdaudioC1D0: Rear Mic=0x18 May 16 23:35:47 volumio-x86 kernel: input: HDA Intel PCH Front Mic as /devices/pci0000:00/0000:00:1b.0/sound/card1/input11 May 16 23:35:47 volumio-x86 kernel: input: HDA Intel PCH Front Headphone Front as /devices/pci0000:00/0000:00:1b.0/sound/card1/input12 May 16 23:35:47 volumio-x86 systemd-udevd[534]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 16 23:35:47 volumio-x86 systemd-udevd[547]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 16 23:35:47 volumio-x86 systemd-udevd[514]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 16 23:35:47 volumio-x86 systemd-udevd[513]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 16 23:35:47 volumio-x86 systemd-udevd[518]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 16 23:35:47 volumio-x86 systemd-udevd[527]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 16 23:35:47 volumio-x86 systemd-udevd[542]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 16 23:35:47 volumio-x86 systemd-udevd[522]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 16 23:35:47 volumio-x86 kernel: alsactl[631]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set May 16 23:35:47 volumio-x86 systemd-udevd[533]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 1' failed with exit code 99. May 16 23:35:47 volumio-x86 systemd-udevd[534]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 16 23:35:47 volumio-x86 systemd-udevd[535]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. May 16 23:35:47 volumio-x86 systemd[1]: Found device SAMSWEET_SSD_SM851_128GB boot. May 16 23:35:47 volumio-x86 systemd[1]: Condition check resulted in RTL8111/8168/8411 PCI Express Gigabit Ethernet Controller being skipped. May 16 23:35:47 volumio-x86 systemd[1]: Starting Show Plymouth Boot Screen... May 16 23:35:47 volumio-x86 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. May 16 23:35:47 volumio-x86 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. May 16 23:35:47 volumio-x86 systemd[1]: Starting File System Check on /dev/disk/by-uuid/79FB-2BA3... May 16 23:35:47 volumio-x86 systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. May 16 23:35:47 volumio-x86 systemd[1]: Started File System Check Daemon to report status. May 16 23:35:47 volumio-x86 systemd[1]: plymouth-start.service: Succeeded. May 16 23:35:47 volumio-x86 systemd[1]: Started Show Plymouth Boot Screen. May 16 23:35:47 volumio-x86 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. May 16 23:35:47 volumio-x86 systemd-fsck[652]: fsck.fat 4.1 (2017-01-24) May 16 23:35:47 volumio-x86 systemd-fsck[652]: 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. May 16 23:35:47 volumio-x86 systemd-fsck[652]: Automatically removing dirty bit. May 16 23:35:47 volumio-x86 systemd-fsck[652]: There are differences between boot sector and its backup. May 16 23:35:47 volumio-x86 systemd-fsck[652]: This is mostly harmless. Differences: (offset:original/backup) May 16 23:35:47 volumio-x86 systemd-fsck[652]: 3:53/6d, 4:59/6b, 5:53/66, 6:4c/73, 7:49/2e, 8:4e/66, 9:55/61, 10:58/74 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 90:fa/0e, 91:fc/1f, 92:31/be, 93:c9/77, 94:8e/7c, 95:d1/ac, 96:bc/22 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 97:76/c0, 98:7b/74, 99:52/0b, 100:06/56, 101:57/b4, 102:1e/0e, 103:56/bb May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 104:8e/07, 105:c1/00, 106:b1/cd, 107:26/10, 108:bf/5e, 109:78/eb May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 110:7b/f0, 111:f3/32, 112:a5/e4, 113:8e/cd, 114:d9/16, 115:bb/cd May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 116:78/19, 117:00/eb, 118:0f/fe, 119:b4/54, 120:37/68, 121:0f/69 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 122:a0/73, 123:56/20, 124:20/69, 125:d2/73, 126:78/20, 127:1b/6e May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 128:31/6f, 129:c0/74, 130:b1/20, 131:06/61, 132:89/20, 133:3f/62 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 134:89/6f, 135:47/6f, 136:02/74, 137:f3/61, 138:64/62, 139:a5/6c May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 140:8a/65, 141:0e/20, 142:18/64, 143:7c/69, 144:88/73, 145:4d/6b May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 146:f8/2e, 147:50/20, 148:50/20, 150:50/6c, 151:cd/65, 152:13/61 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 153:eb/73, 154:62/65, 155:8b/20, 156:55/69, 157:aa/6e, 158:8b/73 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 159:75/65, 160:a8/72, 161:c1/74, 162:ee/20, 163:04/61, 164:01/20 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 165:f2/62, 166:83/6f, 167:fa/6f, 168:4f/74, 169:76/61, 170:31/62 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 171:81/6c, 172:fa/65, 173:b2/20, 174:07/66, 175:73/6c, 176:2b/6f May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 177:f6/70, 178:45/70, 179:b4/79, 180:7f/20, 181:75/61, 182:25/6e May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 183:38/64, 184:4d/0d, 185:b8/0a, 186:74/70, 187:20/72, 188:66/65 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 189:3d/73, 190:21/73, 191:47/20, 192:50/61, 193:54/6e, 194:75/79 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 195:10/20, 196:80/6b, 197:7d/65, 198:b8/79, 199:ed/20, 200:75/74 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 201:0a/6f, 202:66/20, 203:ff/74, 204:75/72, 205:ec/79, 206:66/20 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 207:ff/61, 208:75/67, 209:e8/61, 210:eb/69, 211:0f/6e, 212:51/20 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 213:51/2e, 214:66/2e, 215:ff/2e, 216:75/20, 217:bc/0d, 218:eb/0a May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 219:07/00, 220:51/00, 221:51/00, 222:66/00, 223:ff/00, 224:36/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 225:1c/00, 226:7c/00, 227:b4/00, 228:08/00, 229:e8/00, 230:e9/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 232:72/00, 233:13/00, 234:20/00, 235:e4/00, 236:75/00, 237:0f/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 238:c1/00, 239:ea/00, 240:08/00, 241:42/00, 242:89/00, 243:16/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 244:1a/00, 245:7c/00, 246:83/00, 247:e1/00, 248:3f/00, 249:89/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 250:0e/00, 251:18/00, 252:7c/00, 253:fb/00, 254:bb/00, 255:aa/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 256:55/00, 257:b4/00, 258:41/00, 259:e8/00, 260:cb/00, 262:72/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 263:10/00, 264:81/00, 265:fb/00, 266:55/00, 267:aa/00, 268:75/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 269:0a/00, 270:f6/00, 271:c1/00, 272:01/00, 273:74/00, 274:05/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 275:c6/00, 276:06/00, 277:46/00, 278:7d/00, 280:66/00, 281:b8/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 282:c6/00, 283:9b/00, 286:66/00, 287:ba/00, 292:bb/00, 294:80/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 295:e8/00, 296:0e/00, 298:66/00, 299:81/00, 300:3e/00, 301:1c/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 302:80/00, 303:d5/00, 304:cf/00, 305:c7/00, 306:62/00, 307:75/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 308:74/00, 309:e9/00, 310:f8/00, 311:02/00, 312:66/00, 313:03/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 314:06/00, 315:60/00, 316:7b/00, 317:66/00, 318:13/00, 319:16/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 320:64/00, 321:7b/00, 322:b9/00, 323:10/00, 325:eb/00, 326:2b/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 327:66/00, 328:52/00, 329:66/00, 330:50/00, 331:06/00, 332:53/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 333:6a/00, 334:01/00, 335:6a/00, 336:10/00, 337:89/00, 338:e6/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 339:66/00, 340:60/00, 341:b4/00, 342:42/00, 343:e8/00, 344:77/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 346:66/00, 347:61/00, 348:8d/00, 349:64/00, 350:10/00, 351:72/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 352:01/00, 353:c3/00, 354:66/00, 355:60/00, 356:31/00, 357:c0/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 358:e8/00, 359:68/00, 361:66/00, 362:61/00, 363:e2/00, 364:da/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 365:c6/00, 366:06/00, 367:46/00, 368:7d/00, 369:2b/00, 370:66/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 371:60/00, 372:66/00, 373:0f/00, 374:b7/00, 375:36/00, 376:18/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 377:7c/00, 378:66/00, 379:0f/00, 380:b7/00, 381:3e/00, 382:1a/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 383:7c/00, 384:66/00, 385:f7/00, 386:f6/00, 387:31/00, 388:c9/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 389:87/00, 390:ca/00, 391:66/00, 392:f7/00, 393:f7/00, 394:66/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 395:3d/00, 396:ff/00, 397:03/00, 400:77/00, 401:17/00, 402:c0/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 403:e4/00, 404:06/00, 405:41/00, 406:08/00, 407:e1/00, 408:88/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 409:c5/00, 410:88/00, 411:d6/00, 412:b8/00, 413:01/00, 414:02/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 415:e8/00, 416:2f/00, 418:66/00, 419:61/00, 420:72/00, 421:01/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 422:c3/00, 423:e2/00, 424:c9/00, 425:31/00, 426:f6/00, 427:8e/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 428:d6/00, 429:bc/00, 430:68/00, 431:7b/00, 432:8e/00, 433:de/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 434:66/00, 435:8f/00, 436:06/00, 437:78/00, 439:be/00, 440:da/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 441:7d/00, 442:ac/00, 443:20/00, 444:c0/00, 445:74/00, 446:09/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 447:b4/00, 448:0e/00, 449:bb/00, 450:07/00, 452:cd/00, 453:10/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 454:eb/00, 455:f2/00, 456:31/00, 457:c0/00, 458:cd/00, 459:16/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 460:cd/00, 461:19/00, 462:f4/00, 463:eb/00, 464:fd/00, 465:8a/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 466:16/00, 467:74/00, 468:7b/00, 469:06/00, 470:cd/00, 471:13/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 472:07/00, 473:c3/00, 474:42/00, 475:6f/00, 476:6f/00, 477:74/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 478:20/00, 479:65/00, 480:72/00, 481:72/00, 482:6f/00, 483:72/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 484:0d/00, 485:0a/00, 504:fe/00, 505:02/00, 506:b2/00, 507:3e/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: , 508:18/00, 509:37/00 May 16 23:35:47 volumio-x86 systemd-fsck[652]: Not automatically fixing this. May 16 23:35:47 volumio-x86 systemd-fsck[652]: Performing changes. May 16 23:35:47 volumio-x86 systemd-fsck[652]: /dev/sda1: 18 files, 159614/344788 clusters May 16 23:35:47 volumio-x86 systemd[1]: Started File System Check on /dev/disk/by-uuid/79FB-2BA3. May 16 23:35:47 volumio-x86 systemd[1]: Mounting /boot... May 16 23:35:47 volumio-x86 kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! May 16 23:35:47 volumio-x86 systemd[1]: Mounted /boot. May 16 23:35:47 volumio-x86 systemd[1]: Reached target Local File Systems. May 16 23:35:47 volumio-x86 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... May 16 23:35:47 volumio-x86 systemd[1]: Starting Create Volatile Files and Directories... May 16 23:35:47 volumio-x86 systemd[1]: Starting Preprocess NFS configuration... May 16 23:35:47 volumio-x86 systemd[1]: Started ifup for eth0. May 16 23:35:47 volumio-x86 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. May 16 23:35:47 volumio-x86 systemd[1]: nfs-config.service: Succeeded. May 16 23:35:47 volumio-x86 systemd[1]: Started Preprocess NFS configuration. May 16 23:35:47 volumio-x86 systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. May 16 23:35:47 volumio-x86 systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. May 16 23:35:47 volumio-x86 systemd[1]: Reached target NFS client services. May 16 23:35:47 volumio-x86 systemd[1]: Received SIGRTMIN+20 from PID 363 (plymouthd). May 16 23:35:47 volumio-x86 systemd[1]: plymouth-read-write.service: Succeeded. May 16 23:35:47 volumio-x86 systemd[1]: Started Tell Plymouth To Write Out Runtime Data. May 16 23:35:47 volumio-x86 systemd[1]: Started Create Volatile Files and Directories. May 16 23:35:47 volumio-x86 systemd[1]: Starting RPC bind portmap service... May 16 23:35:47 volumio-x86 systemd[1]: Starting Update UTMP about System Boot/Shutdown... May 16 23:35:47 volumio-x86 systemd[1]: Condition check resulted in Network Time Synchronization being skipped. May 16 23:35:47 volumio-x86 systemd[1]: Started Entropy daemon using the HAVEGE algorithm. May 16 23:35:47 volumio-x86 systemd[1]: Started RPC bind portmap service. May 16 23:35:47 volumio-x86 systemd[1]: Reached target RPC Port Mapper. May 16 23:35:47 volumio-x86 systemd[1]: Reached target Remote File Systems (Pre). May 16 23:35:47 volumio-x86 systemd[1]: Reached target Remote File Systems. May 16 23:35:47 volumio-x86 systemd[1]: Started Update UTMP about System Boot/Shutdown. May 16 23:35:47 volumio-x86 systemd[1]: Reached target System Initialization. May 16 23:35:47 volumio-x86 systemd[1]: Listening on triggerhappy.socket. May 16 23:35:47 volumio-x86 systemd[1]: Started Daily apt download activities. May 16 23:35:47 volumio-x86 systemd[1]: man-db.timer: Not using persistent file timestamp Sat 2025-05-17 00:16:31 CST as it is in the future. May 16 23:35:47 volumio-x86 systemd[1]: Started Daily man-db regeneration. May 16 23:35:47 volumio-x86 systemd[1]: Started Daily Cleanup of Temporary Directories. May 16 23:35:47 volumio-x86 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. May 16 23:35:47 volumio-x86 systemd[1]: Started Daily apt upgrade and clean activities. May 16 23:35:47 volumio-x86 systemd[1]: Reached target Timers. May 16 23:35:47 volumio-x86 systemd[1]: Listening on D-Bus System Message Bus Socket. May 16 23:35:47 volumio-x86 systemd[1]: Reached target Sockets. May 16 23:35:47 volumio-x86 systemd[1]: Reached target Basic System. May 16 23:35:47 volumio-x86 systemd[1]: Started UPnP Renderer front-end to MPD. May 16 23:35:47 volumio-x86 systemd[1]: Starting Login Service... May 16 23:35:47 volumio-x86 systemd[1]: Started My Shell Script. May 16 23:35:47 volumio-x86 systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. May 16 23:35:47 volumio-x86 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... May 16 23:35:47 volumio-x86 systemd[1]: Started Manage Sound Card State (restore and store). May 16 23:35:47 volumio-x86 systemd[1]: Started MPD Monitor Service. May 16 23:35:47 volumio-x86 systemd[1]: Started D-Bus System Message Bus. May 16 23:35:47 volumio-x86 alsactl[681]: alsactl 1.1.8 daemon started May 16 23:35:47 volumio-x86 systemd[1]: Starting triggerhappy global hotkey daemon... May 16 23:35:47 volumio-x86 systemd[1]: Starting WPA supplicant... May 16 23:35:47 volumio-x86 systemd[1]: Started Volumio Log Rotation Service. May 16 23:35:47 volumio-x86 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... May 16 23:35:47 volumio-x86 systemd[1]: Starting Volumio Time Update Utility... May 16 23:35:47 volumio-x86 systemd[1]: Starting Save/Restore Sound Card State... May 16 23:35:47 volumio-x86 systemd[1]: Started Volumio Iptables Module. May 16 23:35:47 volumio-x86 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. May 16 23:35:47 volumio-x86 systemd[1]: Started volumio-remote-updater.service. May 16 23:35:47 volumio-x86 systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. May 16 23:35:47 volumio-x86 systemd[1]: Starting DHCP Client Daemon... May 16 23:35:47 volumio-x86 systemd-udevd[541]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 16 23:35:47 volumio-x86 systemd[1]: Starting Wireless Services... May 16 23:35:47 volumio-x86 alsactl[681]: Found hardware: "HDA-Intel" "Realtek ALC662 rev3" "HDA:10ec0662,10ec0662,00100300" "0x8086" "0x8c20" May 16 23:35:47 volumio-x86 alsactl[681]: Hardware is initialized using a generic method May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #1 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #2 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #3 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #4 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #6 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #7 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #8 (No such file or directory) May 16 23:35:47 volumio-x86 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #9 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #10 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #13 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #14 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #17 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #18 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #19 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #20 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #23 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #24 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #25 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #26 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #27 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #28 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #29 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #30 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #31 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #32 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #33 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #34 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #35 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #36 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #37 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #38 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #39 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #40 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #41 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #42 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[681]: /usr/sbin/alsactl: set_control:1325failed to obtain info for control #43 (No such file or directory) May 16 23:35:47 volumio-x86 volumio-time-update[690]: volumio-time-update-util: Fetching time from Volumio... May 16 23:35:47 volumio-x86 alsactl[691]: Found hardware: "HDA-Intel" "Realtek ALC662 rev3" "HDA:10ec0662,10ec0662,00100300" "0x8086" "0x8c20" May 16 23:35:47 volumio-x86 alsactl[691]: Hardware is initialized using a generic method May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #1 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #2 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #3 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #4 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #6 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #7 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #8 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #9 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #10 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #13 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #14 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #17 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #18 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #19 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #20 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #23 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #24 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #25 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #26 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #27 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #28 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #29 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #30 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #31 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #32 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #33 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #34 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #35 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #36 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #37 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #38 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #39 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #40 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #41 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #42 (No such file or directory) May 16 23:35:47 volumio-x86 alsactl[691]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #43 (No such file or directory) May 16 23:35:47 volumio-x86 thd[684]: Unable to parse trigger line: May 16 23:35:47 volumio-x86 thd[684]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle May 16 23:35:47 volumio-x86 thd[684]: Unable to parse trigger line: May 16 23:35:47 volumio-x86 thd[684]: Found socket passed from systemd May 16 23:35:47 volumio-x86 kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' May 16 23:35:47 volumio-x86 kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' May 16 23:35:47 volumio-x86 systemd-logind[678]: New seat seat0. May 16 23:35:47 volumio-x86 systemd-logind[678]: Watching system buttons on /dev/input/event1 (Power Button) May 16 23:35:47 volumio-x86 sh[663]: command failed: No such device (-19) May 16 23:35:47 volumio-x86 systemd-logind[678]: Watching system buttons on /dev/input/event0 (Power Button) May 16 23:35:47 volumio-x86 mpd_monitor.sh[682]: MPD error: Connection refused May 16 23:35:47 volumio-x86 mpd_monitor.sh[682]: mpd: no process found May 16 23:35:47 volumio-x86 kernel: 8021q: 802.1Q VLAN Support v1.8 May 16 23:35:47 volumio-x86 avahi-daemon[689]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). May 16 23:35:47 volumio-x86 avahi-daemon[689]: Successfully dropped root privileges. May 16 23:35:47 volumio-x86 avahi-daemon[689]: avahi-daemon 0.7 starting up. May 16 23:35:47 volumio-x86 volumio-remote-updater[693]: Error: No active session May 16 23:35:47 volumio-x86 volumio-remote-updater[693]: [2025-05-16 23:35:47] [info] asio async_connect error: system:111 (Connection refused) May 16 23:35:47 volumio-x86 volumio-remote-updater[693]: [2025-05-16 23:35:47] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 16 23:35:47 volumio-x86 volumio-remote-updater[693]: [2025-05-16 23:35:47] [error] handle_connect error: Underlying Transport Error May 16 23:35:47 volumio-x86 kernel: RTL8211E Gigabit Ethernet r8169-0-200:00: attached PHY driver (mii_bus:phy_addr=r8169-0-200:00, irq=MAC) May 16 23:35:47 volumio-x86 volumio-time-update[690]: volumio-time-update-util: Date not found in response May 16 23:35:47 volumio-x86 volumio-time-update[690]: volumio-time-update-util: Retrying in 5 seconds... May 16 23:35:47 volumio-x86 volumio[677]: Could not open config: /tmp/upmpdcli.conf May 16 23:35:47 volumio-x86 haveged[676]: haveged: ver: 1.9.1; arch: x86; vend: GenuineIntel; build: (gcc 8.3.0 ITV); collect: 128K May 16 23:35:47 volumio-x86 haveged[676]: haveged: cpu: (L4 VC); data: 32K (L4 V); inst: 32K (L4 V); idx: 24/40; sz: 31410/52825 May 16 23:35:47 volumio-x86 haveged[676]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00038 May 16 23:35:47 volumio-x86 haveged[676]: haveged: fills: 0, generated: 0 May 16 23:35:47 volumio-x86 systemd[1]: Started Login Service. May 16 23:35:48 volumio-x86 systemd[1]: Started triggerhappy global hotkey daemon. May 16 23:35:48 volumio-x86 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 16 23:35:48 volumio-x86 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 16 23:35:48 volumio-x86 wpa_supplicant[685]: Successfully initialized wpa_supplicant May 16 23:35:48 volumio-x86 avahi-daemon[689]: Successfully called chroot(). May 16 23:35:48 volumio-x86 avahi-daemon[689]: Successfully dropped remaining capabilities. May 16 23:35:48 volumio-x86 systemd[1]: Started Save/Restore Sound Card State. May 16 23:35:48 volumio-x86 avahi-daemon[689]: Loading service file /services/volumio.service. May 16 23:35:48 volumio-x86 systemd[1]: Started Avahi mDNS/DNS-SD Stack. May 16 23:35:48 volumio-x86 systemd[1]: Started WPA supplicant. May 16 23:35:48 volumio-x86 systemd[1]: Listening on mpd.socket. May 16 23:35:48 volumio-x86 systemd[1]: Reached target Sound Card. May 16 23:35:48 volumio-x86 dhcpcd[694]: no interfaces have a carrier May 16 23:35:48 volumio-x86 dhcpcd[694]: forked to background, child pid 754 May 16 23:35:48 volumio-x86 dhcpcd[694]: no interfaces have a carrier May 16 23:35:48 volumio-x86 dhcpcd[694]: forked to background, child pid 754 May 16 23:35:48 volumio-x86 systemd[1]: Started DHCP Client Daemon. May 16 23:35:48 volumio-x86 loadcpufreq[680]: Loading cpufreq kernel modules...done (acpi-cpufreq). May 16 23:35:48 volumio-x86 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. May 16 23:35:48 volumio-x86 systemd[1]: Starting LSB: set CPUFreq kernel parameters... May 16 23:35:48 volumio-x86 cpufrequtils[785]: CPUFreq Utilities: Setting ondemand CPUFreq governor...disabled, governor not available...done. May 16 23:35:48 volumio-x86 systemd[1]: Started LSB: set CPUFreq kernel parameters. May 16 23:35:48 volumio-x86 dhcpcd[754]: eth0: waiting for carrier May 16 23:35:48 volumio-x86 avahi-daemon[689]: Network interface enumeration completed. May 16 23:35:48 volumio-x86 dhcpcd[754]: eth0: carrier acquired May 16 23:35:48 volumio-x86 avahi-daemon[689]: Server startup complete. Host name is volumio-x86.local. Local service cookie is 4002922157. May 16 23:35:48 volumio-x86 avahi-daemon[689]: Service "Volumio-x86" (/services/volumio.service) successfully established. May 16 23:35:48 volumio-x86 kernel: r8169 0000:02:00.0 eth0: Link is Down May 16 23:35:48 volumio-x86 dhcpcd[754]: DUID 00:04:03:00:02:00:04:00:05:00:00:06:00:07:00:08:00:09 May 16 23:35:48 volumio-x86 dhcpcd[754]: eth0: IAID 66:fe:d4:54 May 16 23:35:48 volumio-x86 dhcpcd[754]: eth0: adding address fe80::2e0:66ff:fefe:d454 May 16 23:35:48 volumio-x86 dhcpcd[754]: ipv6_addaddr1: Permission denied May 16 23:35:48 volumio-x86 dhcpcd[754]: eth0: probing address 192.168.3.187/24 May 16 23:35:48 volumio-x86 sh[663]: eth0=eth0 May 16 23:35:48 volumio-x86 dhcpcd[754]: eth0: carrier lost May 16 23:35:48 volumio-x86 systemd[1]: iptables.service: Succeeded. May 16 23:35:48 volumio-x86 wireless.js[695]: WIRELESS: No wireless interface, exiting May 16 23:35:48 volumio-x86 iw[842]: command failed: No such device (-19) May 16 23:35:48 volumio-x86 iwconfig[845]: Error for wireless request "Set Power Management" (8B2C) : May 16 23:35:48 volumio-x86 iwconfig[845]: SET failed on device wlan0 ; No such device. May 16 23:35:48 volumio-x86 systemd[1]: wireless.service: Succeeded. May 16 23:35:48 volumio-x86 systemd[1]: Started Wireless Services. May 16 23:35:48 volumio-x86 systemd[1]: Started Volumio Backend Module. May 16 23:35:48 volumio-x86 systemd[1]: Started Intel SST and HDA soundcard init service. May 16 23:35:48 volumio-x86 systemd[1]: Started Volumio Cpu Tweaker. May 16 23:35:48 volumio-x86 systemd[1]: Started Start Volumio Kiosk. May 16 23:35:48 volumio-x86 volumio-cpu-tweak[850]: Setting RT Priority for mpd May 16 23:35:48 volumio-x86 volumio-cpu-tweak[850]: pid 35's current scheduling policy: SCHED_FIFO May 16 23:35:48 volumio-x86 volumio-cpu-tweak[850]: pid 35's current scheduling priority: 99 May 16 23:35:48 volumio-x86 volumio-cpu-tweak[850]: Setting MPD Affinity May 16 23:35:48 volumio-x86 volumio-cpu-tweak[850]: pid 3's current affinity mask: f May 16 23:35:48 volumio-x86 volumio-cpu-tweak[850]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance May 16 23:35:48 volumio-x86 systemd[1]: Started Helper to synchronize boot up for ifupdown. May 16 23:35:48 volumio-x86 systemd[1]: volumio_cpu_tweak.service: Succeeded. May 16 23:35:48 volumio-x86 systemd[1]: Starting Raise network interfaces... May 16 23:35:48 volumio-x86 ifup[889]: command failed: No such device (-19) May 16 23:35:48 volumio-x86 ifup[889]: command failed: No such device (-19) May 16 23:35:48 volumio-x86 startx[852]: X.Org X Server 1.20.4 May 16 23:35:48 volumio-x86 startx[852]: X Protocol Version 11, Revision 0 May 16 23:35:48 volumio-x86 startx[852]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian May 16 23:35:48 volumio-x86 startx[852]: Current Operating System: Linux volumio-x86 6.6.32-volumio #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 x86_64 May 16 23:35:48 volumio-x86 startx[852]: Kernel command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=d35eb915-f24a-437a-a43b-4afe991304f7 bootpart=UUID=79FB-2BA3 datapart=UUID=2fc5abe1-9579-4377-a3f7-b00c1ebaeecd hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no initrd=volumio.initrd May 16 23:35:48 volumio-x86 startx[852]: Build Date: 18 December 2021 09:05:36AM May 16 23:35:48 volumio-x86 startx[852]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) May 16 23:35:48 volumio-x86 startx[852]: Current version of pixman: 0.36.0 May 16 23:35:48 volumio-x86 startx[852]: Before reporting problems, check http://wiki.x.org May 16 23:35:48 volumio-x86 startx[852]: to make sure that you have the latest version. May 16 23:35:48 volumio-x86 startx[852]: Markers: (--) probed, (**) from config file, (==) default setting, May 16 23:35:48 volumio-x86 startx[852]: (++) from command line, (!!) notice, (II) informational, May 16 23:35:48 volumio-x86 startx[852]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. May 16 23:35:48 volumio-x86 startx[852]: (==) Log file: "/var/log/Xorg.0.log", Time: Fri May 16 23:35:48 2025 May 16 23:35:48 volumio-x86 startx[852]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" May 16 23:35:48 volumio-x86 ifup[889]: command failed: No such device (-19) May 16 23:35:48 volumio-x86 systemd[1]: Started Raise network interfaces. May 16 23:35:48 volumio-x86 systemd[1]: Reached target Network. May 16 23:35:48 volumio-x86 systemd[1]: Starting OpenBSD Secure Shell server... May 16 23:35:48 volumio-x86 systemd[1]: Starting Music Player Daemon... May 16 23:35:48 volumio-x86 systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. May 16 23:35:48 volumio-x86 systemd[1]: Starting Permit User Sessions... May 16 23:35:48 volumio-x86 systemd[1]: Starting Network Time Service... May 16 23:35:48 volumio-x86 systemd[1]: Reached target Network is Online. May 16 23:35:48 volumio-x86 systemd[1]: Starting LSB: Brings up/down network automatically... May 16 23:35:48 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 16 23:35:48 volumio-x86 systemd[1]: Starting /etc/rc.local Compatibility... May 16 23:35:48 volumio-x86 systemd[1]: Starting Samba NMB Daemon... May 16 23:35:48 volumio-x86 systemd[1]: Started Permit User Sessions. May 16 23:35:48 volumio-x86 systemd[1]: Started /etc/rc.local Compatibility. May 16 23:35:48 volumio-x86 systemd[1]: Starting Hold until boot process finishes up... May 16 23:35:48 volumio-x86 systemd[1]: Starting Terminate Plymouth Boot Screen... May 16 23:35:48 volumio-x86 systemd[1]: Received SIGRTMIN+21 from PID 363 (plymouthd). May 16 23:35:48 volumio-x86 systemd[1]: Received SIGRTMIN+21 from PID 363 (plymouthd). May 16 23:35:48 volumio-x86 systemd[1]: plymouth-quit-wait.service: Succeeded. May 16 23:35:48 volumio-x86 systemd[1]: Started Hold until boot process finishes up. May 16 23:35:48 volumio-x86 sshd[1082]: Server listening on 0.0.0.0 port 22. May 16 23:35:48 volumio-x86 systemd[1]: Started Getty on tty1. May 16 23:35:48 volumio-x86 sshd[1082]: Server listening on :: port 22. May 16 23:35:48 volumio-x86 systemd[1]: Reached target Login Prompts. May 16 23:35:48 volumio-x86 systemd[1]: Started OpenBSD Secure Shell server. May 16 23:35:48 volumio-x86 systemd[1]: plymouth-quit.service: Succeeded. May 16 23:35:48 volumio-x86 systemd[1]: Started Terminate Plymouth Boot Screen. May 16 23:35:48 volumio-x86 ifplugd(eth0)[1096]: ifplugd 0.28 initializing. May 16 23:35:48 volumio-x86 ifplugd(eth0)[1096]: Using interface eth0/00:E0:66:FE:D4:54 with driver (version: 6.6.32-volumio) May 16 23:35:48 volumio-x86 ifplugd(eth0)[1096]: Using detection mode: SIOCETHTOOL May 16 23:35:48 volumio-x86 ifplugd(eth0)[1096]: Initialization complete, link beat not detected. May 16 23:35:48 volumio-x86 ntpd[1058]: ntpd 4.2.8p12@1.3728-o (1): Starting May 16 23:35:48 volumio-x86 ntpd[1058]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 16 23:35:48 volumio-x86 systemd[1]: Started Network Time Service. May 16 23:35:48 volumio-x86 ntpd[1116]: proto: precision = 0.060 usec (-24) May 16 23:35:48 volumio-x86 ntpd[1116]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 16 23:35:48 volumio-x86 ntpd[1116]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 16 23:35:48 volumio-x86 ntpd[1116]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 871 days ago May 16 23:35:48 volumio-x86 ntpd[1116]: Listen and drop on 0 v6wildcard [::]:123 May 16 23:35:48 volumio-x86 ntpd[1116]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 16 23:35:48 volumio-x86 ntpd[1116]: Listen normally on 2 lo 127.0.0.1:123 May 16 23:35:48 volumio-x86 ntpd[1116]: Listening on routing socket on fd #19 for interface updates May 16 23:35:48 volumio-x86 ntpd[1116]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 16 23:35:48 volumio-x86 ntpd[1116]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 16 23:35:48 volumio-x86 sudo[1056]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 16 23:35:48 volumio-x86 sudo[1056]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:35:48 volumio-x86 systemd[1]: soundcard-init.service: Succeeded. May 16 23:35:48 volumio-x86 sudo[1056]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 16 23:35:48 volumio-x86 sudo[1056]: pam_unix(sudo:session): session closed for user root May 16 23:35:48 volumio-x86 ifplugd[1060]: Network Interface Plugging Daemon...start eth0...done. May 16 23:35:48 volumio-x86 systemd[1]: Started LSB: Brings up/down network automatically. May 16 23:35:48 volumio-x86 kernel: broken atomic modeset userspace detected, disabling atomic May 16 23:35:48 volumio-x86 nmbd[1071]: [2025/05/16 23:35:48.961890, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) May 16 23:35:48 volumio-x86 nmbd[1071]: started asyncdns process 1141 May 16 23:35:48 volumio-x86 nmbd[1071]: [2025/05/16 23:35:48.964622, 0] ../lib/util/become_daemon.c:149(daemon_status) May 16 23:35:48 volumio-x86 nmbd[1071]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... May 16 23:35:48 volumio-x86 nmbd[1071]: [2025/05/16 23:35:48.964666, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) May 16 23:35:48 volumio-x86 nmbd[1071]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). May 16 23:35:49 volumio-x86 startx[852]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. May 16 23:35:49 volumio-x86 volumio[848]: info: ------------------------------------------- May 16 23:35:49 volumio-x86 volumio[848]: info: ----- Volumio3 ---- May 16 23:35:49 volumio-x86 volumio[848]: info: ------------------------------------------- May 16 23:35:49 volumio-x86 volumio[848]: info: ----- System startup ---- May 16 23:35:49 volumio-x86 volumio[848]: info: ------------------------------------------- May 16 23:35:49 volumio-x86 mpd[1140]: May 16 23:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 16 23:35:49 volumio-x86 systemd[1]: Started Music Player Daemon. May 16 23:35:49 volumio-x86 mpd_monitor.sh[682]: MPD restarted due to no mpc output. May 16 23:35:49 volumio-x86 ntpd[1116]: error resolving pool 0.debian.pool.ntp.org: System error (-11) May 16 23:35:49 volumio-x86 volumio[848]: info: MYVOLUMIO Environment detected May 16 23:35:49 volumio-x86 volumio[848]: info: Plugin folders cleanup May 16 23:35:49 volumio-x86 volumio[848]: info: Scanning into folder /volumio/app/plugins/ May 16 23:35:49 volumio-x86 volumio[848]: info: Scanning category audio_interface May 16 23:35:49 volumio-x86 volumio[848]: info: Scanning category miscellanea May 16 23:35:49 volumio-x86 volumio[848]: info: Scanning category music_service May 16 23:35:49 volumio-x86 volumio[848]: info: Scanning category plugins.json May 16 23:35:49 volumio-x86 volumio[848]: info: Scanning category system_controller May 16 23:35:49 volumio-x86 volumio[848]: info: Scanning category user_interface May 16 23:35:49 volumio-x86 volumio[848]: info: Scanning into folder /data/plugins/ May 16 23:35:49 volumio-x86 volumio[848]: info: Scanning category music_service May 16 23:35:49 volumio-x86 volumio[848]: info: Scanning category system_controller May 16 23:35:49 volumio-x86 volumio[848]: info: Scanning category user_interface May 16 23:35:49 volumio-x86 volumio[848]: info: Plugin folders cleanup completed May 16 23:35:49 volumio-x86 volumio[848]: info: ------------------------------------------- May 16 23:35:49 volumio-x86 volumio[848]: info: ----- Core plugins startup ---- May 16 23:35:49 volumio-x86 volumio[848]: info: ------------------------------------------- May 16 23:35:49 volumio-x86 volumio[848]: info: Loading plugins from folder /volumio/app/plugins/ May 16 23:35:49 volumio-x86 volumio[848]: info: Adding plugin upnp to MyMusic Plugins May 16 23:35:49 volumio-x86 volumio[848]: info: Adding plugin airplay_emulation to MyMusic Plugins May 16 23:35:49 volumio-x86 volumio[848]: info: Adding plugin upnp_browser to MyMusic Plugins May 16 23:35:49 volumio-x86 volumio[848]: info: Loading plugins from folder /data/plugins/ May 16 23:35:49 volumio-x86 volumio[848]: info: Loading plugin "system"... May 16 23:35:49 volumio-x86 volumio[848]: info: Loading plugin "appearance"... May 16 23:35:50 volumio-x86 volumio[848]: info: Loading plugin "network"... May 16 23:35:50 volumio-x86 volumio[848]: info: Refreshing Cached IP Addresses May 16 23:35:50 volumio-x86 sudo[1193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 16 23:35:50 volumio-x86 sudo[1193]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:35:50 volumio-x86 sudo[1195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 23:35:50 volumio-x86 sudo[1195]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:35:50 volumio-x86 volumio[848]: info: Loading plugin "services"... May 16 23:35:50 volumio-x86 sudo[1193]: pam_unix(sudo:session): session closed for user root May 16 23:35:50 volumio-x86 volumio[848]: info: Loading plugin "alsa_controller"... May 16 23:35:50 volumio-x86 sudo[1195]: pam_unix(sudo:session): session closed for user root May 16 23:35:50 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 16 23:35:50 volumio-x86 volumio[848]: info: Loading plugin "wizard"... May 16 23:35:50 volumio-x86 volumio[848]: info: Loading plugin "networkfs"... May 16 23:35:50 volumio-x86 sudo[1220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //127.0.0.1/Internal Storage/musics /mnt/NAS/local May 16 23:35:50 volumio-x86 sudo[1220]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:35:50 volumio-x86 volumio[848]: info: Starting Udev Watcher for removable devices May 16 23:35:50 volumio-x86 volumio[848]: info: Ignoring mount for partition: boot May 16 23:35:50 volumio-x86 volumio[848]: info: Ignoring mount for partition: volumio May 16 23:35:50 volumio-x86 volumio[848]: info: Ignoring mount for partition: volumio_data May 16 23:35:50 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 16 23:35:50 volumio-x86 volumio[848]: info: Loading plugin "volumio_command_line_client"... May 16 23:35:50 volumio-x86 sudo[1223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.3.222/Music /mnt/NAS/Music May 16 23:35:50 volumio-x86 sudo[1223]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:35:50 volumio-x86 volumio[848]: info: Loading plugin "upnp"... May 16 23:35:50 volumio-x86 volumio[848]: info: [1747409750400] Starting Upmpd Daemon May 16 23:35:50 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 16 23:35:50 volumio-x86 volumio[848]: info: Loading plugin "my_music"... May 16 23:35:50 volumio-x86 kernel: FS-Cache: Loaded May 16 23:35:50 volumio-x86 volumio[848]: info: Loading plugin "mpd"... May 16 23:35:50 volumio-x86 kernel: Key type dns_resolver registered May 16 23:35:50 volumio-x86 sudo[1220]: pam_unix(sudo:session): session closed for user root May 16 23:35:50 volumio-x86 sudo[1223]: pam_unix(sudo:session): session closed for user root May 16 23:35:50 volumio-x86 kernel: Key type cifs.spnego registered May 16 23:35:50 volumio-x86 kernel: Key type cifs.idmap registered May 16 23:35:50 volumio-x86 kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. May 16 23:35:50 volumio-x86 kernel: CIFS: Attempting to mount //192.168.3.222/Music May 16 23:35:50 volumio-x86 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 16 23:35:50 volumio-x86 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 May 16 23:35:50 volumio-x86 kernel: CIFS: Attempting to mount //127.0.0.1/Internal Storage/musics May 16 23:35:50 volumio-x86 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 16 23:35:50 volumio-x86 kernel: CIFS: VFS: cifs_mount failed w/return code = -111 May 16 23:35:50 volumio-x86 ntpd[1116]: error resolving pool 1.debian.pool.ntp.org: System error (-11) May 16 23:35:50 volumio-x86 volumio[848]: info: Plugin upnp_browser is not enabled May 16 23:35:50 volumio-x86 volumio[848]: info: Loading plugin "alarm-clock"... May 16 23:35:50 volumio-x86 dhcpcd[754]: eth0: carrier acquired May 16 23:35:50 volumio-x86 kernel: r8169 0000:02:00.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx May 16 23:35:50 volumio-x86 ifplugd(eth0)[1096]: Link beat detected. May 16 23:35:50 volumio-x86 dhcpcd[754]: eth0: IAID 66:fe:d4:54 May 16 23:35:50 volumio-x86 dhcpcd[754]: eth0: probing address 192.168.3.187/24 May 16 23:35:50 volumio-x86 ifplugd(eth0)[1096]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. May 16 23:35:50 volumio-x86 dhcpcd[754]: eth0: soliciting an IPv6 router May 16 23:35:50 volumio-x86 volumio[848]: info: Loading plugin "airplay_emulation"... May 16 23:35:50 volumio-x86 ifplugd(eth0)[1096]: client: ifup: interface eth0 already configured May 16 23:35:50 volumio-x86 volumio[848]: info: Starting Shairport Sync May 16 23:35:50 volumio-x86 volumio[848]: info: Loading plugin "last_100"... May 16 23:35:50 volumio-x86 volumio[848]: info: Loading plugin "webradio"... May 16 23:35:50 volumio-x86 ifplugd(eth0)[1096]: Program executed successfully. May 16 23:35:50 volumio-x86 volumio[848]: info: Loading plugin "i2s_dacs"... May 16 23:35:50 volumio-x86 volumio[848]: info: I2S DAC not set, start Auto-detection May 16 23:35:50 volumio-x86 volumio[848]: info: Loading plugin "volumiodiscovery"... May 16 23:35:51 volumio-x86 volumio[848]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 16 23:35:51 volumio-x86 volumio[848]: *** WARNING *** Please fix your application to use the native API of Avahi! May 16 23:35:51 volumio-x86 volumio[848]: *** WARNING *** For more information see May 16 23:35:51 volumio-x86 node[848]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 16 23:35:51 volumio-x86 volumio[848]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 16 23:35:51 volumio-x86 volumio[848]: *** WARNING *** Please fix your application to use the native API of Avahi! May 16 23:35:51 volumio-x86 volumio[848]: *** WARNING *** For more information see May 16 23:35:51 volumio-x86 node[848]: *** WARNING *** Please fix your application to use the native API of Avahi! May 16 23:35:51 volumio-x86 node[848]: *** WARNING *** For more information see May 16 23:35:51 volumio-x86 node[848]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 16 23:35:51 volumio-x86 node[848]: *** WARNING *** Please fix your application to use the native API of Avahi! May 16 23:35:51 volumio-x86 node[848]: *** WARNING *** For more information see May 16 23:35:51 volumio-x86 volumio[848]: info: Applying required configuration parameters for plugin volumiodiscovery May 16 23:35:51 volumio-x86 volumio[848]: info: Discovery: Started advertising with name: Volumio-x86 May 16 23:35:51 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 16 23:35:51 volumio-x86 volumio[848]: info: Loading plugin "jellyfin"... May 16 23:35:51 volumio-x86 volumio[848]: info: Loading plugin "autostart"... May 16 23:35:51 volumio-x86 volumio[848]: info: Applying required configuration parameters for plugin autostart May 16 23:35:51 volumio-x86 volumio[848]: info: AutoStart - onVolumioStart - read config.json May 16 23:35:51 volumio-x86 volumio[848]: info: Loading plugin "peppy_screensaver"... May 16 23:35:51 volumio-x86 volumio[848]: info: Applying required configuration parameters for plugin peppy_screensaver May 16 23:35:51 volumio-x86 volumio[848]: info: Loading plugin "outputs"... May 16 23:35:51 volumio-x86 volumio[848]: info: Loading plugin "albumart"... May 16 23:35:51 volumio-x86 volumio[848]: info: Plugin example_plugin is not enabled May 16 23:35:51 volumio-x86 volumio[848]: info: Loading plugin "inputs"... May 16 23:35:51 volumio-x86 volumio[848]: info: Loading plugin "updater_comm"... May 16 23:35:51 volumio-x86 volumio[848]: Forking 3 albumart workers May 16 23:35:51 volumio-x86 ntpd[1116]: error resolving pool 2.debian.pool.ntp.org: System error (-11) May 16 23:35:51 volumio-x86 volumio[848]: info: Plugin mpdemulation is not enabled May 16 23:35:51 volumio-x86 volumio[848]: info: Loading plugin "rest_api"... May 16 23:35:51 volumio-x86 volumio[848]: info: Loading plugin "websocket"... May 16 23:35:51 volumio-x86 volumio[848]: info: Starting Socket.io Server version 2.3.0 May 16 23:35:51 volumio-x86 volumio[848]: info: Loading plugin "RoonBridge"... May 16 23:35:51 volumio-x86 volumio[848]: Starting albumart workers May 16 23:35:51 volumio-x86 volumio[848]: Starting albumart workers May 16 23:35:51 volumio-x86 volumio[848]: info: Applying required configuration parameters for plugin RoonBridge May 16 23:35:51 volumio-x86 volumio[848]: info: Loading plugin "Systeminfo"... May 16 23:35:51 volumio-x86 volumio[848]: Starting albumart workers May 16 23:35:51 volumio-x86 dbus-daemon[683]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.8' (uid=0 pid=1240 comm="/usr/lib/chromium/chromium --show-component-extens") May 16 23:35:51 volumio-x86 systemd[1]: Starting Bluetooth service... May 16 23:35:52 volumio-x86 kernel: Bluetooth: Core ver 2.22 May 16 23:35:52 volumio-x86 kernel: NET: Registered PF_BLUETOOTH protocol family May 16 23:35:52 volumio-x86 kernel: Bluetooth: HCI device and connection manager initialized May 16 23:35:52 volumio-x86 kernel: Bluetooth: HCI socket layer initialized May 16 23:35:52 volumio-x86 kernel: Bluetooth: L2CAP socket layer initialized May 16 23:35:52 volumio-x86 kernel: Bluetooth: SCO socket layer initialized May 16 23:35:52 volumio-x86 bluetoothd[1319]: Bluetooth daemon 5.50 May 16 23:35:52 volumio-x86 bluetoothd[1319]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf May 16 23:35:52 volumio-x86 dbus-daemon[683]: [system] Successfully activated service 'org.bluez' May 16 23:35:52 volumio-x86 systemd[1]: Started Bluetooth service. May 16 23:35:52 volumio-x86 bluetoothd[1319]: Starting SDP server May 16 23:35:52 volumio-x86 bluetoothd[1319]: Excluding (cli) sap May 16 23:35:52 volumio-x86 volumio[848]: info: Loading i18n strings for locale zh May 16 23:35:52 volumio-x86 volumio[848]: Updating browse sources language May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 16 23:35:52 volumio-x86 bluetoothd[1319]: Bluetooth management interface 1.22 initialized May 16 23:35:52 volumio-x86 dbus-daemon[683]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.9' (uid=0 pid=1319 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") May 16 23:35:52 volumio-x86 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 May 16 23:35:52 volumio-x86 kernel: Bluetooth: BNEP filters: protocol multicast May 16 23:35:52 volumio-x86 kernel: Bluetooth: BNEP socket layer initialized May 16 23:35:52 volumio-x86 systemd[1]: Starting Hostname Service... May 16 23:35:52 volumio-x86 dbus-daemon[683]: [system] Successfully activated service 'org.freedesktop.hostname1' May 16 23:35:52 volumio-x86 systemd[1]: Started Hostname Service. May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::initPlayerControls May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: Express server listening on port 3000 May 16 23:35:52 volumio-x86 volumio[848]: [Metrics] WebUI: 3s 132.79ms May 16 23:35:52 volumio-x86 volumio[848]: info: Setting Device type: x86 May 16 23:35:52 volumio-x86 volumio[848]: info: CoreStateMachine::resetVolumioState May 16 23:35:52 volumio-x86 volumio[848]: info: CoreStateMachine::getcurrentVolume May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::volumioRetrievevolume May 16 23:35:52 volumio-x86 volumio[848]: info: CoreStateMachine::pushState May 16 23:35:52 volumio-x86 volumio[848]: info: CorePlayQueue::getTrack 0 May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::volumioPushState May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 16 23:35:52 volumio-x86 volumio[848]: info: Completed loading Core Plugins May 16 23:35:52 volumio-x86 volumio[848]: info: Preparing to generate the ALSA configuration file May 16 23:35:52 volumio-x86 volumio[848]: info: Volumio Network Manager: Network status updated: 0 May 16 23:35:52 volumio-x86 volumio[848]: info: Cannot mount NAS Music at system boot, trial number 1 ,retrying in 5 seconds May 16 23:35:52 volumio-x86 volumio[848]: info: Cannot mount NAS local at system boot, trial number 1 ,retrying in 5 seconds May 16 23:35:52 volumio-x86 volumio[848]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision May 16 23:35:52 volumio-x86 volumio[848]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf May 16 23:35:52 volumio-x86 volumio[848]: info: Reading ALSA contributions from plugins. May 16 23:35:52 volumio-x86 volumio[848]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 16 23:35:52 volumio-x86 volumio[848]: info: Reloading queue from file May 16 23:35:52 volumio-x86 volumio[848]: info: CoreStateMachine::setRepeat null single undefined May 16 23:35:52 volumio-x86 volumio[848]: info: CoreStateMachine::pushState May 16 23:35:52 volumio-x86 volumio[848]: info: CorePlayQueue::getTrack 0 May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::volumioPushState May 16 23:35:52 volumio-x86 volumio[848]: info: CoreStateMachine::setRandom null May 16 23:35:52 volumio-x86 volumio[848]: info: CoreStateMachine::pushState May 16 23:35:52 volumio-x86 volumio[848]: info: CorePlayQueue::getTrack 0 May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::volumioPushState May 16 23:35:52 volumio-x86 volumio[848]: info: Asound.conf file unchanged, so no further update is needed May 16 23:35:52 volumio-x86 volumio[848]: info: Output device has changed, restarting MPD May 16 23:35:52 volumio-x86 sudo[1457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 16 23:35:52 volumio-x86 sudo[1457]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:35:52 volumio-x86 sudo[1457]: pam_unix(sudo:session): session closed for user root May 16 23:35:52 volumio-x86 volumio[848]: info: Output device has changed, restarting Shairport Sync May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 23:35:52 volumio-x86 sudo[1460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 16 23:35:52 volumio-x86 sudo[1460]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:35:52 volumio-x86 systemd[1]: Stopping Music Player Daemon... May 16 23:35:52 volumio-x86 volumio[848]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 16 23:35:52 volumio-x86 volumio[848]: info: ___________ START PLUGINS ___________ May 16 23:35:52 volumio-x86 volumio[848]: info: ControllerMpd::onStart: Initializing MPD May 16 23:35:52 volumio-x86 volumio[848]: info: Creating MPD Configuration file May 16 23:35:52 volumio-x86 sudo[1466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 16 23:35:52 volumio-x86 sudo[1466]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:35:52 volumio-x86 sudo[1466]: pam_unix(sudo:session): session closed for user root May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 23:35:52 volumio-x86 sudo[1469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 16 23:35:52 volumio-x86 sudo[1469]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:35:52 volumio-x86 systemd[1]: mpd.service: Succeeded. May 16 23:35:52 volumio-x86 systemd[1]: Stopped Music Player Daemon. May 16 23:35:52 volumio-x86 volumio[848]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 16 23:35:52 volumio-x86 volumio[848]: info: [1747409752520] CoreMusicLibrary::Adding element Last_100 May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 16 23:35:52 volumio-x86 volumio[848]: info: [1747409752520] CoreMusicLibrary::Adding element Webradio May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 16 23:35:52 volumio-x86 volumio[848]: info: Initializing BBC Radios May 16 23:35:52 volumio-x86 systemd[1]: Starting Music Player Daemon... May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 16 23:35:52 volumio-x86 volumio[848]: info: Discovery: Getting this device information May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::volumioGetState May 16 23:35:52 volumio-x86 volumio[848]: info: CorePlayQueue::getTrack 0 May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 16 23:35:52 volumio-x86 sudo[1474]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 16 23:35:52 volumio-x86 sudo[1474]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 16 23:35:52 volumio-x86 volumio[848]: info: [1747409752546] CoreMusicLibrary::Adding element Jellyfin May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 16 23:35:52 volumio-x86 volumio[848]: Cannot find translation for source Jellyfin May 16 23:35:52 volumio-x86 volumio[848]: info: [jellyfin] Initialized plugin with device info: {"id":"c9162a36-5b6c-45cd-a4c6-ef7b8f43fa3a","host":"http://127.0.0.1","name":"Volumio-x86","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":100,"mute":false,"artist":"","track":"","albumart":"http://127.0.0.1/albumart"}} May 16 23:35:52 volumio-x86 sudo[1474]: pam_unix(sudo:session): session closed for user root May 16 23:35:52 volumio-x86 volumio[848]: info: AutoStart - onStart May 16 23:35:52 volumio-x86 volumio[848]: info: Loading i18n strings for locale zh May 16 23:35:52 volumio-x86 volumio[848]: info: /tmp/myfifo created May 16 23:35:52 volumio-x86 volumio[848]: info: /tmp/myfifosa created May 16 23:35:52 volumio-x86 sudo[1495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 May 16 23:35:52 volumio-x86 sudo[1495]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:35:52 volumio-x86 sudo[1495]: pam_unix(sudo:session): session closed for user root May 16 23:35:52 volumio-x86 volumio[848]: info: snd-dummy loaded May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 16 23:35:52 volumio-x86 volumio[848]: info: Volumio Calling Home May 16 23:35:52 volumio-x86 sudo[1506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service May 16 23:35:52 volumio-x86 sudo[1506]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:35:52 volumio-x86 systemd[1]: Started RoonBridge. May 16 23:35:52 volumio-x86 sudo[1506]: pam_unix(sudo:session): session closed for user root May 16 23:35:52 volumio-x86 volumio[848]: info: MPD Permissions set May 16 23:35:52 volumio-x86 volumio[848]: info: MPD Permissions set May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::volumioGetState May 16 23:35:52 volumio-x86 volumio[848]: info: CorePlayQueue::getTrack 0 May 16 23:35:52 volumio-x86 volumio[848]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 ntpd[1116]: error resolving pool 3.debian.pool.ntp.org: System error (-11) May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: Starting Shairport Sync May 16 23:35:52 volumio-x86 volumio[848]: info: Starting Shairport Sync May 16 23:35:52 volumio-x86 sudo[1535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 16 23:35:52 volumio-x86 sudo[1535]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::volumioGetState May 16 23:35:52 volumio-x86 volumio[848]: info: CorePlayQueue::getTrack 0 May 16 23:35:52 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 16 23:35:52 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. May 16 23:35:52 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 16 23:35:52 volumio-x86 sudo[1538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 16 23:35:52 volumio-x86 sudo[1538]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:35:52 volumio-x86 volumio[848]: info: Starting Shairport Sync May 16 23:35:52 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 16 23:35:52 volumio-x86 sudo[1535]: pam_unix(sudo:session): session closed for user root May 16 23:35:52 volumio-x86 sudo[1547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 16 23:35:52 volumio-x86 sudo[1547]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:35:52 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 16 23:35:52 volumio-x86 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 16 23:35:52 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. May 16 23:35:52 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 16 23:35:52 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 16 23:35:52 volumio-x86 mpd[1489]: May 16 23:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 16 23:35:52 volumio-x86 sudo[1546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl May 16 23:35:52 volumio-x86 sudo[1546]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:35:52 volumio-x86 sudo[1546]: pam_unix(sudo:session): session closed for user root May 16 23:35:52 volumio-x86 systemd[1]: Started Music Player Daemon. May 16 23:35:52 volumio-x86 sudo[1469]: pam_unix(sudo:session): session closed for user root May 16 23:35:52 volumio-x86 sudo[1550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 16 23:35:52 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 16 23:35:52 volumio-x86 sudo[1550]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:35:52 volumio-x86 sudo[1460]: pam_unix(sudo:session): session closed for user root May 16 23:35:52 volumio-x86 sudo[1538]: pam_unix(sudo:session): session closed for user root May 16 23:35:52 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. May 16 23:35:52 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 16 23:35:52 volumio-x86 volumio[848]: info: Completed starting Core Plugins May 16 23:35:52 volumio-x86 volumio[848]: info: ------------------------------------------- May 16 23:35:52 volumio-x86 volumio[848]: info: ----- MyVolumio plugins startup ---- May 16 23:35:52 volumio-x86 volumio[848]: info: ------------------------------------------- May 16 23:35:52 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Fetching plans data.... May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile May 16 23:35:52 volumio-x86 volumio[848]: info: Shairport-Sync Started May 16 23:35:52 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 16 23:35:52 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 16 23:35:52 volumio-x86 sudo[1561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 16 23:35:52 volumio-x86 sudo[1561]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:35:52 volumio-x86 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 16 23:35:52 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. May 16 23:35:52 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 16 23:35:52 volumio-x86 sudo[1561]: pam_unix(sudo:session): session closed for user root May 16 23:35:52 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 16 23:35:52 volumio-x86 sudo[1547]: pam_unix(sudo:session): session closed for user root May 16 23:35:52 volumio-x86 volumio[848]: Error adding Membership: Error: addMembership EINVAL May 16 23:35:52 volumio-x86 volumio[848]: info: MPD Permissions set May 16 23:35:52 volumio-x86 volumio[848]: info: MPD running with PID1489 May 16 23:35:52 volumio-x86 volumio[848]: ,establishing connection May 16 23:35:52 volumio-x86 volumio[848]: info: Shairport-Sync Started May 16 23:35:52 volumio-x86 sudo[1550]: pam_unix(sudo:session): session closed for user root May 16 23:35:52 volumio-x86 volumio[848]: error: MPD error: The expression evaluated to a falsy value: May 16 23:35:52 volumio-x86 volumio[848]: assert.ok(self.idling) May 16 23:35:52 volumio-x86 volumio[848]: error: The expression evaluated to a falsy value: May 16 23:35:52 volumio-x86 volumio[848]: assert.ok(self.idling) May 16 23:35:52 volumio-x86 volumio[848]: error: MPD error: The expression evaluated to a falsy value: May 16 23:35:52 volumio-x86 volumio[848]: assert.ok(self.idling) May 16 23:35:52 volumio-x86 volumio[848]: error: The expression evaluated to a falsy value: May 16 23:35:52 volumio-x86 volumio[848]: assert.ok(self.idling) May 16 23:35:52 volumio-x86 volumio[848]: info: Shairport-Sync Started May 16 23:35:52 volumio-x86 volumio[848]: info: Shairport-Sync Started May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 16 23:35:52 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 23:35:52 volumio-x86 volumio[848]: error: updateQueue error: null May 16 23:35:52 volumio-x86 volumio-remote-updater[693]: [2025-05-16 23:35:52] [connect] Successful connection May 16 23:35:52 volumio-x86 volumio-remote-updater[693]: [2025-05-16 23:35:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747409752 101 May 16 23:35:52 volumio-x86 volumio[848]: 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 May 16 23:35:52 volumio-x86 RoonBridge[1510]: 00:00:00.002 Warn: get lock file path: /tmp/.rnbgem0- May 16 23:35:52 volumio-x86 volumio-time-update[690]: volumio-time-update-util: Fetching time from Volumio... May 16 23:35:52 volumio-x86 volumio-time-update[690]: volumio-time-update-util: Date not found in response May 16 23:35:52 volumio-x86 volumio-time-update[690]: volumio-time-update-util: Retrying in 5 seconds... May 16 23:35:53 volumio-x86 RoonBridge[1510]: 00:00:00.092 Trace: [childprocess] using unix child process May 16 23:35:53 volumio-x86 RoonBridge[1510]: 00:00:00.111 Debug: PathForResource, filename: ../.update May 16 23:35:53 volumio-x86 RoonBridge[1510]: 00:00:00.124 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update May 16 23:35:53 volumio-x86 RoonBridge[1510]: 00:00:00.125 Debug: PathForResource, filename: RAATServer May 16 23:35:53 volumio-x86 RoonBridge[1510]: 00:00:00.125 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer May 16 23:35:53 volumio-x86 RoonBridge[1510]: 00:00:00.125 Debug: PathForResource, filename: RoonBridgeHelper May 16 23:35:53 volumio-x86 RoonBridge[1510]: 00:00:00.125 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper May 16 23:35:53 volumio-x86 RoonBridge[1510]: Initializing May 16 23:35:53 volumio-x86 RoonBridge[1510]: 00:00:00.136 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper May 16 23:35:53 volumio-x86 RoonBridge[1510]: 00:00:00.146 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer May 16 23:35:53 volumio-x86 RoonBridge[1510]: Not Running (.o) May 16 23:35:53 volumio-x86 RoonBridge[1510]: Running May 16 23:35:53 volumio-x86 RoonBridge[1510]: 00:00:00.008 Warn: get lock file path: /tmp/.rnbhgem0- May 16 23:35:53 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 16 23:35:53 volumio-x86 sudo[1602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 16 23:35:53 volumio-x86 sudo[1602]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:35:53 volumio-x86 systemd[1]: Stopping Music Player Daemon... May 16 23:35:53 volumio-x86 systemd[1]: mpd.service: Succeeded. May 16 23:35:53 volumio-x86 systemd[1]: Stopped Music Player Daemon. May 16 23:35:53 volumio-x86 volumio[848]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 16 23:35:53 volumio-x86 systemd[1]: Starting Music Player Daemon... May 16 23:35:53 volumio-x86 sudo[1604]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 16 23:35:53 volumio-x86 sudo[1604]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:35:53 volumio-x86 sudo[1604]: pam_unix(sudo:session): session closed for user root May 16 23:35:53 volumio-x86 mpd[1609]: May 16 23:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 16 23:35:53 volumio-x86 systemd[1]: Started Music Player Daemon. May 16 23:35:53 volumio-x86 sudo[1602]: pam_unix(sudo:session): session closed for user root May 16 23:35:53 volumio-x86 volumio[848]: error: updateQueue error: null May 16 23:35:53 volumio-x86 volumio[848]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 16 23:35:54 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 16 23:35:54 volumio-x86 volumio[848]: info: CoreCommandRouter::volumioGetVisibleSources May 16 23:35:54 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 16 23:35:54 volumio-x86 volumio[848]: info: CoreCommandRouter::volumioGetState May 16 23:35:54 volumio-x86 volumio[848]: info: CorePlayQueue::getTrack 0 May 16 23:35:54 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 16 23:35:54 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 16 23:35:54 volumio-x86 volumio[848]: info: Received Get System Info May 16 23:35:54 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 16 23:35:54 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 16 23:35:54 volumio-x86 volumio[848]: info: Discovery: Getting this device information May 16 23:35:54 volumio-x86 volumio[848]: info: CoreCommandRouter::volumioGetState May 16 23:35:54 volumio-x86 volumio[848]: info: CorePlayQueue::getTrack 0 May 16 23:35:54 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 16 23:35:54 volumio-x86 volumio[848]: info: CoreCommandRouter::volumioGetState May 16 23:35:54 volumio-x86 volumio[848]: info: CorePlayQueue::getTrack 0 May 16 23:35:54 volumio-x86 volumio[848]: info: Listing playlists May 16 23:35:54 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 16 23:35:54 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 16 23:35:55 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 16 23:35:55 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 16 23:35:55 volumio-x86 volumio[848]: info: Received Get System Info May 16 23:35:55 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 16 23:35:55 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 16 23:35:55 volumio-x86 volumio[848]: info: Discovery: Getting this device information May 16 23:35:55 volumio-x86 volumio[848]: info: CoreCommandRouter::volumioGetState May 16 23:35:55 volumio-x86 volumio[848]: info: CorePlayQueue::getTrack 0 May 16 23:35:55 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 16 23:35:56 volumio-x86 dhcpcd[754]: eth0: using static address 192.168.3.187/24 May 16 23:35:56 volumio-x86 dhcpcd[754]: eth0: adding route to 192.168.3.0/24 May 16 23:35:56 volumio-x86 dhcpcd[754]: eth0: adding default route via 192.168.3.1 May 16 23:35:56 volumio-x86 avahi-daemon[689]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.3.187. May 16 23:35:56 volumio-x86 avahi-daemon[689]: New relevant interface eth0.IPv4 for mDNS. May 16 23:35:56 volumio-x86 avahi-daemon[689]: Registering new address record for 192.168.3.187 on eth0.IPv4. May 16 23:35:57 volumio-x86 volumio[848]: info: Discovery: adding c9162a36-5b6c-45cd-a4c6-ef7b8f43fa3a May 16 23:35:57 volumio-x86 volumio[848]: info: Discovery: Found device Volumio-x86 May 16 23:35:57 volumio-x86 volumio[848]: info: CoreCommandRouter::volumioGetState May 16 23:35:57 volumio-x86 volumio[848]: info: CorePlayQueue::getTrack 0 May 16 23:35:57 volumio-x86 sudo[1651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.3.222/Music /mnt/NAS/Music May 16 23:35:57 volumio-x86 sudo[1651]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:35:57 volumio-x86 sudo[1654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //127.0.0.1/Internal Storage/musics /mnt/NAS/local May 16 23:35:57 volumio-x86 sudo[1654]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:35:57 volumio-x86 kernel: CIFS: Attempting to mount //192.168.3.222/Music May 16 23:35:57 volumio-x86 ntpd[1116]: Listen normally on 3 eth0 192.168.3.187:123 May 16 23:35:57 volumio-x86 ntpd[1116]: new interface(s) found: waking up resolver May 16 23:35:57 volumio-x86 volumio[848]: info: CoreCommandRouter::volumioGetState May 16 23:35:57 volumio-x86 volumio[848]: info: CorePlayQueue::getTrack 0 May 16 23:35:57 volumio-x86 volumio[848]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 16 23:35:57 volumio-x86 volumio-time-update[690]: volumio-time-update-util: Fetching time from Volumio... May 16 23:35:58 volumio-x86 nmbd[1071]: [2025/05/16 23:35:58.965740, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 16 23:35:58 volumio-x86 nmbd[1071]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 16 23:35:58 volumio-x86 systemd[1]: Started Samba NMB Daemon. May 16 23:35:59 volumio-x86 systemd[1]: Starting Samba Winbind Daemon... May 16 23:35:59 volumio-x86 winbindd[1672]: [2025/05/16 23:35:59.059454, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 16 23:35:59 volumio-x86 winbindd[1672]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 16 23:35:59 volumio-x86 winbindd[1672]: [2025/05/16 23:35:59.063437, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 16 23:35:59 volumio-x86 winbindd[1672]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 16 23:35:59 volumio-x86 systemd[1]: Started Samba Winbind Daemon. May 16 23:35:59 volumio-x86 systemd[1]: Starting Samba SMB Daemon... May 16 23:35:59 volumio-x86 smbd[1677]: [2025/05/16 23:35:59.203652, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 16 23:35:59 volumio-x86 smbd[1677]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 16 23:35:59 volumio-x86 systemd[1]: Started Samba SMB Daemon. May 16 23:36:00 volumio-x86 volumio[848]: info: Volumio Network Manager: Network status updated: 1 May 16 23:36:00 volumio-x86 sudo[1708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 16 23:36:00 volumio-x86 sudo[1708]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:36:00 volumio-x86 sudo[1708]: pam_unix(sudo:session): session closed for user root May 16 23:36:00 volumio-x86 sudo[1710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 23:36:00 volumio-x86 sudo[1710]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:36:00 volumio-x86 sudo[1710]: pam_unix(sudo:session): session closed for user root May 16 23:36:00 volumio-x86 sudo[1715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 16 23:36:00 volumio-x86 sudo[1715]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:36:00 volumio-x86 systemd[1]: Started UPnP Renderer front-end to MPD. May 16 23:36:00 volumio-x86 sudo[1715]: pam_unix(sudo:session): session closed for user root May 16 23:36:00 volumio-x86 volumio[848]: info: Upmpdcli Daemon Started May 16 23:36:00 volumio-x86 volumio[1718]: Generating RSA private key, 4096 bit long modulus (2 primes) May 16 23:36:01 volumio-x86 volumio[1718]: ...................................................................................................................++++ May 16 23:36:01 volumio-x86 volumio[1718]: ................................................++++ May 16 23:36:01 volumio-x86 volumio[1718]: e is 65537 (0x010001) May 16 23:36:01 volumio-x86 volumio[1718]: writing RSA key May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 16 23:36:01 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 16 23:36:01 volumio-x86 volumio[848]: info: Adding plugin bluetooth to MyMusic Plugins May 16 23:36:01 volumio-x86 volumio[848]: info: Adding plugin multiroom to MyMusic Plugins May 16 23:36:01 volumio-x86 volumio[848]: info: Adding plugin metavolumio to MyMusic Plugins May 16 23:36:01 volumio-x86 volumio[848]: info: Adding plugin cd_controller to MyMusic Plugins May 16 23:36:01 volumio-x86 volumio[848]: info: Adding plugin smart_inputs to MyMusic Plugins May 16 23:36:02 volumio-x86 volumio[848]: info: Adding plugin tidalconnect to MyMusic Plugins May 16 23:36:02 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 16 23:36:02 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 16 23:36:02 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 16 23:36:02 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 23:36:02 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 23:36:02 volumio-x86 volumio[848]: info: Starting MyVolumio Remote Streaming Endpoints May 16 23:36:02 volumio-x86 volumio[848]: info: MyVolumio login type: Token May 16 23:36:02 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 16 23:36:02 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 16 23:36:02 volumio-x86 volumio[848]: info: Starting Streaming Service Transparent Proxy May 16 23:36:02 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 16 23:36:02 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 16 23:36:02 volumio-x86 volumio[848]: info: Streaming services startup May 16 23:36:02 volumio-x86 volumio[848]: info: Starting Streaming Daemon May 16 23:36:02 volumio-x86 sudo[1933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 16 23:36:02 volumio-x86 sudo[1933]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:36:02 volumio-x86 sudo[1933]: pam_unix(sudo:session): session closed for user root May 16 23:36:02 volumio-x86 volumio[848]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 16 23:36:02 volumio-x86 volumio[848]: error: Cannot start Volumio Streaming Daemon May 16 23:36:02 volumio-x86 volumio[848]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 16 23:36:02 volumio-x86 volumio[848]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 16 23:36:02 volumio-x86 volumio[848]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.779&uuid=28b14c4262d90ccb0cb05b73b12fd5bb" http://updates.volumio.org/downloader-v1/track-device May 16 23:36:02 volumio-x86 volumio[848]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 23:36:02 volumio-x86 volumio[848]: Dload Upload Total Spent Left Speed May 16 23:36:02 volumio-x86 volumio[848]: [132B blob data] May 16 23:36:02 volumio-x86 volumio[848]: retrying in 5 seconds, trial 0 May 16 23:36:02 volumio-x86 volumio[848]: info: Volumio Calling Home May 16 23:36:02 volumio-x86 volumio[848]: STREAMING PROXY: Starting server on port 3245 May 16 23:36:02 volumio-x86 volumio[848]: Node JS runtime: 14 May 16 23:36:03 volumio-x86 sudo[1651]: pam_unix(sudo:session): session closed for user root May 16 23:36:03 volumio-x86 volumio[848]: info: Cannot mount NAS Music at system boot, trial number 2 ,retrying in 5 seconds May 16 23:36:03 volumio-x86 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 16 23:36:03 volumio-x86 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 16 23:36:03 volumio-x86 kernel: CIFS: Attempting to mount //127.0.0.1/Internal Storage/musics May 16 23:36:03 volumio-x86 sudo[1654]: pam_unix(sudo:session): session closed for user root May 16 23:36:03 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 16 23:36:08 volumio-x86 volumio[848]: info: Volumio called home May 16 23:36:08 volumio-x86 sudo[1961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.3.222/Music /mnt/NAS/Music May 16 23:36:08 volumio-x86 sudo[1961]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:36:08 volumio-x86 kernel: CIFS: Attempting to mount //192.168.3.222/Music May 16 23:36:12 volumio-x86 volumio[848]: info: AutoStart - Plugin is starting May 16 23:36:12 volumio-x86 volumio[848]: info: CoreCommandRouter::volumioGetQueue May 16 23:36:12 volumio-x86 volumio[848]: info: CoreStateMachine::getQueue May 16 23:36:12 volumio-x86 volumio[848]: info: CorePlayQueue::getQueue May 16 23:36:14 volumio-x86 sudo[1961]: pam_unix(sudo:session): session closed for user root May 16 23:36:14 volumio-x86 volumio[848]: info: Cannot mount NAS Music at system boot, trial number 3 ,retrying in 5 seconds May 16 23:36:14 volumio-x86 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 16 23:36:14 volumio-x86 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 16 23:36:17 volumio-x86 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 16 23:36:17 volumio-x86 volumio[848]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 16 23:36:17 volumio-x86 volumio[848]: info: CoreCommandRouter::volumioGetState May 16 23:36:17 volumio-x86 volumio[848]: info: CorePlayQueue::getTrack 0 May 16 23:36:17 volumio-x86 volumio-time-update[690]: volumio-time-update-util: Setting system time to: 2025-05-16 21:21:11 May 16 23:36:17 volumio-x86 sudo[1983]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-05-16 21:21:11 May 16 23:36:17 volumio-x86 sudo[1983]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 23:36:17 volumio-x86 dbus-daemon[683]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.15' (uid=0 pid=1984 comm="timedatectl set-time 2025-05-16 21:21:11 ") May 16 23:36:17 volumio-x86 systemd[1]: Starting Time & Date Service... May 16 23:36:17 volumio-x86 dbus-daemon[683]: [system] Successfully activated service 'org.freedesktop.timedate1' May 16 23:36:17 volumio-x86 systemd[1]: Started Time & Date Service. May 16 22:09:08 volumio-x86 volumio[848]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 16 22:09:08 volumio-x86 volumio[848]: info: Retrying Login Due to Network Error May 16 22:09:18 volumio-x86 volumio[848]: info: MyVolumio login type: Token May 16 22:09:48 volumio-x86 volumio[848]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 16 22:09:48 volumio-x86 volumio[848]: info: Retrying Login Due to Network Error May 16 22:09:58 volumio-x86 volumio[848]: info: MyVolumio login type: Token May 16 22:10:21 volumio-x86 volumio[848]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 16 22:10:21 volumio-x86 volumio[848]: Error: connect ETIMEDOUT 199.96.58.105:443 May 16 22:10:21 volumio-x86 volumio[848]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 16 22:10:21 volumio-x86 volumio[848]: errno: -110, May 16 22:10:21 volumio-x86 volumio[848]: code: 'ETIMEDOUT', May 16 22:10:21 volumio-x86 volumio[848]: syscall: 'connect', May 16 22:10:21 volumio-x86 volumio[848]: address: '199.96.58.105', May 16 22:10:21 volumio-x86 volumio[848]: port: 443 May 16 22:10:21 volumio-x86 volumio[848]: } May 16 22:10:21 volumio-x86 volumio[848]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 16 22:10:21 volumio-x86 sudo[25210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-16 22:09 May 16 22:10:21 volumio-x86 sudo[25210]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"