-- Logs begin at Thu 2024-10-31 18:50:15 +07, end at Thu 2024-10-31 20:03:49 +07. -- Oct 31 20:02:06 volumio vtcs[2143]: [2024-10-31 20:02:06.472] [tisoc] [warning] [PlaybackControllerImpl.cpp:510] Illegal state:0 Oct 31 20:02:06 volumio volumio[994]: info: PlaybackInactive Oct 31 20:02:06 volumio volumio[994]: info: Device Session is_active: true Oct 31 20:02:06 volumio volumio[994]: info: DeviceInactive Oct 31 20:02:06 volumio volumio[994]: info: Relinquishing Volumio State Oct 31 20:02:06 volumio volumio[994]: verbose: UNSET VOLATILE: Service: tidalconnect Oct 31 20:02:06 volumio volumio[994]: info: unSetVolatile called Oct 31 20:02:06 volumio volumio[994]: info: Relinquishing Volumio State to another service Oct 31 20:02:06 volumio vtcs[2143]: [2024-10-31 20:02:06.473] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Oct 31 20:02:06 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:02:06 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:02:06 volumio volumio[994]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 31 20:02:06 volumio vtcs[2143]: [2024-10-31 20:02:06] [info] asio async_shutdown error: asio.misc:2 (End of file) Oct 31 20:02:11 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 31 20:02:11 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 31 20:02:11 volumio volumio[994]: info: Discovery: Getting this device information Oct 31 20:02:11 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Oct 31 20:02:11 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:02:11 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 31 20:02:12 volumio volumio[994]: info: CoreCommandRouter::volumioClearQueue Oct 31 20:02:12 volumio volumio[994]: info: CoreStateMachine::ClearQueue Oct 31 20:02:12 volumio volumio[994]: info: CoreStateMachine::stop Oct 31 20:02:12 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 20:02:12 volumio volumio[994]: info: CoreStateMachine::stPlaybackTimer Oct 31 20:02:12 volumio volumio[994]: info: CoreStateMachine::updateTrackBlock Oct 31 20:02:12 volumio volumio[994]: info: CorePlayQueue::getTrackBlock Oct 31 20:02:12 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:02:12 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:02:12 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 20:02:12 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:02:12 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:02:12 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:02:12 volumio volumio[994]: info: CoreStateMachine::serviceStop Oct 31 20:02:12 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:02:12 volumio volumio[994]: info: CoreCommandRouter::serviceStop Oct 31 20:02:12 volumio volumio[994]: info: ControllerMpd::stop Oct 31 20:02:12 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 20:02:12 volumio volumio[994]: info: CorePlayQueue::clearPlayQueue Oct 31 20:02:12 volumio volumio[994]: info: CorePlayQueue::saveQueue Oct 31 20:02:12 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:02:12 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:02:12 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:02:12 volumio volumio[994]: info: CoreCommandRouter::volumioPushQueue Oct 31 20:02:12 volumio volumio[994]: info: sendMpdCommand stop took 8 milliseconds Oct 31 20:02:13 volumio volumio[994]: info: CoreCommandRouter::volumioClearQueue Oct 31 20:02:13 volumio volumio[994]: info: CoreStateMachine::ClearQueue Oct 31 20:02:13 volumio volumio[994]: info: CoreStateMachine::stop Oct 31 20:02:13 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 20:02:13 volumio volumio[994]: info: CorePlayQueue::clearPlayQueue Oct 31 20:02:13 volumio volumio[994]: info: CorePlayQueue::saveQueue Oct 31 20:02:13 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:02:13 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:02:13 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:02:13 volumio volumio[994]: info: CoreCommandRouter::volumioPushQueue Oct 31 20:02:15 volumio volumio[994]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 31 20:02:15 volumio volumio[994]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 31 20:02:15 volumio volumio[994]: info: Retrieving Cloud Streaming UI Oct 31 20:02:15 volumio volumio[994]: info: Getting Tidal Cloud Configuration Oct 31 20:02:15 volumio volumio[994]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 31 20:02:15 volumio volumio[994]: info: Getting Qobuz Cloud Configuration Oct 31 20:02:15 volumio volumio[994]: info: Asking plugin for UI Config Oct 31 20:02:15 volumio volumio[994]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 31 20:02:15 volumio volumio[994]: info: Getting Spotify Cloud Configuration Oct 31 20:02:15 volumio volumio[994]: info: Asking plugin for UI Config Oct 31 20:02:15 volumio volumio[994]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 31 20:02:15 volumio volumio[994]: info: Saving Spotify Acccount Oct 31 20:02:15 volumio volumio[994]: info: Got it Oct 31 20:02:15 volumio volumio[994]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 31 20:02:15 volumio volumio[994]: info: Got Tidal Cloud Configuration Oct 31 20:02:15 volumio volumio[994]: info: Got it Oct 31 20:02:15 volumio volumio[994]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 31 20:02:15 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 31 20:02:15 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 31 20:02:15 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 31 20:02:15 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 31 20:02:15 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 31 20:02:15 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 31 20:02:15 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 31 20:02:15 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 31 20:02:15 volumio volumio[994]: info: CoreCommandRouter::volumioGetBrowseSources Oct 31 20:02:15 volumio volumio[994]: info: CoreCommandRouter::volumioGetBrowseSources Oct 31 20:02:15 volumio volumio[994]: info: CoreCommandRouter::volumioGetBrowseSources Oct 31 20:02:16 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 31 20:02:16 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 31 20:02:19 volumio volumio[994]: info: Disabling MyMusic plugin tidalconnect Oct 31 20:02:19 volumio volumio[994]: info: [MyVolumio PluginManager] Disabling and stopping plugin music_service tidalconnect Oct 31 20:02:19 volumio vtcs[2143]: [2024-10-31 20:02:19.202] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Oct 31 20:02:19 volumio vtcs[2143]: [2024-10-31 20:02:19.202] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Oct 31 20:02:19 volumio volumio[994]: info: Disabling plugin tidalconnect Oct 31 20:02:19 volumio sudo[8284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 31 20:02:19 volumio sudo[8284]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:02:19 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Oct 31 20:02:19 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 31 20:02:19 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 31 20:02:19 volumio systemd[1]: vtcs.service: Succeeded. Oct 31 20:02:19 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Oct 31 20:02:19 volumio sudo[8284]: pam_unix(sudo:session): session closed for user root Oct 31 20:02:20 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 31 20:02:21 volumio volumio[994]: info: Enabling MyMusic plugin tidalconnect Oct 31 20:02:21 volumio volumio[994]: info: [MyVolumio PluginManager] Enabling and starting plugin music_service tidalconnect Oct 31 20:02:21 volumio volumio[994]: info: Enabling plugin tidalconnect Oct 31 20:02:21 volumio volumio[994]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Oct 31 20:02:21 volumio sudo[8289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 31 20:02:21 volumio sudo[8289]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:02:21 volumio sudo[8289]: pam_unix(sudo:session): session closed for user root Oct 31 20:02:22 volumio volumio[994]: info: TidalConnect service stoped! Oct 31 20:02:24 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 31 20:02:24 volumio volumio[994]: info: TidalConnect service stoped! Oct 31 20:02:24 volumio volumio[994]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 31 20:02:24 volumio volumio[994]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 31 20:02:24 volumio sudo[8351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 31 20:02:24 volumio sudo[8351]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:02:24 volumio systemd[1]: Started Volumio Tidal Connect Service. Oct 31 20:02:24 volumio sudo[8351]: pam_unix(sudo:session): session closed for user root Oct 31 20:02:25 volumio volumio[994]: info: Executing endpoint tc_getconfig Oct 31 20:02:25 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 31 20:02:25 volumio vtcs[8353]: STARTING TidalConnect services, version: 1.4.0.34 Oct 31 20:02:25 volumio vtcs[8353]: STARTED TidalConnect services. Oct 31 20:02:25 volumio volumio[994]: info: Executing endpoint tc_connect Oct 31 20:02:25 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 31 20:02:25 volumio volumio[994]: info: Connecting to TidalConnect Oct 31 20:02:25 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:02:25 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:02:25 volumio volumio[994]: verbose: STATE SERVICE {"status":"pause","service":"tidalconnect","title":"Symphony Of Destruction","artist":"Megadeth","album":"Countdown To Extinction (1992 Mix Remaster)","albumart":"https://resources.tidal.com/images/6c91b242/74be/433b/b176/d6d1f4147bbc/1280x1280.jpg","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"flac","seek":0,"duration":243,"samplerate":"192 KHz","bitdepth":"24 bit","bitrate":9216000,"channels":2,"random":false,"repeat":false,"repeatSingle":false,"stream":false,"repeatMode":"all"} Oct 31 20:02:25 volumio volumio[994]: verbose: CURRENT POSITION 0 Oct 31 20:02:25 volumio volumio[994]: info: CoreStateMachine::syncState stateService pause Oct 31 20:02:25 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Oct 31 20:02:25 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:02:25 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:02:25 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 20:02:25 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:02:25 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:02:25 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:02:25 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:02:25 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:02:25 volumio volumio[994]: verbose: STATE SERVICE {"status":"pause","service":"tidalconnect","title":"Symphony Of Destruction","artist":"Megadeth","album":"Countdown To Extinction (1992 Mix Remaster)","albumart":"https://resources.tidal.com/images/6c91b242/74be/433b/b176/d6d1f4147bbc/1280x1280.jpg","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"flac","seek":0,"duration":243,"samplerate":"192 KHz","bitdepth":"24 bit","bitrate":9216000,"channels":2,"random":false,"repeat":false,"repeatSingle":false,"stream":false,"repeatMode":"all"} Oct 31 20:02:25 volumio volumio[994]: verbose: CURRENT POSITION 0 Oct 31 20:02:25 volumio volumio[994]: info: CoreStateMachine::syncState stateService pause Oct 31 20:02:25 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus stop Oct 31 20:02:25 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:02:25 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:02:25 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:02:25 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:02:25 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:02:27 volumio volumio[994]: info: TidalConnect service started! Oct 31 20:02:27 volumio volumio[994]: info: Done. Oct 31 20:02:37 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Oct 31 20:02:37 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:02:37 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Oct 31 20:02:37 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:02:37 volumio volumio[994]: info: Currently active: mpd Oct 31 20:02:37 volumio volumio[994]: info: Stopping currently active service Oct 31 20:02:37 volumio volumio[994]: info: CoreCommandRouter::volumioStop Oct 31 20:02:37 volumio volumio[994]: info: CoreStateMachine::stop Oct 31 20:02:37 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 20:02:37 volumio volumio[994]: info: TidalConnect Active Oct 31 20:02:37 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Oct 31 20:02:37 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:02:37 volumio volumio[994]: info: Currently active: mpd Oct 31 20:02:37 volumio volumio[994]: info: Setting Volatile state to tidalconnect Oct 31 20:02:37 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 20:02:37 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:02:37 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:02:37 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 20:02:37 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:02:37 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:02:37 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:02:37 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:02:37 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:02:37 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:02:37 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:02:37 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:02:38 volumio vtcs[8353]: [2024-10-31 20:02:38.226] [tisoc] [warning] [PlaybackControllerImpl.cpp:510] Illegal state:1 Oct 31 20:02:38 volumio volumio[994]: info: Pushing metadata Oct 31 20:02:38 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:02:38 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:02:38 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:02:38 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:02:38 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:02:38 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:02:38 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:02:38 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:02:38 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:02:38 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:02:38 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:02:38 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:02:38 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:02:38 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:02:38 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:02:39 volumio volumio[994]: info: Pushing metadata Oct 31 20:02:39 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:02:39 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:02:39 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:02:39 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:02:39 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:02:39 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:02:39 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:02:39 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:02:39 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:02:39 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:02:39 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:02:39 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:02:39 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:02:39 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:02:39 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:02:39 volumio vtcs[8353]: [2024-10-31 20:02:39.080] [tisoc] [error] [pcm_sink_alsa.cpp:469] Cannot use: 192000 rate, offered: 48000 Oct 31 20:02:39 volumio vtcs[8353]: [2024-10-31 20:02:39.080] [tisoc] [error] [pcm_sink_alsa.cpp:494] setHwParams FAILED: Invalid argument Oct 31 20:02:39 volumio vtcs[8353]: [2024-10-31 20:02:39.080] [tisoc] [error] [pcm_sink_alsa.cpp:124] AlsaSink::open() setHwParams FAILED: Invalid argument Oct 31 20:02:39 volumio vtcs[8353]: [2024-10-31 20:02:39.080] [tisoc] [error] [media_player_tidal.cpp:178] Could not open pcm sink: 3 Oct 31 20:02:39 volumio volumio[994]: info: Unknown command: notifyPlaybackError Oct 31 20:02:39 volumio volumio[994]: info: Executing endpoint metavolumio Oct 31 20:02:39 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 31 20:02:39 volumio volumio[994]: info: Executing endpoint metavolumio Oct 31 20:02:39 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 31 20:02:39 volumio volumio[994]: info: Executing endpoint metavolumio Oct 31 20:02:39 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 31 20:02:40 volumio vtcs[8353]: [2024-10-31 20:02:40.601] [tisoc] [warning] [PlaybackControllerImpl.cpp:214] Ignore play request because playback state is not STARTED. state_=5, playstate_=1, player_state_=0 Oct 31 20:02:40 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:02:40 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:02:40 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 20:02:40 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:02:40 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:02:40 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:02:41 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 31 20:02:41 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 31 20:02:41 volumio volumio[994]: info: Discovery: Getting this device information Oct 31 20:02:41 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Oct 31 20:02:41 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 31 20:02:58 volumio volumio[994]: info: Volume: {"mute":false} Oct 31 20:02:58 volumio volumio[994]: info: VolumeController::SetAlsaVolume99 Oct 31 20:02:58 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Oct 31 20:02:58 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:02:58 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:02:58 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:02:58 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:01 volumio volumio[994]: info: VolumeController::SetAlsaVolume- Oct 31 20:03:01 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Oct 31 20:03:01 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:03:01 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:01 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:01 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:01 volumio volumio[994]: info: VolumeController::SetAlsaVolume- Oct 31 20:03:01 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Oct 31 20:03:01 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:03:01 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:01 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:01 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:01 volumio volumio[994]: info: VolumeController::SetAlsaVolume- Oct 31 20:03:01 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Oct 31 20:03:01 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:03:01 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:01 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:01 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:01 volumio volumio[994]: info: VolumeController::SetAlsaVolume- Oct 31 20:03:01 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Oct 31 20:03:01 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:03:01 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:01 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:01 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:01 volumio volumio[994]: info: VolumeController::SetAlsaVolume- Oct 31 20:03:01 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Oct 31 20:03:01 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:03:01 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:01 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:01 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:11 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 31 20:03:11 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 31 20:03:11 volumio volumio[994]: info: Discovery: Getting this device information Oct 31 20:03:11 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Oct 31 20:03:11 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 31 20:03:12 volumio volumio[994]: info: Volume: {"mute":false} Oct 31 20:03:12 volumio volumio[994]: info: VolumeController::SetAlsaVolume98 Oct 31 20:03:12 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Oct 31 20:03:12 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:03:12 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:12 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:12 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:18 volumio vtcs[8353]: [2024-10-31 20:03:18.261] [tisoc] [warning] [SessionManagerImpl.cpp:177] No Session Oct 31 20:03:18 volumio volumio[994]: info: PlaybackInactive Oct 31 20:03:18 volumio volumio[994]: info: Device Session is_active: true Oct 31 20:03:18 volumio volumio[994]: info: DeviceInactive Oct 31 20:03:18 volumio volumio[994]: info: Relinquishing Volumio State Oct 31 20:03:18 volumio volumio[994]: verbose: UNSET VOLATILE: Service: tidalconnect Oct 31 20:03:18 volumio volumio[994]: info: unSetVolatile called Oct 31 20:03:18 volumio volumio[994]: info: Relinquishing Volumio State to another service Oct 31 20:03:18 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:03:18 volumio vtcs[8353]: [2024-10-31 20:03:18.261] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Oct 31 20:03:18 volumio vtcs[8353]: [2024-10-31 20:03:18.262] [tisoc] [warning] [PlaybackControllerImpl.cpp:510] Illegal state:0 Oct 31 20:03:18 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:03:18 volumio volumio[994]: verbose: STATE SERVICE {"status":"play","service":"tidalconnect","title":"Symphony Of Destruction","artist":"Megadeth","album":"Countdown To Extinction (1992 Mix Remaster)","albumart":"https://resources.tidal.com/images/6c91b242/74be/433b/b176/d6d1f4147bbc/1280x1280.jpg","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"flac","seek":0,"duration":243,"samplerate":"192 KHz","bitdepth":"24 bit","bitrate":9216000,"channels":2,"random":false,"repeat":false,"repeatSingle":false,"stream":false,"repeatMode":"all"} Oct 31 20:03:18 volumio volumio[994]: verbose: CURRENT POSITION 0 Oct 31 20:03:18 volumio volumio[994]: info: CoreStateMachine::syncState stateService play Oct 31 20:03:18 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus play Oct 31 20:03:18 volumio volumio[994]: info: Received an update from plugin. extracting info from payload Oct 31 20:03:18 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:03:18 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:03:18 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 20:03:18 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:18 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:18 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:18 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:03:18 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:03:18 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:18 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:18 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:18 volumio vtcs[8353]: [2024-10-31 20:03:18] [info] asio async_shutdown error: asio.misc:2 (End of file) Oct 31 20:03:25 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Oct 31 20:03:25 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:03:25 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Oct 31 20:03:25 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:03:25 volumio volumio[994]: info: Currently active: mpd Oct 31 20:03:25 volumio volumio[994]: info: Stopping currently active service Oct 31 20:03:25 volumio volumio[994]: info: CoreCommandRouter::volumioStop Oct 31 20:03:25 volumio volumio[994]: info: CoreStateMachine::stop Oct 31 20:03:25 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 20:03:25 volumio volumio[994]: info: CoreStateMachine::stPlaybackTimer Oct 31 20:03:25 volumio volumio[994]: info: CoreStateMachine::updateTrackBlock Oct 31 20:03:25 volumio volumio[994]: info: CorePlayQueue::getTrackBlock Oct 31 20:03:25 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:03:25 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:03:25 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:25 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:25 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:25 volumio volumio[994]: info: CoreStateMachine::serviceStop Oct 31 20:03:25 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:03:25 volumio volumio[994]: info: ControllerMpd::stop Oct 31 20:03:25 volumio volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 20:03:25 volumio volumio[994]: info: sendMpdCommand stop took 2 milliseconds Oct 31 20:03:25 volumio volumio[994]: info: TidalConnect Active Oct 31 20:03:25 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Oct 31 20:03:25 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:03:25 volumio volumio[994]: info: Currently active: mpd Oct 31 20:03:25 volumio volumio[994]: info: Setting Volatile state to tidalconnect Oct 31 20:03:25 volumio volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 20:03:25 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:03:25 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:03:25 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 20:03:25 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:25 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:25 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:25 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:03:25 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:03:25 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:25 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:25 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:25 volumio vtcs[8353]: [2024-10-31 20:03:25.491] [tisoc] [warning] [PlaybackControllerImpl.cpp:510] Illegal state:1 Oct 31 20:03:25 volumio vtcs[8353]: [2024-10-31 20:03:25.491] [tisoc] [warning] [PlaybackControllerImpl.cpp:214] Ignore play request because playback state is not STARTED. state_=2, playstate_=1, player_state_=0 Oct 31 20:03:25 volumio volumio[994]: info: Pushing metadata Oct 31 20:03:25 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:03:25 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:03:25 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:25 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:25 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:25 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:03:25 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:03:25 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 20:03:25 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:25 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:25 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:25 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:03:25 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:03:25 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:25 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:25 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:25 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:03:25 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:03:25 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 20:03:25 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:25 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:25 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:26 volumio volumio[994]: info: Pushing metadata Oct 31 20:03:26 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:03:26 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:03:26 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:26 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:26 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:26 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:03:26 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:03:26 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:26 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:26 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:26 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:03:26 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:03:26 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 20:03:26 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:26 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:26 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:26 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:03:26 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:03:26 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 20:03:26 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:26 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:26 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:26 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:03:26 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:03:26 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:26 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:26 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:26 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:03:26 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:03:26 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:26 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:26 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:26 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio volumio[994]: info: Executing endpoint metavolumio Oct 31 20:03:27 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 31 20:03:27 volumio volumio[994]: info: Executing endpoint metavolumio Oct 31 20:03:27 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 31 20:03:27 volumio volumio[994]: info: Executing endpoint metavolumio Oct 31 20:03:27 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 31 20:03:27 volumio vtcs[8353]: [2024-10-31 20:03:27.350] [tisoc] [error] [pcm_sink_alsa.cpp:114] Failed to open device volumio, error: Device or resource busy Oct 31 20:03:27 volumio vtcs[8353]: [2024-10-31 20:03:27.350] [tisoc] [error] [media_player_tidal.cpp:178] Could not open pcm sink: 1 Oct 31 20:03:27 volumio volumio[994]: info: Unknown command: notifyPlaybackError Oct 31 20:03:27 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:03:27 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:03:27 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 20:03:27 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:27 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:27 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:35 volumio volumio[994]: info: CoreCommandRouter::volumioClearQueue Oct 31 20:03:35 volumio volumio[994]: info: CoreStateMachine::ClearQueue Oct 31 20:03:35 volumio volumio[994]: info: CoreStateMachine::stop Oct 31 20:03:35 volumio volumio[994]: info: CoreStateMachine::serviceStop Oct 31 20:03:35 volumio volumio[994]: info: CoreCommandRouter::serviceStop Oct 31 20:03:35 volumio volumio[994]: info: CorePlayQueue::clearPlayQueue Oct 31 20:03:35 volumio volumio[994]: info: CorePlayQueue::saveQueue Oct 31 20:03:35 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:35 volumio vtcs[8353]: [2024-10-31 20:03:35.579] [tisoc] [warning] [PlaybackControllerImpl.cpp:510] Illegal state:0 Oct 31 20:03:35 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:35 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:35 volumio volumio[994]: info: CoreCommandRouter::volumioPushQueue Oct 31 20:03:35 volumio volumio[994]: info: PlaybackInactive Oct 31 20:03:35 volumio volumio[994]: info: Device Session is_active: true Oct 31 20:03:35 volumio volumio[994]: info: DeviceInactive Oct 31 20:03:35 volumio volumio[994]: info: Relinquishing Volumio State Oct 31 20:03:35 volumio volumio[994]: verbose: UNSET VOLATILE: Service: tidalconnect Oct 31 20:03:35 volumio volumio[994]: info: unSetVolatile called Oct 31 20:03:35 volumio volumio[994]: info: Relinquishing Volumio State to another service Oct 31 20:03:35 volumio vtcs[8353]: [2024-10-31 20:03:35.583] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Oct 31 20:03:35 volumio vtcs[8353]: [2024-10-31 20:03:35] [info] asio async_shutdown error: asio.misc:2 (End of file) Oct 31 20:03:35 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 31 20:03:35 volumio volumio[994]: info: Received Get System Info Oct 31 20:03:35 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 31 20:03:35 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 31 20:03:35 volumio volumio[994]: info: Discovery: Getting this device information Oct 31 20:03:35 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Oct 31 20:03:35 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:03:35 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 31 20:03:38 volumio volumio[994]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 31 20:03:38 volumio volumio[994]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 31 20:03:38 volumio volumio[994]: info: Retrieving Cloud Streaming UI Oct 31 20:03:38 volumio volumio[994]: info: Getting Tidal Cloud Configuration Oct 31 20:03:38 volumio volumio[994]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 31 20:03:38 volumio volumio[994]: info: Getting Qobuz Cloud Configuration Oct 31 20:03:38 volumio volumio[994]: info: Asking plugin for UI Config Oct 31 20:03:38 volumio volumio[994]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 31 20:03:38 volumio volumio[994]: info: Getting Spotify Cloud Configuration Oct 31 20:03:38 volumio volumio[994]: info: Asking plugin for UI Config Oct 31 20:03:38 volumio volumio[994]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 31 20:03:38 volumio volumio[994]: info: Saving Spotify Acccount Oct 31 20:03:38 volumio volumio[994]: info: Got it Oct 31 20:03:38 volumio volumio[994]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 31 20:03:38 volumio volumio[994]: info: Got Tidal Cloud Configuration Oct 31 20:03:38 volumio volumio[994]: info: Got it Oct 31 20:03:38 volumio volumio[994]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 31 20:03:38 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 31 20:03:38 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 31 20:03:38 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 31 20:03:38 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 31 20:03:38 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 31 20:03:38 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 31 20:03:38 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 31 20:03:38 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 31 20:03:38 volumio volumio[994]: info: CoreCommandRouter::volumioGetBrowseSources Oct 31 20:03:38 volumio volumio[994]: info: CoreCommandRouter::volumioGetBrowseSources Oct 31 20:03:38 volumio volumio[994]: info: CoreCommandRouter::volumioGetBrowseSources Oct 31 20:03:40 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 31 20:03:40 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 31 20:03:41 volumio volumio[994]: info: Disabling MyMusic plugin multiroom Oct 31 20:03:41 volumio volumio[994]: info: [MyVolumio PluginManager] Disabling and stopping plugin audio_interface multiroom Oct 31 20:03:41 volumio volumio[994]: info: Disabling plugin multiroom Oct 31 20:03:41 volumio volumio[994]: info: Preparing to generate the ALSA configuration file Oct 31 20:03:41 volumio volumio[994]: info: Asound.conf file written Oct 31 20:03:41 volumio sudo[8459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 31 20:03:41 volumio sudo[8459]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:41 volumio sudo[8459]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:41 volumio volumio[994]: No protocol specified Oct 31 20:03:41 volumio volumio[994]: xcb_connection_has_error() returned true Oct 31 20:03:41 volumio volumio[994]: info: Output device has changed, restarting MPD Oct 31 20:03:41 volumio sudo[8464]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 31 20:03:41 volumio sudo[8464]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:41 volumio sudo[8464]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:41 volumio volumio[994]: info: Output device has changed, restarting Shairport Sync Oct 31 20:03:41 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:41 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 31 20:03:41 volumio sudo[8467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 31 20:03:41 volumio sudo[8467]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:41 volumio systemd[1]: musicservicesshield.service: Succeeded. Oct 31 20:03:41 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Oct 31 20:03:41 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Oct 31 20:03:41 volumio systemd[1]: Stopping Music Player Daemon... Oct 31 20:03:41 volumio systemd[1]: mpd.service: Succeeded. Oct 31 20:03:41 volumio systemd[1]: Stopped Music Player Daemon. Oct 31 20:03:41 volumio volumio[994]: No protocol specified Oct 31 20:03:41 volumio volumio[994]: xcb_connection_has_error() returned true Oct 31 20:03:41 volumio volumio[994]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 31 20:03:41 volumio volumio[994]: No protocol specified Oct 31 20:03:41 volumio volumio[994]: xcb_connection_has_error() returned true Oct 31 20:03:41 volumio volumio[994]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 31 20:03:41 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:41 volumio vtcs[8353]: [2024-10-31 20:03:41.907] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Oct 31 20:03:41 volumio vtcs[8353]: [2024-10-31 20:03:41.907] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Oct 31 20:03:41 volumio systemd[1]: Starting Music Player Daemon... Oct 31 20:03:41 volumio sudo[8477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 31 20:03:41 volumio sudo[8477]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:41 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Oct 31 20:03:41 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 31 20:03:41 volumio systemd[1]: vtcs.service: Succeeded. Oct 31 20:03:41 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Oct 31 20:03:41 volumio sudo[8477]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:41 volumio sudo[8472]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 31 20:03:41 volumio sudo[8472]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:41 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 31 20:03:41 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 31 20:03:41 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 31 20:03:41 volumio sudo[8472]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:41 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 31 20:03:41 volumio sudo[8481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 31 20:03:41 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 31 20:03:41 volumio sudo[8481]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:41 volumio volumio[994]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Oct 31 20:03:41 volumio volumio[994]: info: MPD Permissions set Oct 31 20:03:41 volumio sudo[8481]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:41 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Oct 31 20:03:41 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:03:41 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 31 20:03:41 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 31 20:03:41 volumio volumio[994]: info: Discovery: Getting this device information Oct 31 20:03:41 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Oct 31 20:03:41 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:03:41 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 31 20:03:41 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:41 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:41 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:41 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:41 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:41 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:41 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:41 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 31 20:03:41 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 31 20:03:41 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:41 volumio volumio[994]: info: Starting Shairport Sync Oct 31 20:03:41 volumio volumio[994]: info: Preparing to generate the ALSA configuration file Oct 31 20:03:41 volumio sudo[8493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 31 20:03:41 volumio sudo[8493]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:41 volumio volumio[994]: info: Asound.conf file unchanged, so no further update is needed Oct 31 20:03:41 volumio volumio[994]: info: Output device has changed, restarting MPD Oct 31 20:03:41 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 31 20:03:41 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 31 20:03:41 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 31 20:03:41 volumio sudo[8497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 31 20:03:41 volumio sudo[8497]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:42 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 31 20:03:42 volumio volumio[994]: info: Output device has changed, restarting Shairport Sync Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 31 20:03:42 volumio volumio[994]: No protocol specified Oct 31 20:03:42 volumio volumio[994]: xcb_connection_has_error() returned true Oct 31 20:03:42 volumio volumio[994]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 31 20:03:42 volumio volumio[994]: No protocol specified Oct 31 20:03:42 volumio volumio[994]: xcb_connection_has_error() returned true Oct 31 20:03:41 volumio sudo[8497]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:42 volumio sudo[8500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 31 20:03:42 volumio sudo[8500]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:42 volumio volumio[994]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:42 volumio sudo[8493]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:42 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 31 20:03:42 volumio systemd[1]: mpd.service: Succeeded. Oct 31 20:03:42 volumio systemd[1]: Stopped Music Player Daemon. Oct 31 20:03:42 volumio systemd[1]: Starting Music Player Daemon... Oct 31 20:03:42 volumio sudo[8511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 31 20:03:42 volumio sudo[8511]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:42 volumio sudo[8506]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 31 20:03:42 volumio sudo[8506]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:42 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 31 20:03:42 volumio sudo[8506]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:42 volumio sudo[8511]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:42 volumio sudo[8514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 31 20:03:42 volumio sudo[8514]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:42 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 31 20:03:42 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 31 20:03:42 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 31 20:03:42 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 31 20:03:42 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 31 20:03:42 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 31 20:03:42 volumio volumio[994]: info: MPD Permissions set Oct 31 20:03:42 volumio volumio[994]: info: Shairport-Sync Started Oct 31 20:03:42 volumio sudo[8514]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:42 volumio volumio[994]: info: Starting Shairport Sync Oct 31 20:03:42 volumio volumio[994]: info: Preparing to generate the ALSA configuration file Oct 31 20:03:42 volumio sudo[8527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 31 20:03:42 volumio sudo[8527]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:42 volumio volumio[994]: info: Asound.conf file unchanged, so no further update is needed Oct 31 20:03:42 volumio volumio[994]: info: Output device has changed, restarting MPD Oct 31 20:03:42 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 31 20:03:42 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 31 20:03:42 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 31 20:03:42 volumio sudo[8531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 31 20:03:42 volumio sudo[8531]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:42 volumio volumio[994]: info: Output device has changed, restarting Shairport Sync Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 31 20:03:42 volumio volumio[994]: No protocol specified Oct 31 20:03:42 volumio volumio[994]: xcb_connection_has_error() returned true Oct 31 20:03:42 volumio volumio[994]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 31 20:03:42 volumio volumio[994]: No protocol specified Oct 31 20:03:42 volumio volumio[994]: xcb_connection_has_error() returned true Oct 31 20:03:42 volumio volumio[994]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:42 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 31 20:03:42 volumio sudo[8531]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:42 volumio sudo[8534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 31 20:03:42 volumio sudo[8534]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:42 volumio sudo[8527]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:42 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 31 20:03:42 volumio systemd[1]: mpd.service: Succeeded. Oct 31 20:03:42 volumio systemd[1]: Stopped Music Player Daemon. Oct 31 20:03:42 volumio systemd[1]: Starting Music Player Daemon... Oct 31 20:03:42 volumio sudo[8545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 31 20:03:42 volumio sudo[8545]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:42 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 31 20:03:42 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 31 20:03:42 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 31 20:03:42 volumio sudo[8545]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:42 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 31 20:03:42 volumio sudo[8540]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 31 20:03:42 volumio sudo[8548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 31 20:03:42 volumio sudo[8548]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:42 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 31 20:03:42 volumio sudo[8540]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:42 volumio volumio[994]: info: MPD Permissions set Oct 31 20:03:42 volumio volumio[994]: info: Shairport-Sync Started Oct 31 20:03:42 volumio sudo[8540]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:42 volumio sudo[8548]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 31 20:03:42 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:42 volumio volumio[994]: info: Starting Shairport Sync Oct 31 20:03:42 volumio sudo[8562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 31 20:03:42 volumio sudo[8562]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:42 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 31 20:03:42 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 31 20:03:42 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 31 20:03:42 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 31 20:03:42 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 31 20:03:42 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 31 20:03:42 volumio sudo[8562]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:42 volumio volumio[994]: info: Shairport-Sync Started Oct 31 20:03:42 volumio mpd[8553]: Oct 31 20:03 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 31 20:03:42 volumio systemd[1]: Started Music Player Daemon. Oct 31 20:03:42 volumio sudo[8534]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:42 volumio sudo[8467]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:42 volumio sudo[8500]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:42 volumio volumio[994]: error: MPD error: The expression evaluated to a falsy value: Oct 31 20:03:42 volumio volumio[994]: assert.ok(self.idling) Oct 31 20:03:42 volumio volumio[994]: error: The expression evaluated to a falsy value: Oct 31 20:03:42 volumio volumio[994]: assert.ok(self.idling) Oct 31 20:03:42 volumio volumio[994]: error: MPD error: The expression evaluated to a falsy value: Oct 31 20:03:42 volumio volumio[994]: assert.ok(self.idling) Oct 31 20:03:42 volumio volumio[994]: error: The expression evaluated to a falsy value: Oct 31 20:03:42 volumio volumio[994]: assert.ok(self.idling) Oct 31 20:03:42 volumio volumio[994]: error: updateQueue error: null Oct 31 20:03:42 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Oct 31 20:03:42 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 31 20:03:42 volumio sudo[8583]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/cset shield -r Oct 31 20:03:42 volumio sudo[8583]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:42 volumio sudo[8583]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:42 volumio volumio[8569]: cset: **> shielding not active on system Oct 31 20:03:42 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Oct 31 20:03:42 volumio volumio[994]: info: Enabling MyMusic plugin multiroom Oct 31 20:03:42 volumio volumio[994]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface multiroom Oct 31 20:03:42 volumio volumio[994]: info: Enabling plugin multiroom Oct 31 20:03:42 volumio volumio[994]: info: Preparing to generate the ALSA configuration file Oct 31 20:03:42 volumio volumio[994]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 31 20:03:42 volumio volumio[994]: info: Reading ALSA contributions from plugins. Oct 31 20:03:42 volumio volumio[994]: info: Asound.conf file written Oct 31 20:03:42 volumio sudo[8589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 31 20:03:42 volumio sudo[8589]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:42 volumio sudo[8589]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:42 volumio volumio[994]: No protocol specified Oct 31 20:03:42 volumio volumio[994]: xcb_connection_has_error() returned true Oct 31 20:03:42 volumio volumio[994]: info: Output device has changed, restarting MPD Oct 31 20:03:43 volumio sudo[8594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 31 20:03:43 volumio sudo[8594]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:43 volumio sudo[8594]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:43 volumio volumio[994]: info: Output device has changed, restarting Shairport Sync Oct 31 20:03:43 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:43 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 31 20:03:43 volumio sudo[8597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 31 20:03:43 volumio sudo[8597]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:43 volumio systemd[1]: musicservicesshield.service: Succeeded. Oct 31 20:03:43 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Oct 31 20:03:43 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Oct 31 20:03:43 volumio systemd[1]: Stopping Music Player Daemon... Oct 31 20:03:43 volumio systemd[1]: mpd.service: Succeeded. Oct 31 20:03:43 volumio systemd[1]: Stopped Music Player Daemon. Oct 31 20:03:43 volumio volumio[994]: No protocol specified Oct 31 20:03:43 volumio volumio[994]: xcb_connection_has_error() returned true Oct 31 20:03:43 volumio volumio[994]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 31 20:03:43 volumio volumio[994]: No protocol specified Oct 31 20:03:43 volumio volumio[994]: xcb_connection_has_error() returned true Oct 31 20:03:43 volumio volumio[994]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 31 20:03:43 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:43 volumio volumio[994]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Oct 31 20:03:43 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 31 20:03:43 volumio systemd[1]: Starting Music Player Daemon... Oct 31 20:03:43 volumio sudo[8606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 31 20:03:43 volumio sudo[8606]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:43 volumio volumio[994]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 31 20:03:43 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:43 volumio sudo[8606]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:43 volumio sudo[8602]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 31 20:03:43 volumio sudo[8602]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:43 volumio sudo[8602]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:43 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 31 20:03:43 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 31 20:03:43 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 31 20:03:43 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 31 20:03:43 volumio sudo[8613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 31 20:03:43 volumio sudo[8613]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:43 volumio volumio[994]: info: MPD Permissions set Oct 31 20:03:43 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Oct 31 20:03:43 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:03:43 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:43 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:43 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:43 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:43 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:43 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:43 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:43 volumio sudo[8613]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:43 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 31 20:03:43 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 31 20:03:43 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 20:03:43 volumio volumio[994]: info: Done. Oct 31 20:03:43 volumio volumio[994]: info: Starting Shairport Sync Oct 31 20:03:43 volumio sudo[8626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 31 20:03:43 volumio sudo[8626]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:43 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 31 20:03:43 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 31 20:03:43 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 31 20:03:43 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 31 20:03:43 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 31 20:03:43 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 31 20:03:43 volumio sudo[8626]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:43 volumio volumio[994]: info: Shairport-Sync Started Oct 31 20:03:43 volumio mpd[8615]: Oct 31 20:03 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 31 20:03:43 volumio systemd[1]: Started Music Player Daemon. Oct 31 20:03:43 volumio sudo[8597]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:43 volumio volumio[994]: error: updateQueue error: null Oct 31 20:03:43 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Oct 31 20:03:43 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 31 20:03:43 volumio volumio[994]: info: MRS: Found cast device: Smart-TV-72abb2bb3235d3c6b86a1d4efc0ac2e3 Oct 31 20:03:43 volumio sudo[8647]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/cset shield -r Oct 31 20:03:43 volumio sudo[8647]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:43 volumio sudo[8647]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:43 volumio volumio[8633]: cset: **> shielding not active on system Oct 31 20:03:43 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Oct 31 20:03:44 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 31 20:03:44 volumio volumio[994]: info: TidalConnect service stoped! Oct 31 20:03:44 volumio volumio[994]: info: TidalConnect service stoped! Oct 31 20:03:44 volumio volumio[994]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 31 20:03:44 volumio volumio[994]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 31 20:03:44 volumio sudo[8659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 31 20:03:44 volumio sudo[8659]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:45 volumio systemd[1]: Started Volumio Tidal Connect Service. Oct 31 20:03:45 volumio sudo[8659]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:45 volumio volumio[994]: info: Executing endpoint tc_getconfig Oct 31 20:03:45 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 31 20:03:45 volumio vtcs[8661]: STARTING TidalConnect services, version: 1.4.0.34 Oct 31 20:03:45 volumio volumio[994]: info: TidalConnect service stoped! Oct 31 20:03:45 volumio vtcs[8661]: STARTED TidalConnect services. Oct 31 20:03:45 volumio volumio[994]: info: Executing endpoint tc_connect Oct 31 20:03:45 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 31 20:03:45 volumio volumio[994]: info: Connecting to TidalConnect Oct 31 20:03:45 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:03:45 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:03:45 volumio volumio[994]: verbose: STATE SERVICE {"status":"pause","service":"tidalconnect","title":"Symphony Of Destruction","artist":"Megadeth","album":"Countdown To Extinction (1992 Mix Remaster)","albumart":"https://resources.tidal.com/images/6c91b242/74be/433b/b176/d6d1f4147bbc/1280x1280.jpg","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"flac","seek":0,"duration":243,"samplerate":"192 KHz","bitdepth":"24 bit","bitrate":9216000,"channels":2,"random":false,"repeat":false,"repeatSingle":false,"stream":false,"repeatMode":"all"} Oct 31 20:03:45 volumio volumio[994]: verbose: CURRENT POSITION 0 Oct 31 20:03:45 volumio volumio[994]: info: CoreStateMachine::syncState stateService pause Oct 31 20:03:45 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus pause Oct 31 20:03:45 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:03:45 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:03:45 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 20:03:45 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:45 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:45 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:45 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:45 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:45 volumio volumio[994]: info: CoreStateMachine::stPlaybackTimer Oct 31 20:03:45 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:03:45 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:03:45 volumio volumio[994]: verbose: STATE SERVICE {"status":"pause","service":"tidalconnect","title":"Symphony Of Destruction","artist":"Megadeth","album":"Countdown To Extinction (1992 Mix Remaster)","albumart":"https://resources.tidal.com/images/6c91b242/74be/433b/b176/d6d1f4147bbc/1280x1280.jpg","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"flac","seek":0,"duration":243,"samplerate":"192 KHz","bitdepth":"24 bit","bitrate":9216000,"channels":2,"random":false,"repeat":false,"repeatSingle":false,"stream":false,"repeatMode":"all"} Oct 31 20:03:45 volumio volumio[994]: verbose: CURRENT POSITION 0 Oct 31 20:03:45 volumio volumio[994]: info: CoreStateMachine::syncState stateService pause Oct 31 20:03:45 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus pause Oct 31 20:03:45 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:03:45 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:03:45 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:45 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:45 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:45 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:45 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:45 volumio volumio[994]: info: CoreStateMachine::stPlaybackTimer Oct 31 20:03:45 volumio volumio[994]: info: TidalConnect service stoped! Oct 31 20:03:45 volumio volumio[994]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 31 20:03:45 volumio volumio[994]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 31 20:03:45 volumio sudo[8689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 31 20:03:45 volumio sudo[8689]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:45 volumio sudo[8689]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:45 volumio volumio[994]: info: TidalConnect service stoped! Oct 31 20:03:45 volumio volumio[994]: info: TidalConnect service stoped! Oct 31 20:03:45 volumio volumio[994]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 31 20:03:45 volumio volumio[994]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 31 20:03:45 volumio sudo[8701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 31 20:03:45 volumio sudo[8701]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:45 volumio sudo[8701]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:45 volumio volumio[994]: info: Disabling MyMusic plugin tidalconnect Oct 31 20:03:45 volumio volumio[994]: info: [MyVolumio PluginManager] Disabling and stopping plugin music_service tidalconnect Oct 31 20:03:45 volumio vtcs[8661]: [2024-10-31 20:03:45.264] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Oct 31 20:03:45 volumio vtcs[8661]: [2024-10-31 20:03:45.265] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Oct 31 20:03:45 volumio volumio[994]: info: Disabling plugin tidalconnect Oct 31 20:03:45 volumio sudo[8705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 31 20:03:45 volumio sudo[8705]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:45 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Oct 31 20:03:45 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 31 20:03:45 volumio systemd[1]: vtcs.service: Succeeded. Oct 31 20:03:45 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Oct 31 20:03:45 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 31 20:03:45 volumio sudo[8705]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:46 volumio volumio[994]: info: TidalConnect service stoped! Oct 31 20:03:46 volumio volumio[994]: info: TidalConnect service stoped! Oct 31 20:03:46 volumio volumio[994]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 31 20:03:46 volumio volumio[994]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 31 20:03:46 volumio sudo[8717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 31 20:03:46 volumio sudo[8717]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:46 volumio systemd[1]: Started Volumio Tidal Connect Service. Oct 31 20:03:46 volumio sudo[8717]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:46 volumio volumio[994]: info: Enabling MyMusic plugin tidalconnect Oct 31 20:03:46 volumio volumio[994]: info: [MyVolumio PluginManager] Enabling and starting plugin music_service tidalconnect Oct 31 20:03:46 volumio volumio[994]: info: Enabling plugin tidalconnect Oct 31 20:03:46 volumio volumio[994]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Oct 31 20:03:46 volumio sudo[8723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 31 20:03:46 volumio sudo[8723]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:46 volumio volumio[994]: info: Executing endpoint tc_getconfig Oct 31 20:03:46 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 31 20:03:46 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 31 20:03:46 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Oct 31 20:03:46 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 31 20:03:46 volumio systemd[1]: vtcs.service: Succeeded. Oct 31 20:03:46 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Oct 31 20:03:46 volumio sudo[8723]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:48 volumio volumio[994]: info: TidalConnect service started! Oct 31 20:03:48 volumio volumio[994]: info: MRS: Getting audio outputs on start Oct 31 20:03:48 volumio volumio[994]: info: MRS: Requesting all other devices output Oct 31 20:03:48 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 31 20:03:48 volumio volumio[994]: info: TidalConnect service started! Oct 31 20:03:48 volumio volumio[994]: info: TidalConnect service started! Oct 31 20:03:48 volumio volumio[994]: info: TidalConnect service stoped! Oct 31 20:03:48 volumio volumio[994]: info: Disabling MyMusic plugin upnp Oct 31 20:03:48 volumio sudo[8728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 31 20:03:48 volumio sudo[8728]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:48 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Oct 31 20:03:48 volumio volumio[994]: error: Upnp client error: Error: This socket has been ended by the other party Oct 31 20:03:48 volumio volumio[994]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 31 20:03:49 volumio volumio[994]: info: TidalConnect service started! Oct 31 20:03:49 volumio volumio[994]: info: TidalConnect service stoped! Oct 31 20:03:49 volumio volumio[994]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 31 20:03:49 volumio volumio[994]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 31 20:03:49 volumio sudo[8739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 31 20:03:49 volumio sudo[8739]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 20:03:49 volumio systemd[1]: Started Volumio Tidal Connect Service. Oct 31 20:03:49 volumio sudo[8739]: pam_unix(sudo:session): session closed for user root Oct 31 20:03:49 volumio volumio[994]: info: Executing endpoint tc_getconfig Oct 31 20:03:49 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 31 20:03:49 volumio vtcs[8741]: STARTING TidalConnect services, version: 1.4.0.34 Oct 31 20:03:49 volumio vtcs[8741]: STARTED TidalConnect services. Oct 31 20:03:49 volumio volumio[994]: info: Executing endpoint tc_connect Oct 31 20:03:49 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 31 20:03:49 volumio volumio[994]: info: Connecting to TidalConnect Oct 31 20:03:49 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:03:49 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:03:49 volumio volumio[994]: verbose: STATE SERVICE {"status":"pause","service":"tidalconnect","title":"Symphony Of Destruction","artist":"Megadeth","album":"Countdown To Extinction (1992 Mix Remaster)","albumart":"https://resources.tidal.com/images/6c91b242/74be/433b/b176/d6d1f4147bbc/1280x1280.jpg","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"flac","seek":0,"duration":243,"samplerate":"192 KHz","bitdepth":"24 bit","bitrate":9216000,"channels":2,"random":false,"repeat":false,"repeatSingle":false,"stream":false,"repeatMode":"all"} Oct 31 20:03:49 volumio volumio[994]: verbose: CURRENT POSITION 0 Oct 31 20:03:49 volumio volumio[994]: info: CoreStateMachine::syncState stateService pause Oct 31 20:03:49 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus pause Oct 31 20:03:49 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:03:49 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:03:49 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:49 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:49 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:49 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:49 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:49 volumio volumio[994]: info: CoreStateMachine::stPlaybackTimer Oct 31 20:03:49 volumio volumio[994]: info: CoreCommandRouter::servicePushState Oct 31 20:03:49 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:03:49 volumio volumio[994]: verbose: STATE SERVICE {"status":"pause","service":"tidalconnect","title":"Symphony Of Destruction","artist":"Megadeth","album":"Countdown To Extinction (1992 Mix Remaster)","albumart":"https://resources.tidal.com/images/6c91b242/74be/433b/b176/d6d1f4147bbc/1280x1280.jpg","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"flac","seek":0,"duration":243,"samplerate":"192 KHz","bitdepth":"24 bit","bitrate":9216000,"channels":2,"random":false,"repeat":false,"repeatSingle":false,"stream":false,"repeatMode":"all"} Oct 31 20:03:49 volumio volumio[994]: verbose: CURRENT POSITION 0 Oct 31 20:03:49 volumio volumio[994]: info: CoreStateMachine::syncState stateService pause Oct 31 20:03:49 volumio volumio[994]: info: CoreStateMachine::syncState currentStatus pause Oct 31 20:03:49 volumio volumio[994]: info: CoreStateMachine::pushState Oct 31 20:03:49 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 31 20:03:49 volumio volumio[994]: info: CoreCommandRouter::volumioPushState Oct 31 20:03:49 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:49 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:49 volumio volumio[994]: info: MRS: Pushing multiroomSync output update for this device Oct 31 20:03:49 volumio volumio[994]: info: MRS: Pushing multiroomSync output Oct 31 20:03:49 volumio volumio[994]: info: CoreStateMachine::stPlaybackTimer Oct 31 20:03:49 volumio volumio[994]: info: Enabling MyMusic plugin upnp Oct 31 20:03:49 volumio volumio[994]: info: Enabling plugin upnp Oct 31 20:03:49 volumio volumio[994]: info: Loading plugin "upnp"... Oct 31 20:03:49 volumio volumio[994]: info: [1730379829802] Starting Upmpd Daemon Oct 31 20:03:49 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 31 20:03:49 volumio volumio[994]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 31 20:03:49 volumio volumio[994]: Error: listen EADDRINUSE: address already in use :::6599 Oct 31 20:03:49 volumio volumio[994]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Oct 31 20:03:49 volumio volumio[994]: at listenInCluster (net.js:1379:12) Oct 31 20:03:49 volumio volumio[994]: at Server.listen (net.js:1465:7) Oct 31 20:03:49 volumio volumio[994]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 31 20:03:49 volumio volumio[994]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 31 20:03:49 volumio volumio[994]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 31 20:03:49 volumio volumio[994]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 31 20:03:49 volumio volumio[994]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Oct 31 20:03:49 volumio volumio[994]: code: 'EADDRINUSE', Oct 31 20:03:49 volumio volumio[994]: errno: -98, Oct 31 20:03:49 volumio volumio[994]: syscall: 'listen', Oct 31 20:03:49 volumio volumio[994]: address: '::', Oct 31 20:03:49 volumio volumio[994]: port: 6599 Oct 31 20:03:49 volumio volumio[994]: } Oct 31 20:03:49 volumio volumio[994]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 31 20:03:49 volumio sudo[8769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-31 20:02 Oct 31 20:03:49 volumio sudo[8769]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"