-- Logs begin at Mon 2025-04-21 15:10:07 CST, end at Mon 2025-04-21 19:05:48 CST. -- Apr 21 19:04:02 volumio volumio[1199]: verbose: New Socket.io Connection to 192.168.1.4 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/27.1 Chrome/125.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Apr 21 19:04:02 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 21 19:04:02 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 21 19:04:04 volumio volumio[1199]: info: CoreCommandRouter::volumioGetVisibleSources Apr 21 19:04:04 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 19:04:04 volumio volumio[1199]: info: CoreCommandRouter::volumioGetState Apr 21 19:04:04 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:04:04 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 21 19:04:04 volumio volumio[1199]: info: CoreCommandRouter::volumioGetQueue Apr 21 19:04:04 volumio volumio[1199]: info: CoreStateMachine::getQueue Apr 21 19:04:04 volumio volumio[1199]: info: CorePlayQueue::getQueue Apr 21 19:04:04 volumio volumio[1199]: info: Listing playlists Apr 21 19:04:04 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 21 19:04:04 volumio volumio[1199]: info: Received Get System Info Apr 21 19:04:04 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 19:04:04 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 19:04:04 volumio volumio[1199]: info: Discovery: Getting this device information Apr 21 19:04:04 volumio volumio[1199]: info: CoreCommandRouter::volumioGetState Apr 21 19:04:04 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:04:04 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 19:04:04 volumio volumio[1199]: info: CoreCommandRouter::volumioGetState Apr 21 19:04:04 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:04:04 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 21 19:04:04 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 21 19:04:04 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 21 19:04:04 volumio volumio[1199]: info: Received Get System Info Apr 21 19:04:04 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 19:04:04 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 19:04:04 volumio volumio[1199]: info: Discovery: Getting this device information Apr 21 19:04:04 volumio volumio[1199]: info: CoreCommandRouter::volumioGetState Apr 21 19:04:04 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:04:04 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 19:04:05 volumio ntpd[1188]: Soliciting pool server 185.209.85.222 Apr 21 19:04:06 volumio ntpd[1188]: Soliciting pool server 193.182.111.141 Apr 21 19:04:07 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 21 19:04:12 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 21 19:04:16 volumio volumio[1199]: verbose: New Socket.io Connection to 192.168.1.4 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/27.1 Chrome/125.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Apr 21 19:04:16 volumio volumio[1199]: info: Received Get System Info Apr 21 19:04:16 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 19:04:16 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 19:04:16 volumio volumio[1199]: info: Discovery: Getting this device information Apr 21 19:04:16 volumio volumio[1199]: info: CoreCommandRouter::volumioGetState Apr 21 19:04:16 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:04:16 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 19:04:16 volumio volumio[1199]: info: CoreCommandRouter::volumioGetState Apr 21 19:04:16 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:04:16 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 21 19:04:16 volumio volumio[1199]: info: CoreCommandRouter::volumioGetVisibleSources Apr 21 19:04:16 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 19:04:16 volumio volumio[1199]: info: CoreCommandRouter::volumioGetState Apr 21 19:04:16 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:04:16 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 21 19:04:16 volumio volumio[1199]: info: CoreCommandRouter::volumioGetQueue Apr 21 19:04:16 volumio volumio[1199]: info: CoreStateMachine::getQueue Apr 21 19:04:16 volumio volumio[1199]: info: CorePlayQueue::getQueue Apr 21 19:04:16 volumio volumio[1199]: info: Listing playlists Apr 21 19:04:16 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 21 19:04:16 volumio volumio[1199]: info: Received Get System Info Apr 21 19:04:16 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 19:04:16 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 19:04:16 volumio volumio[1199]: info: Discovery: Getting this device information Apr 21 19:04:16 volumio volumio[1199]: info: CoreCommandRouter::volumioGetState Apr 21 19:04:16 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:04:16 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 19:04:16 volumio volumio[1199]: info: CoreCommandRouter::volumioGetState Apr 21 19:04:16 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:04:17 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 21 19:04:25 volumio ntpd[1188]: Soliciting pool server 2606:4700:f1::1 Apr 21 19:04:26 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 21 19:04:35 volumio volumio[1199]: verbose: New Socket.io Connection to 192.168.1.4 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/27.1 Chrome/125.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Apr 21 19:04:35 volumio volumio[1199]: info: Received Get System Info Apr 21 19:04:35 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 19:04:35 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 19:04:35 volumio volumio[1199]: info: Discovery: Getting this device information Apr 21 19:04:35 volumio volumio[1199]: info: CoreCommandRouter::volumioGetState Apr 21 19:04:35 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:04:35 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 19:04:35 volumio volumio[1199]: info: CoreCommandRouter::volumioGetState Apr 21 19:04:35 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:04:35 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 21 19:04:35 volumio volumio[1199]: info: CoreCommandRouter::volumioGetVisibleSources Apr 21 19:04:35 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 19:04:35 volumio volumio[1199]: info: CoreCommandRouter::volumioGetState Apr 21 19:04:35 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:04:35 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 21 19:04:35 volumio volumio[1199]: info: CoreCommandRouter::volumioGetQueue Apr 21 19:04:35 volumio volumio[1199]: info: CoreStateMachine::getQueue Apr 21 19:04:35 volumio volumio[1199]: info: CorePlayQueue::getQueue Apr 21 19:04:35 volumio volumio[1199]: info: Listing playlists Apr 21 19:04:35 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 21 19:04:35 volumio volumio[1199]: info: Received Get System Info Apr 21 19:04:35 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 19:04:35 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 19:04:35 volumio volumio[1199]: info: Discovery: Getting this device information Apr 21 19:04:35 volumio volumio[1199]: info: CoreCommandRouter::volumioGetState Apr 21 19:04:35 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:04:35 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 19:04:35 volumio volumio[1199]: info: CoreCommandRouter::volumioGetState Apr 21 19:04:35 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:04:35 volumio volumio[1199]: info: Received Get System Info Apr 21 19:04:35 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 19:04:35 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 19:04:35 volumio volumio[1199]: info: Discovery: Getting this device information Apr 21 19:04:35 volumio volumio[1199]: info: CoreCommandRouter::volumioGetState Apr 21 19:04:35 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:04:35 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 19:04:35 volumio volumio[1199]: info: CoreCommandRouter::volumioGetState Apr 21 19:04:35 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:04:35 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 21 19:04:38 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 21 19:04:39 volumio volumio[1199]: info: CoreCommandRouter::Close All Modals sent Apr 21 19:04:45 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 21 19:04:46 volumio volumio[1199]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 21 19:04:46 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 21 19:04:46 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 21 19:04:46 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 21 19:04:46 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Apr 21 19:04:47 volumio volumio[1199]: info: Received Get System Version Apr 21 19:04:47 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 21 19:04:50 volumio volumio[1199]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 21 19:04:50 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 21 19:04:50 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Apr 21 19:04:50 volumio sudo[2995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Apr 21 19:04:50 volumio sudo[2995]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 19:04:50 volumio sudo[2995]: pam_unix(sudo:session): session closed for user root Apr 21 19:04:50 volumio sudo[3001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 21 19:04:50 volumio sudo[3001]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 19:04:50 volumio sudo[3001]: pam_unix(sudo:session): session closed for user root Apr 21 19:04:50 volumio sudo[3009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 21 19:04:50 volumio sudo[3009]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 19:04:50 volumio sudo[3009]: pam_unix(sudo:session): session closed for user root Apr 21 19:04:50 volumio sudo[3015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 21 19:04:50 volumio sudo[3015]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 19:04:50 volumio sudo[3015]: pam_unix(sudo:session): session closed for user root Apr 21 19:04:50 volumio sudo[3021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 21 19:04:50 volumio sudo[3021]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 19:04:50 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 21 19:04:50 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 21 19:04:50 volumio sudo[3021]: pam_unix(sudo:session): session closed for user root Apr 21 19:04:50 volumio sudo[3023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 21 19:04:50 volumio sudo[3023]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 19:04:50 volumio sudo[3023]: pam_unix(sudo:session): session closed for user root Apr 21 19:04:50 volumio sudo[3027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 21 19:04:50 volumio sudo[3027]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 19:04:55 volumio volumio[1199]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 21 19:04:55 volumio volumio[1199]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 21 19:04:55 volumio volumio[1199]: info: Retrieving Cloud Streaming UI Apr 21 19:04:55 volumio volumio[1199]: info: Getting Tidal Cloud Configuration Apr 21 19:04:55 volumio volumio[1199]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 21 19:04:55 volumio volumio[1199]: info: Getting Qobuz Cloud Configuration Apr 21 19:04:55 volumio volumio[1199]: info: Asking plugin for UI Config Apr 21 19:04:55 volumio volumio[1199]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 21 19:04:55 volumio volumio[1199]: info: Getting Spotify Cloud Configuration Apr 21 19:04:55 volumio volumio[1199]: info: Asking plugin for UI Config Apr 21 19:04:55 volumio volumio[1199]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 21 19:04:55 volumio volumio[1199]: info: Saving Spotify Acccount Apr 21 19:04:55 volumio volumio[1199]: info: Got Tidal Cloud Configuration Apr 21 19:04:55 volumio volumio[1199]: info: Got it Apr 21 19:04:55 volumio volumio[1199]: info: Got it Apr 21 19:04:55 volumio volumio[1199]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 21 19:04:55 volumio volumio[1199]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 21 19:04:55 volumio volumio[1199]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 21 19:04:55 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 21 19:04:55 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 21 19:04:55 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 21 19:04:55 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 21 19:04:55 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 19:04:55 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 19:04:55 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 19:04:55 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 21 19:04:55 volumio volumio[1199]: info: CoreCommandRouter::volumioGetBrowseSources Apr 21 19:04:55 volumio volumio[1199]: info: CoreCommandRouter::volumioGetBrowseSources Apr 21 19:04:55 volumio volumio[1199]: info: CoreCommandRouter::volumioGetBrowseSources Apr 21 19:04:55 volumio kernel: RTW: wlan0- hw port(0) mac_addr =90:de:80:28:a0:49 Apr 21 19:04:55 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 21 19:04:55 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 21 19:04:55 volumio sudo[3027]: pam_unix(sudo:session): session closed for user root Apr 21 19:04:58 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 21 19:04:58 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand rescan Apr 21 19:04:58 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:04:58 volumio volumio[1199]: info: Apr 21 19:04:58 volumio volumio[1199]: ---------------------------- MPD announces state update: update Apr 21 19:04:58 volumio volumio[1199]: info: sendMpdCommand rescan took 10 milliseconds Apr 21 19:04:58 volumio volumio[1199]: info: ControllerMpd::getState Apr 21 19:04:58 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:04:58 volumio volumio[1199]: info: sendMpdCommand status took 14 milliseconds Apr 21 19:04:58 volumio volumio[1199]: info: sendMpdCommand status took 6 milliseconds Apr 21 19:04:58 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:04:58 volumio volumio[1199]: info: Command Router : Notfying DB Updatetrue Apr 21 19:04:58 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:04:58 volumio volumio[1199]: info: ControllerMpd::pushState Apr 21 19:04:58 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 21 19:04:58 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:04:58 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:04:58 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:04:58 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:04:58 volumio volumio[1199]: 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} Apr 21 19:04:58 volumio volumio[1199]: verbose: CURRENT POSITION 0 Apr 21 19:04:58 volumio volumio[1199]: info: CoreStateMachine::syncState stateService stop Apr 21 19:04:58 volumio volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Apr 21 19:04:58 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:04:58 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:04:58 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 19:04:58 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:04:58 volumio volumio[1199]: info: No code Apr 21 19:04:58 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:04:58 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:04:58 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:04:58 volumio volumio[1199]: info: ------------------------------ 126ms Apr 21 19:04:58 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:04:58 volumio volumio[1199]: info: Apr 21 19:04:58 volumio volumio[1199]: ---------------------------- MPD announces state update: update Apr 21 19:04:58 volumio volumio[1199]: info: ControllerMpd::getState Apr 21 19:04:58 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:04:59 volumio volumio[1199]: info: sendMpdCommand status took 11 milliseconds Apr 21 19:04:59 volumio volumio[1199]: info: sendMpdCommand status took 9 milliseconds Apr 21 19:04:59 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:04:59 volumio volumio[1199]: info: Command Router : Notfying DB Updatefalse Apr 21 19:04:59 volumio volumio[1199]: info: CoreCommandRouter::Close All Modals sent Apr 21 19:04:59 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:04:59 volumio volumio[1199]: info: ControllerMpd::pushState Apr 21 19:04:59 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 21 19:04:59 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:04:59 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:04:59 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:04:59 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:04:59 volumio volumio[1199]: 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 21 19:04:59 volumio volumio[1199]: verbose: CURRENT POSITION 0 Apr 21 19:04:59 volumio volumio[1199]: info: CoreStateMachine::syncState stateService stop Apr 21 19:04:59 volumio volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Apr 21 19:04:59 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:04:59 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:04:59 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 19:04:59 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:04:59 volumio volumio[1199]: info: No code Apr 21 19:04:59 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:04:59 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:04:59 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:04:59 volumio volumio[1199]: info: ------------------------------ 109ms Apr 21 19:04:59 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 21 19:04:59 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 21 19:04:59 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand rescan Apr 21 19:04:59 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:04:59 volumio volumio[1199]: info: Apr 21 19:04:59 volumio volumio[1199]: ---------------------------- MPD announces state update: update Apr 21 19:04:59 volumio volumio[1199]: info: sendMpdCommand rescan took 3 milliseconds Apr 21 19:04:59 volumio volumio[1199]: info: ControllerMpd::getState Apr 21 19:04:59 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:04:59 volumio volumio[1199]: info: sendMpdCommand status took 2 milliseconds Apr 21 19:04:59 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:00 volumio volumio[1199]: info: Command Router : Notfying DB Updatetrue Apr 21 19:05:00 volumio volumio[1199]: info: CoreCommandRouter::Close All Modals sent Apr 21 19:05:00 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:00 volumio volumio[1199]: info: Apr 21 19:05:00 volumio volumio[1199]: ---------------------------- MPD announces state update: update Apr 21 19:05:00 volumio volumio[1199]: info: sendMpdCommand status took 45 milliseconds Apr 21 19:05:00 volumio volumio[1199]: info: ControllerMpd::getState Apr 21 19:05:00 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:00 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:00 volumio volumio[1199]: info: ControllerMpd::pushState Apr 21 19:05:00 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 21 19:05:00 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:00 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:00 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:00 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:00 volumio volumio[1199]: 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} Apr 21 19:05:00 volumio volumio[1199]: verbose: CURRENT POSITION 0 Apr 21 19:05:00 volumio volumio[1199]: info: CoreStateMachine::syncState stateService stop Apr 21 19:05:00 volumio volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Apr 21 19:05:00 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:00 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:00 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 19:05:00 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:00 volumio volumio[1199]: info: No code Apr 21 19:05:00 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:00 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:00 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:00 volumio volumio[1199]: info: ------------------------------ 82ms Apr 21 19:05:00 volumio volumio[1199]: info: sendMpdCommand status took 41 milliseconds Apr 21 19:05:00 volumio volumio[1199]: info: sendMpdCommand status took 41 milliseconds Apr 21 19:05:00 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:00 volumio volumio[1199]: info: Command Router : Notfying DB Updatefalse Apr 21 19:05:00 volumio volumio[1199]: info: CoreCommandRouter::Close All Modals sent Apr 21 19:05:00 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:00 volumio volumio[1199]: info: ControllerMpd::pushState Apr 21 19:05:00 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 21 19:05:00 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:00 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:00 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:00 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:00 volumio volumio[1199]: 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 21 19:05:00 volumio volumio[1199]: verbose: CURRENT POSITION 0 Apr 21 19:05:00 volumio volumio[1199]: info: CoreStateMachine::syncState stateService stop Apr 21 19:05:00 volumio volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Apr 21 19:05:00 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:00 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:00 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 19:05:00 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:00 volumio volumio[1199]: info: No code Apr 21 19:05:00 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:00 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:00 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:00 volumio volumio[1199]: info: ------------------------------ 143ms Apr 21 19:05:00 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 21 19:05:00 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand rescan Apr 21 19:05:00 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:00 volumio volumio[1199]: info: Apr 21 19:05:00 volumio volumio[1199]: ---------------------------- MPD announces state update: update Apr 21 19:05:00 volumio volumio[1199]: info: sendMpdCommand rescan took 3 milliseconds Apr 21 19:05:00 volumio volumio[1199]: info: ControllerMpd::getState Apr 21 19:05:00 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:00 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:00 volumio volumio[1199]: info: Apr 21 19:05:00 volumio volumio[1199]: ---------------------------- MPD announces state update: update Apr 21 19:05:00 volumio volumio[1199]: info: sendMpdCommand status took 4 milliseconds Apr 21 19:05:00 volumio volumio[1199]: info: ControllerMpd::getState Apr 21 19:05:00 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:00 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:00 volumio volumio[1199]: info: Command Router : Notfying DB Updatetrue Apr 21 19:05:00 volumio volumio[1199]: info: CoreCommandRouter::Close All Modals sent Apr 21 19:05:00 volumio volumio[1199]: info: sendMpdCommand status took 48 milliseconds Apr 21 19:05:00 volumio volumio[1199]: info: sendMpdCommand status took 47 milliseconds Apr 21 19:05:00 volumio volumio[1199]: info: sendMpdCommand status took 46 milliseconds Apr 21 19:05:00 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:00 volumio volumio[1199]: info: Command Router : Notfying DB Updatefalse Apr 21 19:05:00 volumio volumio[1199]: info: CoreCommandRouter::Close All Modals sent Apr 21 19:05:00 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:00 volumio volumio[1199]: info: ControllerMpd::pushState Apr 21 19:05:00 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 21 19:05:00 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:00 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:00 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:00 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:00 volumio volumio[1199]: 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 21 19:05:00 volumio volumio[1199]: verbose: CURRENT POSITION 0 Apr 21 19:05:00 volumio volumio[1199]: info: CoreStateMachine::syncState stateService stop Apr 21 19:05:00 volumio volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Apr 21 19:05:00 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:00 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:00 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:00 volumio volumio[1199]: info: No code Apr 21 19:05:00 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:00 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:00 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:00 volumio volumio[1199]: info: ControllerMpd::pushState Apr 21 19:05:00 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 21 19:05:00 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:00 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:00 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:00 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:00 volumio volumio[1199]: 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 21 19:05:00 volumio volumio[1199]: verbose: CURRENT POSITION 0 Apr 21 19:05:00 volumio volumio[1199]: info: CoreStateMachine::syncState stateService stop Apr 21 19:05:00 volumio volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Apr 21 19:05:00 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:00 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:00 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:00 volumio volumio[1199]: info: No code Apr 21 19:05:00 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:00 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:00 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:01 volumio volumio[1199]: info: ------------------------------ 127ms Apr 21 19:05:01 volumio volumio[1199]: info: ------------------------------ 148ms Apr 21 19:05:03 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 21 19:05:04 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 21 19:05:04 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand rescan Apr 21 19:05:04 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:04 volumio volumio[1199]: info: Apr 21 19:05:04 volumio volumio[1199]: ---------------------------- MPD announces state update: update Apr 21 19:05:04 volumio volumio[1199]: info: sendMpdCommand rescan took 5 milliseconds Apr 21 19:05:04 volumio volumio[1199]: info: ControllerMpd::getState Apr 21 19:05:04 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:04 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:04 volumio volumio[1199]: info: Apr 21 19:05:04 volumio volumio[1199]: ---------------------------- MPD announces state update: update Apr 21 19:05:04 volumio volumio[1199]: info: ControllerMpd::getState Apr 21 19:05:04 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:04 volumio volumio[1199]: info: sendMpdCommand status took 6 milliseconds Apr 21 19:05:04 volumio volumio[1199]: info: sendMpdCommand status took 4 milliseconds Apr 21 19:05:04 volumio volumio[1199]: info: sendMpdCommand status took 4 milliseconds Apr 21 19:05:04 volumio volumio[1199]: info: sendMpdCommand status took 3 milliseconds Apr 21 19:05:04 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:04 volumio volumio[1199]: info: Command Router : Notfying DB Updatefalse Apr 21 19:05:04 volumio volumio[1199]: info: CoreCommandRouter::Close All Modals sent Apr 21 19:05:04 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:04 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:04 volumio volumio[1199]: info: Command Router : Notfying DB Updatefalse Apr 21 19:05:04 volumio volumio[1199]: info: CoreCommandRouter::Close All Modals sent Apr 21 19:05:04 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:04 volumio volumio[1199]: info: ControllerMpd::pushState Apr 21 19:05:04 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 21 19:05:04 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:04 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:04 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:04 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:04 volumio volumio[1199]: 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 21 19:05:04 volumio volumio[1199]: verbose: CURRENT POSITION 0 Apr 21 19:05:04 volumio volumio[1199]: info: CoreStateMachine::syncState stateService stop Apr 21 19:05:04 volumio volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Apr 21 19:05:04 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:04 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:04 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:04 volumio volumio[1199]: info: No code Apr 21 19:05:04 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:04 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:04 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:04 volumio volumio[1199]: info: ControllerMpd::pushState Apr 21 19:05:04 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 21 19:05:04 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:04 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:04 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:04 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:04 volumio volumio[1199]: 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 21 19:05:04 volumio volumio[1199]: verbose: CURRENT POSITION 0 Apr 21 19:05:04 volumio volumio[1199]: info: CoreStateMachine::syncState stateService stop Apr 21 19:05:04 volumio volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Apr 21 19:05:04 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:04 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:04 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:04 volumio volumio[1199]: info: No code Apr 21 19:05:04 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:04 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:04 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:04 volumio volumio[1199]: info: ------------------------------ 160ms Apr 21 19:05:04 volumio volumio[1199]: info: ------------------------------ 158ms Apr 21 19:05:05 volumio ntpd[1188]: Soliciting pool server 193.182.111.14 Apr 21 19:05:05 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Apr 21 19:05:05 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand update Apr 21 19:05:05 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:05 volumio volumio[1199]: info: Apr 21 19:05:05 volumio volumio[1199]: ---------------------------- MPD announces state update: update Apr 21 19:05:05 volumio volumio[1199]: info: sendMpdCommand update took 5 milliseconds Apr 21 19:05:05 volumio volumio[1199]: info: ControllerMpd::getState Apr 21 19:05:05 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:05 volumio volumio[1199]: info: sendMpdCommand status took 3 milliseconds Apr 21 19:05:05 volumio volumio[1199]: info: sendMpdCommand status took 2 milliseconds Apr 21 19:05:05 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:05 volumio volumio[1199]: info: Command Router : Notfying DB Updatefalse Apr 21 19:05:05 volumio volumio[1199]: info: CoreCommandRouter::Close All Modals sent Apr 21 19:05:05 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:05 volumio volumio[1199]: info: ControllerMpd::pushState Apr 21 19:05:05 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 21 19:05:05 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:05 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:05 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:05 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:05 volumio volumio[1199]: 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 21 19:05:05 volumio volumio[1199]: verbose: CURRENT POSITION 0 Apr 21 19:05:05 volumio volumio[1199]: info: CoreStateMachine::syncState stateService stop Apr 21 19:05:05 volumio volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Apr 21 19:05:05 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:05 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:05 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:05 volumio volumio[1199]: info: No code Apr 21 19:05:05 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:05 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:05 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:05 volumio volumio[1199]: info: ------------------------------ 82ms Apr 21 19:05:06 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Apr 21 19:05:06 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand update Apr 21 19:05:06 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:06 volumio volumio[1199]: info: Apr 21 19:05:06 volumio volumio[1199]: ---------------------------- MPD announces state update: update Apr 21 19:05:06 volumio volumio[1199]: info: sendMpdCommand update took 5 milliseconds Apr 21 19:05:06 volumio volumio[1199]: info: ControllerMpd::getState Apr 21 19:05:06 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:06 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:06 volumio volumio[1199]: info: Apr 21 19:05:06 volumio volumio[1199]: ---------------------------- MPD announces state update: update Apr 21 19:05:06 volumio volumio[1199]: info: ControllerMpd::getState Apr 21 19:05:06 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:06 volumio volumio[1199]: info: sendMpdCommand status took 4 milliseconds Apr 21 19:05:06 volumio volumio[1199]: info: sendMpdCommand status took 2 milliseconds Apr 21 19:05:06 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:06 volumio volumio[1199]: info: Command Router : Notfying DB Updatefalse Apr 21 19:05:06 volumio volumio[1199]: info: CoreCommandRouter::Close All Modals sent Apr 21 19:05:06 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:06 volumio volumio[1199]: info: ControllerMpd::pushState Apr 21 19:05:06 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 21 19:05:06 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:06 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:06 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:06 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:06 volumio volumio[1199]: 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 21 19:05:06 volumio volumio[1199]: verbose: CURRENT POSITION 0 Apr 21 19:05:06 volumio volumio[1199]: info: CoreStateMachine::syncState stateService stop Apr 21 19:05:06 volumio volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Apr 21 19:05:06 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:06 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:06 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:06 volumio volumio[1199]: info: No code Apr 21 19:05:06 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:06 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:06 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:06 volumio volumio[1199]: info: ------------------------------ 69ms Apr 21 19:05:06 volumio volumio[1199]: info: sendMpdCommand status took 68 milliseconds Apr 21 19:05:06 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:06 volumio volumio[1199]: info: Command Router : Notfying DB Updatefalse Apr 21 19:05:06 volumio volumio[1199]: info: CoreCommandRouter::Close All Modals sent Apr 21 19:05:06 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:06 volumio volumio[1199]: info: ControllerMpd::pushState Apr 21 19:05:06 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 21 19:05:06 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:06 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:06 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:06 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:06 volumio volumio[1199]: 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 21 19:05:06 volumio volumio[1199]: verbose: CURRENT POSITION 0 Apr 21 19:05:06 volumio volumio[1199]: info: CoreStateMachine::syncState stateService stop Apr 21 19:05:06 volumio volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Apr 21 19:05:06 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:06 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:06 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:06 volumio volumio[1199]: info: No code Apr 21 19:05:06 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:06 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:06 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:06 volumio volumio[1199]: info: ------------------------------ 138ms Apr 21 19:05:07 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 21 19:05:09 volumio ntpd[1188]: Soliciting pool server 108.59.2.24 Apr 21 19:05:09 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Apr 21 19:05:09 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand update Apr 21 19:05:09 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:09 volumio volumio[1199]: info: Apr 21 19:05:09 volumio volumio[1199]: ---------------------------- MPD announces state update: update Apr 21 19:05:09 volumio volumio[1199]: info: sendMpdCommand update took 3 milliseconds Apr 21 19:05:09 volumio volumio[1199]: info: ControllerMpd::getState Apr 21 19:05:09 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:09 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:09 volumio volumio[1199]: info: Apr 21 19:05:09 volumio volumio[1199]: ---------------------------- MPD announces state update: update Apr 21 19:05:09 volumio volumio[1199]: info: sendMpdCommand status took 4 milliseconds Apr 21 19:05:09 volumio volumio[1199]: info: sendMpdCommand status took 2 milliseconds Apr 21 19:05:09 volumio volumio[1199]: info: ControllerMpd::getState Apr 21 19:05:09 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:09 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:09 volumio volumio[1199]: info: Command Router : Notfying DB Updatetrue Apr 21 19:05:09 volumio volumio[1199]: info: CoreCommandRouter::Close All Modals sent Apr 21 19:05:09 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:09 volumio volumio[1199]: info: ControllerMpd::pushState Apr 21 19:05:09 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 21 19:05:09 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:09 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:09 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:09 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:09 volumio volumio[1199]: 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} Apr 21 19:05:09 volumio volumio[1199]: verbose: CURRENT POSITION 0 Apr 21 19:05:09 volumio volumio[1199]: info: CoreStateMachine::syncState stateService stop Apr 21 19:05:09 volumio volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Apr 21 19:05:09 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:09 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:09 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 19:05:09 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:09 volumio volumio[1199]: info: No code Apr 21 19:05:09 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:09 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:09 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:09 volumio volumio[1199]: info: ------------------------------ 73ms Apr 21 19:05:09 volumio volumio[1199]: info: sendMpdCommand status took 72 milliseconds Apr 21 19:05:09 volumio volumio[1199]: info: sendMpdCommand status took 71 milliseconds Apr 21 19:05:09 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:09 volumio volumio[1199]: info: Command Router : Notfying DB Updatefalse Apr 21 19:05:09 volumio volumio[1199]: info: CoreCommandRouter::Close All Modals sent Apr 21 19:05:09 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:09 volumio volumio[1199]: info: ControllerMpd::pushState Apr 21 19:05:09 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 21 19:05:09 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:09 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:09 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:09 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:09 volumio volumio[1199]: 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 21 19:05:09 volumio volumio[1199]: verbose: CURRENT POSITION 0 Apr 21 19:05:09 volumio volumio[1199]: info: CoreStateMachine::syncState stateService stop Apr 21 19:05:09 volumio volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Apr 21 19:05:09 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:09 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:09 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 19:05:09 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:09 volumio volumio[1199]: info: No code Apr 21 19:05:09 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:09 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:09 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:09 volumio volumio[1199]: info: ------------------------------ 149ms Apr 21 19:05:10 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Apr 21 19:05:10 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand update Apr 21 19:05:10 volumio volumio[1199]: info: sendMpdCommand update took 3 milliseconds Apr 21 19:05:10 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:10 volumio volumio[1199]: info: Apr 21 19:05:10 volumio volumio[1199]: ---------------------------- MPD announces state update: update Apr 21 19:05:10 volumio volumio[1199]: info: ControllerMpd::getState Apr 21 19:05:10 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:10 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:10 volumio volumio[1199]: info: Apr 21 19:05:10 volumio volumio[1199]: ---------------------------- MPD announces state update: update Apr 21 19:05:10 volumio volumio[1199]: info: ControllerMpd::getState Apr 21 19:05:10 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:10 volumio volumio[1199]: info: sendMpdCommand status took 4 milliseconds Apr 21 19:05:10 volumio volumio[1199]: info: sendMpdCommand status took 4 milliseconds Apr 21 19:05:10 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:10 volumio volumio[1199]: info: Command Router : Notfying DB Updatefalse Apr 21 19:05:10 volumio volumio[1199]: info: CoreCommandRouter::Close All Modals sent Apr 21 19:05:10 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:10 volumio volumio[1199]: info: ControllerMpd::pushState Apr 21 19:05:10 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 21 19:05:10 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:10 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:10 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:10 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:10 volumio volumio[1199]: 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 21 19:05:10 volumio volumio[1199]: verbose: CURRENT POSITION 0 Apr 21 19:05:10 volumio volumio[1199]: info: CoreStateMachine::syncState stateService stop Apr 21 19:05:10 volumio volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Apr 21 19:05:10 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:10 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:10 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:10 volumio volumio[1199]: info: No code Apr 21 19:05:10 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:10 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:10 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:10 volumio volumio[1199]: info: ------------------------------ 75ms Apr 21 19:05:10 volumio volumio[1199]: info: sendMpdCommand status took 74 milliseconds Apr 21 19:05:10 volumio volumio[1199]: info: sendMpdCommand status took 73 milliseconds Apr 21 19:05:10 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:10 volumio volumio[1199]: info: Command Router : Notfying DB Updatefalse Apr 21 19:05:10 volumio volumio[1199]: info: CoreCommandRouter::Close All Modals sent Apr 21 19:05:10 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:10 volumio volumio[1199]: info: ControllerMpd::pushState Apr 21 19:05:10 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 21 19:05:10 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:10 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:10 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:10 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:10 volumio volumio[1199]: 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 21 19:05:10 volumio volumio[1199]: verbose: CURRENT POSITION 0 Apr 21 19:05:10 volumio volumio[1199]: info: CoreStateMachine::syncState stateService stop Apr 21 19:05:10 volumio volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Apr 21 19:05:10 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:10 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:10 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:10 volumio volumio[1199]: info: No code Apr 21 19:05:10 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:10 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:10 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:10 volumio volumio[1199]: info: ------------------------------ 147ms Apr 21 19:05:10 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 21 19:05:10 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand rescan Apr 21 19:05:10 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:10 volumio volumio[1199]: info: Apr 21 19:05:10 volumio volumio[1199]: ---------------------------- MPD announces state update: update Apr 21 19:05:10 volumio volumio[1199]: info: sendMpdCommand rescan took 5 milliseconds Apr 21 19:05:10 volumio volumio[1199]: info: ControllerMpd::getState Apr 21 19:05:10 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:10 volumio volumio[1199]: info: Apr 21 19:05:10 volumio volumio[1199]: ---------------------------- MPD announces state update: update Apr 21 19:05:10 volumio volumio[1199]: info: ControllerMpd::getState Apr 21 19:05:10 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Apr 21 19:05:10 volumio volumio[1199]: info: sendMpdCommand status took 4 milliseconds Apr 21 19:05:10 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:10 volumio volumio[1199]: info: Command Router : Notfying DB Updatefalse Apr 21 19:05:10 volumio volumio[1199]: info: CoreCommandRouter::Close All Modals sent Apr 21 19:05:10 volumio volumio[1199]: info: sendMpdCommand status took 55 milliseconds Apr 21 19:05:10 volumio volumio[1199]: info: sendMpdCommand status took 55 milliseconds Apr 21 19:05:10 volumio volumio[1199]: info: sendMpdCommand status took 53 milliseconds Apr 21 19:05:10 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:10 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:10 volumio volumio[1199]: info: Command Router : Notfying DB Updatefalse Apr 21 19:05:10 volumio volumio[1199]: info: CoreCommandRouter::Close All Modals sent Apr 21 19:05:10 volumio volumio[1199]: verbose: ControllerMpd::parseState Apr 21 19:05:10 volumio volumio[1199]: info: ControllerMpd::pushState Apr 21 19:05:10 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 21 19:05:10 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:10 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:10 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:10 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:10 volumio volumio[1199]: 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 21 19:05:10 volumio volumio[1199]: verbose: CURRENT POSITION 0 Apr 21 19:05:10 volumio volumio[1199]: info: CoreStateMachine::syncState stateService stop Apr 21 19:05:10 volumio volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Apr 21 19:05:10 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:10 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:10 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:10 volumio volumio[1199]: info: No code Apr 21 19:05:10 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:10 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:10 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:10 volumio volumio[1199]: info: ControllerMpd::pushState Apr 21 19:05:10 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Apr 21 19:05:10 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:10 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:10 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:10 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:10 volumio volumio[1199]: 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 21 19:05:10 volumio volumio[1199]: verbose: CURRENT POSITION 0 Apr 21 19:05:10 volumio volumio[1199]: info: CoreStateMachine::syncState stateService stop Apr 21 19:05:10 volumio volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Apr 21 19:05:10 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:10 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:10 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:10 volumio volumio[1199]: info: No code Apr 21 19:05:10 volumio volumio[1199]: info: CoreStateMachine::pushState Apr 21 19:05:10 volumio volumio[1199]: info: CorePlayQueue::getTrack 0 Apr 21 19:05:10 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Apr 21 19:05:10 volumio volumio[1199]: info: ------------------------------ 144ms Apr 21 19:05:10 volumio volumio[1199]: info: ------------------------------ 164ms Apr 21 19:05:11 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 21 19:05:12 volumio ntpd[1188]: Soliciting pool server 5.79.108.34 Apr 21 19:05:29 volumio ntpd[1188]: Soliciting pool server 84.16.67.12 Apr 21 19:05:40 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 21 19:05:40 volumio volumio[1199]: info: Preload queue cleared Apr 21 19:05:42 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 21 19:05:42 volumio volumio[1199]: info: Preload queue cleared Apr 21 19:05:43 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 21 19:05:43 volumio volumio[1199]: info: Preload queue cleared Apr 21 19:05:47 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 21 19:05:47 volumio volumio[1199]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 19:05:47 volumio volumio[1199]: TypeError: Cannot read property 'length' of undefined Apr 21 19:05:47 volumio volumio[1199]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Apr 21 19:05:47 volumio volumio[1199]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Apr 21 19:05:47 volumio volumio[1199]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Apr 21 19:05:47 volumio volumio[1199]: at Parser.emit (events.js:400:28) Apr 21 19:05:47 volumio volumio[1199]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Apr 21 19:05:47 volumio volumio[1199]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Apr 21 19:05:47 volumio volumio[1199]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Apr 21 19:05:47 volumio volumio[1199]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Apr 21 19:05:47 volumio volumio[1199]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Apr 21 19:05:47 volumio volumio[1199]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Apr 21 19:05:47 volumio volumio[1199]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Apr 21 19:05:47 volumio volumio[1199]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Apr 21 19:05:47 volumio volumio[1199]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Apr 21 19:05:47 volumio volumio[1199]: at IncomingMessage.emit (events.js:412:35) Apr 21 19:05:47 volumio volumio[1199]: at endReadableNT (internal/streams/readable.js:1333:12) Apr 21 19:05:47 volumio volumio[1199]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Apr 21 19:05:47 volumio volumio[1199]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 19:05:48 volumio sudo[3223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-21 19:04 Apr 21 19:05:48 volumio sudo[3223]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:27:48 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4bc1ecb08f34ad14b420d6678a2aef1b"