-- Logs begin at Sat 2025-12-06 20:57:36 CST, end at Sat 2025-12-06 21:01:03 CST. -- Dec 06 21:00:02 volumio1 volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 21:00:06 volumio1 volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 21:00:10 volumio1 volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 21:00:14 volumio1 volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 21:00:18 volumio1 volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 21:00:22 volumio1 volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 21:00:26 volumio1 volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 21:00:30 volumio1 volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 21:00:34 volumio1 volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 21:00:38 volumio1 volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 21:00:42 volumio1 volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 21:00:46 volumio1 volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 21:00:50 volumio1 volumio[1053]: info: MPD Database updated - AlbumList cache refreshed Dec 06 21:00:50 volumio1 volumio[1053]: info: Dec 06 21:00:50 volumio1 volumio[1053]: ---------------------------- MPD announces state update: database Dec 06 21:00:50 volumio1 volumio[1053]: verbose: ControllerMpd::sendMpdCommand status Dec 06 21:00:50 volumio1 volumio[1053]: info: Dec 06 21:00:50 volumio1 volumio[1053]: ---------------------------- MPD announces state update: update Dec 06 21:00:50 volumio1 volumio[1053]: info: ControllerMpd::getState Dec 06 21:00:50 volumio1 volumio[1053]: verbose: ControllerMpd::sendMpdCommand status Dec 06 21:00:50 volumio1 volumio[1053]: info: ControllerMpd::getState Dec 06 21:00:50 volumio1 volumio[1053]: verbose: ControllerMpd::sendMpdCommand status Dec 06 21:00:50 volumio1 volumio[1053]: info: MPD Database updated - AlbumList cache refreshed Dec 06 21:00:50 volumio1 volumio[1053]: info: Dec 06 21:00:50 volumio1 volumio[1053]: ---------------------------- MPD announces state update: database Dec 06 21:00:50 volumio1 volumio[1053]: verbose: ControllerMpd::sendMpdCommand status Dec 06 21:00:50 volumio1 volumio[1053]: info: Dec 06 21:00:50 volumio1 volumio[1053]: ---------------------------- MPD announces state update: update Dec 06 21:00:50 volumio1 volumio[1053]: info: ControllerMpd::getState Dec 06 21:00:50 volumio1 volumio[1053]: verbose: ControllerMpd::sendMpdCommand status Dec 06 21:00:50 volumio1 volumio[1053]: info: ControllerMpd::getState Dec 06 21:00:50 volumio1 volumio[1053]: verbose: ControllerMpd::sendMpdCommand status Dec 06 21:00:50 volumio1 volumio[1053]: info: MPD Database updated - AlbumList cache refreshed Dec 06 21:00:50 volumio1 volumio[1053]: info: Dec 06 21:00:50 volumio1 volumio[1053]: ---------------------------- MPD announces state update: database Dec 06 21:00:50 volumio1 volumio[1053]: verbose: ControllerMpd::sendMpdCommand status Dec 06 21:00:50 volumio1 volumio[1053]: info: Dec 06 21:00:50 volumio1 volumio[1053]: ---------------------------- MPD announces state update: update Dec 06 21:00:50 volumio1 volumio[1053]: info: ControllerMpd::getState Dec 06 21:00:50 volumio1 volumio[1053]: verbose: ControllerMpd::sendMpdCommand status Dec 06 21:00:50 volumio1 volumio[1053]: info: ControllerMpd::getState Dec 06 21:00:50 volumio1 volumio[1053]: verbose: ControllerMpd::sendMpdCommand status Dec 06 21:00:50 volumio1 volumio[1053]: info: sendMpdCommand status took 355 milliseconds Dec 06 21:00:50 volumio1 volumio[1053]: info: sendMpdCommand status took 351 milliseconds Dec 06 21:00:50 volumio1 volumio[1053]: info: sendMpdCommand status took 351 milliseconds Dec 06 21:00:50 volumio1 volumio[1053]: verbose: ControllerMpd::parseState Dec 06 21:00:50 volumio1 volumio[1053]: info: Command Router : Notfying DB Updatefalse Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreCommandRouter::Close All Modals sent Dec 06 21:00:50 volumio1 volumio[1053]: verbose: ControllerMpd::parseState Dec 06 21:00:50 volumio1 volumio[1053]: verbose: ControllerMpd::parseState Dec 06 21:00:50 volumio1 volumio[1053]: info: ControllerMpd::pushState Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreCommandRouter::servicePushState Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:50 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:50 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:50 volumio1 volumio[1053]: 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} Dec 06 21:00:50 volumio1 volumio[1053]: verbose: CURRENT POSITION 0 Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreStateMachine::syncState stateService stop Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreStateMachine::syncState currentStatus stop Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:50 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:50 volumio1 volumio[1053]: info: No code Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:50 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:50 volumio1 volumio[1053]: info: ControllerMpd::pushState Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreCommandRouter::servicePushState Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:50 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:50 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:50 volumio1 volumio[1053]: 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} Dec 06 21:00:50 volumio1 volumio[1053]: verbose: CURRENT POSITION 0 Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreStateMachine::syncState stateService stop Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreStateMachine::syncState currentStatus stop Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:50 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:50 volumio1 volumio[1053]: info: No code Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:50 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:50 volumio1 volumio[1053]: info: ------------------------------ 449ms Dec 06 21:00:50 volumio1 volumio[1053]: info: ------------------------------ 449ms Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 21:00:50 volumio1 volumio[1053]: info: sendMpdCommand status took 591 milliseconds Dec 06 21:00:50 volumio1 volumio[1053]: info: sendMpdCommand status took 591 milliseconds Dec 06 21:00:50 volumio1 volumio[1053]: info: sendMpdCommand status took 592 milliseconds Dec 06 21:00:50 volumio1 volumio[1053]: verbose: ControllerMpd::parseState Dec 06 21:00:50 volumio1 volumio[1053]: info: Command Router : Notfying DB Updatefalse Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreCommandRouter::Close All Modals sent Dec 06 21:00:50 volumio1 volumio[1053]: verbose: ControllerMpd::parseState Dec 06 21:00:50 volumio1 volumio[1053]: verbose: ControllerMpd::parseState Dec 06 21:00:50 volumio1 volumio[1053]: info: ControllerMpd::pushState Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreCommandRouter::servicePushState Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:50 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:50 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:50 volumio1 volumio[1053]: 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} Dec 06 21:00:50 volumio1 volumio[1053]: verbose: CURRENT POSITION 0 Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreStateMachine::syncState stateService stop Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreStateMachine::syncState currentStatus stop Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:50 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:50 volumio1 volumio[1053]: info: No code Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:50 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:50 volumio1 volumio[1053]: info: ControllerMpd::pushState Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreCommandRouter::servicePushState Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:50 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:50 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:50 volumio1 volumio[1053]: 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} Dec 06 21:00:50 volumio1 volumio[1053]: verbose: CURRENT POSITION 0 Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreStateMachine::syncState stateService stop Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreStateMachine::syncState currentStatus stop Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:50 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:50 volumio1 volumio[1053]: info: No code Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:50 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:50 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:50 volumio1 volumio[1053]: info: ------------------------------ 671ms Dec 06 21:00:50 volumio1 volumio[1053]: info: ------------------------------ 671ms Dec 06 21:00:51 volumio1 volumio[1053]: info: sendMpdCommand status took 802 milliseconds Dec 06 21:00:51 volumio1 volumio[1053]: info: sendMpdCommand status took 802 milliseconds Dec 06 21:00:51 volumio1 volumio[1053]: info: sendMpdCommand status took 802 milliseconds Dec 06 21:00:51 volumio1 volumio[1053]: verbose: ControllerMpd::parseState Dec 06 21:00:51 volumio1 volumio[1053]: info: Command Router : Notfying DB Updatefalse Dec 06 21:00:51 volumio1 volumio[1053]: info: CoreCommandRouter::Close All Modals sent Dec 06 21:00:51 volumio1 volumio[1053]: verbose: ControllerMpd::parseState Dec 06 21:00:51 volumio1 volumio[1053]: verbose: ControllerMpd::parseState Dec 06 21:00:51 volumio1 volumio[1053]: info: ControllerMpd::pushState Dec 06 21:00:51 volumio1 volumio[1053]: info: CoreCommandRouter::servicePushState Dec 06 21:00:51 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:51 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:51 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:51 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:51 volumio1 volumio[1053]: 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} Dec 06 21:00:51 volumio1 volumio[1053]: verbose: CURRENT POSITION 0 Dec 06 21:00:51 volumio1 volumio[1053]: info: CoreStateMachine::syncState stateService stop Dec 06 21:00:51 volumio1 volumio[1053]: info: CoreStateMachine::syncState currentStatus stop Dec 06 21:00:51 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:51 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:51 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:51 volumio1 volumio[1053]: info: No code Dec 06 21:00:51 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:51 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:51 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:51 volumio1 volumio[1053]: info: ControllerMpd::pushState Dec 06 21:00:51 volumio1 volumio[1053]: info: CoreCommandRouter::servicePushState Dec 06 21:00:51 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:51 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:51 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:51 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:51 volumio1 volumio[1053]: 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} Dec 06 21:00:51 volumio1 volumio[1053]: verbose: CURRENT POSITION 0 Dec 06 21:00:51 volumio1 volumio[1053]: info: CoreStateMachine::syncState stateService stop Dec 06 21:00:51 volumio1 volumio[1053]: info: CoreStateMachine::syncState currentStatus stop Dec 06 21:00:51 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:51 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:51 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:51 volumio1 volumio[1053]: info: No code Dec 06 21:00:51 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:51 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:51 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:51 volumio1 volumio[1053]: info: ------------------------------ 875ms Dec 06 21:00:54 volumio1 volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 21:00:57 volumio1 volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 06 21:00:57 volumio1 volumio[1053]: verbose: ControllerMpd::sendMpdCommand update Dec 06 21:00:57 volumio1 volumio[1053]: verbose: ControllerMpd::sendMpdCommand status Dec 06 21:00:57 volumio1 volumio[1053]: info: Dec 06 21:00:57 volumio1 volumio[1053]: ---------------------------- MPD announces state update: update Dec 06 21:00:57 volumio1 volumio[1053]: info: sendMpdCommand update took 87 milliseconds Dec 06 21:00:57 volumio1 volumio[1053]: info: ControllerMpd::getState Dec 06 21:00:57 volumio1 volumio[1053]: verbose: ControllerMpd::sendMpdCommand status Dec 06 21:00:57 volumio1 volumio[1053]: verbose: ControllerMpd::sendMpdCommand status Dec 06 21:00:57 volumio1 volumio[1053]: info: Dec 06 21:00:57 volumio1 volumio[1053]: ---------------------------- MPD announces state update: update Dec 06 21:00:57 volumio1 volumio[1053]: info: ControllerMpd::getState Dec 06 21:00:57 volumio1 volumio[1053]: verbose: ControllerMpd::sendMpdCommand status Dec 06 21:00:57 volumio1 volumio[1053]: verbose: ControllerMpd::sendMpdCommand status Dec 06 21:00:57 volumio1 volumio[1053]: info: Dec 06 21:00:57 volumio1 volumio[1053]: ---------------------------- MPD announces state update: update Dec 06 21:00:57 volumio1 volumio[1053]: info: ControllerMpd::getState Dec 06 21:00:57 volumio1 volumio[1053]: verbose: ControllerMpd::sendMpdCommand status Dec 06 21:00:57 volumio1 volumio[1053]: info: sendMpdCommand status took 6 milliseconds Dec 06 21:00:57 volumio1 volumio[1053]: info: sendMpdCommand status took 5 milliseconds Dec 06 21:00:57 volumio1 volumio[1053]: info: sendMpdCommand status took 4 milliseconds Dec 06 21:00:57 volumio1 volumio[1053]: info: sendMpdCommand status took 3 milliseconds Dec 06 21:00:57 volumio1 volumio[1053]: verbose: ControllerMpd::parseState Dec 06 21:00:57 volumio1 volumio[1053]: info: Command Router : Notfying DB Updatetrue Dec 06 21:00:57 volumio1 volumio[1053]: info: CoreCommandRouter::Close All Modals sent Dec 06 21:00:57 volumio1 volumio[1053]: verbose: ControllerMpd::parseState Dec 06 21:00:57 volumio1 volumio[1053]: info: Command Router : Notfying DB Updatetrue Dec 06 21:00:57 volumio1 volumio[1053]: info: CoreCommandRouter::Close All Modals sent Dec 06 21:00:57 volumio1 volumio[1053]: verbose: ControllerMpd::parseState Dec 06 21:00:57 volumio1 volumio[1053]: info: ControllerMpd::pushState Dec 06 21:00:57 volumio1 volumio[1053]: info: CoreCommandRouter::servicePushState Dec 06 21:00:57 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:57 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:57 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:57 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:57 volumio1 volumio[1053]: 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} Dec 06 21:00:57 volumio1 volumio[1053]: verbose: CURRENT POSITION 0 Dec 06 21:00:57 volumio1 volumio[1053]: info: CoreStateMachine::syncState stateService stop Dec 06 21:00:57 volumio1 volumio[1053]: info: CoreStateMachine::syncState currentStatus stop Dec 06 21:00:57 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:57 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:57 volumio1 volumio[1053]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 21:00:57 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:57 volumio1 volumio[1053]: info: No code Dec 06 21:00:57 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:57 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:57 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:57 volumio1 volumio[1053]: info: ControllerMpd::pushState Dec 06 21:00:57 volumio1 volumio[1053]: info: CoreCommandRouter::servicePushState Dec 06 21:00:57 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:57 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:57 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:57 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:57 volumio1 volumio[1053]: 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} Dec 06 21:00:57 volumio1 volumio[1053]: verbose: CURRENT POSITION 0 Dec 06 21:00:57 volumio1 volumio[1053]: info: CoreStateMachine::syncState stateService stop Dec 06 21:00:57 volumio1 volumio[1053]: info: CoreStateMachine::syncState currentStatus stop Dec 06 21:00:57 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:57 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:57 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:57 volumio1 volumio[1053]: info: No code Dec 06 21:00:57 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:57 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:57 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:58 volumio1 volumio[1053]: info: ------------------------------ 108ms Dec 06 21:00:58 volumio1 volumio[1053]: info: ------------------------------ 111ms Dec 06 21:00:58 volumio1 volumio[1053]: info: sendMpdCommand status took 111 milliseconds Dec 06 21:00:58 volumio1 volumio[1053]: info: sendMpdCommand status took 110 milliseconds Dec 06 21:00:58 volumio1 volumio[1053]: verbose: ControllerMpd::parseState Dec 06 21:00:58 volumio1 volumio[1053]: info: Command Router : Notfying DB Updatetrue Dec 06 21:00:58 volumio1 volumio[1053]: info: CoreCommandRouter::Close All Modals sent Dec 06 21:00:58 volumio1 volumio[1053]: verbose: ControllerMpd::parseState Dec 06 21:00:58 volumio1 volumio[1053]: info: ControllerMpd::pushState Dec 06 21:00:58 volumio1 volumio[1053]: info: CoreCommandRouter::servicePushState Dec 06 21:00:58 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:58 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:58 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:58 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:58 volumio1 volumio[1053]: 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} Dec 06 21:00:58 volumio1 volumio[1053]: verbose: CURRENT POSITION 0 Dec 06 21:00:58 volumio1 volumio[1053]: info: CoreStateMachine::syncState stateService stop Dec 06 21:00:58 volumio1 volumio[1053]: info: CoreStateMachine::syncState currentStatus stop Dec 06 21:00:58 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:58 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:58 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:58 volumio1 volumio[1053]: info: No code Dec 06 21:00:58 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:00:58 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:00:58 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:00:58 volumio1 volumio[1053]: info: ------------------------------ 180ms Dec 06 21:00:58 volumio1 volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 21:00:58 volumio1 volumio[1053]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 06 21:00:58 volumio1 volumio[1053]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 06 21:00:58 volumio1 volumio-remote-updater[671]: No test mode Dec 06 21:00:58 volumio1 volumio-remote-updater[671]: No alpha test mode Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 06 21:01:02 volumio1 volumio[1053]: verbose: ControllerMpd::sendMpdCommand status Dec 06 21:01:02 volumio1 volumio[1053]: info: Dec 06 21:01:02 volumio1 volumio[1053]: ---------------------------- MPD announces state update: update Dec 06 21:01:02 volumio1 volumio[1053]: info: ControllerMpd::getState Dec 06 21:01:02 volumio1 volumio[1053]: verbose: ControllerMpd::sendMpdCommand status Dec 06 21:01:02 volumio1 volumio[1053]: verbose: ControllerMpd::sendMpdCommand status Dec 06 21:01:02 volumio1 volumio[1053]: info: Dec 06 21:01:02 volumio1 volumio[1053]: ---------------------------- MPD announces state update: update Dec 06 21:01:02 volumio1 volumio[1053]: info: ControllerMpd::getState Dec 06 21:01:02 volumio1 volumio[1053]: verbose: ControllerMpd::sendMpdCommand status Dec 06 21:01:02 volumio1 volumio[1053]: verbose: ControllerMpd::sendMpdCommand status Dec 06 21:01:02 volumio1 volumio[1053]: info: Dec 06 21:01:02 volumio1 volumio[1053]: ---------------------------- MPD announces state update: update Dec 06 21:01:02 volumio1 volumio[1053]: info: ControllerMpd::getState Dec 06 21:01:02 volumio1 volumio[1053]: verbose: ControllerMpd::sendMpdCommand status Dec 06 21:01:02 volumio1 volumio[1053]: info: sendMpdCommand status took 5 milliseconds Dec 06 21:01:02 volumio1 volumio[1053]: info: sendMpdCommand status took 4 milliseconds Dec 06 21:01:02 volumio1 volumio[1053]: info: sendMpdCommand status took 4 milliseconds Dec 06 21:01:02 volumio1 volumio[1053]: info: sendMpdCommand status took 3 milliseconds Dec 06 21:01:02 volumio1 volumio[1053]: info: sendMpdCommand status took 3 milliseconds Dec 06 21:01:02 volumio1 volumio[1053]: info: sendMpdCommand status took 2 milliseconds Dec 06 21:01:02 volumio1 volumio[1053]: verbose: ControllerMpd::parseState Dec 06 21:01:02 volumio1 volumio[1053]: info: Command Router : Notfying DB Updatefalse Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreCommandRouter::Close All Modals sent Dec 06 21:01:02 volumio1 volumio[1053]: verbose: ControllerMpd::parseState Dec 06 21:01:02 volumio1 volumio[1053]: verbose: ControllerMpd::parseState Dec 06 21:01:02 volumio1 volumio[1053]: info: Command Router : Notfying DB Updatefalse Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreCommandRouter::Close All Modals sent Dec 06 21:01:02 volumio1 volumio[1053]: verbose: ControllerMpd::parseState Dec 06 21:01:02 volumio1 volumio[1053]: verbose: ControllerMpd::parseState Dec 06 21:01:02 volumio1 volumio[1053]: info: Command Router : Notfying DB Updatefalse Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreCommandRouter::Close All Modals sent Dec 06 21:01:02 volumio1 volumio[1053]: verbose: ControllerMpd::parseState Dec 06 21:01:02 volumio1 volumio[1053]: info: ControllerMpd::pushState Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreCommandRouter::servicePushState Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:01:02 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:01:02 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:01:02 volumio1 volumio[1053]: 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} Dec 06 21:01:02 volumio1 volumio[1053]: verbose: CURRENT POSITION 0 Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreStateMachine::syncState stateService stop Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreStateMachine::syncState currentStatus stop Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:01:02 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:01:02 volumio1 volumio[1053]: info: No code Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:01:02 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:01:02 volumio1 volumio[1053]: info: ControllerMpd::pushState Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreCommandRouter::servicePushState Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:01:02 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:01:02 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:01:02 volumio1 volumio[1053]: 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} Dec 06 21:01:02 volumio1 volumio[1053]: verbose: CURRENT POSITION 0 Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreStateMachine::syncState stateService stop Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreStateMachine::syncState currentStatus stop Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:01:02 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:01:02 volumio1 volumio[1053]: info: No code Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:01:02 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:01:02 volumio1 volumio[1053]: info: ControllerMpd::pushState Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreCommandRouter::servicePushState Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:01:02 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:01:02 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:01:02 volumio1 volumio[1053]: 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} Dec 06 21:01:02 volumio1 volumio[1053]: verbose: CURRENT POSITION 0 Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreStateMachine::syncState stateService stop Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreStateMachine::syncState currentStatus stop Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:01:02 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:01:02 volumio1 volumio[1053]: info: No code Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreStateMachine::pushState Dec 06 21:01:02 volumio1 volumio[1053]: info: CorePlayQueue::getTrack 0 Dec 06 21:01:02 volumio1 volumio[1053]: info: CoreCommandRouter::volumioPushState Dec 06 21:01:02 volumio1 volumio[1053]: info: ------------------------------ 149ms Dec 06 21:01:02 volumio1 volumio[1053]: info: ------------------------------ 149ms Dec 06 21:01:02 volumio1 volumio[1053]: info: ------------------------------ 149ms Dec 06 21:01:03 volumio1 volumio[1053]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 21:01:03 volumio1 volumio[1053]: Error: connect ETIMEDOUT 31.13.96.192:443 Dec 06 21:01:03 volumio1 volumio[1053]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Dec 06 21:01:03 volumio1 volumio[1053]: errno: -110, Dec 06 21:01:03 volumio1 volumio[1053]: code: 'ETIMEDOUT', Dec 06 21:01:03 volumio1 volumio[1053]: syscall: 'connect', Dec 06 21:01:03 volumio1 volumio[1053]: address: '31.13.96.192', Dec 06 21:01:03 volumio1 volumio[1053]: port: 443 Dec 06 21:01:03 volumio1 volumio[1053]: } Dec 06 21:01:03 volumio1 volumio[1053]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 21:01:03 volumio1 sudo[2111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-06 21:00 Dec 06 21:01:03 volumio1 sudo[2111]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"