-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Sat 2026-04-11 20:11:45 CST. -- Apr 11 20:10:00 volumio volumio[5936]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 11 20:10:00 volumio volumio[5936]: info: Completed starting MyVolumio Plugin Apr 11 20:10:00 volumio volumio[5936]: [Metrics] CommandRouter: 36s 691.58ms Apr 11 20:10:00 volumio volumio[5936]: info: CoreCommandRouter::volumiosetStartupVolume Apr 11 20:10:00 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 20:10:00 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 20:10:00 volumio volumio[5936]: info: CoreCommandRouter::Close All Modals sent Apr 11 20:10:00 volumio volumio[5936]: info: CoreCommandRouter::Close All Modals sent Apr 11 20:10:00 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 11 20:10:01 volumio volumio[5936]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Dolores%20Keane/A%20Woman%27s%20Heart/4371885f-6e04-4add-80e1-3b19057c1ba0.jpg' Apr 11 20:10:01 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 11 20:10:01 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 20:10:01 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 11 20:10:03 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 20:10:03 volumio volumio[5936]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Mary%20Black/A%20Woman%27s%20Heart/922eb826-8541-4506-bd37-2e38ab9bdd7e.jpg' Apr 11 20:10:05 volumio volumio[5936]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Frances%20Black/A%20Woman%27s%20Heart/a49631cd-13fc-4aba-a2aa-3597350ee1ff.jpg' Apr 11 20:10:05 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 11 20:10:07 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 20:10:07 volumio volumio[5936]: info: BOOT COMPLETED Apr 11 20:10:07 volumio volumio[5936]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Maura%20O%27Connell/A%20Woman%27s%20Heart/860b02a3-e12c-4eb4-b611-e0d849a2b2cd.jpg' Apr 11 20:10:08 volumio ntpd[826]: 116.203.151.74 local addr 192.168.199.161 -> Apr 11 20:10:11 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 20:10:16 volumio volumio-remote-updater[643]: No test mode Apr 11 20:10:16 volumio volumio-remote-updater[643]: No alpha test mode Apr 11 20:10:16 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 11 20:10:18 volumio volumio[5936]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 11 20:10:18 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 11 20:10:30 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 20:10:31 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 11 20:10:32 volumio volumio[5936]: verbose: ControllerMpd::sendMpdCommand rescan Apr 11 20:10:32 volumio volumio[5936]: verbose: ControllerMpd::sendMpdCommand status Apr 11 20:10:32 volumio volumio[5936]: info: Apr 11 20:10:32 volumio volumio[5936]: ---------------------------- MPD announces state update: update Apr 11 20:10:32 volumio volumio[5936]: info: sendMpdCommand rescan took 2 milliseconds Apr 11 20:10:32 volumio volumio[5936]: info: ControllerMpd::getState Apr 11 20:10:32 volumio volumio[5936]: verbose: ControllerMpd::sendMpdCommand status Apr 11 20:10:32 volumio volumio[5936]: verbose: ControllerMpd::sendMpdCommand status Apr 11 20:10:32 volumio volumio[5936]: info: Apr 11 20:10:32 volumio volumio[5936]: ---------------------------- MPD announces state update: update Apr 11 20:10:32 volumio volumio[5936]: info: ControllerMpd::getState Apr 11 20:10:32 volumio volumio[5936]: verbose: ControllerMpd::sendMpdCommand status Apr 11 20:10:32 volumio volumio[5936]: verbose: ControllerMpd::sendMpdCommand status Apr 11 20:10:32 volumio volumio[5936]: info: Apr 11 20:10:32 volumio volumio[5936]: ---------------------------- MPD announces state update: update Apr 11 20:10:32 volumio volumio[5936]: info: ControllerMpd::getState Apr 11 20:10:32 volumio volumio[5936]: verbose: ControllerMpd::sendMpdCommand status Apr 11 20:10:32 volumio volumio[5936]: verbose: ControllerMpd::sendMpdCommand status Apr 11 20:10:32 volumio volumio[5936]: info: Apr 11 20:10:32 volumio volumio[5936]: ---------------------------- MPD announces state update: update Apr 11 20:10:32 volumio volumio[5936]: info: ControllerMpd::getState Apr 11 20:10:32 volumio volumio[5936]: verbose: ControllerMpd::sendMpdCommand status Apr 11 20:10:32 volumio volumio[5936]: info: sendMpdCommand status took 6 milliseconds Apr 11 20:10:32 volumio volumio[5936]: info: sendMpdCommand status took 5 milliseconds Apr 11 20:10:32 volumio volumio[5936]: info: sendMpdCommand status took 4 milliseconds Apr 11 20:10:32 volumio volumio[5936]: info: sendMpdCommand status took 4 milliseconds Apr 11 20:10:32 volumio volumio[5936]: info: sendMpdCommand status took 3 milliseconds Apr 11 20:10:32 volumio volumio[5936]: info: sendMpdCommand status took 3 milliseconds Apr 11 20:10:32 volumio volumio[5936]: verbose: ControllerMpd::parseState Apr 11 20:10:32 volumio volumio[5936]: info: Command Router : Notfying DB Updatefalse Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::Close All Modals sent Apr 11 20:10:32 volumio volumio[5936]: verbose: ControllerMpd::parseState Apr 11 20:10:32 volumio volumio[5936]: verbose: ControllerMpd::parseState Apr 11 20:10:32 volumio volumio[5936]: info: Command Router : Notfying DB Updatefalse Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::Close All Modals sent Apr 11 20:10:32 volumio volumio[5936]: verbose: ControllerMpd::parseState Apr 11 20:10:32 volumio volumio[5936]: verbose: ControllerMpd::parseState Apr 11 20:10:32 volumio volumio[5936]: info: Command Router : Notfying DB Updatefalse Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::Close All Modals sent Apr 11 20:10:32 volumio volumio[5936]: verbose: ControllerMpd::parseState Apr 11 20:10:32 volumio volumio[5936]: info: ControllerMpd::pushState Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::servicePushState Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:32 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:32 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:32 volumio volumio[5936]: 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} Apr 11 20:10:32 volumio volumio[5936]: verbose: CURRENT POSITION 0 Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::syncState stateService stop Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::syncState currentStatus stop Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:32 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:32 volumio volumio[5936]: info: No code Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:32 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:32 volumio volumio[5936]: info: ControllerMpd::pushState Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::servicePushState Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:32 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:32 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:32 volumio volumio[5936]: 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} Apr 11 20:10:32 volumio volumio[5936]: verbose: CURRENT POSITION 0 Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::syncState stateService stop Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::syncState currentStatus stop Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:32 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:32 volumio volumio[5936]: info: No code Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:32 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:32 volumio volumio[5936]: info: ControllerMpd::pushState Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::servicePushState Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:32 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:32 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:32 volumio volumio[5936]: 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} Apr 11 20:10:32 volumio volumio[5936]: verbose: CURRENT POSITION 0 Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::syncState stateService stop Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::syncState currentStatus stop Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:32 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:32 volumio volumio[5936]: info: No code Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:32 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:32 volumio volumio[5936]: info: ------------------------------ 141ms Apr 11 20:10:32 volumio volumio[5936]: info: ------------------------------ 140ms Apr 11 20:10:32 volumio volumio[5936]: info: ------------------------------ 141ms Apr 11 20:10:32 volumio volumio[5936]: verbose: ControllerMpd::sendMpdCommand status Apr 11 20:10:32 volumio volumio[5936]: info: Apr 11 20:10:32 volumio volumio[5936]: ---------------------------- MPD announces state update: update Apr 11 20:10:32 volumio volumio[5936]: info: ControllerMpd::getState Apr 11 20:10:32 volumio volumio[5936]: verbose: ControllerMpd::sendMpdCommand status Apr 11 20:10:32 volumio volumio[5936]: verbose: ControllerMpd::sendMpdCommand status Apr 11 20:10:32 volumio volumio[5936]: info: Apr 11 20:10:32 volumio volumio[5936]: ---------------------------- MPD announces state update: update Apr 11 20:10:32 volumio volumio[5936]: info: ControllerMpd::getState Apr 11 20:10:32 volumio volumio[5936]: verbose: ControllerMpd::sendMpdCommand status Apr 11 20:10:32 volumio volumio[5936]: info: sendMpdCommand status took 146 milliseconds Apr 11 20:10:32 volumio volumio[5936]: info: sendMpdCommand status took 146 milliseconds Apr 11 20:10:32 volumio volumio[5936]: info: sendMpdCommand status took 5 milliseconds Apr 11 20:10:32 volumio volumio[5936]: info: sendMpdCommand status took 4 milliseconds Apr 11 20:10:32 volumio volumio[5936]: info: sendMpdCommand status took 3 milliseconds Apr 11 20:10:32 volumio volumio[5936]: info: sendMpdCommand status took 2 milliseconds Apr 11 20:10:32 volumio volumio[5936]: verbose: ControllerMpd::parseState Apr 11 20:10:32 volumio volumio[5936]: info: Command Router : Notfying DB Updatefalse Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::Close All Modals sent Apr 11 20:10:32 volumio volumio[5936]: verbose: ControllerMpd::parseState Apr 11 20:10:32 volumio volumio[5936]: verbose: ControllerMpd::parseState Apr 11 20:10:32 volumio volumio[5936]: info: Command Router : Notfying DB Updatefalse Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::Close All Modals sent Apr 11 20:10:32 volumio volumio[5936]: verbose: ControllerMpd::parseState Apr 11 20:10:32 volumio volumio[5936]: verbose: ControllerMpd::parseState Apr 11 20:10:32 volumio volumio[5936]: info: Command Router : Notfying DB Updatefalse Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::Close All Modals sent Apr 11 20:10:32 volumio volumio[5936]: verbose: ControllerMpd::parseState Apr 11 20:10:32 volumio volumio[5936]: info: ControllerMpd::pushState Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::servicePushState Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:32 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:32 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:32 volumio volumio[5936]: 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} Apr 11 20:10:32 volumio volumio[5936]: verbose: CURRENT POSITION 0 Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::syncState stateService stop Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::syncState currentStatus stop Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:32 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:32 volumio volumio[5936]: info: No code Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:32 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:32 volumio volumio[5936]: info: ControllerMpd::pushState Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::servicePushState Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:32 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:32 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:32 volumio volumio[5936]: 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} Apr 11 20:10:32 volumio volumio[5936]: verbose: CURRENT POSITION 0 Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::syncState stateService stop Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::syncState currentStatus stop Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:32 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:32 volumio volumio[5936]: info: No code Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:32 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:32 volumio volumio[5936]: info: ControllerMpd::pushState Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::servicePushState Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:32 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:32 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:32 volumio volumio[5936]: 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} Apr 11 20:10:32 volumio volumio[5936]: verbose: CURRENT POSITION 0 Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::syncState stateService stop Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::syncState currentStatus stop Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:32 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:32 volumio volumio[5936]: info: No code Apr 11 20:10:32 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:32 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:32 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:32 volumio volumio[5936]: info: ------------------------------ 267ms Apr 11 20:10:32 volumio volumio[5936]: info: ------------------------------ 126ms Apr 11 20:10:32 volumio volumio[5936]: info: ------------------------------ 125ms Apr 11 20:10:35 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 20:10:35 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 20:10:35 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 20:10:35 volumio volumio[5936]: info: Discovery: Getting this device information Apr 11 20:10:35 volumio volumio[5936]: info: CoreCommandRouter::volumioGetState Apr 11 20:10:35 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:35 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 20:10:40 volumio volumio[5936]: verbose: New Socket.io Connection to 192.168.199.161 from 192.168.199.101 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 5 Apr 11 20:10:40 volumio volumio[5936]: info: CoreCommandRouter::volumioGetState Apr 11 20:10:40 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:40 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 11 20:10:40 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 11 20:10:41 volumio volumio[5936]: verbose: New Socket.io Connection to 192.168.199.161 from 192.168.199.101 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 6 Apr 11 20:10:41 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::volumioGetVisibleSources Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::volumioGetState Apr 11 20:10:42 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 11 20:10:42 volumio volumio[5936]: info: Received Get System Info Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 20:10:42 volumio volumio[5936]: info: Discovery: Getting this device information Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::volumioGetState Apr 11 20:10:42 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::volumioGetState Apr 11 20:10:42 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:42 volumio volumio[5936]: info: Listing playlists Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::volumioGetQueue Apr 11 20:10:42 volumio volumio[5936]: info: CoreStateMachine::getQueue Apr 11 20:10:42 volumio volumio[5936]: info: CorePlayQueue::getQueue Apr 11 20:10:42 volumio volumio[5936]: info: Received Get System Info Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 20:10:42 volumio volumio[5936]: info: Discovery: Getting this device information Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::volumioGetState Apr 11 20:10:42 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::volumioGetState Apr 11 20:10:42 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::volumioGetVisibleSources Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::volumioGetState Apr 11 20:10:42 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 11 20:10:42 volumio volumio[5936]: info: Received Get System Info Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 20:10:42 volumio volumio[5936]: info: Discovery: Getting this device information Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::volumioGetState Apr 11 20:10:42 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::volumioGetState Apr 11 20:10:42 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:42 volumio volumio[5936]: info: Listing playlists Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::volumioGetQueue Apr 11 20:10:42 volumio volumio[5936]: info: CoreStateMachine::getQueue Apr 11 20:10:42 volumio volumio[5936]: info: CorePlayQueue::getQueue Apr 11 20:10:42 volumio volumio[5936]: info: Received Get System Info Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 20:10:42 volumio volumio[5936]: info: Discovery: Getting this device information Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::volumioGetState Apr 11 20:10:42 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::volumioGetState Apr 11 20:10:42 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::volumioGetVisibleSources Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::volumioGetState Apr 11 20:10:42 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 11 20:10:42 volumio volumio[5936]: info: Received Get System Info Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 20:10:42 volumio volumio[5936]: info: Discovery: Getting this device information Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::volumioGetState Apr 11 20:10:42 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::volumioGetState Apr 11 20:10:42 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:42 volumio volumio[5936]: info: Listing playlists Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::volumioGetQueue Apr 11 20:10:42 volumio volumio[5936]: info: CoreStateMachine::getQueue Apr 11 20:10:42 volumio volumio[5936]: info: CorePlayQueue::getQueue Apr 11 20:10:42 volumio volumio[5936]: info: Received Get System Info Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 20:10:42 volumio volumio[5936]: info: Discovery: Getting this device information Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::volumioGetState Apr 11 20:10:42 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::volumioGetState Apr 11 20:10:42 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:42 volumio volumio[5936]: info: Received Get System Info Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 20:10:42 volumio volumio[5936]: info: Discovery: Getting this device information Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::volumioGetState Apr 11 20:10:42 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::volumioGetState Apr 11 20:10:42 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:42 volumio volumio[5936]: info: Received Get System Info Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 20:10:42 volumio volumio[5936]: info: Discovery: Getting this device information Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::volumioGetState Apr 11 20:10:42 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 20:10:42 volumio volumio[5936]: info: CoreCommandRouter::volumioGetState Apr 11 20:10:42 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:43 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 11 20:10:43 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 11 20:10:45 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 20:10:45 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 20:10:45 volumio volumio[5936]: info: Discovery: Getting this device information Apr 11 20:10:45 volumio volumio[5936]: info: CoreCommandRouter::volumioGetState Apr 11 20:10:45 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:45 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 20:10:48 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 20:10:48 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 20:10:51 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 11 20:10:52 volumio volumio[5936]: verbose: ControllerMpd::sendMpdCommand rescan Apr 11 20:10:52 volumio volumio[5936]: verbose: ControllerMpd::sendMpdCommand status Apr 11 20:10:52 volumio volumio[5936]: info: Apr 11 20:10:52 volumio volumio[5936]: ---------------------------- MPD announces state update: update Apr 11 20:10:52 volumio volumio[5936]: info: sendMpdCommand rescan took 2 milliseconds Apr 11 20:10:52 volumio volumio[5936]: info: ControllerMpd::getState Apr 11 20:10:52 volumio volumio[5936]: verbose: ControllerMpd::sendMpdCommand status Apr 11 20:10:52 volumio volumio[5936]: verbose: ControllerMpd::sendMpdCommand status Apr 11 20:10:52 volumio volumio[5936]: info: Apr 11 20:10:52 volumio volumio[5936]: ---------------------------- MPD announces state update: update Apr 11 20:10:52 volumio volumio[5936]: info: ControllerMpd::getState Apr 11 20:10:52 volumio volumio[5936]: verbose: ControllerMpd::sendMpdCommand status Apr 11 20:10:52 volumio volumio[5936]: verbose: ControllerMpd::sendMpdCommand status Apr 11 20:10:52 volumio volumio[5936]: info: Apr 11 20:10:52 volumio volumio[5936]: ---------------------------- MPD announces state update: update Apr 11 20:10:52 volumio volumio[5936]: info: ControllerMpd::getState Apr 11 20:10:52 volumio volumio[5936]: verbose: ControllerMpd::sendMpdCommand status Apr 11 20:10:52 volumio volumio[5936]: verbose: ControllerMpd::sendMpdCommand status Apr 11 20:10:52 volumio volumio[5936]: info: Apr 11 20:10:52 volumio volumio[5936]: ---------------------------- MPD announces state update: update Apr 11 20:10:52 volumio volumio[5936]: info: ControllerMpd::getState Apr 11 20:10:52 volumio volumio[5936]: verbose: ControllerMpd::sendMpdCommand status Apr 11 20:10:52 volumio volumio[5936]: info: sendMpdCommand status took 5 milliseconds Apr 11 20:10:52 volumio volumio[5936]: info: sendMpdCommand status took 5 milliseconds Apr 11 20:10:52 volumio volumio[5936]: info: sendMpdCommand status took 4 milliseconds Apr 11 20:10:52 volumio volumio[5936]: info: sendMpdCommand status took 3 milliseconds Apr 11 20:10:52 volumio volumio[5936]: info: sendMpdCommand status took 3 milliseconds Apr 11 20:10:52 volumio volumio[5936]: info: sendMpdCommand status took 2 milliseconds Apr 11 20:10:52 volumio volumio[5936]: verbose: ControllerMpd::parseState Apr 11 20:10:52 volumio volumio[5936]: info: Command Router : Notfying DB Updatefalse Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::Close All Modals sent Apr 11 20:10:52 volumio volumio[5936]: verbose: ControllerMpd::parseState Apr 11 20:10:52 volumio volumio[5936]: verbose: ControllerMpd::parseState Apr 11 20:10:52 volumio volumio[5936]: info: Command Router : Notfying DB Updatefalse Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::Close All Modals sent Apr 11 20:10:52 volumio volumio[5936]: verbose: ControllerMpd::parseState Apr 11 20:10:52 volumio volumio[5936]: verbose: ControllerMpd::parseState Apr 11 20:10:52 volumio volumio[5936]: info: Command Router : Notfying DB Updatefalse Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::Close All Modals sent Apr 11 20:10:52 volumio volumio[5936]: verbose: ControllerMpd::parseState Apr 11 20:10:52 volumio volumio[5936]: info: ControllerMpd::pushState Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::servicePushState Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:52 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:52 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:52 volumio volumio[5936]: 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} Apr 11 20:10:52 volumio volumio[5936]: verbose: CURRENT POSITION 0 Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::syncState stateService stop Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::syncState currentStatus stop Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:52 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:52 volumio volumio[5936]: info: No code Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:52 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:52 volumio volumio[5936]: info: ControllerMpd::pushState Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::servicePushState Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:52 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:52 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:52 volumio volumio[5936]: 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} Apr 11 20:10:52 volumio volumio[5936]: verbose: CURRENT POSITION 0 Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::syncState stateService stop Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::syncState currentStatus stop Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:52 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:52 volumio volumio[5936]: info: No code Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:52 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:52 volumio volumio[5936]: info: ControllerMpd::pushState Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::servicePushState Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:52 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:52 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:52 volumio volumio[5936]: 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} Apr 11 20:10:52 volumio volumio[5936]: verbose: CURRENT POSITION 0 Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::syncState stateService stop Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::syncState currentStatus stop Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:52 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:52 volumio volumio[5936]: info: No code Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:52 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:52 volumio volumio[5936]: info: ------------------------------ 164ms Apr 11 20:10:52 volumio volumio[5936]: info: ------------------------------ 163ms Apr 11 20:10:52 volumio volumio[5936]: info: ------------------------------ 163ms Apr 11 20:10:52 volumio volumio[5936]: verbose: ControllerMpd::sendMpdCommand status Apr 11 20:10:52 volumio volumio[5936]: info: Apr 11 20:10:52 volumio volumio[5936]: ---------------------------- MPD announces state update: update Apr 11 20:10:52 volumio volumio[5936]: info: ControllerMpd::getState Apr 11 20:10:52 volumio volumio[5936]: verbose: ControllerMpd::sendMpdCommand status Apr 11 20:10:52 volumio volumio[5936]: verbose: ControllerMpd::sendMpdCommand status Apr 11 20:10:52 volumio volumio[5936]: info: Apr 11 20:10:52 volumio volumio[5936]: ---------------------------- MPD announces state update: update Apr 11 20:10:52 volumio volumio[5936]: info: ControllerMpd::getState Apr 11 20:10:52 volumio volumio[5936]: verbose: ControllerMpd::sendMpdCommand status Apr 11 20:10:52 volumio volumio[5936]: info: sendMpdCommand status took 166 milliseconds Apr 11 20:10:52 volumio volumio[5936]: info: sendMpdCommand status took 165 milliseconds Apr 11 20:10:52 volumio volumio[5936]: info: sendMpdCommand status took 4 milliseconds Apr 11 20:10:52 volumio volumio[5936]: info: sendMpdCommand status took 4 milliseconds Apr 11 20:10:52 volumio volumio[5936]: info: sendMpdCommand status took 3 milliseconds Apr 11 20:10:52 volumio volumio[5936]: info: sendMpdCommand status took 2 milliseconds Apr 11 20:10:52 volumio volumio[5936]: verbose: ControllerMpd::parseState Apr 11 20:10:52 volumio volumio[5936]: info: Command Router : Notfying DB Updatefalse Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::Close All Modals sent Apr 11 20:10:52 volumio volumio[5936]: verbose: ControllerMpd::parseState Apr 11 20:10:52 volumio volumio[5936]: verbose: ControllerMpd::parseState Apr 11 20:10:52 volumio volumio[5936]: info: Command Router : Notfying DB Updatefalse Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::Close All Modals sent Apr 11 20:10:52 volumio volumio[5936]: verbose: ControllerMpd::parseState Apr 11 20:10:52 volumio volumio[5936]: verbose: ControllerMpd::parseState Apr 11 20:10:52 volumio volumio[5936]: info: Command Router : Notfying DB Updatefalse Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::Close All Modals sent Apr 11 20:10:52 volumio volumio[5936]: verbose: ControllerMpd::parseState Apr 11 20:10:52 volumio volumio[5936]: info: ControllerMpd::pushState Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::servicePushState Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:52 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:52 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:52 volumio volumio[5936]: 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} Apr 11 20:10:52 volumio volumio[5936]: verbose: CURRENT POSITION 0 Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::syncState stateService stop Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::syncState currentStatus stop Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:52 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:52 volumio volumio[5936]: info: No code Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:52 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:52 volumio volumio[5936]: info: ControllerMpd::pushState Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::servicePushState Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:52 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:52 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:52 volumio volumio[5936]: 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} Apr 11 20:10:52 volumio volumio[5936]: verbose: CURRENT POSITION 0 Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::syncState stateService stop Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::syncState currentStatus stop Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:52 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:52 volumio volumio[5936]: info: No code Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:52 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:52 volumio volumio[5936]: info: ControllerMpd::pushState Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::servicePushState Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:52 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:52 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:52 volumio volumio[5936]: 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} Apr 11 20:10:52 volumio volumio[5936]: verbose: CURRENT POSITION 0 Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::syncState stateService stop Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::syncState currentStatus stop Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:52 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:52 volumio volumio[5936]: info: No code Apr 11 20:10:52 volumio volumio[5936]: info: CoreStateMachine::pushState Apr 11 20:10:52 volumio volumio[5936]: info: CorePlayQueue::getTrack 0 Apr 11 20:10:52 volumio volumio[5936]: info: CoreCommandRouter::volumioPushState Apr 11 20:10:52 volumio volumio[5936]: info: ------------------------------ 295ms Apr 11 20:10:52 volumio volumio[5936]: info: ------------------------------ 133ms Apr 11 20:10:52 volumio volumio[5936]: info: ------------------------------ 133ms Apr 11 20:10:55 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 20:10:59 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 20:11:03 volumio volumio[5936]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 11 20:11:45 volumio volumio[5936]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 20:11:45 volumio volumio[5936]: Error: connect ETIMEDOUT 103.200.31.172:443 Apr 11 20:11:45 volumio volumio[5936]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Apr 11 20:11:45 volumio volumio[5936]: errno: -110, Apr 11 20:11:45 volumio volumio[5936]: code: 'ETIMEDOUT', Apr 11 20:11:45 volumio volumio[5936]: syscall: 'connect', Apr 11 20:11:45 volumio volumio[5936]: address: '103.200.31.172', Apr 11 20:11:45 volumio volumio[5936]: port: 443 Apr 11 20:11:45 volumio volumio[5936]: } Apr 11 20:11:45 volumio volumio[5936]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 20:11:45 volumio sudo[6496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-11 20:10 Apr 11 20:11:45 volumio sudo[6496]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"