-- Logs begin at Wed 2024-11-27 16:33:42 EST, end at Wed 2024-11-27 19:28:41 EST. -- Nov 27 19:27:01 beat-box volumio[2750]: info: CoreCommandRouter::volumioGetState Nov 27 19:27:01 beat-box volumio[2750]: info: CorePlayQueue::getTrack 0 Nov 27 19:27:09 beat-box volumio[2750]: info: Airplay started streaming, receiving metadatas Nov 27 19:27:09 beat-box volumio[2750]: info: CorePlayQueue::getTrack 0 Nov 27 19:27:09 beat-box volumio[2750]: info: CoreCommandRouter::volumioStop Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::stop Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 19:27:09 beat-box volumio[2750]: info: Airplay started streaming, receiving metadatas Nov 27 19:27:09 beat-box volumio[2750]: info: CorePlayQueue::getTrack 0 Nov 27 19:27:09 beat-box volumio[2750]: info: CoreCommandRouter::volumioStop Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::stop Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 19:27:09 beat-box volumio[2750]: info: Airplay started streaming, receiving metadatas Nov 27 19:27:09 beat-box volumio[2750]: info: CorePlayQueue::getTrack 0 Nov 27 19:27:09 beat-box volumio[2750]: info: CoreCommandRouter::volumioStop Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::stop Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 19:27:09 beat-box volumio[2750]: info: Airplay started streaming, receiving metadatas Nov 27 19:27:09 beat-box volumio[2750]: info: CorePlayQueue::getTrack 0 Nov 27 19:27:09 beat-box volumio[2750]: info: CoreCommandRouter::volumioStop Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::stop Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 19:27:09 beat-box volumio[2750]: info: Airplay started streaming, receiving metadatas Nov 27 19:27:09 beat-box volumio[2750]: info: CorePlayQueue::getTrack 0 Nov 27 19:27:09 beat-box volumio[2750]: info: CoreCommandRouter::volumioStop Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::stop Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 19:27:09 beat-box volumio[2750]: info: Airplay started streaming, receiving metadatas Nov 27 19:27:09 beat-box volumio[2750]: info: CorePlayQueue::getTrack 0 Nov 27 19:27:09 beat-box volumio[2750]: info: CoreCommandRouter::volumioStop Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::stop Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 19:27:09 beat-box volumio[2750]: info: Airplay started streaming, receiving metadatas Nov 27 19:27:09 beat-box volumio[2750]: info: CorePlayQueue::getTrack 0 Nov 27 19:27:09 beat-box volumio[2750]: info: CoreCommandRouter::volumioStop Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::stop Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 19:27:09 beat-box volumio[2750]: info: Airplay started streaming, receiving metadatas Nov 27 19:27:09 beat-box volumio[2750]: info: CorePlayQueue::getTrack 0 Nov 27 19:27:09 beat-box volumio[2750]: info: CoreCommandRouter::volumioStop Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::stop Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 19:27:09 beat-box volumio[2750]: info: Airplay started streaming, receiving metadatas Nov 27 19:27:09 beat-box volumio[2750]: info: CorePlayQueue::getTrack 0 Nov 27 19:27:09 beat-box volumio[2750]: info: CoreCommandRouter::volumioStop Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::stop Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 19:27:09 beat-box volumio[2750]: info: Airplay started streaming, receiving metadatas Nov 27 19:27:09 beat-box volumio[2750]: info: CorePlayQueue::getTrack 0 Nov 27 19:27:09 beat-box volumio[2750]: info: CoreCommandRouter::volumioStop Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::stop Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 19:27:09 beat-box volumio[2750]: info: Airplay started streaming, receiving metadatas Nov 27 19:27:09 beat-box volumio[2750]: info: CorePlayQueue::getTrack 0 Nov 27 19:27:09 beat-box volumio[2750]: info: CoreCommandRouter::volumioStop Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::stop Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 19:27:09 beat-box shairport-sync[32103]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 27 19:27:09 beat-box shairport-sync[32103]: Dload Upload Total Spent Left Speed Nov 27 19:27:09 beat-box volumio[2750]: info: Nov 27 19:27:09 beat-box volumio[2750]: ---------------------------- Client requests Start Airplay PlaybackRoutine Nov 27 19:27:09 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Nov 27 19:27:09 beat-box volumio[2750]: info: Airplay playback start Nov 27 19:27:09 beat-box volumio[2750]: info: CorePlayQueue::getTrack 0 Nov 27 19:27:09 beat-box volumio[2750]: info: CoreCommandRouter::volumioStop Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::stop Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 19:27:09 beat-box volumio[2750]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 27 19:27:09 beat-box shairport-sync[32103]: [158B blob data] Nov 27 19:27:09 beat-box shairport-sync[32103]: {"time":1732753629052,"response":"startAirplayPlayback Success"}ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:257:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioOutput has buffer size 88200 Nov 27 19:27:09 beat-box shairport-sync[32103]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:268:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioOutput has buffer size 88200 Nov 27 19:27:09 beat-box shairport-sync[32103]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:257:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioOutput has buffer size 88200 Nov 27 19:27:09 beat-box shairport-sync[32103]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:268:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioOutput has buffer size 88200 Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:10 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 19:27:10 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:11 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:11 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:11 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:11 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:11 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:11 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:11 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:11 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:11 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:11 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:11 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:11 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:11 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:11 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:11 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:11 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:11 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:11 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:11 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:11 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:11 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:11 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:11 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:11 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:11 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:11 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:11 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:11 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:11 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:11 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:11 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:11 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:11 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:11 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:11 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:11 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:11 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:11 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:11 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:11 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:11 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:11 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:11 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:11 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:11 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:11 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:11 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:11 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:27:11 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:11 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:11 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:11 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:11 beat-box shairport-sync[32103]: Very large sync error: 4294967308 frames, with delay: 4295055508, td_in_frames: 3147, rt: 9207124035, nt: 4912078178, current_delay: 6504, seqno: 6353, given timestamp: 617110882. Nov 27 19:27:11 beat-box shairport-sync[32103]: Very large sync error: 4294967341 frames, with delay: 4295055541, td_in_frames: 3622, rt: 9207124035, nt: 4912078530, current_delay: 6414, seqno: 6354, given timestamp: 617111234. Nov 27 19:27:11 beat-box shairport-sync[32103]: Very large sync error: 4294967329 frames, with delay: 4295055529, td_in_frames: 3787, rt: 9207124035, nt: 4912078882, current_delay: 6589, seqno: 6355, given timestamp: 617111586. Nov 27 19:27:11 beat-box shairport-sync[32103]: Very large sync error: 4294967323 frames, with delay: 4295055523, td_in_frames: 4268, rt: 9207124035, nt: 4912079234, current_delay: 6454, seqno: 6356, given timestamp: 617111938. Nov 27 19:27:11 beat-box shairport-sync[32103]: Shome mhistake shurely: very large number of frames to drop: 4294967323 -- setting it to 132300. Nov 27 19:27:11 beat-box volumio[2750]: info: CoreCommandRouter::volumioGetState Nov 27 19:27:11 beat-box volumio[2750]: info: Listing playlists Nov 27 19:27:11 beat-box volumio[2750]: info: Listing playlists Nov 27 19:27:13 beat-box volumio[2750]: info: Executing endpoint metavolumio Nov 27 19:27:13 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 27 19:27:13 beat-box volumio[2750]: info: Executing endpoint metavolumio Nov 27 19:27:13 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 27 19:27:13 beat-box volumio[2750]: info: Executing endpoint metavolumio Nov 27 19:27:13 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 27 19:27:13 beat-box volumio[2750]: info: Executing endpoint metavolumio Nov 27 19:27:13 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 27 19:27:13 beat-box volumio[2750]: info: Executing endpoint metavolumio Nov 27 19:27:13 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 27 19:27:13 beat-box volumio[2750]: info: Executing endpoint metavolumio Nov 27 19:27:13 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 27 19:27:14 beat-box volumio[2750]: info: Executing endpoint metavolumio Nov 27 19:27:14 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 27 19:27:14 beat-box volumio[2750]: info: Executing endpoint metavolumio Nov 27 19:27:14 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 27 19:27:14 beat-box volumio[2750]: info: Executing endpoint metavolumio Nov 27 19:27:14 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 27 19:27:21 beat-box volumio[2750]: info: CoreCommandRouter::volumioGetState Nov 27 19:27:31 beat-box volumio[2750]: info: CoreCommandRouter::volumioGetState Nov 27 19:27:31 beat-box volumio[2750]: info: Listing playlists Nov 27 19:27:31 beat-box volumio[2750]: info: Listing playlists Nov 27 19:27:39 beat-box volumio[2750]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 27 19:27:39 beat-box volumio[2750]: No protocol specified Nov 27 19:27:39 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:27:39 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 27 19:27:39 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 19:27:39 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 27 19:27:39 beat-box volumio[2750]: No protocol specified Nov 27 19:27:39 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:27:39 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 19:27:39 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 19:27:39 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 19:27:39 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 19:27:39 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 19:27:39 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 19:27:39 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 19:27:39 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 27 19:27:39 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 27 19:27:41 beat-box volumio[2750]: info: CoreCommandRouter::volumioGetState Nov 27 19:27:46 beat-box volumio[2750]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Nov 27 19:27:46 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Nov 27 19:27:46 beat-box sudo[32705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 19:27:46 beat-box sudo[32705]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:46 beat-box sudo[32705]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:46 beat-box volumio[2750]: info: MPD Permissions set Nov 27 19:27:46 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:46 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:46 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:46 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:46 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:46 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:46 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:46 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:46 beat-box sudo[32709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 19:27:46 beat-box sudo[32709]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:46 beat-box systemd[1]: Stopping Music Player Daemon... Nov 27 19:27:46 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 19:27:46 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 27 19:27:46 beat-box systemd[1]: mpd.service: Succeeded. Nov 27 19:27:46 beat-box systemd[1]: Stopped Music Player Daemon. Nov 27 19:27:46 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:46 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 27 19:27:46 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 27 19:27:46 beat-box systemd[1]: Starting Music Player Daemon... Nov 27 19:27:46 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 27 19:27:46 beat-box sudo[32715]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 27 19:27:46 beat-box sudo[32715]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:46 beat-box sudo[32715]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:46 beat-box mpd[32719]: Nov 27 19:27 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 27 19:27:46 beat-box systemd[1]: Started Music Player Daemon. Nov 27 19:27:46 beat-box sudo[32709]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:46 beat-box volumio[2750]: error: updateQueue error: null Nov 27 19:27:51 beat-box volumio[2750]: info: CoreCommandRouter::volumioGetState Nov 27 19:27:51 beat-box volumio[2750]: info: Listing playlists Nov 27 19:27:51 beat-box volumio[2750]: info: Listing playlists Nov 27 19:27:57 beat-box volumio[2750]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::volumioGetState Nov 27 19:27:57 beat-box volumio[2750]: info: Restoring Previous Volume level: 41 false true Nov 27 19:27:57 beat-box volumio[2750]: info: VolumeController::SetAlsaVolume100 Nov 27 19:27:57 beat-box volumio[2750]: info: Enable softmixer device for audio device number 5 Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::volumioStop Nov 27 19:27:57 beat-box volumio[2750]: info: CoreStateMachine::stop Nov 27 19:27:57 beat-box volumio[2750]: info: CoreStateMachine::serviceStop Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::serviceStop Nov 27 19:27:57 beat-box volumio[2750]: info: Airplay Stop Nov 27 19:27:57 beat-box volumio[2750]: info: Stopping Airplay Playback and sending pause command to client via USR2 Nov 27 19:27:57 beat-box volumio[2750]: info: Enable softmixer device for audio device undefined Nov 27 19:27:57 beat-box sudo[335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 32103 Nov 27 19:27:57 beat-box sudo[335]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:57 beat-box sudo[335]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:57 beat-box volumio[2750]: No protocol specified Nov 27 19:27:57 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 27 19:27:57 beat-box volumio[2750]: info: Updating Volume Controller Parameters: Device: 5 Name: Digital Hifi Audio Mixer: Extension Unit Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 27 19:27:57 beat-box volumio[2750]: info: Disabling external Volume Control Nov 27 19:27:57 beat-box volumio[2750]: info: Output device has changed, restarting MPD Nov 27 19:27:57 beat-box sudo[340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 19:27:57 beat-box sudo[340]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:57 beat-box sudo[340]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:57 beat-box volumio[2750]: info: Output device has changed, restarting Shairport Sync Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 19:27:57 beat-box sudo[343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 19:27:57 beat-box sudo[343]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:57 beat-box systemd[1]: Stopping Music Player Daemon... Nov 27 19:27:57 beat-box systemd[1]: mpd.service: Succeeded. Nov 27 19:27:57 beat-box systemd[1]: Stopped Music Player Daemon. Nov 27 19:27:57 beat-box volumio[2750]: No protocol specified Nov 27 19:27:57 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:27:57 beat-box volumio[2750]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 19:27:57 beat-box volumio[2750]: No protocol specified Nov 27 19:27:57 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:27:57 beat-box volumio[2750]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:57 beat-box vtcs[32125]: [2024-11-27 19:27:57.599] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Nov 27 19:27:57 beat-box vtcs[32125]: [2024-11-27 19:27:57.599] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Nov 27 19:27:57 beat-box volumio[2750]: info: Volume configurations have been set Nov 27 19:27:57 beat-box volumio[2750]: No protocol specified Nov 27 19:27:57 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 27 19:27:57 beat-box volumio[2750]: info: Updating Volume Controller Parameters: Device: 5 Name: Digital Hifi Audio Mixer: Extension Unit Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 27 19:27:57 beat-box volumio[2750]: info: Disabling external Volume Control Nov 27 19:27:57 beat-box sudo[352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 27 19:27:57 beat-box systemd[1]: Starting Music Player Daemon... Nov 27 19:27:57 beat-box sudo[352]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:57 beat-box sudo[355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 27 19:27:57 beat-box sudo[355]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 27 19:27:57 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:57 beat-box systemd[1]: Stopping Volumio Tidal Connect Service... Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:57 beat-box systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Nov 27 19:27:57 beat-box systemd[1]: vtcs.service: Succeeded. Nov 27 19:27:57 beat-box systemd[1]: Stopped Volumio Tidal Connect Service. Nov 27 19:27:57 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:57 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:57 beat-box sudo[352]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:57 beat-box sudo[355]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:57 beat-box sudo[370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 27 19:27:57 beat-box sudo[370]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:57 beat-box sudo[348]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 27 19:27:57 beat-box sudo[348]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:57 beat-box sudo[348]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:57 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 27 19:27:57 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 27 19:27:57 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 27 19:27:57 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 27 19:27:57 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 27 19:27:57 beat-box sudo[374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 27 19:27:57 beat-box sudo[374]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:57 beat-box sudo[370]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:57 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:57 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:57 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:57 beat-box volumio[2750]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Nov 27 19:27:57 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:27:57 beat-box volumio[2750]: info: MPD Permissions set Nov 27 19:27:57 beat-box volumio[2750]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Nov 27 19:27:57 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:27:57 beat-box sudo[374]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:57 beat-box volumio[2750]: info: Shairport-Sync paused with USR2 Nov 27 19:27:57 beat-box volumio[2750]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Nov 27 19:27:57 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:27:57 beat-box volumio[2750]: info: Software Volume ALSA configuration written Nov 27 19:27:57 beat-box volumio[2750]: info: Preparing to generate the ALSA configuration file Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:57 beat-box volumio[2750]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 27 19:27:57 beat-box volumio[2750]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 27 19:27:57 beat-box volumio[2750]: info: Reading ALSA contributions from plugins. Nov 27 19:27:57 beat-box volumio[2750]: No protocol specified Nov 27 19:27:57 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 27 19:27:57 beat-box volumio[2750]: No protocol specified Nov 27 19:27:57 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 27 19:27:57 beat-box volumio[2750]: info: VolumeController:: Volume=undefined Mute =false Nov 27 19:27:57 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:57 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:57 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:57 beat-box volumio[2750]: info: Starting Shairport Sync Nov 27 19:27:57 beat-box volumio[2750]: info: Asound.conf file written Nov 27 19:27:57 beat-box sudo[392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 27 19:27:57 beat-box sudo[392]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:57 beat-box systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 27 19:27:57 beat-box systemd[1]: shairport-sync.service: Succeeded. Nov 27 19:27:57 beat-box systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 27 19:27:57 beat-box sudo[395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 27 19:27:57 beat-box sudo[395]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:57 beat-box volumio[2750]: No protocol specified Nov 27 19:27:57 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:27:57 beat-box volumio[2750]: No state is present for card PCH Nov 27 19:27:57 beat-box volumio[2750]: Found hardware: "HDA-Intel" "Realtek ALC3234" "HDA:10ec0255,102807a1,00100002 HDA:8086280b,80860101,00100000" "0x1028" "0x07a1" Nov 27 19:27:57 beat-box volumio[2750]: Hardware is initialized using a generic method Nov 27 19:27:57 beat-box volumio[2750]: No state is present for card PCH Nov 27 19:27:57 beat-box volumio[2750]: No state is present for card Audio Nov 27 19:27:57 beat-box volumio[2750]: Found hardware: "USB-Audio" "USB Mixer" "USB0d8c:0158" "" "" Nov 27 19:27:57 beat-box volumio[2750]: Hardware is initialized using a generic method Nov 27 19:27:57 beat-box volumio[2750]: No state is present for card Audio Nov 27 19:27:57 beat-box volumio[2750]: info: Output device has changed, restarting MPD Nov 27 19:27:57 beat-box sudo[395]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:57 beat-box systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 27 19:27:57 beat-box sudo[392]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:57 beat-box sudo[401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 19:27:57 beat-box sudo[401]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:57 beat-box sudo[401]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:57 beat-box volumio[2750]: info: Output device has changed, restarting Shairport Sync Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 19:27:57 beat-box sudo[405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 19:27:57 beat-box sudo[405]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:57 beat-box systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 27 19:27:57 beat-box systemd[1]: mpd.service: Succeeded. Nov 27 19:27:57 beat-box systemd[1]: Stopped Music Player Daemon. Nov 27 19:27:57 beat-box systemd[1]: Starting Music Player Daemon... Nov 27 19:27:57 beat-box volumio[2750]: No protocol specified Nov 27 19:27:57 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:27:57 beat-box volumio[2750]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 19:27:57 beat-box volumio[2750]: No protocol specified Nov 27 19:27:57 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:27:57 beat-box volumio[2750]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 27 19:27:57 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:57 beat-box sudo[411]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 27 19:27:57 beat-box sudo[415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 27 19:27:57 beat-box sudo[411]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:57 beat-box sudo[415]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:57 beat-box sudo[411]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:57 beat-box sudo[415]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:57 beat-box mpd[420]: Nov 27 19:27 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 27 19:27:57 beat-box systemd[1]: Started Music Player Daemon. Nov 27 19:27:57 beat-box sudo[343]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:57 beat-box sudo[405]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:58 beat-box volumio[2750]: No protocol specified Nov 27 19:27:58 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:27:58 beat-box volumio[2750]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Nov 27 19:27:58 beat-box volumio[2750]: No protocol specified Nov 27 19:27:58 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:27:58 beat-box volumio[2750]: No state is present for card PCH Nov 27 19:27:58 beat-box volumio[2750]: Found hardware: "HDA-Intel" "Realtek ALC3234" "HDA:10ec0255,102807a1,00100002 HDA:8086280b,80860101,00100000" "0x1028" "0x07a1" Nov 27 19:27:58 beat-box volumio[2750]: Hardware is initialized using a generic method Nov 27 19:27:58 beat-box volumio[2750]: No state is present for card PCH Nov 27 19:27:58 beat-box volumio[2750]: No state is present for card Audio Nov 27 19:27:58 beat-box volumio[2750]: Found hardware: "USB-Audio" "USB Mixer" "USB0d8c:0158" "" "" Nov 27 19:27:58 beat-box volumio[2750]: Hardware is initialized using a generic method Nov 27 19:27:58 beat-box volumio[2750]: No state is present for card Audio Nov 27 19:27:58 beat-box volumio[2750]: info: Output device has changed, restarting MPD Nov 27 19:27:58 beat-box sudo[430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 27 19:27:58 beat-box sudo[430]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:58 beat-box sudo[430]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:58 beat-box sudo[433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 19:27:58 beat-box sudo[433]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:58 beat-box sudo[433]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:58 beat-box volumio[2750]: info: Output device has changed, restarting Shairport Sync Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 19:27:58 beat-box sudo[437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 19:27:58 beat-box sudo[437]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:58 beat-box systemd[1]: Stopping Music Player Daemon... Nov 27 19:27:58 beat-box systemd[1]: mpd.service: Succeeded. Nov 27 19:27:58 beat-box systemd[1]: Stopped Music Player Daemon. Nov 27 19:27:58 beat-box volumio[2750]: No protocol specified Nov 27 19:27:58 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:27:58 beat-box volumio[2750]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 19:27:58 beat-box volumio[2750]: No protocol specified Nov 27 19:27:58 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:27:58 beat-box volumio[2750]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:58 beat-box sudo[446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 27 19:27:58 beat-box sudo[446]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:58 beat-box volumio[2750]: No protocol specified Nov 27 19:27:58 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 27 19:27:58 beat-box volumio[2750]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 27 19:27:58 beat-box volumio[2750]: info: Disabling external Volume Control Nov 27 19:27:58 beat-box systemd[1]: Starting Music Player Daemon... Nov 27 19:27:58 beat-box sudo[446]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:58 beat-box sudo[460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 27 19:27:58 beat-box sudo[460]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:58 beat-box sudo[460]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:58 beat-box sudo[464]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 27 19:27:58 beat-box sudo[464]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:58 beat-box volumio[2750]: info: Restarting Shairport-Sync after stop Nov 27 19:27:58 beat-box sudo[464]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:58 beat-box sudo[468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 27 19:27:58 beat-box sudo[468]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:58 beat-box sudo[442]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 27 19:27:58 beat-box sudo[442]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:58 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 27 19:27:58 beat-box sudo[442]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:58 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 27 19:27:58 beat-box sudo[472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 27 19:27:58 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 27 19:27:58 beat-box sudo[468]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:58 beat-box sudo[472]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:58 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 27 19:27:58 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 27 19:27:58 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 27 19:27:58 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 27 19:27:58 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 27 19:27:58 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 27 19:27:58 beat-box systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 27 19:27:58 beat-box systemd[1]: shairport-sync.service: Succeeded. Nov 27 19:27:58 beat-box systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 27 19:27:58 beat-box systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 27 19:27:58 beat-box volumio[2750]: info: VolumeController::SetAlsaVolume0 Nov 27 19:27:58 beat-box sudo[472]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:58 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:58 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:58 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:58 beat-box volumio[2750]: info: VolumeController::SetAlsaVolume0 Nov 27 19:27:58 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:58 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:58 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:58 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:58 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:58 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:58 beat-box volumio[2750]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Nov 27 19:27:58 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:27:58 beat-box volumio[2750]: info: MPD Permissions set Nov 27 19:27:58 beat-box volumio[2750]: info: MPD Permissions set Nov 27 19:27:58 beat-box volumio[2750]: info: Shairport-Sync Started Nov 27 19:27:58 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 27 19:27:58 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 27 19:27:58 beat-box volumio[2750]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Nov 27 19:27:58 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:27:58 beat-box volumio[2750]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Nov 27 19:27:58 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:27:58 beat-box volumio[2750]: info: Shairport-Sync retarted Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 27 19:27:58 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 19:27:58 beat-box volumio[2750]: info: Starting Shairport Sync Nov 27 19:27:58 beat-box volumio[2750]: info: Starting Shairport Sync Nov 27 19:27:58 beat-box sudo[495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 27 19:27:58 beat-box sudo[495]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:58 beat-box systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 27 19:27:58 beat-box systemd[1]: shairport-sync.service: Succeeded. Nov 27 19:27:58 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 27 19:27:58 beat-box systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 27 19:27:58 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 27 19:27:58 beat-box sudo[498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 27 19:27:58 beat-box sudo[498]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:27:58 beat-box systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 27 19:27:58 beat-box sudo[495]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:58 beat-box volumio[2750]: info: Shairport-Sync Started Nov 27 19:27:58 beat-box systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 27 19:27:58 beat-box systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 27 19:27:58 beat-box systemd[1]: shairport-sync.service: Succeeded. Nov 27 19:27:58 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 27 19:27:58 beat-box systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 27 19:27:58 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 27 19:27:58 beat-box systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 27 19:27:58 beat-box sudo[498]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:58 beat-box volumio[2750]: info: Shairport-Sync Started Nov 27 19:27:59 beat-box mpd[474]: Nov 27 19:27 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 27 19:27:59 beat-box systemd[1]: Started Music Player Daemon. Nov 27 19:27:59 beat-box sudo[437]: pam_unix(sudo:session): session closed for user root Nov 27 19:27:59 beat-box volumio[2750]: error: MPD error: The expression evaluated to a falsy value: Nov 27 19:27:59 beat-box volumio[2750]: assert.ok(self.idling) Nov 27 19:27:59 beat-box volumio[2750]: error: The expression evaluated to a falsy value: Nov 27 19:27:59 beat-box volumio[2750]: assert.ok(self.idling) Nov 27 19:27:59 beat-box volumio[2750]: error: updateQueue error: null Nov 27 19:27:59 beat-box volumio[2750]: error: updateQueue error: null Nov 27 19:27:59 beat-box volumio[2750]: info: VolumeController::SetAlsaVolume0 Nov 27 19:27:59 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:27:59 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 19:27:59 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:27:59 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:27:59 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:27:59 beat-box volumio[2750]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Nov 27 19:27:59 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:28:00 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Nov 27 19:28:00 beat-box sudo[510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 19:28:00 beat-box sudo[510]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:28:00 beat-box systemd[1]: Stopping Music Player Daemon... Nov 27 19:28:00 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 27 19:28:00 beat-box systemd[1]: mpd.service: Succeeded. Nov 27 19:28:00 beat-box systemd[1]: Stopped Music Player Daemon. Nov 27 19:28:00 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 27 19:28:00 beat-box systemd[1]: Starting Music Player Daemon... Nov 27 19:28:00 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 27 19:28:00 beat-box sudo[512]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 27 19:28:00 beat-box sudo[512]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:28:00 beat-box sudo[512]: pam_unix(sudo:session): session closed for user root Nov 27 19:28:00 beat-box mpd[514]: Nov 27 19:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 27 19:28:00 beat-box systemd[1]: Started Music Player Daemon. Nov 27 19:28:00 beat-box sudo[510]: pam_unix(sudo:session): session closed for user root Nov 27 19:28:00 beat-box volumio[2750]: error: updateQueue error: null Nov 27 19:28:00 beat-box volumio[2750]: info: TidalConnect service stoped! Nov 27 19:28:00 beat-box volumio[2750]: info: TidalConnect service stoped! Nov 27 19:28:00 beat-box volumio[2750]: info: TidalConnect service stoped! Nov 27 19:28:00 beat-box volumio[2750]: info: TidalConnect service stoped! Nov 27 19:28:00 beat-box volumio[2750]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 27 19:28:00 beat-box volumio[2750]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 27 19:28:00 beat-box volumio[2750]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 27 19:28:00 beat-box volumio[2750]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 27 19:28:00 beat-box sudo[534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 27 19:28:00 beat-box sudo[534]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:28:00 beat-box sudo[537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 27 19:28:00 beat-box sudo[537]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:28:00 beat-box systemd[1]: Started Volumio Tidal Connect Service. Nov 27 19:28:00 beat-box sudo[534]: pam_unix(sudo:session): session closed for user root Nov 27 19:28:00 beat-box sudo[537]: pam_unix(sudo:session): session closed for user root Nov 27 19:28:00 beat-box volumio[2750]: info: Executing endpoint tc_getconfig Nov 27 19:28:00 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 27 19:28:00 beat-box vtcs[538]: STARTING TidalConnect services, version: 1.4.0.34 Nov 27 19:28:00 beat-box vtcs[538]: STARTED TidalConnect services. Nov 27 19:28:00 beat-box volumio[2750]: info: Executing endpoint tc_connect Nov 27 19:28:00 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 27 19:28:00 beat-box volumio[2750]: info: Connecting to TidalConnect Nov 27 19:28:00 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:28:00 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:28:00 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:28:00 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:28:00 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:28:00 beat-box volumio[2750]: info: CorePlayQueue::getTrack 0 Nov 27 19:28:00 beat-box volumio[2750]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Nov 27 19:28:00 beat-box volumio[2750]: verbose: CURRENT POSITION 0 Nov 27 19:28:00 beat-box volumio[2750]: info: CoreStateMachine::syncState stateService stop Nov 27 19:28:00 beat-box volumio[2750]: info: CoreStateMachine::syncState currentStatus play Nov 27 19:28:00 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:28:00 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:28:00 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:28:00 beat-box volumio[2750]: info: CoreStateMachine::stPlaybackTimer Nov 27 19:28:00 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:28:00 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:28:00 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:28:00 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:28:00 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:28:00 beat-box volumio[2750]: info: CorePlayQueue::getTrack 0 Nov 27 19:28:00 beat-box volumio[2750]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Nov 27 19:28:00 beat-box volumio[2750]: verbose: CURRENT POSITION 0 Nov 27 19:28:00 beat-box volumio[2750]: info: CoreStateMachine::syncState stateService stop Nov 27 19:28:00 beat-box volumio[2750]: info: CoreStateMachine::syncState currentStatus stop Nov 27 19:28:00 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:28:00 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:28:00 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:28:00 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:28:00 beat-box volumio[2750]: info: No code Nov 27 19:28:00 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:28:00 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:28:00 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:28:00 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:28:00 beat-box volumio[2750]: info: TidalConnect service stoped! Nov 27 19:28:01 beat-box volumio[2750]: info: TidalConnect service stoped! Nov 27 19:28:01 beat-box volumio[2750]: info: TidalConnect service stoped! Nov 27 19:28:01 beat-box volumio[2750]: info: TidalConnect service stoped! Nov 27 19:28:01 beat-box volumio[2750]: info: TidalConnect service stoped! Nov 27 19:28:01 beat-box volumio[2750]: info: TidalConnect service stoped! Nov 27 19:28:01 beat-box volumio[2750]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 27 19:28:01 beat-box volumio[2750]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 27 19:28:01 beat-box volumio[2750]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 27 19:28:01 beat-box volumio[2750]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 27 19:28:01 beat-box volumio[2750]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 27 19:28:01 beat-box volumio[2750]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 27 19:28:01 beat-box sudo[602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 27 19:28:01 beat-box sudo[602]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:28:01 beat-box sudo[602]: pam_unix(sudo:session): session closed for user root Nov 27 19:28:01 beat-box sudo[605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 27 19:28:01 beat-box sudo[605]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:28:01 beat-box sudo[605]: pam_unix(sudo:session): session closed for user root Nov 27 19:28:01 beat-box sudo[609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 27 19:28:01 beat-box sudo[609]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:28:01 beat-box sudo[609]: pam_unix(sudo:session): session closed for user root Nov 27 19:28:01 beat-box volumio[2750]: info: CoreCommandRouter::volumioGetState Nov 27 19:28:02 beat-box volumio[2750]: info: VolumeController::SetAlsaVolume41 Nov 27 19:28:02 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:28:02 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 19:28:02 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:28:02 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:28:02 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:28:02 beat-box volumio[2750]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Nov 27 19:28:02 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:28:02 beat-box volumio[2750]: info: Executing endpoint metavolumio Nov 27 19:28:02 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 27 19:28:02 beat-box volumio[2750]: info: Executing endpoint metavolumio Nov 27 19:28:02 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 27 19:28:02 beat-box volumio[2750]: info: Executing endpoint metavolumio Nov 27 19:28:02 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 27 19:28:03 beat-box volumio[2750]: info: Executing endpoint metavolumio Nov 27 19:28:03 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 27 19:28:03 beat-box volumio[2750]: info: Executing endpoint metavolumio Nov 27 19:28:03 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 27 19:28:03 beat-box volumio[2750]: info: Executing endpoint metavolumio Nov 27 19:28:03 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 27 19:28:03 beat-box volumio[2750]: info: TidalConnect service started! Nov 27 19:28:03 beat-box volumio[2750]: info: TidalConnect service started! Nov 27 19:28:04 beat-box volumio[2750]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Nov 27 19:28:04 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Nov 27 19:28:04 beat-box volumio[2750]: info: CoreCommandRouter::volumioGetState Nov 27 19:28:04 beat-box volumio[2750]: info: Restoring Previous Volume level: 41 false true Nov 27 19:28:04 beat-box volumio[2750]: info: VolumeController::SetAlsaVolume100 Nov 27 19:28:04 beat-box volumio[2750]: info: Volume configurations have been set Nov 27 19:28:04 beat-box vtcs[538]: [2024-11-27 19:28:04.092] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Nov 27 19:28:04 beat-box vtcs[538]: [2024-11-27 19:28:04.092] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Nov 27 19:28:04 beat-box sudo[627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 27 19:28:04 beat-box sudo[627]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:28:04 beat-box systemd[1]: Stopping Volumio Tidal Connect Service... Nov 27 19:28:04 beat-box systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Nov 27 19:28:04 beat-box systemd[1]: vtcs.service: Succeeded. Nov 27 19:28:04 beat-box systemd[1]: Stopped Volumio Tidal Connect Service. Nov 27 19:28:04 beat-box sudo[627]: pam_unix(sudo:session): session closed for user root Nov 27 19:28:04 beat-box volumio[2750]: No protocol specified Nov 27 19:28:04 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:28:04 beat-box volumio[2750]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 27 19:28:04 beat-box volumio[2750]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 27 19:28:04 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 27 19:28:04 beat-box volumio[2750]: info: Disabling external Volume Control Nov 27 19:28:04 beat-box volumio[2750]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 27 19:28:04 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:28:04 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 19:28:04 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:28:04 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:28:04 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:28:04 beat-box volumio[2750]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 27 19:28:04 beat-box sudo[640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 27 19:28:04 beat-box sudo[640]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:28:04 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:28:04 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 19:28:04 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:28:04 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:28:04 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:28:04 beat-box sudo[640]: pam_unix(sudo:session): session closed for user root Nov 27 19:28:04 beat-box volumio[2750]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Nov 27 19:28:04 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:28:04 beat-box volumio[2750]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Nov 27 19:28:04 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:28:04 beat-box volumio[2750]: No protocol specified Nov 27 19:28:04 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:28:04 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 27 19:28:04 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 19:28:04 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 27 19:28:04 beat-box volumio[2750]: No protocol specified Nov 27 19:28:04 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:28:04 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 19:28:04 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 19:28:04 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 19:28:04 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 19:28:04 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 19:28:04 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 19:28:04 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 19:28:04 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 27 19:28:04 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 27 19:28:04 beat-box volumio[2750]: info: TidalConnect service started! Nov 27 19:28:04 beat-box volumio[2750]: info: TidalConnect service started! Nov 27 19:28:04 beat-box volumio[2750]: info: TidalConnect service started! Nov 27 19:28:07 beat-box volumio[2750]: info: TidalConnect service stoped! Nov 27 19:28:07 beat-box volumio[2750]: info: TidalConnect service stoped! Nov 27 19:28:07 beat-box volumio[2750]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 27 19:28:07 beat-box volumio[2750]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 27 19:28:07 beat-box sudo[658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 27 19:28:07 beat-box sudo[658]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 19:28:07 beat-box systemd[1]: Started Volumio Tidal Connect Service. Nov 27 19:28:07 beat-box sudo[658]: pam_unix(sudo:session): session closed for user root Nov 27 19:28:07 beat-box volumio[2750]: info: Executing endpoint tc_getconfig Nov 27 19:28:07 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 27 19:28:07 beat-box vtcs[660]: STARTING TidalConnect services, version: 1.4.0.34 Nov 27 19:28:07 beat-box vtcs[660]: STARTED TidalConnect services. Nov 27 19:28:07 beat-box volumio[2750]: info: Executing endpoint tc_connect Nov 27 19:28:07 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 27 19:28:07 beat-box volumio[2750]: info: Connecting to TidalConnect Nov 27 19:28:07 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:28:07 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:28:07 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:28:07 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:28:07 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:28:07 beat-box volumio[2750]: info: CorePlayQueue::getTrack 0 Nov 27 19:28:07 beat-box volumio[2750]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Nov 27 19:28:07 beat-box volumio[2750]: verbose: CURRENT POSITION 0 Nov 27 19:28:07 beat-box volumio[2750]: info: CoreStateMachine::syncState stateService stop Nov 27 19:28:07 beat-box volumio[2750]: info: CoreStateMachine::syncState currentStatus stop Nov 27 19:28:07 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:28:07 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:28:07 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:28:07 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:28:07 beat-box volumio[2750]: info: No code Nov 27 19:28:07 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:28:07 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:28:07 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:28:07 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:28:07 beat-box volumio[2750]: info: CoreCommandRouter::servicePushState Nov 27 19:28:07 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:28:07 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:28:07 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:28:07 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:28:07 beat-box volumio[2750]: info: CorePlayQueue::getTrack 0 Nov 27 19:28:07 beat-box volumio[2750]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Nov 27 19:28:07 beat-box volumio[2750]: verbose: CURRENT POSITION 0 Nov 27 19:28:07 beat-box volumio[2750]: info: CoreStateMachine::syncState stateService stop Nov 27 19:28:07 beat-box volumio[2750]: info: CoreStateMachine::syncState currentStatus stop Nov 27 19:28:07 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:28:07 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:28:07 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:28:07 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:28:07 beat-box volumio[2750]: info: No code Nov 27 19:28:07 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:28:07 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:28:07 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:28:07 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:28:08 beat-box volumio[2750]: info: VolumeController::SetAlsaVolume41 Nov 27 19:28:08 beat-box volumio[2750]: info: CoreStateMachine::pushState Nov 27 19:28:08 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 19:28:08 beat-box volumio[2750]: info: CoreCommandRouter::volumioPushState Nov 27 19:28:08 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output update for this device Nov 27 19:28:08 beat-box volumio[2750]: info: MRS: Pushing multiroomSync output Nov 27 19:28:08 beat-box volumio[2750]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Nov 27 19:28:08 beat-box volumio[2750]: xcb_connection_has_error() returned true Nov 27 19:28:10 beat-box volumio[2750]: info: TidalConnect service started! Nov 27 19:28:11 beat-box volumio[2750]: info: CoreCommandRouter::volumioGetState Nov 27 19:28:11 beat-box volumio[2750]: info: Listing playlists Nov 27 19:28:11 beat-box volumio[2750]: info: Listing playlists Nov 27 19:28:14 beat-box volumio[2750]: info: CoreCommandRouter::volumioGetState Nov 27 19:28:16 beat-box volumio[2750]: info: Executing endpoint metavolumio Nov 27 19:28:16 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 27 19:28:16 beat-box volumio[2750]: info: Executing endpoint metavolumio Nov 27 19:28:16 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 27 19:28:16 beat-box volumio[2750]: info: Executing endpoint metavolumio Nov 27 19:28:16 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 27 19:28:17 beat-box volumio[2750]: info: CoreCommandRouter::volumioGetQueue Nov 27 19:28:17 beat-box volumio[2750]: info: CoreStateMachine::getQueue Nov 27 19:28:17 beat-box volumio[2750]: info: CorePlayQueue::getQueue Nov 27 19:28:21 beat-box volumio[2750]: info: CoreCommandRouter::volumioGetState Nov 27 19:28:21 beat-box volumio[2750]: info: CoreCommandRouter::volumioGetState Nov 27 19:28:23 beat-box volumio[2750]: info: Executing endpoint metavolumio Nov 27 19:28:23 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 27 19:28:23 beat-box volumio[2750]: info: Executing endpoint metavolumio Nov 27 19:28:23 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 27 19:28:23 beat-box volumio[2750]: info: Executing endpoint metavolumio Nov 27 19:28:23 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 27 19:28:23 beat-box volumio[2750]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 27 19:28:23 beat-box volumio[2750]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 27 19:28:23 beat-box volumio[2750]: info: Retrieving Cloud Streaming UI Nov 27 19:28:23 beat-box volumio[2750]: info: Getting Tidal Cloud Configuration Nov 27 19:28:23 beat-box volumio[2750]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 27 19:28:23 beat-box volumio[2750]: info: Getting Qobuz Cloud Configuration Nov 27 19:28:23 beat-box volumio[2750]: info: Asking plugin for UI Config Nov 27 19:28:23 beat-box volumio[2750]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 27 19:28:23 beat-box volumio[2750]: info: Getting Spotify Cloud Configuration Nov 27 19:28:23 beat-box volumio[2750]: info: Asking plugin for UI Config Nov 27 19:28:23 beat-box volumio[2750]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 27 19:28:23 beat-box volumio[2750]: info: Saving Spotify Acccount Nov 27 19:28:23 beat-box volumio[2750]: info: Got it Nov 27 19:28:23 beat-box volumio[2750]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 27 19:28:23 beat-box volumio[2750]: info: Got Tidal Cloud Configuration Nov 27 19:28:23 beat-box volumio[2750]: info: Got it Nov 27 19:28:23 beat-box volumio[2750]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 27 19:28:23 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 27 19:28:23 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 27 19:28:23 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 27 19:28:23 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 27 19:28:23 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 19:28:23 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 19:28:23 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 19:28:23 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 19:28:23 beat-box volumio[2750]: info: CoreCommandRouter::volumioGetBrowseSources Nov 27 19:28:23 beat-box volumio[2750]: info: CoreCommandRouter::volumioGetBrowseSources Nov 27 19:28:23 beat-box volumio[2750]: info: CoreCommandRouter::volumioGetBrowseSources Nov 27 19:28:24 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 27 19:28:24 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 27 19:28:28 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 27 19:28:31 beat-box volumio[2750]: info: CoreCommandRouter::volumioGetState Nov 27 19:28:31 beat-box volumio[2750]: info: Listing playlists Nov 27 19:28:31 beat-box volumio[2750]: info: Listing playlists Nov 27 19:28:32 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 27 19:28:36 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 27 19:28:36 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Nov 27 19:28:36 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Nov 27 19:28:40 beat-box volumio[2750]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 27 19:28:41 beat-box volumio[2750]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 19:28:41 beat-box volumio[2750]: Error: dns service error: unknown Nov 27 19:28:41 beat-box volumio[2750]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Nov 27 19:28:41 beat-box volumio[2750]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Nov 27 19:28:41 beat-box volumio[2750]: errorCode: -65537 Nov 27 19:28:41 beat-box volumio[2750]: } Nov 27 19:28:41 beat-box volumio[2750]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 19:28:41 beat-box sudo[814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-27 19:27 Nov 27 19:28:41 beat-box sudo[814]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"