-- Logs begin at Tue 2025-03-11 15:25:44 UTC, end at Tue 2025-03-11 16:02:46 UTC. -- Mar 11 16:01:00 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 11 16:01:00 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 11 16:01:00 volumio volumio[3766]: info: Discovery: Getting this device information Mar 11 16:01:00 volumio volumio[3766]: info: CoreCommandRouter::volumioGetState Mar 11 16:01:00 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:01:00 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 11 16:01:01 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Mar 11 16:01:03 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 11 16:01:05 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Mar 11 16:01:07 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 11 16:01:11 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 11 16:01:11 volumio volumio[3766]: error: Cannot Delete Folder. Error: Error: Command failed: rm -rf /mnt/NAS/又又 Mar 11 16:01:11 volumio volumio[3766]: rm: cannot remove '/mnt/NAS/又又': Is a directory Mar 11 16:01:12 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 11 16:01:15 volumio volumio[3766]: error: Cannot Delete Folder. Error: Error: Command failed: rm -rf /mnt/NAS/泽典 Mar 11 16:01:15 volumio volumio[3766]: rm: cannot remove '/mnt/NAS/泽典': Is a directory Mar 11 16:01:16 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 11 16:01:19 volumio volumio[3766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 11 16:01:19 volumio volumio[3766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 11 16:01:19 volumio volumio[3766]: info: Retrieving Cloud Streaming UI Mar 11 16:01:19 volumio volumio[3766]: info: Getting Tidal Cloud Configuration Mar 11 16:01:19 volumio volumio[3766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 11 16:01:19 volumio volumio[3766]: info: Getting Qobuz Cloud Configuration Mar 11 16:01:19 volumio volumio[3766]: info: Asking plugin for UI Config Mar 11 16:01:19 volumio volumio[3766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 11 16:01:19 volumio volumio[3766]: info: Getting Spotify Cloud Configuration Mar 11 16:01:19 volumio volumio[3766]: info: Asking plugin for UI Config Mar 11 16:01:19 volumio volumio[3766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 11 16:01:19 volumio volumio[3766]: info: Saving Spotify Acccount Mar 11 16:01:19 volumio volumio[3766]: info: Got Tidal Cloud Configuration Mar 11 16:01:19 volumio volumio[3766]: info: Got it Mar 11 16:01:19 volumio volumio[3766]: info: Got it Mar 11 16:01:19 volumio volumio[3766]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 11 16:01:19 volumio volumio[3766]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 11 16:01:19 volumio volumio[3766]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 11 16:01:19 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 11 16:01:19 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 11 16:01:19 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 11 16:01:19 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 11 16:01:19 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 16:01:19 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 16:01:19 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 16:01:19 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 16:01:19 volumio volumio[3766]: info: CoreCommandRouter::volumioGetBrowseSources Mar 11 16:01:19 volumio volumio[3766]: info: CoreCommandRouter::volumioGetBrowseSources Mar 11 16:01:19 volumio volumio[3766]: info: CoreCommandRouter::volumioGetBrowseSources Mar 11 16:01:19 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 11 16:01:19 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 11 16:01:23 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 11 16:01:27 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 11 16:01:30 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 11 16:01:30 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 11 16:01:30 volumio volumio[3766]: info: Discovery: Getting this device information Mar 11 16:01:30 volumio volumio[3766]: info: CoreCommandRouter::volumioGetState Mar 11 16:01:30 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:01:30 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 11 16:01:30 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Mar 11 16:01:30 volumio volumio[3766]: info: Share 又又 successfully unmounted Mar 11 16:01:41 volumio volumio[3766]: error: Could not mount NAS: Error: EHOSTDOWN: host is down, stat '/mnt/NAS/又又' Mar 11 16:01:41 volumio volumio[3766]: info: An error occurred mounting the new share. Rolling back configuration Mar 11 16:01:41 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 11 16:01:41 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Mar 11 16:01:41 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 11 16:01:41 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 11 16:01:41 volumio sudo[7463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/chinese Mar 11 16:01:41 volumio sudo[7463]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 16:01:42 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 11 16:01:43 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 11 16:01:44 volumio volumio[3766]: info: [1741708904172] Database update started Mar 11 16:01:47 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 11 16:01:51 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 11 16:01:51 volumio sudo[7463]: pam_unix(sudo:session): session closed for user root Mar 11 16:01:51 volumio volumio[3766]: error: Mount point '/mnt/NAS/chinese' cannot be removed. Error: exec error Error: Command failed: /usr/bin/sudo /bin/umount '/mnt/NAS/chinese' Mar 11 16:01:51 volumio volumio[3766]: umount: /mnt/NAS/chinese: target is busy. Mar 11 16:01:52 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 11 16:01:54 volumio volumio[3766]: info: MPD Database updated - AlbumList cache refreshed Mar 11 16:01:54 volumio volumio[3766]: info: Mar 11 16:01:54 volumio volumio[3766]: ---------------------------- MPD announces state update: database Mar 11 16:01:54 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:01:54 volumio volumio[3766]: info: Mar 11 16:01:54 volumio volumio[3766]: ---------------------------- MPD announces state update: update Mar 11 16:01:54 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:01:54 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:01:54 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:01:54 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:01:54 volumio volumio[3766]: info: MPD Database updated - AlbumList cache refreshed Mar 11 16:01:54 volumio volumio[3766]: info: Mar 11 16:01:54 volumio volumio[3766]: ---------------------------- MPD announces state update: database Mar 11 16:01:54 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:01:54 volumio volumio[3766]: info: Mar 11 16:01:54 volumio volumio[3766]: ---------------------------- MPD announces state update: update Mar 11 16:01:54 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:01:54 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:01:54 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:01:54 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:01:54 volumio volumio[3766]: info: MPD Database updated - AlbumList cache refreshed Mar 11 16:01:54 volumio volumio[3766]: info: Mar 11 16:01:54 volumio volumio[3766]: ---------------------------- MPD announces state update: database Mar 11 16:01:54 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:01:54 volumio volumio[3766]: info: Mar 11 16:01:54 volumio volumio[3766]: ---------------------------- MPD announces state update: update Mar 11 16:01:54 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:01:54 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:01:54 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:01:54 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:01:54 volumio volumio[3766]: info: sendMpdCommand status took 44 milliseconds Mar 11 16:01:54 volumio volumio[3766]: info: sendMpdCommand status took 41 milliseconds Mar 11 16:01:54 volumio volumio[3766]: info: sendMpdCommand status took 41 milliseconds Mar 11 16:01:54 volumio volumio[3766]: info: sendMpdCommand status took 37 milliseconds Mar 11 16:01:54 volumio volumio[3766]: info: sendMpdCommand status took 37 milliseconds Mar 11 16:01:54 volumio volumio[3766]: info: sendMpdCommand status took 36 milliseconds Mar 11 16:01:54 volumio volumio[3766]: info: sendMpdCommand status took 33 milliseconds Mar 11 16:01:54 volumio volumio[3766]: info: sendMpdCommand status took 25 milliseconds Mar 11 16:01:54 volumio volumio[3766]: info: sendMpdCommand status took 24 milliseconds Mar 11 16:01:54 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:01:55 volumio volumio[3766]: info: Command Router : Notfying DB Updatetrue Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::Close All Modals sent Mar 11 16:01:55 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:01:55 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:01:55 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:01:55 volumio volumio[3766]: info: Command Router : Notfying DB Updatetrue Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::Close All Modals sent Mar 11 16:01:55 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:01:55 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:01:55 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:01:55 volumio volumio[3766]: info: Command Router : Notfying DB Updatetrue Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::Close All Modals sent Mar 11 16:01:55 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:01:55 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:01:55 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:01:55 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:01:55 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:01:55 volumio volumio[3766]: 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} Mar 11 16:01:55 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:01:55 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:01:55 volumio volumio[3766]: info: No code Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:01:55 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:01:55 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:01:55 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:01:55 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:01:55 volumio volumio[3766]: 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} Mar 11 16:01:55 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:01:55 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:01:55 volumio volumio[3766]: info: No code Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:01:55 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:01:55 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:01:55 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:01:55 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:01:55 volumio volumio[3766]: 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} Mar 11 16:01:55 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:01:55 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:01:55 volumio volumio[3766]: info: No code Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:01:55 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:01:55 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:01:55 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:01:55 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:01:55 volumio volumio[3766]: 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} Mar 11 16:01:55 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:01:55 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:01:55 volumio volumio[3766]: info: No code Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:01:55 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:01:55 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:01:55 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:01:55 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:01:55 volumio volumio[3766]: 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} Mar 11 16:01:55 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:01:55 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:01:55 volumio volumio[3766]: info: No code Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:01:55 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:01:55 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:01:55 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:01:55 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:01:55 volumio volumio[3766]: 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} Mar 11 16:01:55 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:01:55 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:01:55 volumio volumio[3766]: info: No code Mar 11 16:01:55 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:01:55 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:01:55 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:01:55 volumio volumio[3766]: info: ------------------------------ 429ms Mar 11 16:01:55 volumio volumio[3766]: info: ------------------------------ 429ms Mar 11 16:01:55 volumio volumio[3766]: info: ------------------------------ 424ms Mar 11 16:01:55 volumio volumio[3766]: info: ------------------------------ 424ms Mar 11 16:01:55 volumio volumio[3766]: info: ------------------------------ 420ms Mar 11 16:01:55 volumio volumio[3766]: info: ------------------------------ 421ms Mar 11 16:02:02 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 11 16:02:02 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 11 16:02:02 volumio volumio[3766]: info: Discovery: Getting this device information Mar 11 16:02:02 volumio volumio[3766]: info: CoreCommandRouter::volumioGetState Mar 11 16:02:02 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:02 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 11 16:02:02 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 11 16:02:02 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 11 16:02:02 volumio volumio[3766]: info: Discovery: Getting this device information Mar 11 16:02:02 volumio volumio[3766]: info: CoreCommandRouter::volumioGetState Mar 11 16:02:02 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:02 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 11 16:02:03 volumio volumio[3766]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%B0%B9%E6%98%94%E7%9C%A0/%E5%86%9B%E4%BB%A4%E7%8A%B6/47fbfd23-0901-47dc-ab40-72dde12d040d.jpg' Mar 11 16:02:03 volumio volumio[3766]: verbose: New Socket.io Connection to 192.168.0.80 from 192.168.0.74 UA: Mozilla/5.0 (Linux; Android 15; PJD110 Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/133.0.6943.137 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Mar 11 16:02:03 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 11 16:02:03 volumio volumio[3766]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%B0%B9%E6%98%94%E7%9C%A0/%E6%98%9F%E6%B2%B3%E5%85%A5%E4%BD%A0%E7%9C%B8/50f92fc2-cd96-4932-aebf-b7d31789c49b.jpg' Mar 11 16:02:03 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 11 16:02:03 volumio volumio[3766]: info: CoreCommandRouter::volumioGetState Mar 11 16:02:03 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:03 volumio volumio[3766]: info: CoreCommandRouter::volumioGetVisibleSources Mar 11 16:02:03 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 11 16:02:03 volumio volumio[3766]: info: CoreCommandRouter::volumioGetQueue Mar 11 16:02:03 volumio volumio[3766]: info: CoreStateMachine::getQueue Mar 11 16:02:03 volumio volumio[3766]: info: CorePlayQueue::getQueue Mar 11 16:02:03 volumio volumio[3766]: info: Listing playlists Mar 11 16:02:03 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 11 16:02:04 volumio volumio[3766]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%B0%B9%E6%98%94%E7%9C%A0/%E9%97%AE%E5%BF%83%E6%9C%89%E6%84%A7/3795b6b0-6f7e-477b-ba76-7ccdc95d51a7.jpg' Mar 11 16:02:04 volumio volumio[3766]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%B0%B9%E6%98%94%E7%9C%A0/%E7%94%BB%E9%9B%A8%E5%85%B0%E8%88%9F/81810886-35c7-41cb-a507-8fee02a1feaf.jpg' Mar 11 16:02:04 volumio volumio[3766]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%B0%B9%E6%98%94%E7%9C%A0/%E7%BA%A2%E5%B0%98%E5%9D%A0%E6%A2%A6/f2ff853d-250c-4845-85f9-25131aa381cb.jpg' Mar 11 16:02:04 volumio volumio[3766]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%B0%B9%E6%98%94%E7%9C%A0/%E6%B8%A1%E4%B8%8D%E4%BA%86%E8%BD%AE%E5%9B%9E/3c095adf-28e2-40fa-95e2-98b8d046b840.jpg' Mar 11 16:02:04 volumio volumio[3766]: verbose: New Socket.io Connection to 192.168.0.80:3000 from 192.168.0.74 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Mar 11 16:02:04 volumio volumio[3766]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%B0%B9%E6%98%94%E7%9C%A0/%E6%9C%88%E5%8D%8E%E8%90%BD/6c3d9046-c4e5-4254-b4b9-c28521b589a6.jpg' Mar 11 16:02:04 volumio volumio[3766]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%B0%B9%E6%98%94%E7%9C%A0/%E8%84%B1%E7%BA%BF%E6%9C%A8%E5%81%B6/0ca0dd17-5f26-4c5e-bf6c-63b798bac846.jpg' Mar 11 16:02:07 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 11 16:02:08 volumio kernel: CIFS: VFS: \\192.168.0.89 has not responded in 180 seconds. Reconnecting... Mar 11 16:02:10 volumio volumio[3766]: info: MPD Database updated - AlbumList cache refreshed Mar 11 16:02:10 volumio volumio[3766]: info: Mar 11 16:02:10 volumio volumio[3766]: ---------------------------- MPD announces state update: database Mar 11 16:02:10 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:10 volumio volumio[3766]: info: Mar 11 16:02:10 volumio volumio[3766]: ---------------------------- MPD announces state update: update Mar 11 16:02:10 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:02:10 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:10 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:02:10 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:10 volumio volumio[3766]: info: MPD Database updated - AlbumList cache refreshed Mar 11 16:02:10 volumio volumio[3766]: info: Mar 11 16:02:10 volumio volumio[3766]: ---------------------------- MPD announces state update: database Mar 11 16:02:10 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:10 volumio volumio[3766]: info: Mar 11 16:02:10 volumio volumio[3766]: ---------------------------- MPD announces state update: update Mar 11 16:02:10 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:02:10 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:10 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:02:10 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:10 volumio volumio[3766]: info: MPD Database updated - AlbumList cache refreshed Mar 11 16:02:10 volumio volumio[3766]: info: Mar 11 16:02:10 volumio volumio[3766]: ---------------------------- MPD announces state update: database Mar 11 16:02:10 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:10 volumio volumio[3766]: info: Mar 11 16:02:10 volumio volumio[3766]: ---------------------------- MPD announces state update: update Mar 11 16:02:10 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:02:10 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:10 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:02:10 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:10 volumio volumio[3766]: info: sendMpdCommand status took 46 milliseconds Mar 11 16:02:10 volumio volumio[3766]: info: sendMpdCommand status took 45 milliseconds Mar 11 16:02:10 volumio volumio[3766]: info: sendMpdCommand status took 43 milliseconds Mar 11 16:02:10 volumio volumio[3766]: info: sendMpdCommand status took 41 milliseconds Mar 11 16:02:10 volumio volumio[3766]: info: sendMpdCommand status took 39 milliseconds Mar 11 16:02:10 volumio volumio[3766]: info: sendMpdCommand status took 38 milliseconds Mar 11 16:02:10 volumio volumio[3766]: info: sendMpdCommand status took 36 milliseconds Mar 11 16:02:10 volumio volumio[3766]: info: sendMpdCommand status took 28 milliseconds Mar 11 16:02:10 volumio volumio[3766]: info: sendMpdCommand status took 28 milliseconds Mar 11 16:02:10 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:10 volumio volumio[3766]: info: Command Router : Notfying DB Updatefalse Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::Close All Modals sent Mar 11 16:02:10 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:10 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:10 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:10 volumio volumio[3766]: info: Command Router : Notfying DB Updatefalse Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::Close All Modals sent Mar 11 16:02:10 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:10 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:10 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:10 volumio volumio[3766]: info: Command Router : Notfying DB Updatefalse Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::Close All Modals sent Mar 11 16:02:10 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:10 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:10 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:10 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:10 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:10 volumio volumio[3766]: 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} Mar 11 16:02:10 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:10 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:10 volumio volumio[3766]: info: No code Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:10 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:10 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:10 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:10 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:10 volumio volumio[3766]: 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} Mar 11 16:02:10 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:10 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:10 volumio volumio[3766]: info: No code Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:10 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:10 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:10 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:10 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:10 volumio volumio[3766]: 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} Mar 11 16:02:10 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:10 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:10 volumio volumio[3766]: info: No code Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:10 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:10 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:10 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:10 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:10 volumio volumio[3766]: 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} Mar 11 16:02:10 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:10 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:10 volumio volumio[3766]: info: No code Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:10 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:10 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:10 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:10 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:10 volumio volumio[3766]: 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} Mar 11 16:02:10 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:10 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:10 volumio volumio[3766]: info: No code Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:10 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:10 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:10 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:10 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:10 volumio volumio[3766]: 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} Mar 11 16:02:10 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:10 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:10 volumio volumio[3766]: info: No code Mar 11 16:02:10 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:10 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:10 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:11 volumio volumio[3766]: info: ------------------------------ 503ms Mar 11 16:02:11 volumio volumio[3766]: info: ------------------------------ 503ms Mar 11 16:02:11 volumio volumio[3766]: info: ------------------------------ 499ms Mar 11 16:02:11 volumio volumio[3766]: info: ------------------------------ 499ms Mar 11 16:02:11 volumio volumio[3766]: info: ------------------------------ 493ms Mar 11 16:02:11 volumio volumio[3766]: info: ------------------------------ 489ms Mar 11 16:02:11 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 11 16:02:15 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 11 16:02:17 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Mar 11 16:02:17 volumio volumio[3766]: info: Share 又又 successfully unmounted Mar 11 16:02:17 volumio sudo[7602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.0.89:g/Chinese hires /mnt/NAS/又又 Mar 11 16:02:17 volumio sudo[7602]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 16:02:19 volumio kernel: NFS: Registering the id_resolver key type Mar 11 16:02:19 volumio kernel: Key type id_resolver registered Mar 11 16:02:19 volumio kernel: Key type id_legacy registered Mar 11 16:02:19 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 11 16:02:21 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Mar 11 16:02:21 volumio sudo[7632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/chinese Mar 11 16:02:21 volumio sudo[7632]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 16:02:21 volumio systemd[1]: mnt-NAS-chinese.mount: Succeeded. Mar 11 16:02:21 volumio sudo[7632]: pam_unix(sudo:session): session closed for user root Mar 11 16:02:21 volumio volumio[3766]: info: Share chinese successfully unmounted Mar 11 16:02:22 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 11 16:02:23 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 11 16:02:24 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:24 volumio volumio[3766]: info: Mar 11 16:02:24 volumio volumio[3766]: ---------------------------- MPD announces state update: update Mar 11 16:02:24 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:02:24 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:24 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:24 volumio volumio[3766]: info: Mar 11 16:02:24 volumio volumio[3766]: ---------------------------- MPD announces state update: update Mar 11 16:02:24 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:02:24 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:24 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:24 volumio volumio[3766]: info: Mar 11 16:02:24 volumio volumio[3766]: ---------------------------- MPD announces state update: update Mar 11 16:02:24 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:02:24 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:24 volumio volumio[3766]: info: sendMpdCommand status took 15 milliseconds Mar 11 16:02:24 volumio volumio[3766]: info: sendMpdCommand status took 13 milliseconds Mar 11 16:02:24 volumio volumio[3766]: info: sendMpdCommand status took 12 milliseconds Mar 11 16:02:24 volumio volumio[3766]: info: sendMpdCommand status took 10 milliseconds Mar 11 16:02:24 volumio volumio[3766]: info: sendMpdCommand status took 8 milliseconds Mar 11 16:02:24 volumio volumio[3766]: info: sendMpdCommand status took 7 milliseconds Mar 11 16:02:24 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:24 volumio volumio[3766]: info: Command Router : Notfying DB Updatetrue Mar 11 16:02:24 volumio volumio[3766]: info: CoreCommandRouter::Close All Modals sent Mar 11 16:02:24 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:24 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:25 volumio volumio[3766]: info: Command Router : Notfying DB Updatetrue Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::Close All Modals sent Mar 11 16:02:25 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:25 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:25 volumio volumio[3766]: info: Command Router : Notfying DB Updatetrue Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::Close All Modals sent Mar 11 16:02:25 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:25 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:25 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:25 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:25 volumio volumio[3766]: 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} Mar 11 16:02:25 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:25 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:25 volumio volumio[3766]: info: No code Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:25 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:25 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:25 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:25 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:25 volumio volumio[3766]: 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} Mar 11 16:02:25 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:25 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:25 volumio volumio[3766]: info: No code Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:25 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:25 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:25 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:25 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:25 volumio volumio[3766]: 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} Mar 11 16:02:25 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:25 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:25 volumio volumio[3766]: info: No code Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:25 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:25 volumio volumio[3766]: info: ------------------------------ 350ms Mar 11 16:02:25 volumio volumio[3766]: info: ------------------------------ 346ms Mar 11 16:02:25 volumio volumio[3766]: info: ------------------------------ 342ms Mar 11 16:02:25 volumio volumio[3766]: info: [1741708945193] Database update started Mar 11 16:02:25 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:25 volumio volumio[3766]: info: Mar 11 16:02:25 volumio volumio[3766]: ---------------------------- MPD announces state update: update Mar 11 16:02:25 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:02:25 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:25 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:25 volumio volumio[3766]: info: Mar 11 16:02:25 volumio volumio[3766]: ---------------------------- MPD announces state update: update Mar 11 16:02:25 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:02:25 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:25 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:25 volumio volumio[3766]: info: Mar 11 16:02:25 volumio volumio[3766]: ---------------------------- MPD announces state update: update Mar 11 16:02:25 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:02:25 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:25 volumio volumio[3766]: info: sendMpdCommand status took 27 milliseconds Mar 11 16:02:25 volumio volumio[3766]: info: sendMpdCommand status took 25 milliseconds Mar 11 16:02:25 volumio volumio[3766]: info: sendMpdCommand status took 11 milliseconds Mar 11 16:02:25 volumio volumio[3766]: info: sendMpdCommand status took 9 milliseconds Mar 11 16:02:25 volumio volumio[3766]: info: sendMpdCommand status took 7 milliseconds Mar 11 16:02:25 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:25 volumio volumio[3766]: info: Command Router : Notfying DB Updatefalse Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::Close All Modals sent Mar 11 16:02:25 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:25 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:25 volumio volumio[3766]: info: Command Router : Notfying DB Updatefalse Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::Close All Modals sent Mar 11 16:02:25 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:25 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:25 volumio volumio[3766]: info: Command Router : Notfying DB Updatefalse Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::Close All Modals sent Mar 11 16:02:25 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:25 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:25 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:25 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:25 volumio volumio[3766]: 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} Mar 11 16:02:25 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:25 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:25 volumio volumio[3766]: info: No code Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:25 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:25 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:25 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:25 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:25 volumio volumio[3766]: 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} Mar 11 16:02:25 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:25 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:25 volumio volumio[3766]: info: No code Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:25 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:25 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:25 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:25 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:25 volumio volumio[3766]: 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} Mar 11 16:02:25 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:25 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:25 volumio volumio[3766]: info: No code Mar 11 16:02:25 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:25 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:25 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:25 volumio volumio[3766]: info: ------------------------------ 288ms Mar 11 16:02:25 volumio volumio[3766]: info: ------------------------------ 272ms Mar 11 16:02:25 volumio volumio[3766]: info: ------------------------------ 273ms Mar 11 16:02:27 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 11 16:02:27 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Mar 11 16:02:27 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand update Mar 11 16:02:27 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:27 volumio volumio[3766]: info: Mar 11 16:02:27 volumio volumio[3766]: ---------------------------- MPD announces state update: update Mar 11 16:02:27 volumio volumio[3766]: info: sendMpdCommand update took 4 milliseconds Mar 11 16:02:27 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:02:27 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:27 volumio volumio[3766]: info: sendMpdCommand status took 3 milliseconds Mar 11 16:02:27 volumio volumio[3766]: info: sendMpdCommand status took 2 milliseconds Mar 11 16:02:27 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:27 volumio volumio[3766]: info: Command Router : Notfying DB Updatetrue Mar 11 16:02:27 volumio volumio[3766]: info: CoreCommandRouter::Close All Modals sent Mar 11 16:02:27 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:27 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:02:27 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:02:27 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:27 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:27 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:27 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:27 volumio volumio[3766]: 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} Mar 11 16:02:27 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:02:27 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:02:27 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:02:27 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:27 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:27 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 16:02:27 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:27 volumio volumio[3766]: info: No code Mar 11 16:02:27 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:27 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:27 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:27 volumio volumio[3766]: info: ------------------------------ 104ms Mar 11 16:02:27 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:27 volumio volumio[3766]: info: Mar 11 16:02:27 volumio volumio[3766]: ---------------------------- MPD announces state update: update Mar 11 16:02:27 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:02:27 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:27 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:27 volumio volumio[3766]: info: Mar 11 16:02:27 volumio volumio[3766]: ---------------------------- MPD announces state update: update Mar 11 16:02:27 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:02:27 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:27 volumio volumio[3766]: info: sendMpdCommand status took 7 milliseconds Mar 11 16:02:27 volumio volumio[3766]: info: sendMpdCommand status took 6 milliseconds Mar 11 16:02:27 volumio volumio[3766]: info: sendMpdCommand status took 6 milliseconds Mar 11 16:02:27 volumio volumio[3766]: info: sendMpdCommand status took 3 milliseconds Mar 11 16:02:27 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:27 volumio volumio[3766]: info: Command Router : Notfying DB Updatetrue Mar 11 16:02:27 volumio volumio[3766]: info: CoreCommandRouter::Close All Modals sent Mar 11 16:02:27 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:27 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:27 volumio volumio[3766]: info: Command Router : Notfying DB Updatetrue Mar 11 16:02:27 volumio volumio[3766]: info: CoreCommandRouter::Close All Modals sent Mar 11 16:02:27 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:27 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:02:27 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:02:27 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:27 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:27 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:27 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:27 volumio volumio[3766]: 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} Mar 11 16:02:27 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:02:27 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:02:27 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:02:27 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:27 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:27 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:27 volumio volumio[3766]: info: No code Mar 11 16:02:27 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:27 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:27 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:27 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:02:27 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:02:27 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:27 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:27 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:27 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:27 volumio volumio[3766]: 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} Mar 11 16:02:27 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:02:27 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:02:27 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:02:27 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:27 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:27 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:27 volumio volumio[3766]: info: No code Mar 11 16:02:27 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:27 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:27 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:28 volumio volumio[3766]: info: ------------------------------ 202ms Mar 11 16:02:28 volumio volumio[3766]: info: ------------------------------ 200ms Mar 11 16:02:28 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:28 volumio volumio[3766]: info: Mar 11 16:02:28 volumio volumio[3766]: ---------------------------- MPD announces state update: update Mar 11 16:02:28 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:02:28 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:28 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:28 volumio volumio[3766]: info: Mar 11 16:02:28 volumio volumio[3766]: ---------------------------- MPD announces state update: update Mar 11 16:02:28 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:02:28 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:28 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:28 volumio volumio[3766]: info: Mar 11 16:02:28 volumio volumio[3766]: ---------------------------- MPD announces state update: update Mar 11 16:02:28 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:02:28 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:28 volumio volumio[3766]: info: sendMpdCommand status took 9 milliseconds Mar 11 16:02:28 volumio volumio[3766]: info: sendMpdCommand status took 8 milliseconds Mar 11 16:02:28 volumio volumio[3766]: info: sendMpdCommand status took 7 milliseconds Mar 11 16:02:28 volumio volumio[3766]: info: sendMpdCommand status took 5 milliseconds Mar 11 16:02:28 volumio volumio[3766]: info: sendMpdCommand status took 5 milliseconds Mar 11 16:02:28 volumio volumio[3766]: info: sendMpdCommand status took 4 milliseconds Mar 11 16:02:28 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:28 volumio volumio[3766]: info: Command Router : Notfying DB Updatefalse Mar 11 16:02:28 volumio volumio[3766]: info: CoreCommandRouter::Close All Modals sent Mar 11 16:02:28 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:28 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:28 volumio volumio[3766]: info: Command Router : Notfying DB Updatefalse Mar 11 16:02:28 volumio volumio[3766]: info: CoreCommandRouter::Close All Modals sent Mar 11 16:02:28 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:28 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:28 volumio volumio[3766]: info: Command Router : Notfying DB Updatefalse Mar 11 16:02:28 volumio volumio[3766]: info: CoreCommandRouter::Close All Modals sent Mar 11 16:02:28 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:28 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:02:28 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:02:28 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:28 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:28 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:28 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:28 volumio volumio[3766]: 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} Mar 11 16:02:28 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:02:28 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:02:28 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:02:28 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:28 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:28 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 16:02:28 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:28 volumio volumio[3766]: info: No code Mar 11 16:02:28 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:28 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:28 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:28 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:02:28 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:02:28 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:28 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:28 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:28 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:28 volumio volumio[3766]: 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} Mar 11 16:02:28 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:02:28 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:02:28 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:02:28 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:28 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:28 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:28 volumio volumio[3766]: info: No code Mar 11 16:02:28 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:28 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:28 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:28 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:02:28 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:02:28 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:28 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:28 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:28 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:28 volumio volumio[3766]: 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} Mar 11 16:02:28 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:02:28 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:02:28 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:02:28 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:28 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:28 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:28 volumio volumio[3766]: info: No code Mar 11 16:02:28 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:28 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:28 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:28 volumio volumio[3766]: info: ------------------------------ 250ms Mar 11 16:02:28 volumio volumio[3766]: info: ------------------------------ 249ms Mar 11 16:02:28 volumio volumio[3766]: info: ------------------------------ 247ms Mar 11 16:02:30 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 11 16:02:30 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 11 16:02:30 volumio volumio[3766]: info: Discovery: Getting this device information Mar 11 16:02:30 volumio volumio[3766]: info: CoreCommandRouter::volumioGetState Mar 11 16:02:30 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:30 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 11 16:02:31 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 11 16:02:31 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Mar 11 16:02:31 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand update Mar 11 16:02:31 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:31 volumio volumio[3766]: info: Mar 11 16:02:31 volumio volumio[3766]: ---------------------------- MPD announces state update: update Mar 11 16:02:31 volumio volumio[3766]: info: sendMpdCommand update took 4 milliseconds Mar 11 16:02:31 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:02:31 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:31 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:31 volumio volumio[3766]: info: Mar 11 16:02:31 volumio volumio[3766]: ---------------------------- MPD announces state update: update Mar 11 16:02:31 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:02:31 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:31 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:31 volumio volumio[3766]: info: Mar 11 16:02:31 volumio volumio[3766]: ---------------------------- MPD announces state update: update Mar 11 16:02:31 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:02:31 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:31 volumio volumio[3766]: info: sendMpdCommand status took 13 milliseconds Mar 11 16:02:31 volumio volumio[3766]: info: sendMpdCommand status took 10 milliseconds Mar 11 16:02:31 volumio volumio[3766]: info: sendMpdCommand status took 9 milliseconds Mar 11 16:02:31 volumio volumio[3766]: info: sendMpdCommand status took 7 milliseconds Mar 11 16:02:31 volumio volumio[3766]: info: sendMpdCommand status took 7 milliseconds Mar 11 16:02:31 volumio volumio[3766]: info: sendMpdCommand status took 6 milliseconds Mar 11 16:02:31 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:31 volumio volumio[3766]: info: Command Router : Notfying DB Updatetrue Mar 11 16:02:31 volumio volumio[3766]: info: CoreCommandRouter::Close All Modals sent Mar 11 16:02:31 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:31 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:31 volumio volumio[3766]: info: Command Router : Notfying DB Updatetrue Mar 11 16:02:31 volumio volumio[3766]: info: CoreCommandRouter::Close All Modals sent Mar 11 16:02:31 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:31 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:31 volumio volumio[3766]: info: Command Router : Notfying DB Updatetrue Mar 11 16:02:31 volumio volumio[3766]: info: CoreCommandRouter::Close All Modals sent Mar 11 16:02:31 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:31 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:02:31 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:02:31 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:31 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:31 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:31 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:31 volumio volumio[3766]: 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} Mar 11 16:02:31 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:02:31 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:02:31 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:02:31 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:31 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:31 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 16:02:31 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:31 volumio volumio[3766]: info: No code Mar 11 16:02:31 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:31 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:31 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:31 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:02:31 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:02:31 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:31 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:31 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:31 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:31 volumio volumio[3766]: 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} Mar 11 16:02:31 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:02:31 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:02:31 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:02:31 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:31 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:31 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:31 volumio volumio[3766]: info: No code Mar 11 16:02:31 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:31 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:31 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:31 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:02:31 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:02:31 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:31 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:31 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:31 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:31 volumio volumio[3766]: 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} Mar 11 16:02:31 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:02:31 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:02:31 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:02:31 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:31 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:31 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:31 volumio volumio[3766]: info: No code Mar 11 16:02:31 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:31 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:31 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:31 volumio volumio[3766]: info: ------------------------------ 267ms Mar 11 16:02:31 volumio volumio[3766]: info: ------------------------------ 264ms Mar 11 16:02:31 volumio volumio[3766]: info: ------------------------------ 262ms Mar 11 16:02:31 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:31 volumio volumio[3766]: info: Mar 11 16:02:31 volumio volumio[3766]: ---------------------------- MPD announces state update: update Mar 11 16:02:31 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:02:31 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:31 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:31 volumio volumio[3766]: info: Mar 11 16:02:31 volumio volumio[3766]: ---------------------------- MPD announces state update: update Mar 11 16:02:31 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:02:31 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:31 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:31 volumio volumio[3766]: info: Mar 11 16:02:31 volumio volumio[3766]: ---------------------------- MPD announces state update: update Mar 11 16:02:31 volumio volumio[3766]: info: ControllerMpd::getState Mar 11 16:02:31 volumio volumio[3766]: verbose: ControllerMpd::sendMpdCommand status Mar 11 16:02:31 volumio volumio[3766]: info: sendMpdCommand status took 23 milliseconds Mar 11 16:02:31 volumio volumio[3766]: info: sendMpdCommand status took 21 milliseconds Mar 11 16:02:31 volumio volumio[3766]: info: sendMpdCommand status took 7 milliseconds Mar 11 16:02:31 volumio volumio[3766]: info: sendMpdCommand status took 6 milliseconds Mar 11 16:02:31 volumio volumio[3766]: info: sendMpdCommand status took 5 milliseconds Mar 11 16:02:31 volumio volumio[3766]: info: sendMpdCommand status took 4 milliseconds Mar 11 16:02:31 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:31 volumio volumio[3766]: info: Command Router : Notfying DB Updatefalse Mar 11 16:02:31 volumio volumio[3766]: info: CoreCommandRouter::Close All Modals sent Mar 11 16:02:31 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:31 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:31 volumio volumio[3766]: info: Command Router : Notfying DB Updatefalse Mar 11 16:02:31 volumio volumio[3766]: info: CoreCommandRouter::Close All Modals sent Mar 11 16:02:31 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:31 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:32 volumio volumio[3766]: info: Command Router : Notfying DB Updatefalse Mar 11 16:02:32 volumio volumio[3766]: info: CoreCommandRouter::Close All Modals sent Mar 11 16:02:32 volumio volumio[3766]: verbose: ControllerMpd::parseState Mar 11 16:02:32 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:02:32 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:02:32 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:32 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:32 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:32 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:32 volumio volumio[3766]: 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} Mar 11 16:02:32 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:02:32 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:02:32 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:02:32 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:32 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:32 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 16:02:32 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:32 volumio volumio[3766]: info: No code Mar 11 16:02:32 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:32 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:32 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:32 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:02:32 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:02:32 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:32 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:32 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:32 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:32 volumio volumio[3766]: 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} Mar 11 16:02:32 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:02:32 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:02:32 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:02:32 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:32 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:32 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:32 volumio volumio[3766]: info: No code Mar 11 16:02:32 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:32 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:32 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:32 volumio volumio[3766]: info: ControllerMpd::pushState Mar 11 16:02:32 volumio volumio[3766]: info: CoreCommandRouter::servicePushState Mar 11 16:02:32 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:32 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:32 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:32 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:32 volumio volumio[3766]: 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} Mar 11 16:02:32 volumio volumio[3766]: verbose: CURRENT POSITION 0 Mar 11 16:02:32 volumio volumio[3766]: info: CoreStateMachine::syncState stateService stop Mar 11 16:02:32 volumio volumio[3766]: info: CoreStateMachine::syncState currentStatus stop Mar 11 16:02:32 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:32 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:32 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:32 volumio volumio[3766]: info: No code Mar 11 16:02:32 volumio volumio[3766]: info: CoreStateMachine::pushState Mar 11 16:02:32 volumio volumio[3766]: info: CorePlayQueue::getTrack 0 Mar 11 16:02:32 volumio volumio[3766]: info: CoreCommandRouter::volumioPushState Mar 11 16:02:32 volumio volumio[3766]: info: ------------------------------ 262ms Mar 11 16:02:32 volumio volumio[3766]: info: ------------------------------ 246ms Mar 11 16:02:32 volumio volumio[3766]: info: ------------------------------ 244ms Mar 11 16:02:35 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 11 16:02:39 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 11 16:02:43 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 11 16:02:43 volumio volumio[3766]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Mar 11 16:02:43 volumio volumio[3766]: info: Share 又又 successfully unmounted Mar 11 16:02:43 volumio sudo[7718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.0.89:g/Chinese hires /mnt/NAS/又又 Mar 11 16:02:43 volumio sudo[7718]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 16:02:46 volumio volumio[3766]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 11 16:02:46 volumio volumio[3766]: Error: connect ETIMEDOUT 69.171.224.36:443 Mar 11 16:02:46 volumio volumio[3766]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 11 16:02:46 volumio volumio[3766]: errno: -110, Mar 11 16:02:46 volumio volumio[3766]: code: 'ETIMEDOUT', Mar 11 16:02:46 volumio volumio[3766]: syscall: 'connect', Mar 11 16:02:46 volumio volumio[3766]: address: '69.171.224.36', Mar 11 16:02:46 volumio volumio[3766]: port: 443 Mar 11 16:02:46 volumio volumio[3766]: } Mar 11 16:02:46 volumio volumio[3766]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 11 16:02:46 volumio sudo[7733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-11 16:01 Mar 11 16:02:46 volumio sudo[7733]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"