Dec 22 18:55:51 volumio ntpd[918]: CLOCK: time stepped by 1457702.780259 Dec 22 18:55:51 volumio ntpd[918]: CLOCK: time changed from 2025-12-05 to 2025-12-22 Dec 22 18:55:51 volumio ntpd[918]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 22 18:55:51 volumio systemd[1]: Starting apt-daily-upgrade.service - Daily apt upgrade and clean activities... Dec 22 18:55:51 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Dec 22 18:55:51 volumio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Dec 22 18:55:51 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Dec 22 18:55:51 volumio systemd[1]: e2scrub_all.service: Deactivated successfully. Dec 22 18:55:51 volumio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Dec 22 18:55:51 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Dec 22 18:55:51 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Dec 22 18:55:51 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Dec 22 18:55:52 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 22 18:55:52 volumio systemd[1]: apt-daily-upgrade.service: Deactivated successfully. Dec 22 18:55:52 volumio systemd[1]: Finished apt-daily-upgrade.service - Daily apt upgrade and clean activities. Dec 22 18:55:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 22 18:55:53 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 18:55:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 22 18:55:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 22 18:55:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 22 18:55:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Dec 22 18:55:53 volumio volumio[1135]: info: Received Get System Version Dec 22 18:55:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 18:55:54 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 22 18:55:55 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 22 18:55:56 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 22 18:55:57 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 22 18:55:58 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 22 18:55:59 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 22 18:56:00 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 22 18:56:01 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 22 18:56:02 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 22 18:56:03 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 22 18:56:04 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 22 18:56:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 22 18:56:06 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 22 18:56:07 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 22 18:56:07 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard Dec 22 18:56:07 volumio volumio[1135]: info: CoreCommandRouter::Reload Ui Dec 22 18:56:08 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 22 18:56:09 volumio sudo[4720]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 22 18:56:09 volumio sudo[4720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 18:56:09 volumio sudo[4720]: pam_unix(sudo:session): session closed for user root Dec 22 18:56:09 volumio sudo[4722]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 22 18:56:09 volumio sudo[4722]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 18:56:09 volumio sudo[4722]: pam_unix(sudo:session): session closed for user root Dec 22 18:56:10 volumio volumio[1135]: verbose: New Socket.io Connection to 192.168.1.232 from 192.168.1.23 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Dec 22 18:56:10 volumio volumio[1135]: info: CoreCommandRouter::volumioGetVisibleSources Dec 22 18:56:10 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 18:56:10 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Dec 22 18:56:10 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Dec 22 18:56:10 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 22 18:56:10 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Dec 22 18:56:10 volumio volumio[1135]: info: Received Get System Info Dec 22 18:56:10 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 18:56:10 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 18:56:10 volumio volumio[1135]: info: Discovery: Getting this device information Dec 22 18:56:10 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Dec 22 18:56:10 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 18:56:10 volumio volumio-remote-updater[2922]: Test mode disabled Dec 22 18:56:10 volumio volumio-remote-updater[2922]: Alpha mode disabled Dec 22 18:56:10 volumio volumio-remote-updater[2922]: Alpha legacy test mode disabled Dec 22 18:56:10 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 22 18:56:10 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 22 18:56:10 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 18:56:10 volumio volumio[1135]: info: Retrieving Cloud Streaming UI Dec 22 18:56:10 volumio volumio[1135]: info: Getting Tidal Cloud Configuration Dec 22 18:56:10 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 18:56:10 volumio volumio[1135]: info: Getting Qobuz Cloud Configuration Dec 22 18:56:10 volumio volumio[1135]: info: Asking plugin for UI Config Dec 22 18:56:10 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 18:56:10 volumio volumio[1135]: info: Getting Spotify Cloud Configuration Dec 22 18:56:10 volumio volumio[1135]: info: Asking plugin for UI Config Dec 22 18:56:10 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 18:56:10 volumio volumio[1135]: info: Saving Spotify Acccount Dec 22 18:56:10 volumio volumio[1135]: info: Got Tidal Cloud Configuration Dec 22 18:56:10 volumio volumio[1135]: info: Got it Dec 22 18:56:10 volumio volumio[1135]: info: Got it Dec 22 18:56:10 volumio volumio[1135]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 22 18:56:10 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 18:56:10 volumio volumio[1135]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 22 18:56:10 volumio volumio[1135]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 22 18:56:10 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 22 18:56:11 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 18:56:11 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 22 18:56:11 volumio volumio[1135]: info: Loading i18n strings for locale cs Dec 22 18:56:11 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 22 18:56:11 volumio volumio[1135]: info: Setting timezone to Europe/Prague Dec 22 18:56:11 volumio sudo[4739]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 22 18:56:11 volumio sudo[4739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 18:56:11 volumio sudo[4739]: pam_unix(sudo:session): session closed for user root Dec 22 18:56:11 volumio sudo[4744]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Prague /etc/localtime Dec 22 18:56:11 volumio sudo[4744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 18:56:11 volumio sudo[4744]: pam_unix(sudo:session): session closed for user root Dec 22 18:56:11 volumio sudo[4750]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 22 18:56:11 volumio sudo[4750]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 18:56:11 volumio sudo[4750]: pam_unix(sudo:session): session closed for user root Dec 22 18:56:11 volumio systemd[1]: Starting fstrim.service - Discard unused blocks on filesystems from /etc/fstab... Dec 22 18:56:11 volumio sudo[4756]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Prague Dec 22 18:56:11 volumio sudo[4756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 18:56:11 volumio dbus-daemon[718]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.36' (uid=0 pid=4757 comm="/usr/bin/timedatectl set-timezone Europe/Prague") Dec 22 18:56:12 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Dec 22 18:56:12 volumio fstrim[4743]: /boot: 273.6 MiB (286879744 bytes) trimmed on /dev/mmcblk0p1 Dec 22 18:56:12 volumio systemd[1]: fstrim.service: Deactivated successfully. Dec 22 18:56:12 volumio systemd[1]: Finished fstrim.service - Discard unused blocks on filesystems from /etc/fstab. Dec 22 18:56:12 volumio dbus-daemon[718]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 22 18:56:12 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Dec 22 18:56:12 volumio sudo[4756]: pam_unix(sudo:session): session closed for user root Dec 22 18:56:12 volumio volumio[1135]: info: Fetching Streaming Services browse cache Dec 22 18:56:12 volumio volumio[1135]: Updating browse sources language Dec 22 18:56:12 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 18:56:18 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 22 18:56:18 volumio volumio[1135]: info: Loading i18n strings for locale cs Dec 22 18:56:18 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Dec 22 18:56:18 volumio volumio[1135]: info: Setting timezone to Europe/Prague Dec 22 18:56:18 volumio sudo[4788]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 22 18:56:18 volumio sudo[4788]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 18:56:18 volumio sudo[4788]: pam_unix(sudo:session): session closed for user root Dec 22 18:56:18 volumio sudo[4792]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Prague /etc/localtime Dec 22 18:56:18 volumio sudo[4792]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 18:56:18 volumio sudo[4792]: pam_unix(sudo:session): session closed for user root Dec 22 18:56:18 volumio sudo[4796]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 22 18:56:18 volumio sudo[4796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 18:56:18 volumio sudo[4796]: pam_unix(sudo:session): session closed for user root Dec 22 18:56:18 volumio sudo[4801]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Prague Dec 22 18:56:18 volumio sudo[4801]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 18:56:18 volumio sudo[4801]: pam_unix(sudo:session): session closed for user root Dec 22 18:56:18 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 22 18:56:18 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 22 18:56:18 volumio sudo[4804]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 22 18:56:18 volumio sudo[4804]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 18:56:18 volumio volumio[1135]: Updating browse sources language Dec 22 18:56:18 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 18:56:19 volumio volumio[1135]: info: Fetching Streaming Services browse cache Dec 22 18:56:19 volumio sudo[4804]: pam_unix(sudo:session): session closed for user root Dec 22 18:56:25 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Dec 22 18:56:26 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Dec 22 18:56:26 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 22 18:56:26 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 18:56:26 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 22 18:56:27 volumio volumio[1135]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 22 18:56:27 volumio volumio[1135]: info: Received Get System Version Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 18:56:27 volumio volumio[1135]: info: Received Get System Info Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 18:56:27 volumio volumio[1135]: info: Discovery: Getting this device information Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 22 18:56:27 volumio volumio[1135]: info: Preparing to save Alsa Options, stopping services first Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::volumioStop Dec 22 18:56:27 volumio volumio[1135]: info: CoreStateMachine::stop Dec 22 18:56:27 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 22 18:56:27 volumio volumio[1135]: info: CoreStateMachine::stPlaybackTimer Dec 22 18:56:27 volumio volumio[1135]: info: CoreStateMachine::updateTrackBlock Dec 22 18:56:27 volumio volumio[1135]: info: CorePlayQueue::getTrackBlock Dec 22 18:56:27 volumio volumio[1135]: info: CoreStateMachine::pushState Dec 22 18:56:27 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Dec 22 18:56:27 volumio volumio[1135]: info: CoreStateMachine::serviceStop Dec 22 18:56:27 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::serviceStop Dec 22 18:56:27 volumio volumio[1135]: info: [1766426187724] ControllerWebradio::stop Dec 22 18:56:27 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand stop Dec 22 18:56:27 volumio volumio[1135]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"1","label":"Headphones"},"disallowPush":true} Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 22 18:56:27 volumio volumio[1135]: info: Found match in Cards Database: setting mixer PCM for card Headphones Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 22 18:56:27 volumio volumio[1135]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 22 18:56:27 volumio volumio[1135]: info: Disabling external Volume Control Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 18:56:27 volumio volumio[1135]: info: Preparing to generate the ALSA configuration file Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 18:56:27 volumio volumio[1135]: info: Retrieving Cloud Streaming UI Dec 22 18:56:27 volumio volumio[1135]: info: Dec 22 18:56:27 volumio volumio[1135]: ---------------------------- MPD announces state update: player Dec 22 18:56:27 volumio volumio[1135]: info: sendMpdCommand stop took 151 milliseconds Dec 22 18:56:27 volumio volumio[1135]: info: ControllerMpd::getState Dec 22 18:56:27 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Dec 22 18:56:27 volumio volumio[1135]: info: Dec 22 18:56:27 volumio volumio[1135]: ---------------------------- MPD announces state update: player Dec 22 18:56:27 volumio volumio[1135]: info: ControllerMpd::getState Dec 22 18:56:27 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Dec 22 18:56:27 volumio volumio[1135]: info: sendMpdCommand status took 17 milliseconds Dec 22 18:56:27 volumio volumio[1135]: info: sendMpdCommand status took 16 milliseconds Dec 22 18:56:27 volumio volumio[1135]: verbose: ControllerMpd::parseState Dec 22 18:56:27 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 18:56:27 volumio volumio[1135]: verbose: ControllerMpd::parseState Dec 22 18:56:27 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 22 18:56:27 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 22 18:56:27 volumio volumio[1135]: info: Asound.conf file unchanged, so no further update is needed Dec 22 18:56:27 volumio volumio[1135]: info: Output device has changed, restarting MPD Dec 22 18:56:28 volumio volumio[1135]: info: Output device has changed, restarting Shairport Sync Dec 22 18:56:28 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 18:56:28 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 18:56:28 volumio sudo[4864]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 18:56:28 volumio sudo[4864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 18:56:28 volumio sudo[4864]: pam_unix(sudo:session): session closed for user root Dec 22 18:56:28 volumio sudo[4866]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 18:56:28 volumio sudo[4866]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 18:56:28 volumio volumio[1135]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 18:56:28 volumio volumio[1135]: info: Getting Tidal Cloud Configuration Dec 22 18:56:28 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 18:56:28 volumio volumio[1135]: info: Getting Qobuz Cloud Configuration Dec 22 18:56:28 volumio volumio[1135]: info: Asking plugin for UI Config Dec 22 18:56:28 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 18:56:28 volumio volumio[1135]: info: Getting Spotify Cloud Configuration Dec 22 18:56:28 volumio volumio[1135]: info: Asking plugin for UI Config Dec 22 18:56:28 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 18:56:28 volumio volumio[1135]: info: Saving Spotify Acccount Dec 22 18:56:28 volumio volumio[1135]: info: Got Tidal Cloud Configuration Dec 22 18:56:28 volumio volumio[1135]: info: Got it Dec 22 18:56:28 volumio volumio[1135]: info: Got it Dec 22 18:56:28 volumio volumio[1135]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 22 18:56:28 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 176 milliseconds Dec 22 18:56:28 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 176 milliseconds Dec 22 18:56:28 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Dec 22 18:56:28 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Dec 22 18:56:28 volumio volumio[1135]: info: ControllerMpd::pushState Dec 22 18:56:28 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Dec 22 18:56:28 volumio volumio[1135]: info: CoreStateMachine::pushState Dec 22 18:56:28 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 22 18:56:28 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 18:56:28 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Dec 22 18:56:28 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 22 18:56:28 volumio volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 22 18:56:28 volumio volumio[1135]: info: ControllerMpd::pushState Dec 22 18:56:28 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Dec 22 18:56:28 volumio volumio[1135]: info: CoreStateMachine::pushState Dec 22 18:56:28 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 22 18:56:28 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Dec 22 18:56:28 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 22 18:56:28 volumio volumio[1135]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 22 18:56:28 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 22 18:56:28 volumio volumio[1135]: info: ------------------------------ 227ms Dec 22 18:56:28 volumio volumio[1135]: info: ------------------------------ 225ms Dec 22 18:56:28 volumio volumio[1135]: info: MPD Permissions set Dec 22 18:56:28 volumio volumio[1135]: info: VolumeController:: Volume=86 Mute =false Dec 22 18:56:28 volumio volumio[1135]: info: CoreStateMachine::pushState Dec 22 18:56:28 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 22 18:56:28 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Dec 22 18:56:28 volumio volumio[1135]: error: Upnp client error: Error: This socket has been ended by the other party Dec 22 18:56:28 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 22 18:56:28 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 22 18:56:28 volumio systemd[1]: mpd.service: Consumed 5.154s CPU time. Dec 22 18:56:28 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 22 18:56:28 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 18:56:28 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 18:56:28 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 18:56:28 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 22 18:56:28 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 22 18:56:28 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 18:56:28 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 18:56:28 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 18:56:28 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 18:56:28 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 18:56:28 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 18:56:28 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 18:56:28 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 18:56:28 volumio volumio[1135]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 22 18:56:28 volumio volumio[1135]: info: Starting Shairport Sync Dec 22 18:56:28 volumio sudo[4882]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 18:56:28 volumio sudo[4882]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 18:56:28 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 22 18:56:28 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 22 18:56:28 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 22 18:56:28 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 22 18:56:28 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 18:56:28 volumio systemd[1]: shairport-sync.service: Consumed 3.182s CPU time. Dec 22 18:56:28 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 18:56:28 volumio sudo[4882]: pam_unix(sudo:session): session closed for user root Dec 22 18:56:28 volumio volumio[1135]: info: Shairport-Sync Started Dec 22 18:56:28 volumio sudo[4884]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 22 18:56:28 volumio sudo[4884]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 18:56:28 volumio sudo[4884]: pam_unix(sudo:session): session closed for user root Dec 22 18:56:31 volumio mpd[4901]: 2025-12-22T18:56:31 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 22 18:56:31 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 22 18:56:31 volumio sudo[4866]: pam_unix(sudo:session): session closed for user root Dec 22 18:56:31 volumio volumio[1135]: error: updateQueue error: null Dec 22 18:56:31 volumio volumio[1135]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 22 18:56:31 volumio volumio[1135]: info: CoreStateMachine::getcurrentVolume Dec 22 18:56:31 volumio volumio[1135]: info: CoreCommandRouter::volumioRetrievevolume Dec 22 18:56:31 volumio volumio[1135]: info: VolumeController:: Volume=86 Mute =false Dec 22 18:56:31 volumio volumio[1135]: info: CoreStateMachine::pushState Dec 22 18:56:31 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 22 18:56:31 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Dec 22 18:56:31 volumio volumio[1135]: info: CoreStateMachine::updateTrackBlock Dec 22 18:56:31 volumio volumio[1135]: info: CorePlayQueue::getTrackBlock Dec 22 18:56:31 volumio volumio[1135]: info: CoreCommandRouter::volumioRetrievevolume Dec 22 18:56:31 volumio volumio[1135]: info: VolumeController:: Volume=86 Mute =false Dec 22 18:56:31 volumio volumio[1135]: info: CoreStateMachine::pushState Dec 22 18:56:31 volumio volumio[1135]: info: CorePlayQueue::getTrack 0 Dec 22 18:56:31 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Dec 22 18:56:34 volumio sudo[4926]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 22 18:56:34 volumio sudo[4926]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 18:56:34 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 22 18:56:34 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 22 18:56:34 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 22 18:56:34 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 22 18:56:34 volumio sudo[4926]: pam_unix(sudo:session): session closed for user root Dec 22 18:56:34 volumio mpd_monitor.sh[4928]: MPD Monitor Service: Starting MPD Monitor Service Dec 22 18:56:34 volumio volumio[1135]: info: Successfully started MPD Monitor Dec 22 18:56:35 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Dec 22 18:56:35 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Dec 22 18:56:35 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Dec 22 18:56:35 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Dec 22 18:56:35 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Dec 22 18:56:37 volumio volumio[1135]: info: READ Dec 22 18:56:37 volumio volumio[1135]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 18:56:37 volumio volumio[1135]: Error Dec 22 18:56:37 volumio volumio[1135]: at /volumio/app/plugins/music_service/webradio/index.js:286:31 Dec 22 18:56:37 volumio volumio[1135]: at Request.handleRequestResponse [as _callback] (/volumio/node_modules/unirest/index.js:444:15) Dec 22 18:56:37 volumio volumio[1135]: at self.callback (/volumio/node_modules/request/request.js:185:22) Dec 22 18:56:37 volumio volumio[1135]: at Request.emit (node:events:514:28) Dec 22 18:56:37 volumio volumio[1135]: at Request.onRequestError (/volumio/node_modules/request/request.js:877:8) Dec 22 18:56:37 volumio volumio[1135]: at ClientRequest.emit (node:events:514:28) Dec 22 18:56:37 volumio volumio[1135]: at Socket.socketErrorListener (node:_http_client:495:9) Dec 22 18:56:37 volumio volumio[1135]: at Socket.emit (node:events:514:28) Dec 22 18:56:37 volumio volumio[1135]: at emitErrorNT (node:internal/streams/destroy:151:8) Dec 22 18:56:37 volumio volumio[1135]: at emitErrorCloseNT (node:internal/streams/destroy:116:3) Dec 22 18:56:37 volumio volumio[1135]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) Dec 22 18:56:37 volumio volumio[1135]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 18:56:38 volumio sudo[4958]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-22 18:55' Dec 22 18:56:38 volumio sudo[4958]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"