Oct 14 16:48:52 harmonyv4 volumio[1341]: info: CoreCommandRouter::volumioPause Oct 14 16:48:52 harmonyv4 volumio[1341]: info: CoreStateMachine::pause Oct 14 16:48:52 harmonyv4 volumio[1341]: info: CoreStateMachine::stPlaybackTimer Oct 14 16:48:52 harmonyv4 volumio[1341]: info: CoreStateMachine::servicePause Oct 14 16:48:52 harmonyv4 volumio[1341]: info: CorePlayQueue::getTrack 1 Oct 14 16:48:52 harmonyv4 volumio[1341]: info: CoreCommandRouter::servicePause Oct 14 16:48:52 harmonyv4 volumio[1341]: info: [1791985732051] ControllerTidal::pause Oct 14 16:48:52 harmonyv4 volumio[1341]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 14 16:48:52 harmonyv4 volumio[1341]: info: ControllerMpd::pause Oct 14 16:48:52 harmonyv4 volumio[1341]: verbose: ControllerMpd::sendMpdCommand pause Oct 14 16:48:52 harmonyv4 volumio[1341]: info: Oct 14 16:48:52 harmonyv4 volumio[1341]: ---------------------------- MPD announces state update: player Oct 14 16:48:52 harmonyv4 volumio[1341]: info: sendMpdCommand pause took 1 milliseconds Oct 14 16:48:52 harmonyv4 volumio[1341]: info: ControllerMpd::getState Oct 14 16:48:52 harmonyv4 volumio[1341]: verbose: ControllerMpd::sendMpdCommand status Oct 14 16:48:52 harmonyv4 volumio[1341]: info: sendMpdCommand status took 1 milliseconds Oct 14 16:48:52 harmonyv4 volumio[1341]: verbose: ControllerMpd::parseState Oct 14 16:48:52 harmonyv4 volumio[1341]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 16:48:52 harmonyv4 volumio[1341]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 14 16:48:52 harmonyv4 volumio[1341]: verbose: ControllerMpd::parseTrackInfo Oct 14 16:48:52 harmonyv4 volumio[1341]: info: ControllerMpd::pushState Oct 14 16:48:52 harmonyv4 volumio[1341]: info: CoreCommandRouter::servicePushState Oct 14 16:48:52 harmonyv4 volumio[1341]: info: CorePlayQueue::getTrack 1 Oct 14 16:48:52 harmonyv4 volumio[1341]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":68985,"duration":267,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1934 Kbps","isStreaming":false,"title":"0.flac?token=1791989263~MDI5ZWYzMzU2Yzg2NmRlM2YzMjQ5MTYxYTQxMzY4Y2YxYWNlNWRhOQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEicxNTU3N2M4YjNkNDc3NDY0M2ExZDlmNjMwZDg1NGZjYl82Mi5tcDQ/0.flac?token=1791989263~MDI5ZWYzMzU2Yzg2NmRlM2YzMjQ5MTYxYTQxMzY4Y2YxYWNlNWRhOQ==","trackType":"tidal"} Oct 14 16:48:52 harmonyv4 volumio[1341]: verbose: CURRENT POSITION 1 Oct 14 16:48:52 harmonyv4 volumio[1341]: info: CoreStateMachine::syncState stateService pause Oct 14 16:48:52 harmonyv4 volumio[1341]: info: CoreStateMachine::syncState currentStatus pause Oct 14 16:48:52 harmonyv4 volumio[1341]: info: CoreStateMachine::pushState Oct 14 16:48:52 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 16:48:52 harmonyv4 volumio[1341]: info: CoreCommandRouter::volumioPushState Oct 14 16:48:52 harmonyv4 volumio[1341]: info: CoreStateMachine::stPlaybackTimer Oct 14 16:48:52 harmonyv4 volumio[1341]: info: ------------------------------ 8ms Oct 14 16:48:52 harmonyv4 volumio5-onboarding[1545]: time=2026-10-14T16:48:52.060+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.124:57456 @ 0x1c365d0" state=STATUS_PAUSED positionMs=68071 volume=100 Oct 14 16:48:52 harmonyv4 volumio5-onboarding[1545]: time=2026-10-14T16:48:52.061+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.124:57456 @ 0x1c365d0" id=tidal://song/226215895 title=Jolene Oct 14 16:48:54 harmonyv4 volumio[1341]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 14 16:48:54 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 14 16:48:54 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 16:48:54 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 14 16:48:54 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 16:48:54 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 16:48:54 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 16:48:54 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 16:48:54 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 16:48:54 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 16:48:54 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 16:48:54 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 14 16:48:54 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 14 16:49:04 harmonyv4 volumio[1341]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Oct 14 16:49:04 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Oct 14 16:49:04 harmonyv4 volumio[1341]: info: CoreCommandRouter::volumioGetState Oct 14 16:49:04 harmonyv4 volumio[1341]: info: Restoring Previous Volume level: 100 false false Oct 14 16:49:04 harmonyv4 volumio[1341]: info: Output device has changed, restarting MPD Oct 14 16:49:04 harmonyv4 volumio[1341]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 14 16:49:04 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:49:04 harmonyv4 sudo[2912]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 14 16:49:04 harmonyv4 sudo[2912]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:49:04 harmonyv4 sudo[2912]: pam_unix(sudo:session): session closed for user root Oct 14 16:49:04 harmonyv4 sudo[2914]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 14 16:49:04 harmonyv4 sudo[2914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:49:04 harmonyv4 vtcs[2343]: [2026-10-14 16:49:04.979] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 14 16:49:04 harmonyv4 vtcs[2343]: [2026-10-14 16:49:04.980] [tisoc] [error] [SpkconServer.cpp:383] recv error. client fd=8 errorno=104 error=Connection reset by peer Oct 14 16:49:04 harmonyv4 vtcs[2343]: [2026-10-14 16:49:04.980] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 14 16:49:04 harmonyv4 volumio[1341]: info: Volume configurations have been set Oct 14 16:49:04 harmonyv4 sudo[2920]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 16:49:04 harmonyv4 systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 14 16:49:04 harmonyv4 sudo[2920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:49:04 harmonyv4 sudo[2922]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 16:49:04 harmonyv4 sudo[2922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:49:05 harmonyv4 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 14 16:49:05 harmonyv4 systemd[1]: vtcs.service: Killing process 2358 (vtcs) with signal SIGKILL. Oct 14 16:49:05 harmonyv4 systemd[1]: vtcs.service: Deactivated successfully. Oct 14 16:49:05 harmonyv4 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 14 16:49:05 harmonyv4 volumio[1341]: info: Updating Volume Controller Parameters: Device: 5 Name: Gustard USB Audio 2.0 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 14 16:49:05 harmonyv4 volumio[1341]: info: Disabling external Volume Control Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreStateMachine::pushState Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 16:49:05 harmonyv4 sudo[2920]: pam_unix(sudo:session): session closed for user root Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::volumioPushState Oct 14 16:49:05 harmonyv4 systemd[1]: mpd.service: Deactivated successfully. Oct 14 16:49:05 harmonyv4 volumio5-onboarding[1545]: time=2026-10-14T16:49:05.027+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.124:57456 @ 0x1c365d0" state=STATUS_PAUSED positionMs=68322 volume=100 Oct 14 16:49:05 harmonyv4 volumio5-onboarding[1545]: time=2026-10-14T16:49:05.027+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.124:57456 @ 0x1c365d0" id=tidal://song/226215895 title=Jolene Oct 14 16:49:05 harmonyv4 systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 14 16:49:05 harmonyv4 systemd[1]: mpd.service: Consumed 2.539s CPU time. Oct 14 16:49:05 harmonyv4 systemd[1]: mpd.socket: Deactivated successfully. Oct 14 16:49:05 harmonyv4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 14 16:49:05 harmonyv4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 14 16:49:05 harmonyv4 sudo[2931]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 16:49:05 harmonyv4 sudo[2931]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:49:05 harmonyv4 sudo[2934]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 16:49:05 harmonyv4 sudo[2934]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:49:05 harmonyv4 volumio[1341]: info: MPD Permissions set Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 16:49:05 harmonyv4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 14 16:49:05 harmonyv4 systemd[1]: Starting mpd.service - Music Player Daemon... Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 14 16:49:05 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 14 16:49:05 harmonyv4 sudo[2922]: pam_unix(sudo:session): session closed for user root Oct 14 16:49:05 harmonyv4 sudo[2931]: pam_unix(sudo:session): session closed for user root Oct 14 16:49:05 harmonyv4 sudo[2934]: pam_unix(sudo:session): session closed for user root Oct 14 16:49:05 harmonyv4 sudo[2942]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 14 16:49:05 harmonyv4 sudo[2942]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 14 16:49:05 harmonyv4 sudo[2942]: pam_unix(sudo:session): session closed for user root Oct 14 16:49:05 harmonyv4 mpd[2953]: 2026-10-14T16:49:05 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 14 16:49:05 harmonyv4 systemd[1]: Started mpd.service - Music Player Daemon. Oct 14 16:49:05 harmonyv4 sudo[2914]: pam_unix(sudo:session): session closed for user root Oct 14 16:49:05 harmonyv4 volumio[1341]: error: updateQueue error: null Oct 14 16:49:08 harmonyv4 volumio[1341]: info: TidalConnect service stoped! Oct 14 16:49:08 harmonyv4 volumio[1341]: info: TidalConnect service stoped! Oct 14 16:49:08 harmonyv4 volumio[1341]: info: TidalConnect service stoped! Oct 14 16:49:08 harmonyv4 volumio[1341]: info: TidalConnect service stoped! Oct 14 16:49:08 harmonyv4 volumio[1341]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 14 16:49:08 harmonyv4 volumio[1341]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 14 16:49:08 harmonyv4 volumio[1341]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 14 16:49:08 harmonyv4 volumio[1341]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 14 16:49:08 harmonyv4 sudo[2973]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 14 16:49:08 harmonyv4 sudo[2973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:49:08 harmonyv4 sudo[2975]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 14 16:49:08 harmonyv4 sudo[2975]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:49:08 harmonyv4 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 14 16:49:08 harmonyv4 sudo[2973]: pam_unix(sudo:session): session closed for user root Oct 14 16:49:08 harmonyv4 sudo[2975]: pam_unix(sudo:session): session closed for user root Oct 14 16:49:08 harmonyv4 volumio[1341]: info: Executing endpoint tc_getconfig Oct 14 16:49:08 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 14 16:49:08 harmonyv4 vtcs[2979]: STARTING TidalConnect services, version: 1.6.1 Oct 14 16:49:08 harmonyv4 vtcs[2979]: STARTED TidalConnect services. Oct 14 16:49:08 harmonyv4 volumio[1341]: info: Executing endpoint tc_connect Oct 14 16:49:08 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 14 16:49:08 harmonyv4 volumio[1341]: info: Connecting to TidalConnect Oct 14 16:49:08 harmonyv4 volumio[1341]: info: CoreCommandRouter::servicePushState Oct 14 16:49:08 harmonyv4 volumio[1341]: info: CoreStateMachine::pushState Oct 14 16:49:08 harmonyv4 volumio[1341]: info: CoreCommandRouter::volumioPushState Oct 14 16:49:08 harmonyv4 volumio[1341]: info: CorePlayQueue::getTrack 1 Oct 14 16:49:08 harmonyv4 volumio[1341]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Oct 14 16:49:08 harmonyv4 volumio[1341]: info: CoreCommandRouter::servicePushState Oct 14 16:49:08 harmonyv4 volumio[1341]: info: CoreStateMachine::pushState Oct 14 16:49:08 harmonyv4 volumio[1341]: info: CoreCommandRouter::volumioPushState Oct 14 16:49:08 harmonyv4 volumio[1341]: info: CorePlayQueue::getTrack 1 Oct 14 16:49:08 harmonyv4 volumio[1341]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Oct 14 16:49:08 harmonyv4 volumio5-onboarding[1545]: time=2026-10-14T16:49:08.284+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.124:57456 @ 0x1c365d0" state=STATUS_PAUSED positionMs=68322 volume=100 Oct 14 16:49:08 harmonyv4 volumio5-onboarding[1545]: time=2026-10-14T16:49:08.284+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.124:57456 @ 0x1c365d0" state=STATUS_PAUSED positionMs=68322 volume=100 Oct 14 16:49:08 harmonyv4 volumio5-onboarding[1545]: time=2026-10-14T16:49:08.284+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.124:57456 @ 0x1c365d0" id=tidal://song/226215895 title=Jolene Oct 14 16:49:08 harmonyv4 volumio5-onboarding[1545]: time=2026-10-14T16:49:08.285+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.124:57456 @ 0x1c365d0" id=tidal://song/226215895 title=Jolene Oct 14 16:49:09 harmonyv4 volumio[1341]: info: VolumeController::SetAlsaVolume100 Oct 14 16:49:09 harmonyv4 volumio[1341]: info: CoreStateMachine::pushState Oct 14 16:49:09 harmonyv4 volumio[1341]: info: CoreCommandRouter::volumioPushState Oct 14 16:49:09 harmonyv4 volumio5-onboarding[1545]: time=2026-10-14T16:49:09.470+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.124:57456 @ 0x1c365d0" state=STATUS_PAUSED positionMs=68322 volume=100 Oct 14 16:49:09 harmonyv4 volumio5-onboarding[1545]: time=2026-10-14T16:49:09.470+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.124:57456 @ 0x1c365d0" id=tidal://song/226215895 title=Jolene Oct 14 16:49:11 harmonyv4 volumio[1341]: info: TidalConnect service started! Oct 14 16:49:11 harmonyv4 volumio[1341]: info: TidalConnect service started! Oct 14 16:49:14 harmonyv4 volumio[1341]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 14 16:49:14 harmonyv4 volumio[1341]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 14 16:49:14 harmonyv4 volumio[1341]: info: Retrieving Cloud Streaming UI Oct 14 16:49:14 harmonyv4 volumio[1341]: info: Getting Tidal Cloud Configuration Oct 14 16:49:14 harmonyv4 volumio[1341]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 14 16:49:14 harmonyv4 volumio[1341]: info: Getting Qobuz Cloud Configuration Oct 14 16:49:14 harmonyv4 volumio[1341]: info: Asking plugin for UI Config Oct 14 16:49:14 harmonyv4 volumio[1341]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 14 16:49:14 harmonyv4 volumio[1341]: info: Getting Spotify Cloud Configuration Oct 14 16:49:14 harmonyv4 volumio[1341]: info: Asking plugin for UI Config Oct 14 16:49:14 harmonyv4 volumio[1341]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 14 16:49:14 harmonyv4 volumio[1341]: info: Saving Spotify Acccount Oct 14 16:49:14 harmonyv4 volumio[1341]: info: Got it Oct 14 16:49:14 harmonyv4 volumio[1341]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 14 16:49:14 harmonyv4 volumio[1341]: info: Got Tidal Cloud Configuration Oct 14 16:49:14 harmonyv4 volumio[1341]: info: Got it Oct 14 16:49:14 harmonyv4 volumio[1341]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 14 16:49:14 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 14 16:49:14 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 14 16:49:14 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 14 16:49:14 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 14 16:49:14 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 16:49:14 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 16:49:14 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 16:49:14 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 16:49:14 harmonyv4 volumio[1341]: info: CoreCommandRouter::volumioGetBrowseSources Oct 14 16:49:14 harmonyv4 volumio[1341]: info: CoreCommandRouter::volumioGetBrowseSources Oct 14 16:49:14 harmonyv4 volumio[1341]: info: CoreCommandRouter::volumioGetBrowseSources Oct 14 16:49:14 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 16:49:14 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 14 16:49:18 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 16:49:22 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 16:49:26 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 16:49:30 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 16:49:34 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 16:49:38 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 16:49:42 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 16:49:46 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 16:49:49 harmonyv4 volumio[1341]: info: Disabling MyMusic plugin upnp Oct 14 16:49:49 harmonyv4 sudo[3057]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 14 16:49:49 harmonyv4 sudo[3057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 16:49:49 harmonyv4 systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Oct 14 16:49:50 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 16:49:54 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 16:49:55 harmonyv4 ntpd[1123]: CLOCK: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 14 16:49:55 harmonyv4 volumio[1341]: info: Enabling MyMusic plugin upnp Oct 14 16:49:55 harmonyv4 volumio[1341]: info: Enabling plugin upnp Oct 14 16:49:55 harmonyv4 volumio[1341]: info: Loading plugin "upnp"... Oct 14 16:49:55 harmonyv4 volumio[1341]: info: [1791985795670] Starting Upmpd Daemon Oct 14 16:49:55 harmonyv4 volumio[1341]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 14 16:49:55 harmonyv4 volumio[1341]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 16:49:55 harmonyv4 volumio[1341]: Error: listen EADDRINUSE: address already in use :::6599 Oct 14 16:49:55 harmonyv4 volumio[1341]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Oct 14 16:49:55 harmonyv4 volumio[1341]: at listenInCluster (node:net:1920:12) Oct 14 16:49:55 harmonyv4 volumio[1341]: at Server.listen (node:net:2008:7) Oct 14 16:49:55 harmonyv4 volumio[1341]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 14 16:49:55 harmonyv4 volumio[1341]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 14 16:49:55 harmonyv4 volumio[1341]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 14 16:49:55 harmonyv4 volumio[1341]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 14 16:49:55 harmonyv4 volumio[1341]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Oct 14 16:49:55 harmonyv4 volumio[1341]: code: 'EADDRINUSE', Oct 14 16:49:55 harmonyv4 volumio[1341]: errno: -98, Oct 14 16:49:55 harmonyv4 volumio[1341]: syscall: 'listen', Oct 14 16:49:55 harmonyv4 volumio[1341]: address: '::', Oct 14 16:49:55 harmonyv4 volumio[1341]: port: 6599 Oct 14 16:49:55 harmonyv4 volumio[1341]: } Oct 14 16:49:55 harmonyv4 volumio[1341]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 16:49:55 harmonyv4 sudo[3087]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-14 16:48' Oct 14 16:49:55 harmonyv4 sudo[3087]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"