-- Logs begin at Thu 2019-02-14 12:11:59 EET, end at Sat 2025-12-20 12:05:18 EET. -- Dec 20 12:04:12 audio---livingroom kernel: SMSC LAN8700 usb-001:003:01: attached PHY driver (mii_bus:phy_addr=usb-001:003:01, irq=199) Dec 20 12:04:12 audio---livingroom kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:59:7c:68 Dec 20 12:04:12 audio---livingroom kernel: usbcore: registered new interface driver uas Dec 20 12:04:12 audio---livingroom kernel: EXT4-fs (mmcblk0p2): recovery complete Dec 20 12:04:12 audio---livingroom kernel: EXT4-fs (mmcblk0p2): mounted filesystem 8543fa12-4125-4451-a534-cec5480e0606 r/w with ordered data mode. Quota mode: none. Dec 20 12:04:12 audio---livingroom kernel: loop0: detected capacity change from 0 to 1005568 Dec 20 12:04:12 audio---livingroom kernel: EXT4-fs (mmcblk0p3): recovery complete Dec 20 12:04:12 audio---livingroom kernel: EXT4-fs (mmcblk0p3): mounted filesystem f7cca1c6-391b-47a8-9c19-3b1948539def r/w with ordered data mode. Quota mode: none. Dec 20 12:04:12 audio---livingroom systemd[1]: System time before build time, advancing clock. Dec 20 12:04:12 audio---livingroom kernel: NET: Registered PF_INET6 protocol family Dec 20 12:04:12 audio---livingroom kernel: Segment Routing with IPv6 Dec 20 12:04:12 audio---livingroom kernel: In-situ OAM (IOAM) with IPv6 Dec 20 12:04:12 audio---livingroom systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) Dec 20 12:04:12 audio---livingroom systemd[1]: Detected architecture arm. Dec 20 12:04:12 audio---livingroom systemd[1]: Set hostname to . Dec 20 12:04:12 audio---livingroom systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 20 12:04:12 audio---livingroom systemd[1]: Started Forward Password Requests to Wall Directory Watch. Dec 20 12:04:12 audio---livingroom systemd[1]: Listening on udev Kernel Socket. Dec 20 12:04:12 audio---livingroom systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Dec 20 12:04:12 audio---livingroom systemd[1]: Reached target System Time Synchronized. Dec 20 12:04:12 audio---livingroom systemd[1]: Created slice system-getty.slice. Dec 20 12:04:12 audio---livingroom systemd[1]: Listening on initctl Compatibility Named Pipe. Dec 20 12:04:12 audio---livingroom kernel: i2c_dev: i2c /dev entries driver Dec 20 12:04:12 audio---livingroom systemd-journald[308]: Journal started Dec 20 12:04:12 audio---livingroom systemd-journald[308]: Runtime journal (/run/log/journal/d9a3e155a633914cdbe1662066e99934) is 7.5M, max 30.0M, 22.5M free. Dec 20 12:04:12 audio---livingroom systemd-modules-load[304]: Inserted module 'i2c_dev' Dec 20 12:04:12 audio---livingroom fake-hwclock[310]: Sat Dec 20 10:04:12 UTC 2025 Dec 20 12:04:12 audio---livingroom systemd[1]: Started Restore / save the current clock. Dec 20 12:04:12 audio---livingroom systemd[1]: Mounted Kernel Configuration File System. Dec 20 12:04:12 audio---livingroom systemd[1]: Mounted FUSE Control File System. Dec 20 12:04:12 audio---livingroom systemd[1]: Started Apply Kernel Variables. Dec 20 12:04:12 audio---livingroom systemd[1]: dynamicswap.service: Succeeded. Dec 20 12:04:12 audio---livingroom systemd[1]: Started Load/Save Random Seed. Dec 20 12:04:12 audio---livingroom systemd[1]: Started Create System Users. Dec 20 12:04:12 audio---livingroom systemd[1]: Starting Create Static Device Nodes in /dev... Dec 20 12:04:12 audio---livingroom systemd[1]: Started udev Coldplug all Devices. Dec 20 12:04:12 audio---livingroom systemd[1]: Starting Helper to synchronize boot up for ifupdown... Dec 20 12:04:12 audio---livingroom systemd[1]: Started Create Static Device Nodes in /dev. Dec 20 12:04:12 audio---livingroom systemd[1]: Starting udev Kernel Device Manager... Dec 20 12:04:12 audio---livingroom systemd[1]: Reached target Local File Systems (Pre). Dec 20 12:04:12 audio---livingroom systemd[1]: Mounting /var/spool/cups... Dec 20 12:04:12 audio---livingroom systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Dec 20 12:04:12 audio---livingroom systemd[1]: Mounting /tmp... Dec 20 12:04:12 audio---livingroom systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Dec 20 12:04:12 audio---livingroom systemd[1]: Mounting /var/log... Dec 20 12:04:12 audio---livingroom systemd[1]: Mounted /var/spool/cups. Dec 20 12:04:12 audio---livingroom systemd[1]: Mounted /tmp. Dec 20 12:04:12 audio---livingroom systemd[1]: Mounted /var/log. Dec 20 12:04:12 audio---livingroom systemd[1]: Starting Flush Journal to Persistent Storage... Dec 20 12:04:12 audio---livingroom systemd[1]: Mounting /var/spool/cups/tmp... Dec 20 12:04:12 audio---livingroom systemd-udevd[338]: Network interface NamePolicy= disabled on kernel command line, ignoring. Dec 20 12:04:12 audio---livingroom systemd[1]: Mounted /var/spool/cups/tmp. Dec 20 12:04:12 audio---livingroom systemd-journald[308]: Runtime journal (/run/log/journal/d9a3e155a633914cdbe1662066e99934) is 7.5M, max 30.0M, 22.5M free. Dec 20 12:04:12 audio---livingroom systemd[1]: Started udev Kernel Device Manager. Dec 20 12:04:12 audio---livingroom systemd[1]: Started Flush Journal to Persistent Storage. Dec 20 12:04:12 audio---livingroom systemd[1]: Starting Show Plymouth Boot Screen... Dec 20 12:04:12 audio---livingroom systemd[1]: plymouth-start.service: Succeeded. Dec 20 12:04:12 audio---livingroom systemd[1]: Started Show Plymouth Boot Screen. Dec 20 12:04:12 audio---livingroom systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Dec 20 12:04:12 audio---livingroom systemd[1]: Reached target Paths. Dec 20 12:04:12 audio---livingroom systemd[1]: Reached target Local Encrypted Volumes. Dec 20 12:04:12 audio---livingroom systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Dec 20 12:04:12 audio---livingroom kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Dec 20 12:04:12 audio---livingroom kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Dec 20 12:04:12 audio---livingroom kernel: [vc_sm_connected_init]: start Dec 20 12:04:12 audio---livingroom kernel: [vc_sm_connected_init]: installed successfully Dec 20 12:04:12 audio---livingroom kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 Dec 20 12:04:12 audio---livingroom kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem Dec 20 12:04:12 audio---livingroom kernel: mc: Linux media interface: v0.10 Dec 20 12:04:12 audio---livingroom kernel: videodev: Linux video capture interface: v2.00 Dec 20 12:04:12 audio---livingroom kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Dec 20 12:04:12 audio---livingroom kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Dec 20 12:04:12 audio---livingroom systemd[1]: Found device /dev/disk/by-uuid/9C48-E5A3. Dec 20 12:04:13 audio---livingroom kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Dec 20 12:04:13 audio---livingroom kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Dec 20 12:04:13 audio---livingroom kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Dec 20 12:04:13 audio---livingroom kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Dec 20 12:04:13 audio---livingroom kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Dec 20 12:04:13 audio---livingroom kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Dec 20 12:04:13 audio---livingroom kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Dec 20 12:04:13 audio---livingroom kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Dec 20 12:04:13 audio---livingroom kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Dec 20 12:04:13 audio---livingroom kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Dec 20 12:04:13 audio---livingroom kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Dec 20 12:04:13 audio---livingroom kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Dec 20 12:04:13 audio---livingroom kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Dec 20 12:04:13 audio---livingroom kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Dec 20 12:04:13 audio---livingroom kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Dec 20 12:04:13 audio---livingroom kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Dec 20 12:04:13 audio---livingroom kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Dec 20 12:04:13 audio---livingroom kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Dec 20 12:04:13 audio---livingroom kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Dec 20 12:04:13 audio---livingroom kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Dec 20 12:04:13 audio---livingroom kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Dec 20 12:04:13 audio---livingroom kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Dec 20 12:04:13 audio---livingroom kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Dec 20 12:04:13 audio---livingroom kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Dec 20 12:04:13 audio---livingroom kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Dec 20 12:04:13 audio---livingroom kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Dec 20 12:04:13 audio---livingroom kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Dec 20 12:04:13 audio---livingroom kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Dec 20 12:04:13 audio---livingroom kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Dec 20 12:04:13 audio---livingroom kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Dec 20 12:04:13 audio---livingroom kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Dec 20 12:04:13 audio---livingroom kernel: Bluetooth: Core ver 2.22 Dec 20 12:04:13 audio---livingroom kernel: NET: Registered PF_BLUETOOTH protocol family Dec 20 12:04:13 audio---livingroom kernel: Bluetooth: HCI device and connection manager initialized Dec 20 12:04:13 audio---livingroom kernel: Bluetooth: HCI socket layer initialized Dec 20 12:04:13 audio---livingroom kernel: Bluetooth: L2CAP socket layer initialized Dec 20 12:04:13 audio---livingroom kernel: Bluetooth: SCO socket layer initialized Dec 20 12:04:13 audio---livingroom kernel: Bluetooth: HCI UART driver ver 2.3 Dec 20 12:04:13 audio---livingroom kernel: Bluetooth: HCI UART protocol H4 registered Dec 20 12:04:13 audio---livingroom kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Dec 20 12:04:13 audio---livingroom kernel: Bluetooth: HCI UART protocol Broadcom registered Dec 20 12:04:13 audio---livingroom kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Dec 20 12:04:13 audio---livingroom kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Dec 20 12:04:13 audio---livingroom kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Dec 20 12:04:13 audio---livingroom kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Dec 20 12:04:13 audio---livingroom kernel: uart-pl011 3f201000.serial: no DMA platform data Dec 20 12:04:13 audio---livingroom kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Dec 20 12:04:13 audio---livingroom kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Dec 20 12:04:13 audio---livingroom kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Dec 20 12:04:13 audio---livingroom kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Dec 20 12:04:13 audio---livingroom kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Dec 20 12:04:13 audio---livingroom systemd[1]: Starting File System Check on /dev/disk/by-uuid/9C48-E5A3... Dec 20 12:04:13 audio---livingroom systemd-udevd[353]: Using default interface naming scheme 'v240'. Dec 20 12:04:13 audio---livingroom systemd-udevd[353]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 20 12:04:13 audio---livingroom systemd[1]: Started File System Check Daemon to report status. Dec 20 12:04:13 audio---livingroom kernel: Bluetooth: hci0: BCM: chip id 94 Dec 20 12:04:13 audio---livingroom kernel: Bluetooth: hci0: BCM: features 0x2e Dec 20 12:04:13 audio---livingroom kernel: Bluetooth: hci0: BCM43430A1 Dec 20 12:04:13 audio---livingroom kernel: Bluetooth: hci0: BCM43430A1 (001.002.009) build 0000 Dec 20 12:04:13 audio---livingroom systemd-udevd[354]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 20 12:04:13 audio---livingroom kernel: brcmfmac: F1 signature read @0x18000000=0x1541a9a6 Dec 20 12:04:13 audio---livingroom kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Dec 20 12:04:13 audio---livingroom kernel: usbcore: registered new interface driver brcmfmac Dec 20 12:04:14 audio---livingroom kernel: snd-rpi-hifiberry-dacplus soc:sound: activating headphone amplifier Dec 20 12:04:14 audio---livingroom kernel: Bluetooth: hci0: BCM43430A1 'brcm/BCM43430A1.hcd' Patch Dec 20 12:04:15 audio---livingroom kernel: Bluetooth: hci0: BCM: features 0x2e Dec 20 12:04:15 audio---livingroom kernel: Bluetooth: hci0: BCM43438A1 37.4MHz Raspberry Pi 3-0141 Dec 20 12:04:15 audio---livingroom kernel: Bluetooth: hci0: BCM43430A1 (001.002.009) build 0508 Dec 20 12:04:15 audio---livingroom kernel: tpa6130a2 1-0060: supply Vdd not found, using dummy regulator Dec 20 12:04:15 audio---livingroom kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Dec 20 12:04:15 audio---livingroom kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Jul 19 2021 03:24:18 version 7.45.98 (TOB) (56df937 CY) FWID 01-8e14b897 Dec 20 12:04:15 audio---livingroom systemd-udevd[350]: Using default interface naming scheme 'v240'. Dec 20 12:04:15 audio---livingroom systemd-udevd[350]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 20 12:04:16 audio---livingroom systemd-fsck[405]: fsck.fat 4.1 (2017-01-24) Dec 20 12:04:16 audio---livingroom systemd-fsck[405]: /dev/mmcblk0p1: 403 files, 166422/184582 clusters Dec 20 12:04:16 audio---livingroom systemd[1]: Started File System Check on /dev/disk/by-uuid/9C48-E5A3. Dec 20 12:04:17 audio---livingroom kernel: alsactl[506]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Dec 20 12:04:17 audio---livingroom kernel: alsactl[507]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Dec 20 12:04:17 audio---livingroom systemd[1]: Started Helper to synchronize boot up for ifupdown. Dec 20 12:04:17 audio---livingroom systemd[1]: Condition check resulted in SMSC9512/9514 Fast Ethernet Adapter being skipped. Dec 20 12:04:17 audio---livingroom systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. Dec 20 12:04:17 audio---livingroom systemd[1]: Created slice system-bthelper.slice. Dec 20 12:04:17 audio---livingroom systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Dec 20 12:04:17 audio---livingroom systemd[1]: Condition check resulted in Huge Pages File System being skipped. Dec 20 12:04:18 audio---livingroom systemd[1]: Starting Show Plymouth Boot Screen... Dec 20 12:04:18 audio---livingroom systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Dec 20 12:04:18 audio---livingroom systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Dec 20 12:04:18 audio---livingroom systemd[1]: Mounting /boot... Dec 20 12:04:18 audio---livingroom systemd[1]: Starting Load/Save RF Kill Switch Status... Dec 20 12:04:18 audio---livingroom systemd[1]: plymouth-start.service: Succeeded. Dec 20 12:04:18 audio---livingroom systemd[1]: Started Show Plymouth Boot Screen. Dec 20 12:04:18 audio---livingroom systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Dec 20 12:04:18 audio---livingroom systemd[1]: Started Load/Save RF Kill Switch Status. Dec 20 12:04:18 audio---livingroom systemd[1]: Mounted /boot. Dec 20 12:04:18 audio---livingroom systemd[1]: Reached target Local File Systems. Dec 20 12:04:18 audio---livingroom systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Dec 20 12:04:18 audio---livingroom systemd[1]: Started ifup for eth0. Dec 20 12:04:18 audio---livingroom systemd[1]: Starting Create Volatile Files and Directories... Dec 20 12:04:18 audio---livingroom systemd[1]: Starting Raise network interfaces... Dec 20 12:04:18 audio---livingroom systemd[1]: Starting Preprocess NFS configuration... Dec 20 12:04:18 audio---livingroom systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Dec 20 12:04:18 audio---livingroom systemd[1]: Started ifup for wlan0. Dec 20 12:04:18 audio---livingroom systemd[1]: plymouth-read-write.service: Succeeded. Dec 20 12:04:18 audio---livingroom systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Dec 20 12:04:18 audio---livingroom systemd[1]: Received SIGRTMIN+20 from PID 200 (plymouthd). Dec 20 12:04:18 audio---livingroom systemd[1]: nfs-config.service: Succeeded. Dec 20 12:04:18 audio---livingroom systemd[1]: Started Preprocess NFS configuration. Dec 20 12:04:18 audio---livingroom systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Dec 20 12:04:18 audio---livingroom systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Dec 20 12:04:18 audio---livingroom systemd[1]: Reached target NFS client services. Dec 20 12:04:18 audio---livingroom systemd[1]: Started Create Volatile Files and Directories. Dec 20 12:04:18 audio---livingroom systemd[1]: Starting Update UTMP about System Boot/Shutdown... Dec 20 12:04:18 audio---livingroom kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Dec 20 12:04:18 audio---livingroom systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Dec 20 12:04:18 audio---livingroom systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Dec 20 12:04:18 audio---livingroom systemd[1]: Starting RPC bind portmap service... Dec 20 12:04:18 audio---livingroom systemd[1]: Started Update UTMP about System Boot/Shutdown. Dec 20 12:04:18 audio---livingroom systemd[1]: Reached target System Initialization. Dec 20 12:04:18 audio---livingroom systemd[1]: Started Daily Cleanup of Temporary Directories. Dec 20 12:04:18 audio---livingroom systemd[1]: Listening on D-Bus System Message Bus Socket. Dec 20 12:04:18 audio---livingroom systemd[1]: Started Daily apt download activities. Dec 20 12:04:18 audio---livingroom systemd[1]: Started Daily apt upgrade and clean activities. Dec 20 12:04:18 audio---livingroom systemd[1]: Reached target Timers. Dec 20 12:04:18 audio---livingroom systemd[1]: Listening on triggerhappy.socket. Dec 20 12:04:18 audio---livingroom systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Dec 20 12:04:18 audio---livingroom systemd[1]: Reached target Sockets. Dec 20 12:04:18 audio---livingroom systemd[1]: Reached target Basic System. Dec 20 12:04:18 audio---livingroom systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Dec 20 12:04:18 audio---livingroom systemd[1]: Starting Volumio Time Update Utility... Dec 20 12:04:18 audio---livingroom systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Dec 20 12:04:18 audio---livingroom systemd[1]: Started Volumio Iptables Module. Dec 20 12:04:18 audio---livingroom systemd[1]: Started volumio-remote-updater.service. Dec 20 12:04:18 audio---livingroom volumio-time-update[606]: volumio-time-update-util: Fetching time from Volumio... Dec 20 12:04:18 audio---livingroom systemd[1]: Starting Configure Bluetooth Modems connected by UART... Dec 20 12:04:18 audio---livingroom systemd[1]: Starting triggerhappy global hotkey daemon... Dec 20 12:04:18 audio---livingroom systemd[1]: Starting Login Service... Dec 20 12:04:18 audio---livingroom systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Dec 20 12:04:18 audio---livingroom systemd[1]: Starting dhcpcd on all interfaces... Dec 20 12:04:18 audio---livingroom systemd[1]: Started D-Bus System Message Bus. Dec 20 12:04:18 audio---livingroom kernel: 8021q: 802.1Q VLAN Support v1.8 Dec 20 12:04:18 audio---livingroom systemd[1]: Starting WPA supplicant... Dec 20 12:04:18 audio---livingroom systemd[1]: Started UPnP Renderer front-end to MPD. Dec 20 12:04:18 audio---livingroom systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Dec 20 12:04:18 audio---livingroom systemd[1]: Started Manage Sound Card State (restore and store). Dec 20 12:04:18 audio---livingroom systemd[1]: Starting Wireless Services... Dec 20 12:04:18 audio---livingroom systemd[1]: Starting Save/Restore Sound Card State... Dec 20 12:04:18 audio---livingroom alsactl[621]: alsactl 1.1.8 daemon started Dec 20 12:04:18 audio---livingroom systemd[1]: Started Volumio Log Rotation Service. Dec 20 12:04:19 audio---livingroom dhcpcd[616]: Not running dhcpcd because /etc/network/interfaces Dec 20 12:04:19 audio---livingroom dhcpcd[616]: defines some interfaces that will use a Dec 20 12:04:19 audio---livingroom dhcpcd[616]: DHCP client or static address Dec 20 12:04:19 audio---livingroom thd[612]: Unable to parse trigger line: Dec 20 12:04:19 audio---livingroom thd[612]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Dec 20 12:04:19 audio---livingroom thd[612]: Unable to parse trigger line: Dec 20 12:04:19 audio---livingroom thd[612]: Found socket passed from systemd Dec 20 12:04:19 audio---livingroom kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 20 12:04:19 audio---livingroom systemd-logind[615]: New seat seat0. Dec 20 12:04:19 audio---livingroom avahi-daemon[605]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Dec 20 12:04:19 audio---livingroom avahi-daemon[605]: Successfully dropped root privileges. Dec 20 12:04:19 audio---livingroom avahi-daemon[605]: avahi-daemon 0.7 starting up. Dec 20 12:04:19 audio---livingroom systemd[1]: Started Login Service. Dec 20 12:04:19 audio---livingroom systemd[1]: Started triggerhappy global hotkey daemon. Dec 20 12:04:19 audio---livingroom systemd[1]: Started RPC bind portmap service. Dec 20 12:04:19 audio---livingroom systemd[1]: hciuart.service: Succeeded. Dec 20 12:04:19 audio---livingroom systemd[1]: Started Configure Bluetooth Modems connected by UART. Dec 20 12:04:19 audio---livingroom systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Dec 20 12:04:19 audio---livingroom systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Dec 20 12:04:19 audio---livingroom systemd[1]: Failed to start dhcpcd on all interfaces. Dec 20 12:04:19 audio---livingroom avahi-daemon[605]: Successfully called chroot(). Dec 20 12:04:19 audio---livingroom avahi-daemon[605]: Successfully dropped remaining capabilities. Dec 20 12:04:19 audio---livingroom systemd[1]: Started Save/Restore Sound Card State. Dec 20 12:04:19 audio---livingroom wpa_supplicant[619]: Successfully initialized wpa_supplicant Dec 20 12:04:19 audio---livingroom avahi-daemon[605]: Loading service file /services/volumio.service. Dec 20 12:04:19 audio---livingroom systemd[1]: Started Avahi mDNS/DNS-SD Stack. Dec 20 12:04:19 audio---livingroom systemd[1]: Started WPA supplicant. Dec 20 12:04:19 audio---livingroom systemd[1]: Reached target Sound Card. Dec 20 12:04:19 audio---livingroom sh[534]: eth0: waiting for carrier Dec 20 12:04:19 audio---livingroom kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Dec 20 12:04:19 audio---livingroom kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Dec 20 12:04:19 audio---livingroom systemd[1]: Starting Raspberry Pi bluetooth helper... Dec 20 12:04:19 audio---livingroom dhcpcd[588]: eth0: waiting for carrier Dec 20 12:04:19 audio---livingroom ifup[537]: ifup: waiting for lock on /run/network/ifstate.wlan0 Dec 20 12:04:19 audio---livingroom sh[534]: eth0: carrier acquired Dec 20 12:04:19 audio---livingroom sh[534]: DUID 00:01:00:01:2e:7c:57:94:b8:27:eb:59:7c:68 Dec 20 12:04:19 audio---livingroom sh[534]: eth0: IAID eb:59:7c:68 Dec 20 12:04:19 audio---livingroom sh[534]: eth0: adding address fe80::ba27:ebff:fe59:7c68 Dec 20 12:04:19 audio---livingroom sh[534]: ipv6_addaddr1: Permission denied Dec 20 12:04:19 audio---livingroom systemd[1]: Reached target Remote File Systems (Pre). Dec 20 12:04:19 audio---livingroom avahi-daemon[605]: Network interface enumeration completed. Dec 20 12:04:19 audio---livingroom systemd[1]: Reached target Remote File Systems. Dec 20 12:04:19 audio---livingroom dhcpcd[588]: eth0: carrier acquired Dec 20 12:04:19 audio---livingroom avahi-daemon[605]: Server startup complete. Host name is audio---livingroom.local. Local service cookie is 3349265984. Dec 20 12:04:19 audio---livingroom avahi-daemon[605]: Service "audio - livingroom" (/services/volumio.service) successfully established. Dec 20 12:04:19 audio---livingroom dhcpcd[588]: DUID 00:01:00:01:2e:7c:57:94:b8:27:eb:59:7c:68 Dec 20 12:04:19 audio---livingroom dhcpcd[588]: eth0: IAID eb:59:7c:68 Dec 20 12:04:19 audio---livingroom dhcpcd[588]: eth0: adding address fe80::ba27:ebff:fe59:7c68 Dec 20 12:04:19 audio---livingroom dhcpcd[588]: ipv6_addaddr1: Permission denied Dec 20 12:04:20 audio---livingroom systemd[1]: Started Regular background program processing daemon. Dec 20 12:04:20 audio---livingroom sh[544]: wlan0=wlan0 Dec 20 12:04:20 audio---livingroom systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Dec 20 12:04:20 audio---livingroom cron[702]: (CRON) INFO (pidfile fd = 3) Dec 20 12:04:20 audio---livingroom systemd[1]: Reached target RPC Port Mapper. Dec 20 12:04:20 audio---livingroom cron[702]: (*system*) WRONG FILE OWNER (/etc/crontab) Dec 20 12:04:20 audio---livingroom cron[702]: (CRON) INFO (Running @reboot jobs) Dec 20 12:04:20 audio---livingroom sh[534]: eth0: soliciting a DHCP lease Dec 20 12:04:20 audio---livingroom dhcpcd[588]: eth0: soliciting a DHCP lease Dec 20 12:04:20 audio---livingroom sh[534]: eth0: offered 192.168.2.67 from 192.168.2.1 Dec 20 12:04:20 audio---livingroom dhcpcd[588]: eth0: offered 192.168.2.67 from 192.168.2.1 Dec 20 12:04:20 audio---livingroom sh[534]: eth0: probing address 192.168.2.67/24 Dec 20 12:04:20 audio---livingroom dhcpcd[588]: eth0: probing address 192.168.2.67/24 Dec 20 12:04:20 audio---livingroom volumio-remote-updater[608]: Error: No active session Dec 20 12:04:20 audio---livingroom volumio-remote-updater[608]: [2025-12-20 12:04:20] [info] asio async_connect error: system:111 (Connection refused) Dec 20 12:04:20 audio---livingroom volumio-remote-updater[608]: [2025-12-20 12:04:20] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 20 12:04:20 audio---livingroom volumio-remote-updater[608]: [2025-12-20 12:04:20] [error] handle_connect error: Underlying Transport Error Dec 20 12:04:20 audio---livingroom bthelper[695]: Raspberry Pi BDADDR already set Dec 20 12:04:20 audio---livingroom haveged[573]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Dec 20 12:04:20 audio---livingroom haveged[573]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Dec 20 12:04:20 audio---livingroom haveged[573]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99951 Dec 20 12:04:20 audio---livingroom haveged[573]: haveged: fills: 0, generated: 0 Dec 20 12:04:20 audio---livingroom volumio[620]: Could not open config: /tmp/upmpdcli.conf Dec 20 12:04:20 audio---livingroom systemd[1]: Started Raise network interfaces. Dec 20 12:04:20 audio---livingroom systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 20 12:04:20 audio---livingroom systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 20 12:04:20 audio---livingroom systemd[1]: Started Raspberry Pi bluetooth helper. Dec 20 12:04:20 audio---livingroom volumio-time-update[606]: volumio-time-update-util: Date not found in response Dec 20 12:04:20 audio---livingroom volumio-time-update[606]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 12:04:20 audio---livingroom systemd[1]: Starting Bluetooth service... Dec 20 12:04:20 audio---livingroom systemd[1]: Reached target Network. Dec 20 12:04:20 audio---livingroom systemd[1]: Starting OpenBSD Secure Shell server... Dec 20 12:04:20 audio---livingroom sh[534]: eth0: soliciting an IPv6 router Dec 20 12:04:20 audio---livingroom dhcpcd[588]: eth0: soliciting an IPv6 router Dec 20 12:04:20 audio---livingroom systemd[1]: Starting Network Time Service... Dec 20 12:04:20 audio---livingroom systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Dec 20 12:04:20 audio---livingroom systemd[1]: Starting Permit User Sessions... Dec 20 12:04:20 audio---livingroom systemd[1]: Reached target Network is Online. Dec 20 12:04:20 audio---livingroom systemd[1]: Starting LSB: exim Mail Transport Agent... Dec 20 12:04:20 audio---livingroom systemd[1]: Starting LSB: Brings up/down network automatically... Dec 20 12:04:20 audio---livingroom systemd[1]: Starting Samba NMB Daemon... Dec 20 12:04:20 audio---livingroom systemd[1]: Starting /etc/rc.local Compatibility... Dec 20 12:04:20 audio---livingroom systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 20 12:04:20 audio---livingroom systemd[1]: iptables.service: Succeeded. Dec 20 12:04:20 audio---livingroom systemd[1]: Started Permit User Sessions. Dec 20 12:04:20 audio---livingroom loadcpufreq[703]: Loading cpufreq kernel modules...done (none). Dec 20 12:04:20 audio---livingroom systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Dec 20 12:04:20 audio---livingroom systemd[1]: Started /etc/rc.local Compatibility. Dec 20 12:04:20 audio---livingroom systemd[1]: Starting LSB: set CPUFreq kernel parameters... Dec 20 12:04:20 audio---livingroom systemd[1]: Starting Terminate Plymouth Boot Screen... Dec 20 12:04:20 audio---livingroom systemd[1]: Starting Hold until boot process finishes up... Dec 20 12:04:21 audio---livingroom systemd[1]: plymouth-quit.service: Succeeded. Dec 20 12:04:21 audio---livingroom systemd[1]: Started Terminate Plymouth Boot Screen. Dec 20 12:04:21 audio---livingroom systemd[1]: plymouth-quit-wait.service: Succeeded. Dec 20 12:04:21 audio---livingroom systemd[1]: Started Hold until boot process finishes up. Dec 20 12:04:21 audio---livingroom systemd[1]: Received SIGRTMIN+21 from PID 200 (n/a). Dec 20 12:04:21 audio---livingroom systemd[1]: Received SIGRTMIN+21 from PID 200 (n/a). Dec 20 12:04:21 audio---livingroom bluetoothd[742]: Bluetooth daemon 5.83 Dec 20 12:04:21 audio---livingroom ifplugd(eth0)[811]: ifplugd 0.28 initializing. Dec 20 12:04:21 audio---livingroom ifplugd(eth0)[811]: Using interface eth0/B8:27:EB:59:7C:68 with driver (version: 6.6.62-v7+) Dec 20 12:04:21 audio---livingroom ifplugd(eth0)[811]: Using detection mode: SIOCETHTOOL Dec 20 12:04:21 audio---livingroom ifplugd(eth0)[811]: Initialization complete, link beat detected. Dec 20 12:04:21 audio---livingroom ntpd[754]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 20 12:04:21 audio---livingroom ntpd[754]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Dec 20 12:04:21 audio---livingroom systemd[1]: Started Getty on tty1. Dec 20 12:04:21 audio---livingroom ntpd[812]: proto: precision = 0.677 usec (-20) Dec 20 12:04:21 audio---livingroom bluetoothd[742]: src/main.c:check_options() Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Dec 20 12:04:21 audio---livingroom systemd[1]: Reached target Login Prompts. Dec 20 12:04:21 audio---livingroom systemd[1]: Started Network Time Service. Dec 20 12:04:21 audio---livingroom ntpd[812]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 20 12:04:21 audio---livingroom ntpd[812]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 20 12:04:21 audio---livingroom ntpd[812]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 358 days ago Dec 20 12:04:21 audio---livingroom ntpd[812]: Listen and drop on 0 v6wildcard [::]:123 Dec 20 12:04:21 audio---livingroom ntpd[812]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 20 12:04:21 audio---livingroom ntpd[812]: Listen normally on 2 lo 127.0.0.1:123 Dec 20 12:04:21 audio---livingroom ntpd[812]: Listening on routing socket on fd #19 for interface updates Dec 20 12:04:21 audio---livingroom ntpd[812]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 20 12:04:21 audio---livingroom ntpd[812]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 20 12:04:21 audio---livingroom systemd[1]: Started Bluetooth service. Dec 20 12:04:21 audio---livingroom bluetoothd[742]: Starting SDP server Dec 20 12:04:21 audio---livingroom bluetoothd[742]: Excluding (cli) hostname Dec 20 12:04:21 audio---livingroom bluetoothd[742]: Excluding (cli) scanparam Dec 20 12:04:21 audio---livingroom bluetoothd[742]: Excluding (cli) battery Dec 20 12:04:21 audio---livingroom ifplugd(eth0)[811]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Dec 20 12:04:21 audio---livingroom systemd[1]: Reached target Bluetooth. Dec 20 12:04:21 audio---livingroom sshd[823]: Server listening on 0.0.0.0 port 22. Dec 20 12:04:21 audio---livingroom sshd[823]: Server listening on :: port 22. Dec 20 12:04:21 audio---livingroom systemd[1]: Started OpenBSD Secure Shell server. Dec 20 12:04:21 audio---livingroom kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Dec 20 12:04:21 audio---livingroom kernel: Bluetooth: BNEP filters: protocol multicast Dec 20 12:04:21 audio---livingroom kernel: Bluetooth: BNEP socket layer initialized Dec 20 12:04:21 audio---livingroom bluetoothd[742]: Bluetooth management interface 1.22 initialized Dec 20 12:04:21 audio---livingroom bluetoothd[742]: Battery Provider Manager created Dec 20 12:04:21 audio---livingroom kernel: Bluetooth: MGMT ver 1.22 Dec 20 12:04:21 audio---livingroom ifplugd(eth0)[811]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Dec 20 12:04:21 audio---livingroom cpufrequtils[792]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Dec 20 12:04:21 audio---livingroom systemd[1]: Started LSB: set CPUFreq kernel parameters. Dec 20 12:04:21 audio---livingroom kernel: NET: Registered PF_ALG protocol family Dec 20 12:04:21 audio---livingroom kernel: cryptd: max_cpu_qlen set to 1000 Dec 20 12:04:21 audio---livingroom bluetoothd[742]: Failed to set privacy: Rejected (0x0b) Dec 20 12:04:22 audio---livingroom ntpd[812]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 20 12:04:22 audio---livingroom exim4[759]: Starting MTA: exim4. Dec 20 12:04:22 audio---livingroom systemd[1]: Started LSB: exim Mail Transport Agent. Dec 20 12:04:22 audio---livingroom exim[1095]: 2025-12-20 12:04:22 socket bind() to port 25 for address ::1 failed: Cannot assign requested address: waiting 30s before trying again (9 more tries) Dec 20 12:04:22 audio---livingroom exim[1095]: 2025-12-20 12:04:22 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=0 egid=124 Dec 20 12:04:22 audio---livingroom exim[1095]: exim: could not open panic log - aborting: see message(s) above Dec 20 12:04:22 audio---livingroom nmbd[781]: [2025/12/20 12:04:22.472341, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Dec 20 12:04:22 audio---livingroom nmbd[781]: started asyncdns process 1098 Dec 20 12:04:22 audio---livingroom nmbd[781]: [2025/12/20 12:04:22.474751, 0] ../lib/util/become_daemon.c:149(daemon_status) Dec 20 12:04:22 audio---livingroom nmbd[781]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Dec 20 12:04:22 audio---livingroom nmbd[781]: [2025/12/20 12:04:22.474947, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Dec 20 12:04:22 audio---livingroom nmbd[781]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Dec 20 12:04:22 audio---livingroom wireless.js[622]: Cleaning previous... Dec 20 12:04:22 audio---livingroom sudo[1106]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 20 12:04:22 audio---livingroom sudo[1106]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 12:04:22 audio---livingroom sudo[1106]: pam_unix(sudo:session): session closed for user root Dec 20 12:04:22 audio---livingroom sudo[1108]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 20 12:04:22 audio---livingroom sudo[1108]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 12:04:23 audio---livingroom systemd[1]: systemd-rfkill.service: Succeeded. Dec 20 12:04:23 audio---livingroom ntpd[812]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 20 12:04:23 audio---livingroom sudo[1108]: pam_unix(sudo:session): session closed for user root Dec 20 12:04:23 audio---livingroom wireless.js[622]: Stopped aP Dec 20 12:04:23 audio---livingroom wireless.js[622]: Wireless Networking DISABLED, not starting wireless flow Dec 20 12:04:23 audio---livingroom systemd[1]: wireless.service: Succeeded. Dec 20 12:04:23 audio---livingroom systemd[1]: Started Wireless Services. Dec 20 12:04:23 audio---livingroom systemd[1]: Started Volumio Backend Module. Dec 20 12:04:23 audio---livingroom systemd[1]: Started Volumio Cpu Tweaker. Dec 20 12:04:23 audio---livingroom volumio-cpu-tweak[1117]: Setting RT Priority for mpd Dec 20 12:04:23 audio---livingroom volumio-cpu-tweak[1117]: pid 35's current scheduling policy: SCHED_OTHER Dec 20 12:04:23 audio---livingroom volumio-cpu-tweak[1117]: pid 35's current scheduling priority: 0 Dec 20 12:04:23 audio---livingroom volumio-cpu-tweak[1117]: Setting MPD Affinity Dec 20 12:04:23 audio---livingroom volumio-cpu-tweak[1117]: pid 3's current affinity mask: f Dec 20 12:04:23 audio---livingroom volumio-cpu-tweak[1117]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Dec 20 12:04:23 audio---livingroom volumio-cpu-tweak[1117]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Dec 20 12:04:23 audio---livingroom systemd[1]: volumio_cpu_tweak.service: Succeeded. Dec 20 12:04:24 audio---livingroom ntpd[812]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 20 12:04:25 audio---livingroom ntpd[812]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 20 12:04:25 audio---livingroom volumio-remote-updater[608]: [2025-12-20 12:04:25] [info] asio async_connect error: system:111 (Connection refused) Dec 20 12:04:25 audio---livingroom volumio-remote-updater[608]: [2025-12-20 12:04:25] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 20 12:04:25 audio---livingroom volumio-remote-updater[608]: [2025-12-20 12:04:25] [error] handle_connect error: Underlying Transport Error Dec 20 12:04:25 audio---livingroom bluetoothd[742]: src/adv_monitor.c:btd_adv_monitor_power_down() Unexpected NULL btd_adv_monitor_manager object upon power down Dec 20 12:04:25 audio---livingroom bthelper[695]: [NEW] Media /org/bluez/hci0 Dec 20 12:04:25 audio---livingroom bthelper[695]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 20 12:04:25 audio---livingroom bthelper[695]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 20 12:04:25 audio---livingroom bthelper[695]: hci0 class of device changed: 0x000000 Dec 20 12:04:25 audio---livingroom bthelper[695]: hci0 new_settings: ssp br/edr le secure-conn Dec 20 12:04:25 audio---livingroom bthelper[695]: [CHG] Controller B8:27:EB:F3:D6:C2 PowerState: on-disabling Dec 20 12:04:25 audio---livingroom bthelper[695]: Changing power off succeeded Dec 20 12:04:25 audio---livingroom bthelper[695]: [NEW] Media /org/bluez/hci0 Dec 20 12:04:25 audio---livingroom bthelper[695]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 20 12:04:25 audio---livingroom bthelper[695]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 20 12:04:25 audio---livingroom bthelper[695]: [CHG] Controller B8:27:EB:F3:D6:C2 PowerState: off-enabling Dec 20 12:04:25 audio---livingroom volumio-time-update[606]: volumio-time-update-util: Fetching time from Volumio... Dec 20 12:04:25 audio---livingroom volumio-time-update[606]: volumio-time-update-util: Date not found in response Dec 20 12:04:25 audio---livingroom volumio-time-update[606]: volumio-time-update-util: Retrying in 5 seconds... Dec 20 12:04:25 audio---livingroom bthelper[695]: hci0 class of device changed: 0x00041c Dec 20 12:04:25 audio---livingroom bthelper[695]: [CHG] Controller B8:27:EB:F3:D6:C2 Class: 0x0000041c (1052) Dec 20 12:04:25 audio---livingroom bthelper[695]: hci0 new_settings: powered ssp br/edr le secure-conn Dec 20 12:04:25 audio---livingroom bthelper[695]: Changing power on succeeded Dec 20 12:04:25 audio---livingroom sh[534]: eth0: leased 192.168.2.67 for 86400 seconds Dec 20 12:04:25 audio---livingroom sh[534]: eth0: adding route to 192.168.2.0/24 Dec 20 12:04:25 audio---livingroom sh[534]: eth0: adding default route via 192.168.2.1 Dec 20 12:04:25 audio---livingroom dhcpcd[588]: eth0: leased 192.168.2.67 for 86400 seconds Dec 20 12:04:25 audio---livingroom dhcpcd[588]: eth0: adding route to 192.168.2.0/24 Dec 20 12:04:25 audio---livingroom dhcpcd[588]: eth0: adding default route via 192.168.2.1 Dec 20 12:04:25 audio---livingroom avahi-daemon[605]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.2.67. Dec 20 12:04:25 audio---livingroom avahi-daemon[605]: New relevant interface eth0.IPv4 for mDNS. Dec 20 12:04:25 audio---livingroom avahi-daemon[605]: Registering new address record for 192.168.2.67 on eth0.IPv4. Dec 20 12:04:25 audio---livingroom sh[534]: forked to background, child pid 1159 Dec 20 12:04:25 audio---livingroom dhcpcd[588]: forked to background, child pid 1159 Dec 20 12:04:25 audio---livingroom ifplugd(eth0)[811]: client: ifup: interface eth0 already configured Dec 20 12:04:25 audio---livingroom sh[534]: eth0=eth0 Dec 20 12:04:26 audio---livingroom ifplugd(eth0)[811]: Program executed successfully. Dec 20 12:04:26 audio---livingroom ifplugd[760]: Network Interface Plugging Daemon...start eth0...done. Dec 20 12:04:26 audio---livingroom systemd[1]: Started LSB: Brings up/down network automatically. Dec 20 12:04:26 audio---livingroom volumio[1116]: info: ------------------------------------------- Dec 20 12:04:26 audio---livingroom volumio[1116]: info: ----- Volumio3 ---- Dec 20 12:04:26 audio---livingroom volumio[1116]: info: ------------------------------------------- Dec 20 12:04:26 audio---livingroom volumio[1116]: info: ----- System startup ---- Dec 20 12:04:26 audio---livingroom volumio[1116]: info: ------------------------------------------- Dec 20 12:04:27 audio---livingroom ntpd[812]: Listen normally on 3 eth0 192.168.2.67:123 Dec 20 12:04:27 audio---livingroom ntpd[812]: new interface(s) found: waking up resolver Dec 20 12:04:27 audio---livingroom nmbd[781]: [2025/12/20 12:04:27.585975, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 20 12:04:27 audio---livingroom nmbd[781]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Dec 20 12:04:27 audio---livingroom systemd[1]: Started Samba NMB Daemon. Dec 20 12:04:27 audio---livingroom nmbd[781]: [2025/12/20 12:04:27.667628, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 20 12:04:27 audio---livingroom nmbd[781]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.67 for name WORKGROUP<1d>. Dec 20 12:04:27 audio---livingroom nmbd[781]: This response was from IP 192.168.2.68, reporting an IP address of 192.168.2.68. Dec 20 12:04:27 audio---livingroom systemd[1]: Starting Samba Winbind Daemon... Dec 20 12:04:28 audio---livingroom winbindd[1193]: [2025/12/20 12:04:28.066110, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Dec 20 12:04:28 audio---livingroom winbindd[1193]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 20 12:04:28 audio---livingroom volumio[1116]: info: MYVOLUMIO Environment detected Dec 20 12:04:28 audio---livingroom winbindd[1193]: [2025/12/20 12:04:28.151943, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 20 12:04:28 audio---livingroom winbindd[1193]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Dec 20 12:04:28 audio---livingroom systemd[1]: Started Samba Winbind Daemon. Dec 20 12:04:28 audio---livingroom systemd[1]: Starting Samba SMB Daemon... Dec 20 12:04:28 audio---livingroom volumio[1116]: info: Plugin folders cleanup Dec 20 12:04:28 audio---livingroom volumio[1116]: info: Scanning into folder /volumio/app/plugins/ Dec 20 12:04:28 audio---livingroom volumio[1116]: info: Scanning category audio_interface Dec 20 12:04:28 audio---livingroom volumio[1116]: info: Scanning category miscellanea Dec 20 12:04:28 audio---livingroom volumio[1116]: info: Scanning category music_service Dec 20 12:04:28 audio---livingroom volumio[1116]: info: Scanning category plugins.json Dec 20 12:04:28 audio---livingroom volumio[1116]: info: Scanning category system_controller Dec 20 12:04:28 audio---livingroom volumio[1116]: info: Scanning category user_interface Dec 20 12:04:28 audio---livingroom volumio[1116]: info: Scanning into folder /data/plugins/ Dec 20 12:04:28 audio---livingroom volumio[1116]: info: Scanning category music_service Dec 20 12:04:28 audio---livingroom volumio[1116]: info: Plugin folders cleanup completed Dec 20 12:04:28 audio---livingroom volumio[1116]: info: ------------------------------------------- Dec 20 12:04:28 audio---livingroom volumio[1116]: info: ----- Core plugins startup ---- Dec 20 12:04:28 audio---livingroom volumio[1116]: info: ------------------------------------------- Dec 20 12:04:28 audio---livingroom volumio[1116]: info: Loading plugins from folder /volumio/app/plugins/ Dec 20 12:04:28 audio---livingroom volumio[1116]: info: Adding plugin upnp to MyMusic Plugins Dec 20 12:04:28 audio---livingroom volumio[1116]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 20 12:04:28 audio---livingroom volumio[1116]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 20 12:04:28 audio---livingroom volumio[1116]: info: Loading plugins from folder /data/plugins/ Dec 20 12:04:28 audio---livingroom volumio[1116]: info: Loading plugin "system"... Dec 20 12:04:28 audio---livingroom volumio[1116]: info: Loading plugin "appearance"... Dec 20 12:04:28 audio---livingroom smbd[1200]: [2025/12/20 12:04:28.815396, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 20 12:04:28 audio---livingroom systemd[1]: Started Samba SMB Daemon. Dec 20 12:04:28 audio---livingroom smbd[1200]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Dec 20 12:04:30 audio---livingroom volumio[1116]: info: Loading plugin "network"... Dec 20 12:04:30 audio---livingroom volumio[1116]: info: Refreshing Cached IP Addresses Dec 20 12:04:30 audio---livingroom sudo[1212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 12:04:30 audio---livingroom sudo[1212]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 12:04:30 audio---livingroom sudo[1212]: pam_unix(sudo:session): session closed for user root Dec 20 12:04:30 audio---livingroom sudo[1214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 12:04:30 audio---livingroom sudo[1214]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 12:04:30 audio---livingroom volumio[1116]: info: Loading plugin "services"... Dec 20 12:04:30 audio---livingroom volumio[1116]: info: Loading plugin "alsa_controller"... Dec 20 12:04:30 audio---livingroom sudo[1214]: pam_unix(sudo:session): session closed for user root Dec 20 12:04:30 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 12:04:30 audio---livingroom volumio[1116]: info: Loading plugin "wizard"... Dec 20 12:04:30 audio---livingroom volumio[1116]: info: Loading plugin "networkfs"... Dec 20 12:04:30 audio---livingroom volumio-time-update[606]: volumio-time-update-util: Fetching time from Volumio... Dec 20 12:04:30 audio---livingroom volumio[1116]: info: Starting Udev Watcher for removable devices Dec 20 12:04:30 audio---livingroom sudo[1241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=tudor,password=k2Hzqu{(WNcwGX+KZPFPIXuV,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,noserverino //thenode.local/Media/Music /mnt/NAS/Media Dec 20 12:04:30 audio---livingroom sudo[1241]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 12:04:30 audio---livingroom volumio[1116]: info: Ignoring mount for partition: boot Dec 20 12:04:30 audio---livingroom volumio[1116]: info: Ignoring mount for partition: volumio Dec 20 12:04:30 audio---livingroom volumio[1116]: info: Ignoring mount for partition: volumio_data Dec 20 12:04:30 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 12:04:30 audio---livingroom volumio[1116]: info: Loading plugin "volumio_command_line_client"... Dec 20 12:04:30 audio---livingroom volumio[1116]: info: Loading plugin "upnp"... Dec 20 12:04:30 audio---livingroom volumio[1116]: info: [1766225070555] Starting Upmpd Daemon Dec 20 12:04:30 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 12:04:30 audio---livingroom volumio[1116]: info: Loading plugin "my_music"... Dec 20 12:04:30 audio---livingroom volumio[1116]: info: Loading plugin "mpd"... Dec 20 12:04:30 audio---livingroom volumio-time-update[606]: volumio-time-update-util: Setting system time to: 2025-12-20 12:04:52 Dec 20 12:04:30 audio---livingroom sudo[1246]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-12-20 12:04:52 Dec 20 12:04:30 audio---livingroom sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 12:04:30 audio---livingroom dbus-daemon[617]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.9' (uid=0 pid=1247 comm="timedatectl set-time 2025-12-20 12:04:52 ") Dec 20 12:04:30 audio---livingroom systemd[1]: Starting Time & Date Service... Dec 20 12:04:30 audio---livingroom kernel: Key type cifs.spnego registered Dec 20 12:04:30 audio---livingroom kernel: Key type cifs.idmap registered Dec 20 12:04:30 audio---livingroom kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Dec 20 12:04:30 audio---livingroom kernel: CIFS: Attempting to mount //thenode.local/Media/Music Dec 20 12:04:30 audio---livingroom dbus-daemon[617]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 20 12:04:30 audio---livingroom systemd[1]: Started Time & Date Service. Dec 20 12:04:52 audio---livingroom systemd-timedated[1251]: Changed local time to Sat Dec 20 12:04:52 2025 Dec 20 12:04:52 audio---livingroom sudo[1246]: pam_unix(sudo:session): session closed for user root Dec 20 12:04:52 audio---livingroom volumio-time-update[606]: volumio-time-update-util: System time updated successfully. Dec 20 12:04:52 audio---livingroom systemd[1]: Started Volumio Time Update Utility. Dec 20 12:04:52 audio---livingroom systemd[1]: Reached target Multi-User System. Dec 20 12:04:52 audio---livingroom systemd[1]: Reached target Graphical Interface. Dec 20 12:04:52 audio---livingroom systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 20 12:04:52 audio---livingroom systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 20 12:04:52 audio---livingroom systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 20 12:04:52 audio---livingroom systemd[1]: Startup finished in 13.707s (kernel) + 20.619s (userspace) = 34.327s. Dec 20 12:04:52 audio---livingroom sudo[1241]: pam_unix(sudo:session): session closed for user root Dec 20 12:04:52 audio---livingroom volumio[1116]: info: Loading plugin "upnp_browser"... Dec 20 12:04:52 audio---livingroom volumio[1116]: info: Starting UPNP Browser Dec 20 12:04:52 audio---livingroom volumio[1116]: info: Loading plugin "alarm-clock"... Dec 20 12:04:52 audio---livingroom volumio[1116]: info: Loading plugin "airplay_emulation"... Dec 20 12:04:52 audio---livingroom volumio[1116]: info: Starting Shairport Sync Dec 20 12:04:52 audio---livingroom volumio[1116]: info: Loading plugin "last_100"... Dec 20 12:04:52 audio---livingroom volumio[1116]: info: Loading plugin "webradio"... Dec 20 12:04:53 audio---livingroom volumio[1116]: info: Loading plugin "i2s_dacs"... Dec 20 12:04:53 audio---livingroom volumio[1116]: info: Loading plugin "volumiodiscovery"... Dec 20 12:04:53 audio---livingroom volumio[1116]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 12:04:53 audio---livingroom volumio[1116]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 12:04:53 audio---livingroom volumio[1116]: *** WARNING *** For more information see Dec 20 12:04:53 audio---livingroom volumio[1116]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 12:04:53 audio---livingroom node[1116]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 20 12:04:53 audio---livingroom volumio[1116]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 12:04:53 audio---livingroom volumio[1116]: *** WARNING *** For more information see Dec 20 12:04:53 audio---livingroom node[1116]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 12:04:53 audio---livingroom node[1116]: *** WARNING *** For more information see Dec 20 12:04:53 audio---livingroom node[1116]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 20 12:04:53 audio---livingroom node[1116]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 20 12:04:53 audio---livingroom node[1116]: *** WARNING *** For more information see Dec 20 12:04:53 audio---livingroom volumio[1116]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 20 12:04:53 audio---livingroom volumio[1116]: info: Discovery: Started advertising with name: audio - livingroom Dec 20 12:04:53 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 20 12:04:53 audio---livingroom volumio[1116]: info: Plugin jellyfin is not enabled Dec 20 12:04:53 audio---livingroom volumio[1116]: info: Loading plugin "soundcloud"... Dec 20 12:04:53 audio---livingroom volumio-remote-updater[608]: [2025-12-20 12:04:53] [connect] Successful connection Dec 20 12:04:54 audio---livingroom volumio[1116]: info: Loading plugin "spop"... Dec 20 12:04:55 audio---livingroom volumio[1116]: info: Loading plugin "ytcr"... Dec 20 12:04:59 audio---livingroom volumio[1116]: info: Loading plugin "outputs"... Dec 20 12:04:59 audio---livingroom volumio[1116]: info: Loading plugin "albumart"... Dec 20 12:04:59 audio---livingroom volumio[1116]: info: Plugin example_plugin is not enabled Dec 20 12:04:59 audio---livingroom volumio[1116]: info: Loading plugin "inputs"... Dec 20 12:04:59 audio---livingroom volumio[1116]: info: Loading plugin "updater_comm"... Dec 20 12:04:59 audio---livingroom volumio[1116]: info: Plugin mpdemulation is not enabled Dec 20 12:04:59 audio---livingroom volumio[1116]: info: Loading plugin "rest_api"... Dec 20 12:04:59 audio---livingroom volumio[1116]: info: Loading plugin "websocket"... Dec 20 12:04:59 audio---livingroom volumio[1116]: info: Starting Socket.io Server version 2.3.0 Dec 20 12:05:00 audio---livingroom volumio[1116]: info: Loading i18n strings for locale en Dec 20 12:05:00 audio---livingroom volumio[1116]: Updating browse sources language Dec 20 12:05:00 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 12:05:00 audio---livingroom volumio[1116]: Forking 3 albumart workers Dec 20 12:05:00 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 12:05:00 audio---livingroom volumio[1116]: info: CoreCommandRouter::initPlayerControls Dec 20 12:05:00 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:00 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:00 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:00 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:00 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:00 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:00 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:00 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:00 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 12:05:00 audio---livingroom volumio[1116]: Express server listening on port 3000 Dec 20 12:05:00 audio---livingroom volumio[1116]: [Metrics] WebUI: 13s 921.01ms Dec 20 12:05:00 audio---livingroom volumio[1116]: info: CoreStateMachine::resetVolumioState Dec 20 12:05:00 audio---livingroom volumio[1116]: info: CoreStateMachine::getcurrentVolume Dec 20 12:05:00 audio---livingroom volumio[1116]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 12:05:00 audio---livingroom volumio[1116]: info: Volumio Network Manager: Network status updated: 1 Dec 20 12:05:00 audio---livingroom volumio[1116]: verbose: New Socket.io Connection to 192.168.2.67:3000 from 192.168.2.80 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Dec 20 12:05:00 audio---livingroom volumio[1116]: verbose: New Socket.io Connection to 192.168.2.67 from 192.168.2.80 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 2 Dec 20 12:05:00 audio---livingroom volumio[1116]: verbose: New Socket.io Connection to 192.168.2.67:3000 from 192.168.2.80 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Dec 20 12:05:00 audio---livingroom volumio[1116]: verbose: New Socket.io Connection to 192.168.2.67 from 192.168.2.80 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 4 Dec 20 12:05:00 audio---livingroom volumio[1116]: verbose: New Socket.io Connection to 192.168.2.67:3000 from 192.168.2.80 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Dec 20 12:05:00 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 12:05:00 audio---livingroom volumio[1116]: info: Reloading queue from file Dec 20 12:05:00 audio---livingroom volumio[1116]: info: Setting Device type: Raspberry PI Dec 20 12:05:00 audio---livingroom volumio[1116]: info: CoreStateMachine::setRepeat true single undefined Dec 20 12:05:00 audio---livingroom volumio[1116]: info: CoreStateMachine::pushState Dec 20 12:05:00 audio---livingroom volumio[1116]: info: CorePlayQueue::getTrack 0 Dec 20 12:05:00 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 12:05:00 audio---livingroom volumio[1116]: info: CoreCommandRouter::volumioPushState Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreStateMachine::setRandom null Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreStateMachine::pushState Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CorePlayQueue::getTrack 0 Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreCommandRouter::volumioPushState Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreCommandRouter::volumioGetState Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CorePlayQueue::getTrack 0 Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 12:05:01 audio---livingroom volumio[1116]: info: Discovery: Getting this device information Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreCommandRouter::volumioGetState Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CorePlayQueue::getTrack 0 Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 12:05:01 audio---livingroom volumio[1116]: info: Completed loading Core Plugins Dec 20 12:05:01 audio---livingroom volumio[1116]: info: Preparing to generate the ALSA configuration file Dec 20 12:05:01 audio---livingroom volumio[1116]: Starting albumart workers Dec 20 12:05:01 audio---livingroom volumio[1116]: Starting albumart workers Dec 20 12:05:01 audio---livingroom volumio[1116]: info: VolumeController:: Volume=28 Mute =false Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreStateMachine::pushState Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CorePlayQueue::getTrack 0 Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreCommandRouter::volumioPushState Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreStateMachine::updateTrackBlock Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CorePlayQueue::getTrackBlock Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreCommandRouter::volumioRetrievevolume Dec 20 12:05:01 audio---livingroom volumio[1116]: Starting albumart workers Dec 20 12:05:01 audio---livingroom volumio[1116]: info: Listing playlists Dec 20 12:05:01 audio---livingroom volumio[1116]: info: Listing playlists Dec 20 12:05:01 audio---livingroom volumio[1116]: info: Asound.conf file unchanged, so no further update is needed Dec 20 12:05:01 audio---livingroom volumio[1116]: info: Output device has changed, restarting MPD Dec 20 12:05:01 audio---livingroom volumio[1116]: info: Output device has changed, restarting Shairport Sync Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 12:05:01 audio---livingroom sudo[1330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 12:05:01 audio---livingroom sudo[1330]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 12:05:01 audio---livingroom sudo[1334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 12:05:01 audio---livingroom sudo[1334]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 12:05:01 audio---livingroom sudo[1330]: pam_unix(sudo:session): session closed for user root Dec 20 12:05:01 audio---livingroom volumio[1116]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 12:05:01 audio---livingroom volumio[1116]: info: ___________ START PLUGINS ___________ Dec 20 12:05:01 audio---livingroom volumio[1116]: info: ControllerMpd::onStart: Initializing MPD Dec 20 12:05:01 audio---livingroom volumio[1116]: info: Creating MPD Configuration file Dec 20 12:05:01 audio---livingroom sudo[1348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 20 12:05:01 audio---livingroom systemd[1]: Listening on mpd.socket. Dec 20 12:05:01 audio---livingroom systemd[1]: Starting Music Player Daemon... Dec 20 12:05:01 audio---livingroom sudo[1348]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 12:05:01 audio---livingroom volumio[1116]: info: [1766225101533] CoreMusicLibrary::Adding element Media Servers Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 12:05:01 audio---livingroom sudo[1351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 20 12:05:01 audio---livingroom sudo[1351]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 12:05:01 audio---livingroom sudo[1348]: pam_unix(sudo:session): session closed for user root Dec 20 12:05:01 audio---livingroom volumio[1116]: info: UPNP Browser: Client initialized successfully Dec 20 12:05:01 audio---livingroom systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 20 12:05:01 audio---livingroom systemd[1]: mpd.service: Succeeded. Dec 20 12:05:01 audio---livingroom systemd[1]: Stopped Music Player Daemon. Dec 20 12:05:01 audio---livingroom systemd[1]: Starting Music Player Daemon... Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 12:05:01 audio---livingroom volumio[1116]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 12:05:01 audio---livingroom sudo[1354]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 20 12:05:01 audio---livingroom sudo[1354]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 12:05:01 audio---livingroom sudo[1354]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 20 12:05:01 audio---livingroom sudo[1354]: pam_unix(sudo:session): session closed for user root Dec 20 12:05:01 audio---livingroom volumio[1116]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 12:05:01 audio---livingroom volumio[1116]: info: [1766225101899] CoreMusicLibrary::Adding element Last_100 Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 12:05:01 audio---livingroom volumio[1116]: info: [1766225101906] CoreMusicLibrary::Adding element Webradio Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 12:05:01 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 12:05:01 audio---livingroom volumio[1116]: info: Initializing BBC Radios Dec 20 12:05:02 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 12:05:02 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 12:05:02 audio---livingroom volumio[1116]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 12:05:02 audio---livingroom volumio[1116]: info: [1766225102051] CoreMusicLibrary::Adding element SoundCloud Dec 20 12:05:02 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 12:05:02 audio---livingroom volumio[1116]: Cannot find translation for source SoundCloud Dec 20 12:05:02 audio---livingroom volumio[1116]: info: Creating Spotify config file Dec 20 12:05:02 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:02 audio---livingroom volumio[1116]: info: Volumio Calling Home Dec 20 12:05:02 audio---livingroom volumio[1116]: info: Discovery: adding 91a0b469-0130-43c2-9946-73b56b95bd1f Dec 20 12:05:02 audio---livingroom volumio[1116]: info: Discovery: Found device audio - livingroom Dec 20 12:05:02 audio---livingroom volumio[1116]: info: CoreCommandRouter::volumioGetState Dec 20 12:05:02 audio---livingroom volumio[1116]: info: CorePlayQueue::getTrack 0 Dec 20 12:05:02 audio---livingroom sudo[1401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 20 12:05:02 audio---livingroom sudo[1401]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 12:05:02 audio---livingroom sudo[1401]: pam_unix(sudo:session): session closed for user root Dec 20 12:05:02 audio---livingroom sudo[1403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 20 12:05:02 audio---livingroom sudo[1403]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 12:05:02 audio---livingroom volumio[1116]: verbose: New Socket.io Connection to 192.168.2.67 from 192.168.2.80 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Dec 20 12:05:02 audio---livingroom sudo[1403]: pam_unix(sudo:session): session closed for user root Dec 20 12:05:02 audio---livingroom volumio[1116]: info: MPD Permissions set Dec 20 12:05:02 audio---livingroom volumio[1116]: info: MPD Permissions set Dec 20 12:05:02 audio---livingroom volumio[1116]: info: VolumeController:: Volume=28 Mute =false Dec 20 12:05:02 audio---livingroom volumio[1116]: info: CoreCommandRouter::volumioGetState Dec 20 12:05:02 audio---livingroom volumio[1116]: info: CorePlayQueue::getTrack 0 Dec 20 12:05:02 audio---livingroom volumio[1116]: info: CoreStateMachine::pushState Dec 20 12:05:02 audio---livingroom volumio[1116]: info: CorePlayQueue::getTrack 0 Dec 20 12:05:02 audio---livingroom volumio[1116]: info: CoreCommandRouter::volumioPushState Dec 20 12:05:02 audio---livingroom volumio[1116]: info: Volumio called home Dec 20 12:05:02 audio---livingroom volumio[1116]: info: Spotify config file written Dec 20 12:05:02 audio---livingroom sudo[1407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 20 12:05:02 audio---livingroom sudo[1407]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 12:05:02 audio---livingroom volumio[1116]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 20 12:05:02 audio---livingroom systemd[1]: Started go-librespot Daemon. Dec 20 12:05:02 audio---livingroom volumio[1116]: info: No need to fix Spotify hosts Dec 20 12:05:02 audio---livingroom go-librespot[1409]: go-librespot daemon starting... Dec 20 12:05:02 audio---livingroom sudo[1407]: pam_unix(sudo:session): session closed for user root Dec 20 12:05:02 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:02 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:02 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:02 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:02 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:02 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:02 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:02 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:02 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 12:05:03 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:03 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:03 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:03 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:03 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:03 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:03 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 12:05:03 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 20 12:05:03 audio---livingroom volumio[1116]: info: Starting Shairport Sync Dec 20 12:05:03 audio---livingroom volumio[1116]: info: Starting Shairport Sync Dec 20 12:05:03 audio---livingroom volumio[1116]: info: Starting Shairport Sync Dec 20 12:05:03 audio---livingroom sudo[1425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 20 12:05:03 audio---livingroom sudo[1425]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 12:05:03 audio---livingroom sudo[1429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 20 12:05:03 audio---livingroom sudo[1429]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 12:05:03 audio---livingroom sudo[1427]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 20 12:05:03 audio---livingroom sudo[1427]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 12:05:03 audio---livingroom volumio[1116]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 20 12:05:03 audio---livingroom volumio[1116]: SPOTIFY: BQCWn3thuDuGSjx-Pf4-kfDoMihxa6cozk_2F3_0JVStRVCWea-WN5VOdkFzdoXPJiydc3MZLk6lSuXOVqWAPXX8lQfK9rEcZmG_CHHmB0TUnDIdPmvp_yKSHmxadDi_LxJMp_ZHq242KXhMNtD_QabEGHPWSkl3_Mrp_PZHoJPpdICrJMEONqHOb0tulOlHGIpBNySpiy9ElgmsX5P-GBITWIrUpitOohvliqb9ywLIDW-yfPSFRzdFBjARui5d6uwepwG3X4lRCY98pez29ZturGZ-oQ1asuj0oaXMfTBzcUZQV80lLRrd Dec 20 12:05:03 audio---livingroom volumio[1116]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 20 12:05:03 audio---livingroom volumio[1116]: info: New Spotify access token = BQCWn3thuDuGSjx-Pf4-kfDoMihxa6cozk_2F3_0JVStRVCWea-WN5VOdkFzdoXPJiydc3MZLk6lSuXOVqWAPXX8lQfK9rEcZmG_CHHmB0TUnDIdPmvp_yKSHmxadDi_LxJMp_ZHq242KXhMNtD_QabEGHPWSkl3_Mrp_PZHoJPpdICrJMEONqHOb0tulOlHGIpBNySpiy9ElgmsX5P-GBITWIrUpitOohvliqb9ywLIDW-yfPSFRzdFBjARui5d6uwepwG3X4lRCY98pez29ZturGZ-oQ1asuj0oaXMfTBzcUZQV80lLRrd Dec 20 12:05:03 audio---livingroom volumio[1116]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 20 12:05:03 audio---livingroom sudo[1432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 20 12:05:03 audio---livingroom sudo[1432]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 12:05:03 audio---livingroom volumio[1116]: info: CoreCommandRouter::volumioGetState Dec 20 12:05:03 audio---livingroom volumio[1116]: info: CorePlayQueue::getTrack 0 Dec 20 12:05:03 audio---livingroom systemd[1]: Started UPnP Renderer front-end to MPD. Dec 20 12:05:03 audio---livingroom sudo[1429]: pam_unix(sudo:session): session closed for user root Dec 20 12:05:03 audio---livingroom systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 20 12:05:03 audio---livingroom systemd[1]: shairport-sync.service: Succeeded. Dec 20 12:05:03 audio---livingroom systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 20 12:05:03 audio---livingroom volumio[1116]: info: Upmpdcli Daemon Started Dec 20 12:05:03 audio---livingroom systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 20 12:05:03 audio---livingroom sudo[1432]: pam_unix(sudo:session): session closed for user root Dec 20 12:05:03 audio---livingroom sudo[1425]: pam_unix(sudo:session): session closed for user root Dec 20 12:05:03 audio---livingroom volumio[1116]: info: Shairport-Sync Started Dec 20 12:05:03 audio---livingroom sudo[1427]: pam_unix(sudo:session): session closed for user root Dec 20 12:05:03 audio---livingroom volumio[1116]: Error adding Membership: Error: addMembership EINVAL Dec 20 12:05:03 audio---livingroom volumio[1116]: info: Shairport-Sync Started Dec 20 12:05:03 audio---livingroom volumio[1116]: info: Shairport-Sync Started Dec 20 12:05:03 audio---livingroom volumio[1116]: SPOTIFY: User informations: {"country":"RO","display_name":"Buligă.","email":"tudortdr@icloud.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/x3w0fjbe50ho4aght8e0e9scc"},"followers":{"href":null,"total":6},"href":"https://api.spotify.com/v1/users/x3w0fjbe50ho4aght8e0e9scc","id":"x3w0fjbe50ho4aght8e0e9scc","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee856ff528e4ffeea1dd30038abc","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b826ff528e4ffeea1dd30038abc","width":64}],"product":"premium","type":"user","uri":"spotify:user:x3w0fjbe50ho4aght8e0e9scc"} Dec 20 12:05:03 audio---livingroom volumio[1116]: info: Spotify Successfully logged in Dec 20 12:05:03 audio---livingroom volumio[1116]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 20 12:05:03 audio---livingroom volumio[1116]: info: [1766225103798] CoreMusicLibrary::Adding element Spotify Dec 20 12:05:03 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 12:05:03 audio---livingroom volumio[1116]: Cannot find translation for source SoundCloud Dec 20 12:05:03 audio---livingroom volumio[1116]: Cannot find translation for source Spotify Dec 20 12:05:04 audio---livingroom go-librespot[1409]: time="2025-12-20T12:05:04+02:00" level=info msg="running go-librespot 0.4.0" Dec 20 12:05:04 audio---livingroom go-librespot[1409]: time="2025-12-20T12:05:04+02:00" level=debug msg="app state loaded" Dec 20 12:05:04 audio---livingroom go-librespot[1409]: time="2025-12-20T12:05:04+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 12:05:04 audio---livingroom volumio[1116]: info: [yt-cast-receiver] DIAL server listening on port 8098 Dec 20 12:05:04 audio---livingroom go-librespot[1409]: time="2025-12-20T12:05:04+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 20 12:05:04 audio---livingroom go-librespot[1409]: time="2025-12-20T12:05:04+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 20 12:05:04 audio---livingroom go-librespot[1409]: time="2025-12-20T12:05:04+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 20 12:05:04 audio---livingroom go-librespot[1409]: time="2025-12-20T12:05:04+02:00" level=info msg="zeroconf server listening on port 37523" Dec 20 12:05:04 audio---livingroom go-librespot[1409]: time="2025-12-20T12:05:04+02:00" level=debug msg="obtained new client token: AAAWweGJ68OluXWqz1dk0XeCTWj26MNlhhq5klplAXhSBDT9htYNw0YJeU3S3z/jcXk9Sjle3TZMuAEEC3IN+SNDrMgHQ/BqOv5USCxzM047CvqzoAKJOu+LLA5BkcaM/Vhhgg79PebWXo1irqgDJOk3IaW0mjPyIKb1R9NcxUomQd6FVzo4iJFFw2bPbRQcw0t/EkVnVxIOhFarDdgUkKZkwMfLo/dKZjzy74PZS2H2s8kEEqs=" Dec 20 12:05:04 audio---livingroom go-librespot[1409]: time="2025-12-20T12:05:04+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Dec 20 12:05:04 audio---livingroom go-librespot[1409]: time="2025-12-20T12:05:04+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Dec 20 12:05:04 audio---livingroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 12:05:04 audio---livingroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 12:05:05 audio---livingroom mpd[1362]: Dec 20 12:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 20 12:05:05 audio---livingroom systemd[1]: Started Music Player Daemon. Dec 20 12:05:05 audio---livingroom volumio[1116]: Upnp client error: Error: This socket has been ended by the other party Dec 20 12:05:05 audio---livingroom sudo[1351]: pam_unix(sudo:session): session closed for user root Dec 20 12:05:05 audio---livingroom sudo[1334]: pam_unix(sudo:session): session closed for user root Dec 20 12:05:05 audio---livingroom volumio[1116]: info: Completed starting Core Plugins Dec 20 12:05:05 audio---livingroom volumio[1116]: info: ------------------------------------------- Dec 20 12:05:05 audio---livingroom volumio[1116]: info: ----- MyVolumio plugins startup ---- Dec 20 12:05:05 audio---livingroom volumio[1116]: info: ------------------------------------------- Dec 20 12:05:05 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 20 12:05:05 audio---livingroom volumio[1116]: info: MPD running with PID1362 Dec 20 12:05:05 audio---livingroom volumio[1116]: ,establishing connection Dec 20 12:05:06 audio---livingroom volumio[1116]: error: MPD error: The expression evaluated to a falsy value: Dec 20 12:05:06 audio---livingroom volumio[1116]: assert.ok(self.idling) Dec 20 12:05:06 audio---livingroom volumio[1116]: error: The expression evaluated to a falsy value: Dec 20 12:05:06 audio---livingroom volumio[1116]: assert.ok(self.idling) Dec 20 12:05:06 audio---livingroom volumio[1116]: error: MPD error: The expression evaluated to a falsy value: Dec 20 12:05:06 audio---livingroom volumio[1116]: assert.ok(self.idling) Dec 20 12:05:06 audio---livingroom volumio[1116]: error: The expression evaluated to a falsy value: Dec 20 12:05:06 audio---livingroom volumio[1116]: assert.ok(self.idling) Dec 20 12:05:06 audio---livingroom volumio[1116]: error: updateQueue error: null Dec 20 12:05:06 audio---livingroom volumio[1116]: info: go-librespot daemon successfully initialized Dec 20 12:05:06 audio---livingroom volumio[1116]: verbose: New Socket.io Connection to 192.168.2.67 from 192.168.2.80 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Dec 20 12:05:06 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 20 12:05:06 audio---livingroom volumio[1116]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 20 12:05:06 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 20 12:05:06 audio---livingroom volumio[1116]: info: Received Get System Version Dec 20 12:05:06 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 12:05:06 audio---livingroom volumio[1116]: info: Received Get System Info Dec 20 12:05:06 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 12:05:06 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 12:05:06 audio---livingroom volumio[1116]: info: Discovery: Getting this device information Dec 20 12:05:06 audio---livingroom volumio[1116]: info: CoreCommandRouter::volumioGetState Dec 20 12:05:06 audio---livingroom volumio[1116]: info: CorePlayQueue::getTrack 0 Dec 20 12:05:06 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 12:05:06 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 20 12:05:06 audio---livingroom volumio[1116]: info: CoreCommandRouter::volumioGetVisibleSources Dec 20 12:05:06 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 12:05:06 audio---livingroom volumio[1116]: info: CoreCommandRouter::volumioGetState Dec 20 12:05:06 audio---livingroom volumio[1116]: info: CorePlayQueue::getTrack 0 Dec 20 12:05:06 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 20 12:05:06 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 20 12:05:06 audio---livingroom volumio[1116]: info: Received Get System Info Dec 20 12:05:06 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 12:05:06 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 12:05:06 audio---livingroom volumio[1116]: info: Discovery: Getting this device information Dec 20 12:05:06 audio---livingroom volumio[1116]: info: CoreCommandRouter::volumioGetState Dec 20 12:05:06 audio---livingroom volumio[1116]: info: CorePlayQueue::getTrack 0 Dec 20 12:05:06 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 12:05:06 audio---livingroom volumio[1116]: info: CoreCommandRouter::volumioGetState Dec 20 12:05:06 audio---livingroom volumio[1116]: info: CorePlayQueue::getTrack 0 Dec 20 12:05:06 audio---livingroom volumio[1116]: info: Listing playlists Dec 20 12:05:07 audio---livingroom systemd[1]: systemd-fsckd.service: Succeeded. Dec 20 12:05:07 audio---livingroom volumio[1436]: Generating RSA private key, 4096 bit long modulus (2 primes) Dec 20 12:05:07 audio---livingroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 20 12:05:07 audio---livingroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 20 12:05:07 audio---livingroom systemd[1]: Stopped go-librespot Daemon. Dec 20 12:05:07 audio---livingroom systemd[1]: Started go-librespot Daemon. Dec 20 12:05:07 audio---livingroom go-librespot[1461]: go-librespot daemon starting... Dec 20 12:05:07 audio---livingroom go-librespot[1461]: time="2025-12-20T12:05:07+02:00" level=info msg="running go-librespot 0.4.0" Dec 20 12:05:07 audio---livingroom go-librespot[1461]: time="2025-12-20T12:05:07+02:00" level=debug msg="app state loaded" Dec 20 12:05:07 audio---livingroom go-librespot[1461]: time="2025-12-20T12:05:07+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 12:05:08 audio---livingroom go-librespot[1461]: time="2025-12-20T12:05:08+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Dec 20 12:05:08 audio---livingroom go-librespot[1461]: time="2025-12-20T12:05:08+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 20 12:05:08 audio---livingroom go-librespot[1461]: time="2025-12-20T12:05:08+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 20 12:05:08 audio---livingroom go-librespot[1461]: time="2025-12-20T12:05:08+02:00" level=info msg="zeroconf server listening on port 34813" Dec 20 12:05:08 audio---livingroom go-librespot[1461]: time="2025-12-20T12:05:08+02:00" level=debug msg="obtained new client token: AACyByYZYPzaSdy/PghfB9G3McReptPotov+EbcJIO3jLureB87m/wGrke8R6XOwwLeKJQGmJI2xrjCAU0hzymFIcyBpY6VNOwQVQacx264TPGgcH/OYoNzAkfY5VikkAqOGXdb7GuF9dY9nrk3+Pqe9tBVK3UnCVx4mpEwIaWjTZD8kpkFOTtYM2x9OcgYFIYTDU+sgiZaMSubXSgQrbVCmjPEvwTPPxTQfMhL2ooW0Evp1cnmoDg==" Dec 20 12:05:08 audio---livingroom go-librespot[1461]: time="2025-12-20T12:05:08+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Dec 20 12:05:08 audio---livingroom go-librespot[1461]: time="2025-12-20T12:05:08+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.2.67:44850->104.199.65.9:4070: read: connection reset by peer" Dec 20 12:05:08 audio---livingroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 20 12:05:08 audio---livingroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 20 12:05:08 audio---livingroom volumio-remote-updater[608]: [2025-12-20 12:05:08] [connect] Successful connection Dec 20 12:05:08 audio---livingroom volumio-remote-updater[608]: [2025-12-20 12:05:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766225108 101 Dec 20 12:05:08 audio---livingroom volumio[1116]: 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: 9 Dec 20 12:05:08 audio---livingroom sudo[1470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 20 12:05:08 audio---livingroom sudo[1470]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 12:05:08 audio---livingroom sudo[1473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 20 12:05:08 audio---livingroom sudo[1473]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 12:05:09 audio---livingroom systemd[1]: Started MPD Monitor Service. Dec 20 12:05:09 audio---livingroom mpd_monitor.sh[1474]: MPD Monitor Service: Starting MPD Monitor Service Dec 20 12:05:09 audio---livingroom sudo[1470]: pam_unix(sudo:session): session closed for user root Dec 20 12:05:09 audio---livingroom volumio[1116]: info: Successfully started MPD Monitor Dec 20 12:05:09 audio---livingroom systemd[1]: Stopping MPD Monitor Service... Dec 20 12:05:09 audio---livingroom systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 20 12:05:09 audio---livingroom systemd[1]: mpd_monitor.service: Succeeded. Dec 20 12:05:09 audio---livingroom systemd[1]: Stopped MPD Monitor Service. Dec 20 12:05:09 audio---livingroom systemd[1]: Started MPD Monitor Service. Dec 20 12:05:09 audio---livingroom sudo[1473]: pam_unix(sudo:session): session closed for user root Dec 20 12:05:09 audio---livingroom mpd_monitor.sh[1478]: MPD Monitor Service: Starting MPD Monitor Service Dec 20 12:05:09 audio---livingroom volumio[1116]: info: Successfully started MPD Monitor Dec 20 12:05:09 audio---livingroom volumio[1116]: info: Initializing connection to go-librespot Websocket Dec 20 12:05:09 audio---livingroom volumio[1116]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 20 12:05:10 audio---livingroom volumio[1116]: info: CoreCommandRouter::volumioGetState Dec 20 12:05:10 audio---livingroom volumio[1116]: info: CorePlayQueue::getTrack 0 Dec 20 12:05:11 audio---livingroom volumio[1116]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 20 12:05:11 audio---livingroom systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 20 12:05:11 audio---livingroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Dec 20 12:05:11 audio---livingroom systemd[1]: Stopped go-librespot Daemon. Dec 20 12:05:11 audio---livingroom systemd[1]: Started go-librespot Daemon. Dec 20 12:05:11 audio---livingroom go-librespot[1496]: go-librespot daemon starting... Dec 20 12:05:11 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:11+02:00" level=info msg="running go-librespot 0.4.0" Dec 20 12:05:11 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:11+02:00" level=debug msg="app state loaded" Dec 20 12:05:11 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:11+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 20 12:05:11 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:11+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 20 12:05:11 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:11+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 20 12:05:11 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:11+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 20 12:05:11 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:11+02:00" level=info msg="zeroconf server listening on port 35763" Dec 20 12:05:12 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:12+02:00" level=debug msg="obtained new client token: AADICPpYNLaLNtnt0Oqa0krcB4xWrPsTghUJ+juLUb1cqv5/RZGf+Oi5pDKv3X7M14+rpIBt6h3zhLT7Vc3QlzPDt1KzLXjgUi/0AlFZ5zaliguWtJ4wyKPhiZFf3L8coGrvzWmEo3Cliakb8GPk+IBOuODeJ0Q744PTnC+gyvQfj8pLFTK/B+4H5Q/XlksKw+DSDHFZ1/m1ZwIxnJ3iE69xPm3JmxvpSO1PXIrT+jW4inz6w4I8KA==" Dec 20 12:05:12 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:12+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Dec 20 12:05:12 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:12+02:00" level=debug msg="completed keyexchange" Dec 20 12:05:12 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:12+02:00" level=debug msg="completed challenge" Dec 20 12:05:12 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:12+02:00" level=info msg="authenticated AP" username="x3*********************cc" Dec 20 12:05:12 audio---livingroom volumio[1116]: info: Initializing connection to go-librespot Websocket Dec 20 12:05:12 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:12+02:00" level=debug msg="new websocket client" Dec 20 12:05:12 audio---livingroom volumio[1116]: info: Connection to go-librespot Websocket established Dec 20 12:05:12 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:12+02:00" level=info msg="authenticated Login5" username="x3*********************cc" Dec 20 12:05:12 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:12+02:00" level=debug msg="initializing zeroconf session" username="x3*********************cc" Dec 20 12:05:12 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:12+02:00" level=debug msg="dealer connection opened" Dec 20 12:05:12 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:12+02:00" level=trace msg="starting accesspoint recv loop" Dec 20 12:05:12 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:12+02:00" level=trace msg="starting dealer recv loop" Dec 20 12:05:12 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:12+02:00" level=trace msg="received accesspoint ping" Dec 20 12:05:12 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:12+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 20 12:05:12 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:12+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 20 12:05:12 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:12+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 20 12:05:12 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:12+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 20 12:05:12 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:12+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 20 12:05:12 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:12+02:00" level=debug msg="received connection id: ZWFjMzk0ZDAtMGVl...N0ZDMjVBRDI1OQ==" Dec 20 12:05:12 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:12+02:00" level=trace msg="received accesspoint pong ack" Dec 20 12:05:12 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:12+02:00" level=debug msg="put connect state because NEW_DEVICE" Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 20 12:05:14 audio---livingroom volumio[1116]: info: Adding plugin bluetooth to MyMusic Plugins Dec 20 12:05:14 audio---livingroom volumio[1116]: info: Adding plugin multiroom to MyMusic Plugins Dec 20 12:05:14 audio---livingroom volumio[1116]: info: Adding plugin metavolumio to MyMusic Plugins Dec 20 12:05:14 audio---livingroom volumio[1116]: info: Adding plugin cd_controller to MyMusic Plugins Dec 20 12:05:14 audio---livingroom volumio[1116]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 20 12:05:14 audio---livingroom volumio[1116]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 20 12:05:14 audio---livingroom volumio[1116]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 20 12:05:14 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 20 12:05:15 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:15+02:00" level=debug msg="handling transfer player command from 4f7cc80d56e2967fc0c2ffc258cf3f0b22b1feb1" Dec 20 12:05:15 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:15+02:00" level=debug msg="resolved context of track" uri="spotify:search:Waste+-+RY+X+Remix" Dec 20 12:05:15 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:15+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:search:Waste+-+RY+X+Remix" Dec 20 12:05:15 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:15+02:00" level=debug msg="loading track (paused: true, position: 9200ms)" uri="spotify:track:08HR9PoLSeovgXWvJEZrvi" Dec 20 12:05:15 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:15+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 20 12:05:15 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:15+02:00" level=trace msg="emitting websocket event: will_play" Dec 20 12:05:15 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:15+02:00" level=debug msg="selected format OGG_VORBIS_320 (e7f02a90084a9bde1f1be5538cd052ea130adad8)" uri="spotify:track:08HR9PoLSeovgXWvJEZrvi" Dec 20 12:05:15 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:15+02:00" level=debug msg="requested aes key for file e7f02a90084a9bde1f1be5538cd052ea130adad8, gid: 08HR9PoLSeovgXWvJEZrvi" Dec 20 12:05:15 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:15+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1306" Dec 20 12:05:15 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:15+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 415" Dec 20 12:05:15 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:15+02:00" level=trace msg="found 2 cdn urls" uri="spotify:track:08HR9PoLSeovgXWvJEZrvi" Dec 20 12:05:15 audio---livingroom volumio[1436]: ...........................................................++++ Dec 20 12:05:16 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 20 12:05:16 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 20 12:05:16 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 12:05:16 audio---livingroom volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 20 12:05:16 audio---livingroom volumio[1116]: info: Starting MyVolumio Remote Streaming Endpoints Dec 20 12:05:16 audio---livingroom volumio[1116]: info: MyVolumio login type: Token Dec 20 12:05:16 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:16+02:00" level=debug msg="fetched first chunk of 40, total size is 20495260 bytes" uri="spotify:track:08HR9PoLSeovgXWvJEZrvi" Dec 20 12:05:16 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 20 12:05:16 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:16+02:00" level=trace msg="seek to 9200ms (diff: 181ms, samples: 405720, bytes: 301694)" uri="spotify:track:08HR9PoLSeovgXWvJEZrvi" Dec 20 12:05:16 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:16+02:00" level=debug msg="created new output device" Dec 20 12:05:16 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:16+02:00" level=info msg="loaded track \"Waste - RY X Remix\" (paused: true, position: 9200ms, duration: 553413ms, prefetched: false)" uri="spotify:track:08HR9PoLSeovgXWvJEZrvi" Dec 20 12:05:16 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 20 12:05:16 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:16+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 20 12:05:16 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:16+02:00" level=trace msg="emitting websocket event: metadata" Dec 20 12:05:16 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:16+02:00" level=trace msg="emitting websocket event: active" Dec 20 12:05:16 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:16+02:00" level=debug msg="sending successful reply for dealer request" Dec 20 12:05:16 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:16+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 20 12:05:16 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:16+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Dec 20 12:05:16 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:16+02:00" level=debug msg="fetched chunk 1/39, size: 524288" uri="spotify:track:08HR9PoLSeovgXWvJEZrvi" Dec 20 12:05:16 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:16+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 20 12:05:16 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:16+02:00" level=trace msg="emitting websocket event: paused" Dec 20 12:05:16 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:16+02:00" level=debug msg="fetched chunk 2/39, size: 524288" uri="spotify:track:08HR9PoLSeovgXWvJEZrvi" Dec 20 12:05:16 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:16+02:00" level=debug msg="fetched chunk 3/39, size: 524288" uri="spotify:track:08HR9PoLSeovgXWvJEZrvi" Dec 20 12:05:17 audio---livingroom volumio[1436]: .......++++ Dec 20 12:05:17 audio---livingroom volumio[1436]: e is 65537 (0x010001) Dec 20 12:05:17 audio---livingroom volumio[1436]: writing RSA key Dec 20 12:05:17 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:17+02:00" level=debug msg="handling resume player command from 4f7cc80d56e2967fc0c2ffc258cf3f0b22b1feb1" Dec 20 12:05:17 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:17+02:00" level=trace msg="seek to 9200ms (diff: 181ms, samples: 405720, bytes: 301694)" uri="spotify:track:08HR9PoLSeovgXWvJEZrvi" Dec 20 12:05:17 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:17+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames, PCM format = FLOAT_LE" Dec 20 12:05:17 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:17+02:00" level=debug msg="resume track at 9019ms" Dec 20 12:05:17 audio---livingroom volumio[1116]: info: Starting Streaming Service Transparent Proxy Dec 20 12:05:17 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 20 12:05:17 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 20 12:05:17 audio---livingroom volumio[1116]: info: Streaming services startup Dec 20 12:05:17 audio---livingroom volumio[1116]: info: Starting Streaming Daemon Dec 20 12:05:17 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:17+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 20 12:05:17 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:17+02:00" level=trace msg="scheduling prefetch in 514s" Dec 20 12:05:17 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:17+02:00" level=debug msg="sending successful reply for dealer request" Dec 20 12:05:17 audio---livingroom sudo[1527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 20 12:05:17 audio---livingroom sudo[1527]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 12:05:17 audio---livingroom volumio[1116]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 20 12:05:17 audio---livingroom sudo[1527]: pam_unix(sudo:session): session closed for user root Dec 20 12:05:17 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:17+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 20 12:05:17 audio---livingroom go-librespot[1496]: time="2025-12-20T12:05:17+02:00" level=trace msg="emitting websocket event: playing" Dec 20 12:05:17 audio---livingroom volumio[1116]: info: Getting Spotify volume Dec 20 12:05:17 audio---livingroom volumio[1116]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:search:Waste+-+RY+X+Remix","uri":"spotify:track:08HR9PoLSeovgXWvJEZrvi","play_origin":"search"}} Dec 20 12:05:17 audio---livingroom volumio[1116]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:08HR9PoLSeovgXWvJEZrvi","name":"Waste - RY X Remix","artist_names":["Rhye","RY X"],"album_name":"Blood Remixed","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02d266d98f5f9a0ba1fd1aeac1","position":9200,"duration":553413,"release_date":"year:2018 month:11 day:16","track_number":1,"disc_number":1}} Dec 20 12:05:17 audio---livingroom volumio[1116]: SPOTIFY: received: {"type":"active","data":null} Dec 20 12:05:17 audio---livingroom volumio[1116]: info: Aligning Spotify Volume to Volumio Volume Dec 20 12:05:17 audio---livingroom volumio[1116]: info: CoreCommandRouter::volumioGetState Dec 20 12:05:17 audio---livingroom volumio[1116]: info: CorePlayQueue::getTrack 0 Dec 20 12:05:17 audio---livingroom volumio[1116]: info: Setting Spotify Volume from Volumio: 28 Dec 20 12:05:17 audio---livingroom volumio[1116]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:search:Waste+-+RY+X+Remix","uri":"spotify:track:08HR9PoLSeovgXWvJEZrvi","play_origin":"search"}} Dec 20 12:05:17 audio---livingroom volumio[1116]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 12:05:17 audio---livingroom volumio[1116]: TypeError: Cannot read property 'service' of undefined Dec 20 12:05:17 audio---livingroom volumio[1116]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:346:50) Dec 20 12:05:17 audio---livingroom volumio[1116]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:280:18) Dec 20 12:05:17 audio---livingroom volumio[1116]: at WebSocket.message (/data/plugins/music_service/spop/index.js:199:14) Dec 20 12:05:17 audio---livingroom volumio[1116]: at WebSocket.emit (events.js:315:20) Dec 20 12:05:17 audio---livingroom volumio[1116]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Dec 20 12:05:17 audio---livingroom volumio[1116]: at Receiver.emit (events.js:315:20) Dec 20 12:05:17 audio---livingroom volumio[1116]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Dec 20 12:05:17 audio---livingroom volumio[1116]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Dec 20 12:05:17 audio---livingroom volumio[1116]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Dec 20 12:05:17 audio---livingroom volumio[1116]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Dec 20 12:05:17 audio---livingroom volumio[1116]: at writeOrBuffer (internal/streams/writable.js:358:12) Dec 20 12:05:17 audio---livingroom volumio[1116]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Dec 20 12:05:17 audio---livingroom volumio[1116]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Dec 20 12:05:17 audio---livingroom volumio[1116]: at Socket.emit (events.js:315:20) Dec 20 12:05:17 audio---livingroom volumio[1116]: at addChunk (internal/streams/readable.js:309:12) Dec 20 12:05:17 audio---livingroom volumio[1116]: at readableAddChunk (internal/streams/readable.js:284:9) Dec 20 12:05:17 audio---livingroom volumio[1116]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 12:05:18 audio---livingroom sudo[1543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-20 12:04 Dec 20 12:05:18 audio---livingroom sudo[1543]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"