-- Logs begin at Thu 2025-05-22 15:26:14 CST, end at Thu 2025-05-22 15:29:16 CST. -- May 22 15:28:01 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 22 15:28:05 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 22 15:28:09 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 22 15:28:13 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 22 15:28:17 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 22 15:28:21 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 22 15:28:25 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 22 15:28:34 volumio volumio[953]: info: May 22 15:28:34 volumio volumio[953]: ---------------------------- MPD announces state update: player May 22 15:28:34 volumio volumio[953]: info: ControllerMpd::getState May 22 15:28:34 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status May 22 15:28:34 volumio volumio[953]: info: May 22 15:28:34 volumio volumio[953]: ---------------------------- MPD announces state update: player May 22 15:28:34 volumio volumio[953]: info: ControllerMpd::getState May 22 15:28:34 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status May 22 15:28:34 volumio volumio[953]: info: May 22 15:28:34 volumio volumio[953]: ---------------------------- MPD announces state update: player May 22 15:28:34 volumio volumio[953]: info: ControllerMpd::getState May 22 15:28:34 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status May 22 15:28:34 volumio volumio[953]: info: sendMpdCommand status took 5 milliseconds May 22 15:28:34 volumio volumio[953]: info: sendMpdCommand status took 3 milliseconds May 22 15:28:34 volumio volumio[953]: info: sendMpdCommand status took 2 milliseconds May 22 15:28:34 volumio volumio[953]: verbose: ControllerMpd::parseState May 22 15:28:34 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 15:28:34 volumio volumio[953]: verbose: ControllerMpd::parseState May 22 15:28:34 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 15:28:34 volumio volumio[953]: verbose: ControllerMpd::parseState May 22 15:28:34 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 15:28:34 volumio volumio[953]: info: sendMpdCommand playlistinfo took 3 milliseconds May 22 15:28:34 volumio volumio[953]: info: sendMpdCommand playlistinfo took 3 milliseconds May 22 15:28:34 volumio volumio[953]: info: sendMpdCommand playlistinfo took 2 milliseconds May 22 15:28:34 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo May 22 15:28:34 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo May 22 15:28:34 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo May 22 15:28:34 volumio volumio[953]: info: ControllerMpd::pushState May 22 15:28:34 volumio volumio[953]: info: CoreCommandRouter::servicePushState May 22 15:28:34 volumio volumio[953]: info: CoreStateMachine::pushState May 22 15:28:34 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 22 15:28:34 volumio volumio[953]: info: CorePlayQueue::getTrack 0 May 22 15:28:34 volumio volumio[953]: 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":"Intro","artist":"Dreamtale","album":"Beyond Reality","uri":"http://m701.music.126.net/20250522155158/194a3c673f67e117734b8d70fa89ddb6/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/14780750749/62c8/d51e/1963/8889f9ab3298fefdd0a464c15c5929e4.mp3?vuutv=wE0M9Ck+BXmelJa2cIBGJ+WdAqwu6CgIreh14SbGtkHG8v1sIDC4T6BgLf7v95rgA0qtFrqShlOZ1aChgOBrAfsS7hiCxWYi5WuxR91hbok=","trackType":""} May 22 15:28:34 volumio volumio[953]: verbose: CURRENT POSITION 0 May 22 15:28:34 volumio volumio[953]: info: CoreStateMachine::syncState stateService stop May 22 15:28:34 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus play May 22 15:28:34 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 22 15:28:34 volumio volumio[953]: info: CoreStateMachine::stPlaybackTimer May 22 15:28:34 volumio volumio[953]: info: ControllerMpd::pushState May 22 15:28:34 volumio volumio[953]: info: CoreCommandRouter::servicePushState May 22 15:28:34 volumio volumio[953]: info: CoreStateMachine::pushState May 22 15:28:34 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 15:28:34 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 22 15:28:34 volumio volumio[953]: info: CorePlayQueue::getTrack 0 May 22 15:28:34 volumio volumio[953]: 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":"Intro","artist":"Dreamtale","album":"Beyond Reality","uri":"http://m701.music.126.net/20250522155158/194a3c673f67e117734b8d70fa89ddb6/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/14780750749/62c8/d51e/1963/8889f9ab3298fefdd0a464c15c5929e4.mp3?vuutv=wE0M9Ck+BXmelJa2cIBGJ+WdAqwu6CgIreh14SbGtkHG8v1sIDC4T6BgLf7v95rgA0qtFrqShlOZ1aChgOBrAfsS7hiCxWYi5WuxR91hbok=","trackType":""} May 22 15:28:34 volumio volumio[953]: verbose: CURRENT POSITION 0 May 22 15:28:34 volumio volumio[953]: info: CoreStateMachine::syncState stateService stop May 22 15:28:34 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus stop May 22 15:28:34 volumio volumio[953]: info: CoreStateMachine::pushState May 22 15:28:34 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 22 15:28:34 volumio volumio[953]: info: No code May 22 15:28:34 volumio volumio[953]: info: CoreStateMachine::pushState May 22 15:28:34 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 22 15:28:34 volumio volumio[953]: info: ControllerMpd::pushState May 22 15:28:34 volumio volumio[953]: info: CoreCommandRouter::servicePushState May 22 15:28:34 volumio volumio[953]: info: CoreStateMachine::pushState May 22 15:28:34 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 22 15:28:34 volumio volumio[953]: info: CorePlayQueue::getTrack 0 May 22 15:28:34 volumio volumio[953]: 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":"Intro","artist":"Dreamtale","album":"Beyond Reality","uri":"http://m701.music.126.net/20250522155158/194a3c673f67e117734b8d70fa89ddb6/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/14780750749/62c8/d51e/1963/8889f9ab3298fefdd0a464c15c5929e4.mp3?vuutv=wE0M9Ck+BXmelJa2cIBGJ+WdAqwu6CgIreh14SbGtkHG8v1sIDC4T6BgLf7v95rgA0qtFrqShlOZ1aChgOBrAfsS7hiCxWYi5WuxR91hbok=","trackType":""} May 22 15:28:34 volumio volumio[953]: verbose: CURRENT POSITION 0 May 22 15:28:34 volumio volumio[953]: info: CoreStateMachine::syncState stateService stop May 22 15:28:34 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus stop May 22 15:28:34 volumio volumio[953]: info: CoreStateMachine::pushState May 22 15:28:34 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 22 15:28:34 volumio volumio[953]: info: No code May 22 15:28:34 volumio volumio[953]: info: CoreStateMachine::pushState May 22 15:28:34 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 22 15:28:34 volumio volumio[953]: info: ------------------------------ 63ms May 22 15:28:34 volumio volumio[953]: info: ------------------------------ 63ms May 22 15:28:34 volumio volumio[953]: info: ------------------------------ 63ms May 22 15:28:34 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 22 15:28:34 volumio volumio[953]: info: CoreStateMachine::getQueue May 22 15:28:34 volumio volumio[953]: info: CorePlayQueue::getQueue May 22 15:28:34 volumio volumio[953]: info: Clearing queue after UPNP request May 22 15:28:35 volumio volumio[953]: info: CoreStateMachine::ClearQueue May 22 15:28:35 volumio volumio[953]: info: CoreStateMachine::stop May 22 15:28:35 volumio volumio[953]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 15:28:35 volumio volumio[953]: info: CorePlayQueue::clearPlayQueue May 22 15:28:35 volumio volumio[953]: info: CorePlayQueue::saveQueue May 22 15:28:35 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 22 15:28:35 volumio volumio[953]: info: CoreCommandRouter::volumioPushQueue May 22 15:28:35 volumio volumio[953]: info: May 22 15:28:35 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 22 15:28:35 volumio volumio[953]: info: Ignoring MPD Status Update May 22 15:28:35 volumio volumio[953]: info: May 22 15:28:35 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 22 15:28:35 volumio volumio[953]: info: Ignoring MPD Status Update May 22 15:28:35 volumio volumio[953]: info: May 22 15:28:35 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 22 15:28:35 volumio volumio[953]: info: Ignoring MPD Status Update May 22 15:28:35 volumio volumio[953]: error: updateQueue error: null May 22 15:28:35 volumio volumio[953]: error: updateQueue error: null May 22 15:28:35 volumio volumio[953]: error: updateQueue error: null May 22 15:28:35 volumio volumio[953]: info: ------------------------------ 4ms May 22 15:28:35 volumio volumio[953]: info: ------------------------------ 3ms May 22 15:28:35 volumio volumio[953]: info: ------------------------------ 3ms May 22 15:28:35 volumio volumio[953]: info: Starting UPNP Playback May 22 15:28:35 volumio volumio[953]: info: Preparing playback through UPNP May 22 15:28:35 volumio volumio[953]: info: CoreCommandRouter::volumioGetState May 22 15:28:35 volumio volumio[953]: info: CorePlayQueue::getTrack 0 May 22 15:28:35 volumio volumio[953]: info: CoreStateMachine::setConsumeUpdateService mpd May 22 15:28:35 volumio volumio[953]: info: May 22 15:28:35 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 22 15:28:35 volumio volumio[953]: info: Ignoring MPD Status Update May 22 15:28:35 volumio volumio[953]: info: May 22 15:28:35 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 22 15:28:35 volumio volumio[953]: info: Ignoring MPD Status Update May 22 15:28:35 volumio volumio[953]: info: May 22 15:28:35 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 22 15:28:35 volumio volumio[953]: info: Ignoring MPD Status Update May 22 15:28:35 volumio volumio[953]: info: ------------------------------ 4ms May 22 15:28:35 volumio volumio[953]: info: ------------------------------ 3ms May 22 15:28:35 volumio volumio[953]: info: ------------------------------ 2ms May 22 15:28:35 volumio volumio[953]: info: May 22 15:28:35 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 22 15:28:35 volumio volumio[953]: info: Ignoring MPD Status Update May 22 15:28:35 volumio volumio[953]: info: May 22 15:28:35 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 22 15:28:35 volumio volumio[953]: info: Ignoring MPD Status Update May 22 15:28:35 volumio volumio[953]: info: May 22 15:28:35 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 22 15:28:35 volumio volumio[953]: info: Ignoring MPD Status Update May 22 15:28:35 volumio volumio[953]: info: ------------------------------ 4ms May 22 15:28:35 volumio volumio[953]: info: ------------------------------ 3ms May 22 15:28:35 volumio volumio[953]: info: ------------------------------ 3ms May 22 15:28:35 volumio volumio[953]: info: May 22 15:28:35 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 22 15:28:35 volumio volumio[953]: info: Ignoring MPD Status Update May 22 15:28:35 volumio volumio[953]: info: May 22 15:28:35 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 22 15:28:35 volumio volumio[953]: info: Ignoring MPD Status Update May 22 15:28:35 volumio volumio[953]: info: May 22 15:28:35 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 22 15:28:35 volumio volumio[953]: info: Ignoring MPD Status Update May 22 15:28:35 volumio volumio[953]: info: ------------------------------ 6ms May 22 15:28:35 volumio volumio[953]: info: ------------------------------ 5ms May 22 15:28:35 volumio volumio[953]: info: ------------------------------ 4ms May 22 15:28:35 volumio volumio[953]: info: May 22 15:28:35 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 22 15:28:35 volumio volumio[953]: info: Ignoring MPD Status Update May 22 15:28:35 volumio volumio[953]: info: May 22 15:28:35 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 22 15:28:35 volumio volumio[953]: info: Ignoring MPD Status Update May 22 15:28:35 volumio volumio[953]: info: May 22 15:28:35 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 22 15:28:35 volumio volumio[953]: info: Ignoring MPD Status Update May 22 15:28:35 volumio volumio[953]: info: ------------------------------ 4ms May 22 15:28:35 volumio volumio[953]: info: ------------------------------ 3ms May 22 15:28:35 volumio volumio[953]: info: ------------------------------ 3ms May 22 15:28:35 volumio volumio[953]: info: May 22 15:28:35 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 22 15:28:35 volumio volumio[953]: info: Ignoring MPD Status Update May 22 15:28:35 volumio volumio[953]: info: May 22 15:28:35 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 22 15:28:35 volumio volumio[953]: info: Ignoring MPD Status Update May 22 15:28:35 volumio volumio[953]: info: May 22 15:28:35 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 22 15:28:35 volumio volumio[953]: info: Ignoring MPD Status Update May 22 15:28:35 volumio volumio[953]: info: ------------------------------ 4ms May 22 15:28:35 volumio volumio[953]: info: ------------------------------ 3ms May 22 15:28:35 volumio volumio[953]: info: ------------------------------ 2ms May 22 15:28:35 volumio volumio[953]: info: May 22 15:28:35 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 22 15:28:35 volumio volumio[953]: info: Ignoring MPD Status Update May 22 15:28:35 volumio volumio[953]: info: May 22 15:28:35 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 22 15:28:35 volumio volumio[953]: info: Ignoring MPD Status Update May 22 15:28:35 volumio volumio[953]: info: May 22 15:28:35 volumio volumio[953]: ---------------------------- MPD announces system playlist update May 22 15:28:35 volumio volumio[953]: info: Ignoring MPD Status Update May 22 15:28:35 volumio volumio[953]: info: ------------------------------ 4ms May 22 15:28:35 volumio volumio[953]: info: ------------------------------ 3ms May 22 15:28:35 volumio volumio[953]: info: ------------------------------ 3ms May 22 15:28:36 volumio volumio[953]: info: May 22 15:28:36 volumio volumio[953]: ---------------------------- MPD announces state update: player May 22 15:28:36 volumio volumio[953]: info: ControllerMpd::getState May 22 15:28:36 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status May 22 15:28:36 volumio volumio[953]: info: May 22 15:28:36 volumio volumio[953]: ---------------------------- MPD announces state update: player May 22 15:28:36 volumio volumio[953]: info: ControllerMpd::getState May 22 15:28:36 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status May 22 15:28:36 volumio volumio[953]: info: May 22 15:28:36 volumio volumio[953]: ---------------------------- MPD announces state update: player May 22 15:28:36 volumio volumio[953]: info: ControllerMpd::getState May 22 15:28:36 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status May 22 15:28:36 volumio volumio[953]: info: May 22 15:28:36 volumio volumio[953]: ---------------------------- MPD announces state update: player May 22 15:28:36 volumio volumio[953]: info: sendMpdCommand status took 5 milliseconds May 22 15:28:36 volumio volumio[953]: info: ControllerMpd::getState May 22 15:28:36 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status May 22 15:28:36 volumio volumio[953]: info: sendMpdCommand status took 5 milliseconds May 22 15:28:36 volumio volumio[953]: info: sendMpdCommand status took 3 milliseconds May 22 15:28:36 volumio volumio[953]: verbose: ControllerMpd::parseState May 22 15:28:36 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 15:28:36 volumio volumio[953]: verbose: ControllerMpd::parseState May 22 15:28:36 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 15:28:36 volumio volumio[953]: verbose: ControllerMpd::parseState May 22 15:28:36 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 15:28:36 volumio volumio[953]: info: May 22 15:28:36 volumio volumio[953]: ---------------------------- MPD announces state update: player May 22 15:28:36 volumio volumio[953]: info: ControllerMpd::getState May 22 15:28:36 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status May 22 15:28:36 volumio volumio[953]: info: May 22 15:28:36 volumio volumio[953]: ---------------------------- MPD announces state update: player May 22 15:28:36 volumio volumio[953]: info: ControllerMpd::getState May 22 15:28:36 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand status May 22 15:28:36 volumio volumio[953]: info: sendMpdCommand status took 8 milliseconds May 22 15:28:36 volumio volumio[953]: info: sendMpdCommand playlistinfo took 7 milliseconds May 22 15:28:36 volumio volumio[953]: info: sendMpdCommand playlistinfo took 6 milliseconds May 22 15:28:36 volumio volumio[953]: verbose: ControllerMpd::parseState May 22 15:28:36 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 15:28:36 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo May 22 15:28:36 volumio volumio[953]: info: ControllerMpd::pushState May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::servicePushState May 22 15:28:36 volumio volumio[953]: verbose: In UPNP mode May 22 15:28:36 volumio volumio[953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":300,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fairy Tale","artist":"Michael Learns To Rock","album":"Everlasting Love Songs","uri":"http://m801.music.126.net/20250522155334/3f0673b13ba34b6f2b72f98ce8b97981/jdymusic/obj/w5zDlMODwrDDiGjCn8Ky/1581666059/f441/b3a9/2860/241b051d49db837d7c9a196b7549bd3a.mp3?vuutv=HJavPeEmjbvW9csEhpek/GfEvMVqrHLHIdTdA2CB70uX46X/BWne05faggggss7APdxzyti1EqpEwmxbXn9qBw5eyIwy13R7LUPBD9dQt44=","trackType":""} May 22 15:28:36 volumio volumio[953]: verbose: CURRENT POSITION 0 May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::syncState stateService play May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus stop May 22 15:28:36 volumio volumio[953]: info: ControllerMpd::pushState May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::servicePushState May 22 15:28:36 volumio volumio[953]: verbose: In UPNP mode May 22 15:28:36 volumio volumio[953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":300,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fairy Tale","artist":"Michael Learns To Rock","album":"Everlasting Love Songs","uri":"http://m801.music.126.net/20250522155334/3f0673b13ba34b6f2b72f98ce8b97981/jdymusic/obj/w5zDlMODwrDDiGjCn8Ky/1581666059/f441/b3a9/2860/241b051d49db837d7c9a196b7549bd3a.mp3?vuutv=HJavPeEmjbvW9csEhpek/GfEvMVqrHLHIdTdA2CB70uX46X/BWne05faggggss7APdxzyti1EqpEwmxbXn9qBw5eyIwy13R7LUPBD9dQt44=","trackType":""} May 22 15:28:36 volumio volumio[953]: verbose: CURRENT POSITION 0 May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::syncState stateService play May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus play May 22 15:28:36 volumio volumio[953]: info: Received an update from plugin. extracting info from payload May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::pushState May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::pushState May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 22 15:28:36 volumio volumio[953]: info: ControllerMpd::pushState May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::servicePushState May 22 15:28:36 volumio volumio[953]: verbose: In UPNP mode May 22 15:28:36 volumio volumio[953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":300,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fairy Tale","artist":"Michael Learns To Rock","album":"Everlasting Love Songs","uri":"http://m801.music.126.net/20250522155334/3f0673b13ba34b6f2b72f98ce8b97981/jdymusic/obj/w5zDlMODwrDDiGjCn8Ky/1581666059/f441/b3a9/2860/241b051d49db837d7c9a196b7549bd3a.mp3?vuutv=HJavPeEmjbvW9csEhpek/GfEvMVqrHLHIdTdA2CB70uX46X/BWne05faggggss7APdxzyti1EqpEwmxbXn9qBw5eyIwy13R7LUPBD9dQt44=","trackType":""} May 22 15:28:36 volumio volumio[953]: verbose: CURRENT POSITION 0 May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::syncState stateService play May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus play May 22 15:28:36 volumio volumio[953]: info: Received an update from plugin. extracting info from payload May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::pushState May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::pushState May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 22 15:28:36 volumio volumio[953]: info: ------------------------------ 41ms May 22 15:28:36 volumio volumio[953]: info: ------------------------------ 58ms May 22 15:28:36 volumio volumio[953]: info: ------------------------------ 56ms May 22 15:28:36 volumio volumio[953]: info: sendMpdCommand status took 48 milliseconds May 22 15:28:36 volumio volumio[953]: info: sendMpdCommand status took 47 milliseconds May 22 15:28:36 volumio volumio[953]: info: sendMpdCommand playlistinfo took 44 milliseconds May 22 15:28:36 volumio volumio[953]: verbose: ControllerMpd::parseState May 22 15:28:36 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 15:28:36 volumio volumio[953]: verbose: ControllerMpd::parseState May 22 15:28:36 volumio volumio[953]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 15:28:36 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo May 22 15:28:36 volumio volumio[953]: info: ControllerMpd::pushState May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::servicePushState May 22 15:28:36 volumio volumio[953]: verbose: In UPNP mode May 22 15:28:36 volumio volumio[953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":300,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fairy Tale","artist":"Michael Learns To Rock","album":"Everlasting Love Songs","uri":"http://m801.music.126.net/20250522155334/3f0673b13ba34b6f2b72f98ce8b97981/jdymusic/obj/w5zDlMODwrDDiGjCn8Ky/1581666059/f441/b3a9/2860/241b051d49db837d7c9a196b7549bd3a.mp3?vuutv=HJavPeEmjbvW9csEhpek/GfEvMVqrHLHIdTdA2CB70uX46X/BWne05faggggss7APdxzyti1EqpEwmxbXn9qBw5eyIwy13R7LUPBD9dQt44=","trackType":""} May 22 15:28:36 volumio volumio[953]: verbose: CURRENT POSITION 0 May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::syncState stateService play May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus play May 22 15:28:36 volumio volumio[953]: info: Received an update from plugin. extracting info from payload May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::pushState May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::pushState May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 22 15:28:36 volumio volumio[953]: info: ------------------------------ 79ms May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::getQueue May 22 15:28:36 volumio volumio[953]: info: CorePlayQueue::getQueue May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::getQueue May 22 15:28:36 volumio volumio[953]: info: CorePlayQueue::getQueue May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::getQueue May 22 15:28:36 volumio volumio[953]: info: CorePlayQueue::getQueue May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::getQueue May 22 15:28:36 volumio volumio[953]: info: CorePlayQueue::getQueue May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::getQueue May 22 15:28:36 volumio volumio[953]: info: CorePlayQueue::getQueue May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::getQueue May 22 15:28:36 volumio volumio[953]: info: CorePlayQueue::getQueue May 22 15:28:36 volumio volumio[953]: info: sendMpdCommand playlistinfo took 34 milliseconds May 22 15:28:36 volumio volumio[953]: info: sendMpdCommand playlistinfo took 33 milliseconds May 22 15:28:36 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo May 22 15:28:36 volumio volumio[953]: verbose: ControllerMpd::parseTrackInfo May 22 15:28:36 volumio volumio[953]: info: ControllerMpd::pushState May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::servicePushState May 22 15:28:36 volumio volumio[953]: verbose: In UPNP mode May 22 15:28:36 volumio volumio[953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":300,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Fairy Tale","artist":"Michael Learns To Rock","album":"Everlasting Love Songs","uri":"http://m801.music.126.net/20250522155334/3f0673b13ba34b6f2b72f98ce8b97981/jdymusic/obj/w5zDlMODwrDDiGjCn8Ky/1581666059/f441/b3a9/2860/241b051d49db837d7c9a196b7549bd3a.mp3?vuutv=HJavPeEmjbvW9csEhpek/GfEvMVqrHLHIdTdA2CB70uX46X/BWne05faggggss7APdxzyti1EqpEwmxbXn9qBw5eyIwy13R7LUPBD9dQt44=","trackType":""} May 22 15:28:36 volumio volumio[953]: verbose: CURRENT POSITION 0 May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::syncState stateService play May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus play May 22 15:28:36 volumio volumio[953]: info: Received an update from plugin. extracting info from payload May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::pushState May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::pushState May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 22 15:28:36 volumio volumio[953]: info: ControllerMpd::pushState May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::servicePushState May 22 15:28:36 volumio volumio[953]: verbose: In UPNP mode May 22 15:28:36 volumio volumio[953]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":300,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Fairy Tale","artist":"Michael Learns To Rock","album":"Everlasting Love Songs","uri":"http://m801.music.126.net/20250522155334/3f0673b13ba34b6f2b72f98ce8b97981/jdymusic/obj/w5zDlMODwrDDiGjCn8Ky/1581666059/f441/b3a9/2860/241b051d49db837d7c9a196b7549bd3a.mp3?vuutv=HJavPeEmjbvW9csEhpek/GfEvMVqrHLHIdTdA2CB70uX46X/BWne05faggggss7APdxzyti1EqpEwmxbXn9qBw5eyIwy13R7LUPBD9dQt44=","trackType":""} May 22 15:28:36 volumio volumio[953]: verbose: CURRENT POSITION 0 May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::syncState stateService play May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::syncState currentStatus play May 22 15:28:36 volumio volumio[953]: info: Received an update from plugin. extracting info from payload May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::pushState May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::pushState May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::volumioPushState May 22 15:28:36 volumio volumio[953]: info: ------------------------------ 122ms May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::getQueue May 22 15:28:36 volumio volumio[953]: info: CorePlayQueue::getQueue May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::getQueue May 22 15:28:36 volumio volumio[953]: info: CorePlayQueue::getQueue May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::getQueue May 22 15:28:36 volumio volumio[953]: info: CorePlayQueue::getQueue May 22 15:28:36 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 22 15:28:36 volumio volumio[953]: info: CoreStateMachine::getQueue May 22 15:28:36 volumio volumio[953]: info: CorePlayQueue::getQueue May 22 15:28:36 volumio volumio[953]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Michael%20Learns%20To%20Rock/Everlasting%20Love%20Songs/38c11526-eca6-4e1d-82d5-b1a9296d390e.jpg' May 22 15:28:36 volumio volumio[953]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Michael%20Learns%20To%20Rock/Everlasting%20Love%20Songs/38c11526-eca6-4e1d-82d5-b1a9296d390e.jpg' May 22 15:28:43 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue May 22 15:28:43 volumio volumio[953]: info: CoreStateMachine::getQueue May 22 15:28:43 volumio volumio[953]: info: CorePlayQueue::getQueue May 22 15:28:45 volumio volumio[953]: info: Preload queue cleared May 22 15:29:00 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , lsInfo May 22 15:29:00 volumio volumio[953]: error: Failed LSINFO: Error: [52@0] {lsinfo} Failed to access /m801.music.126.net/20250522155334/3f0673b13ba34b6f2b72f98ce8b97981/jdymusic/obj/w5zDlMODwrDDiGjCn8Ky/1581666059/f441/b3a9/2860/241b051d49db837d7c9a196b7549bd3a.mp3?vuutv=HJavPeEmjbvW9csEhpek/GfEvMVqrHLHIdTdA2CB70uX46X/BWne05faggggss7APdxzyti1EqpEwmxbXn9qBw5eyIwy13R7LUPBD9dQt44=: No such file or directory May 22 15:29:00 volumio volumio[953]: info: Saving local item 111 May 22 15:29:00 volumio volumio[953]: info: Pushing Favourites {"uri":"http://m801.music.126.net/20250522155334/3f0673b13ba34b6f2b72f98ce8b97981/jdymusic/obj/w5zDlMODwrDDiGjCn8Ky/1581666059/f441/b3a9/2860/241b051d49db837d7c9a196b7549bd3a.mp3?vuutv=HJavPeEmjbvW9csEhpek/GfEvMVqrHLHIdTdA2CB70uX46X/BWne05faggggss7APdxzyti1EqpEwmxbXn9qBw5eyIwy13R7LUPBD9dQt44=","favourite":false} May 22 15:29:00 volumio volumio[953]: info: Listing playlists May 22 15:29:13 volumio volumio[953]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 15:29:13 volumio volumio[953]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 15:29:13 volumio volumio[953]: info: Retrieving Cloud Streaming UI May 22 15:29:13 volumio volumio[953]: info: Getting Tidal Cloud Configuration May 22 15:29:13 volumio volumio[953]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 15:29:13 volumio volumio[953]: info: Getting Qobuz Cloud Configuration May 22 15:29:13 volumio volumio[953]: info: Asking plugin for UI Config May 22 15:29:13 volumio volumio[953]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 15:29:13 volumio volumio[953]: info: Getting Spotify Cloud Configuration May 22 15:29:13 volumio volumio[953]: info: Asking plugin for UI Config May 22 15:29:13 volumio volumio[953]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 15:29:13 volumio volumio[953]: info: Saving Spotify Acccount May 22 15:29:13 volumio volumio[953]: info: Got Tidal Cloud Configuration May 22 15:29:13 volumio volumio[953]: info: Got it May 22 15:29:13 volumio volumio[953]: info: Got it May 22 15:29:13 volumio volumio[953]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 22 15:29:13 volumio volumio[953]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 15:29:13 volumio volumio[953]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 22 15:29:13 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 22 15:29:13 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 22 15:29:13 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 22 15:29:13 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 22 15:29:13 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 15:29:13 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 15:29:13 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 15:29:13 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 15:29:13 volumio volumio[953]: info: CoreCommandRouter::volumioGetBrowseSources May 22 15:29:13 volumio volumio[953]: info: CoreCommandRouter::volumioGetBrowseSources May 22 15:29:13 volumio volumio[953]: info: CoreCommandRouter::volumioGetBrowseSources May 22 15:29:13 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 22 15:29:13 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 22 15:29:15 volumio volumio[953]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 15:29:15 volumio volumio[953]: Error: connect ETIMEDOUT 108.160.169.55:443 May 22 15:29:15 volumio volumio[953]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 22 15:29:15 volumio volumio[953]: errno: -110, May 22 15:29:15 volumio volumio[953]: code: 'ETIMEDOUT', May 22 15:29:15 volumio volumio[953]: syscall: 'connect', May 22 15:29:15 volumio volumio[953]: address: '108.160.169.55', May 22 15:29:15 volumio volumio[953]: port: 443 May 22 15:29:15 volumio volumio[953]: } May 22 15:29:15 volumio volumio[953]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 15:29:16 volumio sudo[2536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 15:28 May 22 15:29:16 volumio sudo[2536]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"