-- Logs begin at Fri 2024-10-04 18:02:44 CEST, end at Sun 2024-10-13 14:04:36 CEST. -- Oct 13 14:03:00 kueche volumio[1077]: info: CoreCommandRouter::volumioGetState Oct 13 14:03:00 kueche volumio[1077]: info: Listing playlists Oct 13 14:03:00 kueche volumio[1077]: info: Listing playlists Oct 13 14:03:07 kueche volumio[1271]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Oct 13 14:03:09 kueche volumio[1077]: info: CoreCommandRouter::volumioGetState Oct 13 14:03:19 kueche volumio[1077]: info: CoreCommandRouter::volumioGetState Oct 13 14:03:19 kueche volumio[1077]: info: Listing playlists Oct 13 14:03:19 kueche volumio[1077]: info: Listing playlists Oct 13 14:03:21 kueche volumio[1077]: info: Oct 13 14:03:21 kueche volumio[1077]: ---------------------------- Client requests Volume + Oct 13 14:03:21 kueche volumio[1077]: info: VolumeController::SetAlsaVolume+ Oct 13 14:03:21 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:03:21 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:03:21 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:03:22 kueche volumio[1077]: info: CoreCommandRouter::volumioGetState Oct 13 14:03:24 kueche volumio[1077]: info: Oct 13 14:03:24 kueche volumio[1077]: ---------------------------- Client requests Volume + Oct 13 14:03:24 kueche volumio[1077]: info: VolumeController::SetAlsaVolume+ Oct 13 14:03:24 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:03:24 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:03:24 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:03:24 kueche volumio[1077]: info: Oct 13 14:03:24 kueche volumio[1077]: ---------------------------- Client requests Volume + Oct 13 14:03:24 kueche volumio[1077]: info: VolumeController::SetAlsaVolume+ Oct 13 14:03:24 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:03:24 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:03:24 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:03:25 kueche volumio[1077]: info: CoreCommandRouter::volumioGetState Oct 13 14:03:25 kueche volumio[1077]: info: Oct 13 14:03:25 kueche volumio[1077]: ---------------------------- Client requests Volume + Oct 13 14:03:25 kueche volumio[1077]: info: VolumeController::SetAlsaVolume+ Oct 13 14:03:25 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:03:25 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:03:25 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:03:26 kueche volumio[1077]: info: CoreCommandRouter::volumioGetState Oct 13 14:03:27 kueche volumio[1077]: info: Oct 13 14:03:27 kueche volumio[1077]: ---------------------------- Client requests Volume + Oct 13 14:03:27 kueche volumio[1077]: info: VolumeController::SetAlsaVolume+ Oct 13 14:03:27 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:03:27 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:03:27 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:03:28 kueche volumio[1077]: info: Oct 13 14:03:28 kueche volumio[1077]: ---------------------------- Client requests Volume + Oct 13 14:03:28 kueche volumio[1077]: info: VolumeController::SetAlsaVolume+ Oct 13 14:03:28 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:03:28 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:03:28 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:03:28 kueche volumio[1077]: info: CoreCommandRouter::volumioGetState Oct 13 14:03:29 kueche volumio[1077]: info: CoreCommandRouter::volumioGetState Oct 13 14:03:40 kueche volumio[1077]: info: CoreCommandRouter::volumioGetState Oct 13 14:03:40 kueche volumio[1077]: info: Listing playlists Oct 13 14:03:49 kueche volumio[1077]: info: CoreCommandRouter::volumioGetState Oct 13 14:03:59 kueche volumio[1077]: info: CoreCommandRouter::volumioGetState Oct 13 14:04:00 kueche volumio[1077]: info: Listing playlists Oct 13 14:04:00 kueche volumio[1077]: info: Listing playlists Oct 13 14:04:01 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:01 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:01 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:01 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:01 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:01 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:01 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:01 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:01 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:01 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:01 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:01 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:04:01 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:01 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:01 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:01 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:01 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:01 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:01 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:01 kueche shairport-sync[1290]: {"time":1728820977009,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Oct 13 14:04:01 kueche shairport-sync[1290]: Dload Upload Total Spent Left Speed Oct 13 14:04:01 kueche volumio[1077]: info: Oct 13 14:04:01 kueche volumio[1077]: ---------------------------- Client requests Stop Airplay Playback Oct 13 14:04:01 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Oct 13 14:04:01 kueche volumio[1077]: info: Airplay playback stop Oct 13 14:04:01 kueche volumio[1077]: info: Airplay Playback Stopped, clearing state Oct 13 14:04:01 kueche volumio[1077]: info: CoreStateMachine::resetVolumioState Oct 13 14:04:01 kueche volumio[1077]: info: CoreStateMachine::getcurrentVolume Oct 13 14:04:01 kueche volumio[1077]: info: CoreCommandRouter::volumioRetrievevolume Oct 13 14:04:01 kueche shairport-sync[1290]: [158B blob data] Oct 13 14:04:01 kueche volumio[1077]: info: VolumeController:: Volume=54 Mute =false Oct 13 14:04:01 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:01 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:04:01 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:01 kueche volumio[1077]: info: CoreStateMachine::updateTrackBlock Oct 13 14:04:01 kueche volumio[1077]: info: CorePlayQueue::getTrackBlock Oct 13 14:04:01 kueche volumio[1077]: info: CoreCommandRouter::volumioRetrievevolume Oct 13 14:04:01 kueche volumio[1077]: info: VolumeController:: Volume=54 Mute =false Oct 13 14:04:01 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:01 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:02 kueche volumio[1077]: info: Airplay started streaming, receiving metadatas Oct 13 14:04:02 kueche volumio[1077]: info: Airplay started streaming, receiving metadatas Oct 13 14:04:02 kueche volumio[1077]: info: Airplay started streaming, receiving metadatas Oct 13 14:04:02 kueche shairport-sync[1290]: {"time":1728821041473,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Oct 13 14:04:02 kueche shairport-sync[1290]: Dload Upload Total Spent Left Speed Oct 13 14:04:02 kueche volumio[1077]: info: Oct 13 14:04:02 kueche volumio[1077]: ---------------------------- Client requests Start Airplay PlaybackRoutine Oct 13 14:04:02 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Oct 13 14:04:02 kueche volumio[1077]: info: Airplay playback start Oct 13 14:04:02 kueche shairport-sync[1290]: [158B blob data] Oct 13 14:04:03 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:03 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:03 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:04:03 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:03 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:03 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:03 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:03 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:03 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:03 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:03 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:03 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:03 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:04 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:04 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:04 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:04:04 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:04 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:04 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:04 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:04 kueche kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Oct 13 14:04:04 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:04 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:04 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:04 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:04 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:04 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:04:04 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:04 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:04 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:04 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:04 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:04 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:04 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:06 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:06 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:06 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:04:06 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:06 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:06 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:06 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:06 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:06 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:06 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:07 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:07 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:07 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:04:07 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:07 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:07 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:07 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:07 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:07 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:07 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:08 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:08 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:08 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:04:08 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:08 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:08 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:08 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:08 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:08 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:08 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:08 kueche shairport-sync[1290]: {"time":1728821042337,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Oct 13 14:04:08 kueche shairport-sync[1290]: Dload Upload Total Spent Left Speed Oct 13 14:04:08 kueche volumio[1077]: info: Oct 13 14:04:08 kueche volumio[1077]: ---------------------------- Client requests Stop Airplay Playback Oct 13 14:04:08 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Oct 13 14:04:08 kueche volumio[1077]: info: Airplay playback stop Oct 13 14:04:08 kueche volumio[1077]: info: Airplay Playback Stopped, clearing state Oct 13 14:04:08 kueche volumio[1077]: info: CoreStateMachine::resetVolumioState Oct 13 14:04:08 kueche volumio[1077]: info: CoreStateMachine::getcurrentVolume Oct 13 14:04:08 kueche volumio[1077]: info: CoreCommandRouter::volumioRetrievevolume Oct 13 14:04:08 kueche shairport-sync[1290]: [158B blob data] Oct 13 14:04:08 kueche volumio[1077]: info: VolumeController:: Volume=54 Mute =false Oct 13 14:04:08 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:08 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:04:08 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:08 kueche volumio[1077]: info: CoreStateMachine::updateTrackBlock Oct 13 14:04:08 kueche volumio[1077]: info: CorePlayQueue::getTrackBlock Oct 13 14:04:08 kueche volumio[1077]: info: CoreCommandRouter::volumioRetrievevolume Oct 13 14:04:08 kueche volumio[1077]: info: VolumeController:: Volume=54 Mute =false Oct 13 14:04:08 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:08 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:09 kueche volumio[1077]: info: CoreCommandRouter::volumioGetState Oct 13 14:04:19 kueche volumio[1077]: info: CoreCommandRouter::volumioGetState Oct 13 14:04:19 kueche volumio[1077]: info: Listing playlists Oct 13 14:04:19 kueche volumio[1077]: info: Listing playlists Oct 13 14:04:22 kueche volumio[1077]: info: CoreCommandRouter::volumioGetState Oct 13 14:04:22 kueche volumio[1077]: info: CoreCommandRouter::volumioGetState Oct 13 14:04:22 kueche volumio[1077]: info: CoreCommandRouter::volumioGetState Oct 13 14:04:22 kueche volumio[1077]: info: CoreCommandRouter::volumioGetState Oct 13 14:04:22 kueche volumio[1077]: info: CoreCommandRouter::volumioGetQueue Oct 13 14:04:22 kueche volumio[1077]: info: CoreStateMachine::getQueue Oct 13 14:04:22 kueche volumio[1077]: info: CorePlayQueue::getQueue Oct 13 14:04:23 kueche volumio[1077]: info: CoreCommandRouter::volumioGetQueue Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::getQueue Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::getQueue Oct 13 14:04:23 kueche volumio[1077]: info: CoreCommandRouter::volumioGetQueue Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::getQueue Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::getQueue Oct 13 14:04:23 kueche volumio[1077]: info: CoreCommandRouter::volumioGetQueue Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::getQueue Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::getQueue Oct 13 14:04:23 kueche volumio[1077]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::ClearQueue Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::stop Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::serviceStop Oct 13 14:04:23 kueche volumio[1077]: info: CoreCommandRouter::serviceStop Oct 13 14:04:23 kueche volumio[1077]: info: Airplay Stop Oct 13 14:04:23 kueche volumio[1077]: info: Stopping Airplay Playback and sending pause command to client via USR2 Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::clearPlayQueue Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::saveQueue Oct 13 14:04:23 kueche volumio[1077]: info: CoreCommandRouter::volumioPushQueue Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::addQueueItems Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::addQueueItems Oct 13 14:04:23 kueche volumio[1077]: info: Preload queue cleared Oct 13 14:04:23 kueche volumio[1077]: info: Adding Item to queue: mnt/NAS/NAS/doorbell.mp3 Oct 13 14:04:23 kueche volumio[1077]: info: Using cached record of: mnt/NAS/NAS/doorbell.mp3 Oct 13 14:04:23 kueche volumio[1077]: info: CoreCommandRouter::volumioPushQueue Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::saveQueue Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::updateTrackBlock Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::getTrackBlock Oct 13 14:04:23 kueche volumio[1077]: info: CoreCommandRouter::volumioPlay Oct 13 14:04:23 kueche volumio[1077]: verbose: UNSET VOLATILE: Service: airplay_emulation Oct 13 14:04:23 kueche volumio[1077]: info: Stopping Airplay Playback and sending pause command to client via USR2 Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::play index 0 Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::stop Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::play index undefined Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::startPlaybackTimer Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:23 kueche volumio[1077]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/doorbell.mp3 Oct 13 14:04:23 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:04:23 kueche volumio[1077]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::ClearQueue Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::stop Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::clearPlayQueue Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::saveQueue Oct 13 14:04:23 kueche volumio[1077]: info: CoreCommandRouter::volumioPushQueue Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::addQueueItems Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::addQueueItems Oct 13 14:04:23 kueche volumio[1077]: info: Preload queue cleared Oct 13 14:04:23 kueche volumio[1077]: info: Adding Item to queue: mnt/NAS/NAS/doorbell.mp3 Oct 13 14:04:23 kueche volumio[1077]: info: Using cached record of: mnt/NAS/NAS/doorbell.mp3 Oct 13 14:04:23 kueche volumio[1077]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::ClearQueue Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::stop Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::clearPlayQueue Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::saveQueue Oct 13 14:04:23 kueche volumio[1077]: info: CoreCommandRouter::volumioPushQueue Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::addQueueItems Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::addQueueItems Oct 13 14:04:23 kueche volumio[1077]: info: Preload queue cleared Oct 13 14:04:23 kueche volumio[1077]: info: Adding Item to queue: mnt/NAS/NAS/doorbell.mp3 Oct 13 14:04:23 kueche volumio[1077]: info: Using cached record of: mnt/NAS/NAS/doorbell.mp3 Oct 13 14:04:23 kueche volumio[1077]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::ClearQueue Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::stop Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::clearPlayQueue Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::saveQueue Oct 13 14:04:23 kueche volumio[1077]: info: CoreCommandRouter::volumioPushQueue Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::addQueueItems Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::addQueueItems Oct 13 14:04:23 kueche volumio[1077]: info: Preload queue cleared Oct 13 14:04:23 kueche volumio[1077]: info: Adding Item to queue: mnt/NAS/NAS/doorbell.mp3 Oct 13 14:04:23 kueche volumio[1077]: info: Using cached record of: mnt/NAS/NAS/doorbell.mp3 Oct 13 14:04:23 kueche volumio[1077]: info: CoreCommandRouter::volumioPushQueue Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::saveQueue Oct 13 14:04:23 kueche volumio[1077]: info: CoreCommandRouter::volumioPushQueue Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::saveQueue Oct 13 14:04:23 kueche volumio[1077]: info: CoreCommandRouter::volumioPushQueue Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::saveQueue Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::updateTrackBlock Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::getTrackBlock Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::updateTrackBlock Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::getTrackBlock Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::updateTrackBlock Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::getTrackBlock Oct 13 14:04:23 kueche volumio[1077]: info: CoreCommandRouter::volumioPlay Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::play index 0 Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:04:23 kueche volumio[1077]: info: CoreCommandRouter::volumioPlay Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::play index 0 Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:04:23 kueche volumio[1077]: info: CoreCommandRouter::volumioPlay Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::play index 0 Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::stop Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::stop Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::stop Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::play index undefined Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::play index undefined Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::play index undefined Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::startPlaybackTimer Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:23 kueche volumio[1077]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/doorbell.mp3 Oct 13 14:04:23 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::startPlaybackTimer Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:23 kueche volumio[1077]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/doorbell.mp3 Oct 13 14:04:23 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::startPlaybackTimer Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:23 kueche volumio[1077]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/doorbell.mp3 Oct 13 14:04:23 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:04:23 kueche sudo[2890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1290 Oct 13 14:04:23 kueche sudo[2892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1290 Oct 13 14:04:23 kueche sudo[2892]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 14:04:23 kueche sudo[2890]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 14:04:23 kueche volumio[1077]: info: VolumeController::SetAlsaVolume35 Oct 13 14:04:23 kueche volumio[1077]: info: VolumeController::SetAlsaVolume35 Oct 13 14:04:23 kueche sudo[2892]: pam_unix(sudo:session): session closed for user root Oct 13 14:04:23 kueche sudo[2890]: pam_unix(sudo:session): session closed for user root Oct 13 14:04:23 kueche volumio[1077]: info: VolumeController::SetAlsaVolume35 Oct 13 14:04:23 kueche volumio[1077]: info: VolumeController::SetAlsaVolume35 Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:23 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:04:23 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:23 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:23 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:23 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:23 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:23 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:23 kueche volumio[1077]: info: sendMpdCommand stop took 239 milliseconds Oct 13 14:04:23 kueche volumio[1077]: info: sendMpdCommand stop took 170 milliseconds Oct 13 14:04:23 kueche volumio[1077]: info: sendMpdCommand stop took 169 milliseconds Oct 13 14:04:23 kueche volumio[1077]: info: sendMpdCommand stop took 167 milliseconds Oct 13 14:04:23 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 14:04:23 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 14:04:23 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 14:04:23 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 14:04:23 kueche volumio[1077]: info: Oct 13 14:04:23 kueche volumio[1077]: ---------------------------- MPD announces system playlist update Oct 13 14:04:23 kueche volumio[1077]: info: Ignoring MPD Status Update Oct 13 14:04:23 kueche volumio[1077]: info: Oct 13 14:04:23 kueche volumio[1077]: ---------------------------- MPD announces system playlist update Oct 13 14:04:23 kueche volumio[1077]: info: Ignoring MPD Status Update Oct 13 14:04:23 kueche volumio[1077]: info: Oct 13 14:04:23 kueche volumio[1077]: ---------------------------- MPD announces system playlist update Oct 13 14:04:23 kueche volumio[1077]: info: Ignoring MPD Status Update Oct 13 14:04:23 kueche volumio[1077]: info: Oct 13 14:04:23 kueche volumio[1077]: ---------------------------- MPD announces system playlist update Oct 13 14:04:23 kueche volumio[1077]: info: Ignoring MPD Status Update Oct 13 14:04:23 kueche volumio[1077]: info: sendMpdCommand clear took 9 milliseconds Oct 13 14:04:23 kueche volumio[1077]: info: sendMpdCommand clear took 10 milliseconds Oct 13 14:04:23 kueche volumio[1077]: info: sendMpdCommand clear took 10 milliseconds Oct 13 14:04:23 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/doorbell.mp3" Oct 13 14:04:23 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/doorbell.mp3" Oct 13 14:04:23 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/doorbell.mp3" Oct 13 14:04:23 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/doorbell.mp3" Oct 13 14:04:23 kueche volumio[1077]: info: Oct 13 14:04:23 kueche volumio[1077]: ---------------------------- MPD announces system playlist update Oct 13 14:04:23 kueche volumio[1077]: info: Ignoring MPD Status Update Oct 13 14:04:23 kueche volumio[1077]: info: Oct 13 14:04:23 kueche volumio[1077]: ---------------------------- MPD announces system playlist update Oct 13 14:04:23 kueche volumio[1077]: info: Ignoring MPD Status Update Oct 13 14:04:23 kueche volumio[1077]: info: Shairport-Sync paused with USR2 Oct 13 14:04:23 kueche volumio[1077]: info: Shairport-Sync paused with USR2 Oct 13 14:04:23 kueche volumio[1077]: error: updateQueue error: null Oct 13 14:04:23 kueche volumio[1077]: error: updateQueue error: null Oct 13 14:04:23 kueche volumio[1077]: error: updateQueue error: null Oct 13 14:04:23 kueche volumio[1077]: error: updateQueue error: null Oct 13 14:04:23 kueche volumio[1077]: info: Oct 13 14:04:23 kueche volumio[1077]: ---------------------------- MPD announces system playlist update Oct 13 14:04:23 kueche volumio[1077]: info: Ignoring MPD Status Update Oct 13 14:04:23 kueche volumio[1077]: info: Oct 13 14:04:23 kueche volumio[1077]: ---------------------------- MPD announces system playlist update Oct 13 14:04:23 kueche volumio[1077]: info: Ignoring MPD Status Update Oct 13 14:04:23 kueche volumio[1077]: info: Oct 13 14:04:23 kueche volumio[1077]: ---------------------------- MPD announces system playlist update Oct 13 14:04:23 kueche volumio[1077]: info: Ignoring MPD Status Update Oct 13 14:04:23 kueche volumio[1077]: info: Oct 13 14:04:23 kueche volumio[1077]: ---------------------------- MPD announces system playlist update Oct 13 14:04:23 kueche volumio[1077]: info: Ignoring MPD Status Update Oct 13 14:04:23 kueche volumio[1077]: info: ------------------------------ 28ms Oct 13 14:04:23 kueche volumio[1077]: info: ------------------------------ 28ms Oct 13 14:04:23 kueche volumio[1077]: info: ------------------------------ 28ms Oct 13 14:04:23 kueche volumio[1077]: info: ------------------------------ 27ms Oct 13 14:04:23 kueche volumio[1077]: info: sendMpdCommand add "NAS/NAS/doorbell.mp3" took 26 milliseconds Oct 13 14:04:23 kueche volumio[1077]: info: sendMpdCommand add "NAS/NAS/doorbell.mp3" took 25 milliseconds Oct 13 14:04:23 kueche volumio[1077]: info: sendMpdCommand add "NAS/NAS/doorbell.mp3" took 26 milliseconds Oct 13 14:04:23 kueche volumio[1077]: info: sendMpdCommand add "NAS/NAS/doorbell.mp3" took 25 milliseconds Oct 13 14:04:23 kueche volumio[1077]: info: ------------------------------ 14ms Oct 13 14:04:23 kueche volumio[1077]: info: ------------------------------ 13ms Oct 13 14:04:23 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand play Oct 13 14:04:23 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand play Oct 13 14:04:23 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand play Oct 13 14:04:23 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand play Oct 13 14:04:23 kueche volumio[1077]: info: Oct 13 14:04:23 kueche volumio[1077]: ---------------------------- MPD announces system playlist update Oct 13 14:04:23 kueche volumio[1077]: info: Ignoring MPD Status Update Oct 13 14:04:23 kueche volumio[1077]: info: Oct 13 14:04:23 kueche volumio[1077]: ---------------------------- MPD announces system playlist update Oct 13 14:04:23 kueche volumio[1077]: info: Ignoring MPD Status Update Oct 13 14:04:23 kueche volumio[1077]: error: Upnp client error: Error: This socket has been ended by the other party Oct 13 14:04:23 kueche volumio[1077]: info: ------------------------------ 17ms Oct 13 14:04:23 kueche volumio[1077]: info: ------------------------------ 17ms Oct 13 14:04:23 kueche volumio[1077]: info: ------------------------------ 16ms Oct 13 14:04:23 kueche volumio[1077]: info: ------------------------------ 16ms Oct 13 14:04:23 kueche volumio[1077]: info: sendMpdCommand play took 11 milliseconds Oct 13 14:04:23 kueche volumio[1077]: info: sendMpdCommand play took 11 milliseconds Oct 13 14:04:23 kueche volumio[1077]: info: sendMpdCommand play took 12 milliseconds Oct 13 14:04:23 kueche volumio[1077]: info: sendMpdCommand play took 11 milliseconds Oct 13 14:04:23 kueche volumio[1077]: info: ------------------------------ 9ms Oct 13 14:04:23 kueche volumio[1077]: info: ------------------------------ 8ms Oct 13 14:04:24 kueche volumio[1077]: info: Oct 13 14:04:24 kueche volumio[1077]: ---------------------------- MPD announces state update: player Oct 13 14:04:24 kueche volumio[1077]: info: ControllerMpd::getState Oct 13 14:04:24 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:04:24 kueche volumio[1077]: info: Oct 13 14:04:24 kueche volumio[1077]: ---------------------------- MPD announces state update: player Oct 13 14:04:24 kueche volumio[1077]: info: ControllerMpd::getState Oct 13 14:04:24 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:04:24 kueche volumio[1077]: info: Oct 13 14:04:24 kueche volumio[1077]: ---------------------------- MPD announces state update: player Oct 13 14:04:24 kueche volumio[1077]: info: ControllerMpd::getState Oct 13 14:04:24 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:04:24 kueche kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Oct 13 14:04:24 kueche volumio[1077]: info: sendMpdCommand status took 18 milliseconds Oct 13 14:04:24 kueche volumio[1077]: verbose: ControllerMpd::parseState Oct 13 14:04:24 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:04:24 kueche volumio[1077]: info: Oct 13 14:04:24 kueche volumio[1077]: ---------------------------- MPD announces state update: player Oct 13 14:04:24 kueche volumio[1077]: info: ControllerMpd::getState Oct 13 14:04:24 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:04:24 kueche volumio[1077]: info: sendMpdCommand status took 20 milliseconds Oct 13 14:04:24 kueche volumio[1077]: info: sendMpdCommand status took 18 milliseconds Oct 13 14:04:24 kueche volumio[1077]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 13 14:04:24 kueche volumio[1077]: verbose: ControllerMpd::parseState Oct 13 14:04:24 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:04:24 kueche volumio[1077]: verbose: ControllerMpd::parseState Oct 13 14:04:24 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:04:24 kueche volumio[1077]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:04:24 kueche volumio[1077]: info: ControllerMpd::pushState Oct 13 14:04:24 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:24 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:24 kueche volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":8,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"doorbell.mp3","artist":null,"album":"mobile-rington.ru","uri":"NAS/NAS/doorbell.mp3","trackType":"mp3"} Oct 13 14:04:24 kueche volumio[1077]: verbose: CURRENT POSITION 0 Oct 13 14:04:24 kueche volumio[1077]: info: CoreStateMachine::syncState stateService play Oct 13 14:04:24 kueche volumio[1077]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:04:24 kueche volumio[1077]: info: ------------------------------ 31ms Oct 13 14:04:24 kueche volumio[1077]: info: Oct 13 14:04:24 kueche volumio[1077]: ---------------------------- MPD announces state update: player Oct 13 14:04:24 kueche volumio[1077]: info: ControllerMpd::getState Oct 13 14:04:24 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:04:24 kueche volumio[1077]: info: Oct 13 14:04:24 kueche volumio[1077]: ---------------------------- MPD announces state update: player Oct 13 14:04:24 kueche volumio[1077]: info: ControllerMpd::getState Oct 13 14:04:24 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:04:24 kueche volumio[1077]: info: sendMpdCommand status took 14 milliseconds Oct 13 14:04:24 kueche volumio[1077]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 13 14:04:24 kueche volumio[1077]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 13 14:04:24 kueche volumio[1077]: info: sendMpdCommand status took 5 milliseconds Oct 13 14:04:24 kueche volumio[1077]: info: sendMpdCommand status took 3 milliseconds Oct 13 14:04:24 kueche volumio[1077]: verbose: ControllerMpd::parseState Oct 13 14:04:24 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:04:24 kueche volumio[1077]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:04:24 kueche volumio[1077]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:04:24 kueche volumio[1077]: verbose: ControllerMpd::parseState Oct 13 14:04:24 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:04:24 kueche volumio[1077]: verbose: ControllerMpd::parseState Oct 13 14:04:24 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:04:24 kueche volumio[1077]: info: ControllerMpd::pushState Oct 13 14:04:24 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:24 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:24 kueche volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":8,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"doorbell.mp3","artist":null,"album":"mobile-rington.ru","uri":"NAS/NAS/doorbell.mp3","trackType":"mp3"} Oct 13 14:04:24 kueche volumio[1077]: verbose: CURRENT POSITION 0 Oct 13 14:04:24 kueche volumio[1077]: info: CoreStateMachine::syncState stateService play Oct 13 14:04:24 kueche volumio[1077]: info: CoreStateMachine::syncState currentStatus play Oct 13 14:04:24 kueche volumio[1077]: info: Received an update from plugin. extracting info from payload Oct 13 14:04:24 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:24 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:24 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:04:24 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:24 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:24 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:24 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:24 kueche volumio[1077]: info: ControllerMpd::pushState Oct 13 14:04:24 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:24 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:24 kueche volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":8,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"doorbell.mp3","artist":null,"album":"mobile-rington.ru","uri":"NAS/NAS/doorbell.mp3","trackType":"mp3"} Oct 13 14:04:24 kueche volumio[1077]: verbose: CURRENT POSITION 0 Oct 13 14:04:24 kueche volumio[1077]: info: CoreStateMachine::syncState stateService play Oct 13 14:04:24 kueche volumio[1077]: info: CoreStateMachine::syncState currentStatus play Oct 13 14:04:24 kueche volumio[1077]: info: Received an update from plugin. extracting info from payload Oct 13 14:04:24 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:24 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:24 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:24 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:24 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:24 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:24 kueche volumio[1077]: info: ------------------------------ 97ms Oct 13 14:04:24 kueche volumio[1077]: info: ------------------------------ 96ms Oct 13 14:04:24 kueche volumio[1077]: info: Restarting Shairport-Sync after stop Oct 13 14:04:24 kueche volumio[1077]: info: Restarting Shairport-Sync after stop Oct 13 14:04:24 kueche sudo[2901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 14:04:24 kueche volumio[1077]: info: sendMpdCommand playlistinfo took 107 milliseconds Oct 13 14:04:24 kueche volumio[1077]: info: sendMpdCommand playlistinfo took 107 milliseconds Oct 13 14:04:24 kueche volumio[1077]: info: sendMpdCommand playlistinfo took 106 milliseconds Oct 13 14:04:24 kueche sudo[2901]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 14:04:24 kueche volumio[1077]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:04:24 kueche volumio[1077]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:04:24 kueche volumio[1077]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:04:24 kueche volumio[1077]: info: ControllerMpd::pushState Oct 13 14:04:24 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:24 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:24 kueche volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":8,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"doorbell.mp3","artist":null,"album":"mobile-rington.ru","uri":"NAS/NAS/doorbell.mp3","trackType":"mp3"} Oct 13 14:04:24 kueche volumio[1077]: verbose: CURRENT POSITION 0 Oct 13 14:04:24 kueche volumio[1077]: info: CoreStateMachine::syncState stateService play Oct 13 14:04:24 kueche volumio[1077]: info: CoreStateMachine::syncState currentStatus play Oct 13 14:04:24 kueche volumio[1077]: info: Received an update from plugin. extracting info from payload Oct 13 14:04:24 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:24 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:24 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:24 kueche sudo[2903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 14:04:24 kueche sudo[2903]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 14:04:24 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:24 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:24 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:24 kueche volumio[1077]: info: ControllerMpd::pushState Oct 13 14:04:24 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:24 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:24 kueche volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":8,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"doorbell.mp3","artist":null,"album":"mobile-rington.ru","uri":"NAS/NAS/doorbell.mp3","trackType":"mp3"} Oct 13 14:04:24 kueche volumio[1077]: verbose: CURRENT POSITION 0 Oct 13 14:04:24 kueche volumio[1077]: info: CoreStateMachine::syncState stateService play Oct 13 14:04:24 kueche volumio[1077]: info: CoreStateMachine::syncState currentStatus play Oct 13 14:04:24 kueche volumio[1077]: info: Received an update from plugin. extracting info from payload Oct 13 14:04:24 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:24 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:24 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:04:24 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:24 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:24 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:24 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:24 kueche volumio[1077]: info: ControllerMpd::pushState Oct 13 14:04:24 kueche systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 13 14:04:24 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:24 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:24 kueche volumio[1077]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":8,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"doorbell.mp3","artist":null,"album":"mobile-rington.ru","uri":"NAS/NAS/doorbell.mp3","trackType":"mp3"} Oct 13 14:04:24 kueche volumio[1077]: verbose: CURRENT POSITION 0 Oct 13 14:04:24 kueche volumio[1077]: info: CoreStateMachine::syncState stateService play Oct 13 14:04:24 kueche volumio[1077]: info: CoreStateMachine::syncState currentStatus play Oct 13 14:04:24 kueche volumio[1077]: info: Received an update from plugin. extracting info from payload Oct 13 14:04:24 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:24 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:24 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:24 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:24 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:24 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:24 kueche shairport-sync[1290]: {"time":1728821048597,"response":"stopAirplayPlayback Success"} Oct 13 14:04:24 kueche systemd[1]: shairport-sync.service: Succeeded. Oct 13 14:04:24 kueche systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 13 14:04:24 kueche systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 13 14:04:24 kueche sudo[2901]: pam_unix(sudo:session): session closed for user root Oct 13 14:04:24 kueche volumio[1077]: info: ------------------------------ 243ms Oct 13 14:04:24 kueche volumio[1077]: info: ------------------------------ 235ms Oct 13 14:04:24 kueche volumio[1077]: info: ------------------------------ 234ms Oct 13 14:04:24 kueche sudo[2903]: pam_unix(sudo:session): session closed for user root Oct 13 14:04:24 kueche volumio[1077]: info: Shairport-Sync retarted Oct 13 14:04:24 kueche volumio[1077]: info: Shairport-Sync retarted Oct 13 14:04:26 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:26 kueche volumio[1077]: info: CorePlayQueue::getTrack 1 Oct 13 14:04:29 kueche volumio[1077]: info: CoreCommandRouter::volumioGetState Oct 13 14:04:29 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: info: Oct 13 14:04:31 kueche volumio[1077]: ---------------------------- MPD announces state update: player Oct 13 14:04:31 kueche volumio[1077]: info: ControllerMpd::getState Oct 13 14:04:31 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:04:31 kueche volumio[1077]: info: Oct 13 14:04:31 kueche volumio[1077]: ---------------------------- MPD announces state update: player Oct 13 14:04:31 kueche volumio[1077]: info: ControllerMpd::getState Oct 13 14:04:31 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:04:31 kueche volumio[1077]: info: Oct 13 14:04:31 kueche volumio[1077]: ---------------------------- MPD announces state update: player Oct 13 14:04:31 kueche volumio[1077]: info: ControllerMpd::getState Oct 13 14:04:31 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:04:31 kueche volumio[1077]: info: sendMpdCommand status took 6 milliseconds Oct 13 14:04:31 kueche volumio[1077]: info: sendMpdCommand status took 4 milliseconds Oct 13 14:04:31 kueche volumio[1077]: info: sendMpdCommand status took 3 milliseconds Oct 13 14:04:31 kueche volumio[1077]: verbose: ControllerMpd::parseState Oct 13 14:04:31 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:04:31 kueche volumio[1077]: verbose: ControllerMpd::parseState Oct 13 14:04:31 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:04:31 kueche volumio[1077]: verbose: ControllerMpd::parseState Oct 13 14:04:31 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:04:31 kueche volumio[1077]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 13 14:04:31 kueche volumio[1077]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 13 14:04:31 kueche volumio[1077]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 13 14:04:31 kueche volumio[1077]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:04:31 kueche volumio[1077]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:04:31 kueche volumio[1077]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:04:31 kueche volumio[1077]: info: ControllerMpd::pushState Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: verbose: STATE SERVICE {"status":"play","position":1,"seek":11,"duration":8,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"doorbell.mp3","artist":null,"album":"mobile-rington.ru","uri":"NAS/NAS/doorbell.mp3","trackType":"mp3"} Oct 13 14:04:31 kueche volumio[1077]: verbose: CURRENT POSITION 0 Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::syncState stateService play Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::syncState currentStatus play Oct 13 14:04:31 kueche volumio[1077]: info: Received an update from plugin. extracting info from payload Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:31 kueche volumio[1077]: info: ControllerMpd::pushState Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: verbose: STATE SERVICE {"status":"play","position":1,"seek":11,"duration":8,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"doorbell.mp3","artist":null,"album":"mobile-rington.ru","uri":"NAS/NAS/doorbell.mp3","trackType":"mp3"} Oct 13 14:04:31 kueche volumio[1077]: verbose: CURRENT POSITION 0 Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::syncState stateService play Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::syncState currentStatus play Oct 13 14:04:31 kueche volumio[1077]: info: Received an update from plugin. extracting info from payload Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:31 kueche volumio[1077]: info: ControllerMpd::pushState Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: verbose: STATE SERVICE {"status":"play","position":1,"seek":11,"duration":8,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"doorbell.mp3","artist":null,"album":"mobile-rington.ru","uri":"NAS/NAS/doorbell.mp3","trackType":"mp3"} Oct 13 14:04:31 kueche volumio[1077]: verbose: CURRENT POSITION 0 Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::syncState stateService play Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::syncState currentStatus play Oct 13 14:04:31 kueche volumio[1077]: info: Received an update from plugin. extracting info from payload Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:31 kueche volumio[1077]: info: ------------------------------ 108ms Oct 13 14:04:31 kueche volumio[1077]: info: ------------------------------ 107ms Oct 13 14:04:31 kueche volumio[1077]: info: ------------------------------ 106ms Oct 13 14:04:31 kueche volumio[1077]: info: Airplay started streaming, receiving metadatas Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::volumioStop Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::stop Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::stPlaybackTimer Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::updateTrackBlock Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrackBlock Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::serviceStop Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::serviceStop Oct 13 14:04:31 kueche volumio[1077]: info: ControllerMpd::stop Oct 13 14:04:31 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:04:31 kueche volumio[1077]: info: Airplay started streaming, receiving metadatas Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::volumioStop Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::stop Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:04:31 kueche volumio[1077]: info: Airplay started streaming, receiving metadatas Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::volumioStop Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::stop Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:04:31 kueche volumio[1077]: info: Oct 13 14:04:31 kueche volumio[1077]: ---------------------------- MPD announces state update: player Oct 13 14:04:31 kueche volumio[1077]: info: sendMpdCommand stop took 18 milliseconds Oct 13 14:04:31 kueche volumio[1077]: info: ControllerMpd::getState Oct 13 14:04:31 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:04:31 kueche volumio[1077]: info: sendMpdCommand status took 2 milliseconds Oct 13 14:04:31 kueche volumio[1077]: verbose: ControllerMpd::parseState Oct 13 14:04:31 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:04:31 kueche volumio[1077]: info: Oct 13 14:04:31 kueche volumio[1077]: ---------------------------- MPD announces state update: player Oct 13 14:04:31 kueche volumio[1077]: info: ControllerMpd::getState Oct 13 14:04:31 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:04:31 kueche volumio[1077]: info: Oct 13 14:04:31 kueche volumio[1077]: ---------------------------- MPD announces state update: player Oct 13 14:04:31 kueche volumio[1077]: info: ControllerMpd::getState Oct 13 14:04:31 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand status Oct 13 14:04:31 kueche volumio[1077]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 13 14:04:31 kueche volumio[1077]: info: sendMpdCommand status took 4 milliseconds Oct 13 14:04:31 kueche volumio[1077]: info: sendMpdCommand status took 3 milliseconds Oct 13 14:04:31 kueche volumio[1077]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:04:31 kueche volumio[1077]: verbose: ControllerMpd::parseState Oct 13 14:04:31 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:04:31 kueche volumio[1077]: verbose: ControllerMpd::parseState Oct 13 14:04:31 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 14:04:31 kueche volumio[1077]: info: ControllerMpd::pushState Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: verbose: STATE SERVICE {"status":"stop","position":1,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"doorbell.mp3","artist":null,"album":"mobile-rington.ru","uri":"NAS/NAS/doorbell.mp3","trackType":"mp3"} Oct 13 14:04:31 kueche volumio[1077]: verbose: CURRENT POSITION 0 Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::syncState stateService stop Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:31 kueche volumio[1077]: info: No code Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:31 kueche shairport-sync[2906]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 13 14:04:31 kueche shairport-sync[2906]: Dload Upload Total Spent Left Speed Oct 13 14:04:31 kueche volumio[1077]: info: ------------------------------ 55ms Oct 13 14:04:31 kueche volumio[1077]: info: sendMpdCommand playlistinfo took 46 milliseconds Oct 13 14:04:31 kueche volumio[1077]: info: sendMpdCommand playlistinfo took 46 milliseconds Oct 13 14:04:31 kueche volumio[1077]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:04:31 kueche volumio[1077]: verbose: ControllerMpd::parseTrackInfo Oct 13 14:04:31 kueche volumio[1077]: info: ControllerMpd::pushState Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: verbose: STATE SERVICE {"status":"stop","position":1,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"doorbell.mp3","artist":null,"album":"mobile-rington.ru","uri":"NAS/NAS/doorbell.mp3","trackType":"mp3"} Oct 13 14:04:31 kueche volumio[1077]: verbose: CURRENT POSITION 0 Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::syncState stateService stop Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:31 kueche volumio[1077]: info: No code Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:31 kueche volumio[1077]: info: ControllerMpd::pushState Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: verbose: STATE SERVICE {"status":"stop","position":1,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"doorbell.mp3","artist":null,"album":"mobile-rington.ru","uri":"NAS/NAS/doorbell.mp3","trackType":"mp3"} Oct 13 14:04:31 kueche volumio[1077]: verbose: CURRENT POSITION 0 Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::syncState stateService stop Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::syncState currentStatus stop Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:31 kueche volumio[1077]: info: No code Oct 13 14:04:31 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:31 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:31 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:32 kueche volumio[1077]: info: ------------------------------ 127ms Oct 13 14:04:32 kueche volumio[1077]: info: ------------------------------ 127ms Oct 13 14:04:32 kueche volumio[1077]: info: Oct 13 14:04:32 kueche volumio[1077]: ---------------------------- Client requests Start Airplay PlaybackRoutine Oct 13 14:04:32 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Oct 13 14:04:32 kueche volumio[1077]: info: Airplay playback start Oct 13 14:04:32 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:32 kueche volumio[1077]: info: CoreCommandRouter::volumioStop Oct 13 14:04:32 kueche volumio[1077]: info: CoreStateMachine::stop Oct 13 14:04:32 kueche volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:04:32 kueche volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:04:32 kueche shairport-sync[2906]: [158B blob data] Oct 13 14:04:32 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:32 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:32 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:04:32 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:32 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:32 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:32 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:32 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:32 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:32 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:33 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:33 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:33 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:33 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:33 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:33 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:04:33 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:33 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:33 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:33 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:33 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:33 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:33 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:33 kueche kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Oct 13 14:04:33 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:33 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:33 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:04:33 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:33 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:33 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:33 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:33 kueche volumio[1077]: info: CoreCommandRouter::servicePushState Oct 13 14:04:33 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:33 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:35 kueche volumio[1077]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 14:04:35 kueche volumio[1077]: info: CoreStateMachine::ClearQueue Oct 13 14:04:35 kueche volumio[1077]: info: CoreStateMachine::stop Oct 13 14:04:35 kueche volumio[1077]: info: CoreStateMachine::serviceStop Oct 13 14:04:35 kueche volumio[1077]: info: CoreCommandRouter::serviceStop Oct 13 14:04:35 kueche volumio[1077]: info: Airplay Stop Oct 13 14:04:35 kueche volumio[1077]: info: Stopping Airplay Playback and sending pause command to client via USR2 Oct 13 14:04:35 kueche volumio[1077]: info: CorePlayQueue::clearPlayQueue Oct 13 14:04:35 kueche volumio[1077]: info: CorePlayQueue::saveQueue Oct 13 14:04:35 kueche volumio[1077]: info: CoreCommandRouter::volumioPushQueue Oct 13 14:04:35 kueche volumio[1077]: info: CoreStateMachine::addQueueItems Oct 13 14:04:35 kueche volumio[1077]: info: CorePlayQueue::addQueueItems Oct 13 14:04:35 kueche volumio[1077]: info: Preload queue cleared Oct 13 14:04:35 kueche volumio[1077]: info: Adding Item to queue: https://scdn.nrjaudio.fm/adwz1/at/36001/mp3_128.mp3 Oct 13 14:04:35 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 13 14:04:35 kueche volumio[1077]: info: CoreCommandRouter::volumioPushQueue Oct 13 14:04:35 kueche volumio[1077]: info: CorePlayQueue::saveQueue Oct 13 14:04:35 kueche volumio[1077]: info: CoreStateMachine::updateTrackBlock Oct 13 14:04:35 kueche volumio[1077]: info: CorePlayQueue::getTrackBlock Oct 13 14:04:35 kueche volumio[1077]: info: CoreCommandRouter::volumioPlay Oct 13 14:04:35 kueche volumio[1077]: verbose: UNSET VOLATILE: Service: airplay_emulation Oct 13 14:04:35 kueche volumio[1077]: info: Stopping Airplay Playback and sending pause command to client via USR2 Oct 13 14:04:35 kueche sudo[2948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 2906 Oct 13 14:04:35 kueche sudo[2948]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 14:04:35 kueche volumio[1077]: info: CoreStateMachine::play index 0 Oct 13 14:04:35 kueche volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:04:35 kueche volumio[1077]: info: CoreStateMachine::stop Oct 13 14:04:35 kueche volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:04:35 kueche volumio[1077]: info: CoreStateMachine::stPlaybackTimer Oct 13 14:04:35 kueche volumio[1077]: info: CoreStateMachine::updateTrackBlock Oct 13 14:04:35 kueche volumio[1077]: info: CorePlayQueue::getTrackBlock Oct 13 14:04:35 kueche volumio[1077]: info: CoreStateMachine::pushState Oct 13 14:04:35 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:35 kueche volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 14:04:35 kueche volumio[1077]: info: CoreCommandRouter::volumioPushState Oct 13 14:04:35 kueche sudo[2948]: pam_unix(sudo:session): session closed for user root Oct 13 14:04:35 kueche volumio[1077]: info: CoreStateMachine::serviceStop Oct 13 14:04:35 kueche volumio[1077]: info: CorePlayQueue::getTrack 0 Oct 13 14:04:35 kueche volumio[1077]: info: CoreCommandRouter::serviceStop Oct 13 14:04:35 kueche volumio[1077]: info: [1728821075177] ControllerWebradio::stop Oct 13 14:04:35 kueche volumio[1077]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 14:04:35 kueche volumio[1077]: info: VolumeController::SetAlsaVolume50 Oct 13 14:04:35 kueche sudo[2952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 2906 Oct 13 14:04:35 kueche sudo[2952]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 14:04:35 kueche volumio[1077]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 14:04:35 kueche volumio[1077]: info: CoreStateMachine::ClearQueue Oct 13 14:04:35 kueche volumio[1077]: info: CoreStateMachine::stop Oct 13 14:04:35 kueche volumio[1077]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 14:04:35 kueche volumio[1077]: info: CorePlayQueue::clearPlayQueue Oct 13 14:04:35 kueche volumio[1077]: info: CorePlayQueue::saveQueue Oct 13 14:04:35 kueche volumio[1077]: info: CoreCommandRouter::volumioPushQueue Oct 13 14:04:35 kueche sudo[2952]: pam_unix(sudo:session): session closed for user root Oct 13 14:04:35 kueche volumio[1077]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 14:04:35 kueche volumio[1077]: TypeError: Cannot read property 'uri' of null Oct 13 14:04:35 kueche volumio[1077]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:495:12) Oct 13 14:04:35 kueche volumio[1077]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:108:33) Oct 13 14:04:35 kueche volumio[1077]: at Socket.emit (events.js:315:20) Oct 13 14:04:35 kueche volumio[1077]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 13 14:04:35 kueche volumio[1077]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 13 14:04:35 kueche volumio[1077]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 14:04:36 kueche sudo[2964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-13 14:03 Oct 13 14:04:36 kueche sudo[2964]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"