-- Logs begin at Sun 2024-08-25 17:34:46 +07, end at Mon 2024-08-26 09:56:49 +07. -- Aug 26 09:55:00 volumio volumio[1037]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 09:55:00 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 09:55:00 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Aug 26 09:55:00 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:00 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 26 09:55:00 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 26 09:55:00 volumio volumio[1037]: info: Received Get System Info Aug 26 09:55:00 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 09:55:00 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 09:55:00 volumio volumio[1037]: info: Discovery: Getting this device information Aug 26 09:55:00 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Aug 26 09:55:00 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:00 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 09:55:00 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Aug 26 09:55:00 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:00 volumio volumio[1037]: info: Listing playlists Aug 26 09:55:00 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 26 09:55:00 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 26 09:55:00 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 26 09:55:00 volumio volumio[1037]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 26 09:55:01 volumio volumio[1037]: info: Setting Geolocation for MyVolumio to eu4 Aug 26 09:55:01 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 09:55:01 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 09:55:01 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 09:55:01 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 09:55:01 volumio volumio[1037]: info: Received Get System Info Aug 26 09:55:01 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 09:55:01 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 09:55:01 volumio volumio[1037]: info: Discovery: Getting this device information Aug 26 09:55:01 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Aug 26 09:55:01 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:01 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 09:55:01 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 26 09:55:01 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 26 09:55:02 volumio volumio[1037]: info: Updating MyVolumio device info Aug 26 09:55:02 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 09:55:02 volumio volumio[1037]: info: CoreCommandRouter::volumioGetQueue Aug 26 09:55:02 volumio volumio[1037]: info: CoreStateMachine::getQueue Aug 26 09:55:02 volumio volumio[1037]: info: CorePlayQueue::getQueue Aug 26 09:55:06 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 26 09:55:06 volumio volumio[1037]: info: CURURI: albums:// Aug 26 09:55:06 volumio volumio[1037]: info: listAlbums - loading Albums from cache Aug 26 09:55:06 volumio volumio[1037]: info: Preload queue cleared Aug 26 09:55:07 volumio volumio[1037]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 26 09:55:07 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Aug 26 09:55:07 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:11 volumio volumio[1037]: info: MYVOLUMIO: Adding device Aug 26 09:55:11 volumio volumio[1037]: info: MYVOLUMIO: Evaluating Server Aug 26 09:55:11 volumio sudo[1889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 09:55:11 volumio sudo[1889]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 09:55:11 volumio sudo[1889]: pam_unix(sudo:session): session closed for user root Aug 26 09:55:11 volumio sudo[1891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 09:55:11 volumio sudo[1891]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 09:55:11 volumio sudo[1891]: pam_unix(sudo:session): session closed for user root Aug 26 09:55:11 volumio volumio[1037]: verbose: New Socket.io Connection to 192.168.88.40 from 192.168.88.17 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 26 09:55:12 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 26 09:55:12 volumio volumio[1037]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 09:55:12 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 09:55:12 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Aug 26 09:55:12 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:12 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 26 09:55:12 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 26 09:55:12 volumio volumio[1037]: info: Received Get System Info Aug 26 09:55:12 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 09:55:12 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 09:55:12 volumio volumio[1037]: info: Discovery: Getting this device information Aug 26 09:55:12 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Aug 26 09:55:12 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:12 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 09:55:12 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Aug 26 09:55:12 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:12 volumio volumio[1037]: info: Listing playlists Aug 26 09:55:12 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 26 09:55:12 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 26 09:55:12 volumio volumio[1037]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 26 09:55:12 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 26 09:55:13 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 09:55:13 volumio volumio[1037]: info: Received Get System Info Aug 26 09:55:13 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 09:55:13 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 09:55:13 volumio volumio[1037]: info: Discovery: Getting this device information Aug 26 09:55:13 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Aug 26 09:55:13 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:13 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 09:55:13 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 26 09:55:13 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 26 09:55:15 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 09:55:15 volumio volumio[1037]: info: Received Get System Info Aug 26 09:55:15 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 09:55:15 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 09:55:15 volumio volumio[1037]: info: Discovery: Getting this device information Aug 26 09:55:15 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Aug 26 09:55:15 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:15 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 09:55:16 volumio volumio[1037]: info: Setting Geolocation for MyVolumio to eu7 Aug 26 09:55:16 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 09:55:16 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 09:55:17 volumio volumio[1037]: info: Received Get System Info Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 09:55:17 volumio volumio[1037]: info: Discovery: Getting this device information Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Aug 26 09:55:17 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Aug 26 09:55:17 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:17 volumio volumio[1037]: info: Listing playlists Aug 26 09:55:17 volumio volumio[1037]: info: Listing playlists Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 09:55:17 volumio volumio[1037]: info: Retrieving Cloud Streaming UI Aug 26 09:55:17 volumio volumio[1037]: info: Getting Tidal Cloud Configuration Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 09:55:17 volumio volumio[1037]: info: Getting Qobuz Cloud Configuration Aug 26 09:55:17 volumio volumio[1037]: info: Asking plugin for UI Config Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 09:55:17 volumio volumio[1037]: info: Getting Spotify Cloud Configuration Aug 26 09:55:17 volumio volumio[1037]: info: Asking plugin for UI Config Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 09:55:17 volumio volumio[1037]: info: Saving Spotify Acccount Aug 26 09:55:17 volumio volumio[1037]: info: Got Tidal Cloud Configuration Aug 26 09:55:17 volumio volumio[1037]: info: Got it Aug 26 09:55:17 volumio volumio[1037]: info: Got it Aug 26 09:55:17 volumio volumio[1037]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 09:55:17 volumio volumio[1037]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::volumioGetBrowseSources Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::volumioGetBrowseSources Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::volumioGetBrowseSources Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 09:55:17 volumio volumio[1037]: info: Received Get System Info Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 09:55:17 volumio volumio[1037]: info: Discovery: Getting this device information Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Aug 26 09:55:17 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 09:55:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 26 09:55:17 volumio volumio[1037]: info: Updating MyVolumio device info Aug 26 09:55:18 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 09:55:18 volumio volumio[1037]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 26 09:55:18 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 26 09:55:18 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand rescan Aug 26 09:55:18 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:18 volumio volumio[1037]: info: Aug 26 09:55:18 volumio volumio[1037]: ---------------------------- MPD announces state update: update Aug 26 09:55:18 volumio volumio[1037]: info: sendMpdCommand rescan took 11 milliseconds Aug 26 09:55:18 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:18 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:19 volumio volumio[1037]: info: sendMpdCommand status took 12 milliseconds Aug 26 09:55:19 volumio volumio[1037]: info: sendMpdCommand status took 5 milliseconds Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:19 volumio volumio[1037]: info: Command Router : Notfying DB Updatetrue Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:19 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:19 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: No code Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: ------------------------------ 128ms Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:19 volumio volumio[1037]: info: Aug 26 09:55:19 volumio volumio[1037]: ---------------------------- MPD announces state update: update Aug 26 09:55:19 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:19 volumio volumio[1037]: info: Aug 26 09:55:19 volumio volumio[1037]: ---------------------------- MPD announces state update: update Aug 26 09:55:19 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:19 volumio volumio[1037]: info: MPD Database updated - AlbumList cache refreshed Aug 26 09:55:19 volumio volumio[1037]: info: Aug 26 09:55:19 volumio volumio[1037]: ---------------------------- MPD announces state update: database Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:19 volumio volumio[1037]: info: Aug 26 09:55:19 volumio volumio[1037]: ---------------------------- MPD announces state update: update Aug 26 09:55:19 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:19 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:19 volumio volumio[1037]: info: sendMpdCommand status took 17 milliseconds Aug 26 09:55:19 volumio volumio[1037]: info: sendMpdCommand status took 16 milliseconds Aug 26 09:55:19 volumio volumio[1037]: info: sendMpdCommand status took 15 milliseconds Aug 26 09:55:19 volumio volumio[1037]: info: sendMpdCommand status took 13 milliseconds Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:19 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:19 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:19 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:19 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: No code Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:19 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: No code Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: ------------------------------ 191ms Aug 26 09:55:19 volumio volumio[1037]: info: ------------------------------ 189ms Aug 26 09:55:19 volumio volumio[1037]: info: MPD Database updated - AlbumList cache refreshed Aug 26 09:55:19 volumio volumio[1037]: info: Aug 26 09:55:19 volumio volumio[1037]: ---------------------------- MPD announces state update: database Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:19 volumio volumio[1037]: info: Aug 26 09:55:19 volumio volumio[1037]: ---------------------------- MPD announces state update: update Aug 26 09:55:19 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:19 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:19 volumio volumio[1037]: info: MPD Database updated - AlbumList cache refreshed Aug 26 09:55:19 volumio volumio[1037]: info: Aug 26 09:55:19 volumio volumio[1037]: ---------------------------- MPD announces state update: database Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:19 volumio volumio[1037]: info: Aug 26 09:55:19 volumio volumio[1037]: ---------------------------- MPD announces state update: update Aug 26 09:55:19 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:19 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:19 volumio volumio[1037]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 26 09:55:19 volumio volumio[1037]: info: sendMpdCommand status took 229 milliseconds Aug 26 09:55:19 volumio volumio[1037]: info: sendMpdCommand status took 228 milliseconds Aug 26 09:55:19 volumio volumio[1037]: info: sendMpdCommand status took 227 milliseconds Aug 26 09:55:19 volumio volumio[1037]: info: sendMpdCommand status took 46 milliseconds Aug 26 09:55:19 volumio volumio[1037]: info: sendMpdCommand status took 45 milliseconds Aug 26 09:55:19 volumio volumio[1037]: info: sendMpdCommand status took 44 milliseconds Aug 26 09:55:19 volumio volumio[1037]: info: sendMpdCommand status took 41 milliseconds Aug 26 09:55:19 volumio volumio[1037]: info: sendMpdCommand status took 39 milliseconds Aug 26 09:55:19 volumio volumio[1037]: info: sendMpdCommand status took 38 milliseconds Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:19 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:19 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:19 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:19 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:19 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:19 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: No code Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:19 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: No code Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:19 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: No code Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:19 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: No code Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:19 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: No code Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:19 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: No code Aug 26 09:55:19 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:19 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:19 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:19 volumio volumio[1037]: info: ------------------------------ 580ms Aug 26 09:55:19 volumio volumio[1037]: info: ------------------------------ 580ms Aug 26 09:55:19 volumio volumio[1037]: info: ------------------------------ 398ms Aug 26 09:55:19 volumio volumio[1037]: info: ------------------------------ 399ms Aug 26 09:55:19 volumio volumio[1037]: info: ------------------------------ 395ms Aug 26 09:55:19 volumio volumio[1037]: info: ------------------------------ 395ms Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 26 09:55:20 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand rescan Aug 26 09:55:20 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:20 volumio volumio[1037]: info: Aug 26 09:55:20 volumio volumio[1037]: ---------------------------- MPD announces state update: update Aug 26 09:55:20 volumio volumio[1037]: info: sendMpdCommand rescan took 6 milliseconds Aug 26 09:55:20 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:20 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:20 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:20 volumio volumio[1037]: info: Aug 26 09:55:20 volumio volumio[1037]: ---------------------------- MPD announces state update: update Aug 26 09:55:20 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:20 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:20 volumio volumio[1037]: info: sendMpdCommand status took 7 milliseconds Aug 26 09:55:20 volumio volumio[1037]: info: sendMpdCommand status took 5 milliseconds Aug 26 09:55:20 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:20 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Aug 26 09:55:20 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:20 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:20 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:20 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:20 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:20 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:20 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:20 volumio volumio[1037]: info: No code Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:20 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:20 volumio volumio[1037]: info: ------------------------------ 96ms Aug 26 09:55:20 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:20 volumio volumio[1037]: info: Aug 26 09:55:20 volumio volumio[1037]: ---------------------------- MPD announces state update: update Aug 26 09:55:20 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:20 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:20 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:20 volumio volumio[1037]: info: Aug 26 09:55:20 volumio volumio[1037]: ---------------------------- MPD announces state update: update Aug 26 09:55:20 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:20 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:20 volumio volumio[1037]: info: sendMpdCommand status took 106 milliseconds Aug 26 09:55:20 volumio volumio[1037]: info: sendMpdCommand status took 105 milliseconds Aug 26 09:55:20 volumio volumio[1037]: info: sendMpdCommand status took 14 milliseconds Aug 26 09:55:20 volumio volumio[1037]: info: sendMpdCommand status took 12 milliseconds Aug 26 09:55:20 volumio volumio[1037]: info: sendMpdCommand status took 11 milliseconds Aug 26 09:55:20 volumio volumio[1037]: info: sendMpdCommand status took 9 milliseconds Aug 26 09:55:20 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:20 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Aug 26 09:55:20 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:20 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:20 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Aug 26 09:55:20 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:20 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:20 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Aug 26 09:55:20 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:20 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:20 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:20 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:20 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:20 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:20 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:20 volumio volumio[1037]: info: No code Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:20 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:20 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:20 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:20 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:20 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:20 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:20 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:20 volumio volumio[1037]: info: No code Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:20 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:20 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:20 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:20 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:20 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:20 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:20 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:20 volumio volumio[1037]: info: No code Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:20 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:20 volumio volumio[1037]: info: ------------------------------ 350ms Aug 26 09:55:20 volumio volumio[1037]: info: ------------------------------ 259ms Aug 26 09:55:20 volumio volumio[1037]: info: ------------------------------ 256ms Aug 26 09:55:20 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:20 volumio volumio[1037]: info: Aug 26 09:55:20 volumio volumio[1037]: ---------------------------- MPD announces state update: update Aug 26 09:55:20 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:20 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:20 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:20 volumio volumio[1037]: info: Aug 26 09:55:20 volumio volumio[1037]: ---------------------------- MPD announces state update: update Aug 26 09:55:20 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:20 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:20 volumio volumio[1037]: info: sendMpdCommand status took 29 milliseconds Aug 26 09:55:20 volumio volumio[1037]: info: sendMpdCommand status took 28 milliseconds Aug 26 09:55:20 volumio volumio[1037]: info: sendMpdCommand status took 28 milliseconds Aug 26 09:55:20 volumio volumio[1037]: info: sendMpdCommand status took 25 milliseconds Aug 26 09:55:20 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:20 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Aug 26 09:55:20 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:20 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:20 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Aug 26 09:55:20 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:20 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:20 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:20 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:20 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:20 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:20 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:20 volumio volumio[1037]: info: No code Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:20 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:20 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:20 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:20 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:20 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:20 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:20 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:20 volumio volumio[1037]: info: No code Aug 26 09:55:20 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:20 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:20 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:20 volumio volumio[1037]: info: ------------------------------ 193ms Aug 26 09:55:20 volumio volumio[1037]: info: ------------------------------ 191ms Aug 26 09:55:21 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 09:55:21 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 26 09:55:25 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 26 09:55:26 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand rescan Aug 26 09:55:26 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:26 volumio volumio[1037]: info: Aug 26 09:55:26 volumio volumio[1037]: ---------------------------- MPD announces state update: update Aug 26 09:55:26 volumio volumio[1037]: info: sendMpdCommand rescan took 6 milliseconds Aug 26 09:55:26 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:26 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:26 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:26 volumio volumio[1037]: info: Aug 26 09:55:26 volumio volumio[1037]: ---------------------------- MPD announces state update: update Aug 26 09:55:26 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:26 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:26 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:26 volumio volumio[1037]: info: Aug 26 09:55:26 volumio volumio[1037]: ---------------------------- MPD announces state update: update Aug 26 09:55:26 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:26 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:26 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:26 volumio volumio[1037]: info: Aug 26 09:55:26 volumio volumio[1037]: ---------------------------- MPD announces state update: update Aug 26 09:55:26 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:26 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:26 volumio volumio[1037]: info: sendMpdCommand status took 15 milliseconds Aug 26 09:55:26 volumio volumio[1037]: info: sendMpdCommand status took 13 milliseconds Aug 26 09:55:26 volumio volumio[1037]: info: sendMpdCommand status took 12 milliseconds Aug 26 09:55:26 volumio volumio[1037]: info: sendMpdCommand status took 11 milliseconds Aug 26 09:55:26 volumio volumio[1037]: info: sendMpdCommand status took 9 milliseconds Aug 26 09:55:26 volumio volumio[1037]: info: sendMpdCommand status took 8 milliseconds Aug 26 09:55:26 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:26 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Aug 26 09:55:26 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:26 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:26 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Aug 26 09:55:26 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:26 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:26 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Aug 26 09:55:26 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:26 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:26 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:26 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:26 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:26 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:26 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:26 volumio volumio[1037]: info: No code Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:26 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:26 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:26 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:26 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:26 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:26 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:26 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:26 volumio volumio[1037]: info: No code Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:26 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:26 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:26 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:26 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:26 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:26 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:26 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:26 volumio volumio[1037]: info: No code Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:26 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:26 volumio volumio[1037]: info: ------------------------------ 270ms Aug 26 09:55:26 volumio volumio[1037]: info: ------------------------------ 268ms Aug 26 09:55:26 volumio volumio[1037]: info: ------------------------------ 266ms Aug 26 09:55:26 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:26 volumio volumio[1037]: info: Aug 26 09:55:26 volumio volumio[1037]: ---------------------------- MPD announces state update: update Aug 26 09:55:26 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:26 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:26 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:26 volumio volumio[1037]: info: Aug 26 09:55:26 volumio volumio[1037]: ---------------------------- MPD announces state update: update Aug 26 09:55:26 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:26 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:26 volumio volumio[1037]: info: sendMpdCommand status took 280 milliseconds Aug 26 09:55:26 volumio volumio[1037]: info: sendMpdCommand status took 278 milliseconds Aug 26 09:55:26 volumio volumio[1037]: info: sendMpdCommand status took 16 milliseconds Aug 26 09:55:26 volumio volumio[1037]: info: sendMpdCommand status took 14 milliseconds Aug 26 09:55:26 volumio volumio[1037]: info: sendMpdCommand status took 13 milliseconds Aug 26 09:55:26 volumio volumio[1037]: info: sendMpdCommand status took 11 milliseconds Aug 26 09:55:26 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:26 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Aug 26 09:55:26 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:26 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:26 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Aug 26 09:55:26 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:26 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:26 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Aug 26 09:55:26 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:26 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:26 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:26 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:26 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:26 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:26 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:26 volumio volumio[1037]: info: No code Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:26 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:26 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:26 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:26 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:26 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:26 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:26 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:26 volumio volumio[1037]: info: No code Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:26 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:26 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:26 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:26 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:26 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:26 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:26 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:26 volumio volumio[1037]: info: No code Aug 26 09:55:26 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:26 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:26 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:27 volumio volumio[1037]: info: ------------------------------ 521ms Aug 26 09:55:27 volumio volumio[1037]: info: ------------------------------ 258ms Aug 26 09:55:27 volumio volumio[1037]: info: ------------------------------ 256ms Aug 26 09:55:27 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Aug 26 09:55:27 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:28 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 26 09:55:28 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand update Aug 26 09:55:28 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:28 volumio volumio[1037]: info: Aug 26 09:55:28 volumio volumio[1037]: ---------------------------- MPD announces state update: update Aug 26 09:55:28 volumio volumio[1037]: info: sendMpdCommand update took 6 milliseconds Aug 26 09:55:28 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:28 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:28 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:28 volumio volumio[1037]: info: Aug 26 09:55:28 volumio volumio[1037]: ---------------------------- MPD announces state update: update Aug 26 09:55:28 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:28 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:28 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:28 volumio volumio[1037]: info: Aug 26 09:55:28 volumio volumio[1037]: ---------------------------- MPD announces state update: update Aug 26 09:55:28 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:28 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:28 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:28 volumio volumio[1037]: info: Aug 26 09:55:28 volumio volumio[1037]: ---------------------------- MPD announces state update: update Aug 26 09:55:28 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:28 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:28 volumio volumio[1037]: info: sendMpdCommand status took 18 milliseconds Aug 26 09:55:28 volumio volumio[1037]: info: sendMpdCommand status took 16 milliseconds Aug 26 09:55:28 volumio volumio[1037]: info: sendMpdCommand status took 15 milliseconds Aug 26 09:55:28 volumio volumio[1037]: info: sendMpdCommand status took 13 milliseconds Aug 26 09:55:28 volumio volumio[1037]: info: sendMpdCommand status took 12 milliseconds Aug 26 09:55:28 volumio volumio[1037]: info: sendMpdCommand status took 11 milliseconds Aug 26 09:55:28 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:28 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Aug 26 09:55:28 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Aug 26 09:55:28 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:28 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:29 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Aug 26 09:55:29 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:29 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:29 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Aug 26 09:55:29 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:29 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:29 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:29 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:29 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:29 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:29 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:29 volumio volumio[1037]: info: No code Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:29 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:29 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:29 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:29 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:29 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:29 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:29 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:29 volumio volumio[1037]: info: No code Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:29 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:29 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:29 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:29 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:29 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:29 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:29 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:29 volumio volumio[1037]: info: No code Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:29 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:29 volumio volumio[1037]: info: ------------------------------ 281ms Aug 26 09:55:29 volumio volumio[1037]: info: ------------------------------ 279ms Aug 26 09:55:29 volumio volumio[1037]: info: ------------------------------ 277ms Aug 26 09:55:29 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:29 volumio volumio[1037]: info: Aug 26 09:55:29 volumio volumio[1037]: ---------------------------- MPD announces state update: update Aug 26 09:55:29 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:29 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:29 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:29 volumio volumio[1037]: info: Aug 26 09:55:29 volumio volumio[1037]: ---------------------------- MPD announces state update: update Aug 26 09:55:29 volumio volumio[1037]: info: ControllerMpd::getState Aug 26 09:55:29 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Aug 26 09:55:29 volumio volumio[1037]: info: sendMpdCommand status took 287 milliseconds Aug 26 09:55:29 volumio volumio[1037]: info: sendMpdCommand status took 282 milliseconds Aug 26 09:55:29 volumio volumio[1037]: info: sendMpdCommand status took 13 milliseconds Aug 26 09:55:29 volumio volumio[1037]: info: sendMpdCommand status took 11 milliseconds Aug 26 09:55:29 volumio volumio[1037]: info: sendMpdCommand status took 10 milliseconds Aug 26 09:55:29 volumio volumio[1037]: info: sendMpdCommand status took 8 milliseconds Aug 26 09:55:29 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:29 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Aug 26 09:55:29 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:29 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:29 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Aug 26 09:55:29 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:29 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:29 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Aug 26 09:55:29 volumio volumio[1037]: verbose: ControllerMpd::parseState Aug 26 09:55:29 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:29 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:29 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:29 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:29 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:29 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:29 volumio volumio[1037]: info: No code Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:29 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:29 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:29 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:29 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:29 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:29 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:29 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:29 volumio volumio[1037]: info: No code Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:29 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:29 volumio volumio[1037]: info: ControllerMpd::pushState Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:29 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:29 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:29 volumio volumio[1037]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 26 09:55:29 volumio volumio[1037]: verbose: CURRENT POSITION 0 Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:29 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:29 volumio volumio[1037]: info: No code Aug 26 09:55:29 volumio volumio[1037]: info: CoreStateMachine::pushState Aug 26 09:55:29 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Aug 26 09:55:29 volumio volumio[1037]: info: ------------------------------ 531ms Aug 26 09:55:29 volumio volumio[1037]: info: ------------------------------ 257ms Aug 26 09:55:29 volumio volumio[1037]: info: ------------------------------ 255ms Aug 26 09:55:29 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 09:55:30 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 26 09:55:30 volumio volumio[1037]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 26 09:55:30 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 26 09:55:30 volumio volumio[1037]: info: Received Get System Version Aug 26 09:55:30 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 09:55:30 volumio volumio[1037]: info: Received Get System Info Aug 26 09:55:30 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 09:55:30 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 09:55:30 volumio volumio[1037]: info: Discovery: Getting this device information Aug 26 09:55:30 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Aug 26 09:55:30 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:30 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 09:55:33 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 09:55:37 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Aug 26 09:55:37 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:37 volumio volumio[1037]: info: Listing playlists Aug 26 09:55:37 volumio volumio[1037]: info: Listing playlists Aug 26 09:55:37 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 09:55:41 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 09:55:45 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 09:55:47 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Aug 26 09:55:47 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:49 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 09:55:53 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 09:55:57 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Aug 26 09:55:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:55:57 volumio volumio[1037]: info: Listing playlists Aug 26 09:55:57 volumio volumio[1037]: info: Listing playlists Aug 26 09:55:57 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 09:56:01 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 09:56:05 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 09:56:07 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Aug 26 09:56:07 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:56:09 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 09:56:13 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 09:56:17 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Aug 26 09:56:17 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:56:17 volumio volumio[1037]: info: Listing playlists Aug 26 09:56:17 volumio volumio[1037]: info: Listing playlists Aug 26 09:56:17 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 09:56:21 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 09:56:25 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 09:56:27 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Aug 26 09:56:27 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:56:29 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 09:56:33 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 09:56:37 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Aug 26 09:56:37 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:56:37 volumio volumio[1037]: info: Listing playlists Aug 26 09:56:37 volumio volumio[1037]: info: Listing playlists Aug 26 09:56:37 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 09:56:41 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 09:56:46 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 09:56:47 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Aug 26 09:56:47 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Aug 26 09:56:47 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Aug 26 09:56:47 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Aug 26 09:56:48 volumio volumio[1037]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 09:56:48 volumio volumio[1037]: Error: getaddrinfo -3007 Aug 26 09:56:48 volumio volumio[1037]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Aug 26 09:56:48 volumio volumio[1037]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Aug 26 09:56:48 volumio volumio[1037]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Aug 26 09:56:48 volumio volumio[1037]: code: -3007, Aug 26 09:56:48 volumio volumio[1037]: errno: -3007, Aug 26 09:56:48 volumio volumio[1037]: syscall: 'getaddrinfo' Aug 26 09:56:48 volumio volumio[1037]: } Aug 26 09:56:48 volumio volumio[1037]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 09:56:49 volumio sudo[2000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-26 09:55 Aug 26 09:56:49 volumio sudo[2000]: 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="6e682b9410d28f3874f90b2d7789db824a859264" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="7c81f20187867c3bd55475ead0f12800fe93082c" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 08 Jun 2024 10:32:20 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="orangepipc" VOLUMIO_DEVICENAME="Orange Pi PC" VOLUMIO_HASH="85fabbf1ff267d6584c35638894541bf"