Dec 25 11:39:00 harman systemd[1]: alsa-state.service - Manage Sound Card State (restore and store) was skipped because of an unmet condition check (ConditionPathExists=/etc/alsa/state-daemon.conf). Dec 25 11:39:00 harman systemd[1]: Reached target sound.target - Sound Card. Dec 25 11:39:00 harman ntpd[937]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 25 11:39:00 harman ntpd[937]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 25 11:39:00 harman ntpd[937]: DNS: dns_check: DNS error: -11, System error Dec 25 11:39:00 harman ntpd[937]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Dec 25 11:39:01 harman systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. Dec 25 11:39:01 harman systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. Dec 25 11:39:01 harman kernel: at24 2-0050: supply vcc not found, using dummy regulator Dec 25 11:39:01 harman kernel: at24 2-0050: 256 byte spd EEPROM, read-only Dec 25 11:39:01 harman volumio-remote-updater[666]: [2025-12-25 11:39:01] [info] asio async_connect error: asio.system:111 (Connection refused) Dec 25 11:39:01 harman volumio-remote-updater[666]: [2025-12-25 11:39:01] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Dec 25 11:39:01 harman volumio-remote-updater[666]: [2025-12-25 11:39:01] [error] handle_connect error: Connection refused Dec 25 11:39:01 harman kernel: Bluetooth: hci0: command 0xfc45 tx timeout Dec 25 11:39:01 harman kernel: Bluetooth: hci0: BCM: Reset failed (-110) Dec 25 11:39:02 harman dhcpcd[798]: eth0: leased 192.168.1.176 for 7200 seconds Dec 25 11:39:02 harman avahi-daemon[647]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.176. Dec 25 11:39:02 harman avahi-daemon[647]: New relevant interface eth0.IPv4 for mDNS. Dec 25 11:39:02 harman avahi-daemon[647]: Registering new address record for 192.168.1.176 on eth0.IPv4. Dec 25 11:39:02 harman dhcpcd[798]: eth0: adding route to 192.168.1.0/24 Dec 25 11:39:02 harman dhcpcd[798]: eth0: adding default route via 192.168.1.1 Dec 25 11:39:02 harman systemd[1]: welcome.service: Deactivated successfully. Dec 25 11:39:02 harman systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 25 11:39:02 harman systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 25 11:39:02 harman systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 25 11:39:02 harman welcome[1069]: Resolved ip:[1] 192.168.1.176 Dec 25 11:39:03 harman systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 25 11:39:03 harman systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 25 11:39:03 harman ntpd[937]: IO: Listen normally on 3 eth0 192.168.1.176:123 Dec 25 11:39:03 harman ntpd[937]: IO: new interface(s) found: waking up resolver Dec 25 11:39:03 harman ntpd[937]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 25 11:39:03 harman ntpd[937]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 25 11:39:03 harman ntpd[937]: DNS: Pool taking: 160.119.230.39 Dec 25 11:39:03 harman ntpd[937]: DNS: Pool taking: 196.44.136.165 Dec 25 11:39:03 harman ntpd[937]: DNS: Pool taking: 41.205.142.133 Dec 25 11:39:03 harman ntpd[937]: DNS: Pool taking: 196.10.55.57 Dec 25 11:39:03 harman ntpd[937]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Dec 25 11:39:04 harman ntpd[937]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 25 11:39:05 harman ntpd[937]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 25 11:39:05 harman ntpd[937]: DNS: Pool taking: 196.10.98.182 Dec 25 11:39:05 harman ntpd[937]: DNS: Pool taking: 102.216.37.99 Dec 25 11:39:05 harman ntpd[937]: DNS: Pool taking: 102.64.112.164 Dec 25 11:39:05 harman ntpd[937]: DNS: Pool taking: 139.84.231.151 Dec 25 11:39:05 harman ntpd[937]: DNS: Pool taking: 2001:67c:d74:c6::71be Dec 25 11:39:05 harman ntpd[937]: DNS: Pool taking: 2001:43f8:13b0:2000::25 Dec 25 11:39:05 harman ntpd[937]: DNS: Pool taking: 2c0f:f830:1:b:41:175:36:40 Dec 25 11:39:05 harman ntpd[937]: DNS: Pool taking: 2001:43f8:1f5:100::58 Dec 25 11:39:05 harman ntpd[937]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Dec 25 11:39:05 harman nmbd[1090]: [2025/12/25 11:39:05.588789, 0] ../../source3/nmbd/nmbd.c:901(main) Dec 25 11:39:05 harman nmbd[1090]: nmbd version 4.17.12-Debian started. Dec 25 11:39:05 harman nmbd[1090]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 25 11:39:05 harman ntpd[937]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 25 11:39:05 harman nmbd[1090]: [2025/12/25 11:39:05.673230, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Dec 25 11:39:05 harman nmbd[1090]: started asyncdns process 1113 Dec 25 11:39:05 harman ntpd[937]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 25 11:39:05 harman ntpd[937]: DNS: Pool taking: 196.49.100.57 Dec 25 11:39:05 harman ntpd[937]: DNS: Pool taking: 196.10.55.58 Dec 25 11:39:05 harman ntpd[937]: DNS: Pool taking: 139.84.231.68 Dec 25 11:39:05 harman ntpd[937]: DNS: Pool taking: 160.119.216.197 Dec 25 11:39:05 harman ntpd[937]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Dec 25 11:39:05 harman systemd[1]: Started nmbd.service - Samba NMB Daemon. Dec 25 11:39:05 harman systemd[1]: Starting winbind.service - Samba Winbind Daemon... Dec 25 11:39:05 harman systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Dec 25 11:39:05 harman systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 25 11:39:06 harman systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 25 11:39:06 harman upmpdcli[1118]: Could not open config: /tmp/upmpdcli.conf Dec 25 11:39:06 harman systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 25 11:39:06 harman systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 25 11:39:06 harman ntpd[937]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 25 11:39:06 harman ntpd[937]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 25 11:39:06 harman ntpd[937]: DNS: Pool taking: 176.97.192.150 Dec 25 11:39:06 harman ntpd[937]: DNS: Pool taking: 155.232.19.7 Dec 25 11:39:06 harman ntpd[937]: DNS: Pool taking: 102.64.113.152 Dec 25 11:39:06 harman ntpd[937]: DNS: Pool taking: 102.222.156.151 Dec 25 11:39:06 harman ntpd[937]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Dec 25 11:39:06 harman systemd[1]: Listening on systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Dec 25 11:39:07 harman systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status... Dec 25 11:39:07 harman wireless.js[672]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Dec 25 11:39:07 harman wireless.js[672]: WIRELESS.JS: Wired network status changed to: ---connected--- Dec 25 11:39:07 harman wireless.js[672]: WIRELESS.JS: Wireless.js initializing wireless flow Dec 25 11:39:07 harman wireless.js[672]: WIRELESS.JS: Cleaning previous... Dec 25 11:39:07 harman wireless.js[672]: WIRELESS.JS: No wireless interface, exiting Dec 25 11:39:07 harman systemd[1]: wireless.service: Killing process 1126 (sh) with signal SIGKILL. Dec 25 11:39:07 harman systemd[1]: wireless.service: Killing process 1127 (systemctl) with signal SIGKILL. Dec 25 11:39:07 harman systemd[1]: wireless.service: Failed with result 'protocol'. Dec 25 11:39:07 harman systemd[1]: Failed to start wireless.service - Wireless Services. Dec 25 11:39:07 harman systemd[1]: wireless.service: Consumed 2.555s CPU time. Dec 25 11:39:07 harman systemd[1]: Started volumio.service - Volumio Backend Module. Dec 25 11:39:08 harman systemd[1]: Started screenshot.service - Process screenshots triggered by PrtSc-button. Dec 25 11:39:08 harman systemd[1]: Started soundcard-init.service - Intel SST and HDA soundcard init service. Dec 25 11:39:08 harman systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Dec 25 11:39:08 harman volumio-cpu-tweak[1133]: Setting RT Priority for mpd Dec 25 11:39:09 harman volumio-cpu-tweak[1157]: pid 35's current scheduling policy: SCHED_OTHER Dec 25 11:39:09 harman volumio-cpu-tweak[1157]: pid 35's current scheduling priority: 0 Dec 25 11:39:09 harman volumio-cpu-tweak[1133]: Not enough cores to set MPD affinity Dec 25 11:39:09 harman volumio-cpu-tweak[1133]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Dec 25 11:39:09 harman systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Dec 25 11:39:10 harman ntpd[937]: CLOCK: time stepped by 0.338016 Dec 25 11:39:10 harman ntpd[937]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Dec 25 11:39:10 harman systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 25 11:39:11 harman volumio-remote-updater[666]: [2025-12-25 11:39:11] [info] asio async_connect error: asio.system:111 (Connection refused) Dec 25 11:39:11 harman volumio-remote-updater[666]: [2025-12-25 11:39:11] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Dec 25 11:39:11 harman volumio-remote-updater[666]: [2025-12-25 11:39:11] [error] handle_connect error: Connection refused Dec 25 11:39:12 harman winbindd[1128]: [2025/12/25 11:39:12.530298, 0] ../../source3/winbindd/winbindd.c:1440(main) Dec 25 11:39:12 harman winbindd[1128]: winbindd version 4.17.12-Debian started. Dec 25 11:39:12 harman winbindd[1128]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 25 11:39:12 harman winbindd[1128]: [2025/12/25 11:39:12.734824, 0] ../../source3/winbindd/winbindd_cache.c:3117(initialize_winbindd_cache) Dec 25 11:39:12 harman winbindd[1128]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 25 11:39:12 harman soundcard-init.sh[1190]: Card 0 Chip Realtek Generic Name HDA Intel PCH Dec 25 11:39:12 harman systemd[1]: Started winbind.service - Samba Winbind Daemon. Dec 25 11:39:12 harman systemd[1]: Starting smbd.service - Samba SMB Daemon... Dec 25 11:39:13 harman soundcard-init.sh[1205]: Simple mixer control 'Master',0 Dec 25 11:39:13 harman soundcard-init.sh[1205]: Capabilities: pvolume pswitch Dec 25 11:39:13 harman soundcard-init.sh[1205]: Playback channels: Front Left - Front Right Dec 25 11:39:13 harman soundcard-init.sh[1205]: Limits: Playback 0 - 87 Dec 25 11:39:13 harman soundcard-init.sh[1205]: Mono: Dec 25 11:39:13 harman soundcard-init.sh[1205]: Front Left: Playback 65 [75%] [-16.50dB] [on] Dec 25 11:39:13 harman soundcard-init.sh[1205]: Front Right: Playback 65 [75%] [-16.50dB] [on] Dec 25 11:39:13 harman systemd[1]: soundcard-init.service: Deactivated successfully. Dec 25 11:39:18 harman sshd[1189]: Accepted password for volumio from 192.168.1.12 port 61122 ssh2 Dec 25 11:39:18 harman sshd[1189]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0) Dec 25 11:39:18 harman smbd[1214]: [2025/12/25 11:39:18.618590, 0] ../../source3/smbd/server.c:1741(main) Dec 25 11:39:18 harman smbd[1214]: smbd version 4.17.12-Debian started. Dec 25 11:39:18 harman smbd[1214]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 25 11:39:19 harman systemd-logind[656]: New session 1 of user volumio. Dec 25 11:39:19 harman systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. Dec 25 11:39:19 harman systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Dec 25 11:39:19 harman systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Dec 25 11:39:19 harman systemd[1]: Starting user@1000.service - User Manager for UID 1000... Dec 25 11:39:19 harman (systemd)[1233]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) Dec 25 11:39:20 harman (udev-worker)[567]: hci0: Process '/usr/bin/hciconfig hci0 up' failed with exit code 1. Dec 25 11:39:20 harman systemd[1]: Starting bluetooth.service - Bluetooth service... Dec 25 11:39:20 harman systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. Dec 25 11:39:22 harman volumio-remote-updater[666]: [2025-12-25 11:39:22] [info] asio async_connect error: asio.system:111 (Connection refused) Dec 25 11:39:22 harman volumio-remote-updater[666]: [2025-12-25 11:39:22] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Dec 25 11:39:22 harman volumio-remote-updater[666]: [2025-12-25 11:39:22] [error] handle_connect error: Connection refused Dec 25 11:39:22 harman systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Dec 25 11:39:22 harman systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 25 11:39:22 harman systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 25 11:39:22 harman systemd[1233]: Queued start job for default target default.target. Dec 25 11:39:22 harman systemd[1233]: Created slice app.slice - User Application Slice. Dec 25 11:39:22 harman systemd[1233]: Reached target paths.target - Paths. Dec 25 11:39:22 harman systemd[1233]: Reached target timers.target - Timers. Dec 25 11:39:22 harman systemd[1233]: Starting dbus.socket - D-Bus User Message Bus Socket... Dec 25 11:39:22 harman systemd[1233]: Listening on dirmngr.socket - GnuPG network certificate management daemon. Dec 25 11:39:22 harman systemd[1233]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Dec 25 11:39:22 harman systemd[1233]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Dec 25 11:39:22 harman systemd[1233]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Dec 25 11:39:22 harman systemd[1233]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Dec 25 11:39:22 harman systemd[1233]: Listening on dbus.socket - D-Bus User Message Bus Socket. Dec 25 11:39:22 harman systemd[1233]: Reached target sockets.target - Sockets. Dec 25 11:39:22 harman systemd[1233]: Reached target basic.target - Basic System. Dec 25 11:39:22 harman systemd[1]: Started user@1000.service - User Manager for UID 1000. Dec 25 11:39:22 harman systemd[1]: Started session-1.scope - Session 1 of User volumio. Dec 25 11:39:22 harman systemd[1233]: Started mpris-proxy.service - Bluetooth mpris proxy. Dec 25 11:39:22 harman systemd[1233]: Reached target default.target - Main User Target. Dec 25 11:39:22 harman systemd[1233]: Startup finished in 2.794s. Dec 25 11:39:23 harman systemd[1233]: Created slice session.slice - User Core Session Slice. Dec 25 11:39:23 harman systemd[1233]: Starting dbus.service - D-Bus User Message Bus... Dec 25 11:39:23 harman systemd[1233]: Started dbus.service - D-Bus User Message Bus. Dec 25 11:39:23 harman sshd[1189]: pam_env(sshd:session): deprecated reading of user environment enabled Dec 25 11:39:24 harman upmpdcli[1252]: Could not open config: /tmp/upmpdcli.conf Dec 25 11:39:24 harman systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 25 11:39:24 harman systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 25 11:39:26 harman systemd[1]: systemd-rfkill.service: Deactivated successfully. Dec 25 11:39:26 harman bluetoothd[1249]: Bluetooth daemon 5.83 Dec 25 11:39:26 harman systemd[1]: Started bluetooth.service - Bluetooth service. Dec 25 11:39:26 harman systemd[1]: Starting bluealsa.service - BlueALSA service... Dec 25 11:39:26 harman bluetoothd[1249]: Starting SDP server Dec 25 11:39:26 harman bluetoothd[1249]: Excluding (cli) sap Dec 25 11:39:26 harman bluetoothd[1249]: Excluding (cli) midi Dec 25 11:39:26 harman bluetoothd[1249]: Excluding (cli) battery Dec 25 11:39:27 harman kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Dec 25 11:39:27 harman kernel: Bluetooth: BNEP filters: protocol multicast Dec 25 11:39:27 harman kernel: Bluetooth: BNEP socket layer initialized Dec 25 11:39:27 harman bluetoothd[1249]: Bluetooth management interface 1.23 initialized Dec 25 11:39:27 harman dbus-daemon[648]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.10' (uid=0 pid=1249 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Dec 25 11:39:27 harman systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Dec 25 11:39:31 harman dbus-daemon[648]: [system] Successfully activated service 'org.freedesktop.hostname1' Dec 25 11:39:31 harman systemd[1]: Started systemd-hostnamed.service - Hostname Service. Dec 25 11:39:32 harman volumio-remote-updater[666]: [2025-12-25 11:39:32] [info] asio async_connect error: asio.system:111 (Connection refused) Dec 25 11:39:32 harman volumio-remote-updater[666]: [2025-12-25 11:39:32] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Dec 25 11:39:32 harman volumio-remote-updater[666]: [2025-12-25 11:39:32] [error] handle_connect error: Connection refused Dec 25 11:39:32 harman bluealsa[1276]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa Dec 25 11:39:32 harman bluealsa[1276]: main.c:663: Starting main dispatching loop Dec 25 11:39:32 harman bluealsa[1276]: main.c:118: Acquired D-Bus service name: org.bluealsa Dec 25 11:39:32 harman systemd[1]: Started bluealsa.service - BlueALSA service. Dec 25 11:39:32 harman bluealsa[1276]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa Dec 25 11:39:32 harman systemd[1]: Reached target bluetooth.target - Bluetooth Support. Dec 25 11:39:32 harman systemd[1]: Started smbd.service - Samba SMB Daemon. Dec 25 11:39:34 harman systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 25 11:39:34 harman systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 25 11:39:34 harman systemd[1]: setdatetime-helper.service: Consumed 4.988s CPU time. Dec 25 11:39:34 harman systemd[1]: Reached target multi-user.target - Multi-User System. Dec 25 11:39:34 harman systemd[1]: Reached target graphical.target - Graphical Interface. Dec 25 11:39:34 harman systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Dec 25 11:39:35 harman systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Dec 25 11:39:35 harman systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Dec 25 11:39:35 harman systemd[1]: Startup finished in 5.260s (firmware) + 3.933s (loader) + 1min 8.280s (kernel) + 1min 5.426s (userspace) = 2min 22.900s. Dec 25 11:39:36 harman volumio[1129]: info: ------------------------------------------- Dec 25 11:39:36 harman volumio[1129]: info: ----- Volumio3 ---- Dec 25 11:39:36 harman volumio[1129]: info: ------------------------------------------- Dec 25 11:39:36 harman volumio[1129]: info: ----- System startup ---- Dec 25 11:39:36 harman volumio[1129]: info: ------------------------------------------- Dec 25 11:39:39 harman systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Dec 25 11:39:39 harman systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 25 11:39:39 harman systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 25 11:39:39 harman upmpdcli[1316]: Could not open config: /tmp/upmpdcli.conf Dec 25 11:39:39 harman systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 25 11:39:39 harman systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 25 11:39:40 harman volumio[1129]: info: MYVOLUMIO Environment detected Dec 25 11:39:40 harman volumio[1129]: info: Plugin folders cleanup Dec 25 11:39:40 harman volumio[1129]: info: Scanning into folder /volumio/app/plugins/ Dec 25 11:39:40 harman volumio[1129]: info: Scanning category audio_interface Dec 25 11:39:40 harman volumio[1129]: info: Scanning category miscellanea Dec 25 11:39:40 harman volumio[1129]: info: Scanning category music_service Dec 25 11:39:40 harman volumio[1129]: info: Scanning category plugins.json Dec 25 11:39:40 harman volumio[1129]: info: Scanning category system_controller Dec 25 11:39:40 harman volumio[1129]: info: Scanning category user_interface Dec 25 11:39:40 harman volumio[1129]: info: Scanning into folder /data/plugins/ Dec 25 11:39:40 harman volumio[1129]: info: Scanning category music_service Dec 25 11:39:40 harman volumio[1129]: info: Plugin folders cleanup completed Dec 25 11:39:40 harman volumio[1129]: info: ------------------------------------------- Dec 25 11:39:40 harman volumio[1129]: info: ----- Core plugins startup ---- Dec 25 11:39:40 harman volumio[1129]: info: ------------------------------------------- Dec 25 11:39:40 harman volumio[1129]: info: Loading plugins from folder /volumio/app/plugins/ Dec 25 11:39:40 harman volumio[1129]: info: Adding plugin upnp to MyMusic Plugins Dec 25 11:39:40 harman volumio[1129]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 25 11:39:40 harman volumio[1129]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 25 11:39:40 harman volumio[1129]: info: Loading plugins from folder /data/plugins/ Dec 25 11:39:40 harman volumio[1129]: info: Loading plugin "system"... Dec 25 11:39:40 harman volumio[1129]: info: Loading plugin "appearance"... Dec 25 11:39:42 harman volumio-remote-updater[666]: [2025-12-25 11:39:42] [connect] Successful connection Dec 25 11:39:44 harman volumio[1129]: info: Loading plugin "network"... Dec 25 11:39:44 harman volumio[1129]: info: Refreshing Cached IP Addresses Dec 25 11:39:45 harman volumio[1129]: info: Loading plugin "services"... Dec 25 11:39:45 harman volumio[1129]: info: Loading plugin "alsa_controller"... Dec 25 11:39:45 harman sudo[1330]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 25 11:39:45 harman sudo[1332]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 25 11:39:45 harman sudo[1330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 11:39:45 harman sudo[1332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 11:39:45 harman sudo[1339]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 25 11:39:45 harman sudo[1339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 11:39:45 harman sudo[1330]: pam_unix(sudo:session): session closed for user root Dec 25 11:39:45 harman sudo[1332]: pam_unix(sudo:session): session closed for user root Dec 25 11:39:45 harman sudo[1339]: pam_unix(sudo:session): session closed for user root Dec 25 11:39:45 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 25 11:39:45 harman volumio[1129]: info: Loading plugin "wizard"... Dec 25 11:39:45 harman volumio[1129]: info: Loading plugin "networkfs"... Dec 25 11:39:46 harman volumio[1129]: info: Starting Udev Watcher for removable devices Dec 25 11:39:46 harman volumio[1129]: info: Ignoring mount for partition: boot Dec 25 11:39:46 harman volumio[1129]: info: Ignoring mount for partition: volumio Dec 25 11:39:46 harman volumio[1129]: info: Ignoring mount for partition: volumio_data Dec 25 11:39:46 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 25 11:39:46 harman volumio[1129]: info: Loading plugin "volumio_command_line_client"... Dec 25 11:39:46 harman volumio[1129]: info: Loading plugin "upnp"... Dec 25 11:39:46 harman volumio[1129]: info: [1766659186256] Starting Upmpd Daemon Dec 25 11:39:46 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 25 11:39:46 harman volumio[1129]: info: Loading plugin "my_music"... Dec 25 11:39:46 harman volumio[1129]: info: Loading plugin "mpd"... Dec 25 11:39:47 harman volumio[1129]: info: Loading plugin "upnp_browser"... Dec 25 11:39:51 harman volumio[1129]: info: Starting UPNP Browser Dec 25 11:39:52 harman volumio[1129]: info: Loading plugin "alarm-clock"... Dec 25 11:39:52 harman volumio[1129]: info: Loading plugin "airplay_emulation"... Dec 25 11:39:52 harman volumio[1129]: info: Starting Shairport Sync Dec 25 11:39:52 harman volumio[1129]: info: Loading plugin "last_100"... Dec 25 11:39:52 harman volumio[1129]: info: Loading plugin "webradio"... Dec 25 11:39:53 harman volumio[1129]: info: Loading plugin "i2s_dacs"... Dec 25 11:39:53 harman volumio[1129]: info: I2S DAC not set, start Auto-detection Dec 25 11:39:53 harman volumio[1129]: info: Loading plugin "volumiodiscovery"... Dec 25 11:39:53 harman volumio[1129]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 25 11:39:53 harman node[1129]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 25 11:39:53 harman volumio[1129]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 25 11:39:53 harman volumio[1129]: *** WARNING *** For more information see Dec 25 11:39:53 harman volumio[1129]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 25 11:39:53 harman volumio[1129]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 25 11:39:53 harman volumio[1129]: *** WARNING *** For more information see Dec 25 11:39:53 harman node[1129]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 25 11:39:53 harman node[1129]: *** WARNING *** For more information see Dec 25 11:39:53 harman node[1129]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 25 11:39:53 harman node[1129]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 25 11:39:53 harman node[1129]: *** WARNING *** For more information see Dec 25 11:39:53 harman volumio[1129]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 25 11:39:53 harman volumio[1129]: info: Discovery: Started advertising with name: harman Dec 25 11:39:53 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 25 11:39:53 harman volumio[1129]: info: Loading plugin "spop"... Dec 25 11:39:54 harman systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Dec 25 11:39:54 harman systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 25 11:39:54 harman systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 25 11:39:55 harman upmpdcli[1378]: Could not open config: /tmp/upmpdcli.conf Dec 25 11:39:55 harman systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 25 11:39:55 harman systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 25 11:39:57 harman volumio-remote-updater[666]: [2025-12-25 11:39:57] [connect] Successful connection Dec 25 11:39:57 harman volumio[1129]: info: Loading plugin "outputs"... Dec 25 11:39:57 harman volumio[1129]: info: Loading plugin "albumart"... Dec 25 11:39:57 harman volumio[1129]: info: Plugin example_plugin is not enabled Dec 25 11:39:57 harman volumio[1129]: info: Loading plugin "inputs"... Dec 25 11:39:58 harman volumio[1129]: info: Loading plugin "updater_comm"... Dec 25 11:39:58 harman volumio[1129]: info: Plugin mpdemulation is not enabled Dec 25 11:39:58 harman volumio[1129]: info: Loading plugin "rest_api"... Dec 25 11:39:58 harman volumio[1396]: Forking 1 albumart workers Dec 25 11:39:58 harman volumio[1129]: info: Loading plugin "websocket"... Dec 25 11:39:58 harman volumio[1129]: info: Starting Socket.io Server version 1.7.4 Dec 25 11:39:59 harman volumio[1129]: info: Loading i18n strings for locale en Dec 25 11:39:59 harman volumio[1129]: Updating browse sources language Dec 25 11:39:59 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 25 11:40:00 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 11:40:00 harman volumio[1129]: info: CoreCommandRouter::initPlayerControls Dec 25 11:40:00 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:00 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:00 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:00 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:00 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:00 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:00 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:00 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:00 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 11:40:00 harman volumio[1129]: Express server listening on port 3000 Dec 25 11:40:00 harman volumio[1129]: [Metrics] WebUI: 26s 630.21ms Dec 25 11:40:00 harman volumio[1129]: info: Setting Device type: x86 Dec 25 11:40:00 harman volumio[1129]: info: CoreStateMachine::resetVolumioState Dec 25 11:40:00 harman volumio[1129]: info: CoreStateMachine::getcurrentVolume Dec 25 11:40:00 harman volumio[1129]: info: CoreCommandRouter::volumioRetrievevolume Dec 25 11:40:00 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 11:40:00 harman sudo[1421]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 25 11:40:01 harman sudo[1421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 11:40:01 harman volumio[1129]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 25 11:40:01 harman sudo[1421]: pam_unix(sudo:session): session closed for user root Dec 25 11:40:01 harman volumio[1129]: info: Completed loading Core Plugins Dec 25 11:40:01 harman sudo[1423]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 25 11:40:01 harman sudo[1423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 11:40:01 harman volumio[1129]: info: Preparing to generate the ALSA configuration file Dec 25 11:40:01 harman sudo[1423]: pam_unix(sudo:session): session closed for user root Dec 25 11:40:01 harman volumio[1129]: info: Volumio Network Manager: Network status updated: 1 Dec 25 11:40:01 harman systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 25 11:40:01 harman volumio[1129]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 25 11:40:01 harman volumio[1129]: wlan0 Interface doesn't support scanning. Dec 25 11:40:01 harman volumio[1129]: info: Cannot use regular scanning, forcing with ap-force Dec 25 11:40:01 harman sudo[1434]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 25 11:40:01 harman sudo[1434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 11:40:01 harman sudo[1434]: pam_unix(sudo:session): session closed for user root Dec 25 11:40:01 harman volumio[1129]: command failed: No such device (-19) Dec 25 11:40:01 harman volumio[1129]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Dec 25 11:40:01 harman volumio[1129]: command failed: No such device (-19) Dec 25 11:40:01 harman volumio[1129]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 25 11:40:02 harman volumio[1129]: verbose: New Socket.io Connection to 192.168.1.176 from 192.168.1.12 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 25 11:40:02 harman volumio[1129]: info: VolumeController:: Volume=49 Mute =false Dec 25 11:40:02 harman volumio[1129]: info: CoreStateMachine::pushState Dec 25 11:40:02 harman volumio[1129]: info: CorePlayQueue::getTrack 0 Dec 25 11:40:02 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 11:40:02 harman volumio[1129]: info: CoreCommandRouter::volumioPushState Dec 25 11:40:02 harman volumio[1129]: info: CoreStateMachine::updateTrackBlock Dec 25 11:40:02 harman volumio[1129]: info: CorePlayQueue::getTrackBlock Dec 25 11:40:02 harman volumio[1129]: info: CoreCommandRouter::volumioRetrievevolume Dec 25 11:40:03 harman volumio[1129]: info: Reloading queue from file Dec 25 11:40:03 harman volumio[1407]: Starting albumart workers Dec 25 11:40:03 harman volumio[1129]: verbose: New Socket.io Connection to 192.168.1.176 from 192.168.1.12 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Dec 25 11:40:03 harman volumio[1129]: info: Asound.conf file unchanged, so no further update is needed Dec 25 11:40:03 harman volumio[1129]: info: Output device has changed, restarting MPD Dec 25 11:40:03 harman volumio[1129]: info: Output device has changed, restarting Shairport Sync Dec 25 11:40:03 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:03 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 11:40:03 harman sudo[1449]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 25 11:40:03 harman sudo[1449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 11:40:03 harman sudo[1449]: pam_unix(sudo:session): session closed for user root Dec 25 11:40:03 harman sudo[1451]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 25 11:40:03 harman sudo[1451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 11:40:03 harman volumio[1129]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 25 11:40:03 harman volumio[1129]: info: ___________ START PLUGINS ___________ Dec 25 11:40:03 harman volumio[1129]: info: ControllerMpd::onStart: Initializing MPD Dec 25 11:40:03 harman volumio[1129]: info: Creating MPD Configuration file Dec 25 11:40:04 harman systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 25 11:40:04 harman systemd[1]: Starting mpd.service - Music Player Daemon... Dec 25 11:40:04 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 11:40:04 harman volumio[1129]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 25 11:40:04 harman volumio[1129]: info: [1766659204147] CoreMusicLibrary::Adding element Media Servers Dec 25 11:40:04 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 25 11:40:04 harman sudo[1459]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 25 11:40:04 harman sudo[1459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 11:40:04 harman sudo[1459]: pam_unix(sudo:session): session closed for user root Dec 25 11:40:04 harman volumio[1129]: info: UPNP Browser: Client initialized successfully Dec 25 11:40:04 harman sudo[1463]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 25 11:40:04 harman sudo[1463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 11:40:04 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:04 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 11:40:04 harman systemd[1]: mpd.service: Deactivated successfully. Dec 25 11:40:04 harman systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 25 11:40:04 harman systemd[1]: mpd.socket: Deactivated successfully. Dec 25 11:40:04 harman systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 25 11:40:04 harman systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 25 11:40:04 harman systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 25 11:40:04 harman systemd[1]: Starting mpd.service - Music Player Daemon... Dec 25 11:40:04 harman volumio[1129]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 25 11:40:04 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:04 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 11:40:05 harman volumio[1129]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 25 11:40:05 harman volumio[1129]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 25 11:40:05 harman volumio[1129]: info: [1766659205019] CoreMusicLibrary::Adding element Last_100 Dec 25 11:40:05 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 25 11:40:05 harman volumio[1129]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 25 11:40:05 harman volumio[1129]: info: [1766659205056] CoreMusicLibrary::Adding element Webradio Dec 25 11:40:05 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 25 11:40:05 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 25 11:40:05 harman volumio[1129]: info: Initializing BBC Radios Dec 25 11:40:05 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 25 11:40:05 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 11:40:05 harman volumio[1129]: info: Creating Spotify config file Dec 25 11:40:05 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:05 harman volumio[1129]: info: Volumio Calling Home Dec 25 11:40:06 harman sudo[1472]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 25 11:40:06 harman sudo[1472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 25 11:40:06 harman sudo[1509]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 25 11:40:06 harman sudo[1472]: pam_unix(sudo:session): session closed for user root Dec 25 11:40:09 harman volumio[1129]: info: CoreStateMachine::setRepeat null single undefined Dec 25 11:40:09 harman volumio[1129]: info: CoreStateMachine::pushState Dec 25 11:40:09 harman volumio[1129]: info: CorePlayQueue::getTrack 0 Dec 25 11:40:09 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 25 11:40:09 harman volumio[1129]: info: CoreCommandRouter::volumioPushState Dec 25 11:40:09 harman volumio[1129]: info: CoreStateMachine::setRandom null Dec 25 11:40:09 harman volumio[1129]: info: CoreStateMachine::pushState Dec 25 11:40:09 harman volumio[1129]: info: CorePlayQueue::getTrack 0 Dec 25 11:40:09 harman volumio[1129]: info: CoreCommandRouter::volumioPushState Dec 25 11:40:09 harman volumio[1129]: info: MPD Permissions set Dec 25 11:40:09 harman volumio[1129]: info: MPD Permissions set Dec 25 11:40:09 harman volumio[1129]: info: VolumeController:: Volume=49 Mute =false Dec 25 11:40:09 harman volumio[1129]: info: CoreStateMachine::pushState Dec 25 11:40:09 harman volumio[1129]: info: CorePlayQueue::getTrack 0 Dec 25 11:40:09 harman volumio[1129]: info: CoreCommandRouter::volumioPushState Dec 25 11:40:10 harman volumio[1129]: info: Discovery: adding 0e22ae55-f258-49fb-8a0a-968a4a5df9f1 Dec 25 11:40:10 harman volumio[1129]: info: Discovery: Found device harman Dec 25 11:40:10 harman volumio[1129]: info: CoreCommandRouter::volumioGetState Dec 25 11:40:10 harman volumio[1129]: info: CorePlayQueue::getTrack 0 Dec 25 11:40:10 harman sudo[1524]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 25 11:40:10 harman sudo[1524]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 11:40:10 harman volumio[1129]: info: Volumio called home Dec 25 11:40:10 harman volumio[1129]: info: Spotify config file written Dec 25 11:40:10 harman systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. Dec 25 11:40:10 harman systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 25 11:40:10 harman volumio[1129]: info: Discovery: this is already registered, 0e22ae55-f258-49fb-8a0a-968a4a5df9f1 Dec 25 11:40:10 harman systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 25 11:40:10 harman volumio[1129]: info: Discovery: Found device harman Dec 25 11:40:10 harman volumio[1129]: info: CoreCommandRouter::volumioGetState Dec 25 11:40:10 harman volumio[1129]: info: CorePlayQueue::getTrack 0 Dec 25 11:40:10 harman sudo[1524]: pam_unix(sudo:session): session closed for user root Dec 25 11:40:10 harman sudo[1527]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 25 11:40:10 harman sudo[1527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 11:40:10 harman systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 25 11:40:10 harman systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 25 11:40:10 harman systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 25 11:40:11 harman sudo[1527]: pam_unix(sudo:session): session closed for user root Dec 25 11:40:11 harman go-librespot[1531]: go-librespot daemon starting... Dec 25 11:40:11 harman volumio[1129]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 25 11:40:11 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:11 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:11 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:11 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:11 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:11 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:11 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:11 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:11 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 11:40:11 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 25 11:40:11 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:11 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:11 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:11 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:11 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:11 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:11 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:11 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:11 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:11 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 11:40:11 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 25 11:40:11 harman go-librespot[1532]: time="2025-12-25T11:40:11+01:00" level=info msg="running go-librespot 0.4.0" Dec 25 11:40:11 harman go-librespot[1532]: time="2025-12-25T11:40:11+01:00" level=debug msg="app state loaded" Dec 25 11:40:11 harman go-librespot[1532]: time="2025-12-25T11:40:11+01:00" level=debug msg="stored credentials not found" Dec 25 11:40:11 harman go-librespot[1532]: time="2025-12-25T11:40:11+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 25 11:40:11 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 11:40:11 harman volumio[1129]: info: No need to fix Spotify hosts Dec 25 11:40:12 harman volumio-remote-updater[666]: [2025-12-25 11:40:12] [connect] Successful connection Dec 25 11:40:12 harman volumio[1129]: info: Upmpdcli Daemon Started Dec 25 11:40:12 harman volumio-remote-updater[666]: [2025-12-25 11:40:12] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1766659212 101 Dec 25 11:40:12 harman volumio[1129]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 4 Dec 25 11:40:12 harman volumio[1129]: info: Starting Shairport Sync Dec 25 11:40:13 harman volumio[1129]: info: Starting Shairport Sync Dec 25 11:40:13 harman volumio[1129]: info: Starting Shairport Sync Dec 25 11:40:13 harman sudo[1566]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 25 11:40:13 harman sudo[1566]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 11:40:13 harman sudo[1568]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 25 11:40:13 harman sudo[1568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 11:40:13 harman sudo[1570]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 25 11:40:13 harman sudo[1570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 11:40:13 harman systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 25 11:40:13 harman systemd[1]: shairport-sync.service: Deactivated successfully. Dec 25 11:40:13 harman systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 25 11:40:13 harman systemd[1]: shairport-sync.service: Consumed 2.106s CPU time. Dec 25 11:40:13 harman systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 25 11:40:13 harman sudo[1566]: pam_unix(sudo:session): session closed for user root Dec 25 11:40:13 harman systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 25 11:40:13 harman systemd[1]: shairport-sync.service: Deactivated successfully. Dec 25 11:40:13 harman systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 25 11:40:13 harman systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 25 11:40:14 harman sudo[1568]: pam_unix(sudo:session): session closed for user root Dec 25 11:40:14 harman systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 25 11:40:14 harman systemd[1]: shairport-sync.service: Deactivated successfully. Dec 25 11:40:14 harman systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 25 11:40:14 harman systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 25 11:40:14 harman go-librespot[1532]: time="2025-12-25T11:40:14+01: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 25 11:40:14 harman sudo[1570]: pam_unix(sudo:session): session closed for user root Dec 25 11:40:14 harman go-librespot[1532]: time="2025-12-25T11:40:14+01: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 25 11:40:14 harman go-librespot[1532]: time="2025-12-25T11:40:14+01: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 25 11:40:14 harman go-librespot[1532]: time="2025-12-25T11:40:14+01:00" level=info msg="zeroconf server listening on port 44767" Dec 25 11:40:14 harman volumio[1129]: info: Shairport-Sync Started Dec 25 11:40:14 harman volumio[1129]: Error adding Membership: Error: addMembership EINVAL Dec 25 11:40:14 harman volumio[1129]: info: Shairport-Sync Started Dec 25 11:40:14 harman volumio[1129]: info: Shairport-Sync Started Dec 25 11:40:14 harman volumio[1129]: info: CoreCommandRouter::volumioGetState Dec 25 11:40:14 harman volumio[1129]: info: CorePlayQueue::getTrack 0 Dec 25 11:40:15 harman volumio[1129]: info: go-librespot daemon successfully initialized Dec 25 11:40:18 harman volumio[1129]: info: Initializing connection to go-librespot Websocket Dec 25 11:40:18 harman go-librespot[1532]: time="2025-12-25T11:40:18+01:00" level=debug msg="new websocket client" Dec 25 11:40:18 harman volumio[1129]: info: Connection to go-librespot Websocket established Dec 25 11:40:19 harman sudo[1614]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 25 11:40:19 harman sudo[1614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 11:40:19 harman sudo[1616]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 25 11:40:19 harman sudo[1614]: pam_unix(sudo:session): session closed for user root Dec 25 11:40:19 harman sudo[1616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 11:40:20 harman sudo[1616]: pam_unix(sudo:session): session closed for user root Dec 25 11:40:20 harman volumio[1129]: verbose: New Socket.io Connection to 192.168.1.176 from 192.168.1.179 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.7.2 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 5 Dec 25 11:40:21 harman volumio[1129]: info: CoreCommandRouter::volumioGetState Dec 25 11:40:21 harman volumio[1129]: info: CorePlayQueue::getTrack 0 Dec 25 11:40:21 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 25 11:40:21 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 25 11:40:21 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 25 11:40:21 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 25 11:40:21 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 25 11:40:22 harman volumio[1129]: info: Getting Spotify volume Dec 25 11:40:22 harman volumio[1129]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 25 11:40:22 harman volumio[1129]: info: CoreCommandRouter::volumioGetState Dec 25 11:40:22 harman volumio[1129]: info: CorePlayQueue::getTrack 0 Dec 25 11:40:23 harman volumio[1129]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Dec 25 11:40:23 harman volumio[1129]: SPOTIFY: SPOTIFY VOLUME undefined Dec 25 11:40:23 harman volumio[1129]: SPOTIFY: VOLUMIO VOLUME 49 Dec 25 11:40:23 harman volumio[1129]: info: Aligning Spotify Volume to Volumio Volume Dec 25 11:40:23 harman volumio[1129]: info: CoreCommandRouter::volumioGetState Dec 25 11:40:23 harman volumio[1129]: info: CorePlayQueue::getTrack 0 Dec 25 11:40:23 harman volumio[1129]: info: Setting Spotify Volume from Volumio: 49 Dec 25 11:40:23 harman volumio[1129]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 25 11:40:23 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 25 11:40:23 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 25 11:40:23 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 25 11:40:23 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 25 11:40:23 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 11:40:23 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 11:40:23 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 11:40:23 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 11:40:23 harman volumio[1129]: info: CoreCommandRouter::volumioGetBrowseSources Dec 25 11:40:23 harman volumio[1129]: info: CoreCommandRouter::volumioGetBrowseSources Dec 25 11:40:23 harman volumio[1129]: info: CoreCommandRouter::volumioGetBrowseSources Dec 25 11:40:23 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 25 11:40:23 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 25 11:40:23 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 25 11:40:23 harman volumio[1129]: info: Received Get System Info Dec 25 11:40:23 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 25 11:40:23 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 25 11:40:23 harman volumio[1129]: info: Discovery: Getting this device information Dec 25 11:40:23 harman volumio[1129]: info: CoreCommandRouter::volumioGetState Dec 25 11:40:23 harman volumio[1129]: info: CorePlayQueue::getTrack 0 Dec 25 11:40:23 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 25 11:40:24 harman volumio[1129]: SPOTIFY: SETTING SPOTIFY VOLUME 49 Dec 25 11:40:24 harman volumio[1129]: info: Sending Spotify command with payload to local API: /player/volume Dec 25 11:40:24 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 25 11:40:24 harman volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 25 11:40:24 harman volumio[1129]: verbose: ControllerMpd::sendMpdCommand rescan Dec 25 11:40:24 harman volumio[1129]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 25 11:40:24 harman volumio[1129]: TypeError: Cannot read properties of undefined (reading 'then') Dec 25 11:40:24 harman volumio[1129]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:332:6) Dec 25 11:40:24 harman volumio[1129]: at ControllerMpd.rescanDb (/volumio/app/plugins/music_service/mpd/index.js:1904:15) Dec 25 11:40:24 harman volumio[1129]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Dec 25 11:40:24 harman volumio[1129]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1098:26) Dec 25 11:40:24 harman volumio[1129]: at Socket.emit (node:events:514:28) Dec 25 11:40:24 harman volumio[1129]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 25 11:40:24 harman volumio[1129]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Dec 25 11:40:24 harman volumio[1129]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 25 11:40:27 harman sudo[1657]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-25 11:39' Dec 25 11:40:27 harman sudo[1657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 11:40:27 harman mpd[1510]: 2025-12-25T11:40:27 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"