Sep 17 09:32:05 volumio volumio[26664]: info: CoreCommandRouter::volumioGetState Sep 17 09:32:05 volumio volumio[26664]: info: CorePlayQueue::getTrack 2 Sep 17 09:32:05 volumio volumio[26664]: info: CoreCommandRouter::volumioGetState Sep 17 09:32:05 volumio volumio[26664]: info: CorePlayQueue::getTrack 2 Sep 17 09:32:05 volumio volumio[26664]: info: Currently active: tidal Sep 17 09:32:05 volumio volumio[26664]: info: Stopping currently active service Sep 17 09:32:05 volumio volumio[26664]: info: CoreCommandRouter::volumioStop Sep 17 09:32:05 volumio volumio[26664]: info: CoreStateMachine::stop Sep 17 09:32:05 volumio volumio[26664]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 09:32:05 volumio volumio[26664]: info: CoreStateMachine::stPlaybackTimer Sep 17 09:32:05 volumio volumio[26664]: info: CoreStateMachine::updateTrackBlock Sep 17 09:32:05 volumio volumio[26664]: info: CorePlayQueue::getTrackBlock Sep 17 09:32:05 volumio volumio[26664]: info: CoreStateMachine::pushState Sep 17 09:32:05 volumio volumio[26664]: info: CorePlayQueue::getTrack 2 Sep 17 09:32:05 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 09:32:05 volumio volumio[26664]: info: CoreCommandRouter::volumioPushState Sep 17 09:32:05 volumio volumio[26664]: info: MRS: Pushing multiroomSync output update for this device Sep 17 09:32:05 volumio volumio[26664]: info: MRS: Pushing multiroomSync output Sep 17 09:32:05 volumio volumio[26664]: info: CoreStateMachine::serviceStop Sep 17 09:32:05 volumio volumio[26664]: info: CorePlayQueue::getTrack 2 Sep 17 09:32:05 volumio volumio[26664]: info: CoreCommandRouter::serviceStop Sep 17 09:32:05 volumio volumio[26664]: info: [1758094325493] ControllerTidal::stop Sep 17 09:32:05 volumio volumio[26664]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 17 09:32:05 volumio volumio[26664]: info: ControllerMpd::stop Sep 17 09:32:05 volumio volumio[26664]: verbose: ControllerMpd::sendMpdCommand stop Sep 17 09:32:05 volumio volumio[26664]: info: Sep 17 09:32:05 volumio volumio[26664]: ---------------------------- MPD announces state update: player Sep 17 09:32:05 volumio volumio[26664]: info: sendMpdCommand stop took 10 milliseconds Sep 17 09:32:05 volumio volumio[26664]: info: ControllerMpd::getState Sep 17 09:32:05 volumio volumio[26664]: verbose: ControllerMpd::sendMpdCommand status Sep 17 09:32:05 volumio volumio[26664]: info: TidalConnect Active Sep 17 09:32:05 volumio volumio[26664]: info: CoreCommandRouter::volumioGetState Sep 17 09:32:05 volumio volumio[26664]: info: Currently active: mpd Sep 17 09:32:05 volumio volumio[26664]: info: Setting Volatile state to tidalconnect Sep 17 09:32:05 volumio volumio[26664]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 09:32:05 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:05 volumio volumio[26664]: info: CoreStateMachine::pushState Sep 17 09:32:05 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 09:32:05 volumio volumio[26664]: info: CoreCommandRouter::volumioPushState Sep 17 09:32:05 volumio volumio[26664]: info: MRS: Pushing multiroomSync output update for this device Sep 17 09:32:05 volumio volumio[26664]: info: MRS: Pushing multiroomSync output Sep 17 09:32:05 volumio volumio[26664]: info: CorePlayQueue::getTrack 2 Sep 17 09:32:05 volumio volumio[26664]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Sep 17 09:32:05 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:05 volumio volumio[26664]: info: CoreStateMachine::pushState Sep 17 09:32:05 volumio volumio[26664]: info: CoreCommandRouter::volumioPushState Sep 17 09:32:05 volumio volumio[26664]: info: MRS: Pushing multiroomSync output update for this device Sep 17 09:32:05 volumio volumio[26664]: info: MRS: Pushing multiroomSync output Sep 17 09:32:05 volumio volumio[26664]: info: CorePlayQueue::getTrack 2 Sep 17 09:32:05 volumio volumio[26664]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Sep 17 09:32:05 volumio volumio[26664]: info: sendMpdCommand status took 9 milliseconds Sep 17 09:32:05 volumio volumio[26664]: verbose: ControllerMpd::parseState Sep 17 09:32:05 volumio volumio[26664]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 09:32:05 volumio volumio[26664]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 17 09:32:05 volumio volumio[26664]: verbose: ControllerMpd::parseTrackInfo Sep 17 09:32:05 volumio volumio[26664]: info: ControllerMpd::pushState Sep 17 09:32:05 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:05 volumio volumio[26664]: info: CoreStateMachine::pushState Sep 17 09:32:05 volumio volumio[26664]: info: CoreCommandRouter::volumioPushState Sep 17 09:32:05 volumio volumio[26664]: info: MRS: Pushing multiroomSync output update for this device Sep 17 09:32:05 volumio volumio[26664]: info: MRS: Pushing multiroomSync output Sep 17 09:32:05 volumio volumio[26664]: info: CorePlayQueue::getTrack 2 Sep 17 09:32:05 volumio volumio[26664]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Sep 17 09:32:05 volumio volumio[26664]: info: ------------------------------ 23ms Sep 17 09:32:06 volumio vtcs[27102]: [2025-09-17 09:32:06.149] [tisoc] [warning] [PlaybackControllerImpl.cpp:520] Illegal state:1 Sep 17 09:32:06 volumio vtcs[27102]: [2025-09-17 09:32:06.149] [tisoc] [warning] [PlaybackControllerImpl.cpp:213] Ignore play request because playback state is not STARTED. state_=2, playstate_=1, player_state_=0 Sep 17 09:32:06 volumio volumio[26664]: info: Pushing metadata Sep 17 09:32:06 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:06 volumio volumio[26664]: info: CoreStateMachine::pushState Sep 17 09:32:06 volumio volumio[26664]: info: CoreCommandRouter::volumioPushState Sep 17 09:32:06 volumio volumio[26664]: info: MRS: Pushing multiroomSync output update for this device Sep 17 09:32:06 volumio volumio[26664]: info: MRS: Pushing multiroomSync output Sep 17 09:32:06 volumio volumio[26664]: info: CorePlayQueue::getTrack 2 Sep 17 09:32:06 volumio volumio[26664]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Sep 17 09:32:06 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:06 volumio volumio[26664]: info: CoreStateMachine::pushState Sep 17 09:32:06 volumio volumio[26664]: info: CoreCommandRouter::volumioPushState Sep 17 09:32:06 volumio volumio[26664]: info: MRS: Pushing multiroomSync output update for this device Sep 17 09:32:06 volumio volumio[26664]: info: MRS: Pushing multiroomSync output Sep 17 09:32:06 volumio volumio[26664]: info: CorePlayQueue::getTrack 2 Sep 17 09:32:06 volumio volumio[26664]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Sep 17 09:32:06 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:06 volumio volumio[26664]: info: CoreStateMachine::pushState Sep 17 09:32:06 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 09:32:06 volumio volumio[26664]: info: CoreCommandRouter::volumioPushState Sep 17 09:32:06 volumio volumio[26664]: info: MRS: Pushing multiroomSync output update for this device Sep 17 09:32:06 volumio volumio[26664]: info: MRS: Pushing multiroomSync output Sep 17 09:32:06 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:06 volumio volumio[26664]: info: CoreStateMachine::pushState Sep 17 09:32:06 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 09:32:06 volumio volumio[26664]: info: CoreCommandRouter::volumioPushState Sep 17 09:32:06 volumio volumio[26664]: info: MRS: Pushing multiroomSync output update for this device Sep 17 09:32:06 volumio volumio[26664]: info: MRS: Pushing multiroomSync output Sep 17 09:32:06 volumio volumio[26664]: info: Pushing metadata Sep 17 09:32:06 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:06 volumio volumio[26664]: info: CoreStateMachine::pushState Sep 17 09:32:06 volumio volumio[26664]: info: CoreCommandRouter::volumioPushState Sep 17 09:32:06 volumio volumio[26664]: info: MRS: Pushing multiroomSync output update for this device Sep 17 09:32:06 volumio volumio[26664]: info: MRS: Pushing multiroomSync output Sep 17 09:32:06 volumio vtcs[27102]: [2025-09-17 09:32:06.735] [tisoc] [error] [ffmpeg_aac_decoder.cpp:31] avcodec major version differ between runtime and compile time. Sep 17 09:32:06 volumio vtcs[27102]: [2025-09-17 09:32:06.735] [tisoc] [error] [ffmpeg_aac_decoder.cpp:34] avformat major version differ between runtime and compile time. Sep 17 09:32:06 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:06 volumio volumio[26664]: info: CoreStateMachine::pushState Sep 17 09:32:06 volumio volumio[26664]: info: CoreCommandRouter::volumioPushState Sep 17 09:32:06 volumio volumio[26664]: info: MRS: Pushing multiroomSync output update for this device Sep 17 09:32:06 volumio volumio[26664]: info: MRS: Pushing multiroomSync output Sep 17 09:32:06 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:06 volumio volumio[26664]: info: CoreStateMachine::pushState Sep 17 09:32:06 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 09:32:06 volumio volumio[26664]: info: CoreCommandRouter::volumioPushState Sep 17 09:32:06 volumio volumio[26664]: info: MRS: Pushing multiroomSync output update for this device Sep 17 09:32:06 volumio volumio[26664]: info: MRS: Pushing multiroomSync output Sep 17 09:32:06 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:06 volumio volumio[26664]: info: CoreStateMachine::pushState Sep 17 09:32:06 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 09:32:06 volumio volumio[26664]: info: CoreCommandRouter::volumioPushState Sep 17 09:32:06 volumio volumio[26664]: info: MRS: Pushing multiroomSync output update for this device Sep 17 09:32:06 volumio volumio[26664]: info: MRS: Pushing multiroomSync output Sep 17 09:32:06 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:06 volumio volumio[26664]: info: CoreStateMachine::pushState Sep 17 09:32:06 volumio volumio[26664]: info: CoreCommandRouter::volumioPushState Sep 17 09:32:06 volumio volumio[26664]: info: MRS: Pushing multiroomSync output update for this device Sep 17 09:32:06 volumio volumio[26664]: info: MRS: Pushing multiroomSync output Sep 17 09:32:07 volumio vtcs[27101]: Segmentation fault Sep 17 09:32:07 volumio systemd[1]: vtcs.service: Main process exited, code=exited, status=139/n/a Sep 17 09:32:07 volumio systemd[1]: vtcs.service: Failed with result 'exit-code'. Sep 17 09:32:07 volumio systemd[1]: vtcs.service: Consumed 21.141s CPU time. Sep 17 09:32:07 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 1. Sep 17 09:32:07 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Sep 17 09:32:07 volumio systemd[1]: vtcs.service: Consumed 21.141s CPU time. Sep 17 09:32:07 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Sep 17 09:32:07 volumio volumio[26664]: info: Executing endpoint tc_getconfig Sep 17 09:32:07 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 17 09:32:07 volumio vtcs[8560]: BYE TidalConnect Sep 17 09:32:07 volumio systemd[1]: vtcs.service: Deactivated successfully. Sep 17 09:32:08 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 2. Sep 17 09:32:08 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Sep 17 09:32:08 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Sep 17 09:32:08 volumio volumio[26664]: info: Executing endpoint tc_getconfig Sep 17 09:32:08 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 17 09:32:08 volumio vtcs[8563]: BYE TidalConnect Sep 17 09:32:08 volumio systemd[1]: vtcs.service: Deactivated successfully. Sep 17 09:32:08 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 3. Sep 17 09:32:08 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Sep 17 09:32:08 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Sep 17 09:32:08 volumio volumio[26664]: info: Executing endpoint tc_getconfig Sep 17 09:32:08 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 17 09:32:08 volumio vtcs[8566]: BYE TidalConnect Sep 17 09:32:08 volumio systemd[1]: vtcs.service: Deactivated successfully. Sep 17 09:32:09 volumio volumio[26664]: info: Executing endpoint metavolumio Sep 17 09:32:09 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 17 09:32:09 volumio volumio[26664]: info: Executing endpoint metavolumio Sep 17 09:32:09 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 17 09:32:09 volumio volumio[26664]: info: Executing endpoint metavolumio Sep 17 09:32:09 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 17 09:32:09 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 4. Sep 17 09:32:09 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Sep 17 09:32:09 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Sep 17 09:32:09 volumio volumio[26664]: info: Executing endpoint tc_getconfig Sep 17 09:32:09 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 17 09:32:09 volumio vtcs[8569]: BYE TidalConnect Sep 17 09:32:09 volumio systemd[1]: vtcs.service: Deactivated successfully. Sep 17 09:32:09 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 5. Sep 17 09:32:09 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Sep 17 09:32:09 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Sep 17 09:32:09 volumio volumio[26664]: info: Executing endpoint tc_getconfig Sep 17 09:32:09 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 17 09:32:09 volumio vtcs[8572]: BYE TidalConnect Sep 17 09:32:09 volumio systemd[1]: vtcs.service: Deactivated successfully. Sep 17 09:32:10 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 6. Sep 17 09:32:10 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Sep 17 09:32:10 volumio systemd[1]: vtcs.service: Start request repeated too quickly. Sep 17 09:32:10 volumio systemd[1]: vtcs.service: Failed with result 'start-limit-hit'. Sep 17 09:32:10 volumio systemd[1]: Failed to start vtcs.service - Volumio Tidal Connect Service. Sep 17 09:32:22 volumio volumio[26664]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 17 09:32:22 volumio volumio[26664]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 17 09:32:22 volumio volumio[26664]: info: Retrieving Cloud Streaming UI Sep 17 09:32:22 volumio volumio[26664]: info: Getting Tidal Cloud Configuration Sep 17 09:32:22 volumio volumio[26664]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 17 09:32:22 volumio volumio[26664]: info: Getting Qobuz Cloud Configuration Sep 17 09:32:22 volumio volumio[26664]: info: Asking plugin for UI Config Sep 17 09:32:22 volumio volumio[26664]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 17 09:32:22 volumio volumio[26664]: info: Getting Spotify Cloud Configuration Sep 17 09:32:22 volumio volumio[26664]: info: Asking plugin for UI Config Sep 17 09:32:22 volumio volumio[26664]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 17 09:32:22 volumio volumio[26664]: info: Saving Spotify Acccount Sep 17 09:32:22 volumio volumio[26664]: info: Got it Sep 17 09:32:22 volumio volumio[26664]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 17 09:32:22 volumio volumio[26664]: info: Got Tidal Cloud Configuration Sep 17 09:32:22 volumio volumio[26664]: info: Got it Sep 17 09:32:22 volumio volumio[26664]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 17 09:32:22 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 17 09:32:22 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 17 09:32:22 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 17 09:32:22 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 17 09:32:22 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 09:32:22 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 09:32:22 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 09:32:22 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 09:32:22 volumio volumio[26664]: info: CoreCommandRouter::volumioGetBrowseSources Sep 17 09:32:22 volumio volumio[26664]: info: CoreCommandRouter::volumioGetBrowseSources Sep 17 09:32:22 volumio volumio[26664]: info: CoreCommandRouter::volumioGetBrowseSources Sep 17 09:32:22 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 17 09:32:22 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 17 09:32:26 volumio volumio[26664]: info: Disabling MyMusic plugin airplay_emulation Sep 17 09:32:26 volumio volumio[26664]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync Sep 17 09:32:26 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 09:32:26 volumio volumio[26664]: Cannot find translation for source Radio Paradise Sep 17 09:32:26 volumio volumio[26664]: Cannot find translation for source TIDAL Sep 17 09:32:26 volumio volumio[26664]: info: Disabling plugin airplay_emulation Sep 17 09:32:26 volumio volumio[26664]: info: Done. Sep 17 09:32:26 volumio sudo[8613]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Sep 17 09:32:26 volumio sudo[8613]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 17 09:32:26 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 17 09:32:26 volumio sudo[8613]: pam_unix(sudo:session): session closed for user root Sep 17 09:32:26 volumio volumio[26664]: info: Shairport-Sync Stopped Sep 17 09:32:26 volumio dbus-daemon[721]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.2715' (uid=0 pid=8616 comm="timedatectl show --property=NTPSynchronized --valu") Sep 17 09:32:26 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 17 09:32:26 volumio dbus-daemon[721]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 17 09:32:26 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 17 09:32:26 volumio setdatetime-helper.sh[8615]: Time is already synchronized. Sep 17 09:32:26 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 17 09:32:26 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 17 09:32:26 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 17 09:32:29 volumio volumio[26664]: info: Disabling MyMusic plugin upnp Sep 17 09:32:29 volumio sudo[8620]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Sep 17 09:32:29 volumio sudo[8620]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 17 09:32:29 volumio systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Sep 17 09:32:30 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 17 09:32:32 volumio volumio[26664]: info: Enabling MyMusic plugin airplay_emulation Sep 17 09:32:32 volumio volumio[26664]: info: Enabling plugin airplay_emulation Sep 17 09:32:32 volumio volumio[26664]: info: Loading plugin "airplay_emulation"... Sep 17 09:32:32 volumio volumio[26664]: info: Starting Shairport Sync Sep 17 09:32:32 volumio volumio[26664]: info: PLUGIN START: airplay_emulation Sep 17 09:32:32 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 09:32:32 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 09:32:32 volumio volumio[26664]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 17 09:32:32 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 09:32:32 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 09:32:32 volumio volumio[26664]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 17 09:32:32 volumio volumio[26664]: info: Done. Sep 17 09:32:32 volumio volumio[26664]: info: Starting Shairport Sync Sep 17 09:32:32 volumio volumio[26664]: info: Starting Shairport Sync Sep 17 09:32:32 volumio sudo[8650]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 17 09:32:32 volumio sudo[8650]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 17 09:32:32 volumio sudo[8652]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 17 09:32:32 volumio sudo[8652]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 17 09:32:32 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 17 09:32:32 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 17 09:32:32 volumio sudo[8650]: pam_unix(sudo:session): session closed for user root Sep 17 09:32:32 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Sep 17 09:32:32 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 17 09:32:32 volumio volumio[26664]: info: Shairport-Sync Started Sep 17 09:32:32 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 17 09:32:32 volumio sudo[8652]: pam_unix(sudo:session): session closed for user root Sep 17 09:32:32 volumio volumio[26664]: info: Shairport-Sync Started Sep 17 09:32:33 volumio wpa_supplicant[1202]: wlan0: WPA: Group rekeying completed with 36:e1:a9:13:89:90 [GTK=CCMP] Sep 17 09:32:34 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 17 09:32:35 volumio volumio[26664]: info: Disabling MyMusic plugin tidalconnect Sep 17 09:32:35 volumio volumio[26664]: info: [MyVolumio PluginManager] Disabling and stopping plugin music_service tidalconnect Sep 17 09:32:35 volumio volumio[26664]: info: Relinquishing Volumio State Sep 17 09:32:35 volumio volumio[26664]: verbose: UNSET VOLATILE: Service: tidalconnect Sep 17 09:32:35 volumio volumio[26664]: info: unSetVolatile called Sep 17 09:32:35 volumio volumio[26664]: info: Relinquishing Volumio State to another service Sep 17 09:32:35 volumio volumio[26664]: info: Disabling plugin tidalconnect Sep 17 09:32:35 volumio sudo[8673]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 17 09:32:35 volumio sudo[8673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 17 09:32:35 volumio sudo[8673]: pam_unix(sudo:session): session closed for user root Sep 17 09:32:37 volumio volumio[26664]: info: Enabling MyMusic plugin tidalconnect Sep 17 09:32:37 volumio volumio[26664]: info: [MyVolumio PluginManager] Enabling and starting plugin music_service tidalconnect Sep 17 09:32:37 volumio volumio[26664]: info: Enabling plugin tidalconnect Sep 17 09:32:37 volumio volumio[26664]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Sep 17 09:32:37 volumio sudo[8677]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 17 09:32:37 volumio sudo[8677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 17 09:32:37 volumio sudo[8677]: pam_unix(sudo:session): session closed for user root Sep 17 09:32:38 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 17 09:32:38 volumio volumio[26664]: info: TidalConnect service stoped! Sep 17 09:32:40 volumio volumio[26664]: info: TidalConnect service stoped! Sep 17 09:32:40 volumio volumio[26664]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 17 09:32:40 volumio volumio[26664]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 17 09:32:40 volumio sudo[8692]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 17 09:32:40 volumio sudo[8692]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 17 09:32:40 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Sep 17 09:32:40 volumio sudo[8692]: pam_unix(sudo:session): session closed for user root Sep 17 09:32:41 volumio volumio[26664]: info: Executing endpoint tc_getconfig Sep 17 09:32:41 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 17 09:32:41 volumio vtcs[8695]: STARTING TidalConnect services, version: 1.5.1-beta1 Sep 17 09:32:41 volumio vtcs[8695]: STARTED TidalConnect services. Sep 17 09:32:41 volumio volumio[26664]: info: Executing endpoint tc_connect Sep 17 09:32:41 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Sep 17 09:32:41 volumio volumio[26664]: info: Connecting to TidalConnect Sep 17 09:32:41 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:41 volumio volumio[26664]: info: CorePlayQueue::getTrack 2 Sep 17 09:32:41 volumio volumio[26664]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Sep 17 09:32:41 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:41 volumio volumio[26664]: info: CorePlayQueue::getTrack 2 Sep 17 09:32:41 volumio volumio[26664]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Sep 17 09:32:42 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 17 09:32:43 volumio volumio[26664]: info: Disabling MyMusic plugin tidalconnect Sep 17 09:32:43 volumio volumio[26664]: info: [MyVolumio PluginManager] Disabling and stopping plugin music_service tidalconnect Sep 17 09:32:43 volumio volumio[26664]: info: Relinquishing Volumio State Sep 17 09:32:43 volumio vtcs[8695]: [2025-09-17 09:32:43.505] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Sep 17 09:32:43 volumio vtcs[8695]: [2025-09-17 09:32:43.506] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Sep 17 09:32:43 volumio volumio[26664]: info: Disabling plugin tidalconnect Sep 17 09:32:43 volumio sudo[8728]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 17 09:32:43 volumio sudo[8728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 17 09:32:43 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Sep 17 09:32:43 volumio systemd[1]: vtcs.service: Killing process 8698 (vtcs) with signal SIGKILL. Sep 17 09:32:43 volumio systemd[1]: vtcs.service: Deactivated successfully. Sep 17 09:32:43 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Sep 17 09:32:43 volumio sudo[8728]: pam_unix(sudo:session): session closed for user root Sep 17 09:32:43 volumio volumio[26664]: info: TidalConnect service started! Sep 17 09:32:43 volumio volumio[26664]: info: Done. Sep 17 09:32:45 volumio volumio[26664]: info: Enabling MyMusic plugin tidalconnect Sep 17 09:32:45 volumio volumio[26664]: info: [MyVolumio PluginManager] Enabling and starting plugin music_service tidalconnect Sep 17 09:32:45 volumio volumio[26664]: info: Enabling plugin tidalconnect Sep 17 09:32:45 volumio volumio[26664]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Sep 17 09:32:45 volumio sudo[8732]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 17 09:32:45 volumio sudo[8732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 17 09:32:45 volumio sudo[8732]: pam_unix(sudo:session): session closed for user root Sep 17 09:32:46 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 17 09:32:46 volumio volumio[26664]: info: TidalConnect service stoped! Sep 17 09:32:48 volumio volumio[26664]: info: TidalConnect service stoped! Sep 17 09:32:48 volumio volumio[26664]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 17 09:32:48 volumio volumio[26664]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 17 09:32:48 volumio sudo[8744]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 17 09:32:48 volumio sudo[8744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 17 09:32:48 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Sep 17 09:32:48 volumio sudo[8744]: pam_unix(sudo:session): session closed for user root Sep 17 09:32:48 volumio volumio[26664]: info: Executing endpoint tc_getconfig Sep 17 09:32:48 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 17 09:32:48 volumio vtcs[8747]: STARTING TidalConnect services, version: 1.5.1-beta1 Sep 17 09:32:48 volumio vtcs[8747]: STARTED TidalConnect services. Sep 17 09:32:48 volumio volumio[26664]: info: Executing endpoint tc_connect Sep 17 09:32:48 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Sep 17 09:32:48 volumio volumio[26664]: info: Connecting to TidalConnect Sep 17 09:32:48 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:48 volumio volumio[26664]: info: CorePlayQueue::getTrack 2 Sep 17 09:32:48 volumio volumio[26664]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Sep 17 09:32:48 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:48 volumio volumio[26664]: info: CorePlayQueue::getTrack 2 Sep 17 09:32:48 volumio volumio[26664]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Sep 17 09:32:50 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 17 09:32:51 volumio volumio[26664]: info: TidalConnect service started! Sep 17 09:32:51 volumio volumio[26664]: info: Done. Sep 17 09:32:53 volumio volumio[26664]: info: CoreCommandRouter::volumioGetState Sep 17 09:32:53 volumio volumio[26664]: info: CorePlayQueue::getTrack 2 Sep 17 09:32:53 volumio volumio[26664]: info: CoreCommandRouter::volumioGetState Sep 17 09:32:53 volumio volumio[26664]: info: CorePlayQueue::getTrack 2 Sep 17 09:32:53 volumio volumio[26664]: info: Currently active: tidal Sep 17 09:32:53 volumio volumio[26664]: info: Stopping currently active service Sep 17 09:32:53 volumio volumio[26664]: info: CoreCommandRouter::volumioStop Sep 17 09:32:53 volumio volumio[26664]: info: CoreStateMachine::stop Sep 17 09:32:53 volumio volumio[26664]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 09:32:53 volumio volumio[26664]: info: CoreStateMachine::stPlaybackTimer Sep 17 09:32:53 volumio volumio[26664]: info: CoreStateMachine::updateTrackBlock Sep 17 09:32:53 volumio volumio[26664]: info: CorePlayQueue::getTrackBlock Sep 17 09:32:53 volumio volumio[26664]: info: CoreStateMachine::pushState Sep 17 09:32:53 volumio volumio[26664]: info: CorePlayQueue::getTrack 2 Sep 17 09:32:53 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 09:32:53 volumio volumio[26664]: info: CoreCommandRouter::volumioPushState Sep 17 09:32:53 volumio volumio[26664]: info: MRS: Pushing multiroomSync output update for this device Sep 17 09:32:53 volumio volumio[26664]: info: MRS: Pushing multiroomSync output Sep 17 09:32:53 volumio volumio[26664]: info: CoreStateMachine::serviceStop Sep 17 09:32:53 volumio volumio[26664]: info: CorePlayQueue::getTrack 2 Sep 17 09:32:53 volumio volumio[26664]: info: CoreCommandRouter::serviceStop Sep 17 09:32:53 volumio volumio[26664]: info: [1758094373557] ControllerTidal::stop Sep 17 09:32:53 volumio volumio[26664]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 17 09:32:53 volumio volumio[26664]: info: ControllerMpd::stop Sep 17 09:32:53 volumio volumio[26664]: verbose: ControllerMpd::sendMpdCommand stop Sep 17 09:32:53 volumio volumio[26664]: info: sendMpdCommand stop took 7 milliseconds Sep 17 09:32:53 volumio volumio[26664]: info: TidalConnect Active Sep 17 09:32:53 volumio volumio[26664]: info: CoreCommandRouter::volumioGetState Sep 17 09:32:53 volumio volumio[26664]: info: Currently active: mpd Sep 17 09:32:53 volumio volumio[26664]: info: Setting Volatile state to tidalconnect Sep 17 09:32:53 volumio volumio[26664]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 09:32:53 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:53 volumio volumio[26664]: info: CoreStateMachine::pushState Sep 17 09:32:53 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 09:32:53 volumio volumio[26664]: info: CoreCommandRouter::volumioPushState Sep 17 09:32:53 volumio volumio[26664]: info: MRS: Pushing multiroomSync output update for this device Sep 17 09:32:53 volumio volumio[26664]: info: MRS: Pushing multiroomSync output Sep 17 09:32:53 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:53 volumio volumio[26664]: info: CoreStateMachine::pushState Sep 17 09:32:53 volumio volumio[26664]: info: CoreCommandRouter::volumioPushState Sep 17 09:32:53 volumio volumio[26664]: info: MRS: Pushing multiroomSync output update for this device Sep 17 09:32:53 volumio volumio[26664]: info: MRS: Pushing multiroomSync output Sep 17 09:32:53 volumio vtcs[8747]: [2025-09-17 09:32:53.897] [tisoc] [warning] [PlaybackControllerImpl.cpp:520] Illegal state:1 Sep 17 09:32:53 volumio vtcs[8747]: [2025-09-17 09:32:53.897] [tisoc] [warning] [PlaybackControllerImpl.cpp:213] Ignore play request because playback state is not STARTED. state_=2, playstate_=1, player_state_=0 Sep 17 09:32:53 volumio volumio[26664]: info: Pushing metadata Sep 17 09:32:53 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:53 volumio volumio[26664]: info: CoreStateMachine::pushState Sep 17 09:32:53 volumio volumio[26664]: info: CoreCommandRouter::volumioPushState Sep 17 09:32:53 volumio volumio[26664]: info: MRS: Pushing multiroomSync output update for this device Sep 17 09:32:53 volumio volumio[26664]: info: MRS: Pushing multiroomSync output Sep 17 09:32:53 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:53 volumio volumio[26664]: info: CoreStateMachine::pushState Sep 17 09:32:53 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 09:32:53 volumio volumio[26664]: info: CoreCommandRouter::volumioPushState Sep 17 09:32:53 volumio volumio[26664]: info: MRS: Pushing multiroomSync output update for this device Sep 17 09:32:53 volumio volumio[26664]: info: MRS: Pushing multiroomSync output Sep 17 09:32:53 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:53 volumio volumio[26664]: info: CoreStateMachine::pushState Sep 17 09:32:53 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 09:32:53 volumio volumio[26664]: info: CoreCommandRouter::volumioPushState Sep 17 09:32:53 volumio volumio[26664]: info: MRS: Pushing multiroomSync output update for this device Sep 17 09:32:53 volumio volumio[26664]: info: MRS: Pushing multiroomSync output Sep 17 09:32:53 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:53 volumio volumio[26664]: info: CoreStateMachine::pushState Sep 17 09:32:53 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 09:32:53 volumio volumio[26664]: info: CoreCommandRouter::volumioPushState Sep 17 09:32:53 volumio volumio[26664]: info: MRS: Pushing multiroomSync output update for this device Sep 17 09:32:53 volumio volumio[26664]: info: MRS: Pushing multiroomSync output Sep 17 09:32:54 volumio vtcs[8747]: [2025-09-17 09:32:54.431] [tisoc] [error] [ffmpeg_aac_decoder.cpp:31] avcodec major version differ between runtime and compile time. Sep 17 09:32:54 volumio vtcs[8747]: [2025-09-17 09:32:54.432] [tisoc] [error] [ffmpeg_aac_decoder.cpp:34] avformat major version differ between runtime and compile time. Sep 17 09:32:54 volumio volumio[26664]: info: Pushing metadata Sep 17 09:32:54 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:54 volumio volumio[26664]: info: CoreStateMachine::pushState Sep 17 09:32:54 volumio volumio[26664]: info: CoreCommandRouter::volumioPushState Sep 17 09:32:54 volumio volumio[26664]: info: MRS: Pushing multiroomSync output update for this device Sep 17 09:32:54 volumio volumio[26664]: info: MRS: Pushing multiroomSync output Sep 17 09:32:54 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:54 volumio volumio[26664]: info: CoreStateMachine::pushState Sep 17 09:32:54 volumio volumio[26664]: info: CoreCommandRouter::volumioPushState Sep 17 09:32:54 volumio volumio[26664]: info: MRS: Pushing multiroomSync output update for this device Sep 17 09:32:54 volumio volumio[26664]: info: MRS: Pushing multiroomSync output Sep 17 09:32:54 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:54 volumio volumio[26664]: info: CoreStateMachine::pushState Sep 17 09:32:54 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 09:32:54 volumio volumio[26664]: info: CoreCommandRouter::volumioPushState Sep 17 09:32:54 volumio volumio[26664]: info: MRS: Pushing multiroomSync output update for this device Sep 17 09:32:54 volumio volumio[26664]: info: MRS: Pushing multiroomSync output Sep 17 09:32:54 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:54 volumio volumio[26664]: info: CoreStateMachine::pushState Sep 17 09:32:54 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 09:32:54 volumio volumio[26664]: info: CoreCommandRouter::volumioPushState Sep 17 09:32:54 volumio volumio[26664]: info: MRS: Pushing multiroomSync output update for this device Sep 17 09:32:54 volumio volumio[26664]: info: MRS: Pushing multiroomSync output Sep 17 09:32:54 volumio volumio[26664]: info: CoreCommandRouter::servicePushState Sep 17 09:32:54 volumio volumio[26664]: info: CoreStateMachine::pushState Sep 17 09:32:54 volumio volumio[26664]: info: CoreCommandRouter::volumioPushState Sep 17 09:32:54 volumio volumio[26664]: info: MRS: Pushing multiroomSync output update for this device Sep 17 09:32:54 volumio volumio[26664]: info: MRS: Pushing multiroomSync output Sep 17 09:32:54 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 17 09:32:55 volumio vtcs[8746]: Segmentation fault Sep 17 09:32:55 volumio systemd[1]: vtcs.service: Main process exited, code=exited, status=139/n/a Sep 17 09:32:55 volumio systemd[1]: vtcs.service: Failed with result 'exit-code'. Sep 17 09:32:55 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 1. Sep 17 09:32:55 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Sep 17 09:32:55 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Sep 17 09:32:55 volumio volumio[26664]: info: Executing endpoint tc_getconfig Sep 17 09:32:55 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 17 09:32:55 volumio vtcs[8789]: BYE TidalConnect Sep 17 09:32:55 volumio systemd[1]: vtcs.service: Deactivated successfully. Sep 17 09:32:55 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 2. Sep 17 09:32:55 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Sep 17 09:32:55 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Sep 17 09:32:55 volumio volumio[26664]: info: Executing endpoint tc_getconfig Sep 17 09:32:55 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 17 09:32:55 volumio vtcs[8792]: BYE TidalConnect Sep 17 09:32:55 volumio systemd[1]: vtcs.service: Deactivated successfully. Sep 17 09:32:56 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 3. Sep 17 09:32:56 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Sep 17 09:32:56 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Sep 17 09:32:56 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Sep 17 09:32:56 volumio volumio[26664]: info: Executing endpoint tc_getconfig Sep 17 09:32:56 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 17 09:32:56 volumio vtcs[8795]: BYE TidalConnect Sep 17 09:32:56 volumio systemd[1]: vtcs.service: Deactivated successfully. Sep 17 09:32:56 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 4. Sep 17 09:32:56 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Sep 17 09:32:56 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Sep 17 09:32:56 volumio volumio[26664]: info: Executing endpoint tc_getconfig Sep 17 09:32:56 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 17 09:32:56 volumio vtcs[8800]: BYE TidalConnect Sep 17 09:32:56 volumio systemd[1]: vtcs.service: Deactivated successfully. Sep 17 09:32:57 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 5. Sep 17 09:32:57 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Sep 17 09:32:57 volumio systemd[1]: vtcs.service: Start request repeated too quickly. Sep 17 09:32:57 volumio systemd[1]: vtcs.service: Failed with result 'start-limit-hit'. Sep 17 09:32:57 volumio systemd[1]: Failed to start vtcs.service - Volumio Tidal Connect Service. Sep 17 09:32:58 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 17 09:33:02 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 17 09:33:06 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 17 09:33:10 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 17 09:33:12 volumio volumio[26664]: info: Disabling MyMusic plugin tidalconnect Sep 17 09:33:12 volumio volumio[26664]: info: [MyVolumio PluginManager] Disabling and stopping plugin music_service tidalconnect Sep 17 09:33:12 volumio volumio[26664]: info: Relinquishing Volumio State Sep 17 09:33:12 volumio volumio[26664]: verbose: UNSET VOLATILE: Service: tidalconnect Sep 17 09:33:12 volumio volumio[26664]: info: unSetVolatile called Sep 17 09:33:12 volumio volumio[26664]: info: Relinquishing Volumio State to another service Sep 17 09:33:12 volumio volumio[26664]: info: Disabling plugin tidalconnect Sep 17 09:33:12 volumio sudo[8835]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 17 09:33:12 volumio sudo[8835]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 17 09:33:12 volumio sudo[8835]: pam_unix(sudo:session): session closed for user root Sep 17 09:33:14 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 17 09:33:15 volumio volumio[26664]: info: TidalConnect service stoped! Sep 17 09:33:18 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 17 09:33:22 volumio volumio[26664]: info: Disabling MyMusic plugin upnp Sep 17 09:33:22 volumio sudo[8856]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Sep 17 09:33:22 volumio sudo[8856]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 17 09:33:22 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 17 09:33:25 volumio volumio[26664]: info: Enabling MyMusic plugin upnp Sep 17 09:33:25 volumio volumio[26664]: info: Enabling plugin upnp Sep 17 09:33:25 volumio volumio[26664]: info: Loading plugin "upnp"... Sep 17 09:33:25 volumio volumio[26664]: info: [1758094405625] Starting Upmpd Daemon Sep 17 09:33:25 volumio volumio[26664]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 17 09:33:25 volumio volumio[26664]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 09:33:25 volumio volumio[26664]: Error: listen EADDRINUSE: address already in use :::6599 Sep 17 09:33:25 volumio volumio[26664]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Sep 17 09:33:25 volumio volumio[26664]: at listenInCluster (node:net:1920:12) Sep 17 09:33:25 volumio volumio[26664]: at Server.listen (node:net:2008:7) Sep 17 09:33:25 volumio volumio[26664]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Sep 17 09:33:25 volumio volumio[26664]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Sep 17 09:33:25 volumio volumio[26664]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Sep 17 09:33:25 volumio volumio[26664]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 17 09:33:25 volumio volumio[26664]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Sep 17 09:33:25 volumio volumio[26664]: code: 'EADDRINUSE', Sep 17 09:33:25 volumio volumio[26664]: errno: -98, Sep 17 09:33:25 volumio volumio[26664]: syscall: 'listen', Sep 17 09:33:25 volumio volumio[26664]: address: '::', Sep 17 09:33:25 volumio volumio[26664]: port: 6599 Sep 17 09:33:25 volumio volumio[26664]: } Sep 17 09:33:25 volumio volumio[26664]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 09:33:26 volumio sudo[8872]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-17 09:32' Sep 17 09:33:26 volumio sudo[8872]: 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="dfc7bb04e09bf2260691765a2340814af1fa2971" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="30d63a8c53d2826bd7f262e22b4e4511a4cc9d01" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Aug 30 06:39:32 UTC 2025" VOLUMIO_VERSION="4.022" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e7b0bd0cb5661ea77402e5bd121ec64a"