-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Fri 2025-07-18 18:25:56 CST. -- Jul 18 18:24:06 volumio volumio[947]: info: Jul 18 18:24:06 volumio volumio[947]: ---------------------------- MPD announces state update: player Jul 18 18:24:06 volumio volumio[947]: info: ControllerMpd::getState Jul 18 18:24:06 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand status Jul 18 18:24:06 volumio volumio[947]: info: sendMpdCommand status took 1 milliseconds Jul 18 18:24:06 volumio volumio[947]: verbose: ControllerMpd::parseState Jul 18 18:24:06 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 18:24:06 volumio volumio[947]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 18 18:24:06 volumio volumio[947]: verbose: ControllerMpd::parseTrackInfo Jul 18 18:24:06 volumio volumio[947]: info: ControllerMpd::pushState Jul 18 18:24:06 volumio volumio[947]: info: CoreCommandRouter::servicePushState Jul 18 18:24:06 volumio volumio[947]: verbose: In UPNP mode Jul 18 18:24:06 volumio volumio[947]: verbose: STATE SERVICE {"status":"play","position":0,"seek":8828,"duration":16752,"samplerate":"8 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"80 Kbps","isStreaming":false,"title":"漠河舞厅","artist":"柳爽","album":"1st.星球","uri":"http://m701.music.126.net/20250718184811/28886897e7208c981c47e972ea19d4d4/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/26306044138/d7b5/2303/b251/371f157d7fc9adcc133fdecf41d41996.flac?vuutv=/zviP5rIhkBpVjV2wD+p1lfeMmxcGnV4HDfRS1u7MwAV2bVeCkeTYcX9X008fqTQgAolZve1f3inoHhBcQbugxc4Rj55KxM6k4mjolzDmE0=","trackType":""} Jul 18 18:24:06 volumio volumio[947]: verbose: CURRENT POSITION 5 Jul 18 18:24:06 volumio volumio[947]: info: CoreStateMachine::syncState stateService play Jul 18 18:24:06 volumio volumio[947]: info: CoreStateMachine::syncState currentStatus play Jul 18 18:24:06 volumio volumio[947]: info: Received an update from plugin. extracting info from payload Jul 18 18:24:06 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 18:24:06 volumio volumio[947]: info: CoreStateMachine::pushState Jul 18 18:24:06 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 18:24:06 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 18 18:24:06 volumio volumio[947]: info: CoreStateMachine::pushState Jul 18 18:24:06 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 18 18:24:06 volumio volumio[947]: info: ------------------------------ 21ms Jul 18 18:24:06 volumio volumio[947]: info: CoreCommandRouter::volumioGetQueue Jul 18 18:24:06 volumio volumio[947]: info: CoreStateMachine::getQueue Jul 18 18:24:06 volumio volumio[947]: info: CorePlayQueue::getQueue Jul 18 18:24:06 volumio volumio[947]: info: CoreCommandRouter::volumioGetQueue Jul 18 18:24:06 volumio volumio[947]: info: CoreStateMachine::getQueue Jul 18 18:24:06 volumio volumio[947]: info: CorePlayQueue::getQueue Jul 18 18:24:11 volumio volumio[947]: info: Jul 18 18:24:11 volumio volumio[947]: ---------------------------- MPD announces state update: player Jul 18 18:24:11 volumio volumio[947]: info: ControllerMpd::getState Jul 18 18:24:11 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand status Jul 18 18:24:11 volumio volumio[947]: info: sendMpdCommand status took 1 milliseconds Jul 18 18:24:11 volumio volumio[947]: verbose: ControllerMpd::parseState Jul 18 18:24:11 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 18:24:11 volumio volumio[947]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 18 18:24:11 volumio volumio[947]: verbose: ControllerMpd::parseTrackInfo Jul 18 18:24:11 volumio volumio[947]: info: ControllerMpd::pushState Jul 18 18:24:11 volumio volumio[947]: info: CoreCommandRouter::servicePushState Jul 18 18:24:11 volumio volumio[947]: info: CoreStateMachine::pushState Jul 18 18:24:11 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 18 18:24:11 volumio volumio[947]: info: CorePlayQueue::getTrack 5 Jul 18 18:24:11 volumio volumio[947]: 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":"1st.星球","uri":"http://m701.music.126.net/20250718184811/28886897e7208c981c47e972ea19d4d4/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/26306044138/d7b5/2303/b251/371f157d7fc9adcc133fdecf41d41996.flac?vuutv=/zviP5rIhkBpVjV2wD+p1lfeMmxcGnV4HDfRS1u7MwAV2bVeCkeTYcX9X008fqTQgAolZve1f3inoHhBcQbugxc4Rj55KxM6k4mjolzDmE0=","trackType":""} Jul 18 18:24:11 volumio volumio[947]: verbose: CURRENT POSITION 5 Jul 18 18:24:11 volumio volumio[947]: info: CoreStateMachine::syncState stateService stop Jul 18 18:24:11 volumio volumio[947]: info: CoreStateMachine::syncState currentStatus play Jul 18 18:24:11 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 18 18:24:11 volumio volumio[947]: info: CoreStateMachine::stPlaybackTimer Jul 18 18:24:11 volumio volumio[947]: info: ------------------------------ 15ms Jul 18 18:24:11 volumio volumio[947]: info: CoreCommandRouter::volumioGetQueue Jul 18 18:24:11 volumio volumio[947]: info: CoreStateMachine::getQueue Jul 18 18:24:11 volumio volumio[947]: info: CorePlayQueue::getQueue Jul 18 18:24:11 volumio volumio[947]: info: Clearing queue after UPNP request Jul 18 18:24:11 volumio volumio[947]: info: CoreStateMachine::ClearQueue Jul 18 18:24:11 volumio volumio[947]: info: CoreStateMachine::stop Jul 18 18:24:11 volumio volumio[947]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 18:24:11 volumio volumio[947]: info: CorePlayQueue::clearPlayQueue Jul 18 18:24:11 volumio volumio[947]: info: CorePlayQueue::saveQueue Jul 18 18:24:11 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 18 18:24:11 volumio volumio[947]: info: CoreCommandRouter::volumioPushQueue Jul 18 18:24:11 volumio volumio[947]: info: Jul 18 18:24:11 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 18 18:24:11 volumio volumio[947]: info: Ignoring MPD Status Update Jul 18 18:24:11 volumio volumio[947]: error: updateQueue error: null Jul 18 18:24:11 volumio volumio[947]: info: ------------------------------ 1ms Jul 18 18:24:11 volumio volumio[947]: info: Starting UPNP Playback Jul 18 18:24:11 volumio volumio[947]: info: Preparing playback through UPNP Jul 18 18:24:11 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Jul 18 18:24:11 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 18 18:24:11 volumio volumio[947]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 18 18:24:12 volumio volumio[947]: info: Jul 18 18:24:12 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 18 18:24:12 volumio volumio[947]: info: Ignoring MPD Status Update Jul 18 18:24:12 volumio volumio[947]: info: Jul 18 18:24:12 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 18 18:24:12 volumio volumio[947]: info: Ignoring MPD Status Update Jul 18 18:24:12 volumio volumio[947]: info: ------------------------------ 1ms Jul 18 18:24:12 volumio volumio[947]: info: ------------------------------ 1ms Jul 18 18:24:12 volumio volumio[947]: info: Jul 18 18:24:12 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 18 18:24:12 volumio volumio[947]: info: Ignoring MPD Status Update Jul 18 18:24:12 volumio volumio[947]: info: ------------------------------ 1ms Jul 18 18:24:12 volumio volumio[947]: info: Jul 18 18:24:12 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 18 18:24:12 volumio volumio[947]: info: Ignoring MPD Status Update Jul 18 18:24:12 volumio volumio[947]: info: ------------------------------ 0ms Jul 18 18:24:12 volumio volumio[947]: info: Jul 18 18:24:12 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 18 18:24:12 volumio volumio[947]: info: Ignoring MPD Status Update Jul 18 18:24:12 volumio volumio[947]: info: ------------------------------ 1ms Jul 18 18:24:12 volumio volumio[947]: info: Jul 18 18:24:12 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 18 18:24:12 volumio volumio[947]: info: Ignoring MPD Status Update Jul 18 18:24:12 volumio volumio[947]: info: ------------------------------ 1ms Jul 18 18:24:12 volumio volumio[947]: info: Jul 18 18:24:12 volumio volumio[947]: ---------------------------- MPD announces state update: player Jul 18 18:24:12 volumio volumio[947]: info: ControllerMpd::getState Jul 18 18:24:12 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand status Jul 18 18:24:12 volumio volumio[947]: info: Jul 18 18:24:12 volumio volumio[947]: ---------------------------- MPD announces state update: player Jul 18 18:24:12 volumio volumio[947]: info: sendMpdCommand status took 26 milliseconds Jul 18 18:24:12 volumio volumio[947]: info: ControllerMpd::getState Jul 18 18:24:12 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand status Jul 18 18:24:12 volumio volumio[947]: verbose: ControllerMpd::parseState Jul 18 18:24:12 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 18:24:12 volumio volumio[947]: info: sendMpdCommand status took 3 milliseconds Jul 18 18:24:12 volumio volumio[947]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 18 18:24:12 volumio volumio[947]: verbose: ControllerMpd::parseState Jul 18 18:24:12 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 18:24:12 volumio volumio[947]: verbose: ControllerMpd::parseTrackInfo Jul 18 18:24:12 volumio volumio[947]: info: ControllerMpd::pushState Jul 18 18:24:12 volumio volumio[947]: info: CoreCommandRouter::servicePushState Jul 18 18:24:12 volumio volumio[947]: verbose: In UPNP mode Jul 18 18:24:12 volumio volumio[947]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":334,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"漠河舞厅","artist":"柳爽","album":"1st.星球","uri":"http://m701.music.126.net/20250718184911/353f574169fe3f37b84a104aa4e3e22c/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/14096439373/c195/8493/f3b9/a1672580d97f209f9c76e80642ce68de.mp3?vuutv=flT36qO3nBvuxdxLBav6deHcY7Bs/gDAdHth4XSNNdVxHlk8PVZY7THhAL9PtUFQ1tqBOJfqA5gedg6gBFBEcsl/j4PFmhNHh/M98IU8kR8=","trackType":""} Jul 18 18:24:12 volumio volumio[947]: verbose: CURRENT POSITION 0 Jul 18 18:24:12 volumio volumio[947]: info: CoreStateMachine::syncState stateService play Jul 18 18:24:12 volumio volumio[947]: info: CoreStateMachine::syncState currentStatus stop Jul 18 18:24:12 volumio volumio[947]: info: ------------------------------ 33ms Jul 18 18:24:12 volumio volumio[947]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 18 18:24:12 volumio volumio[947]: verbose: ControllerMpd::parseTrackInfo Jul 18 18:24:12 volumio volumio[947]: info: ControllerMpd::pushState Jul 18 18:24:12 volumio volumio[947]: info: CoreCommandRouter::servicePushState Jul 18 18:24:12 volumio volumio[947]: verbose: In UPNP mode Jul 18 18:24:12 volumio volumio[947]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":334,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"漠河舞厅","artist":"柳爽","album":"1st.星球","uri":"http://m701.music.126.net/20250718184911/353f574169fe3f37b84a104aa4e3e22c/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/14096439373/c195/8493/f3b9/a1672580d97f209f9c76e80642ce68de.mp3?vuutv=flT36qO3nBvuxdxLBav6deHcY7Bs/gDAdHth4XSNNdVxHlk8PVZY7THhAL9PtUFQ1tqBOJfqA5gedg6gBFBEcsl/j4PFmhNHh/M98IU8kR8=","trackType":""} Jul 18 18:24:12 volumio volumio[947]: verbose: CURRENT POSITION 0 Jul 18 18:24:12 volumio volumio[947]: info: CoreStateMachine::syncState stateService play Jul 18 18:24:12 volumio volumio[947]: info: CoreStateMachine::syncState currentStatus play Jul 18 18:24:12 volumio volumio[947]: info: Received an update from plugin. extracting info from payload Jul 18 18:24:12 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 18:24:12 volumio volumio[947]: info: CoreStateMachine::pushState Jul 18 18:24:12 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 18:24:12 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 18 18:24:12 volumio volumio[947]: info: CoreStateMachine::pushState Jul 18 18:24:12 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 18 18:24:12 volumio volumio[947]: info: ------------------------------ 21ms Jul 18 18:24:12 volumio volumio[947]: info: CoreCommandRouter::volumioGetQueue Jul 18 18:24:12 volumio volumio[947]: info: CoreStateMachine::getQueue Jul 18 18:24:12 volumio volumio[947]: info: CorePlayQueue::getQueue Jul 18 18:24:12 volumio volumio[947]: info: CoreCommandRouter::volumioGetQueue Jul 18 18:24:12 volumio volumio[947]: info: CoreStateMachine::getQueue Jul 18 18:24:12 volumio volumio[947]: info: CorePlayQueue::getQueue Jul 18 18:25:01 volumio volumio[947]: info: Clearing queue after UPNP request Jul 18 18:25:01 volumio volumio[947]: info: CoreStateMachine::ClearQueue Jul 18 18:25:01 volumio volumio[947]: info: CoreStateMachine::stop Jul 18 18:25:01 volumio volumio[947]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 18:25:01 volumio volumio[947]: info: CoreStateMachine::stPlaybackTimer Jul 18 18:25:01 volumio volumio[947]: info: CoreStateMachine::updateTrackBlock Jul 18 18:25:01 volumio volumio[947]: info: CorePlayQueue::getTrackBlock Jul 18 18:25:01 volumio volumio[947]: info: CoreStateMachine::pushState Jul 18 18:25:01 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 18 18:25:01 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 18:25:01 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 18 18:25:01 volumio volumio[947]: info: CoreStateMachine::serviceStop Jul 18 18:25:01 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 18 18:25:01 volumio volumio[947]: info: ControllerMpd::stop Jul 18 18:25:01 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 18:25:01 volumio volumio[947]: info: CorePlayQueue::clearPlayQueue Jul 18 18:25:01 volumio volumio[947]: info: CorePlayQueue::saveQueue Jul 18 18:25:01 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 18 18:25:01 volumio volumio[947]: info: CoreCommandRouter::volumioPushQueue Jul 18 18:25:01 volumio volumio[947]: info: Jul 18 18:25:01 volumio volumio[947]: ---------------------------- MPD announces state update: player Jul 18 18:25:01 volumio volumio[947]: info: sendMpdCommand stop took 20 milliseconds Jul 18 18:25:01 volumio volumio[947]: info: ControllerMpd::getState Jul 18 18:25:01 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand status Jul 18 18:25:01 volumio volumio[947]: info: Jul 18 18:25:01 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 18 18:25:01 volumio volumio[947]: info: Ignoring MPD Status Update Jul 18 18:25:01 volumio volumio[947]: error: updateQueue error: null Jul 18 18:25:01 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 18 18:25:01 volumio volumio[947]: info: sendMpdCommand status took 4 milliseconds Jul 18 18:25:01 volumio volumio[947]: info: ------------------------------ 4ms Jul 18 18:25:01 volumio volumio[947]: verbose: ControllerMpd::parseState Jul 18 18:25:01 volumio volumio[947]: info: ControllerMpd::pushState Jul 18 18:25:01 volumio volumio[947]: info: CoreCommandRouter::servicePushState Jul 18 18:25:01 volumio volumio[947]: info: CoreStateMachine::pushState Jul 18 18:25:01 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 18 18:25:01 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 18 18:25:01 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 18 18:25:01 volumio volumio[947]: 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} Jul 18 18:25:01 volumio volumio[947]: verbose: CURRENT POSITION 0 Jul 18 18:25:01 volumio volumio[947]: info: CoreStateMachine::syncState stateService stop Jul 18 18:25:01 volumio volumio[947]: info: CoreStateMachine::syncState currentStatus stop Jul 18 18:25:01 volumio volumio[947]: info: CoreStateMachine::pushState Jul 18 18:25:01 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 18 18:25:01 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 18 18:25:01 volumio volumio[947]: info: No code Jul 18 18:25:01 volumio volumio[947]: info: CoreStateMachine::pushState Jul 18 18:25:01 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 18 18:25:01 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 18 18:25:01 volumio volumio[947]: info: ------------------------------ 26ms Jul 18 18:25:01 volumio volumio[947]: info: sendMpdCommand clearerror took 22 milliseconds Jul 18 18:25:01 volumio volumio[947]: info: Starting UPNP Playback Jul 18 18:25:01 volumio volumio[947]: info: Preparing playback through UPNP Jul 18 18:25:01 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Jul 18 18:25:01 volumio volumio[947]: info: CorePlayQueue::getTrack 0 Jul 18 18:25:01 volumio volumio[947]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 18 18:25:02 volumio volumio[947]: info: Jul 18 18:25:02 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 18 18:25:02 volumio volumio[947]: info: Ignoring MPD Status Update Jul 18 18:25:02 volumio volumio[947]: info: Jul 18 18:25:02 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 18 18:25:02 volumio volumio[947]: info: Ignoring MPD Status Update Jul 18 18:25:02 volumio volumio[947]: info: ------------------------------ 1ms Jul 18 18:25:02 volumio volumio[947]: info: ------------------------------ 1ms Jul 18 18:25:02 volumio volumio[947]: info: Jul 18 18:25:02 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 18 18:25:02 volumio volumio[947]: info: Ignoring MPD Status Update Jul 18 18:25:02 volumio volumio[947]: info: ------------------------------ 1ms Jul 18 18:25:02 volumio volumio[947]: info: Jul 18 18:25:02 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 18 18:25:02 volumio volumio[947]: info: Ignoring MPD Status Update Jul 18 18:25:02 volumio volumio[947]: info: ------------------------------ 1ms Jul 18 18:25:02 volumio volumio[947]: info: Jul 18 18:25:02 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 18 18:25:02 volumio volumio[947]: info: Ignoring MPD Status Update Jul 18 18:25:02 volumio volumio[947]: info: ------------------------------ 1ms Jul 18 18:25:02 volumio volumio[947]: info: Jul 18 18:25:02 volumio volumio[947]: ---------------------------- MPD announces system playlist update Jul 18 18:25:02 volumio volumio[947]: info: Ignoring MPD Status Update Jul 18 18:25:02 volumio volumio[947]: info: ------------------------------ 1ms Jul 18 18:25:02 volumio volumio[947]: info: Jul 18 18:25:02 volumio volumio[947]: ---------------------------- MPD announces state update: player Jul 18 18:25:02 volumio volumio[947]: info: ControllerMpd::getState Jul 18 18:25:02 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand status Jul 18 18:25:02 volumio volumio[947]: info: Jul 18 18:25:02 volumio volumio[947]: ---------------------------- MPD announces state update: player Jul 18 18:25:02 volumio volumio[947]: info: ControllerMpd::getState Jul 18 18:25:02 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand status Jul 18 18:25:02 volumio volumio[947]: info: sendMpdCommand status took 1 milliseconds Jul 18 18:25:02 volumio volumio[947]: verbose: ControllerMpd::parseState Jul 18 18:25:02 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 18:25:02 volumio volumio[947]: info: sendMpdCommand status took 1 milliseconds Jul 18 18:25:02 volumio volumio[947]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 18 18:25:02 volumio volumio[947]: verbose: ControllerMpd::parseState Jul 18 18:25:02 volumio volumio[947]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 18:25:02 volumio volumio[947]: verbose: ControllerMpd::parseTrackInfo Jul 18 18:25:02 volumio volumio[947]: info: ControllerMpd::pushState Jul 18 18:25:02 volumio volumio[947]: info: CoreCommandRouter::servicePushState Jul 18 18:25:02 volumio volumio[947]: verbose: In UPNP mode Jul 18 18:25:02 volumio volumio[947]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":198,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Greensleeves","artist":"Rosalind McAllister","album":"Arias Ancora","uri":"http://m701.music.126.net/20250718185001/77c4d84d894a24780b1d30f7ef1e07c3/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/23054664547/e6b3/8bb4/9ba1/025e759c288e1c235267359f21b624df.mp3?vuutv=67kq0e+G7vv+DsGOK5wlAt6fixRrLCezA+4nALMDDG0sB7VUBOujNxEa4+aESpw7sc83SoUkda76r2FBAx2JPN6HSw06fGVsb+w9qHC3jkY=","trackType":""} Jul 18 18:25:02 volumio volumio[947]: verbose: CURRENT POSITION 0 Jul 18 18:25:02 volumio volumio[947]: info: CoreStateMachine::syncState stateService play Jul 18 18:25:02 volumio volumio[947]: info: CoreStateMachine::syncState currentStatus stop Jul 18 18:25:02 volumio volumio[947]: info: ------------------------------ 5ms Jul 18 18:25:02 volumio volumio[947]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 18 18:25:02 volumio volumio[947]: verbose: ControllerMpd::parseTrackInfo Jul 18 18:25:02 volumio volumio[947]: info: ControllerMpd::pushState Jul 18 18:25:02 volumio volumio[947]: info: CoreCommandRouter::servicePushState Jul 18 18:25:02 volumio volumio[947]: verbose: In UPNP mode Jul 18 18:25:02 volumio volumio[947]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":198,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Greensleeves","artist":"Rosalind McAllister","album":"Arias Ancora","uri":"http://m701.music.126.net/20250718185001/77c4d84d894a24780b1d30f7ef1e07c3/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/23054664547/e6b3/8bb4/9ba1/025e759c288e1c235267359f21b624df.mp3?vuutv=67kq0e+G7vv+DsGOK5wlAt6fixRrLCezA+4nALMDDG0sB7VUBOujNxEa4+aESpw7sc83SoUkda76r2FBAx2JPN6HSw06fGVsb+w9qHC3jkY=","trackType":""} Jul 18 18:25:02 volumio volumio[947]: verbose: CURRENT POSITION 0 Jul 18 18:25:02 volumio volumio[947]: info: CoreStateMachine::syncState stateService play Jul 18 18:25:02 volumio volumio[947]: info: CoreStateMachine::syncState currentStatus play Jul 18 18:25:02 volumio volumio[947]: info: Received an update from plugin. extracting info from payload Jul 18 18:25:02 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 18 18:25:02 volumio volumio[947]: info: CoreStateMachine::pushState Jul 18 18:25:02 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 18:25:02 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 18 18:25:02 volumio volumio[947]: info: CoreStateMachine::pushState Jul 18 18:25:02 volumio volumio[947]: info: CoreCommandRouter::volumioPushState Jul 18 18:25:02 volumio volumio[947]: info: ------------------------------ 14ms Jul 18 18:25:02 volumio volumio[947]: info: CoreCommandRouter::volumioGetQueue Jul 18 18:25:02 volumio volumio[947]: info: CoreStateMachine::getQueue Jul 18 18:25:02 volumio volumio[947]: info: CorePlayQueue::getQueue Jul 18 18:25:02 volumio volumio[947]: info: CoreCommandRouter::volumioGetQueue Jul 18 18:25:02 volumio volumio[947]: info: CoreStateMachine::getQueue Jul 18 18:25:02 volumio volumio[947]: info: CorePlayQueue::getQueue Jul 18 18:25:15 volumio volumio[947]: verbose: New Socket.io Connection to 192.168.31.47 from 192.168.31.213 UA: Mozilla/5.0 (Linux; U; Android 15; zh-cn; 2201123C Build/AQ3A.241006.001) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.119 Mobile Safari/537.36 XiaoMi/MiuiBrowser/19.7.20630 Engine version: 3 Transport: polling Total Clients: 4 Jul 18 18:25:16 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 18 18:25:16 volumio volumio[947]: info: CoreCommandRouter::volumioGetVisibleSources Jul 18 18:25:16 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 18:25:16 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Jul 18 18:25:16 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 18 18:25:16 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 18 18:25:16 volumio volumio[947]: info: Received Get System Info Jul 18 18:25:16 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 18:25:16 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 18:25:16 volumio volumio[947]: info: Discovery: Getting this device information Jul 18 18:25:16 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Jul 18 18:25:16 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 18 18:25:16 volumio volumio[947]: info: CoreCommandRouter::volumioGetState Jul 18 18:25:16 volumio volumio[947]: info: Listing playlists Jul 18 18:25:16 volumio volumio[947]: info: CoreCommandRouter::volumioGetQueue Jul 18 18:25:16 volumio volumio[947]: info: CoreStateMachine::getQueue Jul 18 18:25:16 volumio volumio[947]: info: CorePlayQueue::getQueue Jul 18 18:25:16 volumio volumio[947]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 18 18:25:56 volumio volumio[947]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 18:25:56 volumio volumio[947]: Error: connect ETIMEDOUT 31.13.91.6:443 Jul 18 18:25:56 volumio volumio[947]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jul 18 18:25:56 volumio volumio[947]: errno: -110, Jul 18 18:25:56 volumio volumio[947]: code: 'ETIMEDOUT', Jul 18 18:25:56 volumio volumio[947]: syscall: 'connect', Jul 18 18:25:56 volumio volumio[947]: address: '31.13.91.6', Jul 18 18:25:56 volumio volumio[947]: port: 443 Jul 18 18:25:56 volumio volumio[947]: } Jul 18 18:25:56 volumio volumio[947]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 18:25:56 volumio sudo[8813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-18 18:24 Jul 18 18:25:56 volumio sudo[8813]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"