-- Logs begin at Fri 2025-05-02 12:56:22 CST, end at Fri 2025-05-02 13:02:15 CST. -- May 02 13:01:45 volumio volumio[727]: info: May 02 13:01:45 volumio volumio[727]: ---------------------------- MPD announces state update: player May 02 13:01:45 volumio volumio[727]: info: ControllerMpd::getState May 02 13:01:45 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status May 02 13:01:45 volumio volumio[727]: info: sendMpdCommand status took 2 milliseconds May 02 13:01:45 volumio volumio[727]: verbose: ControllerMpd::parseState May 02 13:01:45 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 13:01:45 volumio volumio[727]: info: sendMpdCommand playlistinfo took 1 milliseconds May 02 13:01:45 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo May 02 13:01:45 volumio volumio[727]: info: ControllerMpd::pushState May 02 13:01:45 volumio volumio[727]: info: CoreCommandRouter::servicePushState May 02 13:01:45 volumio volumio[727]: info: CoreStateMachine::pushState May 02 13:01:45 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:45 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 02 13:01:45 volumio volumio[727]: 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":"Peacefully","artist":"Giovanni Marradi","album":"Passionate Piano","uri":"http://m801.music.126.net/20250502132500/93b19d837b2fae76bb7ba59998b8099f/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/35947599053/84dd/586c/0ae3/900648386e2a2141ca8943f3fd14165d.mp3?vuutv=lLzFm0kC3LUhOPY36JYn6xl8dueOOo/qoKOxT6i1jvRIoBdeFjE99OAZRPmAJ51gmqzPSzOtQu+aKE/Oo8fXYR9yo58nRFrA5zH2gJ5ptYU=","trackType":""} May 02 13:01:45 volumio volumio[727]: verbose: CURRENT POSITION 0 May 02 13:01:45 volumio volumio[727]: info: CoreStateMachine::syncState stateService stop May 02 13:01:45 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus play May 02 13:01:45 volumio volumio[727]: info: CoreStateMachine::play index undefined May 02 13:01:45 volumio volumio[727]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 13:01:45 volumio volumio[727]: info: CoreStateMachine::pushState May 02 13:01:45 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 02 13:01:45 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 13:01:45 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:45 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 02 13:01:45 volumio volumio[727]: info: ------------------------------ 35ms May 02 13:01:45 volumio volumio[727]: info: CoreCommandRouter::volumioGetQueue May 02 13:01:45 volumio volumio[727]: info: CoreStateMachine::getQueue May 02 13:01:45 volumio volumio[727]: info: CorePlayQueue::getQueue May 02 13:01:45 volumio volumio[727]: info: Clearing queue after UPNP request May 02 13:01:45 volumio volumio[727]: info: CoreStateMachine::ClearQueue May 02 13:01:45 volumio volumio[727]: info: CoreStateMachine::stop May 02 13:01:45 volumio volumio[727]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 13:01:45 volumio volumio[727]: info: CorePlayQueue::clearPlayQueue May 02 13:01:45 volumio volumio[727]: info: CorePlayQueue::saveQueue May 02 13:01:45 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:45 volumio volumio[727]: info: CoreCommandRouter::volumioPushQueue May 02 13:01:45 volumio volumio[727]: info: May 02 13:01:45 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:45 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:45 volumio volumio[727]: error: updateQueue error: null May 02 13:01:45 volumio volumio[727]: info: ------------------------------ 2ms May 02 13:01:45 volumio volumio[727]: info: Starting UPNP Playback May 02 13:01:45 volumio volumio[727]: info: Preparing playback through UPNP May 02 13:01:45 volumio volumio[727]: info: CoreCommandRouter::volumioGetState May 02 13:01:45 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 02 13:01:45 volumio volumio[727]: info: CoreStateMachine::setConsumeUpdateService mpd May 02 13:01:46 volumio volumio[727]: info: May 02 13:01:46 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:46 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:46 volumio volumio[727]: info: May 02 13:01:46 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:46 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:46 volumio volumio[727]: info: ------------------------------ 4ms May 02 13:01:46 volumio volumio[727]: info: ------------------------------ 2ms May 02 13:01:46 volumio volumio[727]: info: May 02 13:01:46 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:46 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:46 volumio volumio[727]: info: May 02 13:01:46 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:46 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:46 volumio volumio[727]: info: ------------------------------ 2ms May 02 13:01:46 volumio volumio[727]: info: ------------------------------ 2ms May 02 13:01:46 volumio volumio[727]: info: May 02 13:01:46 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:46 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:46 volumio volumio[727]: info: ------------------------------ 2ms May 02 13:01:46 volumio volumio[727]: info: May 02 13:01:46 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:46 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:46 volumio volumio[727]: info: ------------------------------ 2ms May 02 13:01:46 volumio volumio[727]: info: May 02 13:01:46 volumio volumio[727]: ---------------------------- MPD announces state update: player May 02 13:01:46 volumio volumio[727]: info: ControllerMpd::getState May 02 13:01:46 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status May 02 13:01:46 volumio volumio[727]: info: May 02 13:01:46 volumio volumio[727]: ---------------------------- MPD announces state update: player May 02 13:01:46 volumio volumio[727]: info: sendMpdCommand status took 3 milliseconds May 02 13:01:46 volumio volumio[727]: info: ControllerMpd::getState May 02 13:01:46 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status May 02 13:01:46 volumio volumio[727]: verbose: ControllerMpd::parseState May 02 13:01:46 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 13:01:46 volumio volumio[727]: info: sendMpdCommand status took 3 milliseconds May 02 13:01:46 volumio volumio[727]: info: sendMpdCommand playlistinfo took 2 milliseconds May 02 13:01:46 volumio volumio[727]: verbose: ControllerMpd::parseState May 02 13:01:46 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 13:01:46 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo May 02 13:01:46 volumio volumio[727]: info: ControllerMpd::pushState May 02 13:01:46 volumio volumio[727]: info: CoreCommandRouter::servicePushState May 02 13:01:46 volumio volumio[727]: verbose: In UPNP mode May 02 13:01:46 volumio volumio[727]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tassel","artist":"Cymophane","album":"Dulcet Series spring special collection","uri":"http://m801.music.126.net/20250502132645/a7e8a5238330ef8040034dc6ea644847/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/35834458969/1e0e/0e98/6489/02531610a479eee622cc028a49c29436.mp3?vuutv=CMnnBvWnwMC98s2ec/1aU9H8tAzsfDwoSREZZi145PU1a8fSCAb/PSnZFBSE2Ncvg5ECtA2vI5t2H9yojEiGKaREGGMrWVT2n3gxVfAWPGc=","trackType":""} May 02 13:01:46 volumio volumio[727]: verbose: CURRENT POSITION 0 May 02 13:01:46 volumio volumio[727]: info: CoreStateMachine::syncState stateService play May 02 13:01:46 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus stop May 02 13:01:46 volumio volumio[727]: info: ------------------------------ 16ms May 02 13:01:46 volumio volumio[727]: info: sendMpdCommand playlistinfo took 8 milliseconds May 02 13:01:46 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo May 02 13:01:46 volumio volumio[727]: info: ControllerMpd::pushState May 02 13:01:46 volumio volumio[727]: info: CoreCommandRouter::servicePushState May 02 13:01:46 volumio volumio[727]: verbose: In UPNP mode May 02 13:01:46 volumio volumio[727]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tassel","artist":"Cymophane","album":"Dulcet Series spring special collection","uri":"http://m801.music.126.net/20250502132645/a7e8a5238330ef8040034dc6ea644847/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/35834458969/1e0e/0e98/6489/02531610a479eee622cc028a49c29436.mp3?vuutv=CMnnBvWnwMC98s2ec/1aU9H8tAzsfDwoSREZZi145PU1a8fSCAb/PSnZFBSE2Ncvg5ECtA2vI5t2H9yojEiGKaREGGMrWVT2n3gxVfAWPGc=","trackType":""} May 02 13:01:46 volumio volumio[727]: verbose: CURRENT POSITION 0 May 02 13:01:46 volumio volumio[727]: info: CoreStateMachine::syncState stateService play May 02 13:01:46 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus play May 02 13:01:46 volumio volumio[727]: info: Received an update from plugin. extracting info from payload May 02 13:01:46 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 02 13:01:46 volumio volumio[727]: info: CoreStateMachine::pushState May 02 13:01:46 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 13:01:46 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:46 volumio volumio[727]: info: CoreStateMachine::pushState May 02 13:01:46 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:46 volumio volumio[727]: info: ------------------------------ 38ms May 02 13:01:46 volumio volumio[727]: info: CoreCommandRouter::volumioGetQueue May 02 13:01:46 volumio volumio[727]: info: CoreStateMachine::getQueue May 02 13:01:46 volumio volumio[727]: info: CorePlayQueue::getQueue May 02 13:01:46 volumio volumio[727]: info: CoreCommandRouter::volumioGetQueue May 02 13:01:46 volumio volumio[727]: info: CoreStateMachine::getQueue May 02 13:01:46 volumio volumio[727]: info: CorePlayQueue::getQueue May 02 13:01:47 volumio volumio[727]: info: May 02 13:01:47 volumio volumio[727]: ---------------------------- MPD announces state update: player May 02 13:01:47 volumio volumio[727]: info: ControllerMpd::getState May 02 13:01:47 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status May 02 13:01:47 volumio volumio[727]: info: sendMpdCommand status took 2 milliseconds May 02 13:01:47 volumio volumio[727]: verbose: ControllerMpd::parseState May 02 13:01:47 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 13:01:47 volumio volumio[727]: info: sendMpdCommand playlistinfo took 1 milliseconds May 02 13:01:47 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo May 02 13:01:47 volumio volumio[727]: info: ControllerMpd::pushState May 02 13:01:47 volumio volumio[727]: info: CoreCommandRouter::servicePushState May 02 13:01:47 volumio volumio[727]: info: CoreStateMachine::pushState May 02 13:01:47 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:47 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 02 13:01:47 volumio volumio[727]: 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":"Tassel","artist":"Cymophane","album":"Dulcet Series spring special collection","uri":"http://m801.music.126.net/20250502132645/a7e8a5238330ef8040034dc6ea644847/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/35834458969/1e0e/0e98/6489/02531610a479eee622cc028a49c29436.mp3?vuutv=CMnnBvWnwMC98s2ec/1aU9H8tAzsfDwoSREZZi145PU1a8fSCAb/PSnZFBSE2Ncvg5ECtA2vI5t2H9yojEiGKaREGGMrWVT2n3gxVfAWPGc=","trackType":""} May 02 13:01:47 volumio volumio[727]: verbose: CURRENT POSITION 0 May 02 13:01:47 volumio volumio[727]: info: CoreStateMachine::syncState stateService stop May 02 13:01:47 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus play May 02 13:01:47 volumio volumio[727]: info: CoreStateMachine::play index undefined May 02 13:01:47 volumio volumio[727]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 13:01:47 volumio volumio[727]: info: CoreStateMachine::pushState May 02 13:01:47 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 02 13:01:47 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 13:01:47 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:47 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 02 13:01:47 volumio volumio[727]: info: ------------------------------ 32ms May 02 13:01:47 volumio volumio[727]: info: CoreCommandRouter::volumioGetQueue May 02 13:01:47 volumio volumio[727]: info: CoreStateMachine::getQueue May 02 13:01:47 volumio volumio[727]: info: CorePlayQueue::getQueue May 02 13:01:47 volumio volumio[727]: info: Clearing queue after UPNP request May 02 13:01:48 volumio volumio[727]: info: CoreStateMachine::ClearQueue May 02 13:01:48 volumio volumio[727]: info: CoreStateMachine::stop May 02 13:01:48 volumio volumio[727]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 13:01:48 volumio volumio[727]: info: CorePlayQueue::clearPlayQueue May 02 13:01:48 volumio volumio[727]: info: CorePlayQueue::saveQueue May 02 13:01:48 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:48 volumio volumio[727]: info: CoreCommandRouter::volumioPushQueue May 02 13:01:48 volumio volumio[727]: info: May 02 13:01:48 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:48 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:48 volumio volumio[727]: error: updateQueue error: null May 02 13:01:48 volumio volumio[727]: info: ------------------------------ 2ms May 02 13:01:48 volumio volumio[727]: info: Starting UPNP Playback May 02 13:01:48 volumio volumio[727]: info: Preparing playback through UPNP May 02 13:01:48 volumio volumio[727]: info: CoreCommandRouter::volumioGetState May 02 13:01:48 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 02 13:01:48 volumio volumio[727]: info: CoreStateMachine::setConsumeUpdateService mpd May 02 13:01:48 volumio volumio[727]: info: May 02 13:01:48 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:48 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:48 volumio volumio[727]: info: May 02 13:01:48 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:48 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:48 volumio volumio[727]: info: ------------------------------ 4ms May 02 13:01:48 volumio volumio[727]: info: ------------------------------ 3ms May 02 13:01:48 volumio volumio[727]: info: May 02 13:01:48 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:48 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:48 volumio volumio[727]: info: May 02 13:01:48 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:48 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:48 volumio volumio[727]: info: ------------------------------ 3ms May 02 13:01:48 volumio volumio[727]: info: ------------------------------ 3ms May 02 13:01:48 volumio volumio[727]: info: May 02 13:01:48 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:48 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:48 volumio volumio[727]: info: ------------------------------ 2ms May 02 13:01:48 volumio volumio[727]: info: May 02 13:01:48 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:48 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:48 volumio volumio[727]: info: ------------------------------ 2ms May 02 13:01:48 volumio volumio[727]: info: May 02 13:01:48 volumio volumio[727]: ---------------------------- MPD announces state update: player May 02 13:01:48 volumio volumio[727]: info: ControllerMpd::getState May 02 13:01:48 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status May 02 13:01:48 volumio volumio[727]: info: May 02 13:01:48 volumio volumio[727]: ---------------------------- MPD announces state update: player May 02 13:01:48 volumio volumio[727]: info: sendMpdCommand status took 4 milliseconds May 02 13:01:48 volumio volumio[727]: info: ControllerMpd::getState May 02 13:01:48 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status May 02 13:01:48 volumio volumio[727]: verbose: ControllerMpd::parseState May 02 13:01:48 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 13:01:48 volumio volumio[727]: info: sendMpdCommand status took 7 milliseconds May 02 13:01:48 volumio volumio[727]: info: sendMpdCommand playlistinfo took 6 milliseconds May 02 13:01:48 volumio volumio[727]: verbose: ControllerMpd::parseState May 02 13:01:48 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 13:01:48 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo May 02 13:01:48 volumio volumio[727]: info: ControllerMpd::pushState May 02 13:01:48 volumio volumio[727]: info: CoreCommandRouter::servicePushState May 02 13:01:48 volumio volumio[727]: verbose: In UPNP mode May 02 13:01:48 volumio volumio[727]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"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://m802.music.126.net/20250502132647/5ccc0c7bd51ed31588f1c415eb416dc2/jd-musicrep-ts/9c4a/aebe/7270/8be6391a4a129be0ad9fef58192df3cc.mp3?vuutv=ilulsbKWI1ETsJzH9DLKhoDNj0kPEfwLtNykLFAUL4VhZaSWrv/uv9OTN5x6k7diguWXgp3urH/qDQnbhngvwp3b7dbtqANqshUfumELDJw=","trackType":""} May 02 13:01:48 volumio volumio[727]: verbose: CURRENT POSITION 0 May 02 13:01:48 volumio volumio[727]: info: CoreStateMachine::syncState stateService play May 02 13:01:48 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus stop May 02 13:01:48 volumio volumio[727]: info: ------------------------------ 36ms May 02 13:01:48 volumio volumio[727]: info: sendMpdCommand playlistinfo took 14 milliseconds May 02 13:01:48 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo May 02 13:01:48 volumio volumio[727]: info: ControllerMpd::pushState May 02 13:01:48 volumio volumio[727]: info: CoreCommandRouter::servicePushState May 02 13:01:48 volumio volumio[727]: verbose: In UPNP mode May 02 13:01:48 volumio volumio[727]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"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://m802.music.126.net/20250502132647/5ccc0c7bd51ed31588f1c415eb416dc2/jd-musicrep-ts/9c4a/aebe/7270/8be6391a4a129be0ad9fef58192df3cc.mp3?vuutv=ilulsbKWI1ETsJzH9DLKhoDNj0kPEfwLtNykLFAUL4VhZaSWrv/uv9OTN5x6k7diguWXgp3urH/qDQnbhngvwp3b7dbtqANqshUfumELDJw=","trackType":""} May 02 13:01:48 volumio volumio[727]: verbose: CURRENT POSITION 0 May 02 13:01:48 volumio volumio[727]: info: CoreStateMachine::syncState stateService play May 02 13:01:48 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus play May 02 13:01:48 volumio volumio[727]: info: Received an update from plugin. extracting info from payload May 02 13:01:48 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 02 13:01:48 volumio volumio[727]: info: CoreStateMachine::pushState May 02 13:01:48 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 13:01:48 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:48 volumio volumio[727]: info: CoreStateMachine::pushState May 02 13:01:48 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:48 volumio volumio[727]: info: ------------------------------ 89ms May 02 13:01:48 volumio volumio[727]: info: CoreCommandRouter::volumioGetQueue May 02 13:01:48 volumio volumio[727]: info: CoreStateMachine::getQueue May 02 13:01:48 volumio volumio[727]: info: CorePlayQueue::getQueue May 02 13:01:48 volumio volumio[727]: info: CoreCommandRouter::volumioGetQueue May 02 13:01:48 volumio volumio[727]: info: CoreStateMachine::getQueue May 02 13:01:48 volumio volumio[727]: info: CorePlayQueue::getQueue May 02 13:01:50 volumio volumio[727]: info: May 02 13:01:50 volumio volumio[727]: ---------------------------- MPD announces state update: player May 02 13:01:50 volumio volumio[727]: info: ControllerMpd::getState May 02 13:01:50 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status May 02 13:01:50 volumio volumio[727]: info: sendMpdCommand status took 2 milliseconds May 02 13:01:50 volumio volumio[727]: verbose: ControllerMpd::parseState May 02 13:01:50 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 13:01:50 volumio volumio[727]: info: sendMpdCommand playlistinfo took 1 milliseconds May 02 13:01:50 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo May 02 13:01:50 volumio volumio[727]: info: ControllerMpd::pushState May 02 13:01:50 volumio volumio[727]: info: CoreCommandRouter::servicePushState May 02 13:01:50 volumio volumio[727]: info: CoreStateMachine::pushState May 02 13:01:50 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:50 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 02 13:01:50 volumio volumio[727]: 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":"What's Going On…?","uri":"http://m802.music.126.net/20250502132647/5ccc0c7bd51ed31588f1c415eb416dc2/jd-musicrep-ts/9c4a/aebe/7270/8be6391a4a129be0ad9fef58192df3cc.mp3?vuutv=ilulsbKWI1ETsJzH9DLKhoDNj0kPEfwLtNykLFAUL4VhZaSWrv/uv9OTN5x6k7diguWXgp3urH/qDQnbhngvwp3b7dbtqANqshUfumELDJw=","trackType":""} May 02 13:01:50 volumio volumio[727]: verbose: CURRENT POSITION 0 May 02 13:01:50 volumio volumio[727]: info: CoreStateMachine::syncState stateService stop May 02 13:01:50 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus play May 02 13:01:50 volumio volumio[727]: info: CoreStateMachine::play index undefined May 02 13:01:50 volumio volumio[727]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 13:01:50 volumio volumio[727]: info: CoreStateMachine::pushState May 02 13:01:50 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 02 13:01:50 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 13:01:50 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:50 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 02 13:01:50 volumio volumio[727]: info: ------------------------------ 51ms May 02 13:01:50 volumio volumio[727]: info: CoreCommandRouter::volumioGetQueue May 02 13:01:50 volumio volumio[727]: info: CoreStateMachine::getQueue May 02 13:01:50 volumio volumio[727]: info: CorePlayQueue::getQueue May 02 13:01:50 volumio volumio[727]: info: Clearing queue after UPNP request May 02 13:01:51 volumio volumio[727]: info: CoreStateMachine::ClearQueue May 02 13:01:51 volumio volumio[727]: info: CoreStateMachine::stop May 02 13:01:51 volumio volumio[727]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 13:01:51 volumio volumio[727]: info: CorePlayQueue::clearPlayQueue May 02 13:01:51 volumio volumio[727]: info: CorePlayQueue::saveQueue May 02 13:01:51 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:51 volumio volumio[727]: info: CoreCommandRouter::volumioPushQueue May 02 13:01:51 volumio volumio[727]: info: May 02 13:01:51 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:51 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:51 volumio volumio[727]: error: updateQueue error: null May 02 13:01:51 volumio volumio[727]: info: ------------------------------ 4ms May 02 13:01:51 volumio volumio[727]: info: Starting UPNP Playback May 02 13:01:51 volumio volumio[727]: info: Preparing playback through UPNP May 02 13:01:51 volumio volumio[727]: info: CoreCommandRouter::volumioGetState May 02 13:01:51 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 02 13:01:51 volumio volumio[727]: info: CoreStateMachine::setConsumeUpdateService mpd May 02 13:01:51 volumio volumio[727]: info: May 02 13:01:51 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:51 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:51 volumio volumio[727]: info: May 02 13:01:51 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:51 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:51 volumio volumio[727]: info: ------------------------------ 4ms May 02 13:01:51 volumio volumio[727]: info: ------------------------------ 2ms May 02 13:01:51 volumio volumio[727]: info: May 02 13:01:51 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:51 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:51 volumio volumio[727]: info: ------------------------------ 2ms May 02 13:01:51 volumio volumio[727]: info: May 02 13:01:51 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:51 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:51 volumio volumio[727]: info: ------------------------------ 2ms May 02 13:01:51 volumio volumio[727]: info: May 02 13:01:51 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:51 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:51 volumio volumio[727]: info: ------------------------------ 2ms May 02 13:01:51 volumio volumio[727]: info: May 02 13:01:51 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:51 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:51 volumio volumio[727]: info: ------------------------------ 2ms May 02 13:01:52 volumio volumio[727]: info: May 02 13:01:52 volumio volumio[727]: ---------------------------- MPD announces state update: player May 02 13:01:52 volumio volumio[727]: info: ControllerMpd::getState May 02 13:01:52 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status May 02 13:01:52 volumio volumio[727]: info: May 02 13:01:52 volumio volumio[727]: ---------------------------- MPD announces state update: player May 02 13:01:52 volumio volumio[727]: info: sendMpdCommand status took 29 milliseconds May 02 13:01:52 volumio volumio[727]: info: ControllerMpd::getState May 02 13:01:52 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status May 02 13:01:52 volumio volumio[727]: verbose: ControllerMpd::parseState May 02 13:01:52 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 13:01:52 volumio volumio[727]: info: sendMpdCommand status took 4 milliseconds May 02 13:01:52 volumio volumio[727]: verbose: ControllerMpd::parseState May 02 13:01:52 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 13:01:52 volumio volumio[727]: info: sendMpdCommand playlistinfo took 9 milliseconds May 02 13:01:52 volumio volumio[727]: info: sendMpdCommand playlistinfo took 4 milliseconds May 02 13:01:52 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo May 02 13:01:52 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo May 02 13:01:52 volumio volumio[727]: info: ControllerMpd::pushState May 02 13:01:52 volumio volumio[727]: info: CoreCommandRouter::servicePushState May 02 13:01:52 volumio volumio[727]: verbose: In UPNP mode May 02 13:01:52 volumio volumio[727]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"唯一","artist":"告五人","album":"运气来得若有似无","uri":"http://m802.music.126.net/20250502132650/370e4324c40ce1d3c1e6545dc599c0bc/jd-musicrep-ts/1753/71cb/8710/ab72487e9fb7ad8b510883ede7e562b0.mp3?vuutv=3NpQqqoDlMboSYIxLitHmB0m0vhLa+9tCcGLs9p/+2cCDydzC6hURtgQA3YkIQizug8IQdxNIo5SuNnzI7MKBM7NtE7MNkwNZRuFlZCTjg8=","trackType":""} May 02 13:01:52 volumio volumio[727]: verbose: CURRENT POSITION 0 May 02 13:01:52 volumio volumio[727]: info: CoreStateMachine::syncState stateService play May 02 13:01:52 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus stop May 02 13:01:52 volumio volumio[727]: info: ControllerMpd::pushState May 02 13:01:52 volumio volumio[727]: info: CoreCommandRouter::servicePushState May 02 13:01:52 volumio volumio[727]: verbose: In UPNP mode May 02 13:01:52 volumio volumio[727]: verbose: STATE SERVICE {"status":"play","position":0,"seek":20,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"唯一","artist":"告五人","album":"运气来得若有似无","uri":"http://m802.music.126.net/20250502132650/370e4324c40ce1d3c1e6545dc599c0bc/jd-musicrep-ts/1753/71cb/8710/ab72487e9fb7ad8b510883ede7e562b0.mp3?vuutv=3NpQqqoDlMboSYIxLitHmB0m0vhLa+9tCcGLs9p/+2cCDydzC6hURtgQA3YkIQizug8IQdxNIo5SuNnzI7MKBM7NtE7MNkwNZRuFlZCTjg8=","trackType":""} May 02 13:01:52 volumio volumio[727]: verbose: CURRENT POSITION 0 May 02 13:01:52 volumio volumio[727]: info: CoreStateMachine::syncState stateService play May 02 13:01:52 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus play May 02 13:01:52 volumio volumio[727]: info: Received an update from plugin. extracting info from payload May 02 13:01:52 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 02 13:01:52 volumio volumio[727]: info: CoreStateMachine::pushState May 02 13:01:52 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 13:01:52 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:52 volumio volumio[727]: info: CoreStateMachine::pushState May 02 13:01:52 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:52 volumio volumio[727]: info: ------------------------------ 57ms May 02 13:01:52 volumio volumio[727]: info: ------------------------------ 37ms May 02 13:01:53 volumio ntpd[909]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 02 13:01:53 volumio volumio[727]: info: Clearing queue after UPNP request May 02 13:01:53 volumio volumio[727]: info: CoreStateMachine::ClearQueue May 02 13:01:53 volumio volumio[727]: info: CoreStateMachine::stop May 02 13:01:53 volumio volumio[727]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 13:01:53 volumio volumio[727]: info: CoreStateMachine::stPlaybackTimer May 02 13:01:53 volumio volumio[727]: info: CoreStateMachine::updateTrackBlock May 02 13:01:53 volumio volumio[727]: info: CorePlayQueue::getTrackBlock May 02 13:01:53 volumio volumio[727]: info: CoreStateMachine::pushState May 02 13:01:53 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 02 13:01:53 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 13:01:53 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:53 volumio volumio[727]: info: CoreStateMachine::serviceStop May 02 13:01:53 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 02 13:01:53 volumio volumio[727]: info: ControllerMpd::stop May 02 13:01:53 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand stop May 02 13:01:53 volumio volumio[727]: info: CorePlayQueue::clearPlayQueue May 02 13:01:53 volumio volumio[727]: info: CorePlayQueue::saveQueue May 02 13:01:53 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:53 volumio volumio[727]: info: CoreCommandRouter::volumioPushQueue May 02 13:01:53 volumio volumio[727]: info: sendMpdCommand stop took 95 milliseconds May 02 13:01:53 volumio volumio[727]: info: May 02 13:01:53 volumio volumio[727]: ---------------------------- MPD announces state update: player May 02 13:01:53 volumio volumio[727]: info: ControllerMpd::getState May 02 13:01:53 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status May 02 13:01:53 volumio volumio[727]: info: May 02 13:01:53 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:53 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:53 volumio volumio[727]: info: sendMpdCommand status took 3 milliseconds May 02 13:01:53 volumio volumio[727]: verbose: ControllerMpd::parseState May 02 13:01:53 volumio volumio[727]: info: ControllerMpd::pushState May 02 13:01:53 volumio volumio[727]: info: CoreCommandRouter::servicePushState May 02 13:01:53 volumio volumio[727]: info: CoreStateMachine::pushState May 02 13:01:53 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 02 13:01:53 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:53 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 02 13:01:53 volumio volumio[727]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 02 13:01:53 volumio volumio[727]: verbose: CURRENT POSITION 0 May 02 13:01:53 volumio volumio[727]: info: CoreStateMachine::syncState stateService stop May 02 13:01:53 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus stop May 02 13:01:53 volumio volumio[727]: info: CoreStateMachine::pushState May 02 13:01:53 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 02 13:01:53 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:53 volumio volumio[727]: info: No code May 02 13:01:53 volumio volumio[727]: info: CoreStateMachine::pushState May 02 13:01:53 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 02 13:01:53 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:53 volumio volumio[727]: info: ------------------------------ 61ms May 02 13:01:53 volumio volumio[727]: error: updateQueue error: null May 02 13:01:53 volumio volumio[727]: info: ------------------------------ 66ms May 02 13:01:53 volumio volumio[727]: info: Starting UPNP Playback May 02 13:01:53 volumio volumio[727]: info: Preparing playback through UPNP May 02 13:01:53 volumio volumio[727]: info: CoreCommandRouter::volumioGetState May 02 13:01:53 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 02 13:01:54 volumio volumio[727]: info: CoreStateMachine::setConsumeUpdateService mpd May 02 13:01:54 volumio volumio[727]: info: May 02 13:01:54 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:54 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:54 volumio volumio[727]: info: May 02 13:01:54 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:54 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:54 volumio volumio[727]: info: ------------------------------ 4ms May 02 13:01:54 volumio volumio[727]: info: ------------------------------ 2ms May 02 13:01:54 volumio volumio[727]: info: May 02 13:01:54 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:54 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:54 volumio volumio[727]: info: May 02 13:01:54 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:54 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:54 volumio volumio[727]: info: ------------------------------ 3ms May 02 13:01:54 volumio volumio[727]: info: ------------------------------ 3ms May 02 13:01:54 volumio volumio[727]: info: May 02 13:01:54 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:54 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:54 volumio volumio[727]: info: ------------------------------ 2ms May 02 13:01:54 volumio volumio[727]: info: May 02 13:01:54 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:54 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:54 volumio volumio[727]: info: ------------------------------ 2ms May 02 13:01:54 volumio volumio[727]: info: May 02 13:01:54 volumio volumio[727]: ---------------------------- MPD announces state update: player May 02 13:01:54 volumio volumio[727]: info: ControllerMpd::getState May 02 13:01:54 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status May 02 13:01:54 volumio volumio[727]: info: May 02 13:01:54 volumio volumio[727]: ---------------------------- MPD announces state update: player May 02 13:01:54 volumio volumio[727]: info: sendMpdCommand status took 29 milliseconds May 02 13:01:54 volumio volumio[727]: info: ControllerMpd::getState May 02 13:01:54 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status May 02 13:01:54 volumio volumio[727]: verbose: ControllerMpd::parseState May 02 13:01:54 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 13:01:54 volumio volumio[727]: info: sendMpdCommand status took 2 milliseconds May 02 13:01:54 volumio volumio[727]: info: sendMpdCommand playlistinfo took 3 milliseconds May 02 13:01:54 volumio volumio[727]: verbose: ControllerMpd::parseState May 02 13:01:54 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 13:01:54 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo May 02 13:01:54 volumio volumio[727]: info: ControllerMpd::pushState May 02 13:01:54 volumio volumio[727]: info: CoreCommandRouter::servicePushState May 02 13:01:54 volumio volumio[727]: verbose: In UPNP mode May 02 13:01:54 volumio volumio[727]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":296,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Care of Love","artist":"Robert Bonfiglio","album":"Harmonica Sound of Hong Kong","uri":"http://m701.music.126.net/20250502132653/0894372aa0051a0756a2a9a89f754fd3/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/34046882197/3bf4/0bc8/8526/a76e13a0c9e914e6a7f416434a263b38.mp3?vuutv=pBa720SnAMQulJRT5KKdru6jJY150YX2tmgoHUdEhIYGl3qygyJSPoVsXFKjrF+uRHggQji9bcSu3Xs0q4CnLxCDFEl03dNpH3ARxa8mGSw=","trackType":""} May 02 13:01:54 volumio volumio[727]: verbose: CURRENT POSITION 0 May 02 13:01:54 volumio volumio[727]: info: CoreStateMachine::syncState stateService play May 02 13:01:54 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus stop May 02 13:01:54 volumio volumio[727]: info: ------------------------------ 37ms May 02 13:01:54 volumio volumio[727]: info: sendMpdCommand playlistinfo took 3 milliseconds May 02 13:01:54 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo May 02 13:01:54 volumio volumio[727]: info: ControllerMpd::pushState May 02 13:01:54 volumio volumio[727]: info: CoreCommandRouter::servicePushState May 02 13:01:54 volumio volumio[727]: verbose: In UPNP mode May 02 13:01:54 volumio volumio[727]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1479,"duration":296,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Care of Love","artist":"Robert Bonfiglio","album":"Harmonica Sound of Hong Kong","uri":"http://m701.music.126.net/20250502132653/0894372aa0051a0756a2a9a89f754fd3/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/34046882197/3bf4/0bc8/8526/a76e13a0c9e914e6a7f416434a263b38.mp3?vuutv=pBa720SnAMQulJRT5KKdru6jJY150YX2tmgoHUdEhIYGl3qygyJSPoVsXFKjrF+uRHggQji9bcSu3Xs0q4CnLxCDFEl03dNpH3ARxa8mGSw=","trackType":""} May 02 13:01:54 volumio volumio[727]: verbose: CURRENT POSITION 0 May 02 13:01:54 volumio volumio[727]: info: CoreStateMachine::syncState stateService play May 02 13:01:54 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus play May 02 13:01:54 volumio volumio[727]: info: Received an update from plugin. extracting info from payload May 02 13:01:54 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 02 13:01:54 volumio volumio[727]: info: CoreStateMachine::pushState May 02 13:01:54 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 13:01:54 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:54 volumio volumio[727]: info: CoreStateMachine::pushState May 02 13:01:54 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:54 volumio volumio[727]: info: ------------------------------ 43ms May 02 13:01:54 volumio volumio[727]: info: CoreCommandRouter::volumioGetQueue May 02 13:01:54 volumio volumio[727]: info: CoreStateMachine::getQueue May 02 13:01:54 volumio volumio[727]: info: CorePlayQueue::getQueue May 02 13:01:54 volumio volumio[727]: info: CoreCommandRouter::volumioGetQueue May 02 13:01:54 volumio volumio[727]: info: CoreStateMachine::getQueue May 02 13:01:54 volumio volumio[727]: info: CorePlayQueue::getQueue May 02 13:01:56 volumio volumio[727]: info: May 02 13:01:56 volumio volumio[727]: ---------------------------- MPD announces state update: player May 02 13:01:56 volumio volumio[727]: info: ControllerMpd::getState May 02 13:01:56 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status May 02 13:01:56 volumio volumio[727]: info: sendMpdCommand status took 2 milliseconds May 02 13:01:56 volumio volumio[727]: verbose: ControllerMpd::parseState May 02 13:01:56 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 13:01:56 volumio volumio[727]: info: sendMpdCommand playlistinfo took 1 milliseconds May 02 13:01:56 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo May 02 13:01:56 volumio volumio[727]: info: ControllerMpd::pushState May 02 13:01:56 volumio volumio[727]: info: CoreCommandRouter::servicePushState May 02 13:01:56 volumio volumio[727]: info: CoreStateMachine::pushState May 02 13:01:56 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:56 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 02 13:01:56 volumio volumio[727]: 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":"Care of Love","artist":"Robert Bonfiglio","album":"Harmonica Sound of Hong Kong","uri":"http://m701.music.126.net/20250502132653/0894372aa0051a0756a2a9a89f754fd3/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/34046882197/3bf4/0bc8/8526/a76e13a0c9e914e6a7f416434a263b38.mp3?vuutv=pBa720SnAMQulJRT5KKdru6jJY150YX2tmgoHUdEhIYGl3qygyJSPoVsXFKjrF+uRHggQji9bcSu3Xs0q4CnLxCDFEl03dNpH3ARxa8mGSw=","trackType":""} May 02 13:01:56 volumio volumio[727]: verbose: CURRENT POSITION 0 May 02 13:01:56 volumio volumio[727]: info: CoreStateMachine::syncState stateService stop May 02 13:01:56 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus play May 02 13:01:56 volumio volumio[727]: info: CoreStateMachine::play index undefined May 02 13:01:56 volumio volumio[727]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 13:01:56 volumio volumio[727]: info: CoreStateMachine::pushState May 02 13:01:56 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 02 13:01:56 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 13:01:56 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:56 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 02 13:01:56 volumio volumio[727]: info: ------------------------------ 33ms May 02 13:01:56 volumio volumio[727]: info: CoreCommandRouter::volumioGetQueue May 02 13:01:56 volumio volumio[727]: info: CoreStateMachine::getQueue May 02 13:01:56 volumio volumio[727]: info: CorePlayQueue::getQueue May 02 13:01:56 volumio volumio[727]: info: Clearing queue after UPNP request May 02 13:01:56 volumio volumio[727]: info: CoreStateMachine::ClearQueue May 02 13:01:56 volumio volumio[727]: info: CoreStateMachine::stop May 02 13:01:56 volumio volumio[727]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 13:01:56 volumio volumio[727]: info: CorePlayQueue::clearPlayQueue May 02 13:01:56 volumio volumio[727]: info: CorePlayQueue::saveQueue May 02 13:01:56 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:56 volumio volumio[727]: info: CoreCommandRouter::volumioPushQueue May 02 13:01:56 volumio volumio[727]: info: May 02 13:01:56 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:56 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:56 volumio volumio[727]: error: updateQueue error: null May 02 13:01:56 volumio volumio[727]: info: ------------------------------ 2ms May 02 13:01:56 volumio volumio[727]: info: Starting UPNP Playback May 02 13:01:56 volumio volumio[727]: info: Preparing playback through UPNP May 02 13:01:56 volumio volumio[727]: info: CoreCommandRouter::volumioGetState May 02 13:01:56 volumio volumio[727]: info: CorePlayQueue::getTrack 0 May 02 13:01:56 volumio volumio[727]: info: CoreStateMachine::setConsumeUpdateService mpd May 02 13:01:57 volumio volumio[727]: info: May 02 13:01:57 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:57 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:57 volumio volumio[727]: info: May 02 13:01:57 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:57 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:57 volumio volumio[727]: info: ------------------------------ 3ms May 02 13:01:57 volumio volumio[727]: info: ------------------------------ 2ms May 02 13:01:57 volumio volumio[727]: info: May 02 13:01:57 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:57 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:57 volumio volumio[727]: info: ------------------------------ 2ms May 02 13:01:57 volumio volumio[727]: info: May 02 13:01:57 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:57 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:57 volumio volumio[727]: info: ------------------------------ 7ms May 02 13:01:57 volumio volumio[727]: info: May 02 13:01:57 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:57 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:57 volumio volumio[727]: info: ------------------------------ 4ms May 02 13:01:57 volumio volumio[727]: info: May 02 13:01:57 volumio volumio[727]: ---------------------------- MPD announces system playlist update May 02 13:01:57 volumio volumio[727]: info: Ignoring MPD Status Update May 02 13:01:57 volumio volumio[727]: info: ------------------------------ 4ms May 02 13:01:57 volumio volumio[727]: info: May 02 13:01:57 volumio volumio[727]: ---------------------------- MPD announces state update: player May 02 13:01:57 volumio volumio[727]: info: ControllerMpd::getState May 02 13:01:57 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status May 02 13:01:57 volumio volumio[727]: info: May 02 13:01:57 volumio volumio[727]: ---------------------------- MPD announces state update: player May 02 13:01:57 volumio volumio[727]: info: sendMpdCommand status took 27 milliseconds May 02 13:01:57 volumio volumio[727]: info: ControllerMpd::getState May 02 13:01:57 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status May 02 13:01:57 volumio volumio[727]: verbose: ControllerMpd::parseState May 02 13:01:57 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 13:01:57 volumio volumio[727]: info: sendMpdCommand status took 3 milliseconds May 02 13:01:57 volumio volumio[727]: verbose: ControllerMpd::parseState May 02 13:01:57 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 13:01:57 volumio volumio[727]: info: sendMpdCommand playlistinfo took 6 milliseconds May 02 13:01:57 volumio volumio[727]: info: sendMpdCommand playlistinfo took 1 milliseconds May 02 13:01:57 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo May 02 13:01:57 volumio volumio[727]: verbose: ControllerMpd::parseTrackInfo May 02 13:01:57 volumio volumio[727]: info: ControllerMpd::pushState May 02 13:01:57 volumio volumio[727]: info: CoreCommandRouter::servicePushState May 02 13:01:57 volumio volumio[727]: verbose: In UPNP mode May 02 13:01:57 volumio volumio[727]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"可不可以","artist":"张紫豪","album":"可不可以","uri":"http://m702.music.126.net/20250502132656/c73da12da70d307d501e91fcbc6b6061/jd-musicrep-ts/8761/980f/ee15/50b33c7bb78e5fbe377797c8e4838a80.mp3?vuutv=ugT7+A7n2pSZXTxJtO7ck/Oa11HEIudD8v1ae831PfaTSmBE6EnmgsUNiseuYSSXEqzoQRZuexKDONVSw46xmsI4BefKIMl/m+wpx4KwANE=","trackType":""} May 02 13:01:57 volumio volumio[727]: verbose: CURRENT POSITION 0 May 02 13:01:57 volumio volumio[727]: info: CoreStateMachine::syncState stateService play May 02 13:01:57 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus stop May 02 13:01:57 volumio volumio[727]: info: ControllerMpd::pushState May 02 13:01:57 volumio volumio[727]: info: CoreCommandRouter::servicePushState May 02 13:01:57 volumio volumio[727]: verbose: In UPNP mode May 02 13:01:57 volumio volumio[727]: verbose: STATE SERVICE {"status":"play","position":0,"seek":637,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"可不可以","artist":"张紫豪","album":"可不可以","uri":"http://m702.music.126.net/20250502132656/c73da12da70d307d501e91fcbc6b6061/jd-musicrep-ts/8761/980f/ee15/50b33c7bb78e5fbe377797c8e4838a80.mp3?vuutv=ugT7+A7n2pSZXTxJtO7ck/Oa11HEIudD8v1ae831PfaTSmBE6EnmgsUNiseuYSSXEqzoQRZuexKDONVSw46xmsI4BefKIMl/m+wpx4KwANE=","trackType":""} May 02 13:01:57 volumio volumio[727]: verbose: CURRENT POSITION 0 May 02 13:01:57 volumio volumio[727]: info: CoreStateMachine::syncState stateService play May 02 13:01:57 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus play May 02 13:01:57 volumio volumio[727]: info: Received an update from plugin. extracting info from payload May 02 13:01:57 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 02 13:01:57 volumio volumio[727]: info: CoreStateMachine::pushState May 02 13:01:57 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 13:01:57 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:57 volumio volumio[727]: info: CoreStateMachine::pushState May 02 13:01:57 volumio volumio[727]: info: CoreCommandRouter::volumioPushState May 02 13:01:57 volumio volumio[727]: info: ------------------------------ 57ms May 02 13:01:57 volumio volumio[727]: info: ------------------------------ 50ms May 02 13:02:15 volumio volumio[727]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 02 13:02:15 volumio volumio[727]: Error: connect ETIMEDOUT 199.59.149.239:443 May 02 13:02:15 volumio volumio[727]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 02 13:02:15 volumio volumio[727]: errno: -110, May 02 13:02:15 volumio volumio[727]: code: 'ETIMEDOUT', May 02 13:02:15 volumio volumio[727]: syscall: 'connect', May 02 13:02:15 volumio volumio[727]: address: '199.59.149.239', May 02 13:02:15 volumio volumio[727]: port: 443 May 02 13:02:15 volumio volumio[727]: } May 02 13:02:15 volumio volumio[727]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 02 13:02:15 volumio sudo[2018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-02 13:01 May 02 13:02:15 volumio sudo[2018]: 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="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 04:09:51 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="2fe933ffb50e198f3245fc325fd10b17"