Aug 31 03:19:00 volumio volumio[1233]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 03:19:00 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 31 03:19:00 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 03:19:00 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 03:19:00 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 03:19:00 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 03:19:00 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 03:19:00 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 03:19:00 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 03:19:00 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 03:19:00 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 03:19:00 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 31 03:19:00 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 31 03:19:07 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 03:19:07 volumio volumio[1233]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 31 03:19:07 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 31 03:19:07 volumio volumio[1233]: info: Received Get System Version Aug 31 03:19:07 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 03:19:07 volumio volumio[1233]: info: Received Get System Info Aug 31 03:19:07 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 03:19:07 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 03:19:07 volumio volumio[1233]: info: Discovery: Getting this device information Aug 31 03:19:07 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Aug 31 03:19:07 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 31 03:19:07 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 03:19:09 volumio volumio[1233]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 03:19:09 volumio volumio[1233]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 03:19:09 volumio volumio[1233]: info: Retrieving Cloud Streaming UI Aug 31 03:19:09 volumio volumio[1233]: info: Getting Tidal Cloud Configuration Aug 31 03:19:09 volumio volumio[1233]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 03:19:09 volumio volumio[1233]: info: Getting Qobuz Cloud Configuration Aug 31 03:19:09 volumio volumio[1233]: info: Asking plugin for UI Config Aug 31 03:19:09 volumio volumio[1233]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 03:19:09 volumio volumio[1233]: info: Getting Spotify Cloud Configuration Aug 31 03:19:09 volumio volumio[1233]: info: Asking plugin for UI Config Aug 31 03:19:09 volumio volumio[1233]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 03:19:09 volumio volumio[1233]: info: Saving Spotify Acccount Aug 31 03:19:09 volumio volumio[1233]: info: Got Tidal Cloud Configuration Aug 31 03:19:09 volumio volumio[1233]: info: Got it Aug 31 03:19:09 volumio volumio[1233]: info: Got it Aug 31 03:19:09 volumio volumio[1233]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 31 03:19:10 volumio volumio[1233]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 03:19:10 volumio volumio[1233]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Aug 31 03:19:10 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 31 03:19:10 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 03:19:10 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 03:19:10 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 03:19:10 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 03:19:10 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 03:19:10 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 03:19:10 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 03:19:10 volumio volumio[1233]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 03:19:10 volumio volumio[1233]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 03:19:10 volumio volumio[1233]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 03:19:15 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 03:19:15 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 31 03:19:19 volumio volumio[1233]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 03:19:20 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 31 03:19:20 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 03:19:20 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 03:19:20 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 03:19:20 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 03:19:20 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 03:19:20 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 03:19:20 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 03:19:20 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 03:19:20 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 03:19:20 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 31 03:19:20 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 31 03:19:34 volumio volumio[1233]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 03:19:34 volumio volumio[1233]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 03:19:34 volumio volumio[1233]: info: Retrieving Cloud Streaming UI Aug 31 03:19:34 volumio volumio[1233]: info: Getting Tidal Cloud Configuration Aug 31 03:19:34 volumio volumio[1233]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 03:19:34 volumio volumio[1233]: info: Getting Qobuz Cloud Configuration Aug 31 03:19:34 volumio volumio[1233]: info: Asking plugin for UI Config Aug 31 03:19:34 volumio volumio[1233]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 03:19:34 volumio volumio[1233]: info: Getting Spotify Cloud Configuration Aug 31 03:19:34 volumio volumio[1233]: info: Asking plugin for UI Config Aug 31 03:19:34 volumio volumio[1233]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 03:19:34 volumio volumio[1233]: info: Saving Spotify Acccount Aug 31 03:19:34 volumio volumio[1233]: info: Got Tidal Cloud Configuration Aug 31 03:19:34 volumio volumio[1233]: info: Got it Aug 31 03:19:34 volumio volumio[1233]: info: Got it Aug 31 03:19:34 volumio volumio[1233]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 31 03:19:34 volumio volumio[1233]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 03:19:35 volumio volumio[1233]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Aug 31 03:19:35 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 31 03:19:35 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 03:19:35 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 03:19:35 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 03:19:35 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 03:19:35 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 03:19:35 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 03:19:35 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 03:19:35 volumio volumio[1233]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 03:19:35 volumio volumio[1233]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 03:19:35 volumio volumio[1233]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 03:19:35 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 03:19:35 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 31 03:19:39 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 03:19:43 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 03:19:47 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 03:19:50 volumio sudo[1838]: pam_unix(sudo:session): session closed for user root Aug 31 03:19:50 volumio volumio[1233]: info: Cannot mount NAS NAS at system boot, trial number 2 ,retrying in 5 seconds Aug 31 03:19:51 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 03:19:54 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 31 03:19:54 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand update Aug 31 03:19:54 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Aug 31 03:19:54 volumio volumio[1233]: info: Aug 31 03:19:54 volumio volumio[1233]: ---------------------------- MPD announces state update: update Aug 31 03:19:54 volumio volumio[1233]: info: sendMpdCommand update took 20 milliseconds Aug 31 03:19:54 volumio volumio[1233]: info: ControllerMpd::getState Aug 31 03:19:54 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Aug 31 03:19:54 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Aug 31 03:19:54 volumio volumio[1233]: info: Aug 31 03:19:54 volumio volumio[1233]: ---------------------------- MPD announces state update: update Aug 31 03:19:54 volumio volumio[1233]: info: ControllerMpd::getState Aug 31 03:19:54 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Aug 31 03:19:54 volumio volumio[1233]: info: sendMpdCommand status took 5 milliseconds Aug 31 03:19:54 volumio volumio[1233]: info: sendMpdCommand status took 3 milliseconds Aug 31 03:19:54 volumio volumio[1233]: info: sendMpdCommand status took 2 milliseconds Aug 31 03:19:54 volumio volumio[1233]: info: sendMpdCommand status took 2 milliseconds Aug 31 03:19:54 volumio volumio[1233]: verbose: ControllerMpd::parseState Aug 31 03:19:54 volumio volumio[1233]: info: Command Router : Notfying DB Updatetrue Aug 31 03:19:54 volumio volumio[1233]: verbose: ControllerMpd::parseState Aug 31 03:19:54 volumio volumio[1233]: verbose: ControllerMpd::parseState Aug 31 03:19:54 volumio volumio[1233]: info: Command Router : Notfying DB Updatetrue Aug 31 03:19:54 volumio volumio[1233]: info: CoreCommandRouter::Close All Modals sent Aug 31 03:19:54 volumio volumio[1233]: verbose: ControllerMpd::parseState Aug 31 03:19:54 volumio volumio[1233]: info: ControllerMpd::pushState Aug 31 03:19:54 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Aug 31 03:19:55 volumio volumio[1233]: info: CoreStateMachine::pushState Aug 31 03:19:55 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 31 03:19:55 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 31 03:19:55 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 31 03:19:55 volumio volumio[1233]: 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} Aug 31 03:19:55 volumio volumio[1233]: verbose: CURRENT POSITION 0 Aug 31 03:19:55 volumio volumio[1233]: info: CoreStateMachine::syncState stateService stop Aug 31 03:19:55 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus stop Aug 31 03:19:55 volumio volumio[1233]: info: CoreStateMachine::pushState Aug 31 03:19:55 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 31 03:19:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 03:19:55 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 31 03:19:55 volumio volumio[1233]: info: No code Aug 31 03:19:55 volumio volumio[1233]: info: CoreStateMachine::pushState Aug 31 03:19:55 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 31 03:19:55 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 31 03:19:55 volumio volumio[1233]: info: ControllerMpd::pushState Aug 31 03:19:55 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Aug 31 03:19:55 volumio volumio[1233]: info: CoreStateMachine::pushState Aug 31 03:19:55 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 31 03:19:55 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 31 03:19:55 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 31 03:19:55 volumio volumio[1233]: 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} Aug 31 03:19:55 volumio volumio[1233]: verbose: CURRENT POSITION 0 Aug 31 03:19:55 volumio volumio[1233]: info: CoreStateMachine::syncState stateService stop Aug 31 03:19:55 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus stop Aug 31 03:19:55 volumio volumio[1233]: info: CoreStateMachine::pushState Aug 31 03:19:55 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 31 03:19:55 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 31 03:19:55 volumio volumio[1233]: info: No code Aug 31 03:19:55 volumio volumio[1233]: info: CoreStateMachine::pushState Aug 31 03:19:55 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 31 03:19:55 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 31 03:19:55 volumio volumio[1233]: info: ------------------------------ 540ms Aug 31 03:19:55 volumio volumio[1233]: info: ------------------------------ 538ms Aug 31 03:19:55 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Aug 31 03:19:55 volumio volumio[1233]: info: Aug 31 03:19:55 volumio volumio[1233]: ---------------------------- MPD announces state update: update Aug 31 03:19:55 volumio volumio[1233]: info: ControllerMpd::getState Aug 31 03:19:55 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Aug 31 03:19:55 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Aug 31 03:19:55 volumio volumio[1233]: info: Aug 31 03:19:55 volumio volumio[1233]: ---------------------------- MPD announces state update: update Aug 31 03:19:55 volumio volumio[1233]: info: ControllerMpd::getState Aug 31 03:19:55 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Aug 31 03:19:55 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Aug 31 03:19:55 volumio volumio[1233]: info: Aug 31 03:19:55 volumio volumio[1233]: ---------------------------- MPD announces state update: update Aug 31 03:19:55 volumio volumio[1233]: info: ControllerMpd::getState Aug 31 03:19:55 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Aug 31 03:19:55 volumio volumio[1233]: info: sendMpdCommand status took 5 milliseconds Aug 31 03:19:55 volumio volumio[1233]: info: sendMpdCommand status took 76 milliseconds Aug 31 03:19:55 volumio volumio[1233]: info: sendMpdCommand status took 74 milliseconds Aug 31 03:19:55 volumio volumio[1233]: info: sendMpdCommand status took 73 milliseconds Aug 31 03:19:55 volumio volumio[1233]: verbose: ControllerMpd::parseState Aug 31 03:19:55 volumio volumio[1233]: info: Command Router : Notfying DB Updatefalse Aug 31 03:19:55 volumio volumio[1233]: info: CoreCommandRouter::Close All Modals sent Aug 31 03:19:55 volumio volumio[1233]: verbose: ControllerMpd::parseState Aug 31 03:19:55 volumio volumio[1233]: verbose: ControllerMpd::parseState Aug 31 03:19:55 volumio volumio[1233]: info: Command Router : Notfying DB Updatefalse Aug 31 03:19:55 volumio volumio[1233]: info: CoreCommandRouter::Close All Modals sent Aug 31 03:19:55 volumio volumio[1233]: verbose: ControllerMpd::parseState Aug 31 03:19:55 volumio volumio[1233]: info: ControllerMpd::pushState Aug 31 03:19:55 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Aug 31 03:19:55 volumio volumio[1233]: info: CoreStateMachine::pushState Aug 31 03:19:55 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 31 03:19:55 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 31 03:19:55 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 31 03:19:55 volumio volumio[1233]: 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} Aug 31 03:19:55 volumio volumio[1233]: verbose: CURRENT POSITION 0 Aug 31 03:19:55 volumio volumio[1233]: info: CoreStateMachine::syncState stateService stop Aug 31 03:19:55 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus stop Aug 31 03:19:55 volumio volumio[1233]: info: CoreStateMachine::pushState Aug 31 03:19:55 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 31 03:19:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 03:19:55 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 31 03:19:55 volumio volumio[1233]: info: No code Aug 31 03:19:55 volumio volumio[1233]: info: CoreStateMachine::pushState Aug 31 03:19:55 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 31 03:19:55 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 31 03:19:55 volumio volumio[1233]: info: ControllerMpd::pushState Aug 31 03:19:55 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Aug 31 03:19:55 volumio volumio[1233]: info: CoreStateMachine::pushState Aug 31 03:19:55 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 31 03:19:55 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 31 03:19:55 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 31 03:19:55 volumio volumio[1233]: 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} Aug 31 03:19:55 volumio volumio[1233]: verbose: CURRENT POSITION 0 Aug 31 03:19:55 volumio volumio[1233]: info: CoreStateMachine::syncState stateService stop Aug 31 03:19:55 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus stop Aug 31 03:19:55 volumio volumio[1233]: info: CoreStateMachine::pushState Aug 31 03:19:55 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 31 03:19:55 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 31 03:19:55 volumio volumio[1233]: info: No code Aug 31 03:19:55 volumio volumio[1233]: info: CoreStateMachine::pushState Aug 31 03:19:55 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 31 03:19:55 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 31 03:19:55 volumio volumio[1233]: info: ------------------------------ 509ms Aug 31 03:19:55 volumio volumio[1233]: info: ------------------------------ 507ms Aug 31 03:19:55 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Aug 31 03:19:55 volumio volumio[1233]: info: Aug 31 03:19:55 volumio volumio[1233]: ---------------------------- MPD announces state update: update Aug 31 03:19:55 volumio volumio[1233]: info: ControllerMpd::getState Aug 31 03:19:55 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Aug 31 03:19:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 03:19:55 volumio volumio[1233]: info: sendMpdCommand status took 719 milliseconds Aug 31 03:19:55 volumio volumio[1233]: info: sendMpdCommand status took 718 milliseconds Aug 31 03:19:55 volumio volumio[1233]: info: sendMpdCommand status took 214 milliseconds Aug 31 03:19:55 volumio volumio[1233]: info: sendMpdCommand status took 213 milliseconds Aug 31 03:19:55 volumio volumio[1233]: verbose: ControllerMpd::parseState Aug 31 03:19:56 volumio volumio[1233]: info: Command Router : Notfying DB Updatefalse Aug 31 03:19:56 volumio volumio[1233]: info: CoreCommandRouter::Close All Modals sent Aug 31 03:19:56 volumio volumio[1233]: verbose: ControllerMpd::parseState Aug 31 03:19:56 volumio volumio[1233]: verbose: ControllerMpd::parseState Aug 31 03:19:56 volumio volumio[1233]: info: Command Router : Notfying DB Updatefalse Aug 31 03:19:56 volumio volumio[1233]: info: CoreCommandRouter::Close All Modals sent Aug 31 03:19:56 volumio volumio[1233]: verbose: ControllerMpd::parseState Aug 31 03:19:56 volumio volumio[1233]: info: ControllerMpd::pushState Aug 31 03:19:56 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Aug 31 03:19:56 volumio volumio[1233]: info: CoreStateMachine::pushState Aug 31 03:19:56 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 31 03:19:56 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 31 03:19:56 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 31 03:19:56 volumio volumio[1233]: 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} Aug 31 03:19:56 volumio volumio[1233]: verbose: CURRENT POSITION 0 Aug 31 03:19:56 volumio volumio[1233]: info: CoreStateMachine::syncState stateService stop Aug 31 03:19:56 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus stop Aug 31 03:19:56 volumio volumio[1233]: info: CoreStateMachine::pushState Aug 31 03:19:56 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 31 03:19:56 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 31 03:19:56 volumio volumio[1233]: info: No code Aug 31 03:19:56 volumio volumio[1233]: info: CoreStateMachine::pushState Aug 31 03:19:56 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 31 03:19:56 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 31 03:19:56 volumio volumio[1233]: info: ControllerMpd::pushState Aug 31 03:19:56 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Aug 31 03:19:56 volumio volumio[1233]: info: CoreStateMachine::pushState Aug 31 03:19:56 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 31 03:19:56 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 31 03:19:56 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 31 03:19:56 volumio volumio[1233]: 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} Aug 31 03:19:56 volumio volumio[1233]: verbose: CURRENT POSITION 0 Aug 31 03:19:56 volumio volumio[1233]: info: CoreStateMachine::syncState stateService stop Aug 31 03:19:56 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus stop Aug 31 03:19:56 volumio volumio[1233]: info: CoreStateMachine::pushState Aug 31 03:19:56 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 31 03:19:56 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 31 03:19:56 volumio volumio[1233]: info: No code Aug 31 03:19:56 volumio volumio[1233]: info: CoreStateMachine::pushState Aug 31 03:19:56 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Aug 31 03:19:56 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Aug 31 03:19:56 volumio volumio[1233]: info: ------------------------------ 942ms Aug 31 03:19:56 volumio volumio[1233]: info: ------------------------------ 436ms Aug 31 03:19:56 volumio sudo[2175]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.68.199:/music /mnt/NAS/NAS Aug 31 03:19:56 volumio sudo[2175]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 03:19:59 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 03:20:03 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 03:20:07 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 03:20:11 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 03:20:15 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 03:20:17 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Aug 31 03:20:17 volumio volumio[1233]: info: Share NAS successfully unmounted Aug 31 03:20:17 volumio sudo[2219]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=dangtrinh,password=Dnt@160994,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.68.199//music /mnt/NAS/NAS Aug 31 03:20:17 volumio sudo[2219]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 03:20:17 volumio kernel: netfs: FS-Cache loaded Aug 31 03:20:17 volumio kernel: Key type cifs.spnego registered Aug 31 03:20:17 volumio kernel: Key type cifs.idmap registered Aug 31 03:20:17 volumio kernel: Malformed UNC in devname Aug 31 03:20:17 volumio kernel: CIFS: VFS: Malformed UNC in devname Aug 31 03:20:17 volumio sudo[2219]: pam_unix(sudo:session): session closed for user root Aug 31 03:20:17 volumio volumio[1233]: info: An error occurred mounting the new share. Rolling back configuration Aug 31 03:20:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 31 03:20:19 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 03:20:23 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 03:20:27 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 03:20:28 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Aug 31 03:20:28 volumio volumio[1233]: info: Share NAS successfully unmounted Aug 31 03:20:29 volumio sudo[2256]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=dangtrinh,password=Dnt@160994,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.68.199//music /mnt/NAS/NAS Aug 31 03:20:29 volumio sudo[2256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 03:20:29 volumio kernel: Malformed UNC in devname Aug 31 03:20:29 volumio kernel: CIFS: VFS: Malformed UNC in devname Aug 31 03:20:29 volumio sudo[2256]: pam_unix(sudo:session): session closed for user root Aug 31 03:20:29 volumio volumio[1233]: info: An error occurred mounting the new share. Rolling back configuration Aug 31 03:20:30 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 31 03:20:31 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 03:20:35 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 03:20:39 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 03:20:39 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Aug 31 03:20:40 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 31 03:20:43 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Aug 31 03:20:43 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Aug 31 03:20:43 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 03:20:47 volumio ntpd[1056]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 31 03:20:47 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 03:20:50 volumio sudo[2175]: pam_unix(sudo:session): session closed for user root Aug 31 03:20:50 volumio volumio[1233]: info: Cannot mount NAS NAS at system boot, trial number 3 ,retrying in 5 seconds Aug 31 03:20:51 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 03:20:52 volumio ntpd[1056]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Aug 31 03:20:52 volumio ntpd[1056]: DNS: Pool taking: 103.184.124.254 Aug 31 03:20:52 volumio ntpd[1056]: DNS: Pool taking: 45.252.250.189 Aug 31 03:20:52 volumio ntpd[1056]: DNS: Pool taking: 160.22.74.161 Aug 31 03:20:52 volumio ntpd[1056]: DNS: Pool taking: 103.221.223.143 Aug 31 03:20:52 volumio ntpd[1056]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Aug 31 03:20:53 volumio ntpd[1056]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Aug 31 03:20:55 volumio volumio[1233]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 03:20:55 volumio volumio[1233]: TypeError: Cannot read properties of undefined (reading 'replace') Aug 31 03:20:55 volumio volumio[1233]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Aug 31 03:20:55 volumio volumio[1233]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Aug 31 03:20:55 volumio volumio[1233]: at listOnTimeout (node:internal/timers:573:17) Aug 31 03:20:55 volumio volumio[1233]: at process.processTimers (node:internal/timers:514:7) Aug 31 03:20:55 volumio volumio[1233]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 03:20:55 volumio sudo[2341]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-31 03:19' Aug 31 03:20:55 volumio sudo[2341]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"