-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Thu 2025-12-18 11:46:35 CST. -- Dec 18 11:45:04 volumio volumio[4534]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 18 11:45:04 volumio volumio[4534]: info: Retrying Login Due to Network Error Dec 18 11:45:14 volumio volumio[4534]: info: MyVolumio login type: Token Dec 18 11:45:17 volumio volumio[4534]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 11:45:17 volumio volumio[4534]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 11:45:17 volumio volumio[4534]: info: Retrieving Cloud Streaming UI Dec 18 11:45:17 volumio volumio[4534]: info: Getting Tidal Cloud Configuration Dec 18 11:45:17 volumio volumio[4534]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 11:45:17 volumio volumio[4534]: info: Getting Qobuz Cloud Configuration Dec 18 11:45:17 volumio volumio[4534]: info: Asking plugin for UI Config Dec 18 11:45:17 volumio volumio[4534]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 11:45:17 volumio volumio[4534]: info: Getting Spotify Cloud Configuration Dec 18 11:45:17 volumio volumio[4534]: info: Asking plugin for UI Config Dec 18 11:45:17 volumio volumio[4534]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 11:45:17 volumio volumio[4534]: info: Saving Spotify Acccount Dec 18 11:45:17 volumio volumio[4534]: info: Got Tidal Cloud Configuration Dec 18 11:45:17 volumio volumio[4534]: info: Got it Dec 18 11:45:17 volumio volumio[4534]: info: Got it Dec 18 11:45:17 volumio volumio[4534]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 18 11:45:17 volumio volumio[4534]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 11:45:17 volumio volumio[4534]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 18 11:45:17 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 18 11:45:17 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 18 11:45:18 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 18 11:45:18 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 18 11:45:18 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:45:18 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:45:18 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:45:18 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:45:18 volumio volumio[4534]: info: CoreCommandRouter::volumioGetBrowseSources Dec 18 11:45:18 volumio volumio[4534]: info: CoreCommandRouter::volumioGetBrowseSources Dec 18 11:45:18 volumio volumio[4534]: info: CoreCommandRouter::volumioGetBrowseSources Dec 18 11:45:19 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 11:45:19 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 18 11:45:23 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 11:45:23 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 18 11:45:23 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand rescan Dec 18 11:45:23 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:23 volumio volumio[4534]: info: Dec 18 11:45:23 volumio volumio[4534]: ---------------------------- MPD announces state update: update Dec 18 11:45:23 volumio volumio[4534]: info: sendMpdCommand rescan took 6 milliseconds Dec 18 11:45:23 volumio volumio[4534]: info: ControllerMpd::getState Dec 18 11:45:23 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:23 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:23 volumio volumio[4534]: info: Dec 18 11:45:23 volumio volumio[4534]: ---------------------------- MPD announces state update: update Dec 18 11:45:23 volumio volumio[4534]: info: ControllerMpd::getState Dec 18 11:45:23 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:23 volumio volumio[4534]: info: sendMpdCommand status took 10 milliseconds Dec 18 11:45:23 volumio volumio[4534]: info: sendMpdCommand status took 7 milliseconds Dec 18 11:45:23 volumio volumio[4534]: info: sendMpdCommand status took 7 milliseconds Dec 18 11:45:23 volumio volumio[4534]: info: sendMpdCommand status took 5 milliseconds Dec 18 11:45:23 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:23 volumio volumio[4534]: info: Command Router : Notfying DB Updatetrue Dec 18 11:45:23 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:23 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:23 volumio volumio[4534]: info: Command Router : Notfying DB Updatetrue Dec 18 11:45:23 volumio volumio[4534]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:45:23 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:23 volumio volumio[4534]: info: ControllerMpd::pushState Dec 18 11:45:23 volumio volumio[4534]: info: CoreCommandRouter::servicePushState Dec 18 11:45:23 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:23 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:23 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:45:23 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:23 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:23 volumio volumio[4534]: 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 18 11:45:23 volumio volumio[4534]: verbose: CURRENT POSITION 6 Dec 18 11:45:23 volumio volumio[4534]: info: CoreStateMachine::syncState stateService stop Dec 18 11:45:23 volumio volumio[4534]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:45:23 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:23 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:23 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:45:23 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:23 volumio volumio[4534]: info: No code Dec 18 11:45:23 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:23 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:23 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:23 volumio volumio[4534]: info: ControllerMpd::pushState Dec 18 11:45:23 volumio volumio[4534]: info: CoreCommandRouter::servicePushState Dec 18 11:45:23 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:23 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:23 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:23 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:23 volumio volumio[4534]: 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 18 11:45:23 volumio volumio[4534]: verbose: CURRENT POSITION 6 Dec 18 11:45:23 volumio volumio[4534]: info: CoreStateMachine::syncState stateService stop Dec 18 11:45:23 volumio volumio[4534]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:45:23 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:23 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:23 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:23 volumio volumio[4534]: info: No code Dec 18 11:45:23 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:23 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:23 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:23 volumio volumio[4534]: info: ------------------------------ 345ms Dec 18 11:45:23 volumio volumio[4534]: info: ------------------------------ 342ms Dec 18 11:45:23 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:23 volumio volumio[4534]: info: Dec 18 11:45:23 volumio volumio[4534]: ---------------------------- MPD announces state update: update Dec 18 11:45:23 volumio volumio[4534]: info: ControllerMpd::getState Dec 18 11:45:23 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:23 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:23 volumio volumio[4534]: info: Dec 18 11:45:23 volumio volumio[4534]: ---------------------------- MPD announces state update: update Dec 18 11:45:23 volumio volumio[4534]: info: ControllerMpd::getState Dec 18 11:45:23 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:23 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:23 volumio volumio[4534]: info: Dec 18 11:45:23 volumio volumio[4534]: ---------------------------- MPD announces state update: update Dec 18 11:45:23 volumio volumio[4534]: info: ControllerMpd::getState Dec 18 11:45:23 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:23 volumio volumio[4534]: info: sendMpdCommand status took 11 milliseconds Dec 18 11:45:23 volumio volumio[4534]: info: sendMpdCommand status took 10 milliseconds Dec 18 11:45:23 volumio volumio[4534]: info: sendMpdCommand status took 7 milliseconds Dec 18 11:45:23 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:23 volumio volumio[4534]: info: Command Router : Notfying DB Updatefalse Dec 18 11:45:23 volumio volumio[4534]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:45:23 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:23 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:24 volumio volumio[4534]: info: Command Router : Notfying DB Updatefalse Dec 18 11:45:24 volumio volumio[4534]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:45:24 volumio volumio[4534]: info: ControllerMpd::pushState Dec 18 11:45:24 volumio volumio[4534]: info: CoreCommandRouter::servicePushState Dec 18 11:45:24 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:24 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:24 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:45:24 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:24 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:24 volumio volumio[4534]: 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 18 11:45:24 volumio volumio[4534]: verbose: CURRENT POSITION 6 Dec 18 11:45:24 volumio volumio[4534]: info: CoreStateMachine::syncState stateService stop Dec 18 11:45:24 volumio volumio[4534]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:45:24 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:24 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:24 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:45:24 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:24 volumio volumio[4534]: info: No code Dec 18 11:45:24 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:24 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:24 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:24 volumio volumio[4534]: info: ------------------------------ 388ms Dec 18 11:45:24 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:24 volumio volumio[4534]: info: Dec 18 11:45:24 volumio volumio[4534]: ---------------------------- MPD announces state update: update Dec 18 11:45:24 volumio volumio[4534]: info: ControllerMpd::getState Dec 18 11:45:24 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:24 volumio volumio[4534]: info: sendMpdCommand status took 434 milliseconds Dec 18 11:45:24 volumio volumio[4534]: info: sendMpdCommand status took 433 milliseconds Dec 18 11:45:24 volumio volumio[4534]: info: sendMpdCommand status took 431 milliseconds Dec 18 11:45:24 volumio volumio[4534]: info: sendMpdCommand status took 52 milliseconds Dec 18 11:45:24 volumio volumio[4534]: info: sendMpdCommand status took 50 milliseconds Dec 18 11:45:24 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:24 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:24 volumio volumio[4534]: info: Command Router : Notfying DB Updatefalse Dec 18 11:45:24 volumio volumio[4534]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:45:24 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:24 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:24 volumio volumio[4534]: info: Command Router : Notfying DB Updatefalse Dec 18 11:45:24 volumio volumio[4534]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:45:24 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:24 volumio volumio[4534]: info: ControllerMpd::pushState Dec 18 11:45:24 volumio volumio[4534]: info: CoreCommandRouter::servicePushState Dec 18 11:45:24 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:24 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:24 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:45:24 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:24 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:24 volumio volumio[4534]: 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 18 11:45:24 volumio volumio[4534]: verbose: CURRENT POSITION 6 Dec 18 11:45:24 volumio volumio[4534]: info: CoreStateMachine::syncState stateService stop Dec 18 11:45:24 volumio volumio[4534]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:45:24 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:24 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:24 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:24 volumio volumio[4534]: info: No code Dec 18 11:45:24 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:24 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:24 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:24 volumio volumio[4534]: info: ControllerMpd::pushState Dec 18 11:45:24 volumio volumio[4534]: info: CoreCommandRouter::servicePushState Dec 18 11:45:24 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:24 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:24 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:24 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:24 volumio volumio[4534]: 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 18 11:45:24 volumio volumio[4534]: verbose: CURRENT POSITION 6 Dec 18 11:45:24 volumio volumio[4534]: info: CoreStateMachine::syncState stateService stop Dec 18 11:45:24 volumio volumio[4534]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:45:24 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:24 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:24 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:24 volumio volumio[4534]: info: No code Dec 18 11:45:24 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:24 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:24 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:24 volumio volumio[4534]: info: ControllerMpd::pushState Dec 18 11:45:24 volumio volumio[4534]: info: CoreCommandRouter::servicePushState Dec 18 11:45:24 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:24 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:24 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:24 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:24 volumio volumio[4534]: 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 18 11:45:24 volumio volumio[4534]: verbose: CURRENT POSITION 6 Dec 18 11:45:24 volumio volumio[4534]: info: CoreStateMachine::syncState stateService stop Dec 18 11:45:24 volumio volumio[4534]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:45:24 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:24 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:24 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:24 volumio volumio[4534]: info: No code Dec 18 11:45:24 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:24 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:24 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:24 volumio volumio[4534]: info: ------------------------------ 662ms Dec 18 11:45:24 volumio volumio[4534]: info: ------------------------------ 740ms Dec 18 11:45:24 volumio volumio[4534]: info: ------------------------------ 360ms Dec 18 11:45:27 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 11:45:31 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 11:45:31 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 18 11:45:32 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand update Dec 18 11:45:32 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:32 volumio volumio[4534]: info: Dec 18 11:45:32 volumio volumio[4534]: ---------------------------- MPD announces state update: update Dec 18 11:45:32 volumio volumio[4534]: info: sendMpdCommand update took 7 milliseconds Dec 18 11:45:32 volumio volumio[4534]: info: ControllerMpd::getState Dec 18 11:45:32 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:32 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:32 volumio volumio[4534]: info: Dec 18 11:45:32 volumio volumio[4534]: ---------------------------- MPD announces state update: update Dec 18 11:45:32 volumio volumio[4534]: info: ControllerMpd::getState Dec 18 11:45:32 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:32 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:32 volumio volumio[4534]: info: Dec 18 11:45:32 volumio volumio[4534]: ---------------------------- MPD announces state update: update Dec 18 11:45:32 volumio volumio[4534]: info: ControllerMpd::getState Dec 18 11:45:32 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:32 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:32 volumio volumio[4534]: info: Dec 18 11:45:32 volumio volumio[4534]: ---------------------------- MPD announces state update: update Dec 18 11:45:32 volumio volumio[4534]: info: ControllerMpd::getState Dec 18 11:45:32 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:32 volumio volumio[4534]: info: sendMpdCommand status took 15 milliseconds Dec 18 11:45:32 volumio volumio[4534]: info: sendMpdCommand status took 12 milliseconds Dec 18 11:45:32 volumio volumio[4534]: info: sendMpdCommand status took 10 milliseconds Dec 18 11:45:32 volumio volumio[4534]: info: sendMpdCommand status took 9 milliseconds Dec 18 11:45:32 volumio volumio[4534]: info: sendMpdCommand status took 8 milliseconds Dec 18 11:45:32 volumio volumio[4534]: info: sendMpdCommand status took 7 milliseconds Dec 18 11:45:32 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:32 volumio volumio[4534]: info: Command Router : Notfying DB Updatefalse Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:45:32 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:32 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:32 volumio volumio[4534]: info: Command Router : Notfying DB Updatefalse Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:45:32 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:32 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:32 volumio volumio[4534]: info: Command Router : Notfying DB Updatefalse Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:45:32 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:32 volumio volumio[4534]: info: ControllerMpd::pushState Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::servicePushState Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:32 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:32 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:32 volumio volumio[4534]: 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 18 11:45:32 volumio volumio[4534]: verbose: CURRENT POSITION 6 Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::syncState stateService stop Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:32 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:32 volumio volumio[4534]: info: No code Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:32 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:32 volumio volumio[4534]: info: ControllerMpd::pushState Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::servicePushState Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:32 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:32 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:32 volumio volumio[4534]: 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 18 11:45:32 volumio volumio[4534]: verbose: CURRENT POSITION 6 Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::syncState stateService stop Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:32 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:32 volumio volumio[4534]: info: No code Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:32 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:32 volumio volumio[4534]: info: ControllerMpd::pushState Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::servicePushState Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:32 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:32 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:32 volumio volumio[4534]: 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 18 11:45:32 volumio volumio[4534]: verbose: CURRENT POSITION 6 Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::syncState stateService stop Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:32 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:32 volumio volumio[4534]: info: No code Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:32 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:32 volumio volumio[4534]: info: ------------------------------ 343ms Dec 18 11:45:32 volumio volumio[4534]: info: ------------------------------ 339ms Dec 18 11:45:32 volumio volumio[4534]: info: ------------------------------ 338ms Dec 18 11:45:32 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:32 volumio volumio[4534]: info: Dec 18 11:45:32 volumio volumio[4534]: ---------------------------- MPD announces state update: update Dec 18 11:45:32 volumio volumio[4534]: info: ControllerMpd::getState Dec 18 11:45:32 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:32 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:32 volumio volumio[4534]: info: Dec 18 11:45:32 volumio volumio[4534]: ---------------------------- MPD announces state update: update Dec 18 11:45:32 volumio volumio[4534]: info: ControllerMpd::getState Dec 18 11:45:32 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:32 volumio volumio[4534]: info: sendMpdCommand status took 346 milliseconds Dec 18 11:45:32 volumio volumio[4534]: info: sendMpdCommand status took 345 milliseconds Dec 18 11:45:32 volumio volumio[4534]: info: sendMpdCommand status took 12 milliseconds Dec 18 11:45:32 volumio volumio[4534]: info: sendMpdCommand status took 10 milliseconds Dec 18 11:45:32 volumio volumio[4534]: info: sendMpdCommand status took 9 milliseconds Dec 18 11:45:32 volumio volumio[4534]: info: sendMpdCommand status took 8 milliseconds Dec 18 11:45:32 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:32 volumio volumio[4534]: info: Command Router : Notfying DB Updatefalse Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:45:32 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:32 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:32 volumio volumio[4534]: info: Command Router : Notfying DB Updatefalse Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:45:32 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:32 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:32 volumio volumio[4534]: info: Command Router : Notfying DB Updatefalse Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:45:32 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:32 volumio volumio[4534]: info: ControllerMpd::pushState Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::servicePushState Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:32 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:32 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:32 volumio volumio[4534]: 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 18 11:45:32 volumio volumio[4534]: verbose: CURRENT POSITION 6 Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::syncState stateService stop Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:32 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:32 volumio volumio[4534]: info: No code Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:32 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:32 volumio volumio[4534]: info: ControllerMpd::pushState Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::servicePushState Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:32 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:32 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:32 volumio volumio[4534]: 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 18 11:45:32 volumio volumio[4534]: verbose: CURRENT POSITION 6 Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::syncState stateService stop Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:32 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:32 volumio volumio[4534]: info: No code Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:32 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:32 volumio volumio[4534]: info: ControllerMpd::pushState Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::servicePushState Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:32 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:32 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:32 volumio volumio[4534]: 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 18 11:45:32 volumio volumio[4534]: verbose: CURRENT POSITION 6 Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::syncState stateService stop Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:32 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:32 volumio volumio[4534]: info: No code Dec 18 11:45:32 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:32 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:32 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:32 volumio volumio[4534]: info: ------------------------------ 635ms Dec 18 11:45:32 volumio volumio[4534]: info: ------------------------------ 299ms Dec 18 11:45:32 volumio volumio[4534]: info: ------------------------------ 297ms Dec 18 11:45:35 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 18 11:45:38 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand rescan Dec 18 11:45:38 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:38 volumio volumio[4534]: info: Dec 18 11:45:38 volumio volumio[4534]: ---------------------------- MPD announces state update: update Dec 18 11:45:38 volumio volumio[4534]: info: sendMpdCommand rescan took 10 milliseconds Dec 18 11:45:38 volumio volumio[4534]: info: ControllerMpd::getState Dec 18 11:45:38 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:38 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:38 volumio volumio[4534]: info: Dec 18 11:45:38 volumio volumio[4534]: ---------------------------- MPD announces state update: update Dec 18 11:45:38 volumio volumio[4534]: info: ControllerMpd::getState Dec 18 11:45:38 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:38 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:38 volumio volumio[4534]: info: Dec 18 11:45:38 volumio volumio[4534]: ---------------------------- MPD announces state update: update Dec 18 11:45:38 volumio volumio[4534]: info: ControllerMpd::getState Dec 18 11:45:38 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:38 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:38 volumio volumio[4534]: info: Dec 18 11:45:38 volumio volumio[4534]: ---------------------------- MPD announces state update: update Dec 18 11:45:38 volumio volumio[4534]: info: sendMpdCommand status took 15 milliseconds Dec 18 11:45:38 volumio volumio[4534]: info: ControllerMpd::getState Dec 18 11:45:38 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:38 volumio volumio[4534]: info: sendMpdCommand status took 13 milliseconds Dec 18 11:45:38 volumio volumio[4534]: info: sendMpdCommand status took 12 milliseconds Dec 18 11:45:38 volumio volumio[4534]: info: sendMpdCommand status took 9 milliseconds Dec 18 11:45:38 volumio volumio[4534]: info: sendMpdCommand status took 8 milliseconds Dec 18 11:45:38 volumio volumio[4534]: info: sendMpdCommand status took 7 milliseconds Dec 18 11:45:38 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:38 volumio volumio[4534]: info: Command Router : Notfying DB Updatetrue Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:45:38 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:38 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:38 volumio volumio[4534]: info: Command Router : Notfying DB Updatefalse Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:45:38 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:38 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:38 volumio volumio[4534]: info: Command Router : Notfying DB Updatefalse Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:45:38 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:38 volumio volumio[4534]: info: ControllerMpd::pushState Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::servicePushState Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:38 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:38 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:38 volumio volumio[4534]: 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 18 11:45:38 volumio volumio[4534]: verbose: CURRENT POSITION 6 Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::syncState stateService stop Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:38 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:38 volumio volumio[4534]: info: No code Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:38 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:38 volumio volumio[4534]: info: ControllerMpd::pushState Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::servicePushState Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:38 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:38 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:38 volumio volumio[4534]: 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 18 11:45:38 volumio volumio[4534]: verbose: CURRENT POSITION 6 Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::syncState stateService stop Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:38 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:38 volumio volumio[4534]: info: No code Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:38 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:38 volumio volumio[4534]: info: ControllerMpd::pushState Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::servicePushState Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:38 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:38 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:38 volumio volumio[4534]: 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 18 11:45:38 volumio volumio[4534]: verbose: CURRENT POSITION 6 Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::syncState stateService stop Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:38 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:38 volumio volumio[4534]: info: No code Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:38 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:38 volumio volumio[4534]: info: ------------------------------ 342ms Dec 18 11:45:38 volumio volumio[4534]: info: ------------------------------ 337ms Dec 18 11:45:38 volumio volumio[4534]: info: ------------------------------ 334ms Dec 18 11:45:38 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:38 volumio volumio[4534]: info: Dec 18 11:45:38 volumio volumio[4534]: ---------------------------- MPD announces state update: update Dec 18 11:45:38 volumio volumio[4534]: info: ControllerMpd::getState Dec 18 11:45:38 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:38 volumio volumio[4534]: info: Dec 18 11:45:38 volumio volumio[4534]: ---------------------------- MPD announces state update: update Dec 18 11:45:38 volumio volumio[4534]: info: ControllerMpd::getState Dec 18 11:45:38 volumio volumio[4534]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:45:38 volumio volumio[4534]: info: sendMpdCommand status took 340 milliseconds Dec 18 11:45:38 volumio volumio[4534]: info: sendMpdCommand status took 337 milliseconds Dec 18 11:45:38 volumio volumio[4534]: info: sendMpdCommand status took 8 milliseconds Dec 18 11:45:38 volumio volumio[4534]: info: sendMpdCommand status took 7 milliseconds Dec 18 11:45:38 volumio volumio[4534]: info: sendMpdCommand status took 5 milliseconds Dec 18 11:45:38 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:38 volumio volumio[4534]: info: Command Router : Notfying DB Updatefalse Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:45:38 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:38 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:38 volumio volumio[4534]: info: Command Router : Notfying DB Updatefalse Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:45:38 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:38 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:38 volumio volumio[4534]: info: Command Router : Notfying DB Updatefalse Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:45:38 volumio volumio[4534]: verbose: ControllerMpd::parseState Dec 18 11:45:38 volumio volumio[4534]: info: ControllerMpd::pushState Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::servicePushState Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:38 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:38 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:38 volumio volumio[4534]: 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 18 11:45:38 volumio volumio[4534]: verbose: CURRENT POSITION 6 Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::syncState stateService stop Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:38 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:38 volumio volumio[4534]: info: No code Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:38 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:38 volumio volumio[4534]: info: ControllerMpd::pushState Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::servicePushState Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:38 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:38 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:38 volumio volumio[4534]: 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 18 11:45:38 volumio volumio[4534]: verbose: CURRENT POSITION 6 Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::syncState stateService stop Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:38 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:38 volumio volumio[4534]: info: No code Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:38 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:38 volumio volumio[4534]: info: ControllerMpd::pushState Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::servicePushState Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:38 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:38 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:38 volumio volumio[4534]: 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 18 11:45:38 volumio volumio[4534]: verbose: CURRENT POSITION 6 Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::syncState stateService stop Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:38 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:38 volumio volumio[4534]: info: No code Dec 18 11:45:38 volumio volumio[4534]: info: CoreStateMachine::pushState Dec 18 11:45:38 volumio volumio[4534]: info: CorePlayQueue::getTrack 6 Dec 18 11:45:38 volumio volumio[4534]: info: CoreCommandRouter::volumioPushState Dec 18 11:45:38 volumio volumio[4534]: info: ------------------------------ 635ms Dec 18 11:45:38 volumio volumio[4534]: info: ------------------------------ 303ms Dec 18 11:45:38 volumio volumio[4534]: info: ------------------------------ 302ms Dec 18 11:45:39 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 11:45:43 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 11:45:44 volumio volumio[4534]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 18 11:45:44 volumio volumio[4534]: info: Retrying Login Due to Network Error Dec 18 11:45:47 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 11:45:54 volumio volumio[4534]: info: MyVolumio login type: Token Dec 18 11:45:56 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 18 11:45:56 volumio volumio[4534]: info: CURURI: music-library Dec 18 11:45:56 volumio volumio[4534]: info: Preload queue cleared Dec 18 11:45:58 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 18 11:45:58 volumio volumio[4534]: info: CURURI: music-library/USB Dec 18 11:45:58 volumio volumio[4534]: info: Preload queue cleared Dec 18 11:46:00 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 18 11:46:00 volumio volumio[4534]: info: CURURI: music-library/USB/mp3 Dec 18 11:46:00 volumio volumio[4534]: error: Failed LSINFO: null Dec 18 11:46:00 volumio volumio[4534]: info: Preload queue cleared Dec 18 11:46:04 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 18 11:46:04 volumio volumio[4534]: info: CURURI: music-library/USB/ϵͳ Dec 18 11:46:04 volumio volumio[4534]: error: Failed LSINFO: null Dec 18 11:46:04 volumio volumio[4534]: info: Preload queue cleared Dec 18 11:46:06 volumio volumio[4534]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 18 11:46:06 volumio volumio[4534]: info: CURURI: music-library/USB/B2D4EB95D4EB5A5D Dec 18 11:46:06 volumio volumio[4534]: error: Failed LSINFO: null Dec 18 11:46:06 volumio volumio[4534]: info: Preload queue cleared Dec 18 11:46:13 volumio kernel: usb 1-1.4: USB disconnect, device number 4 Dec 18 11:46:13 volumio volumio[4534]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-partuuid/3ffc6d5b-01 /dev/disk/by-path/platform-3f980000.usb-usb-0:1.4:1.0-scsi-0:0:0:0-part1 /dev/disk/by-id/usb-General_UDisk-0:0-part1 /dev/disk/by-label/系统 /dev/disk/by-uuid/F0472EF4DC49C2F2","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"26","ID_BUS":"usb","ID_FS_LABEL":"系统","ID_FS_LABEL_ENC":"系统","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"F0472EF4DC49C2F2","ID_FS_UUID_ENC":"F0472EF4DC49C2F2","ID_INSTANCE":"0:0","ID_MODEL":"UDisk","ID_MODEL_ENC":"UDisk\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"1234","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"62912512","ID_PART_ENTRY_TYPE":"0x7","ID_PART_ENTRY_UUID":"3ffc6d5b-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"3ffc6d5b","ID_PATH":"platform-3f980000.usb-usb-0:1.4:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_4_1_0-scsi-0_0_0_0","ID_REVISION":"5.00","ID_SERIAL":"General_UDisk-0:0","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"General","ID_VENDOR_ENC":"General\\x20","ID_VENDOR_ID":"abcd","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2034","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"4709027"} Dec 18 11:46:24 volumio volumio[4534]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 18 11:46:24 volumio volumio[4534]: info: Retrying Login Due to Network Error Dec 18 11:46:30 volumio kernel: usb 1-1.3: new high-speed USB device number 6 using dwc_otg Dec 18 11:46:30 volumio kernel: usb 1-1.3: New USB device found, idVendor=abcd, idProduct=1234, bcdDevice= 1.00 Dec 18 11:46:30 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 18 11:46:30 volumio kernel: usb 1-1.3: Product: UDisk Dec 18 11:46:30 volumio kernel: usb 1-1.3: Manufacturer: General Dec 18 11:46:30 volumio kernel: usb 1-1.3: SerialNumber: Љ Dec 18 11:46:30 volumio kernel: usb-storage 1-1.3:1.0: USB Mass Storage device detected Dec 18 11:46:30 volumio kernel: scsi host1: usb-storage 1-1.3:1.0 Dec 18 11:46:31 volumio kernel: scsi 1:0:0:0: Direct-Access General UDisk 5.00 PQ: 0 ANSI: 2 Dec 18 11:46:31 volumio kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0 Dec 18 11:46:31 volumio kernel: sd 1:0:0:0: [sdb] 62914560 512-byte logical blocks: (32.2 GB/30.0 GiB) Dec 18 11:46:31 volumio kernel: sd 1:0:0:0: [sdb] Write Protect is off Dec 18 11:46:31 volumio kernel: sd 1:0:0:0: [sdb] Mode Sense: 0b 00 00 08 Dec 18 11:46:31 volumio kernel: sd 1:0:0:0: [sdb] No Caching mode page found Dec 18 11:46:31 volumio kernel: sd 1:0:0:0: [sdb] Assuming drive cache: write through Dec 18 11:46:31 volumio kernel: sdb: sdb1 Dec 18 11:46:31 volumio kernel: sd 1:0:0:0: [sdb] Attached SCSI removable disk Dec 18 11:46:32 volumio volumio[4534]: info: Mounting Device 系统 Dec 18 11:46:32 volumio volumio[4534]: /bin/mkdir: cannot create directory ‘/mnt/USB/系统’: File exists Dec 18 11:46:32 volumio volumio[4534]: error: Failed to create folder Error: Command failed: /bin/mkdir -m 777 "/mnt/USB/系统" Dec 18 11:46:32 volumio volumio[4534]: /bin/mkdir: cannot create directory ‘/mnt/USB/系统’: File exists Dec 18 11:46:32 volumio sudo[6132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/系统 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 18 11:46:32 volumio sudo[6132]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:46:32 volumio sudo[6132]: pam_unix(sudo:session): session closed for user root Dec 18 11:46:32 volumio volumio[4534]: ntfs-3g-mount: failed to access mountpoint /media/系统: Transport endpoint is not connected Dec 18 11:46:32 volumio volumio[4534]: error: Failed to mount 系统: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb1" "/mnt/USB/系统" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 18 11:46:32 volumio volumio[4534]: ntfs-3g-mount: failed to access mountpoint /media/系统: Transport endpoint is not connected Dec 18 11:46:34 volumio volumio[4534]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 11:46:34 volumio volumio[4534]: Error: connect ETIMEDOUT 75.126.33.156:443 Dec 18 11:46:34 volumio volumio[4534]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Dec 18 11:46:34 volumio volumio[4534]: errno: -110, Dec 18 11:46:34 volumio volumio[4534]: code: 'ETIMEDOUT', Dec 18 11:46:34 volumio volumio[4534]: syscall: 'connect', Dec 18 11:46:34 volumio volumio[4534]: address: '75.126.33.156', Dec 18 11:46:34 volumio volumio[4534]: port: 443 Dec 18 11:46:34 volumio volumio[4534]: } Dec 18 11:46:34 volumio volumio[4534]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 11:46:35 volumio sudo[6144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-18 11:45 Dec 18 11:46:35 volumio sudo[6144]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 06:58:02 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4ae6504ed6f22e44c01a6e93b16badbd"