-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Fri 2025-05-23 19:16:53 CST. -- May 23 19:15:04 smsl-n100 volumio[21746]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 19:15:04 smsl-n100 volumio[21746]: xcb_connection_has_error() returned true May 23 19:15:04 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 23 19:15:04 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 19:15:04 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 19:15:04 smsl-n100 volumio[21746]: xcb_connection_has_error() returned true May 23 19:15:04 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 19:15:04 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 19:15:04 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 19:15:04 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 19:15:04 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 19:15:04 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 19:15:04 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 19:15:04 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 23 19:15:04 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 23 19:15:04 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection May 23 19:15:04 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 19:15:04 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 19:15:05 smsl-n100 volumio[21746]: info: Executing endpoint metavolumio May 23 19:15:05 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 23 19:15:05 smsl-n100 volumio[21746]: info: Executing endpoint metavolumio May 23 19:15:05 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 23 19:15:05 smsl-n100 volumio[21746]: error: Failed request for metavolumio API May 23 19:15:06 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 23 19:15:06 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 23 19:15:11 smsl-n100 sudo[24344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 23 19:15:11 smsl-n100 sudo[24344]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 19:15:11 smsl-n100 sudo[24344]: pam_unix(sudo:session): session closed for user root May 23 19:15:11 smsl-n100 sudo[24347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 23 19:15:11 smsl-n100 sudo[24347]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 19:15:11 smsl-n100 sudo[24347]: pam_unix(sudo:session): session closed for user root May 23 19:15:11 smsl-n100 volumio[21746]: verbose: New Socket.io Connection to 192.168.31.160 from 192.168.31.78 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 7 May 23 19:15:11 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 23 19:15:11 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioGetVisibleSources May 23 19:15:11 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 23 19:15:11 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioGetState May 23 19:15:11 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:11 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 23 19:15:11 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 23 19:15:11 smsl-n100 volumio[21746]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 23 19:15:11 smsl-n100 volumio[21746]: info: Received Get System Info May 23 19:15:11 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 19:15:11 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 19:15:11 smsl-n100 volumio[21746]: info: Discovery: Getting this device information May 23 19:15:11 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioGetState May 23 19:15:11 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:11 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 19:15:11 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioGetState May 23 19:15:11 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:11 smsl-n100 volumio[21746]: info: Listing playlists May 23 19:15:11 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 23 19:15:11 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 23 19:15:11 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 23 19:15:13 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 23 19:15:13 smsl-n100 volumio[21746]: info: Received Get System Info May 23 19:15:13 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 19:15:13 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 19:15:13 smsl-n100 volumio[21746]: info: Discovery: Getting this device information May 23 19:15:13 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioGetState May 23 19:15:13 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:13 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 19:15:13 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 23 19:15:13 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioGetQueue May 23 19:15:13 smsl-n100 volumio[21746]: info: CoreStateMachine::getQueue May 23 19:15:13 smsl-n100 volumio[21746]: info: CorePlayQueue::getQueue May 23 19:15:13 smsl-n100 volumio[21746]: info: Executing endpoint metavolumio May 23 19:15:13 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 23 19:15:13 smsl-n100 volumio[21746]: info: Executing endpoint metavolumio May 23 19:15:13 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 23 19:15:13 smsl-n100 volumio[21746]: error: Failed request for metavolumio API May 23 19:15:14 smsl-n100 volumio[21746]: info: Preload queue cleared May 23 19:15:14 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioReplaceandPlayItems May 23 19:15:14 smsl-n100 volumio[21746]: info: CoreStateMachine::ClearQueue May 23 19:15:14 smsl-n100 volumio[21746]: info: CoreStateMachine::stop May 23 19:15:14 smsl-n100 volumio[21746]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 19:15:14 smsl-n100 volumio[21746]: info: CorePlayQueue::clearPlayQueue May 23 19:15:14 smsl-n100 volumio[21746]: info: CorePlayQueue::saveQueue May 23 19:15:14 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushQueue May 23 19:15:14 smsl-n100 volumio[21746]: info: CoreStateMachine::addQueueItems May 23 19:15:14 smsl-n100 volumio[21746]: info: CorePlayQueue::addQueueItems May 23 19:15:14 smsl-n100 volumio[21746]: info: Preload queue cleared May 23 19:15:14 smsl-n100 volumio[21746]: info: Adding Item to queue: cdaudio/track0 May 23 19:15:14 smsl-n100 volumio[21746]: info: Exploding uri cdaudio/track0 in service cd_controller May 23 19:15:15 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushQueue May 23 19:15:15 smsl-n100 volumio[21746]: info: CorePlayQueue::saveQueue May 23 19:15:15 smsl-n100 volumio[21746]: info: CoreStateMachine::updateTrackBlock May 23 19:15:15 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrackBlock May 23 19:15:15 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPlay May 23 19:15:15 smsl-n100 volumio[21746]: info: CoreStateMachine::play index 0 May 23 19:15:15 smsl-n100 volumio[21746]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 19:15:15 smsl-n100 volumio[21746]: info: CoreStateMachine::addQueueItems May 23 19:15:15 smsl-n100 volumio[21746]: info: CorePlayQueue::addQueueItems May 23 19:15:15 smsl-n100 volumio[21746]: info: Preload queue cleared May 23 19:15:15 smsl-n100 volumio[21746]: info: Adding Item to queue: cdaudio/track1 May 23 19:15:15 smsl-n100 volumio[21746]: info: Exploding uri cdaudio/track1 in service cd_controller May 23 19:15:15 smsl-n100 volumio[21746]: info: Adding Item to queue: cdaudio/track2 May 23 19:15:15 smsl-n100 volumio[21746]: info: Exploding uri cdaudio/track2 in service cd_controller May 23 19:15:15 smsl-n100 volumio[21746]: info: Adding Item to queue: cdaudio/track3 May 23 19:15:15 smsl-n100 volumio[21746]: info: Exploding uri cdaudio/track3 in service cd_controller May 23 19:15:15 smsl-n100 volumio[21746]: info: Adding Item to queue: cdaudio/track4 May 23 19:15:15 smsl-n100 volumio[21746]: info: Exploding uri cdaudio/track4 in service cd_controller May 23 19:15:15 smsl-n100 volumio[21746]: info: Adding Item to queue: cdaudio/track5 May 23 19:15:15 smsl-n100 volumio[21746]: info: Exploding uri cdaudio/track5 in service cd_controller May 23 19:15:15 smsl-n100 volumio[21746]: info: Adding Item to queue: cdaudio/track6 May 23 19:15:15 smsl-n100 volumio[21746]: info: Exploding uri cdaudio/track6 in service cd_controller May 23 19:15:15 smsl-n100 volumio[21746]: info: Adding Item to queue: cdaudio/track7 May 23 19:15:15 smsl-n100 volumio[21746]: info: Exploding uri cdaudio/track7 in service cd_controller May 23 19:15:15 smsl-n100 volumio[21746]: info: Adding Item to queue: cdaudio/track8 May 23 19:15:15 smsl-n100 volumio[21746]: info: Exploding uri cdaudio/track8 in service cd_controller May 23 19:15:15 smsl-n100 volumio[21746]: info: Adding Item to queue: cdaudio/track9 May 23 19:15:15 smsl-n100 volumio[21746]: info: Exploding uri cdaudio/track9 in service cd_controller May 23 19:15:15 smsl-n100 volumio[21746]: info: Adding Item to queue: cdaudio/track10 May 23 19:15:15 smsl-n100 volumio[21746]: info: Exploding uri cdaudio/track10 in service cd_controller May 23 19:15:15 smsl-n100 volumio[21746]: info: CoreStateMachine::stop May 23 19:15:15 smsl-n100 volumio[21746]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 19:15:15 smsl-n100 volumio[21746]: info: CoreStateMachine::play index undefined May 23 19:15:15 smsl-n100 volumio[21746]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 19:15:15 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:15 smsl-n100 volumio[21746]: info: CoreStateMachine::startPlaybackTimer May 23 19:15:15 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:15 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioGetVisibleSources May 23 19:15:15 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 23 19:15:15 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 23 19:15:15 smsl-n100 volumio[21746]: verbose: ControllerMpd::clearAddPlayTracks cdda:///1 May 23 19:15:15 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand stop May 23 19:15:15 smsl-n100 volumio[21746]: info: sendMpdCommand stop took 11 milliseconds May 23 19:15:15 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand clear May 23 19:15:15 smsl-n100 volumio[21746]: info: May 23 19:15:15 smsl-n100 volumio[21746]: ---------------------------- MPD announces system playlist update May 23 19:15:15 smsl-n100 volumio[21746]: info: Ignoring MPD Status Update May 23 19:15:15 smsl-n100 volumio[21746]: info: sendMpdCommand clear took 7 milliseconds May 23 19:15:15 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand add "cdda:///1" May 23 19:15:15 smsl-n100 volumio[21746]: info: May 23 19:15:15 smsl-n100 volumio[21746]: ---------------------------- MPD announces system playlist update May 23 19:15:15 smsl-n100 volumio[21746]: info: Ignoring MPD Status Update May 23 19:15:15 smsl-n100 volumio[21746]: info: May 23 19:15:15 smsl-n100 volumio[21746]: ---------------------------- MPD announces system playlist update May 23 19:15:15 smsl-n100 volumio[21746]: info: Ignoring MPD Status Update May 23 19:15:15 smsl-n100 volumio[21746]: info: May 23 19:15:15 smsl-n100 volumio[21746]: ---------------------------- MPD announces system playlist update May 23 19:15:15 smsl-n100 volumio[21746]: info: Ignoring MPD Status Update May 23 19:15:15 smsl-n100 volumio[21746]: info: May 23 19:15:15 smsl-n100 volumio[21746]: ---------------------------- MPD announces system playlist update May 23 19:15:15 smsl-n100 volumio[21746]: info: Ignoring MPD Status Update May 23 19:15:15 smsl-n100 volumio[21746]: info: May 23 19:15:15 smsl-n100 volumio[21746]: ---------------------------- MPD announces system playlist update May 23 19:15:15 smsl-n100 volumio[21746]: info: Ignoring MPD Status Update May 23 19:15:15 smsl-n100 volumio[21746]: error: updateQueue error: null May 23 19:15:15 smsl-n100 volumio[21746]: info: May 23 19:15:15 smsl-n100 volumio[21746]: ---------------------------- MPD announces system playlist update May 23 19:15:15 smsl-n100 volumio[21746]: info: Ignoring MPD Status Update May 23 19:15:15 smsl-n100 volumio[21746]: info: ------------------------------ 31ms May 23 19:15:15 smsl-n100 volumio[21746]: info: sendMpdCommand add "cdda:///1" took 28 milliseconds May 23 19:15:15 smsl-n100 volumio[21746]: info: ------------------------------ 27ms May 23 19:15:15 smsl-n100 volumio[21746]: info: ------------------------------ 24ms May 23 19:15:15 smsl-n100 volumio[21746]: info: ------------------------------ 21ms May 23 19:15:15 smsl-n100 volumio[21746]: info: ------------------------------ 19ms May 23 19:15:15 smsl-n100 volumio[21746]: info: ------------------------------ 16ms May 23 19:15:15 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand play May 23 19:15:15 smsl-n100 volumio[21746]: info: May 23 19:15:15 smsl-n100 volumio[21746]: ---------------------------- MPD announces system playlist update May 23 19:15:15 smsl-n100 volumio[21746]: info: Ignoring MPD Status Update May 23 19:15:15 smsl-n100 volumio[21746]: info: May 23 19:15:15 smsl-n100 volumio[21746]: ---------------------------- MPD announces system playlist update May 23 19:15:15 smsl-n100 volumio[21746]: info: Ignoring MPD Status Update May 23 19:15:15 smsl-n100 volumio[21746]: info: May 23 19:15:15 smsl-n100 volumio[21746]: ---------------------------- MPD announces system playlist update May 23 19:15:15 smsl-n100 volumio[21746]: info: Ignoring MPD Status Update May 23 19:15:15 smsl-n100 volumio[21746]: info: May 23 19:15:15 smsl-n100 volumio[21746]: ---------------------------- MPD announces system playlist update May 23 19:15:15 smsl-n100 volumio[21746]: info: Ignoring MPD Status Update May 23 19:15:15 smsl-n100 volumio[21746]: info: May 23 19:15:15 smsl-n100 volumio[21746]: ---------------------------- MPD announces system playlist update May 23 19:15:15 smsl-n100 volumio[21746]: info: Ignoring MPD Status Update May 23 19:15:15 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushQueue May 23 19:15:15 smsl-n100 volumio[21746]: info: CorePlayQueue::saveQueue May 23 19:15:15 smsl-n100 volumio[21746]: info: CoreStateMachine::updateTrackBlock May 23 19:15:15 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrackBlock May 23 19:15:15 smsl-n100 volumio[21746]: info: ------------------------------ 201ms May 23 19:15:15 smsl-n100 volumio[21746]: info: sendMpdCommand play took 192 milliseconds May 23 19:15:15 smsl-n100 volumio[21746]: info: ------------------------------ 190ms May 23 19:15:15 smsl-n100 volumio[21746]: info: ------------------------------ 188ms May 23 19:15:15 smsl-n100 volumio[21746]: info: ------------------------------ 185ms May 23 19:15:15 smsl-n100 volumio[21746]: info: ------------------------------ 182ms May 23 19:15:15 smsl-n100 volumio[21746]: info: ------------------------------ 180ms May 23 19:15:19 smsl-n100 volumio[21746]: info: May 23 19:15:19 smsl-n100 volumio[21746]: ---------------------------- MPD announces state update: player May 23 19:15:19 smsl-n100 volumio[21746]: info: ControllerMpd::getState May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand status May 23 19:15:19 smsl-n100 volumio[21746]: info: May 23 19:15:19 smsl-n100 volumio[21746]: ---------------------------- MPD announces state update: player May 23 19:15:19 smsl-n100 volumio[21746]: info: ControllerMpd::getState May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand status May 23 19:15:19 smsl-n100 volumio[21746]: info: sendMpdCommand status took 6 milliseconds May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::parseState May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 19:15:19 smsl-n100 volumio[21746]: info: May 23 19:15:19 smsl-n100 volumio[21746]: ---------------------------- MPD announces state update: player May 23 19:15:19 smsl-n100 volumio[21746]: info: ControllerMpd::getState May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand status May 23 19:15:19 smsl-n100 volumio[21746]: info: May 23 19:15:19 smsl-n100 volumio[21746]: ---------------------------- MPD announces state update: player May 23 19:15:19 smsl-n100 volumio[21746]: info: ControllerMpd::getState May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand status May 23 19:15:19 smsl-n100 volumio[21746]: info: May 23 19:15:19 smsl-n100 volumio[21746]: ---------------------------- MPD announces state update: player May 23 19:15:19 smsl-n100 volumio[21746]: info: ControllerMpd::getState May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand status May 23 19:15:19 smsl-n100 volumio[21746]: info: May 23 19:15:19 smsl-n100 volumio[21746]: ---------------------------- MPD announces state update: player May 23 19:15:19 smsl-n100 volumio[21746]: info: ControllerMpd::getState May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand status May 23 19:15:19 smsl-n100 volumio[21746]: info: May 23 19:15:19 smsl-n100 volumio[21746]: ---------------------------- MPD announces state update: player May 23 19:15:19 smsl-n100 volumio[21746]: info: ControllerMpd::getState May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand status May 23 19:15:19 smsl-n100 volumio[21746]: info: sendMpdCommand status took 32 milliseconds May 23 19:15:19 smsl-n100 volumio[21746]: info: sendMpdCommand playlistinfo took 28 milliseconds May 23 19:15:19 smsl-n100 volumio[21746]: info: sendMpdCommand status took 25 milliseconds May 23 19:15:19 smsl-n100 volumio[21746]: info: sendMpdCommand status took 21 milliseconds May 23 19:15:19 smsl-n100 volumio[21746]: info: sendMpdCommand status took 18 milliseconds May 23 19:15:19 smsl-n100 volumio[21746]: info: sendMpdCommand status took 14 milliseconds May 23 19:15:19 smsl-n100 volumio[21746]: info: sendMpdCommand status took 10 milliseconds May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::parseState May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::parseTrackInfo May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::parseState May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::parseState May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::parseState May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::parseState May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::parseState May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 19:15:19 smsl-n100 volumio[21746]: info: ControllerMpd::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::servicePushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} May 23 19:15:19 smsl-n100 volumio[21746]: verbose: CURRENT POSITION 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::syncState stateService play May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::syncState currentStatus stop May 23 19:15:19 smsl-n100 volumio[21746]: info: ------------------------------ 74ms May 23 19:15:19 smsl-n100 volumio[21746]: info: May 23 19:15:19 smsl-n100 volumio[21746]: ---------------------------- MPD announces state update: player May 23 19:15:19 smsl-n100 volumio[21746]: info: ControllerMpd::getState May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand status May 23 19:15:19 smsl-n100 volumio[21746]: info: May 23 19:15:19 smsl-n100 volumio[21746]: ---------------------------- MPD announces state update: player May 23 19:15:19 smsl-n100 volumio[21746]: info: ControllerMpd::getState May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand status May 23 19:15:19 smsl-n100 volumio[21746]: info: May 23 19:15:19 smsl-n100 volumio[21746]: ---------------------------- MPD announces state update: player May 23 19:15:19 smsl-n100 volumio[21746]: info: ControllerMpd::getState May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand status May 23 19:15:19 smsl-n100 volumio[21746]: info: May 23 19:15:19 smsl-n100 volumio[21746]: ---------------------------- MPD announces state update: player May 23 19:15:19 smsl-n100 volumio[21746]: info: ControllerMpd::getState May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand status May 23 19:15:19 smsl-n100 volumio[21746]: info: May 23 19:15:19 smsl-n100 volumio[21746]: ---------------------------- MPD announces state update: player May 23 19:15:19 smsl-n100 volumio[21746]: info: ControllerMpd::getState May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand status May 23 19:15:19 smsl-n100 volumio[21746]: info: sendMpdCommand playlistinfo took 48 milliseconds May 23 19:15:19 smsl-n100 volumio[21746]: info: sendMpdCommand playlistinfo took 47 milliseconds May 23 19:15:19 smsl-n100 volumio[21746]: info: sendMpdCommand playlistinfo took 46 milliseconds May 23 19:15:19 smsl-n100 volumio[21746]: info: sendMpdCommand playlistinfo took 45 milliseconds May 23 19:15:19 smsl-n100 volumio[21746]: info: sendMpdCommand playlistinfo took 44 milliseconds May 23 19:15:19 smsl-n100 volumio[21746]: info: sendMpdCommand playlistinfo took 44 milliseconds May 23 19:15:19 smsl-n100 volumio[21746]: info: sendMpdCommand status took 30 milliseconds May 23 19:15:19 smsl-n100 volumio[21746]: info: sendMpdCommand status took 26 milliseconds May 23 19:15:19 smsl-n100 volumio[21746]: info: sendMpdCommand status took 22 milliseconds May 23 19:15:19 smsl-n100 volumio[21746]: info: sendMpdCommand status took 18 milliseconds May 23 19:15:19 smsl-n100 volumio[21746]: info: sendMpdCommand status took 15 milliseconds May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::parseTrackInfo May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::parseTrackInfo May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::parseTrackInfo May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::parseTrackInfo May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::parseTrackInfo May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::parseTrackInfo May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::parseState May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::parseState May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::parseState May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::parseState May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::parseState May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 19:15:19 smsl-n100 volumio[21746]: info: ControllerMpd::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::servicePushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} May 23 19:15:19 smsl-n100 volumio[21746]: verbose: CURRENT POSITION 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::syncState stateService play May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::syncState currentStatus play May 23 19:15:19 smsl-n100 volumio[21746]: info: Received an update from plugin. extracting info from payload May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushState May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output update for this device May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushState May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output update for this device May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output May 23 19:15:19 smsl-n100 volumio[21746]: info: ControllerMpd::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::servicePushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} May 23 19:15:19 smsl-n100 volumio[21746]: verbose: CURRENT POSITION 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::syncState stateService play May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::syncState currentStatus play May 23 19:15:19 smsl-n100 volumio[21746]: info: Received an update from plugin. extracting info from payload May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushState May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output update for this device May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushState May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output update for this device May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output May 23 19:15:19 smsl-n100 volumio[21746]: info: ControllerMpd::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::servicePushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} May 23 19:15:19 smsl-n100 volumio[21746]: verbose: CURRENT POSITION 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::syncState stateService play May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::syncState currentStatus play May 23 19:15:19 smsl-n100 volumio[21746]: info: Received an update from plugin. extracting info from payload May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushState May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output update for this device May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushState May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output update for this device May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output May 23 19:15:19 smsl-n100 volumio[21746]: info: ControllerMpd::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::servicePushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} May 23 19:15:19 smsl-n100 volumio[21746]: verbose: CURRENT POSITION 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::syncState stateService play May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::syncState currentStatus play May 23 19:15:19 smsl-n100 volumio[21746]: info: Received an update from plugin. extracting info from payload May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushState May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output update for this device May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushState May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output update for this device May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output May 23 19:15:19 smsl-n100 volumio[21746]: info: ControllerMpd::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::servicePushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} May 23 19:15:19 smsl-n100 volumio[21746]: verbose: CURRENT POSITION 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::syncState stateService play May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::syncState currentStatus play May 23 19:15:19 smsl-n100 volumio[21746]: info: Received an update from plugin. extracting info from payload May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushState May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output update for this device May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushState May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output update for this device May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output May 23 19:15:19 smsl-n100 volumio[21746]: info: ControllerMpd::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::servicePushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} May 23 19:15:19 smsl-n100 volumio[21746]: verbose: CURRENT POSITION 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::syncState stateService play May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::syncState currentStatus play May 23 19:15:19 smsl-n100 volumio[21746]: info: Received an update from plugin. extracting info from payload May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushState May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output update for this device May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushState May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output update for this device May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output May 23 19:15:19 smsl-n100 volumio[21746]: info: ------------------------------ 423ms May 23 19:15:19 smsl-n100 volumio[21746]: info: ------------------------------ 416ms May 23 19:15:19 smsl-n100 volumio[21746]: info: ------------------------------ 414ms May 23 19:15:19 smsl-n100 volumio[21746]: info: ------------------------------ 417ms May 23 19:15:19 smsl-n100 volumio[21746]: info: ------------------------------ 414ms May 23 19:15:19 smsl-n100 volumio[21746]: info: ------------------------------ 412ms May 23 19:15:19 smsl-n100 volumio[21746]: info: sendMpdCommand playlistinfo took 333 milliseconds May 23 19:15:19 smsl-n100 volumio[21746]: info: sendMpdCommand playlistinfo took 332 milliseconds May 23 19:15:19 smsl-n100 volumio[21746]: info: sendMpdCommand playlistinfo took 331 milliseconds May 23 19:15:19 smsl-n100 volumio[21746]: info: sendMpdCommand playlistinfo took 331 milliseconds May 23 19:15:19 smsl-n100 volumio[21746]: info: sendMpdCommand playlistinfo took 330 milliseconds May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::parseTrackInfo May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::parseTrackInfo May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::parseTrackInfo May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::parseTrackInfo May 23 19:15:19 smsl-n100 volumio[21746]: verbose: ControllerMpd::parseTrackInfo May 23 19:15:19 smsl-n100 volumio[21746]: info: ControllerMpd::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::servicePushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} May 23 19:15:19 smsl-n100 volumio[21746]: verbose: CURRENT POSITION 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::syncState stateService play May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::syncState currentStatus play May 23 19:15:19 smsl-n100 volumio[21746]: info: Received an update from plugin. extracting info from payload May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushState May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output update for this device May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushState May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output update for this device May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output May 23 19:15:19 smsl-n100 volumio[21746]: info: ControllerMpd::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::servicePushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} May 23 19:15:19 smsl-n100 volumio[21746]: verbose: CURRENT POSITION 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::syncState stateService play May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::syncState currentStatus play May 23 19:15:19 smsl-n100 volumio[21746]: info: Received an update from plugin. extracting info from payload May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushState May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output update for this device May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushState May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output update for this device May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output May 23 19:15:19 smsl-n100 volumio[21746]: info: ControllerMpd::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::servicePushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} May 23 19:15:19 smsl-n100 volumio[21746]: verbose: CURRENT POSITION 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::syncState stateService play May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::syncState currentStatus play May 23 19:15:19 smsl-n100 volumio[21746]: info: Received an update from plugin. extracting info from payload May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushState May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output update for this device May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushState May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output update for this device May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output May 23 19:15:19 smsl-n100 volumio[21746]: info: ControllerMpd::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::servicePushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} May 23 19:15:19 smsl-n100 volumio[21746]: verbose: CURRENT POSITION 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::syncState stateService play May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::syncState currentStatus play May 23 19:15:19 smsl-n100 volumio[21746]: info: Received an update from plugin. extracting info from payload May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushState May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output update for this device May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushState May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output update for this device May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output May 23 19:15:19 smsl-n100 volumio[21746]: info: ControllerMpd::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::servicePushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} May 23 19:15:19 smsl-n100 volumio[21746]: verbose: CURRENT POSITION 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::syncState stateService play May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::syncState currentStatus play May 23 19:15:19 smsl-n100 volumio[21746]: info: Received an update from plugin. extracting info from payload May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushState May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output update for this device May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreStateMachine::pushState May 23 19:15:19 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushState May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output update for this device May 23 19:15:19 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output May 23 19:15:20 smsl-n100 volumio[21746]: info: ------------------------------ 640ms May 23 19:15:20 smsl-n100 volumio[21746]: info: ------------------------------ 638ms May 23 19:15:20 smsl-n100 volumio[21746]: info: ------------------------------ 636ms May 23 19:15:20 smsl-n100 volumio[21746]: info: ------------------------------ 633ms May 23 19:15:20 smsl-n100 volumio[21746]: info: ------------------------------ 630ms May 23 19:15:20 smsl-n100 volumio[21746]: info: Updating RAAT Signal Path May 23 19:15:20 smsl-n100 volumio[21746]: info: Updating RAAT Signal Path May 23 19:15:20 smsl-n100 volumio[21746]: info: Updating RAAT Signal Path May 23 19:15:20 smsl-n100 volumio[21746]: info: Updating RAAT Signal Path May 23 19:15:20 smsl-n100 volumio[21746]: info: Updating RAAT Signal Path May 23 19:15:20 smsl-n100 volumio[21746]: info: Updating RAAT Signal Path May 23 19:15:20 smsl-n100 volumio[21746]: info: Updating RAAT Signal Path May 23 19:15:20 smsl-n100 volumio[21746]: info: Updating RAAT Signal Path May 23 19:15:20 smsl-n100 volumio[21746]: info: Updating RAAT Signal Path May 23 19:15:20 smsl-n100 volumio[21746]: info: Updating RAAT Signal Path May 23 19:15:20 smsl-n100 volumio[21746]: info: Updating RAAT Signal Path May 23 19:15:20 smsl-n100 volumio[21746]: info: Updating RAAT Signal Path May 23 19:15:20 smsl-n100 volumio[21746]: info: Updating RAAT Signal Path May 23 19:15:20 smsl-n100 volumio[21746]: info: Updating RAAT Signal Path May 23 19:15:20 smsl-n100 volumio[21746]: info: Updating RAAT Signal Path May 23 19:15:20 smsl-n100 volumio[21746]: info: Updating RAAT Signal Path May 23 19:15:20 smsl-n100 volumio[21746]: info: Updating RAAT Signal Path May 23 19:15:20 smsl-n100 volumio[21746]: info: Updating RAAT Signal Path May 23 19:15:20 smsl-n100 volumio[21746]: info: Updating RAAT Signal Path May 23 19:15:20 smsl-n100 volumio[21746]: info: Updating RAAT Signal Path May 23 19:15:20 smsl-n100 volumio[21746]: info: Updating RAAT Signal Path May 23 19:15:20 smsl-n100 volumio[21746]: info: Updating RAAT Signal Path May 23 19:15:20 smsl-n100 volumio[21746]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/ABBA/Super%20Trouper/bd9b8433-8a89-4028-afaf-1b21fdc0d2ac.png' May 23 19:15:20 smsl-n100 volumio[21746]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/ABBA/Super%20Trouper/bd9b8433-8a89-4028-afaf-1b21fdc0d2ac.png' May 23 19:15:20 smsl-n100 volumio[21746]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/ABBA/Super%20Trouper/bd9b8433-8a89-4028-afaf-1b21fdc0d2ac.png' May 23 19:15:20 smsl-n100 volumio[21746]: info: CoreStateMachine::pushState May 23 19:15:20 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:20 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 19:15:20 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushState May 23 19:15:20 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output update for this device May 23 19:15:20 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output May 23 19:15:20 smsl-n100 volumio[21746]: error: Serial API: Failed to decode command: 100, message: undefined May 23 19:15:20 smsl-n100 volumio[21746]: error: Serial API: Failed to decode command: MAXVOL, message: May 23 19:15:20 smsl-n100 volumio[21746]: error: Serial API: Failed to decode command: 100, message: undefined May 23 19:15:20 smsl-n100 volumio[21746]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 23 19:15:20 smsl-n100 volumio[21746]: info: VolumeController::SetAlsaVolume0 May 23 19:15:20 smsl-n100 volumio[21746]: info: CoreStateMachine::pushState May 23 19:15:20 smsl-n100 volumio[21746]: info: CorePlayQueue::getTrack 0 May 23 19:15:20 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 19:15:20 smsl-n100 volumio[21746]: info: CoreCommandRouter::volumioPushState May 23 19:15:20 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output update for this device May 23 19:15:20 smsl-n100 volumio[21746]: info: MRS: Pushing multiroomSync output May 23 19:15:20 smsl-n100 volumio[21746]: info: Updating RAAT Signal Path May 23 19:15:21 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 23 19:15:27 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: cd_controller , handleBrowseUri May 23 19:15:27 smsl-n100 volumio[21746]: info: Preload queue cleared May 23 19:15:27 smsl-n100 volumio[21746]: info: Preloading song: cdaudio/track11 May 23 19:15:27 smsl-n100 volumio[21746]: info: Exploding uri cdaudio/track11 in service cd_controller May 23 19:15:27 smsl-n100 volumio[21746]: info: Executing endpoint getSimilarAlbums May 23 19:15:27 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums May 23 19:15:28 smsl-n100 volumio[21746]: info: Executing endpoint metavolumio May 23 19:15:28 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 23 19:15:28 smsl-n100 volumio[21746]: info: Executing endpoint metavolumio May 23 19:15:28 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 23 19:15:28 smsl-n100 volumio[21746]: error: Failed request for metavolumio API May 23 19:15:28 smsl-n100 volumio[21746]: error: Failed request for metavolumio API May 23 19:15:44 smsl-n100 nmbd[786]: [2025/05/23 19:15:44.368097, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 23 19:15:44 smsl-n100 nmbd[786]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.31.160 for name WORKGROUP<1d>. May 23 19:15:44 smsl-n100 nmbd[786]: This response was from IP 192.168.31.136, reporting an IP address of 192.168.31.136. May 23 19:16:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::getUIConfigOnPlugin May 23 19:16:19 smsl-n100 volumio[21746]: xcb_connection_has_error() returned true May 23 19:16:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 23 19:16:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 19:16:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 23 19:16:19 smsl-n100 volumio[21746]: xcb_connection_has_error() returned true May 23 19:16:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 19:16:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 19:16:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 19:16:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 19:16:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 19:16:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 19:16:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 23 19:16:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 23 19:16:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 23 19:16:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection May 23 19:16:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 19:16:19 smsl-n100 volumio[21746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 19:16:52 smsl-n100 volumio[21746]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 19:16:52 smsl-n100 volumio[21746]: Error: connect ETIMEDOUT 108.160.169.174:443 May 23 19:16:52 smsl-n100 volumio[21746]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 23 19:16:52 smsl-n100 volumio[21746]: errno: -110, May 23 19:16:52 smsl-n100 volumio[21746]: code: 'ETIMEDOUT', May 23 19:16:52 smsl-n100 volumio[21746]: syscall: 'connect', May 23 19:16:52 smsl-n100 volumio[21746]: address: '108.160.169.174', May 23 19:16:52 smsl-n100 volumio[21746]: port: 443 May 23 19:16:52 smsl-n100 volumio[21746]: } May 23 19:16:52 smsl-n100 volumio[21746]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 19:16:53 smsl-n100 sudo[24619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-23 19:15 May 23 19:16:53 smsl-n100 sudo[24619]: 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="cc0042a1eab1cb7982711a1347e242f8947b54b6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="723ba1d50268911818ebdca474fb7656df6050cb" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 14 Feb 2025 02:46:15 PM CET" VOLUMIO_VERSION="3.789" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="215d731aee688e00fb3cc923952be9a7"