-- Logs begin at Thu 2025-09-11 12:39:57 UTC, end at Thu 2025-09-11 13:12:36 UTC. -- Sep 11 13:11:03 volumio volumio[1124]: info: Clearing queue after UPNP request Sep 11 13:11:03 volumio volumio[1124]: info: CoreStateMachine::ClearQueue Sep 11 13:11:03 volumio volumio[1124]: info: CoreStateMachine::stop Sep 11 13:11:03 volumio volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 13:11:03 volumio volumio[1124]: info: CoreStateMachine::stPlaybackTimer Sep 11 13:11:03 volumio volumio[1124]: info: CoreStateMachine::updateTrackBlock Sep 11 13:11:03 volumio volumio[1124]: info: CorePlayQueue::getTrackBlock Sep 11 13:11:03 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:03 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:11:03 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 13:11:03 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:03 volumio volumio[1124]: info: CoreStateMachine::serviceStop Sep 11 13:11:03 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:11:03 volumio volumio[1124]: info: ControllerMpd::stop Sep 11 13:11:03 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand stop Sep 11 13:11:03 volumio volumio[1124]: info: CorePlayQueue::clearPlayQueue Sep 11 13:11:03 volumio volumio[1124]: info: CorePlayQueue::saveQueue Sep 11 13:11:03 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:03 volumio volumio[1124]: info: CoreCommandRouter::volumioPushQueue Sep 11 13:11:03 volumio volumio[1124]: info: Sep 11 13:11:03 volumio volumio[1124]: ---------------------------- MPD announces state update: player Sep 11 13:11:03 volumio volumio[1124]: info: sendMpdCommand stop took 8 milliseconds Sep 11 13:11:03 volumio volumio[1124]: info: ControllerMpd::getState Sep 11 13:11:03 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Sep 11 13:11:03 volumio volumio[1124]: info: Sep 11 13:11:03 volumio volumio[1124]: ---------------------------- MPD announces state update: player Sep 11 13:11:03 volumio volumio[1124]: info: ControllerMpd::getState Sep 11 13:11:03 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Sep 11 13:11:03 volumio volumio[1124]: info: Sep 11 13:11:03 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:03 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:03 volumio volumio[1124]: info: Sep 11 13:11:03 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:03 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:03 volumio volumio[1124]: error: updateQueue error: null Sep 11 13:11:03 volumio volumio[1124]: info: sendMpdCommand status took 3 milliseconds Sep 11 13:11:03 volumio volumio[1124]: info: sendMpdCommand status took 2 milliseconds Sep 11 13:11:03 volumio volumio[1124]: info: ------------------------------ 2ms Sep 11 13:11:03 volumio volumio[1124]: verbose: ControllerMpd::parseState Sep 11 13:11:03 volumio volumio[1124]: verbose: ControllerMpd::parseState Sep 11 13:11:03 volumio volumio[1124]: info: ControllerMpd::pushState Sep 11 13:11:03 volumio volumio[1124]: info: CoreCommandRouter::servicePushState Sep 11 13:11:03 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:03 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:11:03 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:03 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:11:03 volumio volumio[1124]: 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} Sep 11 13:11:03 volumio volumio[1124]: verbose: CURRENT POSITION 3 Sep 11 13:11:03 volumio volumio[1124]: info: CoreStateMachine::syncState stateService stop Sep 11 13:11:03 volumio volumio[1124]: info: CoreStateMachine::syncState currentStatus stop Sep 11 13:11:03 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:03 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:11:03 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:03 volumio volumio[1124]: info: No code Sep 11 13:11:03 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:03 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:11:03 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:03 volumio volumio[1124]: info: ControllerMpd::pushState Sep 11 13:11:03 volumio volumio[1124]: info: CoreCommandRouter::servicePushState Sep 11 13:11:03 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:03 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:11:03 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:03 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:11:03 volumio volumio[1124]: 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} Sep 11 13:11:03 volumio volumio[1124]: verbose: CURRENT POSITION 3 Sep 11 13:11:03 volumio volumio[1124]: info: CoreStateMachine::syncState stateService stop Sep 11 13:11:03 volumio volumio[1124]: info: CoreStateMachine::syncState currentStatus stop Sep 11 13:11:03 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:03 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:11:03 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:03 volumio volumio[1124]: info: No code Sep 11 13:11:03 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:03 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:11:03 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:03 volumio volumio[1124]: info: ------------------------------ 20ms Sep 11 13:11:03 volumio volumio[1124]: error: updateQueue error: null Sep 11 13:11:03 volumio volumio[1124]: info: ------------------------------ 19ms Sep 11 13:11:03 volumio volumio[1124]: info: Starting UPNP Playback Sep 11 13:11:03 volumio volumio[1124]: info: Preparing playback through UPNP Sep 11 13:11:03 volumio volumio[1124]: info: CoreCommandRouter::volumioGetState Sep 11 13:11:03 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:11:03 volumio volumio[1124]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 11 13:11:03 volumio volumio[1124]: info: Sep 11 13:11:03 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:03 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:03 volumio volumio[1124]: info: Sep 11 13:11:03 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:03 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:03 volumio volumio[1124]: info: ------------------------------ 2ms Sep 11 13:11:03 volumio volumio[1124]: info: ------------------------------ 1ms Sep 11 13:11:03 volumio volumio[1124]: info: Sep 11 13:11:03 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:03 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:03 volumio volumio[1124]: info: Sep 11 13:11:03 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:03 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:03 volumio volumio[1124]: info: ------------------------------ 2ms Sep 11 13:11:03 volumio volumio[1124]: info: ------------------------------ 2ms Sep 11 13:11:03 volumio volumio[1124]: info: Sep 11 13:11:03 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:03 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:03 volumio volumio[1124]: info: Sep 11 13:11:03 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:03 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:03 volumio volumio[1124]: info: ------------------------------ 2ms Sep 11 13:11:03 volumio volumio[1124]: info: ------------------------------ 1ms Sep 11 13:11:03 volumio volumio[1124]: info: Sep 11 13:11:03 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:03 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:03 volumio volumio[1124]: info: Sep 11 13:11:03 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:03 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:03 volumio volumio[1124]: info: ------------------------------ 2ms Sep 11 13:11:03 volumio volumio[1124]: info: ------------------------------ 1ms Sep 11 13:11:03 volumio volumio[1124]: info: Sep 11 13:11:03 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:03 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:03 volumio volumio[1124]: info: Sep 11 13:11:03 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:03 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:03 volumio volumio[1124]: info: ------------------------------ 1ms Sep 11 13:11:03 volumio volumio[1124]: info: ------------------------------ 1ms Sep 11 13:11:03 volumio volumio[1124]: info: Sep 11 13:11:03 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:03 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:03 volumio volumio[1124]: info: Sep 11 13:11:03 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:03 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:03 volumio volumio[1124]: info: ------------------------------ 1ms Sep 11 13:11:03 volumio volumio[1124]: info: ------------------------------ 1ms Sep 11 13:11:03 volumio volumio[1124]: info: Sep 11 13:11:03 volumio volumio[1124]: ---------------------------- MPD announces state update: player Sep 11 13:11:03 volumio volumio[1124]: info: ControllerMpd::getState Sep 11 13:11:03 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Sep 11 13:11:03 volumio volumio[1124]: info: Sep 11 13:11:03 volumio volumio[1124]: ---------------------------- MPD announces state update: player Sep 11 13:11:03 volumio volumio[1124]: info: ControllerMpd::getState Sep 11 13:11:03 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Sep 11 13:11:04 volumio volumio[1124]: info: Sep 11 13:11:04 volumio volumio[1124]: ---------------------------- MPD announces state update: player Sep 11 13:11:04 volumio volumio[1124]: info: sendMpdCommand status took 24 milliseconds Sep 11 13:11:04 volumio volumio[1124]: info: ControllerMpd::getState Sep 11 13:11:04 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Sep 11 13:11:04 volumio volumio[1124]: verbose: ControllerMpd::parseState Sep 11 13:11:04 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 13:11:04 volumio volumio[1124]: info: sendMpdCommand status took 25 milliseconds Sep 11 13:11:04 volumio volumio[1124]: verbose: ControllerMpd::parseState Sep 11 13:11:04 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 13:11:04 volumio volumio[1124]: info: Sep 11 13:11:04 volumio volumio[1124]: ---------------------------- MPD announces state update: player Sep 11 13:11:04 volumio volumio[1124]: info: ControllerMpd::getState Sep 11 13:11:04 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Sep 11 13:11:04 volumio volumio[1124]: info: sendMpdCommand status took 3 milliseconds Sep 11 13:11:04 volumio volumio[1124]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 11 13:11:04 volumio volumio[1124]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 11 13:11:04 volumio volumio[1124]: verbose: ControllerMpd::parseState Sep 11 13:11:04 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 13:11:04 volumio volumio[1124]: verbose: ControllerMpd::parseTrackInfo Sep 11 13:11:04 volumio volumio[1124]: verbose: ControllerMpd::parseTrackInfo Sep 11 13:11:04 volumio volumio[1124]: info: ControllerMpd::pushState Sep 11 13:11:04 volumio volumio[1124]: info: CoreCommandRouter::servicePushState Sep 11 13:11:04 volumio volumio[1124]: verbose: In UPNP mode Sep 11 13:11:04 volumio volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":220,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"你的答案","artist":"阿冗","album":"你的答案","uri":"http://m801.music.126.net/20250911213603/a32ed9bde9deb804670773d185dedcc5/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/28557979144/7081/d6a8/1a2c/80d38bd88f111bd55e07913d600007e3.mp3?vuutv=O5e9lf7waZ/9LOi76JSWe2aA7qrEzRDeZpiHIKR2rNY9RwgxuBIphd4SZ0qWCuwfEtxNoQK1KgigSaQnw8Ld+w7BC0Z3jww5UCR46mhZ+Po=","trackType":""} Sep 11 13:11:04 volumio volumio[1124]: verbose: CURRENT POSITION 3 Sep 11 13:11:04 volumio volumio[1124]: info: CoreStateMachine::syncState stateService play Sep 11 13:11:04 volumio volumio[1124]: info: CoreStateMachine::syncState currentStatus stop Sep 11 13:11:04 volumio volumio[1124]: info: ControllerMpd::pushState Sep 11 13:11:04 volumio volumio[1124]: info: CoreCommandRouter::servicePushState Sep 11 13:11:04 volumio volumio[1124]: verbose: In UPNP mode Sep 11 13:11:04 volumio volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":366,"duration":220,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"你的答案","artist":"阿冗","album":"你的答案","uri":"http://m801.music.126.net/20250911213603/a32ed9bde9deb804670773d185dedcc5/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/28557979144/7081/d6a8/1a2c/80d38bd88f111bd55e07913d600007e3.mp3?vuutv=O5e9lf7waZ/9LOi76JSWe2aA7qrEzRDeZpiHIKR2rNY9RwgxuBIphd4SZ0qWCuwfEtxNoQK1KgigSaQnw8Ld+w7BC0Z3jww5UCR46mhZ+Po=","trackType":""} Sep 11 13:11:04 volumio volumio[1124]: verbose: CURRENT POSITION 3 Sep 11 13:11:04 volumio volumio[1124]: info: CoreStateMachine::syncState stateService play Sep 11 13:11:04 volumio volumio[1124]: info: CoreStateMachine::syncState currentStatus play Sep 11 13:11:04 volumio volumio[1124]: info: Received an update from plugin. extracting info from payload Sep 11 13:11:04 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 11 13:11:04 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:04 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 13:11:04 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:04 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:04 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:04 volumio volumio[1124]: info: ------------------------------ 31ms Sep 11 13:11:04 volumio volumio[1124]: info: ------------------------------ 32ms Sep 11 13:11:04 volumio volumio[1124]: info: sendMpdCommand status took 7 milliseconds Sep 11 13:11:04 volumio volumio[1124]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 11 13:11:04 volumio volumio[1124]: verbose: ControllerMpd::parseState Sep 11 13:11:04 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 13:11:04 volumio volumio[1124]: verbose: ControllerMpd::parseTrackInfo Sep 11 13:11:04 volumio volumio[1124]: info: ControllerMpd::pushState Sep 11 13:11:04 volumio volumio[1124]: info: CoreCommandRouter::servicePushState Sep 11 13:11:04 volumio volumio[1124]: verbose: In UPNP mode Sep 11 13:11:04 volumio volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":220,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"你的答案","artist":"阿冗","album":"你的答案","uri":"http://m801.music.126.net/20250911213603/a32ed9bde9deb804670773d185dedcc5/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/28557979144/7081/d6a8/1a2c/80d38bd88f111bd55e07913d600007e3.mp3?vuutv=O5e9lf7waZ/9LOi76JSWe2aA7qrEzRDeZpiHIKR2rNY9RwgxuBIphd4SZ0qWCuwfEtxNoQK1KgigSaQnw8Ld+w7BC0Z3jww5UCR46mhZ+Po=","trackType":""} Sep 11 13:11:04 volumio volumio[1124]: verbose: CURRENT POSITION 3 Sep 11 13:11:04 volumio volumio[1124]: info: CoreStateMachine::syncState stateService play Sep 11 13:11:04 volumio volumio[1124]: info: CoreStateMachine::syncState currentStatus play Sep 11 13:11:04 volumio volumio[1124]: info: Received an update from plugin. extracting info from payload Sep 11 13:11:04 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 11 13:11:04 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:04 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:04 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:04 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:04 volumio volumio[1124]: info: ------------------------------ 20ms Sep 11 13:11:04 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:11:04 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:11:04 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:11:04 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:11:04 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:11:04 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:11:04 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:11:04 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:11:04 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:11:04 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:11:04 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:11:04 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:11:04 volumio volumio[1124]: info: sendMpdCommand playlistinfo took 13 milliseconds Sep 11 13:11:04 volumio volumio[1124]: verbose: ControllerMpd::parseTrackInfo Sep 11 13:11:04 volumio volumio[1124]: info: ControllerMpd::pushState Sep 11 13:11:04 volumio volumio[1124]: info: CoreCommandRouter::servicePushState Sep 11 13:11:04 volumio volumio[1124]: verbose: In UPNP mode Sep 11 13:11:04 volumio volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":220,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"你的答案","artist":"阿冗","album":"你的答案","uri":"http://m801.music.126.net/20250911213603/a32ed9bde9deb804670773d185dedcc5/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/28557979144/7081/d6a8/1a2c/80d38bd88f111bd55e07913d600007e3.mp3?vuutv=O5e9lf7waZ/9LOi76JSWe2aA7qrEzRDeZpiHIKR2rNY9RwgxuBIphd4SZ0qWCuwfEtxNoQK1KgigSaQnw8Ld+w7BC0Z3jww5UCR46mhZ+Po=","trackType":""} Sep 11 13:11:04 volumio volumio[1124]: verbose: CURRENT POSITION 3 Sep 11 13:11:04 volumio volumio[1124]: info: CoreStateMachine::syncState stateService play Sep 11 13:11:04 volumio volumio[1124]: info: CoreStateMachine::syncState currentStatus play Sep 11 13:11:04 volumio volumio[1124]: info: Received an update from plugin. extracting info from payload Sep 11 13:11:04 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 11 13:11:04 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:04 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:04 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:04 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:04 volumio volumio[1124]: info: ------------------------------ 27ms Sep 11 13:11:04 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:11:04 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:11:04 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:11:04 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:11:04 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:11:04 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:11:09 volumio volumio[1124]: info: CoreCommandRouter::volumioGetState Sep 11 13:11:18 volumio volumio[1124]: info: Clearing queue after UPNP request Sep 11 13:11:18 volumio volumio[1124]: info: CoreStateMachine::ClearQueue Sep 11 13:11:18 volumio volumio[1124]: info: CoreStateMachine::stop Sep 11 13:11:18 volumio volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 13:11:18 volumio volumio[1124]: info: CoreStateMachine::stPlaybackTimer Sep 11 13:11:18 volumio volumio[1124]: info: CoreStateMachine::updateTrackBlock Sep 11 13:11:18 volumio volumio[1124]: info: CorePlayQueue::getTrackBlock Sep 11 13:11:18 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:18 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:11:18 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 13:11:18 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:18 volumio volumio[1124]: info: CoreStateMachine::serviceStop Sep 11 13:11:18 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:11:18 volumio volumio[1124]: info: ControllerMpd::stop Sep 11 13:11:18 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand stop Sep 11 13:11:18 volumio volumio[1124]: info: CorePlayQueue::clearPlayQueue Sep 11 13:11:18 volumio volumio[1124]: info: CorePlayQueue::saveQueue Sep 11 13:11:18 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:18 volumio volumio[1124]: info: CoreCommandRouter::volumioPushQueue Sep 11 13:11:18 volumio volumio[1124]: info: Sep 11 13:11:18 volumio volumio[1124]: ---------------------------- MPD announces state update: player Sep 11 13:11:18 volumio volumio[1124]: info: sendMpdCommand stop took 9 milliseconds Sep 11 13:11:18 volumio volumio[1124]: info: ControllerMpd::getState Sep 11 13:11:18 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Sep 11 13:11:18 volumio volumio[1124]: info: Sep 11 13:11:18 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:18 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:18 volumio volumio[1124]: info: sendMpdCommand status took 1 milliseconds Sep 11 13:11:18 volumio volumio[1124]: verbose: ControllerMpd::parseState Sep 11 13:11:18 volumio volumio[1124]: info: ControllerMpd::pushState Sep 11 13:11:18 volumio volumio[1124]: info: CoreCommandRouter::servicePushState Sep 11 13:11:18 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:18 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:11:18 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:18 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:11:18 volumio volumio[1124]: 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} Sep 11 13:11:18 volumio volumio[1124]: verbose: CURRENT POSITION 3 Sep 11 13:11:18 volumio volumio[1124]: info: CoreStateMachine::syncState stateService stop Sep 11 13:11:18 volumio volumio[1124]: info: CoreStateMachine::syncState currentStatus stop Sep 11 13:11:18 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:18 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:11:18 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:18 volumio volumio[1124]: info: No code Sep 11 13:11:18 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:18 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:11:18 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:18 volumio volumio[1124]: info: ------------------------------ 13ms Sep 11 13:11:18 volumio volumio[1124]: info: Sep 11 13:11:18 volumio volumio[1124]: ---------------------------- MPD announces state update: player Sep 11 13:11:18 volumio volumio[1124]: info: ControllerMpd::getState Sep 11 13:11:18 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Sep 11 13:11:18 volumio volumio[1124]: error: updateQueue error: null Sep 11 13:11:18 volumio volumio[1124]: info: ------------------------------ 15ms Sep 11 13:11:18 volumio volumio[1124]: info: sendMpdCommand status took 2 milliseconds Sep 11 13:11:18 volumio volumio[1124]: verbose: ControllerMpd::parseState Sep 11 13:11:18 volumio volumio[1124]: info: ControllerMpd::pushState Sep 11 13:11:18 volumio volumio[1124]: info: CoreCommandRouter::servicePushState Sep 11 13:11:18 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:18 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:11:18 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:18 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:11:18 volumio volumio[1124]: 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} Sep 11 13:11:18 volumio volumio[1124]: verbose: CURRENT POSITION 3 Sep 11 13:11:18 volumio volumio[1124]: info: CoreStateMachine::syncState stateService stop Sep 11 13:11:18 volumio volumio[1124]: info: CoreStateMachine::syncState currentStatus stop Sep 11 13:11:18 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:18 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:11:18 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:18 volumio volumio[1124]: info: No code Sep 11 13:11:18 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:18 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:11:18 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:18 volumio volumio[1124]: info: ------------------------------ 15ms Sep 11 13:11:18 volumio volumio[1124]: info: Sep 11 13:11:18 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:18 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:18 volumio volumio[1124]: error: updateQueue error: null Sep 11 13:11:18 volumio volumio[1124]: info: ------------------------------ 8ms Sep 11 13:11:18 volumio volumio[1124]: info: Starting UPNP Playback Sep 11 13:11:18 volumio volumio[1124]: info: Preparing playback through UPNP Sep 11 13:11:18 volumio volumio[1124]: info: CoreCommandRouter::volumioGetState Sep 11 13:11:18 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:11:18 volumio volumio[1124]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 11 13:11:19 volumio volumio[1124]: info: Sep 11 13:11:19 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:19 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:19 volumio volumio[1124]: info: Sep 11 13:11:19 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:19 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:19 volumio volumio[1124]: info: Sep 11 13:11:19 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:19 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:19 volumio volumio[1124]: info: ------------------------------ 3ms Sep 11 13:11:19 volumio volumio[1124]: info: ------------------------------ 2ms Sep 11 13:11:19 volumio volumio[1124]: info: ------------------------------ 1ms Sep 11 13:11:19 volumio volumio[1124]: info: Sep 11 13:11:19 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:19 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:19 volumio volumio[1124]: info: ------------------------------ 0ms Sep 11 13:11:19 volumio volumio[1124]: info: Sep 11 13:11:19 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:19 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:19 volumio volumio[1124]: info: Sep 11 13:11:19 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:19 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:19 volumio volumio[1124]: info: ------------------------------ 2ms Sep 11 13:11:19 volumio volumio[1124]: info: ------------------------------ 1ms Sep 11 13:11:19 volumio volumio[1124]: info: Sep 11 13:11:19 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:19 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:19 volumio volumio[1124]: info: Sep 11 13:11:19 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:19 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:19 volumio volumio[1124]: info: ------------------------------ 1ms Sep 11 13:11:19 volumio volumio[1124]: info: ------------------------------ 2ms Sep 11 13:11:19 volumio volumio[1124]: info: Sep 11 13:11:19 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:19 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:19 volumio volumio[1124]: info: Sep 11 13:11:19 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:19 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:19 volumio volumio[1124]: info: ------------------------------ 2ms Sep 11 13:11:19 volumio volumio[1124]: info: Sep 11 13:11:19 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:19 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:19 volumio volumio[1124]: info: Sep 11 13:11:19 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:11:19 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:11:19 volumio volumio[1124]: info: ------------------------------ 2ms Sep 11 13:11:19 volumio volumio[1124]: info: Sep 11 13:11:19 volumio volumio[1124]: ---------------------------- MPD announces state update: player Sep 11 13:11:19 volumio volumio[1124]: info: ControllerMpd::getState Sep 11 13:11:19 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Sep 11 13:11:19 volumio volumio[1124]: info: Sep 11 13:11:19 volumio volumio[1124]: ---------------------------- MPD announces state update: player Sep 11 13:11:19 volumio volumio[1124]: info: ControllerMpd::getState Sep 11 13:11:19 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Sep 11 13:11:19 volumio volumio[1124]: info: Sep 11 13:11:19 volumio volumio[1124]: ---------------------------- MPD announces state update: player Sep 11 13:11:19 volumio volumio[1124]: info: sendMpdCommand status took 34 milliseconds Sep 11 13:11:19 volumio volumio[1124]: info: sendMpdCommand status took 34 milliseconds Sep 11 13:11:19 volumio volumio[1124]: info: ControllerMpd::getState Sep 11 13:11:19 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Sep 11 13:11:19 volumio volumio[1124]: verbose: ControllerMpd::parseState Sep 11 13:11:19 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 13:11:19 volumio volumio[1124]: verbose: ControllerMpd::parseState Sep 11 13:11:19 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 13:11:19 volumio volumio[1124]: info: Sep 11 13:11:19 volumio volumio[1124]: ---------------------------- MPD announces state update: player Sep 11 13:11:19 volumio volumio[1124]: info: ControllerMpd::getState Sep 11 13:11:19 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Sep 11 13:11:19 volumio volumio[1124]: info: sendMpdCommand status took 2 milliseconds Sep 11 13:11:19 volumio volumio[1124]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 11 13:11:19 volumio volumio[1124]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 11 13:11:19 volumio volumio[1124]: info: sendMpdCommand status took 1 milliseconds Sep 11 13:11:19 volumio volumio[1124]: verbose: ControllerMpd::parseState Sep 11 13:11:19 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 13:11:19 volumio volumio[1124]: verbose: ControllerMpd::parseTrackInfo Sep 11 13:11:19 volumio volumio[1124]: verbose: ControllerMpd::parseTrackInfo Sep 11 13:11:19 volumio volumio[1124]: verbose: ControllerMpd::parseState Sep 11 13:11:19 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 13:11:19 volumio volumio[1124]: info: ControllerMpd::pushState Sep 11 13:11:19 volumio volumio[1124]: info: CoreCommandRouter::servicePushState Sep 11 13:11:19 volumio volumio[1124]: verbose: In UPNP mode Sep 11 13:11:19 volumio volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":234,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"5センチ先の夢","artist":"H△G","album":"5センチ先の夢","uri":"http://m701.music.126.net/20250911213618/506e30d2c29c21a6ea848dc3253e3ca9/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/4717766365/6368/adee/9e6f/ae24d36aa94e6ffab287b272cf532612.mp3?vuutv=NO5/DpOScwGMwz75stoCjk5bygvxQGrjgHmPscplRfwOM9oUUks/elB+xppXnv/dGmsaRhxsQV4UwtQreo3CaePkAvlIeLDH/AeUBwf34jo=","trackType":""} Sep 11 13:11:19 volumio volumio[1124]: verbose: CURRENT POSITION 3 Sep 11 13:11:19 volumio volumio[1124]: info: CoreStateMachine::syncState stateService play Sep 11 13:11:19 volumio volumio[1124]: info: CoreStateMachine::syncState currentStatus stop Sep 11 13:11:19 volumio volumio[1124]: info: ControllerMpd::pushState Sep 11 13:11:19 volumio volumio[1124]: info: CoreCommandRouter::servicePushState Sep 11 13:11:19 volumio volumio[1124]: verbose: In UPNP mode Sep 11 13:11:19 volumio volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":113,"duration":234,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"5センチ先の夢","artist":"H△G","album":"5センチ先の夢","uri":"http://m701.music.126.net/20250911213618/506e30d2c29c21a6ea848dc3253e3ca9/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/4717766365/6368/adee/9e6f/ae24d36aa94e6ffab287b272cf532612.mp3?vuutv=NO5/DpOScwGMwz75stoCjk5bygvxQGrjgHmPscplRfwOM9oUUks/elB+xppXnv/dGmsaRhxsQV4UwtQreo3CaePkAvlIeLDH/AeUBwf34jo=","trackType":""} Sep 11 13:11:19 volumio volumio[1124]: verbose: CURRENT POSITION 3 Sep 11 13:11:19 volumio volumio[1124]: info: CoreStateMachine::syncState stateService play Sep 11 13:11:19 volumio volumio[1124]: info: CoreStateMachine::syncState currentStatus play Sep 11 13:11:19 volumio volumio[1124]: info: Received an update from plugin. extracting info from payload Sep 11 13:11:19 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 11 13:11:19 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:19 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 13:11:19 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:19 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:19 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:19 volumio volumio[1124]: info: ------------------------------ 46ms Sep 11 13:11:19 volumio volumio[1124]: info: ------------------------------ 47ms Sep 11 13:11:19 volumio volumio[1124]: info: sendMpdCommand playlistinfo took 11 milliseconds Sep 11 13:11:19 volumio volumio[1124]: info: sendMpdCommand playlistinfo took 11 milliseconds Sep 11 13:11:19 volumio volumio[1124]: verbose: ControllerMpd::parseTrackInfo Sep 11 13:11:19 volumio volumio[1124]: verbose: ControllerMpd::parseTrackInfo Sep 11 13:11:19 volumio volumio[1124]: info: ControllerMpd::pushState Sep 11 13:11:19 volumio volumio[1124]: info: CoreCommandRouter::servicePushState Sep 11 13:11:19 volumio volumio[1124]: verbose: In UPNP mode Sep 11 13:11:19 volumio volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1479,"duration":234,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"5センチ先の夢","artist":"H△G","album":"5センチ先の夢","uri":"http://m701.music.126.net/20250911213618/506e30d2c29c21a6ea848dc3253e3ca9/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/4717766365/6368/adee/9e6f/ae24d36aa94e6ffab287b272cf532612.mp3?vuutv=NO5/DpOScwGMwz75stoCjk5bygvxQGrjgHmPscplRfwOM9oUUks/elB+xppXnv/dGmsaRhxsQV4UwtQreo3CaePkAvlIeLDH/AeUBwf34jo=","trackType":""} Sep 11 13:11:19 volumio volumio[1124]: verbose: CURRENT POSITION 3 Sep 11 13:11:19 volumio volumio[1124]: info: CoreStateMachine::syncState stateService play Sep 11 13:11:19 volumio volumio[1124]: info: CoreStateMachine::syncState currentStatus play Sep 11 13:11:19 volumio volumio[1124]: info: Received an update from plugin. extracting info from payload Sep 11 13:11:19 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 11 13:11:19 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:19 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:19 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:19 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:19 volumio volumio[1124]: info: ControllerMpd::pushState Sep 11 13:11:19 volumio volumio[1124]: info: CoreCommandRouter::servicePushState Sep 11 13:11:19 volumio volumio[1124]: verbose: In UPNP mode Sep 11 13:11:19 volumio volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1479,"duration":234,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"5センチ先の夢","artist":"H△G","album":"5センチ先の夢","uri":"http://m701.music.126.net/20250911213618/506e30d2c29c21a6ea848dc3253e3ca9/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/4717766365/6368/adee/9e6f/ae24d36aa94e6ffab287b272cf532612.mp3?vuutv=NO5/DpOScwGMwz75stoCjk5bygvxQGrjgHmPscplRfwOM9oUUks/elB+xppXnv/dGmsaRhxsQV4UwtQreo3CaePkAvlIeLDH/AeUBwf34jo=","trackType":""} Sep 11 13:11:19 volumio volumio[1124]: verbose: CURRENT POSITION 3 Sep 11 13:11:19 volumio volumio[1124]: info: CoreStateMachine::syncState stateService play Sep 11 13:11:19 volumio volumio[1124]: info: CoreStateMachine::syncState currentStatus play Sep 11 13:11:19 volumio volumio[1124]: info: Received an update from plugin. extracting info from payload Sep 11 13:11:19 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 11 13:11:19 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:19 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:19 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:19 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:19 volumio volumio[1124]: info: ------------------------------ 29ms Sep 11 13:11:19 volumio volumio[1124]: info: ------------------------------ 27ms Sep 11 13:11:19 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:11:19 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:11:19 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:11:19 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:11:19 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:11:19 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:11:19 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:11:19 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:11:19 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:11:19 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:11:19 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:11:19 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:11:19 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:11:19 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:11:19 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:11:19 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:11:19 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:11:19 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:11:22 volumio volumio[1999]: 60 Sep 11 13:11:22 volumio volumio[1999]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 13:11:22 volumio volumio[1999]: Dload Upload Total Spent Left Speed Sep 11 13:11:22 volumio volumio[1124]: info: Sep 11 13:11:22 volumio volumio[1124]: ---------------------------- Client requests Volume 60 Sep 11 13:11:22 volumio volumio[1124]: info: VolumeController::SetAlsaVolume60 Sep 11 13:11:22 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:22 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 13:11:22 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:22 volumio volumio[1999]: [158B blob data] Sep 11 13:11:22 volumio volumio[1999]: {"time":1757596282283,"response":"volume Success"}60 Sep 11 13:11:22 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:11:22 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:11:22 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:11:22 volumio volumio[1999]: 55 Sep 11 13:11:22 volumio volumio[1999]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 13:11:22 volumio volumio[1999]: Dload Upload Total Spent Left Speed Sep 11 13:11:22 volumio volumio[1124]: info: Sep 11 13:11:22 volumio volumio[1124]: ---------------------------- Client requests Volume 55 Sep 11 13:11:22 volumio volumio[1124]: info: VolumeController::SetAlsaVolume55 Sep 11 13:11:22 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:22 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 13:11:22 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:22 volumio volumio[1999]: [158B blob data] Sep 11 13:11:22 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:11:22 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:11:22 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:11:22 volumio volumio[1999]: {"time":1757596282535,"response":"volume Success"}55 Sep 11 13:11:23 volumio volumio[1999]: 50 Sep 11 13:11:23 volumio volumio[1999]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 13:11:23 volumio volumio[1999]: Dload Upload Total Spent Left Speed Sep 11 13:11:23 volumio volumio[1124]: info: Sep 11 13:11:23 volumio volumio[1124]: ---------------------------- Client requests Volume 50 Sep 11 13:11:23 volumio volumio[1124]: info: VolumeController::SetAlsaVolume50 Sep 11 13:11:23 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:23 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 13:11:23 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:23 volumio volumio[1999]: [158B blob data] Sep 11 13:11:23 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:11:23 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:11:23 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:11:23 volumio volumio[1999]: {"time":1757596283226,"response":"volume Success"}50 Sep 11 13:11:23 volumio volumio[1999]: 45 Sep 11 13:11:23 volumio volumio[1999]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 13:11:23 volumio volumio[1999]: Dload Upload Total Spent Left Speed Sep 11 13:11:23 volumio volumio[1124]: info: Sep 11 13:11:23 volumio volumio[1124]: ---------------------------- Client requests Volume 45 Sep 11 13:11:23 volumio volumio[1124]: info: VolumeController::SetAlsaVolume45 Sep 11 13:11:23 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:23 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 13:11:23 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:23 volumio volumio[1999]: [158B blob data] Sep 11 13:11:23 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:11:23 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:11:23 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:11:23 volumio volumio[1999]: {"time":1757596283598,"response":"volume Success"}45 Sep 11 13:11:23 volumio volumio[1999]: 40 Sep 11 13:11:23 volumio volumio[1999]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 13:11:23 volumio volumio[1999]: Dload Upload Total Spent Left Speed Sep 11 13:11:23 volumio volumio[1124]: info: Sep 11 13:11:23 volumio volumio[1124]: ---------------------------- Client requests Volume 40 Sep 11 13:11:23 volumio volumio[1124]: info: VolumeController::SetAlsaVolume40 Sep 11 13:11:23 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:23 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 13:11:23 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:23 volumio volumio[1999]: [158B blob data] Sep 11 13:11:23 volumio volumio[1999]: {"time":1757596283834,"response":"volume Success"}40 Sep 11 13:11:23 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:11:23 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:11:23 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:11:24 volumio volumio[1999]: 35 Sep 11 13:11:24 volumio volumio[1999]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 13:11:24 volumio volumio[1999]: Dload Upload Total Spent Left Speed Sep 11 13:11:24 volumio volumio[1124]: info: Sep 11 13:11:24 volumio volumio[1124]: ---------------------------- Client requests Volume 35 Sep 11 13:11:24 volumio volumio[1124]: info: VolumeController::SetAlsaVolume35 Sep 11 13:11:24 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:24 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 13:11:24 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:24 volumio volumio[1999]: [158B blob data] Sep 11 13:11:24 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:11:24 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:11:24 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:11:24 volumio volumio[1999]: {"time":1757596284281,"response":"volume Success"}35 Sep 11 13:11:25 volumio volumio[1999]: 30 Sep 11 13:11:25 volumio volumio[1999]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 13:11:25 volumio volumio[1999]: Dload Upload Total Spent Left Speed Sep 11 13:11:25 volumio volumio[1124]: info: Sep 11 13:11:25 volumio volumio[1124]: ---------------------------- Client requests Volume 30 Sep 11 13:11:25 volumio volumio[1124]: info: VolumeController::SetAlsaVolume30 Sep 11 13:11:25 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:11:25 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 13:11:25 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:11:25 volumio volumio[1999]: [158B blob data] Sep 11 13:11:25 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:11:25 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:11:25 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:11:25 volumio volumio[1999]: {"time":1757596285133,"response":"volume Success"}30 Sep 11 13:11:57 volumio volumio[1124]: info: CoreCommandRouter::volumioGetState Sep 11 13:12:07 volumio volumio[1124]: info: Clearing queue after UPNP request Sep 11 13:12:07 volumio volumio[1124]: info: CoreStateMachine::ClearQueue Sep 11 13:12:07 volumio volumio[1124]: info: CoreStateMachine::stop Sep 11 13:12:07 volumio volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 13:12:07 volumio volumio[1124]: info: CoreStateMachine::stPlaybackTimer Sep 11 13:12:07 volumio volumio[1124]: info: CoreStateMachine::updateTrackBlock Sep 11 13:12:07 volumio volumio[1124]: info: CorePlayQueue::getTrackBlock Sep 11 13:12:07 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:12:07 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:12:07 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 13:12:07 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:12:07 volumio volumio[1124]: info: CoreStateMachine::serviceStop Sep 11 13:12:07 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:12:07 volumio volumio[1124]: info: ControllerMpd::stop Sep 11 13:12:07 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand stop Sep 11 13:12:07 volumio volumio[1124]: info: CorePlayQueue::clearPlayQueue Sep 11 13:12:07 volumio volumio[1124]: info: CorePlayQueue::saveQueue Sep 11 13:12:07 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:12:07 volumio volumio[1124]: info: CoreCommandRouter::volumioPushQueue Sep 11 13:12:07 volumio volumio[1124]: info: Sep 11 13:12:07 volumio volumio[1124]: ---------------------------- MPD announces state update: player Sep 11 13:12:07 volumio volumio[1124]: info: sendMpdCommand stop took 4 milliseconds Sep 11 13:12:07 volumio volumio[1124]: info: ControllerMpd::getState Sep 11 13:12:07 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Sep 11 13:12:07 volumio volumio[1124]: info: Sep 11 13:12:07 volumio volumio[1124]: ---------------------------- MPD announces state update: player Sep 11 13:12:07 volumio volumio[1124]: info: ControllerMpd::getState Sep 11 13:12:07 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Sep 11 13:12:07 volumio volumio[1124]: info: Sep 11 13:12:07 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:12:07 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:12:07 volumio volumio[1124]: info: sendMpdCommand status took 2 milliseconds Sep 11 13:12:07 volumio volumio[1124]: info: sendMpdCommand status took 3 milliseconds Sep 11 13:12:07 volumio volumio[1124]: verbose: ControllerMpd::parseState Sep 11 13:12:07 volumio volumio[1124]: info: ControllerMpd::pushState Sep 11 13:12:07 volumio volumio[1124]: info: CoreCommandRouter::servicePushState Sep 11 13:12:07 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:12:07 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:12:07 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:12:07 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:12:07 volumio volumio[1124]: 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} Sep 11 13:12:07 volumio volumio[1124]: verbose: CURRENT POSITION 3 Sep 11 13:12:07 volumio volumio[1124]: info: CoreStateMachine::syncState stateService stop Sep 11 13:12:07 volumio volumio[1124]: info: CoreStateMachine::syncState currentStatus stop Sep 11 13:12:07 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:12:07 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:12:07 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:12:07 volumio volumio[1124]: info: No code Sep 11 13:12:07 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:12:07 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:12:07 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:12:07 volumio volumio[1124]: info: ControllerMpd::pushState Sep 11 13:12:07 volumio volumio[1124]: info: CoreCommandRouter::servicePushState Sep 11 13:12:07 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:12:07 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:12:07 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:12:07 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:12:07 volumio volumio[1124]: 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} Sep 11 13:12:07 volumio volumio[1124]: verbose: CURRENT POSITION 3 Sep 11 13:12:07 volumio volumio[1124]: info: CoreStateMachine::syncState stateService stop Sep 11 13:12:07 volumio volumio[1124]: info: CoreStateMachine::syncState currentStatus stop Sep 11 13:12:07 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:12:07 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:12:07 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:12:07 volumio volumio[1124]: info: No code Sep 11 13:12:07 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:12:07 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:12:07 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:12:07 volumio volumio[1124]: info: ------------------------------ 12ms Sep 11 13:12:07 volumio volumio[1124]: info: ------------------------------ 11ms Sep 11 13:12:07 volumio volumio[1124]: info: Sep 11 13:12:07 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:12:07 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:12:07 volumio volumio[1124]: error: updateQueue error: null Sep 11 13:12:07 volumio volumio[1124]: error: updateQueue error: null Sep 11 13:12:07 volumio volumio[1124]: info: ------------------------------ 13ms Sep 11 13:12:07 volumio volumio[1124]: info: ------------------------------ 1ms Sep 11 13:12:07 volumio volumio[1124]: info: Starting UPNP Playback Sep 11 13:12:07 volumio volumio[1124]: info: Preparing playback through UPNP Sep 11 13:12:07 volumio volumio[1124]: info: CoreCommandRouter::volumioGetState Sep 11 13:12:07 volumio volumio[1124]: info: CorePlayQueue::getTrack 3 Sep 11 13:12:07 volumio volumio[1124]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 11 13:12:08 volumio volumio[1124]: info: Sep 11 13:12:08 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:12:08 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:12:08 volumio volumio[1124]: info: Sep 11 13:12:08 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:12:08 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:12:08 volumio volumio[1124]: info: ------------------------------ 1ms Sep 11 13:12:08 volumio volumio[1124]: info: ------------------------------ 1ms Sep 11 13:12:08 volumio volumio[1124]: info: Sep 11 13:12:08 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:12:08 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:12:08 volumio volumio[1124]: info: Sep 11 13:12:08 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:12:08 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:12:08 volumio volumio[1124]: info: ------------------------------ 1ms Sep 11 13:12:08 volumio volumio[1124]: info: ------------------------------ 1ms Sep 11 13:12:08 volumio volumio[1124]: info: Sep 11 13:12:08 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:12:08 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:12:08 volumio volumio[1124]: info: Sep 11 13:12:08 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:12:08 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:12:08 volumio volumio[1124]: info: ------------------------------ 3ms Sep 11 13:12:08 volumio volumio[1124]: info: ------------------------------ 1ms Sep 11 13:12:08 volumio volumio[1124]: info: Sep 11 13:12:08 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:12:08 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:12:08 volumio volumio[1124]: info: Sep 11 13:12:08 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:12:08 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:12:08 volumio volumio[1124]: info: ------------------------------ 1ms Sep 11 13:12:08 volumio volumio[1124]: info: ------------------------------ 0ms Sep 11 13:12:08 volumio volumio[1124]: info: Sep 11 13:12:08 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:12:08 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:12:08 volumio volumio[1124]: info: Sep 11 13:12:08 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:12:08 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:12:08 volumio volumio[1124]: info: ------------------------------ 1ms Sep 11 13:12:08 volumio volumio[1124]: info: ------------------------------ 0ms Sep 11 13:12:08 volumio volumio[1124]: info: Sep 11 13:12:08 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:12:08 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:12:08 volumio volumio[1124]: info: Sep 11 13:12:08 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Sep 11 13:12:08 volumio volumio[1124]: info: Ignoring MPD Status Update Sep 11 13:12:08 volumio volumio[1124]: info: ------------------------------ 0ms Sep 11 13:12:08 volumio volumio[1124]: info: ------------------------------ 0ms Sep 11 13:12:09 volumio volumio[1124]: info: Sep 11 13:12:09 volumio volumio[1124]: ---------------------------- MPD announces state update: player Sep 11 13:12:09 volumio volumio[1124]: info: ControllerMpd::getState Sep 11 13:12:09 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Sep 11 13:12:09 volumio volumio[1124]: info: Sep 11 13:12:09 volumio volumio[1124]: ---------------------------- MPD announces state update: player Sep 11 13:12:09 volumio volumio[1124]: info: ControllerMpd::getState Sep 11 13:12:09 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Sep 11 13:12:09 volumio volumio[1124]: info: Sep 11 13:12:09 volumio volumio[1124]: ---------------------------- MPD announces state update: player Sep 11 13:12:09 volumio volumio[1124]: info: sendMpdCommand status took 2 milliseconds Sep 11 13:12:09 volumio volumio[1124]: info: ControllerMpd::getState Sep 11 13:12:09 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Sep 11 13:12:09 volumio volumio[1124]: info: sendMpdCommand status took 2 milliseconds Sep 11 13:12:09 volumio volumio[1124]: verbose: ControllerMpd::parseState Sep 11 13:12:09 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 13:12:09 volumio volumio[1124]: verbose: ControllerMpd::parseState Sep 11 13:12:09 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 13:12:09 volumio volumio[1124]: info: Sep 11 13:12:09 volumio volumio[1124]: ---------------------------- MPD announces state update: player Sep 11 13:12:09 volumio volumio[1124]: info: ControllerMpd::getState Sep 11 13:12:09 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Sep 11 13:12:09 volumio volumio[1124]: info: sendMpdCommand status took 3 milliseconds Sep 11 13:12:09 volumio volumio[1124]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 11 13:12:09 volumio volumio[1124]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 11 13:12:09 volumio volumio[1124]: verbose: ControllerMpd::parseState Sep 11 13:12:09 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 13:12:09 volumio volumio[1124]: verbose: ControllerMpd::parseTrackInfo Sep 11 13:12:09 volumio volumio[1124]: verbose: ControllerMpd::parseTrackInfo Sep 11 13:12:09 volumio volumio[1124]: info: ControllerMpd::pushState Sep 11 13:12:09 volumio volumio[1124]: info: CoreCommandRouter::servicePushState Sep 11 13:12:09 volumio volumio[1124]: verbose: In UPNP mode Sep 11 13:12:09 volumio volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":259,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"富士山下","artist":"陈奕迅","album":"What's Going On…?","uri":"http://m701.music.126.net/20250911213707/d1435b71d1f2f597fd077f2a5940784e/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32385808972/aa2f/d21e/998b/f15db921905b7dc7be3b5c8ab28f49d4.mp3?vuutv=2Bt8IsFfZYDaJ5YMq8OqID9LuRtkzHupCf6uUrjNbC9Ld6lytPbBt48rWLoK/cXWrrK63PiTAVrEkaEGHKfGzJ/9brfPGFYdAPsZ60qPhB4=","trackType":""} Sep 11 13:12:09 volumio volumio[1124]: verbose: CURRENT POSITION 3 Sep 11 13:12:09 volumio volumio[1124]: info: CoreStateMachine::syncState stateService play Sep 11 13:12:09 volumio volumio[1124]: info: CoreStateMachine::syncState currentStatus stop Sep 11 13:12:09 volumio volumio[1124]: info: ControllerMpd::pushState Sep 11 13:12:09 volumio volumio[1124]: info: CoreCommandRouter::servicePushState Sep 11 13:12:09 volumio volumio[1124]: verbose: In UPNP mode Sep 11 13:12:09 volumio volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":259,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"富士山下","artist":"陈奕迅","album":"What's Going On…?","uri":"http://m701.music.126.net/20250911213707/d1435b71d1f2f597fd077f2a5940784e/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32385808972/aa2f/d21e/998b/f15db921905b7dc7be3b5c8ab28f49d4.mp3?vuutv=2Bt8IsFfZYDaJ5YMq8OqID9LuRtkzHupCf6uUrjNbC9Ld6lytPbBt48rWLoK/cXWrrK63PiTAVrEkaEGHKfGzJ/9brfPGFYdAPsZ60qPhB4=","trackType":""} Sep 11 13:12:09 volumio volumio[1124]: verbose: CURRENT POSITION 3 Sep 11 13:12:09 volumio volumio[1124]: info: CoreStateMachine::syncState stateService play Sep 11 13:12:09 volumio volumio[1124]: info: CoreStateMachine::syncState currentStatus play Sep 11 13:12:09 volumio volumio[1124]: info: Received an update from plugin. extracting info from payload Sep 11 13:12:09 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 11 13:12:09 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:12:09 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 13:12:09 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:12:09 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:12:09 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:12:09 volumio volumio[1124]: info: ------------------------------ 12ms Sep 11 13:12:09 volumio volumio[1124]: info: ------------------------------ 13ms Sep 11 13:12:09 volumio volumio[1124]: info: sendMpdCommand status took 12 milliseconds Sep 11 13:12:09 volumio volumio[1124]: info: sendMpdCommand playlistinfo took 10 milliseconds Sep 11 13:12:09 volumio volumio[1124]: verbose: ControllerMpd::parseState Sep 11 13:12:09 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 13:12:09 volumio volumio[1124]: verbose: ControllerMpd::parseTrackInfo Sep 11 13:12:09 volumio volumio[1124]: info: ControllerMpd::pushState Sep 11 13:12:09 volumio volumio[1124]: info: CoreCommandRouter::servicePushState Sep 11 13:12:09 volumio volumio[1124]: verbose: In UPNP mode Sep 11 13:12:09 volumio volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":259,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"富士山下","artist":"陈奕迅","album":"What's Going On…?","uri":"http://m701.music.126.net/20250911213707/d1435b71d1f2f597fd077f2a5940784e/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32385808972/aa2f/d21e/998b/f15db921905b7dc7be3b5c8ab28f49d4.mp3?vuutv=2Bt8IsFfZYDaJ5YMq8OqID9LuRtkzHupCf6uUrjNbC9Ld6lytPbBt48rWLoK/cXWrrK63PiTAVrEkaEGHKfGzJ/9brfPGFYdAPsZ60qPhB4=","trackType":""} Sep 11 13:12:09 volumio volumio[1124]: verbose: CURRENT POSITION 3 Sep 11 13:12:09 volumio volumio[1124]: info: CoreStateMachine::syncState stateService play Sep 11 13:12:09 volumio volumio[1124]: info: CoreStateMachine::syncState currentStatus play Sep 11 13:12:09 volumio volumio[1124]: info: Received an update from plugin. extracting info from payload Sep 11 13:12:09 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 11 13:12:09 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:12:09 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:12:09 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:12:09 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:12:09 volumio volumio[1124]: info: ------------------------------ 23ms Sep 11 13:12:09 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:12:09 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:12:09 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:12:09 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:12:09 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:12:09 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:12:09 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:12:09 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:12:09 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:12:09 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:12:09 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:12:09 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:12:09 volumio volumio[1124]: info: sendMpdCommand playlistinfo took 18 milliseconds Sep 11 13:12:09 volumio volumio[1124]: verbose: ControllerMpd::parseTrackInfo Sep 11 13:12:09 volumio volumio[1124]: info: ControllerMpd::pushState Sep 11 13:12:09 volumio volumio[1124]: info: CoreCommandRouter::servicePushState Sep 11 13:12:09 volumio volumio[1124]: verbose: In UPNP mode Sep 11 13:12:09 volumio volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":259,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"富士山下","artist":"陈奕迅","album":"What's Going On…?","uri":"http://m701.music.126.net/20250911213707/d1435b71d1f2f597fd077f2a5940784e/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/32385808972/aa2f/d21e/998b/f15db921905b7dc7be3b5c8ab28f49d4.mp3?vuutv=2Bt8IsFfZYDaJ5YMq8OqID9LuRtkzHupCf6uUrjNbC9Ld6lytPbBt48rWLoK/cXWrrK63PiTAVrEkaEGHKfGzJ/9brfPGFYdAPsZ60qPhB4=","trackType":""} Sep 11 13:12:09 volumio volumio[1124]: verbose: CURRENT POSITION 3 Sep 11 13:12:09 volumio volumio[1124]: info: CoreStateMachine::syncState stateService play Sep 11 13:12:09 volumio volumio[1124]: info: CoreStateMachine::syncState currentStatus play Sep 11 13:12:09 volumio volumio[1124]: info: Received an update from plugin. extracting info from payload Sep 11 13:12:09 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 11 13:12:09 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:12:09 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:12:09 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:12:09 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:12:09 volumio volumio[1124]: info: ------------------------------ 41ms Sep 11 13:12:09 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:12:09 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:12:09 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:12:09 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:12:09 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:12:09 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:12:12 volumio volumio[1999]: 35 Sep 11 13:12:12 volumio volumio[1999]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 13:12:12 volumio volumio[1999]: Dload Upload Total Spent Left Speed Sep 11 13:12:12 volumio volumio[1124]: info: Sep 11 13:12:12 volumio volumio[1124]: ---------------------------- Client requests Volume 35 Sep 11 13:12:12 volumio volumio[1124]: info: VolumeController::SetAlsaVolume35 Sep 11 13:12:12 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:12:12 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 13:12:12 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:12:12 volumio volumio[1999]: [158B blob data] Sep 11 13:12:12 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:12:12 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:12:12 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:12:12 volumio volumio[1999]: {"time":1757596332070,"response":"volume Success"}35 Sep 11 13:12:12 volumio volumio[1999]: 40 Sep 11 13:12:12 volumio volumio[1999]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 13:12:12 volumio volumio[1999]: Dload Upload Total Spent Left Speed Sep 11 13:12:12 volumio volumio[1124]: info: Sep 11 13:12:12 volumio volumio[1124]: ---------------------------- Client requests Volume 40 Sep 11 13:12:12 volumio volumio[1124]: info: VolumeController::SetAlsaVolume40 Sep 11 13:12:12 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:12:12 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 13:12:12 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:12:12 volumio volumio[1999]: [158B blob data] Sep 11 13:12:12 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:12:12 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:12:12 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:12:12 volumio volumio[1999]: {"time":1757596332650,"response":"volume Success"}40 Sep 11 13:12:13 volumio volumio[1999]: 45 Sep 11 13:12:13 volumio volumio[1999]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 13:12:13 volumio volumio[1999]: Dload Upload Total Spent Left Speed Sep 11 13:12:13 volumio volumio[1124]: info: Sep 11 13:12:13 volumio volumio[1124]: ---------------------------- Client requests Volume 45 Sep 11 13:12:13 volumio volumio[1124]: info: VolumeController::SetAlsaVolume45 Sep 11 13:12:13 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:12:13 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 13:12:13 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:12:13 volumio volumio[1999]: [158B blob data] Sep 11 13:12:13 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:12:13 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:12:13 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:12:13 volumio volumio[1999]: {"time":1757596333379,"response":"volume Success"}45 Sep 11 13:12:14 volumio volumio[1999]: 40 Sep 11 13:12:14 volumio volumio[1999]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 13:12:14 volumio volumio[1999]: Dload Upload Total Spent Left Speed Sep 11 13:12:14 volumio volumio[1124]: info: Sep 11 13:12:14 volumio volumio[1124]: ---------------------------- Client requests Volume 40 Sep 11 13:12:14 volumio volumio[1124]: info: VolumeController::SetAlsaVolume40 Sep 11 13:12:14 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:12:14 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 13:12:14 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:12:14 volumio volumio[1999]: [158B blob data] Sep 11 13:12:14 volumio volumio[1999]: {"time":1757596334410,"response":"volume Success"}40 Sep 11 13:12:14 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:12:14 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:12:14 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:12:14 volumio volumio[1999]: 35 Sep 11 13:12:14 volumio volumio[1999]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 11 13:12:14 volumio volumio[1999]: Dload Upload Total Spent Left Speed Sep 11 13:12:14 volumio volumio[1124]: info: Sep 11 13:12:14 volumio volumio[1124]: ---------------------------- Client requests Volume 35 Sep 11 13:12:14 volumio volumio[1124]: info: VolumeController::SetAlsaVolume35 Sep 11 13:12:14 volumio volumio[1124]: info: CoreStateMachine::pushState Sep 11 13:12:14 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 13:12:14 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Sep 11 13:12:14 volumio volumio[1999]: [158B blob data] Sep 11 13:12:14 volumio volumio[1999]: {"time":1757596334823,"response":"volume Success"}35 Sep 11 13:12:14 volumio volumio[1124]: info: CoreCommandRouter::volumioGetQueue Sep 11 13:12:14 volumio volumio[1124]: info: CoreStateMachine::getQueue Sep 11 13:12:14 volumio volumio[1124]: info: CorePlayQueue::getQueue Sep 11 13:12:21 volumio volumio[1124]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 11 13:12:21 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 11 13:12:21 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 11 13:12:21 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 11 13:12:21 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Sep 11 13:12:21 volumio volumio[1124]: info: Received Get System Version Sep 11 13:12:21 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 11 13:12:34 volumio volumio[1124]: info: CoreCommandRouter::volumioGetState Sep 11 13:12:36 volumio volumio[1124]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 11 13:12:36 volumio volumio[1124]: Error: connect ETIMEDOUT 103.39.76.66:443 Sep 11 13:12:36 volumio volumio[1124]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Sep 11 13:12:36 volumio volumio[1124]: errno: -110, Sep 11 13:12:36 volumio volumio[1124]: code: 'ETIMEDOUT', Sep 11 13:12:36 volumio volumio[1124]: syscall: 'connect', Sep 11 13:12:36 volumio volumio[1124]: address: '103.39.76.66', Sep 11 13:12:36 volumio volumio[1124]: port: 443 Sep 11 13:12:36 volumio volumio[1124]: } Sep 11 13:12:36 volumio volumio[1124]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 11 13:12:36 volumio sudo[8459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-11 13:11 Sep 11 13:12:36 volumio sudo[8459]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"