-- Logs begin at Thu 2019-02-14 18:11:58 HKT, end at Tue 2026-02-17 11:46:35 HKT. -- Feb 17 11:45:04 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 17 11:45:04 smsl-n100 volumio[789]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 17 11:45:04 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 17 11:45:04 smsl-n100 volumio[789]: info: Received Get System Version Feb 17 11:45:04 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 11:45:04 smsl-n100 volumio[789]: info: Received Get System Info Feb 17 11:45:04 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:45:04 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:45:04 smsl-n100 volumio[789]: info: Discovery: Getting this device information Feb 17 11:45:04 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioGetState Feb 17 11:45:04 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:45:09 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 17 11:45:09 smsl-n100 volumio[789]: info: Preload queue cleared Feb 17 11:45:12 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 17 11:45:12 smsl-n100 volumio[789]: info: CURURI: playlists Feb 17 11:45:12 smsl-n100 volumio[789]: info: Listing playlists Feb 17 11:45:12 smsl-n100 volumio[789]: info: Preload queue cleared Feb 17 11:45:15 smsl-n100 volumio[789]: info: Preload queue cleared Feb 17 11:45:28 smsl-n100 volumio[789]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 11:45:28 smsl-n100 volumio[789]: xcb_connection_has_error() returned true Feb 17 11:45:28 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 17 11:45:28 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:45:28 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 17 11:45:28 smsl-n100 volumio[789]: xcb_connection_has_error() returned true Feb 17 11:45:28 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 11:45:28 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 11:45:28 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 11:45:28 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 11:45:28 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 11:45:28 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 11:45:28 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 11:45:28 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 17 11:45:28 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 17 11:45:34 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 17 11:45:34 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 17 11:45:35 smsl-n100 sudo[16712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 11:45:35 smsl-n100 sudo[16712]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 11:45:35 smsl-n100 sudo[16712]: pam_unix(sudo:session): session closed for user root Feb 17 11:45:35 smsl-n100 sudo[16714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 11:45:35 smsl-n100 sudo[16714]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 11:45:35 smsl-n100 sudo[16714]: pam_unix(sudo:session): session closed for user root Feb 17 11:45:35 smsl-n100 volumio[789]: verbose: New Socket.io Connection to 192.168.3.237 from 192.168.3.162 UA: Mozilla/5.0 (Linux; Android 16; PTP-AN10 Build/HONORPTP-AN10; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 17 11:45:35 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 17 11:45:36 smsl-n100 sudo[16718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 11:45:36 smsl-n100 sudo[16718]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 11:45:36 smsl-n100 sudo[16718]: pam_unix(sudo:session): session closed for user root Feb 17 11:45:36 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 17 11:45:36 smsl-n100 sudo[16721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 11:45:36 smsl-n100 sudo[16721]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 11:45:36 smsl-n100 sudo[16721]: pam_unix(sudo:session): session closed for user root Feb 17 11:45:36 smsl-n100 volumio[789]: verbose: New Socket.io Connection to 192.168.3.237 from 192.168.3.162 UA: Mozilla/5.0 (Linux; Android 16; PTP-AN10 Build/HONORPTP-AN10; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Feb 17 11:45:36 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 17 11:45:36 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioGetVisibleSources Feb 17 11:45:36 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:45:36 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioGetState Feb 17 11:45:36 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 17 11:45:36 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 17 11:45:36 smsl-n100 volumio[789]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 17 11:45:36 smsl-n100 volumio[789]: info: Received Get System Info Feb 17 11:45:36 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:45:36 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:45:36 smsl-n100 volumio[789]: info: Discovery: Getting this device information Feb 17 11:45:36 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioGetState Feb 17 11:45:36 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:45:36 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioGetState Feb 17 11:45:36 smsl-n100 volumio[789]: info: Listing playlists Feb 17 11:45:36 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 17 11:45:36 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 17 11:45:36 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 17 11:45:37 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 17 11:45:38 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 17 11:45:38 smsl-n100 volumio[789]: info: Received Get System Info Feb 17 11:45:38 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:45:38 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:45:38 smsl-n100 volumio[789]: info: Discovery: Getting this device information Feb 17 11:45:38 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioGetState Feb 17 11:45:38 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:45:39 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioGetQueue Feb 17 11:45:39 smsl-n100 volumio[789]: info: CoreStateMachine::getQueue Feb 17 11:45:39 smsl-n100 volumio[789]: info: CorePlayQueue::getQueue Feb 17 11:45:39 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 17 11:45:39 smsl-n100 volumio[789]: info: Received Get System Info Feb 17 11:45:39 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:45:39 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:45:39 smsl-n100 volumio[789]: info: Discovery: Getting this device information Feb 17 11:45:39 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioGetState Feb 17 11:45:39 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:45:43 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 17 11:45:43 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 11:45:43 smsl-n100 volumio[789]: info: Preload queue cleared Feb 17 11:45:45 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 17 11:45:45 smsl-n100 volumio[789]: info: Preload queue cleared Feb 17 11:45:46 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 17 11:45:50 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 17 11:45:52 smsl-n100 volumio[789]: info: Preload queue cleared Feb 17 11:45:54 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 17 11:45:54 smsl-n100 volumio[789]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 17 11:45:54 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 17 11:45:54 smsl-n100 volumio[789]: info: Received Get System Version Feb 17 11:45:54 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 11:45:54 smsl-n100 volumio[789]: info: Received Get System Info Feb 17 11:45:54 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:45:54 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:45:54 smsl-n100 volumio[789]: info: Discovery: Getting this device information Feb 17 11:45:54 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioGetState Feb 17 11:45:54 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:46:13 smsl-n100 volumio[789]: info: Preload queue cleared Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreStateMachine::ClearQueue Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreStateMachine::stop Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreStateMachine::stPlaybackTimer Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreStateMachine::updateTrackBlock Feb 17 11:46:13 smsl-n100 volumio[789]: info: CorePlayQueue::getTrackBlock Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreStateMachine::pushState Feb 17 11:46:13 smsl-n100 volumio[789]: info: CorePlayQueue::getTrack 0 Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioPushState Feb 17 11:46:13 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:46:13 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreStateMachine::serviceStop Feb 17 11:46:13 smsl-n100 volumio[789]: info: CorePlayQueue::getTrack 0 Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreCommandRouter::serviceStop Feb 17 11:46:13 smsl-n100 volumio[789]: info: [1771299973214] ControllerWebradio::stop Feb 17 11:46:13 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 11:46:13 smsl-n100 volumio[789]: info: CorePlayQueue::clearPlayQueue Feb 17 11:46:13 smsl-n100 volumio[789]: info: CorePlayQueue::saveQueue Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioPushQueue Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreStateMachine::addQueueItems Feb 17 11:46:13 smsl-n100 volumio[789]: info: CorePlayQueue::addQueueItems Feb 17 11:46:13 smsl-n100 volumio[789]: info: Preload queue cleared Feb 17 11:46:13 smsl-n100 volumio[789]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99578163 Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioPushQueue Feb 17 11:46:13 smsl-n100 volumio[789]: info: CorePlayQueue::saveQueue Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreStateMachine::updateTrackBlock Feb 17 11:46:13 smsl-n100 volumio[789]: info: CorePlayQueue::getTrackBlock Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioPlay Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreStateMachine::play index 0 Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreStateMachine::stop Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreStateMachine::play index undefined Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 11:46:13 smsl-n100 volumio[789]: info: CorePlayQueue::getTrack 0 Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreStateMachine::startPlaybackTimer Feb 17 11:46:13 smsl-n100 volumio[789]: info: CorePlayQueue::getTrack 0 Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioGetVisibleSources Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 17 11:46:13 smsl-n100 volumio[789]: info: [1771299973265] ControllerWebradio::clearAddPlayTrack Feb 17 11:46:13 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreStateMachine::pushState Feb 17 11:46:13 smsl-n100 volumio[789]: info: CorePlayQueue::getTrack 0 Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioPushState Feb 17 11:46:13 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:46:13 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output Feb 17 11:46:13 smsl-n100 volumio[789]: error: Serial API: Failed to decode command: 100, message: undefined Feb 17 11:46:13 smsl-n100 volumio[789]: error: Serial API: Failed to decode command: MAXVOL, message: Feb 17 11:46:13 smsl-n100 volumio[789]: error: Serial API: Failed to decode command: 100, message: undefined Feb 17 11:46:13 smsl-n100 volumio[789]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Feb 17 11:46:13 smsl-n100 volumio[789]: info: Feb 17 11:46:13 smsl-n100 volumio[789]: ---------------------------- MPD announces state update: player Feb 17 11:46:13 smsl-n100 volumio[789]: info: sendMpdCommand stop took 161 milliseconds Feb 17 11:46:13 smsl-n100 volumio[789]: info: ControllerMpd::getState Feb 17 11:46:13 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand status Feb 17 11:46:13 smsl-n100 volumio[789]: info: sendMpdCommand stop took 112 milliseconds Feb 17 11:46:13 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand clear Feb 17 11:46:13 smsl-n100 volumio[789]: info: Feb 17 11:46:13 smsl-n100 volumio[789]: ---------------------------- MPD announces state update: player Feb 17 11:46:13 smsl-n100 volumio[789]: info: ControllerMpd::getState Feb 17 11:46:13 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand status Feb 17 11:46:13 smsl-n100 volumio[789]: info: Feb 17 11:46:13 smsl-n100 volumio[789]: ---------------------------- MPD announces system playlist update Feb 17 11:46:13 smsl-n100 volumio[789]: info: Ignoring MPD Status Update Feb 17 11:46:13 smsl-n100 volumio[789]: info: sendMpdCommand status took 11 milliseconds Feb 17 11:46:13 smsl-n100 volumio[789]: info: sendMpdCommand clear took 9 milliseconds Feb 17 11:46:13 smsl-n100 volumio[789]: info: sendMpdCommand status took 6 milliseconds Feb 17 11:46:13 smsl-n100 volumio[789]: verbose: ControllerMpd::parseState Feb 17 11:46:13 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 11:46:13 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99578163" Feb 17 11:46:13 smsl-n100 volumio[789]: verbose: ControllerMpd::parseState Feb 17 11:46:13 smsl-n100 volumio[789]: info: ControllerMpd::pushState Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreCommandRouter::servicePushState Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreStateMachine::pushState Feb 17 11:46:13 smsl-n100 volumio[789]: info: CorePlayQueue::getTrack 0 Feb 17 11:46:13 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioPushState Feb 17 11:46:13 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:46:13 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output Feb 17 11:46:13 smsl-n100 volumio[789]: info: CorePlayQueue::getTrack 0 Feb 17 11:46:13 smsl-n100 volumio[789]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 17 11:46:13 smsl-n100 volumio[789]: info: ------------------------------ 37ms Feb 17 11:46:13 smsl-n100 volumio[789]: info: Feb 17 11:46:13 smsl-n100 volumio[789]: ---------------------------- MPD announces system playlist update Feb 17 11:46:13 smsl-n100 volumio[789]: info: Ignoring MPD Status Update Feb 17 11:46:13 smsl-n100 volumio[789]: error: updateQueue error: null Feb 17 11:46:13 smsl-n100 volumio[789]: info: ------------------------------ 38ms Feb 17 11:46:13 smsl-n100 volumio[789]: info: sendMpdCommand playlistinfo took 33 milliseconds Feb 17 11:46:13 smsl-n100 volumio[789]: verbose: ControllerMpd::parseTrackInfo Feb 17 11:46:13 smsl-n100 volumio[789]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 17 11:46:13 smsl-n100 volumio[789]: info: ------------------------------ 54ms Feb 17 11:46:14 smsl-n100 volumio[789]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99578163" took 1391 milliseconds Feb 17 11:46:14 smsl-n100 volumio[789]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 17 11:46:14 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand play Feb 17 11:46:14 smsl-n100 volumio[789]: info: Feb 17 11:46:14 smsl-n100 volumio[789]: ---------------------------- MPD announces system playlist update Feb 17 11:46:14 smsl-n100 volumio[789]: info: Ignoring MPD Status Update Feb 17 11:46:14 smsl-n100 volumio[789]: info: ------------------------------ 1370ms Feb 17 11:46:14 smsl-n100 volumio[789]: info: Feb 17 11:46:14 smsl-n100 volumio[789]: ---------------------------- MPD announces system playlist update Feb 17 11:46:14 smsl-n100 volumio[789]: info: Ignoring MPD Status Update Feb 17 11:46:14 smsl-n100 volumio[789]: info: sendMpdCommand play took 18 milliseconds Feb 17 11:46:14 smsl-n100 volumio[789]: info: ------------------------------ 17ms Feb 17 11:46:14 smsl-n100 volumio[789]: info: ------------------------------ 6ms Feb 17 11:46:15 smsl-n100 volumio[789]: info: Feb 17 11:46:15 smsl-n100 volumio[789]: ---------------------------- MPD announces state update: player Feb 17 11:46:15 smsl-n100 volumio[789]: info: ControllerMpd::getState Feb 17 11:46:15 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand status Feb 17 11:46:15 smsl-n100 volumio[789]: info: Feb 17 11:46:15 smsl-n100 volumio[789]: ---------------------------- MPD announces state update: player Feb 17 11:46:15 smsl-n100 volumio[789]: info: ControllerMpd::getState Feb 17 11:46:15 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand status Feb 17 11:46:15 smsl-n100 volumio[789]: info: Feb 17 11:46:15 smsl-n100 volumio[789]: ---------------------------- MPD announces system playlist update Feb 17 11:46:15 smsl-n100 volumio[789]: info: Ignoring MPD Status Update Feb 17 11:46:15 smsl-n100 volumio[789]: info: Feb 17 11:46:15 smsl-n100 volumio[789]: ---------------------------- MPD announces state update: player Feb 17 11:46:15 smsl-n100 volumio[789]: info: sendMpdCommand status took 406 milliseconds Feb 17 11:46:15 smsl-n100 volumio[789]: info: ControllerMpd::getState Feb 17 11:46:15 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand status Feb 17 11:46:15 smsl-n100 volumio[789]: verbose: ControllerMpd::parseState Feb 17 11:46:15 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 11:46:15 smsl-n100 volumio[789]: info: sendMpdCommand status took 410 milliseconds Feb 17 11:46:15 smsl-n100 volumio[789]: info: ------------------------------ 12ms Feb 17 11:46:15 smsl-n100 volumio[789]: info: sendMpdCommand status took 7 milliseconds Feb 17 11:46:15 smsl-n100 volumio[789]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 17 11:46:15 smsl-n100 volumio[789]: verbose: ControllerMpd::parseState Feb 17 11:46:15 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 11:46:15 smsl-n100 volumio[789]: verbose: ControllerMpd::parseState Feb 17 11:46:15 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 11:46:15 smsl-n100 volumio[789]: verbose: ControllerMpd::parseTrackInfo Feb 17 11:46:15 smsl-n100 volumio[789]: info: ControllerMpd::pushState Feb 17 11:46:15 smsl-n100 volumio[789]: info: CoreCommandRouter::servicePushState Feb 17 11:46:15 smsl-n100 volumio[789]: info: CorePlayQueue::getTrack 0 Feb 17 11:46:15 smsl-n100 volumio[789]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"DHN Plying: SHERMANOLOGY - MOVIN' TOO FAST (EXTENDED MIX)","artist":"Deep House Network","album":null,"uri":"http://158.69.247.54:8000/","trackType":"54:8000/"} Feb 17 11:46:15 smsl-n100 volumio[789]: verbose: CURRENT POSITION 0 Feb 17 11:46:15 smsl-n100 volumio[789]: info: CoreStateMachine::syncState stateService play Feb 17 11:46:15 smsl-n100 volumio[789]: info: CoreStateMachine::syncState currentStatus stop Feb 17 11:46:15 smsl-n100 volumio[789]: info: ------------------------------ 429ms Feb 17 11:46:15 smsl-n100 volumio[789]: info: Feb 17 11:46:15 smsl-n100 volumio[789]: ---------------------------- MPD announces system playlist update Feb 17 11:46:15 smsl-n100 volumio[789]: info: Ignoring MPD Status Update Feb 17 11:46:15 smsl-n100 volumio[789]: info: Feb 17 11:46:15 smsl-n100 volumio[789]: ---------------------------- MPD announces state update: player Feb 17 11:46:15 smsl-n100 volumio[789]: info: ControllerMpd::getState Feb 17 11:46:15 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand status Feb 17 11:46:15 smsl-n100 volumio[789]: info: sendMpdCommand playlistinfo took 14 milliseconds Feb 17 11:46:15 smsl-n100 volumio[789]: info: sendMpdCommand playlistinfo took 14 milliseconds Feb 17 11:46:15 smsl-n100 volumio[789]: info: ------------------------------ 8ms Feb 17 11:46:15 smsl-n100 volumio[789]: info: sendMpdCommand status took 6 milliseconds Feb 17 11:46:15 smsl-n100 volumio[789]: verbose: ControllerMpd::parseTrackInfo Feb 17 11:46:15 smsl-n100 volumio[789]: verbose: ControllerMpd::parseTrackInfo Feb 17 11:46:15 smsl-n100 volumio[789]: verbose: ControllerMpd::parseState Feb 17 11:46:15 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 11:46:15 smsl-n100 volumio[789]: info: ControllerMpd::pushState Feb 17 11:46:15 smsl-n100 volumio[789]: info: CoreCommandRouter::servicePushState Feb 17 11:46:15 smsl-n100 volumio[789]: info: CorePlayQueue::getTrack 0 Feb 17 11:46:15 smsl-n100 volumio[789]: verbose: STATE SERVICE {"status":"play","position":0,"seek":612,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"DHN Plying: SHERMANOLOGY - MOVIN' TOO FAST (EXTENDED MIX)","artist":"Deep House Network","album":null,"uri":"http://158.69.247.54:8000/","trackType":"54:8000/"} Feb 17 11:46:15 smsl-n100 volumio[789]: verbose: CURRENT POSITION 0 Feb 17 11:46:15 smsl-n100 volumio[789]: info: CoreStateMachine::syncState stateService play Feb 17 11:46:15 smsl-n100 volumio[789]: info: CoreStateMachine::syncState currentStatus play Feb 17 11:46:15 smsl-n100 volumio[789]: info: Received an update from plugin. extracting info from payload Feb 17 11:46:15 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 17 11:46:15 smsl-n100 volumio[789]: info: CoreStateMachine::pushState Feb 17 11:46:15 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 11:46:15 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioPushState Feb 17 11:46:15 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:46:15 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output Feb 17 11:46:15 smsl-n100 volumio[789]: info: CoreStateMachine::pushState Feb 17 11:46:15 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioPushState Feb 17 11:46:15 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:46:15 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output Feb 17 11:46:15 smsl-n100 volumio[789]: info: ControllerMpd::pushState Feb 17 11:46:15 smsl-n100 volumio[789]: info: CoreCommandRouter::servicePushState Feb 17 11:46:15 smsl-n100 volumio[789]: info: CorePlayQueue::getTrack 0 Feb 17 11:46:15 smsl-n100 volumio[789]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"DHN Plying: SHERMANOLOGY - MOVIN' TOO FAST (EXTENDED MIX)","artist":"Deep House Network","album":null,"uri":"http://158.69.247.54:8000/","trackType":"54:8000/"} Feb 17 11:46:15 smsl-n100 volumio[789]: verbose: CURRENT POSITION 0 Feb 17 11:46:15 smsl-n100 volumio[789]: info: CoreStateMachine::syncState stateService play Feb 17 11:46:15 smsl-n100 volumio[789]: info: CoreStateMachine::syncState currentStatus play Feb 17 11:46:15 smsl-n100 volumio[789]: info: Received an update from plugin. extracting info from payload Feb 17 11:46:15 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 17 11:46:15 smsl-n100 volumio[789]: info: CoreStateMachine::pushState Feb 17 11:46:15 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioPushState Feb 17 11:46:15 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:46:15 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output Feb 17 11:46:15 smsl-n100 volumio[789]: info: CoreStateMachine::pushState Feb 17 11:46:15 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioPushState Feb 17 11:46:15 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:46:15 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output Feb 17 11:46:16 smsl-n100 volumio[789]: info: ------------------------------ 519ms Feb 17 11:46:16 smsl-n100 volumio[789]: info: ------------------------------ 118ms Feb 17 11:46:16 smsl-n100 volumio[789]: info: sendMpdCommand playlistinfo took 86 milliseconds Feb 17 11:46:16 smsl-n100 volumio[789]: verbose: ControllerMpd::parseTrackInfo Feb 17 11:46:16 smsl-n100 volumio[789]: info: ControllerMpd::pushState Feb 17 11:46:16 smsl-n100 volumio[789]: info: CoreCommandRouter::servicePushState Feb 17 11:46:16 smsl-n100 volumio[789]: info: CorePlayQueue::getTrack 0 Feb 17 11:46:16 smsl-n100 volumio[789]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"DHN Plying: SHERMANOLOGY - MOVIN' TOO FAST (EXTENDED MIX)","artist":"Deep House Network","album":null,"uri":"http://158.69.247.54:8000/","trackType":"54:8000/"} Feb 17 11:46:16 smsl-n100 volumio[789]: verbose: CURRENT POSITION 0 Feb 17 11:46:16 smsl-n100 volumio[789]: info: CoreStateMachine::syncState stateService play Feb 17 11:46:16 smsl-n100 volumio[789]: info: CoreStateMachine::syncState currentStatus play Feb 17 11:46:16 smsl-n100 volumio[789]: info: Received an update from plugin. extracting info from payload Feb 17 11:46:16 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 17 11:46:16 smsl-n100 volumio[789]: info: CoreStateMachine::pushState Feb 17 11:46:16 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 11:46:16 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioPushState Feb 17 11:46:16 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:46:16 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output Feb 17 11:46:16 smsl-n100 volumio[789]: info: CoreStateMachine::pushState Feb 17 11:46:16 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioPushState Feb 17 11:46:16 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:46:16 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output Feb 17 11:46:16 smsl-n100 volumio[789]: info: ------------------------------ 159ms Feb 17 11:46:16 smsl-n100 volumio[789]: info: CoreStateMachine::pushState Feb 17 11:46:16 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 11:46:16 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioPushState Feb 17 11:46:16 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:46:16 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output Feb 17 11:46:16 smsl-n100 volumio[789]: error: Serial API: Failed to decode command: 100, message: undefined Feb 17 11:46:16 smsl-n100 volumio[789]: error: Serial API: Failed to decode command: MAXVOL, message: Feb 17 11:46:16 smsl-n100 volumio[789]: error: Serial API: Failed to decode command: 100, message: undefined Feb 17 11:46:16 smsl-n100 volumio[789]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Feb 17 11:46:29 smsl-n100 volumio[789]: info: Preload queue cleared Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreStateMachine::ClearQueue Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreStateMachine::stop Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreStateMachine::stPlaybackTimer Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreStateMachine::updateTrackBlock Feb 17 11:46:29 smsl-n100 volumio[789]: info: CorePlayQueue::getTrackBlock Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreStateMachine::pushState Feb 17 11:46:29 smsl-n100 volumio[789]: info: CorePlayQueue::getTrack 0 Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioPushState Feb 17 11:46:29 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:46:29 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreStateMachine::serviceStop Feb 17 11:46:29 smsl-n100 volumio[789]: info: CorePlayQueue::getTrack 0 Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreCommandRouter::serviceStop Feb 17 11:46:29 smsl-n100 volumio[789]: info: [1771299989338] ControllerWebradio::stop Feb 17 11:46:29 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 11:46:29 smsl-n100 volumio[789]: info: CorePlayQueue::clearPlayQueue Feb 17 11:46:29 smsl-n100 volumio[789]: info: CorePlayQueue::saveQueue Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioPushQueue Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreStateMachine::addQueueItems Feb 17 11:46:29 smsl-n100 volumio[789]: info: CorePlayQueue::addQueueItems Feb 17 11:46:29 smsl-n100 volumio[789]: info: Preload queue cleared Feb 17 11:46:29 smsl-n100 volumio[789]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320 Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioPushQueue Feb 17 11:46:29 smsl-n100 volumio[789]: info: CorePlayQueue::saveQueue Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreStateMachine::updateTrackBlock Feb 17 11:46:29 smsl-n100 volumio[789]: info: CorePlayQueue::getTrackBlock Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioPlay Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreStateMachine::play index 0 Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreStateMachine::stop Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreStateMachine::play index undefined Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 11:46:29 smsl-n100 volumio[789]: info: CorePlayQueue::getTrack 0 Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreStateMachine::startPlaybackTimer Feb 17 11:46:29 smsl-n100 volumio[789]: info: CorePlayQueue::getTrack 0 Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioGetVisibleSources Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 17 11:46:29 smsl-n100 volumio[789]: info: [1771299989382] ControllerWebradio::clearAddPlayTrack Feb 17 11:46:29 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreStateMachine::pushState Feb 17 11:46:29 smsl-n100 volumio[789]: info: CorePlayQueue::getTrack 0 Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioPushState Feb 17 11:46:29 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:46:29 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output Feb 17 11:46:29 smsl-n100 volumio[789]: error: Serial API: Failed to decode command: 100, message: undefined Feb 17 11:46:29 smsl-n100 volumio[789]: error: Serial API: Failed to decode command: MAXVOL, message: Feb 17 11:46:29 smsl-n100 volumio[789]: error: Serial API: Failed to decode command: 100, message: undefined Feb 17 11:46:29 smsl-n100 volumio[789]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Feb 17 11:46:29 smsl-n100 volumio[789]: info: Feb 17 11:46:29 smsl-n100 volumio[789]: ---------------------------- MPD announces state update: player Feb 17 11:46:29 smsl-n100 volumio[789]: info: sendMpdCommand stop took 157 milliseconds Feb 17 11:46:29 smsl-n100 volumio[789]: info: ControllerMpd::getState Feb 17 11:46:29 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand status Feb 17 11:46:29 smsl-n100 volumio[789]: info: sendMpdCommand stop took 114 milliseconds Feb 17 11:46:29 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand clear Feb 17 11:46:29 smsl-n100 volumio[789]: info: Feb 17 11:46:29 smsl-n100 volumio[789]: ---------------------------- MPD announces state update: player Feb 17 11:46:29 smsl-n100 volumio[789]: info: ControllerMpd::getState Feb 17 11:46:29 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand status Feb 17 11:46:29 smsl-n100 volumio[789]: info: Feb 17 11:46:29 smsl-n100 volumio[789]: ---------------------------- MPD announces system playlist update Feb 17 11:46:29 smsl-n100 volumio[789]: info: Ignoring MPD Status Update Feb 17 11:46:29 smsl-n100 volumio[789]: info: sendMpdCommand status took 10 milliseconds Feb 17 11:46:29 smsl-n100 volumio[789]: info: sendMpdCommand clear took 8 milliseconds Feb 17 11:46:29 smsl-n100 volumio[789]: info: sendMpdCommand status took 6 milliseconds Feb 17 11:46:29 smsl-n100 volumio[789]: verbose: ControllerMpd::parseState Feb 17 11:46:29 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 11:46:29 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" Feb 17 11:46:29 smsl-n100 volumio[789]: verbose: ControllerMpd::parseState Feb 17 11:46:29 smsl-n100 volumio[789]: info: ControllerMpd::pushState Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreCommandRouter::servicePushState Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreStateMachine::pushState Feb 17 11:46:29 smsl-n100 volumio[789]: info: CorePlayQueue::getTrack 0 Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioPushState Feb 17 11:46:29 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:46:29 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output Feb 17 11:46:29 smsl-n100 volumio[789]: info: CorePlayQueue::getTrack 0 Feb 17 11:46:29 smsl-n100 volumio[789]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 17 11:46:29 smsl-n100 volumio[789]: info: ------------------------------ 34ms Feb 17 11:46:29 smsl-n100 volumio[789]: info: Feb 17 11:46:29 smsl-n100 volumio[789]: ---------------------------- MPD announces system playlist update Feb 17 11:46:29 smsl-n100 volumio[789]: info: Ignoring MPD Status Update Feb 17 11:46:29 smsl-n100 volumio[789]: error: updateQueue error: null Feb 17 11:46:29 smsl-n100 volumio[789]: info: ------------------------------ 41ms Feb 17 11:46:29 smsl-n100 volumio[789]: info: sendMpdCommand playlistinfo took 37 milliseconds Feb 17 11:46:29 smsl-n100 volumio[789]: verbose: ControllerMpd::parseTrackInfo Feb 17 11:46:29 smsl-n100 volumio[789]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 17 11:46:29 smsl-n100 volumio[789]: info: ------------------------------ 56ms Feb 17 11:46:29 smsl-n100 volumio[789]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" took 263 milliseconds Feb 17 11:46:29 smsl-n100 volumio[789]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 17 11:46:29 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand play Feb 17 11:46:29 smsl-n100 volumio[789]: info: Feb 17 11:46:29 smsl-n100 volumio[789]: ---------------------------- MPD announces system playlist update Feb 17 11:46:29 smsl-n100 volumio[789]: info: Ignoring MPD Status Update Feb 17 11:46:29 smsl-n100 volumio[789]: info: ------------------------------ 243ms Feb 17 11:46:29 smsl-n100 volumio[789]: info: sendMpdCommand play took 5 milliseconds Feb 17 11:46:29 smsl-n100 volumio[789]: info: Feb 17 11:46:29 smsl-n100 volumio[789]: ---------------------------- MPD announces system playlist update Feb 17 11:46:29 smsl-n100 volumio[789]: info: Ignoring MPD Status Update Feb 17 11:46:29 smsl-n100 volumio[789]: info: ------------------------------ 8ms Feb 17 11:46:29 smsl-n100 volumio[789]: info: ------------------------------ 4ms Feb 17 11:46:31 smsl-n100 volumio[789]: info: Feb 17 11:46:31 smsl-n100 volumio[789]: ---------------------------- MPD announces state update: player Feb 17 11:46:31 smsl-n100 volumio[789]: info: ControllerMpd::getState Feb 17 11:46:31 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand status Feb 17 11:46:31 smsl-n100 volumio[789]: info: Feb 17 11:46:31 smsl-n100 volumio[789]: ---------------------------- MPD announces state update: player Feb 17 11:46:31 smsl-n100 volumio[789]: info: ControllerMpd::getState Feb 17 11:46:31 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand status Feb 17 11:46:32 smsl-n100 volumio[789]: info: sendMpdCommand status took 403 milliseconds Feb 17 11:46:32 smsl-n100 volumio[789]: verbose: ControllerMpd::parseState Feb 17 11:46:32 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 11:46:32 smsl-n100 volumio[789]: info: Feb 17 11:46:32 smsl-n100 volumio[789]: ---------------------------- MPD announces system playlist update Feb 17 11:46:32 smsl-n100 volumio[789]: info: Ignoring MPD Status Update Feb 17 11:46:32 smsl-n100 volumio[789]: info: Feb 17 11:46:32 smsl-n100 volumio[789]: ---------------------------- MPD announces state update: player Feb 17 11:46:32 smsl-n100 volumio[789]: info: ControllerMpd::getState Feb 17 11:46:32 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand status Feb 17 11:46:32 smsl-n100 volumio[789]: info: sendMpdCommand status took 407 milliseconds Feb 17 11:46:32 smsl-n100 volumio[789]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 17 11:46:32 smsl-n100 volumio[789]: verbose: ControllerMpd::parseState Feb 17 11:46:32 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 11:46:32 smsl-n100 volumio[789]: verbose: ControllerMpd::parseTrackInfo Feb 17 11:46:32 smsl-n100 volumio[789]: info: ControllerMpd::pushState Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreCommandRouter::servicePushState Feb 17 11:46:32 smsl-n100 volumio[789]: info: CorePlayQueue::getTrack 0 Feb 17 11:46:32 smsl-n100 volumio[789]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Feb 17 11:46:32 smsl-n100 volumio[789]: verbose: CURRENT POSITION 0 Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreStateMachine::syncState stateService play Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreStateMachine::syncState currentStatus stop Feb 17 11:46:32 smsl-n100 volumio[789]: info: ------------------------------ 424ms Feb 17 11:46:32 smsl-n100 volumio[789]: info: Feb 17 11:46:32 smsl-n100 volumio[789]: ---------------------------- MPD announces system playlist update Feb 17 11:46:32 smsl-n100 volumio[789]: info: Ignoring MPD Status Update Feb 17 11:46:32 smsl-n100 volumio[789]: info: Feb 17 11:46:32 smsl-n100 volumio[789]: ---------------------------- MPD announces state update: player Feb 17 11:46:32 smsl-n100 volumio[789]: info: ControllerMpd::getState Feb 17 11:46:32 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand status Feb 17 11:46:32 smsl-n100 volumio[789]: info: ------------------------------ 22ms Feb 17 11:46:32 smsl-n100 volumio[789]: info: sendMpdCommand status took 19 milliseconds Feb 17 11:46:32 smsl-n100 volumio[789]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 17 11:46:32 smsl-n100 volumio[789]: info: ------------------------------ 9ms Feb 17 11:46:32 smsl-n100 volumio[789]: info: sendMpdCommand status took 6 milliseconds Feb 17 11:46:32 smsl-n100 volumio[789]: verbose: ControllerMpd::parseState Feb 17 11:46:32 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 11:46:32 smsl-n100 volumio[789]: verbose: ControllerMpd::parseTrackInfo Feb 17 11:46:32 smsl-n100 volumio[789]: verbose: ControllerMpd::parseState Feb 17 11:46:32 smsl-n100 volumio[789]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 11:46:32 smsl-n100 volumio[789]: info: ControllerMpd::pushState Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreCommandRouter::servicePushState Feb 17 11:46:32 smsl-n100 volumio[789]: info: CorePlayQueue::getTrack 0 Feb 17 11:46:32 smsl-n100 volumio[789]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Feb 17 11:46:32 smsl-n100 volumio[789]: verbose: CURRENT POSITION 0 Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreStateMachine::syncState stateService play Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreStateMachine::syncState currentStatus play Feb 17 11:46:32 smsl-n100 volumio[789]: info: Received an update from plugin. extracting info from payload Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreStateMachine::pushState Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioPushState Feb 17 11:46:32 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:46:32 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreStateMachine::pushState Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioPushState Feb 17 11:46:32 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:46:32 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output Feb 17 11:46:32 smsl-n100 volumio[789]: info: ------------------------------ 534ms Feb 17 11:46:32 smsl-n100 volumio[789]: info: sendMpdCommand playlistinfo took 104 milliseconds Feb 17 11:46:32 smsl-n100 volumio[789]: info: sendMpdCommand playlistinfo took 104 milliseconds Feb 17 11:46:32 smsl-n100 volumio[789]: verbose: ControllerMpd::parseTrackInfo Feb 17 11:46:32 smsl-n100 volumio[789]: verbose: ControllerMpd::parseTrackInfo Feb 17 11:46:32 smsl-n100 volumio[789]: info: ControllerMpd::pushState Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreCommandRouter::servicePushState Feb 17 11:46:32 smsl-n100 volumio[789]: info: CorePlayQueue::getTrack 0 Feb 17 11:46:32 smsl-n100 volumio[789]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1237,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Feb 17 11:46:32 smsl-n100 volumio[789]: verbose: CURRENT POSITION 0 Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreStateMachine::syncState stateService play Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreStateMachine::syncState currentStatus play Feb 17 11:46:32 smsl-n100 volumio[789]: info: Received an update from plugin. extracting info from payload Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreStateMachine::pushState Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioPushState Feb 17 11:46:32 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:46:32 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreStateMachine::pushState Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioPushState Feb 17 11:46:32 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:46:32 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output Feb 17 11:46:32 smsl-n100 volumio[789]: info: ControllerMpd::pushState Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreCommandRouter::servicePushState Feb 17 11:46:32 smsl-n100 volumio[789]: info: CorePlayQueue::getTrack 0 Feb 17 11:46:32 smsl-n100 volumio[789]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1237,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Feb 17 11:46:32 smsl-n100 volumio[789]: verbose: CURRENT POSITION 0 Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreStateMachine::syncState stateService play Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreStateMachine::syncState currentStatus play Feb 17 11:46:32 smsl-n100 volumio[789]: info: Received an update from plugin. extracting info from payload Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreStateMachine::pushState Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioPushState Feb 17 11:46:32 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:46:32 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreStateMachine::pushState Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioPushState Feb 17 11:46:32 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:46:32 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output Feb 17 11:46:32 smsl-n100 volumio[789]: info: ------------------------------ 235ms Feb 17 11:46:32 smsl-n100 volumio[789]: info: ------------------------------ 222ms Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreStateMachine::pushState Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 11:46:32 smsl-n100 volumio[789]: info: CoreCommandRouter::volumioPushState Feb 17 11:46:32 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output update for this device Feb 17 11:46:32 smsl-n100 volumio[789]: info: MRS: Pushing multiroomSync output Feb 17 11:46:32 smsl-n100 volumio[789]: error: Serial API: Failed to decode command: 100, message: undefined Feb 17 11:46:32 smsl-n100 volumio[789]: error: Serial API: Failed to decode command: MAXVOL, message: Feb 17 11:46:32 smsl-n100 volumio[789]: error: Serial API: Failed to decode command: 100, message: undefined Feb 17 11:46:32 smsl-n100 volumio[789]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Feb 17 11:46:34 smsl-n100 volumio[789]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 11:46:34 smsl-n100 volumio[789]: TypeError: Cannot read property 'slice' of null Feb 17 11:46:34 smsl-n100 volumio[789]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Feb 17 11:46:34 smsl-n100 volumio[789]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Feb 17 11:46:34 smsl-n100 volumio[789]: at Object.onceWrapper (events.js:520:26) Feb 17 11:46:34 smsl-n100 volumio[789]: at ClientRequest.emit (events.js:400:28) Feb 17 11:46:34 smsl-n100 volumio[789]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Feb 17 11:46:34 smsl-n100 volumio[789]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Feb 17 11:46:34 smsl-n100 volumio[789]: at TLSSocket.socketOnData (_http_client.js:515:22) Feb 17 11:46:34 smsl-n100 volumio[789]: at TLSSocket.emit (events.js:400:28) Feb 17 11:46:34 smsl-n100 volumio[789]: at addChunk (internal/streams/readable.js:293:12) Feb 17 11:46:34 smsl-n100 volumio[789]: at readableAddChunk (internal/streams/readable.js:267:9) Feb 17 11:46:34 smsl-n100 volumio[789]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Feb 17 11:46:34 smsl-n100 volumio[789]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Feb 17 11:46:34 smsl-n100 volumio[789]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 11:46:35 smsl-n100 sudo[16869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-17 11:45 Feb 17 11:46:35 smsl-n100 sudo[16869]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="3441cddd43405c36fe444484553f10f2f5bc830b" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 13 Nov 2025 12:39:08 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="7a92d73f7a04bc7854b1812143743fd4"