Dec 06 03:53:08 volumio volumio[1076]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::volumioGetState Dec 06 03:53:08 volumio volumio[1076]: info: CorePlayQueue::getTrack 0 Dec 06 03:53:08 volumio volumio[1076]: info: Restoring Previous Volume level: 100 false false Dec 06 03:53:08 volumio volumio[1076]: info: Output device has changed, restarting MPD Dec 06 03:53:08 volumio sudo[4329]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 06 03:53:08 volumio sudo[4329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 03:53:08 volumio volumio[1076]: info: Output device has changed, restarting Shairport Sync Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 03:53:08 volumio sudo[4329]: pam_unix(sudo:session): session closed for user root Dec 06 03:53:08 volumio sudo[4331]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 06 03:53:08 volumio sudo[4331]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 03:53:08 volumio volumio[1076]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 06 03:53:08 volumio volumio[1076]: info: Volume configurations have been set Dec 06 03:53:08 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 06 03:53:08 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 06 03:53:08 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 06 03:53:08 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 06 03:53:08 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 06 03:53:08 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 06 03:53:08 volumio volumio[1076]: info: Updating Volume Controller Parameters: Device: 5 Name: E30 II Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 06 03:53:08 volumio volumio[1076]: info: Disabling external Volume Control Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 03:53:08 volumio volumio[1076]: info: CoreStateMachine::pushState Dec 06 03:53:08 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 06 03:53:08 volumio volumio[1076]: info: CorePlayQueue::getTrack 0 Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 06 03:53:08 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 06 03:53:08 volumio volumio[1076]: info: MPD Permissions set Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 03:53:08 volumio sudo[4345]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 06 03:53:08 volumio sudo[4345]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 06 03:53:08 volumio sudo[4345]: pam_unix(sudo:session): session closed for user root Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 06 03:53:08 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 06 03:53:08 volumio volumio[1076]: info: Starting Shairport Sync Dec 06 03:53:08 volumio sudo[4364]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 06 03:53:08 volumio sudo[4364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 03:53:08 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 06 03:53:08 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 06 03:53:08 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 03:53:08 volumio systemd[1]: shairport-sync.service: Consumed 1.957s CPU time. Dec 06 03:53:08 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 03:53:08 volumio sudo[4364]: pam_unix(sudo:session): session closed for user root Dec 06 03:53:08 volumio volumio[1076]: info: Shairport-Sync Started Dec 06 03:53:08 volumio mpd[4356]: 2025-12-06T03:53:08 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 06 03:53:08 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 06 03:53:08 volumio sudo[4331]: pam_unix(sudo:session): session closed for user root Dec 06 03:53:08 volumio volumio[1076]: error: updateQueue error: null Dec 06 03:53:11 volumio sudo[4388]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 06 03:53:11 volumio sudo[4388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 03:53:11 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 06 03:53:11 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 06 03:53:11 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 06 03:53:11 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 06 03:53:11 volumio sudo[4388]: pam_unix(sudo:session): session closed for user root Dec 06 03:53:11 volumio mpd_monitor.sh[4390]: MPD Monitor Service: Starting MPD Monitor Service Dec 06 03:53:11 volumio volumio[1076]: info: Successfully started MPD Monitor Dec 06 03:53:12 volumio volumio[1076]: info: VolumeController::SetAlsaVolume100 Dec 06 03:53:12 volumio volumio[1076]: info: CoreStateMachine::pushState Dec 06 03:53:12 volumio volumio[1076]: info: CorePlayQueue::getTrack 0 Dec 06 03:53:12 volumio volumio[1076]: info: CoreCommandRouter::volumioPushState Dec 06 03:53:23 volumio volumio[1076]: info: CoreCommandRouter::volumioGetState Dec 06 03:53:23 volumio volumio[1076]: info: CorePlayQueue::getTrack 0 Dec 06 03:53:26 volumio volumio[1076]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 03:53:26 volumio volumio[1076]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 03:53:26 volumio volumio[1076]: info: Retrieving Cloud Streaming UI Dec 06 03:53:26 volumio volumio[1076]: info: Getting Tidal Cloud Configuration Dec 06 03:53:26 volumio volumio[1076]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 03:53:26 volumio volumio[1076]: info: Getting Qobuz Cloud Configuration Dec 06 03:53:26 volumio volumio[1076]: info: Asking plugin for UI Config Dec 06 03:53:26 volumio volumio[1076]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 03:53:26 volumio volumio[1076]: info: Getting Spotify Cloud Configuration Dec 06 03:53:26 volumio volumio[1076]: info: Asking plugin for UI Config Dec 06 03:53:26 volumio volumio[1076]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 03:53:26 volumio volumio[1076]: info: Saving Spotify Acccount Dec 06 03:53:26 volumio volumio[1076]: info: Got Tidal Cloud Configuration Dec 06 03:53:26 volumio volumio[1076]: info: Got it Dec 06 03:53:26 volumio volumio[1076]: info: Got it Dec 06 03:53:26 volumio volumio[1076]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 06 03:53:26 volumio volumio[1076]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 03:53:26 volumio volumio[1076]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 06 03:53:26 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 06 03:53:26 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 06 03:53:26 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 06 03:53:26 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 06 03:53:26 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 03:53:26 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 03:53:26 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 03:53:26 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 03:53:26 volumio volumio[1076]: info: CoreCommandRouter::volumioGetBrowseSources Dec 06 03:53:26 volumio volumio[1076]: info: CoreCommandRouter::volumioGetBrowseSources Dec 06 03:53:26 volumio volumio[1076]: info: CoreCommandRouter::volumioGetBrowseSources Dec 06 03:53:26 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 03:53:26 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 06 03:53:30 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 03:53:34 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 03:53:37 volumio volumio[1076]: info: Disabling MyMusic plugin upnp Dec 06 03:53:37 volumio sudo[4453]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Dec 06 03:53:37 volumio sudo[4453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 03:53:37 volumio systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Dec 06 03:53:38 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 03:53:42 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 03:53:46 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 03:53:50 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 03:53:54 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 03:53:58 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 03:53:59 volumio volumio[1076]: info: Disabling MyMusic plugin airplay_emulation Dec 06 03:53:59 volumio volumio[1076]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync Dec 06 03:53:59 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 03:53:59 volumio volumio[1076]: info: Disabling plugin airplay_emulation Dec 06 03:53:59 volumio volumio[1076]: info: Done. Dec 06 03:53:59 volumio sudo[4524]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Dec 06 03:53:59 volumio sudo[4524]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 03:53:59 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 06 03:53:59 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 06 03:53:59 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 03:53:59 volumio systemd[1]: shairport-sync.service: Consumed 1.406s CPU time. Dec 06 03:53:59 volumio sudo[4524]: pam_unix(sudo:session): session closed for user root Dec 06 03:53:59 volumio volumio[1076]: info: Shairport-Sync Stopped Dec 06 03:54:02 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 03:54:03 volumio volumio[1076]: info: Disabling MyMusic plugin upnp Dec 06 03:54:03 volumio sudo[4531]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Dec 06 03:54:03 volumio sudo[4531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 03:54:06 volumio volumio[1076]: info: Disabling MyMusic plugin upnp_browser Dec 06 03:54:06 volumio volumio[1076]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesServer multimediali Dec 06 03:54:06 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 03:54:06 volumio volumio[1076]: info: Disabling plugin upnp_browser Dec 06 03:54:06 volumio volumio[1076]: info: Done. Dec 06 03:54:06 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 03:54:09 volumio volumio[1076]: info: Disabling MyMusic plugin upnp Dec 06 03:54:09 volumio sudo[4554]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Dec 06 03:54:09 volumio sudo[4554]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 03:54:10 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 03:54:14 volumio volumio[1076]: info: Enabling MyMusic plugin upnp Dec 06 03:54:14 volumio volumio[1076]: info: Enabling plugin upnp Dec 06 03:54:14 volumio volumio[1076]: info: Loading plugin "upnp"... Dec 06 03:54:14 volumio volumio[1076]: info: [1764989654584] Starting Upmpd Daemon Dec 06 03:54:14 volumio volumio[1076]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 06 03:54:14 volumio volumio[1076]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 03:54:14 volumio volumio[1076]: Error: listen EADDRINUSE: address already in use :::6599 Dec 06 03:54:14 volumio volumio[1076]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Dec 06 03:54:14 volumio volumio[1076]: at listenInCluster (node:net:1920:12) Dec 06 03:54:14 volumio volumio[1076]: at Server.listen (node:net:2008:7) Dec 06 03:54:14 volumio volumio[1076]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Dec 06 03:54:14 volumio volumio[1076]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Dec 06 03:54:14 volumio volumio[1076]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Dec 06 03:54:14 volumio volumio[1076]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 06 03:54:14 volumio volumio[1076]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Dec 06 03:54:14 volumio volumio[1076]: code: 'EADDRINUSE', Dec 06 03:54:14 volumio volumio[1076]: errno: -98, Dec 06 03:54:14 volumio volumio[1076]: syscall: 'listen', Dec 06 03:54:14 volumio volumio[1076]: address: '::', Dec 06 03:54:14 volumio volumio[1076]: port: 6599 Dec 06 03:54:14 volumio volumio[1076]: } Dec 06 03:54:14 volumio volumio[1076]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 03:54:14 volumio sudo[4578]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-06 03:53' Dec 06 03:54:14 volumio sudo[4578]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 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="3a3cfe435f8bb2bc94a920662e91a4ef35bc8e7c" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Dec 2 12:15:37 UTC 2025" VOLUMIO_VERSION="4.072" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="3ca59a0b3ec3a926fd3274a2fb539106"