-- Logs begin at Fri 2025-10-24 20:15:01 CEST, end at Fri 2025-10-24 20:30:06 CEST. -- Oct 24 20:29:03 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 20:29:03 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 20:29:03 smsl-n100 volumio[1071]: info: Discovery: Getting this device information Oct 24 20:29:03 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioGetState Oct 24 20:29:03 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 20:29:15 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioGetState Oct 24 20:29:15 smsl-n100 sudo[5027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 24 20:29:15 smsl-n100 sudo[5027]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 20:29:15 smsl-n100 sudo[5027]: pam_unix(sudo:session): session closed for user root Oct 24 20:29:15 smsl-n100 sudo[5029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 24 20:29:15 smsl-n100 sudo[5029]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 20:29:15 smsl-n100 sudo[5029]: pam_unix(sudo:session): session closed for user root Oct 24 20:29:15 smsl-n100 volumio[1071]: verbose: New Socket.io Connection to 192.168.0.16 from 192.168.0.20 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 24 20:29:15 smsl-n100 sudo[5033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 24 20:29:15 smsl-n100 sudo[5033]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 20:29:15 smsl-n100 sudo[5033]: pam_unix(sudo:session): session closed for user root Oct 24 20:29:15 smsl-n100 sudo[5035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 24 20:29:15 smsl-n100 sudo[5035]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 20:29:15 smsl-n100 sudo[5035]: pam_unix(sudo:session): session closed for user root Oct 24 20:29:15 smsl-n100 volumio[1071]: verbose: New Socket.io Connection to 192.168.0.16 from 192.168.0.20 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Oct 24 20:29:15 smsl-n100 volumio[1071]: info: Executing endpoint metavolumio Oct 24 20:29:15 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 24 20:29:15 smsl-n100 volumio[1071]: info: Executing endpoint metavolumio Oct 24 20:29:15 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 24 20:29:15 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 24 20:29:15 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioGetVisibleSources Oct 24 20:29:15 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 20:29:15 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioGetState Oct 24 20:29:15 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 24 20:29:15 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioGetQueue Oct 24 20:29:15 smsl-n100 volumio[1071]: info: CoreStateMachine::getQueue Oct 24 20:29:15 smsl-n100 volumio[1071]: info: CorePlayQueue::getQueue Oct 24 20:29:15 smsl-n100 volumio[1071]: info: Listing playlists Oct 24 20:29:15 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 24 20:29:15 smsl-n100 volumio[1071]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 24 20:29:15 smsl-n100 volumio[1071]: info: Received Get System Info Oct 24 20:29:15 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 20:29:15 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 20:29:15 smsl-n100 volumio[1071]: info: Discovery: Getting this device information Oct 24 20:29:15 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioGetState Oct 24 20:29:15 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 20:29:15 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioGetState Oct 24 20:29:15 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 24 20:29:15 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 24 20:29:15 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 24 20:29:17 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 24 20:29:17 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 24 20:29:17 smsl-n100 volumio[1071]: info: Received Get System Info Oct 24 20:29:17 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 20:29:17 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 20:29:17 smsl-n100 volumio[1071]: info: Discovery: Getting this device information Oct 24 20:29:17 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioGetState Oct 24 20:29:17 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 24 20:29:19 smsl-n100 volumio[1071]: info: Received Get System Info Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 20:29:19 smsl-n100 volumio[1071]: info: Discovery: Getting this device information Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioGetState Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioNext Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreStateMachine::next Oct 24 20:29:19 smsl-n100 volumio[1071]: info: [1761330559702] ControllerTidal::next Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreStateMachine::next Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreStateMachine::stop Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreStateMachine::stPlaybackTimer Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreStateMachine::updateTrackBlock Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CorePlayQueue::getTrackBlock Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreStateMachine::pushState Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CorePlayQueue::getTrack 0 Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioPushState Oct 24 20:29:19 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output update for this device Oct 24 20:29:19 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreStateMachine::serviceStop Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CorePlayQueue::getTrack 0 Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreCommandRouter::serviceStop Oct 24 20:29:19 smsl-n100 volumio[1071]: info: [1761330559742] ControllerTidal::stop Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 24 20:29:19 smsl-n100 volumio[1071]: info: ControllerMpd::stop Oct 24 20:29:19 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand stop Oct 24 20:29:19 smsl-n100 volumio[1071]: info: Updating RAAT Signal Path Oct 24 20:29:19 smsl-n100 volumio[1071]: info: Oct 24 20:29:19 smsl-n100 volumio[1071]: ---------------------------- MPD announces state update: player Oct 24 20:29:19 smsl-n100 volumio[1071]: info: sendMpdCommand stop took 84 milliseconds Oct 24 20:29:19 smsl-n100 volumio[1071]: info: ControllerMpd::getState Oct 24 20:29:19 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand status Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreStateMachine::play index undefined Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CorePlayQueue::getTrack 1 Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreStateMachine::startPlaybackTimer Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CorePlayQueue::getTrack 1 Oct 24 20:29:19 smsl-n100 volumio[1071]: info: [1761330559841] ControllerTidal::clearAddPlayTrack Oct 24 20:29:19 smsl-n100 volumio[1071]: info: Getting stream with soundQuality LOSSLESS Oct 24 20:29:19 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand stop Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreStateMachine::updateTrackBlock Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CorePlayQueue::getTrackBlock Oct 24 20:29:19 smsl-n100 volumio[1071]: info: Oct 24 20:29:19 smsl-n100 volumio[1071]: ---------------------------- MPD announces state update: player Oct 24 20:29:19 smsl-n100 volumio[1071]: info: ControllerMpd::getState Oct 24 20:29:19 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand status Oct 24 20:29:19 smsl-n100 volumio[1071]: info: Oct 24 20:29:19 smsl-n100 volumio[1071]: ---------------------------- MPD announces state update: player Oct 24 20:29:19 smsl-n100 volumio[1071]: info: ControllerMpd::getState Oct 24 20:29:19 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand status Oct 24 20:29:19 smsl-n100 volumio[1071]: info: sendMpdCommand status took 27 milliseconds Oct 24 20:29:19 smsl-n100 volumio[1071]: info: sendMpdCommand stop took 17 milliseconds Oct 24 20:29:19 smsl-n100 volumio[1071]: info: sendMpdCommand status took 10 milliseconds Oct 24 20:29:19 smsl-n100 volumio[1071]: info: sendMpdCommand status took 6 milliseconds Oct 24 20:29:19 smsl-n100 volumio[1071]: verbose: ControllerMpd::parseState Oct 24 20:29:19 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 20:29:19 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand clear Oct 24 20:29:19 smsl-n100 volumio[1071]: verbose: ControllerMpd::parseState Oct 24 20:29:19 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 20:29:19 smsl-n100 volumio[1071]: verbose: ControllerMpd::parseState Oct 24 20:29:19 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 20:29:19 smsl-n100 volumio[1071]: info: Oct 24 20:29:19 smsl-n100 volumio[1071]: ---------------------------- MPD announces system playlist update Oct 24 20:29:19 smsl-n100 volumio[1071]: info: Ignoring MPD Status Update Oct 24 20:29:19 smsl-n100 volumio[1071]: info: sendMpdCommand playlistinfo took 19 milliseconds Oct 24 20:29:19 smsl-n100 volumio[1071]: info: sendMpdCommand clear took 20 milliseconds Oct 24 20:29:19 smsl-n100 volumio[1071]: info: sendMpdCommand playlistinfo took 19 milliseconds Oct 24 20:29:19 smsl-n100 volumio[1071]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 24 20:29:19 smsl-n100 volumio[1071]: verbose: ControllerMpd::parseTrackInfo Oct 24 20:29:19 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/252371921&soundQuality=LOSSLESS" Oct 24 20:29:19 smsl-n100 volumio[1071]: verbose: ControllerMpd::parseTrackInfo Oct 24 20:29:19 smsl-n100 volumio[1071]: verbose: ControllerMpd::parseTrackInfo Oct 24 20:29:19 smsl-n100 volumio[1071]: info: ControllerMpd::pushState Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreCommandRouter::servicePushState Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreStateMachine::pushState Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CorePlayQueue::getTrack 1 Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioPushState Oct 24 20:29:19 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output update for this device Oct 24 20:29:19 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CorePlayQueue::getTrack 1 Oct 24 20:29:19 smsl-n100 volumio[1071]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Oct 24 20:29:19 smsl-n100 volumio[1071]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 24 20:29:19 smsl-n100 volumio[1071]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 24 20:29:19 smsl-n100 volumio[1071]: info: ------------------------------ 120ms Oct 24 20:29:19 smsl-n100 volumio[1071]: info: ------------------------------ 101ms Oct 24 20:29:19 smsl-n100 volumio[1071]: info: ------------------------------ 98ms Oct 24 20:29:19 smsl-n100 volumio[1071]: info: Oct 24 20:29:19 smsl-n100 volumio[1071]: ---------------------------- MPD announces system playlist update Oct 24 20:29:19 smsl-n100 volumio[1071]: info: Ignoring MPD Status Update Oct 24 20:29:19 smsl-n100 volumio[1071]: info: Oct 24 20:29:19 smsl-n100 volumio[1071]: ---------------------------- MPD announces system playlist update Oct 24 20:29:19 smsl-n100 volumio[1071]: info: Ignoring MPD Status Update Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreStateMachine::pushState Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CorePlayQueue::getTrack 1 Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:29:19 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioPushState Oct 24 20:29:19 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output update for this device Oct 24 20:29:19 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output Oct 24 20:29:19 smsl-n100 volumio[1071]: error: Serial API: Failed to decode command: 100, message: undefined Oct 24 20:29:19 smsl-n100 volumio[1071]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 24 20:29:19 smsl-n100 volumio[1071]: error: Serial API: Failed to decode command: 100, message: undefined Oct 24 20:29:19 smsl-n100 volumio[1071]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 24 20:29:20 smsl-n100 volumio[1071]: error: updateQueue error: null Oct 24 20:29:20 smsl-n100 volumio[1071]: info: Oct 24 20:29:20 smsl-n100 volumio[1071]: ---------------------------- MPD announces system playlist update Oct 24 20:29:20 smsl-n100 volumio[1071]: info: Ignoring MPD Status Update Oct 24 20:29:20 smsl-n100 volumio[1071]: info: ------------------------------ 136ms Oct 24 20:29:20 smsl-n100 volumio[1071]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/252371921&soundQuality=LOSSLESS" took 127 milliseconds Oct 24 20:29:20 smsl-n100 volumio[1071]: info: ------------------------------ 69ms Oct 24 20:29:20 smsl-n100 volumio[1071]: info: ------------------------------ 67ms Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand play Oct 24 20:29:20 smsl-n100 volumio[1071]: info: Oct 24 20:29:20 smsl-n100 volumio[1071]: ---------------------------- MPD announces system playlist update Oct 24 20:29:20 smsl-n100 volumio[1071]: info: Ignoring MPD Status Update Oct 24 20:29:20 smsl-n100 volumio[1071]: STREAMING PROXY: Handling url /?data=tidal://song/252371921&soundQuality=LOSSLESS Oct 24 20:29:20 smsl-n100 volumio[1071]: info: Oct 24 20:29:20 smsl-n100 volumio[1071]: ---------------------------- MPD announces system playlist update Oct 24 20:29:20 smsl-n100 volumio[1071]: info: Ignoring MPD Status Update Oct 24 20:29:20 smsl-n100 volumio[1071]: info: Updating RAAT Signal Path Oct 24 20:29:20 smsl-n100 volumio[1071]: info: ------------------------------ 62ms Oct 24 20:29:20 smsl-n100 volumio[1071]: info: sendMpdCommand play took 51 milliseconds Oct 24 20:29:20 smsl-n100 volumio[1071]: info: ------------------------------ 50ms Oct 24 20:29:20 smsl-n100 volumio[1071]: info: ------------------------------ 43ms Oct 24 20:29:20 smsl-n100 volumio[1071]: info: Executing endpoint getStreamUrltidal Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Oct 24 20:29:20 smsl-n100 volumio[1071]: info: VolumeController::SetAlsaVolume0 Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreStateMachine::pushState Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioPushState Oct 24 20:29:20 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output update for this device Oct 24 20:29:20 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output Oct 24 20:29:20 smsl-n100 volumio[1071]: info: Updating RAAT Signal Path Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreStateMachine::pushState Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioPushState Oct 24 20:29:20 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output update for this device Oct 24 20:29:20 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output Oct 24 20:29:20 smsl-n100 volumio[1071]: error: Serial API: Failed to decode command: 100, message: undefined Oct 24 20:29:20 smsl-n100 volumio[1071]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 24 20:29:20 smsl-n100 volumio[1071]: error: Serial API: Failed to decode command: 100, message: undefined Oct 24 20:29:20 smsl-n100 volumio[1071]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 24 20:29:20 smsl-n100 volumio[1071]: info: getStreamUrl took 350 milliseconds Oct 24 20:29:20 smsl-n100 volumio[1071]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidjZDMwOWVhMTRjN2Q3OGRlNWQ1MDNlM2YwZjg0YjhhMl82MS5tcDQ/0.flac?token=1761334160~MDU2YzIxN2Y5OWUxNDMxOGVhYTk1NjI1ODc3NGExMTk0ZmU1NGVhNg== Oct 24 20:29:20 smsl-n100 volumio[1071]: STREAMING PROXY: Response: 200, length: 42431013 Oct 24 20:29:20 smsl-n100 volumio[1071]: info: Oct 24 20:29:20 smsl-n100 volumio[1071]: ---------------------------- MPD announces state update: player Oct 24 20:29:20 smsl-n100 volumio[1071]: info: ControllerMpd::getState Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand status Oct 24 20:29:20 smsl-n100 volumio[1071]: info: Oct 24 20:29:20 smsl-n100 volumio[1071]: ---------------------------- MPD announces state update: player Oct 24 20:29:20 smsl-n100 volumio[1071]: info: ControllerMpd::getState Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand status Oct 24 20:29:20 smsl-n100 volumio[1071]: info: Oct 24 20:29:20 smsl-n100 volumio[1071]: ---------------------------- MPD announces state update: player Oct 24 20:29:20 smsl-n100 volumio[1071]: info: ControllerMpd::getState Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand status Oct 24 20:29:20 smsl-n100 volumio[1071]: info: Oct 24 20:29:20 smsl-n100 volumio[1071]: ---------------------------- MPD announces state update: player Oct 24 20:29:20 smsl-n100 volumio[1071]: info: ControllerMpd::getState Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand status Oct 24 20:29:20 smsl-n100 volumio[1071]: info: Oct 24 20:29:20 smsl-n100 volumio[1071]: ---------------------------- MPD announces state update: player Oct 24 20:29:20 smsl-n100 volumio[1071]: info: sendMpdCommand status took 34 milliseconds Oct 24 20:29:20 smsl-n100 volumio[1071]: info: ControllerMpd::getState Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand status Oct 24 20:29:20 smsl-n100 volumio[1071]: info: sendMpdCommand status took 33 milliseconds Oct 24 20:29:20 smsl-n100 volumio[1071]: info: sendMpdCommand status took 26 milliseconds Oct 24 20:29:20 smsl-n100 volumio[1071]: info: sendMpdCommand status took 21 milliseconds Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: ControllerMpd::parseState Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: ControllerMpd::parseState Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: ControllerMpd::parseState Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: ControllerMpd::parseState Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 20:29:20 smsl-n100 volumio[1071]: info: Oct 24 20:29:20 smsl-n100 volumio[1071]: ---------------------------- MPD announces state update: player Oct 24 20:29:20 smsl-n100 volumio[1071]: info: ControllerMpd::getState Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand status Oct 24 20:29:20 smsl-n100 volumio[1071]: info: sendMpdCommand status took 45 milliseconds Oct 24 20:29:20 smsl-n100 volumio[1071]: info: sendMpdCommand playlistinfo took 35 milliseconds Oct 24 20:29:20 smsl-n100 volumio[1071]: info: sendMpdCommand playlistinfo took 32 milliseconds Oct 24 20:29:20 smsl-n100 volumio[1071]: info: sendMpdCommand playlistinfo took 30 milliseconds Oct 24 20:29:20 smsl-n100 volumio[1071]: info: sendMpdCommand playlistinfo took 29 milliseconds Oct 24 20:29:20 smsl-n100 volumio[1071]: info: sendMpdCommand status took 16 milliseconds Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: ControllerMpd::parseState Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: ControllerMpd::parseTrackInfo Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: ControllerMpd::parseTrackInfo Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: ControllerMpd::parseState Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 20:29:20 smsl-n100 volumio[1071]: info: ControllerMpd::pushState Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreCommandRouter::servicePushState Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CorePlayQueue::getTrack 1 Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":409,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"252371921&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/252371921&soundQuality=LOSSLESS","trackType":"tidal"} Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: CURRENT POSITION 1 Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreStateMachine::syncState stateService play Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreStateMachine::syncState currentStatus stop Oct 24 20:29:20 smsl-n100 volumio[1071]: info: ControllerMpd::pushState Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreCommandRouter::servicePushState Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CorePlayQueue::getTrack 1 Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":409,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"252371921&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/252371921&soundQuality=LOSSLESS","trackType":"tidal"} Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: CURRENT POSITION 1 Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreStateMachine::syncState stateService play Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreStateMachine::syncState currentStatus play Oct 24 20:29:20 smsl-n100 volumio[1071]: info: Received an update from plugin. extracting info from payload Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreStateMachine::pushState Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioPushState Oct 24 20:29:20 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output update for this device Oct 24 20:29:20 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreStateMachine::pushState Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioPushState Oct 24 20:29:20 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output update for this device Oct 24 20:29:20 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output Oct 24 20:29:20 smsl-n100 volumio[1071]: info: ControllerMpd::pushState Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreCommandRouter::servicePushState Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CorePlayQueue::getTrack 1 Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":409,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"252371921&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/252371921&soundQuality=LOSSLESS","trackType":"tidal"} Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: CURRENT POSITION 1 Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreStateMachine::syncState stateService play Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreStateMachine::syncState currentStatus play Oct 24 20:29:20 smsl-n100 volumio[1071]: info: Received an update from plugin. extracting info from payload Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreStateMachine::pushState Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioPushState Oct 24 20:29:20 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output update for this device Oct 24 20:29:20 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreStateMachine::pushState Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioPushState Oct 24 20:29:20 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output update for this device Oct 24 20:29:20 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output Oct 24 20:29:20 smsl-n100 volumio[1071]: info: ControllerMpd::pushState Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreCommandRouter::servicePushState Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CorePlayQueue::getTrack 1 Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":409,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"252371921&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/252371921&soundQuality=LOSSLESS","trackType":"tidal"} Oct 24 20:29:20 smsl-n100 volumio[1071]: verbose: CURRENT POSITION 1 Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreStateMachine::syncState stateService play Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreStateMachine::syncState currentStatus play Oct 24 20:29:20 smsl-n100 volumio[1071]: info: Received an update from plugin. extracting info from payload Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreStateMachine::pushState Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioPushState Oct 24 20:29:20 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output update for this device Oct 24 20:29:20 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreStateMachine::pushState Oct 24 20:29:20 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioPushState Oct 24 20:29:20 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output update for this device Oct 24 20:29:20 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output Oct 24 20:29:20 smsl-n100 volumio[1071]: info: ------------------------------ 253ms Oct 24 20:29:20 smsl-n100 volumio[1071]: info: ------------------------------ 359ms Oct 24 20:29:20 smsl-n100 volumio[1071]: info: ------------------------------ 355ms Oct 24 20:29:20 smsl-n100 volumio[1071]: info: ------------------------------ 349ms Oct 24 20:29:21 smsl-n100 volumio[1071]: info: sendMpdCommand playlistinfo took 277 milliseconds Oct 24 20:29:21 smsl-n100 volumio[1071]: info: sendMpdCommand playlistinfo took 266 milliseconds Oct 24 20:29:21 smsl-n100 volumio[1071]: verbose: ControllerMpd::parseTrackInfo Oct 24 20:29:21 smsl-n100 volumio[1071]: verbose: ControllerMpd::parseTrackInfo Oct 24 20:29:21 smsl-n100 volumio[1071]: info: ControllerMpd::pushState Oct 24 20:29:21 smsl-n100 volumio[1071]: info: CoreCommandRouter::servicePushState Oct 24 20:29:21 smsl-n100 volumio[1071]: info: CorePlayQueue::getTrack 1 Oct 24 20:29:21 smsl-n100 volumio[1071]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1075,"duration":409,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"685 Kbps","isStreaming":false,"title":"252371921&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/252371921&soundQuality=LOSSLESS","trackType":"tidal"} Oct 24 20:29:21 smsl-n100 volumio[1071]: verbose: CURRENT POSITION 1 Oct 24 20:29:21 smsl-n100 volumio[1071]: info: CoreStateMachine::syncState stateService play Oct 24 20:29:21 smsl-n100 volumio[1071]: info: CoreStateMachine::syncState currentStatus play Oct 24 20:29:21 smsl-n100 volumio[1071]: info: Received an update from plugin. extracting info from payload Oct 24 20:29:21 smsl-n100 volumio[1071]: info: CoreStateMachine::pushState Oct 24 20:29:21 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:29:21 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioPushState Oct 24 20:29:21 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output update for this device Oct 24 20:29:21 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output Oct 24 20:29:21 smsl-n100 volumio[1071]: info: CoreStateMachine::pushState Oct 24 20:29:21 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioPushState Oct 24 20:29:21 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output update for this device Oct 24 20:29:21 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output Oct 24 20:29:21 smsl-n100 volumio[1071]: info: ControllerMpd::pushState Oct 24 20:29:21 smsl-n100 volumio[1071]: info: CoreCommandRouter::servicePushState Oct 24 20:29:21 smsl-n100 volumio[1071]: info: CorePlayQueue::getTrack 1 Oct 24 20:29:21 smsl-n100 volumio[1071]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":409,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"668 Kbps","isStreaming":false,"title":"252371921&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/252371921&soundQuality=LOSSLESS","trackType":"tidal"} Oct 24 20:29:21 smsl-n100 volumio[1071]: verbose: CURRENT POSITION 1 Oct 24 20:29:21 smsl-n100 volumio[1071]: info: CoreStateMachine::syncState stateService play Oct 24 20:29:21 smsl-n100 volumio[1071]: info: CoreStateMachine::syncState currentStatus play Oct 24 20:29:21 smsl-n100 volumio[1071]: info: Received an update from plugin. extracting info from payload Oct 24 20:29:21 smsl-n100 volumio[1071]: info: CoreStateMachine::pushState Oct 24 20:29:21 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:29:21 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioPushState Oct 24 20:29:21 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output update for this device Oct 24 20:29:21 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output Oct 24 20:29:21 smsl-n100 volumio[1071]: info: CoreStateMachine::pushState Oct 24 20:29:21 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioPushState Oct 24 20:29:21 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output update for this device Oct 24 20:29:21 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output Oct 24 20:29:21 smsl-n100 volumio[1071]: info: ------------------------------ 508ms Oct 24 20:29:21 smsl-n100 volumio[1071]: info: ------------------------------ 469ms Oct 24 20:29:21 smsl-n100 volumio[1071]: info: VolumeController::SetAlsaVolume0 Oct 24 20:29:21 smsl-n100 volumio[1071]: info: CoreStateMachine::pushState Oct 24 20:29:21 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:29:21 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioPushState Oct 24 20:29:21 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output update for this device Oct 24 20:29:21 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output Oct 24 20:29:21 smsl-n100 volumio[1071]: info: Updating RAAT Signal Path Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioPause Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreStateMachine::pause Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreStateMachine::stPlaybackTimer Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreStateMachine::servicePause Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CorePlayQueue::getTrack 1 Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreCommandRouter::servicePause Oct 24 20:29:28 smsl-n100 volumio[1071]: info: [1761330568146] ControllerTidal::pause Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 24 20:29:28 smsl-n100 volumio[1071]: info: ControllerMpd::pause Oct 24 20:29:28 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand pause Oct 24 20:29:28 smsl-n100 volumio[1071]: info: sendMpdCommand pause took 3 milliseconds Oct 24 20:29:28 smsl-n100 volumio[1071]: info: Oct 24 20:29:28 smsl-n100 volumio[1071]: ---------------------------- MPD announces state update: player Oct 24 20:29:28 smsl-n100 volumio[1071]: info: ControllerMpd::getState Oct 24 20:29:28 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand status Oct 24 20:29:28 smsl-n100 volumio[1071]: info: Oct 24 20:29:28 smsl-n100 volumio[1071]: ---------------------------- MPD announces state update: player Oct 24 20:29:28 smsl-n100 volumio[1071]: info: ControllerMpd::getState Oct 24 20:29:28 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand status Oct 24 20:29:28 smsl-n100 volumio[1071]: info: Oct 24 20:29:28 smsl-n100 volumio[1071]: ---------------------------- MPD announces state update: player Oct 24 20:29:28 smsl-n100 volumio[1071]: info: ControllerMpd::getState Oct 24 20:29:28 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand status Oct 24 20:29:28 smsl-n100 volumio[1071]: info: sendMpdCommand status took 17 milliseconds Oct 24 20:29:28 smsl-n100 volumio[1071]: info: sendMpdCommand status took 11 milliseconds Oct 24 20:29:28 smsl-n100 volumio[1071]: info: sendMpdCommand status took 6 milliseconds Oct 24 20:29:28 smsl-n100 volumio[1071]: verbose: ControllerMpd::parseState Oct 24 20:29:28 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 20:29:28 smsl-n100 volumio[1071]: verbose: ControllerMpd::parseState Oct 24 20:29:28 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 20:29:28 smsl-n100 volumio[1071]: verbose: ControllerMpd::parseState Oct 24 20:29:28 smsl-n100 volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 20:29:28 smsl-n100 volumio[1071]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 24 20:29:28 smsl-n100 volumio[1071]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 24 20:29:28 smsl-n100 volumio[1071]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 24 20:29:28 smsl-n100 volumio[1071]: verbose: ControllerMpd::parseTrackInfo Oct 24 20:29:28 smsl-n100 volumio[1071]: verbose: ControllerMpd::parseTrackInfo Oct 24 20:29:28 smsl-n100 volumio[1071]: verbose: ControllerMpd::parseTrackInfo Oct 24 20:29:28 smsl-n100 volumio[1071]: info: ControllerMpd::pushState Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreCommandRouter::servicePushState Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CorePlayQueue::getTrack 1 Oct 24 20:29:28 smsl-n100 volumio[1071]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":8831,"duration":409,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"693 Kbps","isStreaming":false,"title":"252371921&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/252371921&soundQuality=LOSSLESS","trackType":"tidal"} Oct 24 20:29:28 smsl-n100 volumio[1071]: verbose: CURRENT POSITION 1 Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreStateMachine::syncState stateService pause Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreStateMachine::syncState currentStatus pause Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreStateMachine::pushState Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioPushState Oct 24 20:29:28 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output update for this device Oct 24 20:29:28 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreStateMachine::stPlaybackTimer Oct 24 20:29:28 smsl-n100 volumio[1071]: info: ControllerMpd::pushState Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreCommandRouter::servicePushState Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CorePlayQueue::getTrack 1 Oct 24 20:29:28 smsl-n100 volumio[1071]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":8831,"duration":409,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"693 Kbps","isStreaming":false,"title":"252371921&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/252371921&soundQuality=LOSSLESS","trackType":"tidal"} Oct 24 20:29:28 smsl-n100 volumio[1071]: verbose: CURRENT POSITION 1 Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreStateMachine::syncState stateService pause Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreStateMachine::syncState currentStatus pause Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreStateMachine::pushState Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioPushState Oct 24 20:29:28 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output update for this device Oct 24 20:29:28 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreStateMachine::stPlaybackTimer Oct 24 20:29:28 smsl-n100 volumio[1071]: info: ControllerMpd::pushState Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreCommandRouter::servicePushState Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CorePlayQueue::getTrack 1 Oct 24 20:29:28 smsl-n100 volumio[1071]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":8831,"duration":409,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"693 Kbps","isStreaming":false,"title":"252371921&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/252371921&soundQuality=LOSSLESS","trackType":"tidal"} Oct 24 20:29:28 smsl-n100 volumio[1071]: verbose: CURRENT POSITION 1 Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreStateMachine::syncState stateService pause Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreStateMachine::syncState currentStatus pause Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreStateMachine::pushState Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioPushState Oct 24 20:29:28 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output update for this device Oct 24 20:29:28 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreStateMachine::stPlaybackTimer Oct 24 20:29:28 smsl-n100 volumio[1071]: info: ------------------------------ 137ms Oct 24 20:29:28 smsl-n100 volumio[1071]: info: ------------------------------ 129ms Oct 24 20:29:28 smsl-n100 volumio[1071]: info: ------------------------------ 126ms Oct 24 20:29:28 smsl-n100 volumio[1071]: info: Updating RAAT Signal Path Oct 24 20:29:28 smsl-n100 volumio[1071]: info: Updating RAAT Signal Path Oct 24 20:29:28 smsl-n100 volumio[1071]: info: Updating RAAT Signal Path Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreStateMachine::pushState Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:29:28 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioPushState Oct 24 20:29:28 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output update for this device Oct 24 20:29:28 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output Oct 24 20:29:28 smsl-n100 volumio[1071]: error: Serial API: Failed to decode command: 100, message: undefined Oct 24 20:29:28 smsl-n100 volumio[1071]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 24 20:29:28 smsl-n100 volumio[1071]: error: Serial API: Failed to decode command: 100, message: undefined Oct 24 20:29:28 smsl-n100 volumio[1071]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 24 20:29:40 smsl-n100 volumio[1071]: STREAMING PROXY: Client dropped request, destroying Oct 24 20:29:44 smsl-n100 dhcpcd[995]: wlan0: carrier lost Oct 24 20:29:44 smsl-n100 wpa_supplicant[858]: wlan0: Associated with 10:7c:61:7c:eb:90 Oct 24 20:29:44 smsl-n100 wpa_supplicant[858]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 24 20:29:44 smsl-n100 avahi-daemon[576]: Withdrawing address record for 192.168.0.8 on wlan0. Oct 24 20:29:44 smsl-n100 avahi-daemon[576]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.8. Oct 24 20:29:44 smsl-n100 avahi-daemon[576]: Interface wlan0.IPv4 no longer relevant for mDNS. Oct 24 20:29:44 smsl-n100 dhcpcd[995]: wlan0: deleting route to 192.168.0.0/24 Oct 24 20:29:44 smsl-n100 dhcpcd[995]: wlan0: deleting default route via 192.168.0.1 Oct 24 20:29:44 smsl-n100 volumio[1071]: info: Discovery: A device disappeared from network Oct 24 20:29:45 smsl-n100 ntpd[2114]: Deleting interface #4 wlan0, 192.168.0.8#123, interface stats: received=0, sent=0, dropped=0, active_time=698 secs Oct 24 20:29:53 smsl-n100 volumio[1071]: info: Reporting MCU Network Status: 1 Oct 24 20:29:53 smsl-n100 volumio[1071]: info: Volumio Network Manager: Network status updated: 1 Oct 24 20:29:53 smsl-n100 volumio[1071]: info: CoreStateMachine::pushState Oct 24 20:29:53 smsl-n100 volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:29:53 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioPushState Oct 24 20:29:53 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output update for this device Oct 24 20:29:53 smsl-n100 volumio[1071]: info: MRS: Pushing multiroomSync output Oct 24 20:29:53 smsl-n100 volumio[1071]: error: Serial API: Failed to decode command: 100, message: undefined Oct 24 20:29:53 smsl-n100 volumio[1071]: error: Serial API: Failed to decode command: MAXVOL, message: Oct 24 20:29:53 smsl-n100 volumio[1071]: error: Serial API: Failed to decode command: 100, message: undefined Oct 24 20:29:53 smsl-n100 volumio[1071]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Oct 24 20:29:53 smsl-n100 volumio[1071]: error: Serial API: Failed to decode command: 1, message: undefined Oct 24 20:29:54 smsl-n100 wpa_supplicant[858]: wlan0: Authentication with 10:7c:61:7c:eb:90 timed out. Oct 24 20:29:54 smsl-n100 wpa_supplicant[858]: wlan0: CTRL-EVENT-DISCONNECTED bssid=10:7c:61:7c:eb:90 reason=3 locally_generated=1 Oct 24 20:29:54 smsl-n100 wpa_supplicant[858]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Oct 24 20:29:54 smsl-n100 wpa_supplicant[858]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Oct 24 20:29:59 smsl-n100 wpa_supplicant[858]: wlan0: Trying to associate with cc:f3:c8:87:1f:d0 (SSID='Ne probalkozz!' freq=2462 MHz) Oct 24 20:29:59 smsl-n100 wpa_supplicant[858]: wlan0: Associated with cc:f3:c8:87:1f:d0 Oct 24 20:29:59 smsl-n100 wpa_supplicant[858]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 24 20:29:59 smsl-n100 wpa_supplicant[858]: wlan0: WPA: Key negotiation completed with cc:f3:c8:87:1f:d0 [PTK=CCMP GTK=CCMP] Oct 24 20:29:59 smsl-n100 wpa_supplicant[858]: wlan0: CTRL-EVENT-CONNECTED - Connection to cc:f3:c8:87:1f:d0 completed [id=0 id_str=] Oct 24 20:29:59 smsl-n100 dhcpcd[995]: wlan0: carrier acquired Oct 24 20:29:59 smsl-n100 dhcpcd[995]: wlan0: IAID 2b:ee:c8:52 Oct 24 20:29:59 smsl-n100 dhcpcd[995]: wlan0: soliciting an IPv6 router Oct 24 20:30:00 smsl-n100 dhcpcd[995]: wlan0: rebinding lease of 192.168.0.8 Oct 24 20:30:00 smsl-n100 dhcpcd[995]: wlan0: probing address 192.168.0.8/24 Oct 24 20:30:04 smsl-n100 dhcpcd[995]: wlan0: leased 192.168.0.8 for 86400 seconds Oct 24 20:30:04 smsl-n100 dhcpcd[995]: wlan0: adding route to 192.168.0.0/24 Oct 24 20:30:04 smsl-n100 dhcpcd[995]: wlan0: adding default route via 192.168.0.1 Oct 24 20:30:04 smsl-n100 avahi-daemon[576]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.8. Oct 24 20:30:04 smsl-n100 avahi-daemon[576]: New relevant interface wlan0.IPv4 for mDNS. Oct 24 20:30:04 smsl-n100 avahi-daemon[576]: Registering new address record for 192.168.0.8 on wlan0.IPv4. Oct 24 20:30:04 smsl-n100 systemd[1]: Starting Cleanup of Temporary Directories... Oct 24 20:30:04 smsl-n100 volumio[1071]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 24 20:30:04 smsl-n100 systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Oct 24 20:30:04 smsl-n100 systemd[1]: Started Cleanup of Temporary Directories. Oct 24 20:30:05 smsl-n100 ntpd[2114]: ntpd exiting on signal 15 (Terminated) Oct 24 20:30:05 smsl-n100 ntpd[2114]: 91.82.109.180 local addr 192.168.0.16 -> Oct 24 20:30:05 smsl-n100 systemd[1]: Stopping Network Time Service... Oct 24 20:30:05 smsl-n100 ntpd[2114]: 92.249.148.253 local addr 192.168.0.16 -> Oct 24 20:30:05 smsl-n100 ntpd[2114]: 195.111.92.55 local addr 192.168.0.16 -> Oct 24 20:30:05 smsl-n100 ntpd[2114]: 62.112.195.26 local addr 192.168.0.16 -> Oct 24 20:30:05 smsl-n100 ntpd[2114]: 81.0.124.200 local addr 192.168.0.16 -> Oct 24 20:30:05 smsl-n100 ntpd[2114]: 193.224.163.77 local addr 192.168.0.16 -> Oct 24 20:30:05 smsl-n100 ntpd[2114]: 80.249.164.125 local addr 192.168.0.16 -> Oct 24 20:30:05 smsl-n100 ntpd[2114]: 81.0.124.253 local addr 192.168.0.16 -> Oct 24 20:30:05 smsl-n100 ntpd[2114]: 193.227.197.2 local addr 192.168.0.16 -> Oct 24 20:30:05 smsl-n100 ntpd[2114]: 89.107.253.4 local addr 192.168.0.16 -> Oct 24 20:30:05 smsl-n100 ntpd[2114]: 62.112.193.9 local addr 192.168.0.16 -> Oct 24 20:30:05 smsl-n100 systemd[1]: ntp.service: Succeeded. Oct 24 20:30:05 smsl-n100 systemd[1]: Stopped Network Time Service. Oct 24 20:30:05 smsl-n100 volumio[1071]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 24 20:30:05 smsl-n100 volumio[1071]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 24 20:30:05 smsl-n100 systemd[1]: Starting Network Time Service... Oct 24 20:30:05 smsl-n100 volumio[1071]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 24 20:30:05 smsl-n100 ntpd[5228]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 24 20:30:05 smsl-n100 ntpd[5228]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 24 20:30:05 smsl-n100 systemd[1]: Started Network Time Service. Oct 24 20:30:05 smsl-n100 ntpd[5234]: proto: precision = 1.166 usec (-20) Oct 24 20:30:05 smsl-n100 ntpd[5234]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 24 20:30:05 smsl-n100 ntpd[5234]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 24 20:30:05 smsl-n100 ntpd[5234]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1032 days ago Oct 24 20:30:05 smsl-n100 ntpd[5234]: Listen and drop on 0 v6wildcard [::]:123 Oct 24 20:30:05 smsl-n100 ntpd[5234]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 24 20:30:05 smsl-n100 ntpd[5234]: Listen normally on 2 lo 127.0.0.1:123 Oct 24 20:30:05 smsl-n100 ntpd[5234]: Listen normally on 3 eth0 192.168.0.16:123 Oct 24 20:30:05 smsl-n100 ntpd[5234]: Listen normally on 4 wlan0 192.168.0.8:123 Oct 24 20:30:05 smsl-n100 ntpd[5234]: Listening on routing socket on fd #21 for interface updates Oct 24 20:30:05 smsl-n100 ntpd[5234]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Oct 24 20:30:05 smsl-n100 ntpd[5234]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Oct 24 20:30:05 smsl-n100 volumio[1071]: info: Discovery: adding 53a0c596-dc94-418f-9d37-1f8c81453e46 Oct 24 20:30:05 smsl-n100 volumio[1071]: info: Discovery: Found device SMSL N100 Oct 24 20:30:05 smsl-n100 volumio[1071]: info: CoreCommandRouter::volumioGetState Oct 24 20:30:05 smsl-n100 volumio[1071]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 20:30:05 smsl-n100 volumio[1071]: Error: The value NaN is not a number Oct 24 20:30:05 smsl-n100 volumio[1071]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Oct 24 20:30:05 smsl-n100 volumio[1071]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Oct 24 20:30:05 smsl-n100 volumio[1071]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Oct 24 20:30:05 smsl-n100 volumio[1071]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Oct 24 20:30:05 smsl-n100 volumio[1071]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Oct 24 20:30:05 smsl-n100 volumio[1071]: at Browser.emit (events.js:400:28) Oct 24 20:30:05 smsl-n100 volumio[1071]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Oct 24 20:30:05 smsl-n100 volumio[1071]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Oct 24 20:30:05 smsl-n100 volumio[1071]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Oct 24 20:30:05 smsl-n100 volumio[1071]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Oct 24 20:30:05 smsl-n100 volumio[1071]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Oct 24 20:30:05 smsl-n100 volumio[1071]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Oct 24 20:30:05 smsl-n100 volumio[1071]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Oct 24 20:30:05 smsl-n100 volumio[1071]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 20:30:06 smsl-n100 ntpd[5234]: Soliciting pool server 193.6.222.47 Oct 24 20:30:06 smsl-n100 sudo[5245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-24 20:29 Oct 24 20:30:06 smsl-n100 sudo[5245]: 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"