-- Logs begin at Wed 2025-05-21 22:43:44 CST, end at Thu 2025-05-22 20:02:45 CST. -- May 22 20:01:01 volumio volumio[1446]: 53 May 22 20:01:01 volumio volumio[1446]: % Total % Received % Xferd Average Speed Time Time Time Current May 22 20:01:01 volumio volumio[1446]: Dload Upload Total Spent Left Speed May 22 20:01:01 volumio volumio[22953]: info: May 22 20:01:01 volumio volumio[22953]: ---------------------------- Client requests Volume 53 May 22 20:01:01 volumio volumio[22953]: info: VolumeController::SetAlsaVolume53 May 22 20:01:01 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:01 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 20:01:01 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:01 volumio volumio[1446]: [158B blob data] May 22 20:01:01 volumio volumio[1446]: {"time":1747915261276,"response":"volume Success"}53 May 22 20:01:01 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:01 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:01 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:01 volumio volumio[1446]: 48 May 22 20:01:01 volumio volumio[1446]: % Total % Received % Xferd Average Speed Time Time Time Current May 22 20:01:01 volumio volumio[1446]: Dload Upload Total Spent Left Speed May 22 20:01:01 volumio volumio[22953]: info: May 22 20:01:01 volumio volumio[22953]: ---------------------------- Client requests Volume 48 May 22 20:01:01 volumio volumio[22953]: info: VolumeController::SetAlsaVolume48 May 22 20:01:01 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:01 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 20:01:01 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:01 volumio volumio[1446]: [158B blob data] May 22 20:01:01 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:01 volumio volumio[1446]: {"time":1747915261642,"response":"volume Success"}48 May 22 20:01:01 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:01 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:04 volumio volumio[1446]: 43 May 22 20:01:04 volumio volumio[1446]: % Total % Received % Xferd Average Speed Time Time Time Current May 22 20:01:04 volumio volumio[1446]: Dload Upload Total Spent Left Speed May 22 20:01:04 volumio volumio[22953]: info: May 22 20:01:04 volumio volumio[22953]: ---------------------------- Client requests Volume 43 May 22 20:01:04 volumio volumio[22953]: info: VolumeController::SetAlsaVolume43 May 22 20:01:04 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:04 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 20:01:04 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:04 volumio volumio[1446]: [158B blob data] May 22 20:01:05 volumio volumio[1446]: {"time":1747915264959,"response":"volume Success"}43 May 22 20:01:05 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:05 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:05 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:05 volumio volumio[1446]: 38 May 22 20:01:05 volumio volumio[1446]: % Total % Received % Xferd Average Speed Time Time Time Current May 22 20:01:05 volumio volumio[1446]: Dload Upload Total Spent Left Speed May 22 20:01:05 volumio volumio[22953]: info: May 22 20:01:05 volumio volumio[22953]: ---------------------------- Client requests Volume 38 May 22 20:01:05 volumio volumio[22953]: info: VolumeController::SetAlsaVolume38 May 22 20:01:05 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:05 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 20:01:05 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:05 volumio volumio[1446]: [158B blob data] May 22 20:01:05 volumio volumio[1446]: {"time":1747915265305,"response":"volume Success"}38 May 22 20:01:05 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:05 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:05 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:16 volumio volumio[22953]: info: May 22 20:01:16 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:16 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:16 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:16 volumio volumio[22953]: info: May 22 20:01:16 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:16 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:16 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:16 volumio volumio[22953]: info: May 22 20:01:16 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:16 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:16 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:16 volumio volumio[22953]: info: sendMpdCommand status took 3 milliseconds May 22 20:01:16 volumio volumio[22953]: info: sendMpdCommand status took 2 milliseconds May 22 20:01:16 volumio volumio[22953]: info: sendMpdCommand status took 1 milliseconds May 22 20:01:16 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:16 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:16 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:16 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:16 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:16 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:16 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:16 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:01:16 volumio volumio[22953]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 22 20:01:16 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:16 volumio volumio[22953]: info: CoreStateMachine::syncState stateService stop May 22 20:01:16 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:16 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:16 volumio volumio[22953]: info: CoreStateMachine::stPlaybackTimer May 22 20:01:16 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:16 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:16 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:16 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 20:01:16 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:16 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:01:16 volumio volumio[22953]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 22 20:01:16 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:16 volumio volumio[22953]: info: CoreStateMachine::syncState stateService stop May 22 20:01:16 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus stop May 22 20:01:16 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:16 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:16 volumio volumio[22953]: info: No code May 22 20:01:16 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:16 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:16 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:16 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:16 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:16 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:16 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:01:16 volumio volumio[22953]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 22 20:01:16 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:16 volumio volumio[22953]: info: CoreStateMachine::syncState stateService stop May 22 20:01:16 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus stop May 22 20:01:16 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:16 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:16 volumio volumio[22953]: info: No code May 22 20:01:16 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:16 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:16 volumio volumio[22953]: info: ------------------------------ 51ms May 22 20:01:16 volumio volumio[22953]: info: ------------------------------ 50ms May 22 20:01:16 volumio volumio[22953]: info: ------------------------------ 50ms May 22 20:01:16 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:16 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:16 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:20 volumio volumio[22953]: info: Clearing queue after UPNP request May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::ClearQueue May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::stop May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 20:01:21 volumio volumio[22953]: info: CorePlayQueue::clearPlayQueue May 22 20:01:21 volumio volumio[22953]: info: CorePlayQueue::saveQueue May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::volumioPushQueue May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:21 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:21 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:21 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:21 volumio volumio[22953]: error: updateQueue error: null May 22 20:01:21 volumio volumio[22953]: error: updateQueue error: null May 22 20:01:21 volumio volumio[22953]: error: updateQueue error: null May 22 20:01:21 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:21 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:21 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:01:21 volumio volumio[22953]: info: Starting UPNP Playback May 22 20:01:21 volumio volumio[22953]: info: Preparing playback through UPNP May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::volumioGetState May 22 20:01:21 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::setConsumeUpdateService mpd May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:21 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:21 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:21 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:21 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:21 volumio volumio[22953]: info: ------------------------------ 6ms May 22 20:01:21 volumio volumio[22953]: info: ------------------------------ 5ms May 22 20:01:21 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:21 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:21 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:21 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:21 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:21 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:21 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:21 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:21 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:21 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:21 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:21 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:21 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:21 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:21 volumio volumio[22953]: info: ------------------------------ 5ms May 22 20:01:21 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:21 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:21 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:21 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:21 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:21 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:21 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:21 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:21 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:21 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:21 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:21 volumio volumio[22953]: info: ------------------------------ 5ms May 22 20:01:21 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:21 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:21 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:21 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:21 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:21 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:21 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:21 volumio volumio[22953]: info: sendMpdCommand status took 26 milliseconds May 22 20:01:21 volumio volumio[22953]: info: sendMpdCommand status took 25 milliseconds May 22 20:01:21 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:21 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:21 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:21 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:21 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:21 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:21 volumio volumio[22953]: info: sendMpdCommand status took 26 milliseconds May 22 20:01:21 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 3 milliseconds May 22 20:01:21 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 2 milliseconds May 22 20:01:21 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:21 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:21 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:21 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:21 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:21 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2392,"samplerate":"12 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lose Control","artist":"Teddy Swims","album":"Lose Control","uri":"http://192.168.1.227:61323/mp3?filepath=%2Fstorage%2Femulated%2F0%2FDownload%2Fnetease%2Fcloudmusic%2FMusic%2FTeddy+Swims+-+Lose+Control.ncm&decode=false","trackType":""} May 22 20:01:21 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus stop May 22 20:01:21 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:21 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:21 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2392,"samplerate":"12 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lose Control","artist":"Teddy Swims","album":"Lose Control","uri":"http://192.168.1.227:61323/mp3?filepath=%2Fstorage%2Femulated%2F0%2FDownload%2Fnetease%2Fcloudmusic%2FMusic%2FTeddy+Swims+-+Lose+Control.ncm&decode=false","trackType":""} May 22 20:01:21 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:21 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:21 volumio volumio[22953]: info: ------------------------------ 39ms May 22 20:01:21 volumio volumio[22953]: info: ------------------------------ 55ms May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:21 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:21 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:21 volumio volumio[22953]: info: May 22 20:01:21 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:21 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:21 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:21 volumio volumio[22953]: info: sendMpdCommand status took 38 milliseconds May 22 20:01:21 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 40 milliseconds May 22 20:01:21 volumio volumio[22953]: info: sendMpdCommand status took 12 milliseconds May 22 20:01:21 volumio volumio[22953]: info: sendMpdCommand status took 6 milliseconds May 22 20:01:21 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:21 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:21 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:21 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:21 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:21 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:21 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:21 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:21 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:21 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2392,"samplerate":"12 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lose Control","artist":"Teddy Swims","album":"Lose Control","uri":"http://192.168.1.227:61323/mp3?filepath=%2Fstorage%2Femulated%2F0%2FDownload%2Fnetease%2Fcloudmusic%2FMusic%2FTeddy+Swims+-+Lose+Control.ncm&decode=false","trackType":""} May 22 20:01:21 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:21 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:21 volumio volumio[22953]: info: ------------------------------ 102ms May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:21 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:21 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:21 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:21 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:21 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 40 milliseconds May 22 20:01:21 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 31 milliseconds May 22 20:01:21 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 30 milliseconds May 22 20:01:21 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:21 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:21 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:21 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2392,"samplerate":"12 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lose Control","artist":"Teddy Swims","album":"Lose Control","uri":"http://192.168.1.227:61323/mp3?filepath=%2Fstorage%2Femulated%2F0%2FDownload%2Fnetease%2Fcloudmusic%2FMusic%2FTeddy+Swims+-+Lose+Control.ncm&decode=false","trackType":""} May 22 20:01:21 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:21 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:21 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:21 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:21 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2392,"samplerate":"12 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lose Control","artist":"Teddy Swims","album":"Lose Control","uri":"http://192.168.1.227:61323/mp3?filepath=%2Fstorage%2Femulated%2F0%2FDownload%2Fnetease%2Fcloudmusic%2FMusic%2FTeddy+Swims+-+Lose+Control.ncm&decode=false","trackType":""} May 22 20:01:21 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:21 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:21 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:21 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:21 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2392,"samplerate":"12 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lose Control","artist":"Teddy Swims","album":"Lose Control","uri":"http://192.168.1.227:61323/mp3?filepath=%2Fstorage%2Femulated%2F0%2FDownload%2Fnetease%2Fcloudmusic%2FMusic%2FTeddy+Swims+-+Lose+Control.ncm&decode=false","trackType":""} May 22 20:01:21 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:21 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:21 volumio volumio[22953]: info: ------------------------------ 149ms May 22 20:01:21 volumio volumio[22953]: info: ------------------------------ 119ms May 22 20:01:21 volumio volumio[22953]: info: ------------------------------ 121ms May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:21 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:21 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:21 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:21 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:21 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:21 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:21 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:21 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:25 volumio volumio[1446]: 43 May 22 20:01:25 volumio volumio[1446]: % Total % Received % Xferd Average Speed Time Time Time Current May 22 20:01:25 volumio volumio[1446]: Dload Upload Total Spent Left Speed May 22 20:01:25 volumio volumio[22953]: info: May 22 20:01:25 volumio volumio[22953]: ---------------------------- Client requests Volume 43 May 22 20:01:25 volumio volumio[22953]: info: VolumeController::SetAlsaVolume43 May 22 20:01:25 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:25 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 20:01:25 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:25 volumio volumio[1446]: [158B blob data] May 22 20:01:25 volumio volumio[1446]: {"time":1747915285138,"response":"volume Success"}43 May 22 20:01:25 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:25 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:25 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:27 volumio volumio[22953]: info: May 22 20:01:27 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:27 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:27 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:27 volumio volumio[22953]: info: May 22 20:01:27 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:27 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:27 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:27 volumio volumio[22953]: info: May 22 20:01:27 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:27 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:27 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:27 volumio volumio[22953]: info: sendMpdCommand status took 3 milliseconds May 22 20:01:27 volumio volumio[22953]: info: sendMpdCommand status took 2 milliseconds May 22 20:01:27 volumio volumio[22953]: info: sendMpdCommand status took 1 milliseconds May 22 20:01:27 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:27 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:27 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:27 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:27 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:27 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:27 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 2 milliseconds May 22 20:01:27 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 1 milliseconds May 22 20:01:27 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 1 milliseconds May 22 20:01:27 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:27 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:27 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:27 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:27 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:27 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:27 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:27 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:01:27 volumio volumio[22953]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lose Control","artist":"Teddy Swims","album":"Lose Control","uri":"http://192.168.1.227:61323/mp3?filepath=%2Fstorage%2Femulated%2F0%2FDownload%2Fnetease%2Fcloudmusic%2FMusic%2FTeddy+Swims+-+Lose+Control.ncm&decode=false","trackType":""} May 22 20:01:27 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:27 volumio volumio[22953]: info: CoreStateMachine::syncState stateService stop May 22 20:01:27 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:27 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:27 volumio volumio[22953]: info: CoreStateMachine::stPlaybackTimer May 22 20:01:27 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:27 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:27 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:27 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 20:01:27 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:27 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:01:27 volumio volumio[22953]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lose Control","artist":"Teddy Swims","album":"Lose Control","uri":"http://192.168.1.227:61323/mp3?filepath=%2Fstorage%2Femulated%2F0%2FDownload%2Fnetease%2Fcloudmusic%2FMusic%2FTeddy+Swims+-+Lose+Control.ncm&decode=false","trackType":""} May 22 20:01:27 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:27 volumio volumio[22953]: info: CoreStateMachine::syncState stateService stop May 22 20:01:27 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus stop May 22 20:01:27 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:27 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:27 volumio volumio[22953]: info: No code May 22 20:01:27 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:27 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:27 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:27 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:27 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:27 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:27 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:01:27 volumio volumio[22953]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lose Control","artist":"Teddy Swims","album":"Lose Control","uri":"http://192.168.1.227:61323/mp3?filepath=%2Fstorage%2Femulated%2F0%2FDownload%2Fnetease%2Fcloudmusic%2FMusic%2FTeddy+Swims+-+Lose+Control.ncm&decode=false","trackType":""} May 22 20:01:27 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:27 volumio volumio[22953]: info: CoreStateMachine::syncState stateService stop May 22 20:01:27 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus stop May 22 20:01:27 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:27 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:27 volumio volumio[22953]: info: No code May 22 20:01:27 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:27 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:27 volumio volumio[22953]: info: ------------------------------ 61ms May 22 20:01:27 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:27 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:27 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:28 volumio volumio[22953]: info: Clearing queue after UPNP request May 22 20:01:28 volumio volumio[22953]: info: CoreStateMachine::ClearQueue May 22 20:01:28 volumio volumio[22953]: info: CoreStateMachine::stop May 22 20:01:28 volumio volumio[22953]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 20:01:28 volumio volumio[22953]: info: CorePlayQueue::clearPlayQueue May 22 20:01:28 volumio volumio[22953]: info: CorePlayQueue::saveQueue May 22 20:01:28 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:28 volumio volumio[22953]: info: CoreCommandRouter::volumioPushQueue May 22 20:01:28 volumio volumio[22953]: info: May 22 20:01:28 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:28 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:28 volumio volumio[22953]: info: May 22 20:01:28 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:28 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:28 volumio volumio[22953]: info: May 22 20:01:28 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:28 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:28 volumio volumio[22953]: error: updateQueue error: null May 22 20:01:28 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:28 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:28 volumio volumio[22953]: info: Starting UPNP Playback May 22 20:01:28 volumio volumio[22953]: info: Preparing playback through UPNP May 22 20:01:28 volumio volumio[22953]: info: CoreCommandRouter::volumioGetState May 22 20:01:28 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:01:28 volumio volumio[22953]: info: CoreStateMachine::setConsumeUpdateService mpd May 22 20:01:28 volumio volumio[22953]: info: May 22 20:01:28 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:28 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:28 volumio volumio[22953]: info: May 22 20:01:28 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:28 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:28 volumio volumio[22953]: info: May 22 20:01:28 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:28 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:28 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:28 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:28 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:28 volumio volumio[22953]: info: May 22 20:01:28 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:28 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:28 volumio volumio[22953]: info: May 22 20:01:28 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:28 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:28 volumio volumio[22953]: info: May 22 20:01:28 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:28 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:28 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:28 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:28 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:01:28 volumio volumio[22953]: info: May 22 20:01:28 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:28 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:28 volumio volumio[22953]: info: May 22 20:01:28 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:28 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:28 volumio volumio[22953]: info: May 22 20:01:28 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:28 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:28 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:28 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:28 volumio volumio[22953]: info: May 22 20:01:28 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:28 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:28 volumio volumio[22953]: info: May 22 20:01:28 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:28 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:28 volumio volumio[22953]: info: May 22 20:01:28 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:28 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:28 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:28 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:01:28 volumio volumio[22953]: info: ------------------------------ 1ms May 22 20:01:28 volumio volumio[22953]: info: May 22 20:01:28 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:28 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:28 volumio volumio[22953]: info: May 22 20:01:28 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:28 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:28 volumio volumio[22953]: info: May 22 20:01:28 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:28 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:28 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:28 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:01:28 volumio volumio[22953]: info: May 22 20:01:28 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:28 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:28 volumio volumio[22953]: info: May 22 20:01:28 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:28 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:28 volumio volumio[22953]: info: May 22 20:01:28 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:28 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:28 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:28 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:28 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:01:29 volumio volumio[22953]: info: May 22 20:01:29 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:29 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:29 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:29 volumio volumio[22953]: info: May 22 20:01:29 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:29 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:29 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:29 volumio volumio[22953]: info: May 22 20:01:29 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:29 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:29 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:29 volumio volumio[22953]: info: May 22 20:01:29 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:29 volumio volumio[22953]: info: sendMpdCommand status took 6 milliseconds May 22 20:01:29 volumio volumio[22953]: info: sendMpdCommand status took 5 milliseconds May 22 20:01:29 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:29 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:29 volumio volumio[22953]: info: sendMpdCommand status took 3 milliseconds May 22 20:01:29 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:29 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:29 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:29 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:29 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:29 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:29 volumio volumio[22953]: info: May 22 20:01:29 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:29 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:29 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:29 volumio volumio[22953]: info: May 22 20:01:29 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:29 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:29 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:29 volumio volumio[22953]: info: sendMpdCommand status took 12 milliseconds May 22 20:01:29 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 11 milliseconds May 22 20:01:29 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 11 milliseconds May 22 20:01:29 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 9 milliseconds May 22 20:01:29 volumio volumio[22953]: info: sendMpdCommand status took 3 milliseconds May 22 20:01:29 volumio volumio[22953]: info: sendMpdCommand status took 2 milliseconds May 22 20:01:29 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:29 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:29 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:29 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:29 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:29 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:29 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:29 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:29 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:29 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:29 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":181,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"死神","artist":"米津玄師","album":"Pale Blue","uri":"http://m701.music.126.net/20250522202628/3a8cd46ec0943376e6fd8ecca32d4513/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32585208232/6efb/e1cb/425c/ec778430547bd89f038ad388a8a84cba.mp3?vuutv=KZSwDyHF1OW+ySddwb7DZ1chYqLXOrtq2ShTZuMIo/nzG+GIwBLM+89ER8m60XUpI2pU4V2Eg7hdhoG3CwtTdPNWFCtxMZvBgX3G8U+6aqM=","trackType":""} May 22 20:01:29 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus stop May 22 20:01:29 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:29 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:29 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":181,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"死神","artist":"米津玄師","album":"Pale Blue","uri":"http://m701.music.126.net/20250522202628/3a8cd46ec0943376e6fd8ecca32d4513/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32585208232/6efb/e1cb/425c/ec778430547bd89f038ad388a8a84cba.mp3?vuutv=KZSwDyHF1OW+ySddwb7DZ1chYqLXOrtq2ShTZuMIo/nzG+GIwBLM+89ER8m60XUpI2pU4V2Eg7hdhoG3CwtTdPNWFCtxMZvBgX3G8U+6aqM=","trackType":""} May 22 20:01:29 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:29 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:29 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:29 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:29 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":181,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"死神","artist":"米津玄師","album":"Pale Blue","uri":"http://m701.music.126.net/20250522202628/3a8cd46ec0943376e6fd8ecca32d4513/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32585208232/6efb/e1cb/425c/ec778430547bd89f038ad388a8a84cba.mp3?vuutv=KZSwDyHF1OW+ySddwb7DZ1chYqLXOrtq2ShTZuMIo/nzG+GIwBLM+89ER8m60XUpI2pU4V2Eg7hdhoG3CwtTdPNWFCtxMZvBgX3G8U+6aqM=","trackType":""} May 22 20:01:29 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:29 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:29 volumio volumio[22953]: info: ------------------------------ 36ms May 22 20:01:29 volumio volumio[22953]: info: ------------------------------ 74ms May 22 20:01:29 volumio volumio[22953]: info: ------------------------------ 82ms May 22 20:01:29 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 68 milliseconds May 22 20:01:29 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 71 milliseconds May 22 20:01:29 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 73 milliseconds May 22 20:01:29 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:29 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:29 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:29 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":181,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"死神","artist":"米津玄師","album":"Pale Blue","uri":"http://m701.music.126.net/20250522202628/3a8cd46ec0943376e6fd8ecca32d4513/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32585208232/6efb/e1cb/425c/ec778430547bd89f038ad388a8a84cba.mp3?vuutv=KZSwDyHF1OW+ySddwb7DZ1chYqLXOrtq2ShTZuMIo/nzG+GIwBLM+89ER8m60XUpI2pU4V2Eg7hdhoG3CwtTdPNWFCtxMZvBgX3G8U+6aqM=","trackType":""} May 22 20:01:29 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:29 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:29 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:29 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:29 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":181,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"死神","artist":"米津玄師","album":"Pale Blue","uri":"http://m701.music.126.net/20250522202628/3a8cd46ec0943376e6fd8ecca32d4513/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32585208232/6efb/e1cb/425c/ec778430547bd89f038ad388a8a84cba.mp3?vuutv=KZSwDyHF1OW+ySddwb7DZ1chYqLXOrtq2ShTZuMIo/nzG+GIwBLM+89ER8m60XUpI2pU4V2Eg7hdhoG3CwtTdPNWFCtxMZvBgX3G8U+6aqM=","trackType":""} May 22 20:01:29 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:29 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:29 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:29 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:29 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":181,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"死神","artist":"米津玄師","album":"Pale Blue","uri":"http://m701.music.126.net/20250522202628/3a8cd46ec0943376e6fd8ecca32d4513/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32585208232/6efb/e1cb/425c/ec778430547bd89f038ad388a8a84cba.mp3?vuutv=KZSwDyHF1OW+ySddwb7DZ1chYqLXOrtq2ShTZuMIo/nzG+GIwBLM+89ER8m60XUpI2pU4V2Eg7hdhoG3CwtTdPNWFCtxMZvBgX3G8U+6aqM=","trackType":""} May 22 20:01:29 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:29 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:29 volumio volumio[22953]: info: ------------------------------ 150ms May 22 20:01:29 volumio volumio[22953]: info: ------------------------------ 144ms May 22 20:01:29 volumio volumio[22953]: info: ------------------------------ 141ms May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:29 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:29 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:29 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:29 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:29 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:29 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:29 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:29 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:29 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:29 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:29 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:29 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:32 volumio volumio[1446]: 48 May 22 20:01:32 volumio volumio[1446]: % Total % Received % Xferd Average Speed Time Time Time Current May 22 20:01:32 volumio volumio[1446]: Dload Upload Total Spent Left Speed May 22 20:01:32 volumio volumio[22953]: info: May 22 20:01:32 volumio volumio[22953]: ---------------------------- Client requests Volume 48 May 22 20:01:32 volumio volumio[22953]: info: VolumeController::SetAlsaVolume48 May 22 20:01:32 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:32 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 20:01:32 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:32 volumio volumio[1446]: [158B blob data] May 22 20:01:32 volumio volumio[1446]: {"time":1747915292612,"response":"volume Success"}48 May 22 20:01:32 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:32 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:32 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:32 volumio volumio[1446]: 53 May 22 20:01:33 volumio volumio[1446]: % Total % Received % Xferd Average Speed Time Time Time Current May 22 20:01:33 volumio volumio[1446]: Dload Upload Total Spent Left Speed May 22 20:01:33 volumio volumio[22953]: info: May 22 20:01:33 volumio volumio[22953]: ---------------------------- Client requests Volume 53 May 22 20:01:33 volumio volumio[22953]: info: VolumeController::SetAlsaVolume53 May 22 20:01:33 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:33 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 20:01:33 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:33 volumio volumio[1446]: [158B blob data] May 22 20:01:33 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:33 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:33 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:33 volumio volumio[1446]: {"time":1747915293067,"response":"volume Success"}53 May 22 20:01:42 volumio volumio[22953]: info: May 22 20:01:42 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:42 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:42 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:42 volumio volumio[22953]: info: May 22 20:01:42 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:43 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:43 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:43 volumio volumio[22953]: info: May 22 20:01:43 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:43 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:43 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:43 volumio volumio[22953]: info: sendMpdCommand status took 2 milliseconds May 22 20:01:43 volumio volumio[22953]: info: sendMpdCommand status took 1 milliseconds May 22 20:01:43 volumio volumio[22953]: info: sendMpdCommand status took 1 milliseconds May 22 20:01:43 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:43 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:43 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:43 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:43 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:43 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:43 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 1 milliseconds May 22 20:01:43 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 1 milliseconds May 22 20:01:43 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 1 milliseconds May 22 20:01:43 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:43 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:43 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:43 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:43 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:43 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:43 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:43 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:01:43 volumio volumio[22953]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"死神","artist":"米津玄師","album":"Pale Blue","uri":"http://m701.music.126.net/20250522202628/3a8cd46ec0943376e6fd8ecca32d4513/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32585208232/6efb/e1cb/425c/ec778430547bd89f038ad388a8a84cba.mp3?vuutv=KZSwDyHF1OW+ySddwb7DZ1chYqLXOrtq2ShTZuMIo/nzG+GIwBLM+89ER8m60XUpI2pU4V2Eg7hdhoG3CwtTdPNWFCtxMZvBgX3G8U+6aqM=","trackType":""} May 22 20:01:43 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:43 volumio volumio[22953]: info: CoreStateMachine::syncState stateService stop May 22 20:01:43 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:43 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:43 volumio volumio[22953]: info: CoreStateMachine::stPlaybackTimer May 22 20:01:43 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:43 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:43 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:43 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 20:01:43 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:43 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:01:43 volumio volumio[22953]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"死神","artist":"米津玄師","album":"Pale Blue","uri":"http://m701.music.126.net/20250522202628/3a8cd46ec0943376e6fd8ecca32d4513/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32585208232/6efb/e1cb/425c/ec778430547bd89f038ad388a8a84cba.mp3?vuutv=KZSwDyHF1OW+ySddwb7DZ1chYqLXOrtq2ShTZuMIo/nzG+GIwBLM+89ER8m60XUpI2pU4V2Eg7hdhoG3CwtTdPNWFCtxMZvBgX3G8U+6aqM=","trackType":""} May 22 20:01:43 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:43 volumio volumio[22953]: info: CoreStateMachine::syncState stateService stop May 22 20:01:43 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus stop May 22 20:01:43 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:43 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:43 volumio volumio[22953]: info: No code May 22 20:01:43 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:43 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:43 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:43 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:43 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:43 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:43 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:01:43 volumio volumio[22953]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"死神","artist":"米津玄師","album":"Pale Blue","uri":"http://m701.music.126.net/20250522202628/3a8cd46ec0943376e6fd8ecca32d4513/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32585208232/6efb/e1cb/425c/ec778430547bd89f038ad388a8a84cba.mp3?vuutv=KZSwDyHF1OW+ySddwb7DZ1chYqLXOrtq2ShTZuMIo/nzG+GIwBLM+89ER8m60XUpI2pU4V2Eg7hdhoG3CwtTdPNWFCtxMZvBgX3G8U+6aqM=","trackType":""} May 22 20:01:43 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:43 volumio volumio[22953]: info: CoreStateMachine::syncState stateService stop May 22 20:01:43 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus stop May 22 20:01:43 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:43 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:43 volumio volumio[22953]: info: No code May 22 20:01:43 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:43 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:43 volumio volumio[22953]: info: ------------------------------ 73ms May 22 20:01:43 volumio volumio[22953]: info: ------------------------------ 73ms May 22 20:01:43 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:43 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:43 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:43 volumio volumio[22953]: info: Clearing queue after UPNP request May 22 20:01:43 volumio volumio[22953]: info: CoreStateMachine::ClearQueue May 22 20:01:43 volumio volumio[22953]: info: CoreStateMachine::stop May 22 20:01:43 volumio volumio[22953]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 20:01:43 volumio volumio[22953]: info: CorePlayQueue::clearPlayQueue May 22 20:01:43 volumio volumio[22953]: info: CorePlayQueue::saveQueue May 22 20:01:43 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:43 volumio volumio[22953]: info: CoreCommandRouter::volumioPushQueue May 22 20:01:43 volumio volumio[22953]: info: May 22 20:01:43 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:43 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:43 volumio volumio[22953]: info: May 22 20:01:43 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:43 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:43 volumio volumio[22953]: info: May 22 20:01:43 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:43 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:43 volumio volumio[22953]: error: updateQueue error: null May 22 20:01:43 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:43 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:43 volumio volumio[22953]: info: Starting UPNP Playback May 22 20:01:43 volumio volumio[22953]: info: Preparing playback through UPNP May 22 20:01:43 volumio volumio[22953]: info: CoreCommandRouter::volumioGetState May 22 20:01:43 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:01:43 volumio volumio[22953]: info: CoreStateMachine::setConsumeUpdateService mpd May 22 20:01:43 volumio volumio[22953]: info: May 22 20:01:43 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:43 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:43 volumio volumio[22953]: info: May 22 20:01:43 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:43 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:43 volumio volumio[22953]: info: May 22 20:01:43 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:43 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:43 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:43 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:43 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:43 volumio volumio[22953]: info: May 22 20:01:43 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:43 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:43 volumio volumio[22953]: info: May 22 20:01:43 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:43 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:43 volumio volumio[22953]: info: May 22 20:01:43 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:43 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:43 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:43 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:43 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:43 volumio volumio[22953]: info: May 22 20:01:43 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:43 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:43 volumio volumio[22953]: info: May 22 20:01:43 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:43 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:43 volumio volumio[22953]: info: May 22 20:01:43 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:43 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:43 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:43 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:43 volumio volumio[22953]: info: May 22 20:01:43 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:43 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:43 volumio volumio[22953]: info: May 22 20:01:43 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:43 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:43 volumio volumio[22953]: info: May 22 20:01:43 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:43 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:43 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:43 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:43 volumio volumio[22953]: info: May 22 20:01:43 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:43 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:43 volumio volumio[22953]: info: May 22 20:01:43 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:43 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:43 volumio volumio[22953]: info: May 22 20:01:43 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:43 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:44 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:44 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:44 volumio volumio[22953]: info: May 22 20:01:44 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:44 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:44 volumio volumio[22953]: info: May 22 20:01:44 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:44 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:44 volumio volumio[22953]: info: May 22 20:01:44 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:44 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:44 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:44 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:44 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:44 volumio volumio[22953]: info: May 22 20:01:44 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:44 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:44 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:44 volumio volumio[22953]: info: May 22 20:01:44 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:44 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:44 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:44 volumio volumio[22953]: info: May 22 20:01:44 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:44 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:44 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:44 volumio volumio[22953]: info: May 22 20:01:44 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:44 volumio volumio[22953]: info: sendMpdCommand status took 26 milliseconds May 22 20:01:44 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:44 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:44 volumio volumio[22953]: info: sendMpdCommand status took 22 milliseconds May 22 20:01:44 volumio volumio[22953]: info: sendMpdCommand status took 20 milliseconds May 22 20:01:44 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:44 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:44 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:44 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:44 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:44 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:44 volumio volumio[22953]: info: May 22 20:01:44 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:44 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:44 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:44 volumio volumio[22953]: info: May 22 20:01:44 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:44 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:44 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:44 volumio volumio[22953]: info: sendMpdCommand status took 9 milliseconds May 22 20:01:44 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 10 milliseconds May 22 20:01:44 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 9 milliseconds May 22 20:01:44 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 9 milliseconds May 22 20:01:44 volumio volumio[22953]: info: sendMpdCommand status took 7 milliseconds May 22 20:01:44 volumio volumio[22953]: info: sendMpdCommand status took 3 milliseconds May 22 20:01:44 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:44 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:44 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:44 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:44 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:44 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:44 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:44 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:44 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:44 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:44 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:44 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":273,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"地球儀","artist":"米津玄師","album":"地球儀","uri":"http://m701.music.126.net/20250522202643/bcd94a0ec6cf6aea8ba0d216a059bb8f/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/44368031700/95fa/ea81/4123/fe43fb28c7d1825ec50151a4122cfd20.mp3?vuutv=c+b3HQGVynTAJLttmg+XIuGBoeu1G1Z3Pe/xSO+LdxObihJFyanMil/+xWGx4Dgw2NIUYjW9vv6GPkWHY49uWcHlC3Qhqd9PZX3dA1RyRhw=","trackType":""} May 22 20:01:44 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus stop May 22 20:01:44 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:44 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:44 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":273,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"地球儀","artist":"米津玄師","album":"地球儀","uri":"http://m701.music.126.net/20250522202643/bcd94a0ec6cf6aea8ba0d216a059bb8f/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/44368031700/95fa/ea81/4123/fe43fb28c7d1825ec50151a4122cfd20.mp3?vuutv=c+b3HQGVynTAJLttmg+XIuGBoeu1G1Z3Pe/xSO+LdxObihJFyanMil/+xWGx4Dgw2NIUYjW9vv6GPkWHY49uWcHlC3Qhqd9PZX3dA1RyRhw=","trackType":""} May 22 20:01:44 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:44 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:44 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:44 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:44 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":273,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"地球儀","artist":"米津玄師","album":"地球儀","uri":"http://m701.music.126.net/20250522202643/bcd94a0ec6cf6aea8ba0d216a059bb8f/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/44368031700/95fa/ea81/4123/fe43fb28c7d1825ec50151a4122cfd20.mp3?vuutv=c+b3HQGVynTAJLttmg+XIuGBoeu1G1Z3Pe/xSO+LdxObihJFyanMil/+xWGx4Dgw2NIUYjW9vv6GPkWHY49uWcHlC3Qhqd9PZX3dA1RyRhw=","trackType":""} May 22 20:01:44 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:44 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:44 volumio volumio[22953]: info: ------------------------------ 67ms May 22 20:01:44 volumio volumio[22953]: info: ------------------------------ 108ms May 22 20:01:44 volumio volumio[22953]: info: ------------------------------ 103ms May 22 20:01:44 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 73 milliseconds May 22 20:01:44 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 72 milliseconds May 22 20:01:44 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 72 milliseconds May 22 20:01:44 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:44 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:44 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:44 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:44 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":273,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"地球儀","artist":"米津玄師","album":"地球儀","uri":"http://m701.music.126.net/20250522202643/bcd94a0ec6cf6aea8ba0d216a059bb8f/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/44368031700/95fa/ea81/4123/fe43fb28c7d1825ec50151a4122cfd20.mp3?vuutv=c+b3HQGVynTAJLttmg+XIuGBoeu1G1Z3Pe/xSO+LdxObihJFyanMil/+xWGx4Dgw2NIUYjW9vv6GPkWHY49uWcHlC3Qhqd9PZX3dA1RyRhw=","trackType":""} May 22 20:01:44 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:44 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:44 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:44 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:44 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":273,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"地球儀","artist":"米津玄師","album":"地球儀","uri":"http://m701.music.126.net/20250522202643/bcd94a0ec6cf6aea8ba0d216a059bb8f/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/44368031700/95fa/ea81/4123/fe43fb28c7d1825ec50151a4122cfd20.mp3?vuutv=c+b3HQGVynTAJLttmg+XIuGBoeu1G1Z3Pe/xSO+LdxObihJFyanMil/+xWGx4Dgw2NIUYjW9vv6GPkWHY49uWcHlC3Qhqd9PZX3dA1RyRhw=","trackType":""} May 22 20:01:44 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:44 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:44 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:44 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:44 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":273,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"地球儀","artist":"米津玄師","album":"地球儀","uri":"http://m701.music.126.net/20250522202643/bcd94a0ec6cf6aea8ba0d216a059bb8f/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/44368031700/95fa/ea81/4123/fe43fb28c7d1825ec50151a4122cfd20.mp3?vuutv=c+b3HQGVynTAJLttmg+XIuGBoeu1G1Z3Pe/xSO+LdxObihJFyanMil/+xWGx4Dgw2NIUYjW9vv6GPkWHY49uWcHlC3Qhqd9PZX3dA1RyRhw=","trackType":""} May 22 20:01:44 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:44 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:44 volumio volumio[22953]: info: ------------------------------ 135ms May 22 20:01:44 volumio volumio[22953]: info: ------------------------------ 131ms May 22 20:01:44 volumio volumio[22953]: info: ------------------------------ 129ms May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:44 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:44 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:44 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:44 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:44 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:44 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:44 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:44 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:44 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:44 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:44 volumio volumio[1446]: 58 May 22 20:01:44 volumio volumio[1446]: % Total % Received % Xferd Average Speed Time Time Time Current May 22 20:01:44 volumio volumio[1446]: Dload Upload Total Spent Left Speed May 22 20:01:44 volumio volumio[22953]: info: May 22 20:01:44 volumio volumio[22953]: ---------------------------- Client requests Volume 58 May 22 20:01:44 volumio volumio[22953]: info: VolumeController::SetAlsaVolume58 May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:44 volumio volumio[1446]: [158B blob data] May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:44 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:44 volumio volumio[1446]: {"time":1747915304699,"response":"volume Success"}58 May 22 20:01:44 volumio volumio[1446]: 63 May 22 20:01:44 volumio volumio[1446]: % Total % Received % Xferd Average Speed Time Time Time Current May 22 20:01:44 volumio volumio[1446]: Dload Upload Total Spent Left Speed May 22 20:01:44 volumio volumio[22953]: info: May 22 20:01:44 volumio volumio[22953]: ---------------------------- Client requests Volume 63 May 22 20:01:44 volumio volumio[22953]: info: VolumeController::SetAlsaVolume63 May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:44 volumio volumio[1446]: [158B blob data] May 22 20:01:44 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:44 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:44 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:44 volumio volumio[1446]: {"time":1747915304937,"response":"volume Success"}63 May 22 20:01:54 volumio volumio[22953]: info: May 22 20:01:54 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:54 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:54 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:54 volumio volumio[22953]: info: May 22 20:01:54 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:54 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:54 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:54 volumio volumio[22953]: info: May 22 20:01:54 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:54 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:54 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:54 volumio volumio[22953]: info: sendMpdCommand status took 2 milliseconds May 22 20:01:54 volumio volumio[22953]: info: sendMpdCommand status took 1 milliseconds May 22 20:01:54 volumio volumio[22953]: info: sendMpdCommand status took 2 milliseconds May 22 20:01:54 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:54 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:54 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:54 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:54 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:54 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:54 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 1 milliseconds May 22 20:01:54 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 1 milliseconds May 22 20:01:54 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 2 milliseconds May 22 20:01:54 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:54 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:54 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:54 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:54 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:54 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:54 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:54 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:01:54 volumio volumio[22953]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"地球儀","artist":"米津玄師","album":"地球儀","uri":"http://m701.music.126.net/20250522202643/bcd94a0ec6cf6aea8ba0d216a059bb8f/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/44368031700/95fa/ea81/4123/fe43fb28c7d1825ec50151a4122cfd20.mp3?vuutv=c+b3HQGVynTAJLttmg+XIuGBoeu1G1Z3Pe/xSO+LdxObihJFyanMil/+xWGx4Dgw2NIUYjW9vv6GPkWHY49uWcHlC3Qhqd9PZX3dA1RyRhw=","trackType":""} May 22 20:01:54 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:54 volumio volumio[22953]: info: CoreStateMachine::syncState stateService stop May 22 20:01:54 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:54 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:54 volumio volumio[22953]: info: CoreStateMachine::stPlaybackTimer May 22 20:01:54 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:54 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:54 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:54 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 20:01:54 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:54 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:01:54 volumio volumio[22953]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"地球儀","artist":"米津玄師","album":"地球儀","uri":"http://m701.music.126.net/20250522202643/bcd94a0ec6cf6aea8ba0d216a059bb8f/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/44368031700/95fa/ea81/4123/fe43fb28c7d1825ec50151a4122cfd20.mp3?vuutv=c+b3HQGVynTAJLttmg+XIuGBoeu1G1Z3Pe/xSO+LdxObihJFyanMil/+xWGx4Dgw2NIUYjW9vv6GPkWHY49uWcHlC3Qhqd9PZX3dA1RyRhw=","trackType":""} May 22 20:01:54 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:54 volumio volumio[22953]: info: CoreStateMachine::syncState stateService stop May 22 20:01:54 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus stop May 22 20:01:54 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:54 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:54 volumio volumio[22953]: info: No code May 22 20:01:54 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:54 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:54 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:54 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:54 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:54 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:54 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:01:54 volumio volumio[22953]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"地球儀","artist":"米津玄師","album":"地球儀","uri":"http://m701.music.126.net/20250522202643/bcd94a0ec6cf6aea8ba0d216a059bb8f/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/44368031700/95fa/ea81/4123/fe43fb28c7d1825ec50151a4122cfd20.mp3?vuutv=c+b3HQGVynTAJLttmg+XIuGBoeu1G1Z3Pe/xSO+LdxObihJFyanMil/+xWGx4Dgw2NIUYjW9vv6GPkWHY49uWcHlC3Qhqd9PZX3dA1RyRhw=","trackType":""} May 22 20:01:54 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:54 volumio volumio[22953]: info: CoreStateMachine::syncState stateService stop May 22 20:01:54 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus stop May 22 20:01:54 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:54 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:54 volumio volumio[22953]: info: No code May 22 20:01:54 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:54 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:54 volumio volumio[22953]: info: ------------------------------ 55ms May 22 20:01:54 volumio volumio[22953]: info: ------------------------------ 54ms May 22 20:01:54 volumio volumio[22953]: info: ------------------------------ 54ms May 22 20:01:54 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:54 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:54 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:54 volumio volumio[22953]: info: Clearing queue after UPNP request May 22 20:01:54 volumio volumio[22953]: info: CoreStateMachine::ClearQueue May 22 20:01:54 volumio volumio[22953]: info: CoreStateMachine::stop May 22 20:01:54 volumio volumio[22953]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 20:01:54 volumio volumio[22953]: info: CorePlayQueue::clearPlayQueue May 22 20:01:54 volumio volumio[22953]: info: CorePlayQueue::saveQueue May 22 20:01:54 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:54 volumio volumio[22953]: info: CoreCommandRouter::volumioPushQueue May 22 20:01:54 volumio volumio[22953]: info: May 22 20:01:54 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:54 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:54 volumio volumio[22953]: info: May 22 20:01:54 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:54 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:54 volumio volumio[22953]: info: May 22 20:01:54 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:54 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:54 volumio volumio[22953]: error: updateQueue error: null May 22 20:01:54 volumio volumio[22953]: error: updateQueue error: null May 22 20:01:54 volumio volumio[22953]: error: updateQueue error: null May 22 20:01:54 volumio volumio[22953]: info: ------------------------------ 5ms May 22 20:01:54 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:54 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:54 volumio volumio[22953]: info: Starting UPNP Playback May 22 20:01:54 volumio volumio[22953]: info: Preparing playback through UPNP May 22 20:01:54 volumio volumio[22953]: info: CoreCommandRouter::volumioGetState May 22 20:01:54 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:01:54 volumio volumio[22953]: info: CoreStateMachine::setConsumeUpdateService mpd May 22 20:01:55 volumio volumio[22953]: info: May 22 20:01:55 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:55 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:55 volumio volumio[22953]: info: May 22 20:01:55 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:55 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:55 volumio volumio[22953]: info: May 22 20:01:55 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:55 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:55 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:01:55 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:01:55 volumio volumio[22953]: info: ------------------------------ 1ms May 22 20:01:55 volumio volumio[22953]: info: May 22 20:01:55 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:55 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:55 volumio volumio[22953]: info: May 22 20:01:55 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:55 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:55 volumio volumio[22953]: info: May 22 20:01:55 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:55 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:55 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:01:55 volumio volumio[22953]: info: ------------------------------ 1ms May 22 20:01:55 volumio volumio[22953]: info: May 22 20:01:55 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:55 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:55 volumio volumio[22953]: info: May 22 20:01:55 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:55 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:55 volumio volumio[22953]: info: May 22 20:01:55 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:55 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:55 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:01:55 volumio volumio[22953]: info: ------------------------------ 1ms May 22 20:01:55 volumio volumio[22953]: info: May 22 20:01:55 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:55 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:55 volumio volumio[22953]: info: May 22 20:01:55 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:55 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:55 volumio volumio[22953]: info: May 22 20:01:55 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:55 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:55 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:01:55 volumio volumio[22953]: info: ------------------------------ 1ms May 22 20:01:55 volumio volumio[22953]: info: May 22 20:01:55 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:55 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:55 volumio volumio[22953]: info: May 22 20:01:55 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:55 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:55 volumio volumio[22953]: info: May 22 20:01:55 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:55 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:55 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:55 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:01:55 volumio volumio[22953]: info: May 22 20:01:55 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:55 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:55 volumio volumio[22953]: info: May 22 20:01:55 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:55 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:55 volumio volumio[22953]: info: May 22 20:01:55 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:55 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:55 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:01:55 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:01:55 volumio volumio[22953]: info: ------------------------------ 1ms May 22 20:01:55 volumio volumio[22953]: info: May 22 20:01:55 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:55 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:55 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:55 volumio volumio[22953]: info: May 22 20:01:55 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:55 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:55 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:55 volumio volumio[22953]: info: May 22 20:01:55 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:55 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:55 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:55 volumio volumio[22953]: info: May 22 20:01:55 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:55 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:55 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:55 volumio volumio[22953]: info: sendMpdCommand status took 6 milliseconds May 22 20:01:55 volumio volumio[22953]: info: sendMpdCommand status took 4 milliseconds May 22 20:01:55 volumio volumio[22953]: info: sendMpdCommand status took 4 milliseconds May 22 20:01:55 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:55 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:55 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:55 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:55 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:55 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:55 volumio volumio[22953]: info: May 22 20:01:55 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:55 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:55 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:55 volumio volumio[22953]: info: May 22 20:01:55 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:55 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:55 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:55 volumio volumio[22953]: info: sendMpdCommand status took 8 milliseconds May 22 20:01:55 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 7 milliseconds May 22 20:01:55 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 8 milliseconds May 22 20:01:55 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 7 milliseconds May 22 20:01:55 volumio volumio[22953]: info: sendMpdCommand status took 4 milliseconds May 22 20:01:55 volumio volumio[22953]: info: sendMpdCommand status took 3 milliseconds May 22 20:01:55 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:55 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:55 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:55 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:55 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:55 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:55 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:55 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:55 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:55 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Memory","artist":"MACO","album":"メトロノーム","uri":"http://m701.music.126.net/20250522202654/58ad840aafed4b328ef846c94963cf1b/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/7305179339/b94a/7e6d/8977/b6af13b7f900327110ec47d49a1827c1.mp3?vuutv=1M+ci78w/72fuyo6KplmTGGis0z552yHCKe5TxhDr5Bu68De0UsTKIfjyxhG4+GiARy2tnag2E1ArpnSm5VXwkdAiOouQV4eS/Dt/ix7xsc=","trackType":""} May 22 20:01:55 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus stop May 22 20:01:55 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:55 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:55 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Memory","artist":"MACO","album":"メトロノーム","uri":"http://m701.music.126.net/20250522202654/58ad840aafed4b328ef846c94963cf1b/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/7305179339/b94a/7e6d/8977/b6af13b7f900327110ec47d49a1827c1.mp3?vuutv=1M+ci78w/72fuyo6KplmTGGis0z552yHCKe5TxhDr5Bu68De0UsTKIfjyxhG4+GiARy2tnag2E1ArpnSm5VXwkdAiOouQV4eS/Dt/ix7xsc=","trackType":""} May 22 20:01:55 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:55 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:55 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:55 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:55 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Memory","artist":"MACO","album":"メトロノーム","uri":"http://m701.music.126.net/20250522202654/58ad840aafed4b328ef846c94963cf1b/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/7305179339/b94a/7e6d/8977/b6af13b7f900327110ec47d49a1827c1.mp3?vuutv=1M+ci78w/72fuyo6KplmTGGis0z552yHCKe5TxhDr5Bu68De0UsTKIfjyxhG4+GiARy2tnag2E1ArpnSm5VXwkdAiOouQV4eS/Dt/ix7xsc=","trackType":""} May 22 20:01:55 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:55 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:55 volumio volumio[22953]: info: ------------------------------ 37ms May 22 20:01:55 volumio volumio[22953]: info: ------------------------------ 56ms May 22 20:01:55 volumio volumio[22953]: info: ------------------------------ 56ms May 22 20:01:55 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 44 milliseconds May 22 20:01:55 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 45 milliseconds May 22 20:01:55 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 43 milliseconds May 22 20:01:55 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:55 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:55 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:55 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:55 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:55 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Memory","artist":"MACO","album":"メトロノーム","uri":"http://m701.music.126.net/20250522202654/58ad840aafed4b328ef846c94963cf1b/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/7305179339/b94a/7e6d/8977/b6af13b7f900327110ec47d49a1827c1.mp3?vuutv=1M+ci78w/72fuyo6KplmTGGis0z552yHCKe5TxhDr5Bu68De0UsTKIfjyxhG4+GiARy2tnag2E1ArpnSm5VXwkdAiOouQV4eS/Dt/ix7xsc=","trackType":""} May 22 20:01:55 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:55 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:55 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:55 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:55 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Memory","artist":"MACO","album":"メトロノーム","uri":"http://m701.music.126.net/20250522202654/58ad840aafed4b328ef846c94963cf1b/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/7305179339/b94a/7e6d/8977/b6af13b7f900327110ec47d49a1827c1.mp3?vuutv=1M+ci78w/72fuyo6KplmTGGis0z552yHCKe5TxhDr5Bu68De0UsTKIfjyxhG4+GiARy2tnag2E1ArpnSm5VXwkdAiOouQV4eS/Dt/ix7xsc=","trackType":""} May 22 20:01:55 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:55 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:55 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:55 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:55 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Memory","artist":"MACO","album":"メトロノーム","uri":"http://m701.music.126.net/20250522202654/58ad840aafed4b328ef846c94963cf1b/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/7305179339/b94a/7e6d/8977/b6af13b7f900327110ec47d49a1827c1.mp3?vuutv=1M+ci78w/72fuyo6KplmTGGis0z552yHCKe5TxhDr5Bu68De0UsTKIfjyxhG4+GiARy2tnag2E1ArpnSm5VXwkdAiOouQV4eS/Dt/ix7xsc=","trackType":""} May 22 20:01:55 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:55 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:55 volumio volumio[22953]: info: ------------------------------ 124ms May 22 20:01:55 volumio volumio[22953]: info: ------------------------------ 119ms May 22 20:01:55 volumio volumio[22953]: info: ------------------------------ 118ms May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:55 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:55 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:55 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:55 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:55 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:55 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:55 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:55 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:55 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:55 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:55 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:55 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:57 volumio volumio[22953]: info: May 22 20:01:57 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:57 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:57 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:57 volumio volumio[22953]: info: May 22 20:01:57 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:57 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:57 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:57 volumio volumio[22953]: info: May 22 20:01:57 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:57 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:57 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:57 volumio volumio[22953]: info: sendMpdCommand status took 2 milliseconds May 22 20:01:57 volumio volumio[22953]: info: sendMpdCommand status took 2 milliseconds May 22 20:01:57 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:57 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:57 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:57 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:57 volumio volumio[22953]: info: sendMpdCommand status took 2 milliseconds May 22 20:01:57 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 1 milliseconds May 22 20:01:57 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 2 milliseconds May 22 20:01:57 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:57 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:57 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:57 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:57 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:57 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:57 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:57 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:57 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:01:57 volumio volumio[22953]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Memory","artist":"MACO","album":"メトロノーム","uri":"http://m701.music.126.net/20250522202654/58ad840aafed4b328ef846c94963cf1b/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/7305179339/b94a/7e6d/8977/b6af13b7f900327110ec47d49a1827c1.mp3?vuutv=1M+ci78w/72fuyo6KplmTGGis0z552yHCKe5TxhDr5Bu68De0UsTKIfjyxhG4+GiARy2tnag2E1ArpnSm5VXwkdAiOouQV4eS/Dt/ix7xsc=","trackType":""} May 22 20:01:57 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:57 volumio volumio[22953]: info: CoreStateMachine::syncState stateService stop May 22 20:01:57 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:57 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:57 volumio volumio[22953]: info: CoreStateMachine::stPlaybackTimer May 22 20:01:57 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:57 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:57 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:57 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 20:01:57 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:57 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:01:57 volumio volumio[22953]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Memory","artist":"MACO","album":"メトロノーム","uri":"http://m701.music.126.net/20250522202654/58ad840aafed4b328ef846c94963cf1b/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/7305179339/b94a/7e6d/8977/b6af13b7f900327110ec47d49a1827c1.mp3?vuutv=1M+ci78w/72fuyo6KplmTGGis0z552yHCKe5TxhDr5Bu68De0UsTKIfjyxhG4+GiARy2tnag2E1ArpnSm5VXwkdAiOouQV4eS/Dt/ix7xsc=","trackType":""} May 22 20:01:57 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:57 volumio volumio[22953]: info: CoreStateMachine::syncState stateService stop May 22 20:01:57 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus stop May 22 20:01:57 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:57 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:57 volumio volumio[22953]: info: No code May 22 20:01:57 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:57 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:57 volumio volumio[22953]: info: ------------------------------ 36ms May 22 20:01:57 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 33 milliseconds May 22 20:01:57 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:57 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:57 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:57 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:57 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:57 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:01:57 volumio volumio[22953]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Memory","artist":"MACO","album":"メトロノーム","uri":"http://m701.music.126.net/20250522202654/58ad840aafed4b328ef846c94963cf1b/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/7305179339/b94a/7e6d/8977/b6af13b7f900327110ec47d49a1827c1.mp3?vuutv=1M+ci78w/72fuyo6KplmTGGis0z552yHCKe5TxhDr5Bu68De0UsTKIfjyxhG4+GiARy2tnag2E1ArpnSm5VXwkdAiOouQV4eS/Dt/ix7xsc=","trackType":""} May 22 20:01:57 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:57 volumio volumio[22953]: info: CoreStateMachine::syncState stateService stop May 22 20:01:57 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus stop May 22 20:01:57 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:57 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:57 volumio volumio[22953]: info: No code May 22 20:01:57 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:57 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:57 volumio volumio[22953]: info: ------------------------------ 65ms May 22 20:01:57 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:57 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:57 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:57 volumio volumio[22953]: info: Clearing queue after UPNP request May 22 20:01:57 volumio volumio[22953]: info: CoreStateMachine::ClearQueue May 22 20:01:57 volumio volumio[22953]: info: CoreStateMachine::stop May 22 20:01:57 volumio volumio[22953]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 20:01:57 volumio volumio[22953]: info: CorePlayQueue::clearPlayQueue May 22 20:01:57 volumio volumio[22953]: info: CorePlayQueue::saveQueue May 22 20:01:57 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:57 volumio volumio[22953]: info: CoreCommandRouter::volumioPushQueue May 22 20:01:57 volumio volumio[22953]: info: May 22 20:01:57 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:57 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:57 volumio volumio[22953]: info: May 22 20:01:57 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:57 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:57 volumio volumio[22953]: info: May 22 20:01:57 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:57 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:57 volumio volumio[22953]: error: updateQueue error: null May 22 20:01:57 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:57 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:57 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:01:57 volumio volumio[22953]: info: Starting UPNP Playback May 22 20:01:57 volumio volumio[22953]: info: Preparing playback through UPNP May 22 20:01:57 volumio volumio[22953]: info: CoreCommandRouter::volumioGetState May 22 20:01:57 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:01:57 volumio volumio[22953]: info: CoreStateMachine::setConsumeUpdateService mpd May 22 20:01:58 volumio volumio[22953]: info: May 22 20:01:58 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:58 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:58 volumio volumio[22953]: info: May 22 20:01:58 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:58 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:58 volumio volumio[22953]: info: May 22 20:01:58 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:58 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:58 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:58 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:58 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:01:58 volumio volumio[22953]: info: May 22 20:01:58 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:58 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:58 volumio volumio[22953]: info: May 22 20:01:58 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:58 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:58 volumio volumio[22953]: info: May 22 20:01:58 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:58 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:58 volumio volumio[22953]: info: ------------------------------ 6ms May 22 20:01:58 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:58 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:01:58 volumio volumio[22953]: info: May 22 20:01:58 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:58 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:58 volumio volumio[22953]: info: May 22 20:01:58 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:58 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:58 volumio volumio[22953]: info: May 22 20:01:58 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:58 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:58 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:58 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:58 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:58 volumio volumio[22953]: info: May 22 20:01:58 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:58 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:58 volumio volumio[22953]: info: May 22 20:01:58 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:58 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:58 volumio volumio[22953]: info: May 22 20:01:58 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:58 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:58 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:58 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:58 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:01:58 volumio volumio[22953]: info: May 22 20:01:58 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:58 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:58 volumio volumio[22953]: info: May 22 20:01:58 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:58 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:58 volumio volumio[22953]: info: May 22 20:01:58 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:58 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:58 volumio volumio[22953]: info: ------------------------------ 4ms May 22 20:01:58 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:58 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:01:58 volumio volumio[22953]: info: May 22 20:01:58 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:58 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:58 volumio volumio[22953]: info: May 22 20:01:58 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:58 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:58 volumio volumio[22953]: info: May 22 20:01:58 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:01:58 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:01:58 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:01:58 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:01:58 volumio volumio[22953]: info: ------------------------------ 1ms May 22 20:01:58 volumio volumio[22953]: info: May 22 20:01:58 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:58 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:58 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:58 volumio volumio[22953]: info: May 22 20:01:58 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:58 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:58 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:58 volumio volumio[22953]: info: May 22 20:01:58 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:58 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:58 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:58 volumio volumio[22953]: info: sendMpdCommand status took 24 milliseconds May 22 20:01:58 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:58 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:58 volumio volumio[22953]: info: May 22 20:01:58 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:58 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:58 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:58 volumio volumio[22953]: info: sendMpdCommand status took 26 milliseconds May 22 20:01:58 volumio volumio[22953]: info: sendMpdCommand status took 25 milliseconds May 22 20:01:58 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 1 milliseconds May 22 20:01:58 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:58 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:58 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:58 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:58 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:58 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:58 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:58 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3397,"samplerate":"12 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"光るなら","artist":"Goose house","album":"光るなら","uri":"http://192.168.1.227:61323/mp3?filepath=%2Fstorage%2Femulated%2F0%2FDownload%2Fnetease%2Fcloudmusic%2FMusic%2FGoose+house+-+%E5%85%89%E3%82%8B%E3%81%AA%E3%82%89.ncm&decode=false","trackType":""} May 22 20:01:58 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus stop May 22 20:01:58 volumio volumio[22953]: info: ------------------------------ 37ms May 22 20:01:58 volumio volumio[22953]: info: May 22 20:01:58 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:58 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:58 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:58 volumio volumio[22953]: info: May 22 20:01:58 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:01:58 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:01:58 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:01:58 volumio volumio[22953]: info: sendMpdCommand status took 14 milliseconds May 22 20:01:58 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 12 milliseconds May 22 20:01:58 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 8 milliseconds May 22 20:01:58 volumio volumio[22953]: info: sendMpdCommand status took 4 milliseconds May 22 20:01:58 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:58 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:58 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:58 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:58 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:58 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:58 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:58 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3397,"samplerate":"12 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"光るなら","artist":"Goose house","album":"光るなら","uri":"http://192.168.1.227:61323/mp3?filepath=%2Fstorage%2Femulated%2F0%2FDownload%2Fnetease%2Fcloudmusic%2FMusic%2FGoose+house+-+%E5%85%89%E3%82%8B%E3%81%AA%E3%82%89.ncm&decode=false","trackType":""} May 22 20:01:58 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:58 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:58 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:58 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:58 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3397,"samplerate":"12 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"光るなら","artist":"Goose house","album":"光るなら","uri":"http://192.168.1.227:61323/mp3?filepath=%2Fstorage%2Femulated%2F0%2FDownload%2Fnetease%2Fcloudmusic%2FMusic%2FGoose+house+-+%E5%85%89%E3%82%8B%E3%81%AA%E3%82%89.ncm&decode=false","trackType":""} May 22 20:01:58 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:58 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:58 volumio volumio[22953]: info: ------------------------------ 79ms May 22 20:01:58 volumio volumio[22953]: info: ------------------------------ 79ms May 22 20:01:58 volumio volumio[22953]: info: sendMpdCommand status took 43 milliseconds May 22 20:01:58 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 39 milliseconds May 22 20:01:58 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 38 milliseconds May 22 20:01:58 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:01:58 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:01:58 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:58 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:58 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:58 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3397,"samplerate":"12 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"光るなら","artist":"Goose house","album":"光るなら","uri":"http://192.168.1.227:61323/mp3?filepath=%2Fstorage%2Femulated%2F0%2FDownload%2Fnetease%2Fcloudmusic%2FMusic%2FGoose+house+-+%E5%85%89%E3%82%8B%E3%81%AA%E3%82%89.ncm&decode=false","trackType":""} May 22 20:01:58 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:58 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:58 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:58 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:58 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3397,"samplerate":"12 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"光るなら","artist":"Goose house","album":"光るなら","uri":"http://192.168.1.227:61323/mp3?filepath=%2Fstorage%2Femulated%2F0%2FDownload%2Fnetease%2Fcloudmusic%2FMusic%2FGoose+house+-+%E5%85%89%E3%82%8B%E3%81%AA%E3%82%89.ncm&decode=false","trackType":""} May 22 20:01:58 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:58 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:58 volumio volumio[22953]: info: ------------------------------ 88ms May 22 20:01:58 volumio volumio[22953]: info: ------------------------------ 78ms May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:58 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:58 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:58 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:58 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:58 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:58 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:58 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:58 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:58 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 43 milliseconds May 22 20:01:58 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:01:58 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:01:58 volumio volumio[22953]: verbose: In UPNP mode May 22 20:01:58 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3397,"samplerate":"12 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"光るなら","artist":"Goose house","album":"光るなら","uri":"http://192.168.1.227:61323/mp3?filepath=%2Fstorage%2Femulated%2F0%2FDownload%2Fnetease%2Fcloudmusic%2FMusic%2FGoose+house+-+%E5%85%89%E3%82%8B%E3%81%AA%E3%82%89.ncm&decode=false","trackType":""} May 22 20:01:58 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:01:58 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:01:58 volumio volumio[22953]: info: ------------------------------ 101ms May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:58 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:01:58 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:01:58 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:01:58 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:02:02 volumio volumio[22953]: info: Clearing queue after UPNP request May 22 20:02:02 volumio volumio[22953]: info: CoreStateMachine::ClearQueue May 22 20:02:02 volumio volumio[22953]: info: CoreStateMachine::stop May 22 20:02:02 volumio volumio[22953]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 20:02:02 volumio volumio[22953]: info: CoreStateMachine::stPlaybackTimer May 22 20:02:02 volumio volumio[22953]: info: CoreStateMachine::updateTrackBlock May 22 20:02:02 volumio volumio[22953]: info: CorePlayQueue::getTrackBlock May 22 20:02:02 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:02:02 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:02:02 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 20:02:02 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:02:02 volumio volumio[22953]: info: CoreStateMachine::serviceStop May 22 20:02:02 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:02:02 volumio volumio[22953]: info: ControllerMpd::stop May 22 20:02:02 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand stop May 22 20:02:02 volumio volumio[22953]: info: CorePlayQueue::clearPlayQueue May 22 20:02:02 volumio volumio[22953]: info: CorePlayQueue::saveQueue May 22 20:02:02 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:02:02 volumio volumio[22953]: info: CoreCommandRouter::volumioPushQueue May 22 20:02:02 volumio volumio[22953]: info: May 22 20:02:02 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:02:02 volumio volumio[22953]: info: sendMpdCommand stop took 28 milliseconds May 22 20:02:02 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:02:02 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:02:02 volumio volumio[22953]: info: May 22 20:02:02 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:02:02 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:02:02 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:02:02 volumio volumio[22953]: info: May 22 20:02:02 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:02:02 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:02:02 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:02:02 volumio volumio[22953]: info: May 22 20:02:02 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:02:02 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:02:02 volumio volumio[22953]: info: May 22 20:02:02 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:02:02 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:02:02 volumio volumio[22953]: info: May 22 20:02:02 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:02:02 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:02:02 volumio volumio[22953]: info: sendMpdCommand status took 7 milliseconds May 22 20:02:02 volumio volumio[22953]: info: sendMpdCommand status took 5 milliseconds May 22 20:02:02 volumio volumio[22953]: info: sendMpdCommand status took 4 milliseconds May 22 20:02:02 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:02:02 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:02:02 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:02:02 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:02:02 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:02:02 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:02:02 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:02:02 volumio volumio[22953]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 22 20:02:02 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:02:02 volumio volumio[22953]: info: CoreStateMachine::syncState stateService stop May 22 20:02:02 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus stop May 22 20:02:02 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:02:02 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:02:02 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:02:02 volumio volumio[22953]: info: No code May 22 20:02:02 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:02:02 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:02:02 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:02:02 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:02:02 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:02:02 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:02:02 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:02:02 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:02:02 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:02:02 volumio volumio[22953]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 22 20:02:02 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:02:02 volumio volumio[22953]: info: CoreStateMachine::syncState stateService stop May 22 20:02:02 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus stop May 22 20:02:02 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:02:02 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:02:02 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:02:02 volumio volumio[22953]: info: No code May 22 20:02:02 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:02:02 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:02:02 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:02:02 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:02:02 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:02:02 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:02:02 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:02:02 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:02:02 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:02:02 volumio volumio[22953]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 22 20:02:02 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:02:02 volumio volumio[22953]: info: CoreStateMachine::syncState stateService stop May 22 20:02:02 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus stop May 22 20:02:02 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:02:02 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:02:02 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:02:02 volumio volumio[22953]: info: No code May 22 20:02:02 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:02:02 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:02:02 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:02:02 volumio volumio[22953]: info: ------------------------------ 86ms May 22 20:02:02 volumio volumio[22953]: info: ------------------------------ 85ms May 22 20:02:02 volumio volumio[22953]: info: ------------------------------ 84ms May 22 20:02:02 volumio volumio[22953]: error: updateQueue error: null May 22 20:02:02 volumio volumio[22953]: info: ------------------------------ 83ms May 22 20:02:02 volumio volumio[22953]: info: Starting UPNP Playback May 22 20:02:02 volumio volumio[22953]: info: Preparing playback through UPNP May 22 20:02:02 volumio volumio[22953]: info: CoreCommandRouter::volumioGetState May 22 20:02:02 volumio volumio[22953]: info: CorePlayQueue::getTrack 0 May 22 20:02:02 volumio volumio[22953]: info: CoreStateMachine::setConsumeUpdateService mpd May 22 20:02:03 volumio volumio[22953]: info: May 22 20:02:03 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:02:03 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:02:03 volumio volumio[22953]: info: May 22 20:02:03 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:02:03 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:02:03 volumio volumio[22953]: info: May 22 20:02:03 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:02:03 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:02:03 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:02:03 volumio volumio[22953]: info: ------------------------------ 1ms May 22 20:02:03 volumio volumio[22953]: info: ------------------------------ 1ms May 22 20:02:03 volumio volumio[22953]: info: May 22 20:02:03 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:02:03 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:02:03 volumio volumio[22953]: info: May 22 20:02:03 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:02:03 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:02:03 volumio volumio[22953]: info: May 22 20:02:03 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:02:03 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:02:03 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:02:03 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:02:03 volumio volumio[22953]: info: May 22 20:02:03 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:02:03 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:02:03 volumio volumio[22953]: info: May 22 20:02:03 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:02:03 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:02:03 volumio volumio[22953]: info: May 22 20:02:03 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:02:03 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:02:03 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:02:03 volumio volumio[22953]: info: ------------------------------ 1ms May 22 20:02:03 volumio volumio[22953]: info: ------------------------------ 1ms May 22 20:02:03 volumio volumio[22953]: info: May 22 20:02:03 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:02:03 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:02:03 volumio volumio[22953]: info: May 22 20:02:03 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:02:03 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:02:03 volumio volumio[22953]: info: May 22 20:02:03 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:02:03 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:02:03 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:02:03 volumio volumio[22953]: info: ------------------------------ 1ms May 22 20:02:03 volumio volumio[22953]: info: May 22 20:02:03 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:02:03 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:02:03 volumio volumio[22953]: info: May 22 20:02:03 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:02:03 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:02:03 volumio volumio[22953]: info: May 22 20:02:03 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:02:03 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:02:03 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:02:03 volumio volumio[22953]: info: ------------------------------ 1ms May 22 20:02:03 volumio volumio[22953]: info: May 22 20:02:03 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:02:03 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:02:03 volumio volumio[22953]: info: May 22 20:02:03 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:02:03 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:02:03 volumio volumio[22953]: info: May 22 20:02:03 volumio volumio[22953]: ---------------------------- MPD announces system playlist update May 22 20:02:03 volumio volumio[22953]: info: Ignoring MPD Status Update May 22 20:02:03 volumio volumio[22953]: info: ------------------------------ 3ms May 22 20:02:03 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:02:03 volumio volumio[22953]: info: ------------------------------ 2ms May 22 20:02:03 volumio volumio[22953]: info: May 22 20:02:03 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:02:03 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:02:03 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:02:03 volumio volumio[22953]: info: May 22 20:02:03 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:02:03 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:02:03 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:02:03 volumio volumio[22953]: info: May 22 20:02:03 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:02:03 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:02:03 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:02:03 volumio volumio[22953]: info: May 22 20:02:03 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:02:03 volumio volumio[22953]: info: sendMpdCommand status took 28 milliseconds May 22 20:02:03 volumio volumio[22953]: info: sendMpdCommand status took 27 milliseconds May 22 20:02:03 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:02:03 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:02:03 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:02:03 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:02:03 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:02:03 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:02:03 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:02:03 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:02:03 volumio volumio[22953]: info: May 22 20:02:03 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:02:03 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:02:03 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:02:03 volumio volumio[22953]: info: May 22 20:02:03 volumio volumio[22953]: ---------------------------- MPD announces state update: player May 22 20:02:03 volumio volumio[22953]: info: ControllerMpd::getState May 22 20:02:03 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand status May 22 20:02:03 volumio volumio[22953]: info: sendMpdCommand status took 3 milliseconds May 22 20:02:03 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 3 milliseconds May 22 20:02:03 volumio volumio[22953]: info: sendMpdCommand status took 2 milliseconds May 22 20:02:03 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:02:03 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:02:03 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:02:03 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:02:03 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:02:03 volumio volumio[22953]: verbose: ControllerMpd::parseState May 22 20:02:03 volumio volumio[22953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 20:02:03 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:02:03 volumio volumio[22953]: verbose: In UPNP mode May 22 20:02:03 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Despacito (Remix)","artist":"Luis Fonsi/Daddy Yankee/Justin Bieber","album":"VIDA","uri":"http://m801.music.126.net/20250522202702/0b442323c2d03d39b31ea0f2afe8c1aa/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/19501760616/9fbb/1c01/fbcd/d7c3428ce95d9fc2ef7706772a72bb8f.mp3?vuutv=+wnxmmM88ydi2ndeqQQ+s3ojow/Rilq/JinWQkDxtOoSnbXgCVdigApqtuGgayBG9bk3wxS1oz6YaThwb8QWXUAlag6LhBgdARsi3MKSsuc=","trackType":""} May 22 20:02:03 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus stop May 22 20:02:03 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:02:03 volumio volumio[22953]: verbose: In UPNP mode May 22 20:02:03 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Despacito (Remix)","artist":"Luis Fonsi/Daddy Yankee/Justin Bieber","album":"VIDA","uri":"http://m801.music.126.net/20250522202702/0b442323c2d03d39b31ea0f2afe8c1aa/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/19501760616/9fbb/1c01/fbcd/d7c3428ce95d9fc2ef7706772a72bb8f.mp3?vuutv=+wnxmmM88ydi2ndeqQQ+s3ojow/Rilq/JinWQkDxtOoSnbXgCVdigApqtuGgayBG9bk3wxS1oz6YaThwb8QWXUAlag6LhBgdARsi3MKSsuc=","trackType":""} May 22 20:02:03 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:02:03 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:02:03 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:02:03 volumio volumio[22953]: verbose: In UPNP mode May 22 20:02:03 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Despacito (Remix)","artist":"Luis Fonsi/Daddy Yankee/Justin Bieber","album":"VIDA","uri":"http://m801.music.126.net/20250522202702/0b442323c2d03d39b31ea0f2afe8c1aa/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/19501760616/9fbb/1c01/fbcd/d7c3428ce95d9fc2ef7706772a72bb8f.mp3?vuutv=+wnxmmM88ydi2ndeqQQ+s3ojow/Rilq/JinWQkDxtOoSnbXgCVdigApqtuGgayBG9bk3wxS1oz6YaThwb8QWXUAlag6LhBgdARsi3MKSsuc=","trackType":""} May 22 20:02:03 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:02:03 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:02:03 volumio volumio[22953]: info: ------------------------------ 46ms May 22 20:02:03 volumio volumio[22953]: info: ------------------------------ 90ms May 22 20:02:03 volumio volumio[22953]: info: ------------------------------ 90ms May 22 20:02:03 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 64 milliseconds May 22 20:02:03 volumio volumio[22953]: info: sendMpdCommand playlistinfo took 63 milliseconds May 22 20:02:03 volumio volumio[22953]: verbose: ControllerMpd::parseTrackInfo May 22 20:02:03 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:02:03 volumio volumio[22953]: verbose: In UPNP mode May 22 20:02:03 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Despacito (Remix)","artist":"Luis Fonsi/Daddy Yankee/Justin Bieber","album":"VIDA","uri":"http://m801.music.126.net/20250522202702/0b442323c2d03d39b31ea0f2afe8c1aa/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/19501760616/9fbb/1c01/fbcd/d7c3428ce95d9fc2ef7706772a72bb8f.mp3?vuutv=+wnxmmM88ydi2ndeqQQ+s3ojow/Rilq/JinWQkDxtOoSnbXgCVdigApqtuGgayBG9bk3wxS1oz6YaThwb8QWXUAlag6LhBgdARsi3MKSsuc=","trackType":""} May 22 20:02:03 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:02:03 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:02:03 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:02:03 volumio volumio[22953]: verbose: In UPNP mode May 22 20:02:03 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Despacito (Remix)","artist":"Luis Fonsi/Daddy Yankee/Justin Bieber","album":"VIDA","uri":"http://m801.music.126.net/20250522202702/0b442323c2d03d39b31ea0f2afe8c1aa/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/19501760616/9fbb/1c01/fbcd/d7c3428ce95d9fc2ef7706772a72bb8f.mp3?vuutv=+wnxmmM88ydi2ndeqQQ+s3ojow/Rilq/JinWQkDxtOoSnbXgCVdigApqtuGgayBG9bk3wxS1oz6YaThwb8QWXUAlag6LhBgdARsi3MKSsuc=","trackType":""} May 22 20:02:03 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:02:03 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:02:03 volumio volumio[22953]: info: ControllerMpd::pushState May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::servicePushState May 22 20:02:03 volumio volumio[22953]: verbose: In UPNP mode May 22 20:02:03 volumio volumio[22953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Despacito (Remix)","artist":"Luis Fonsi/Daddy Yankee/Justin Bieber","album":"VIDA","uri":"http://m801.music.126.net/20250522202702/0b442323c2d03d39b31ea0f2afe8c1aa/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/19501760616/9fbb/1c01/fbcd/d7c3428ce95d9fc2ef7706772a72bb8f.mp3?vuutv=+wnxmmM88ydi2ndeqQQ+s3ojow/Rilq/JinWQkDxtOoSnbXgCVdigApqtuGgayBG9bk3wxS1oz6YaThwb8QWXUAlag6LhBgdARsi3MKSsuc=","trackType":""} May 22 20:02:03 volumio volumio[22953]: verbose: CURRENT POSITION 0 May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::syncState stateService play May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::syncState currentStatus play May 22 20:02:03 volumio volumio[22953]: info: Received an update from plugin. extracting info from payload May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:02:03 volumio volumio[22953]: info: ------------------------------ 120ms May 22 20:02:03 volumio volumio[22953]: info: ------------------------------ 118ms May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:02:03 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:02:03 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:02:03 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:02:03 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:02:03 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:02:03 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:02:03 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:02:03 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:02:03 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:02:03 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:02:03 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:02:03 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:02:07 volumio volumio[1446]: 68 May 22 20:02:07 volumio volumio[1446]: % Total % Received % Xferd Average Speed Time Time Time Current May 22 20:02:07 volumio volumio[1446]: Dload Upload Total Spent Left Speed May 22 20:02:07 volumio volumio[22953]: info: May 22 20:02:07 volumio volumio[22953]: ---------------------------- Client requests Volume 68 May 22 20:02:07 volumio volumio[22953]: info: VolumeController::SetAlsaVolume68 May 22 20:02:07 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:02:07 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 20:02:07 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:02:08 volumio volumio[1446]: [158B blob data] May 22 20:02:08 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:02:08 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:02:08 volumio volumio[1446]: {"time":1747915327966,"response":"volume Success"}68 May 22 20:02:08 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:02:08 volumio volumio[1446]: 73 May 22 20:02:08 volumio volumio[1446]: % Total % Received % Xferd Average Speed Time Time Time Current May 22 20:02:08 volumio volumio[1446]: Dload Upload Total Spent Left Speed May 22 20:02:08 volumio volumio[22953]: info: May 22 20:02:08 volumio volumio[22953]: ---------------------------- Client requests Volume 73 May 22 20:02:08 volumio volumio[22953]: info: VolumeController::SetAlsaVolume73 May 22 20:02:08 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:02:08 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 20:02:08 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:02:08 volumio volumio[1446]: [237B blob data] May 22 20:02:08 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:02:08 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:02:08 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:02:08 volumio volumio[1446]: {"time":1747915328320,"response":"volume Success"}73 May 22 20:02:09 volumio volumio[1446]: 78 May 22 20:02:09 volumio volumio[1446]: % Total % Received % Xferd Average Speed Time Time Time Current May 22 20:02:09 volumio volumio[1446]: Dload Upload Total Spent Left Speed May 22 20:02:09 volumio volumio[22953]: info: May 22 20:02:09 volumio volumio[22953]: ---------------------------- Client requests Volume 78 May 22 20:02:09 volumio volumio[22953]: info: VolumeController::SetAlsaVolume78 May 22 20:02:09 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:02:09 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 20:02:09 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:02:09 volumio volumio[1446]: [237B blob data] May 22 20:02:09 volumio volumio[1446]: {"time":1747915329344,"response":"volume Success"}78 May 22 20:02:09 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:02:09 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:02:09 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:02:09 volumio volumio[1446]: 83 May 22 20:02:09 volumio volumio[1446]: % Total % Received % Xferd Average Speed Time Time Time Current May 22 20:02:09 volumio volumio[1446]: Dload Upload Total Spent Left Speed May 22 20:02:09 volumio volumio[22953]: info: May 22 20:02:09 volumio volumio[22953]: ---------------------------- Client requests Volume 83 May 22 20:02:09 volumio volumio[22953]: info: VolumeController::SetAlsaVolume83 May 22 20:02:09 volumio volumio[22953]: info: CoreStateMachine::pushState May 22 20:02:09 volumio volumio[22953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 20:02:09 volumio volumio[22953]: info: CoreCommandRouter::volumioPushState May 22 20:02:09 volumio volumio[1446]: [158B blob data] May 22 20:02:09 volumio volumio[1446]: {"time":1747915329727,"response":"volume Success"}83 May 22 20:02:09 volumio volumio[22953]: info: CoreCommandRouter::volumioGetQueue May 22 20:02:09 volumio volumio[22953]: info: CoreStateMachine::getQueue May 22 20:02:09 volumio volumio[22953]: info: CorePlayQueue::getQueue May 22 20:02:45 volumio volumio[22953]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 20:02:45 volumio volumio[22953]: Error: connect ETIMEDOUT 208.31.254.33:443 May 22 20:02:45 volumio volumio[22953]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 22 20:02:45 volumio volumio[22953]: errno: -110, May 22 20:02:45 volumio volumio[22953]: code: 'ETIMEDOUT', May 22 20:02:45 volumio volumio[22953]: syscall: 'connect', May 22 20:02:45 volumio volumio[22953]: address: '208.31.254.33', May 22 20:02:45 volumio volumio[22953]: port: 443 May 22 20:02:45 volumio volumio[22953]: } May 22 20:02:45 volumio volumio[22953]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 20:02:45 volumio sudo[24506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 20:01 May 22 20:02:45 volumio sudo[24506]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 10:07:11 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dc77d77f02ef88ea816c3d31de871e8f"