Jan 25 23:50:00 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 23:50:04 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 23:50:05 volumio nmbd[1038]: [2026/01/25 23:50:05.669822, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 25 23:50:05 volumio nmbd[1038]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.10.15 for name WORKGROUP<1d>. Jan 25 23:50:05 volumio nmbd[1038]: This response was from IP 192.168.10.215, reporting an IP address of 192.168.10.215. Jan 25 23:50:08 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 23:50:12 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 23:50:16 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 23:50:22 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 23:50:24 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 23:50:28 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 23:50:32 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 23:50:36 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 23:50:41 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 23:50:44 volumio volumio[5076]: info: MPD Database updated - AlbumList cache refreshed Jan 25 23:50:44 volumio volumio[5076]: info: Jan 25 23:50:44 volumio volumio[5076]: ---------------------------- MPD announces state update: database Jan 25 23:50:44 volumio volumio[5076]: verbose: ControllerMpd::sendMpdCommand status Jan 25 23:50:44 volumio volumio[5076]: info: Jan 25 23:50:44 volumio volumio[5076]: ---------------------------- MPD announces state update: update Jan 25 23:50:44 volumio volumio[5076]: info: ControllerMpd::getState Jan 25 23:50:44 volumio volumio[5076]: verbose: ControllerMpd::sendMpdCommand status Jan 25 23:50:44 volumio volumio[5076]: info: ControllerMpd::getState Jan 25 23:50:44 volumio volumio[5076]: verbose: ControllerMpd::sendMpdCommand status Jan 25 23:50:44 volumio volumio[5076]: info: MPD Database updated - AlbumList cache refreshed Jan 25 23:50:44 volumio volumio[5076]: info: Jan 25 23:50:44 volumio volumio[5076]: ---------------------------- MPD announces state update: database Jan 25 23:50:44 volumio volumio[5076]: verbose: ControllerMpd::sendMpdCommand status Jan 25 23:50:44 volumio volumio[5076]: info: Jan 25 23:50:44 volumio volumio[5076]: ---------------------------- MPD announces state update: update Jan 25 23:50:44 volumio volumio[5076]: info: ControllerMpd::getState Jan 25 23:50:44 volumio volumio[5076]: verbose: ControllerMpd::sendMpdCommand status Jan 25 23:50:44 volumio volumio[5076]: info: ControllerMpd::getState Jan 25 23:50:44 volumio volumio[5076]: verbose: ControllerMpd::sendMpdCommand status Jan 25 23:50:44 volumio volumio[5076]: info: MPD Database updated - AlbumList cache refreshed Jan 25 23:50:44 volumio volumio[5076]: info: Jan 25 23:50:44 volumio volumio[5076]: ---------------------------- MPD announces state update: database Jan 25 23:50:44 volumio volumio[5076]: verbose: ControllerMpd::sendMpdCommand status Jan 25 23:50:44 volumio volumio[5076]: info: Jan 25 23:50:44 volumio volumio[5076]: ---------------------------- MPD announces state update: update Jan 25 23:50:44 volumio volumio[5076]: info: ControllerMpd::getState Jan 25 23:50:44 volumio volumio[5076]: verbose: ControllerMpd::sendMpdCommand status Jan 25 23:50:44 volumio volumio[5076]: info: ControllerMpd::getState Jan 25 23:50:44 volumio volumio[5076]: verbose: ControllerMpd::sendMpdCommand status Jan 25 23:50:44 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 23:50:44 volumio volumio[5076]: info: sendMpdCommand status took 505 milliseconds Jan 25 23:50:44 volumio volumio[5076]: info: sendMpdCommand status took 505 milliseconds Jan 25 23:50:44 volumio volumio[5076]: info: sendMpdCommand status took 505 milliseconds Jan 25 23:50:44 volumio volumio[5076]: verbose: ControllerMpd::parseState Jan 25 23:50:44 volumio volumio[5076]: info: Command Router : Notfying DB Updatefalse Jan 25 23:50:44 volumio volumio[5076]: info: CoreCommandRouter::Close All Modals sent Jan 25 23:50:44 volumio volumio[5076]: verbose: ControllerMpd::parseState Jan 25 23:50:44 volumio volumio[5076]: verbose: ControllerMpd::parseState Jan 25 23:50:44 volumio volumio[5076]: info: ControllerMpd::pushState Jan 25 23:50:44 volumio volumio[5076]: info: CoreCommandRouter::servicePushState Jan 25 23:50:44 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:50:44 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:50:44 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 23:50:44 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:50:44 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:50:44 volumio volumio[5076]: 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} Jan 25 23:50:44 volumio volumio[5076]: verbose: CURRENT POSITION 2 Jan 25 23:50:44 volumio volumio[5076]: info: CoreStateMachine::syncState stateService stop Jan 25 23:50:44 volumio volumio[5076]: info: CoreStateMachine::syncState currentStatus stop Jan 25 23:50:44 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:50:44 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:50:44 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 23:50:44 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:50:44 volumio volumio[5076]: info: No code Jan 25 23:50:44 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:50:44 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:50:44 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:50:44 volumio volumio[5076]: info: ControllerMpd::pushState Jan 25 23:50:44 volumio volumio[5076]: info: CoreCommandRouter::servicePushState Jan 25 23:50:44 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:50:44 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:50:44 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:50:44 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:50:44 volumio volumio[5076]: 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} Jan 25 23:50:44 volumio volumio[5076]: verbose: CURRENT POSITION 2 Jan 25 23:50:44 volumio volumio[5076]: info: CoreStateMachine::syncState stateService stop Jan 25 23:50:44 volumio volumio[5076]: info: CoreStateMachine::syncState currentStatus stop Jan 25 23:50:44 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:50:44 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:50:44 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:50:44 volumio volumio[5076]: info: No code Jan 25 23:50:44 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:50:44 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:50:44 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:50:45 volumio volumio[5076]: info: ------------------------------ 735ms Jan 25 23:50:45 volumio volumio[5076]: info: ------------------------------ 735ms Jan 25 23:50:45 volumio volumio[5076]: info: sendMpdCommand status took 879 milliseconds Jan 25 23:50:45 volumio volumio[5076]: info: sendMpdCommand status took 879 milliseconds Jan 25 23:50:45 volumio volumio[5076]: info: sendMpdCommand status took 878 milliseconds Jan 25 23:50:45 volumio volumio[5076]: verbose: ControllerMpd::parseState Jan 25 23:50:45 volumio volumio[5076]: info: Command Router : Notfying DB Updatefalse Jan 25 23:50:45 volumio volumio[5076]: info: CoreCommandRouter::Close All Modals sent Jan 25 23:50:45 volumio volumio[5076]: verbose: ControllerMpd::parseState Jan 25 23:50:45 volumio volumio[5076]: verbose: ControllerMpd::parseState Jan 25 23:50:45 volumio volumio[5076]: info: ControllerMpd::pushState Jan 25 23:50:45 volumio volumio[5076]: info: CoreCommandRouter::servicePushState Jan 25 23:50:45 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:50:45 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:50:45 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 23:50:45 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:50:45 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:50:45 volumio volumio[5076]: 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} Jan 25 23:50:45 volumio volumio[5076]: verbose: CURRENT POSITION 2 Jan 25 23:50:45 volumio volumio[5076]: info: CoreStateMachine::syncState stateService stop Jan 25 23:50:45 volumio volumio[5076]: info: CoreStateMachine::syncState currentStatus stop Jan 25 23:50:45 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:50:45 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:50:45 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:50:45 volumio volumio[5076]: info: No code Jan 25 23:50:45 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:50:45 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:50:45 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:50:45 volumio volumio[5076]: info: ControllerMpd::pushState Jan 25 23:50:45 volumio volumio[5076]: info: CoreCommandRouter::servicePushState Jan 25 23:50:45 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:50:45 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:50:45 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:50:45 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:50:45 volumio volumio[5076]: 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} Jan 25 23:50:45 volumio volumio[5076]: verbose: CURRENT POSITION 2 Jan 25 23:50:45 volumio volumio[5076]: info: CoreStateMachine::syncState stateService stop Jan 25 23:50:45 volumio volumio[5076]: info: CoreStateMachine::syncState currentStatus stop Jan 25 23:50:45 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:50:45 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:50:45 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:50:45 volumio volumio[5076]: info: No code Jan 25 23:50:45 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:50:45 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:50:45 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:50:45 volumio volumio[5076]: info: ------------------------------ 990ms Jan 25 23:50:45 volumio volumio[5076]: info: ------------------------------ 990ms Jan 25 23:50:45 volumio volumio[5076]: info: sendMpdCommand status took 1150 milliseconds Jan 25 23:50:45 volumio volumio[5076]: info: sendMpdCommand status took 1150 milliseconds Jan 25 23:50:45 volumio volumio[5076]: info: sendMpdCommand status took 1149 milliseconds Jan 25 23:50:45 volumio volumio[5076]: verbose: ControllerMpd::parseState Jan 25 23:50:45 volumio volumio[5076]: info: Command Router : Notfying DB Updatefalse Jan 25 23:50:45 volumio volumio[5076]: info: CoreCommandRouter::Close All Modals sent Jan 25 23:50:45 volumio volumio[5076]: verbose: ControllerMpd::parseState Jan 25 23:50:45 volumio volumio[5076]: verbose: ControllerMpd::parseState Jan 25 23:50:45 volumio volumio[5076]: info: ControllerMpd::pushState Jan 25 23:50:45 volumio volumio[5076]: info: CoreCommandRouter::servicePushState Jan 25 23:50:45 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:50:45 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:50:45 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 23:50:45 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:50:45 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:50:45 volumio volumio[5076]: 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} Jan 25 23:50:45 volumio volumio[5076]: verbose: CURRENT POSITION 2 Jan 25 23:50:45 volumio volumio[5076]: info: CoreStateMachine::syncState stateService stop Jan 25 23:50:45 volumio volumio[5076]: info: CoreStateMachine::syncState currentStatus stop Jan 25 23:50:45 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:50:45 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:50:45 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:50:45 volumio volumio[5076]: info: No code Jan 25 23:50:45 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:50:45 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:50:45 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:50:45 volumio volumio[5076]: info: ControllerMpd::pushState Jan 25 23:50:45 volumio volumio[5076]: info: CoreCommandRouter::servicePushState Jan 25 23:50:45 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:50:45 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:50:45 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:50:45 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:50:45 volumio volumio[5076]: 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} Jan 25 23:50:45 volumio volumio[5076]: verbose: CURRENT POSITION 2 Jan 25 23:50:45 volumio volumio[5076]: info: CoreStateMachine::syncState stateService stop Jan 25 23:50:45 volumio volumio[5076]: info: CoreStateMachine::syncState currentStatus stop Jan 25 23:50:45 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:50:45 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:50:45 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:50:45 volumio volumio[5076]: info: No code Jan 25 23:50:45 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:50:45 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:50:45 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:50:45 volumio volumio[5076]: info: ------------------------------ 1282ms Jan 25 23:50:45 volumio volumio[5076]: info: ------------------------------ 1283ms Jan 25 23:50:48 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 23:50:52 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 23:50:56 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 23:51:00 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 23:51:04 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 23:51:05 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:51:05 volumio volumio[5076]: info: CorePlayQueue::getTrack 3 Jan 25 23:51:05 volumio volumio[5076]: info: Prefetching next song Jan 25 23:51:05 volumio volumio[5076]: info: DOING PREFETCH IN MPD Jan 25 23:51:05 volumio volumio[5076]: verbose: ControllerMpd::sendMpdCommand add "NAS/music/理查德．克来德蔓/音乐.钢琴.理查德黄金经典三部曲全集.1 Piano Trilogy/04 秋日的私语 Come Anour.flac" Jan 25 23:51:05 volumio volumio[5076]: info: Jan 25 23:51:05 volumio volumio[5076]: ---------------------------- MPD announces system playlist update Jan 25 23:51:05 volumio volumio[5076]: info: Ignoring MPD Status Update Jan 25 23:51:05 volumio volumio[5076]: info: sendMpdCommand add "NAS/music/理查德．克来德蔓/音乐.钢琴.理查德黄金经典三部曲全集.1 Piano Trilogy/04 秋日的私语 Come Anour.flac" took 3 milliseconds Jan 25 23:51:05 volumio volumio[5076]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 25 23:51:05 volumio volumio[5076]: info: Jan 25 23:51:05 volumio volumio[5076]: ---------------------------- MPD announces system playlist update Jan 25 23:51:05 volumio volumio[5076]: info: Ignoring MPD Status Update Jan 25 23:51:05 volumio volumio[5076]: info: Jan 25 23:51:05 volumio volumio[5076]: ---------------------------- MPD announces system playlist update Jan 25 23:51:05 volumio volumio[5076]: info: Ignoring MPD Status Update Jan 25 23:51:05 volumio volumio[5076]: info: Jan 25 23:51:05 volumio volumio[5076]: ---------------------------- MPD announces state update: options Jan 25 23:51:05 volumio volumio[5076]: info: ------------------------------ 10ms Jan 25 23:51:05 volumio volumio[5076]: info: sendMpdCommand consume 1 took 7 milliseconds Jan 25 23:51:05 volumio volumio[5076]: info: ControllerMpd::getState Jan 25 23:51:05 volumio volumio[5076]: verbose: ControllerMpd::sendMpdCommand status Jan 25 23:51:05 volumio volumio[5076]: info: ------------------------------ 7ms Jan 25 23:51:05 volumio volumio[5076]: info: ------------------------------ 6ms Jan 25 23:51:05 volumio volumio[5076]: info: Jan 25 23:51:05 volumio volumio[5076]: ---------------------------- MPD announces state update: options Jan 25 23:51:05 volumio volumio[5076]: info: ControllerMpd::getState Jan 25 23:51:05 volumio volumio[5076]: verbose: ControllerMpd::sendMpdCommand status Jan 25 23:51:05 volumio volumio[5076]: info: Jan 25 23:51:05 volumio volumio[5076]: ---------------------------- MPD announces state update: options Jan 25 23:51:05 volumio volumio[5076]: info: ControllerMpd::getState Jan 25 23:51:05 volumio volumio[5076]: verbose: ControllerMpd::sendMpdCommand status Jan 25 23:51:05 volumio volumio[5076]: info: sendMpdCommand status took 6 milliseconds Jan 25 23:51:05 volumio volumio[5076]: info: sendMpdCommand status took 4 milliseconds Jan 25 23:51:05 volumio volumio[5076]: info: sendMpdCommand status took 3 milliseconds Jan 25 23:51:05 volumio volumio[5076]: verbose: ControllerMpd::parseState Jan 25 23:51:05 volumio volumio[5076]: verbose: ControllerMpd::parseState Jan 25 23:51:05 volumio volumio[5076]: verbose: ControllerMpd::parseState Jan 25 23:51:05 volumio volumio[5076]: info: ControllerMpd::pushState Jan 25 23:51:05 volumio volumio[5076]: info: CoreCommandRouter::servicePushState Jan 25 23:51:05 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:51:05 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:51:05 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 23:51:05 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:51:05 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:51:05 volumio volumio[5076]: 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} Jan 25 23:51:05 volumio volumio[5076]: verbose: CURRENT POSITION 2 Jan 25 23:51:05 volumio volumio[5076]: info: CoreStateMachine::syncState stateService stop Jan 25 23:51:05 volumio volumio[5076]: info: CoreStateMachine::syncState currentStatus stop Jan 25 23:51:05 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:51:05 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:51:05 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:51:05 volumio volumio[5076]: info: No code Jan 25 23:51:05 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:51:05 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:51:05 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:51:05 volumio volumio[5076]: info: ControllerMpd::pushState Jan 25 23:51:05 volumio volumio[5076]: info: CoreCommandRouter::servicePushState Jan 25 23:51:05 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:51:05 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:51:05 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:51:05 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:51:05 volumio volumio[5076]: 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} Jan 25 23:51:05 volumio volumio[5076]: verbose: CURRENT POSITION 2 Jan 25 23:51:05 volumio volumio[5076]: info: CoreStateMachine::syncState stateService stop Jan 25 23:51:05 volumio volumio[5076]: info: CoreStateMachine::syncState currentStatus stop Jan 25 23:51:05 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:51:05 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:51:05 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:51:05 volumio volumio[5076]: info: No code Jan 25 23:51:05 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:51:05 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:51:05 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:51:05 volumio volumio[5076]: info: ControllerMpd::pushState Jan 25 23:51:05 volumio volumio[5076]: info: CoreCommandRouter::servicePushState Jan 25 23:51:05 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:51:05 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:51:05 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:51:05 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:51:05 volumio volumio[5076]: 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} Jan 25 23:51:05 volumio volumio[5076]: verbose: CURRENT POSITION 2 Jan 25 23:51:05 volumio volumio[5076]: info: CoreStateMachine::syncState stateService stop Jan 25 23:51:05 volumio volumio[5076]: info: CoreStateMachine::syncState currentStatus stop Jan 25 23:51:05 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:51:05 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:51:05 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:51:05 volumio volumio[5076]: info: No code Jan 25 23:51:05 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:51:05 volumio volumio[5076]: info: CorePlayQueue::getTrack 2 Jan 25 23:51:05 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:51:05 volumio volumio[5076]: info: ------------------------------ 145ms Jan 25 23:51:05 volumio volumio[5076]: info: ------------------------------ 141ms Jan 25 23:51:05 volumio volumio[5076]: info: ------------------------------ 140ms Jan 25 23:51:08 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 23:51:09 volumio volumio[5076]: info: CoreStateMachine::startPlaybackTimer Jan 25 23:51:09 volumio volumio[5076]: info: CorePlayQueue::getTrack 3 Jan 25 23:51:10 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:51:10 volumio volumio[5076]: info: CorePlayQueue::getTrack 3 Jan 25 23:51:10 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 23:51:10 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:51:12 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 23:51:14 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jan 25 23:51:14 volumio volumio[5076]: verbose: ControllerMpd::sendMpdCommand update Jan 25 23:51:14 volumio volumio[5076]: verbose: ControllerMpd::sendMpdCommand status Jan 25 23:51:14 volumio volumio[5076]: info: Jan 25 23:51:14 volumio volumio[5076]: ---------------------------- MPD announces state update: update Jan 25 23:51:14 volumio volumio[5076]: info: sendMpdCommand update took 6 milliseconds Jan 25 23:51:14 volumio volumio[5076]: info: ControllerMpd::getState Jan 25 23:51:14 volumio volumio[5076]: verbose: ControllerMpd::sendMpdCommand status Jan 25 23:51:14 volumio volumio[5076]: info: sendMpdCommand status took 3 milliseconds Jan 25 23:51:14 volumio volumio[5076]: info: sendMpdCommand status took 2 milliseconds Jan 25 23:51:14 volumio volumio[5076]: verbose: ControllerMpd::parseState Jan 25 23:51:14 volumio volumio[5076]: info: Command Router : Notfying DB Updatetrue Jan 25 23:51:14 volumio volumio[5076]: info: CoreCommandRouter::Close All Modals sent Jan 25 23:51:14 volumio volumio[5076]: verbose: ControllerMpd::parseState Jan 25 23:51:14 volumio volumio[5076]: info: ControllerMpd::pushState Jan 25 23:51:14 volumio volumio[5076]: info: CoreCommandRouter::servicePushState Jan 25 23:51:14 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:51:14 volumio volumio[5076]: info: CorePlayQueue::getTrack 3 Jan 25 23:51:14 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 23:51:14 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:51:14 volumio volumio[5076]: info: CorePlayQueue::getTrack 3 Jan 25 23:51:14 volumio volumio[5076]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 25 23:51:14 volumio volumio[5076]: verbose: CURRENT POSITION 3 Jan 25 23:51:14 volumio volumio[5076]: info: CoreStateMachine::syncState stateService stop Jan 25 23:51:14 volumio volumio[5076]: info: CoreStateMachine::syncState currentStatus stop Jan 25 23:51:14 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:51:14 volumio volumio[5076]: info: CorePlayQueue::getTrack 3 Jan 25 23:51:14 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 23:51:14 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:51:14 volumio volumio[5076]: info: No code Jan 25 23:51:14 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:51:14 volumio volumio[5076]: info: CorePlayQueue::getTrack 3 Jan 25 23:51:14 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:51:14 volumio volumio[5076]: info: ------------------------------ 127ms Jan 25 23:51:14 volumio volumio[5076]: verbose: ControllerMpd::sendMpdCommand status Jan 25 23:51:14 volumio volumio[5076]: info: Jan 25 23:51:14 volumio volumio[5076]: ---------------------------- MPD announces state update: update Jan 25 23:51:14 volumio volumio[5076]: info: ControllerMpd::getState Jan 25 23:51:14 volumio volumio[5076]: verbose: ControllerMpd::sendMpdCommand status Jan 25 23:51:14 volumio volumio[5076]: verbose: ControllerMpd::sendMpdCommand status Jan 25 23:51:14 volumio volumio[5076]: info: Jan 25 23:51:14 volumio volumio[5076]: ---------------------------- MPD announces state update: update Jan 25 23:51:14 volumio volumio[5076]: info: ControllerMpd::getState Jan 25 23:51:14 volumio volumio[5076]: verbose: ControllerMpd::sendMpdCommand status Jan 25 23:51:14 volumio volumio[5076]: info: sendMpdCommand status took 5 milliseconds Jan 25 23:51:14 volumio volumio[5076]: info: sendMpdCommand status took 5 milliseconds Jan 25 23:51:14 volumio volumio[5076]: info: sendMpdCommand status took 4 milliseconds Jan 25 23:51:14 volumio volumio[5076]: info: sendMpdCommand status took 2 milliseconds Jan 25 23:51:14 volumio volumio[5076]: verbose: ControllerMpd::parseState Jan 25 23:51:14 volumio volumio[5076]: info: Command Router : Notfying DB Updatetrue Jan 25 23:51:14 volumio volumio[5076]: info: CoreCommandRouter::Close All Modals sent Jan 25 23:51:14 volumio volumio[5076]: verbose: ControllerMpd::parseState Jan 25 23:51:14 volumio volumio[5076]: verbose: ControllerMpd::parseState Jan 25 23:51:14 volumio volumio[5076]: info: Command Router : Notfying DB Updatetrue Jan 25 23:51:14 volumio volumio[5076]: info: CoreCommandRouter::Close All Modals sent Jan 25 23:51:14 volumio volumio[5076]: verbose: ControllerMpd::parseState Jan 25 23:51:14 volumio volumio[5076]: info: ControllerMpd::pushState Jan 25 23:51:14 volumio volumio[5076]: info: CoreCommandRouter::servicePushState Jan 25 23:51:14 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:51:14 volumio volumio[5076]: info: CorePlayQueue::getTrack 3 Jan 25 23:51:14 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:51:14 volumio volumio[5076]: info: CorePlayQueue::getTrack 3 Jan 25 23:51:14 volumio volumio[5076]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 25 23:51:14 volumio volumio[5076]: verbose: CURRENT POSITION 3 Jan 25 23:51:14 volumio volumio[5076]: info: CoreStateMachine::syncState stateService stop Jan 25 23:51:14 volumio volumio[5076]: info: CoreStateMachine::syncState currentStatus stop Jan 25 23:51:14 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:51:14 volumio volumio[5076]: info: CorePlayQueue::getTrack 3 Jan 25 23:51:14 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:51:14 volumio volumio[5076]: info: No code Jan 25 23:51:14 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:51:14 volumio volumio[5076]: info: CorePlayQueue::getTrack 3 Jan 25 23:51:14 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:51:14 volumio volumio[5076]: info: ControllerMpd::pushState Jan 25 23:51:14 volumio volumio[5076]: info: CoreCommandRouter::servicePushState Jan 25 23:51:14 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:51:14 volumio volumio[5076]: info: CorePlayQueue::getTrack 3 Jan 25 23:51:14 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:51:14 volumio volumio[5076]: info: CorePlayQueue::getTrack 3 Jan 25 23:51:14 volumio volumio[5076]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 25 23:51:14 volumio volumio[5076]: verbose: CURRENT POSITION 3 Jan 25 23:51:14 volumio volumio[5076]: info: CoreStateMachine::syncState stateService stop Jan 25 23:51:14 volumio volumio[5076]: info: CoreStateMachine::syncState currentStatus stop Jan 25 23:51:14 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:51:14 volumio volumio[5076]: info: CorePlayQueue::getTrack 3 Jan 25 23:51:14 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:51:14 volumio volumio[5076]: info: No code Jan 25 23:51:14 volumio volumio[5076]: info: CoreStateMachine::pushState Jan 25 23:51:14 volumio volumio[5076]: info: CorePlayQueue::getTrack 3 Jan 25 23:51:14 volumio volumio[5076]: info: CoreCommandRouter::volumioPushState Jan 25 23:51:15 volumio volumio[5076]: info: ------------------------------ 131ms Jan 25 23:51:15 volumio volumio[5076]: info: ------------------------------ 130ms Jan 25 23:51:16 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 23:51:21 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 23:51:24 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Jan 25 23:51:24 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jan 25 23:51:24 volumio systemd[1]: setdatetime-helper.service: Consumed 1.056s CPU time. Jan 25 23:51:26 volumio volumio[5076]: info: Disabling MyMusic plugin upnp Jan 25 23:51:26 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 25 23:51:26 volumio sudo[6041]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jan 25 23:51:26 volumio sudo[6041]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 23:51:26 volumio systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Jan 25 23:51:27 volumio volumio[5076]: info: Enabling MyMusic plugin upnp Jan 25 23:51:27 volumio volumio[5076]: info: Enabling plugin upnp Jan 25 23:51:27 volumio volumio[5076]: info: Loading plugin "upnp"... Jan 25 23:51:27 volumio volumio[5076]: info: [1769356287111] Starting Upmpd Daemon Jan 25 23:51:27 volumio volumio[5076]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 25 23:51:27 volumio volumio[5076]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 23:51:27 volumio volumio[5076]: Error: listen EADDRINUSE: address already in use :::6599 Jan 25 23:51:27 volumio volumio[5076]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Jan 25 23:51:27 volumio volumio[5076]: at listenInCluster (node:net:1920:12) Jan 25 23:51:27 volumio volumio[5076]: at Server.listen (node:net:2008:7) Jan 25 23:51:27 volumio volumio[5076]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Jan 25 23:51:27 volumio volumio[5076]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Jan 25 23:51:27 volumio volumio[5076]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jan 25 23:51:27 volumio volumio[5076]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 25 23:51:27 volumio volumio[5076]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Jan 25 23:51:27 volumio volumio[5076]: code: 'EADDRINUSE', Jan 25 23:51:27 volumio volumio[5076]: errno: -98, Jan 25 23:51:27 volumio volumio[5076]: syscall: 'listen', Jan 25 23:51:27 volumio volumio[5076]: address: '::', Jan 25 23:51:27 volumio volumio[5076]: port: 6599 Jan 25 23:51:27 volumio volumio[5076]: } Jan 25 23:51:27 volumio volumio[5076]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 23:51:27 volumio sudo[6057]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-25 23:50' Jan 25 23:51:27 volumio sudo[6057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"