-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Thu 2025-05-01 00:19:34 CST. -- May 01 00:18:28 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 00:18:28 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 00:18:28 smsl-n100 volumio[1097]: info: Discovery: Getting this device information May 01 00:18:28 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioGetState May 01 00:18:28 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 00:18:28 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 4 May 01 00:18:28 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 5 May 01 00:18:28 smsl-n100 volumio[1097]: info: Prefetching next song May 01 00:18:28 smsl-n100 volumio[1097]: info: [1746029908615] ControllerTidal::prefetch May 01 00:18:28 smsl-n100 volumio[1097]: info: Getting stream with soundQuality LOSSLESS May 01 00:18:28 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/3958012&soundQuality=LOSSLESS" May 01 00:18:28 smsl-n100 volumio[1097]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/3958012&soundQuality=LOSSLESS" took 3 milliseconds May 01 00:18:28 smsl-n100 volumio[1097]: info: CoreStateMachine::setConsumeUpdateService mpd May 01 00:18:28 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand consume 1 May 01 00:18:28 smsl-n100 volumio[1097]: STREAMING PROXY: Handling url /?data=tidal://song/3958012&soundQuality=LOSSLESS May 01 00:18:28 smsl-n100 volumio[1097]: info: May 01 00:18:28 smsl-n100 volumio[1097]: ---------------------------- MPD announces system playlist update May 01 00:18:28 smsl-n100 volumio[1097]: info: Ignoring MPD Status Update May 01 00:18:28 smsl-n100 volumio[1097]: info: sendMpdCommand consume 1 took 6 milliseconds May 01 00:18:28 smsl-n100 volumio[1097]: info: ------------------------------ 8ms May 01 00:18:28 smsl-n100 volumio[1097]: info: Executing endpoint getStreamUrltidal May 01 00:18:28 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI May 01 00:18:29 smsl-n100 volumio[1097]: info: getStreamUrl took 479 milliseconds May 01 00:18:29 smsl-n100 volumio[1097]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKAgDEiRjODFhMzQwZTA0YzVjOGFiMTcyNjE1YWM5MjhjOTRmMi5tcDQ/0.flac?Expires=1746033508&Signature=DSpmlXsyZd06fogf~EL2Jk6lbARiCDQst7Pm4YvHvJyKFJDSE63782mvxZtzhrFqQDaWPqAvoDdwNu5osR7rwKyqXKB1Nr7UYwE72XmBfsmeKbeoIzhPXj1bzTIMstNj8ZxyFn~ijiOyGez4ydX9BiwxmIf9tMKOtj5BVAkO2KPbHngfe8PEqcIvOCQ7syhXga-TswN2I~LnXKCqIIJJLJWbbreLa9if8AbfEBF8XoZU0v-8BxJfAqsmJ7LM4zR7K3kcezlTl3g6j0RB6GL5DzwWxjyotABntArf8eSw8Bi-XtEISAL8MFXAhqgQA2RpSFXWzC9~xgX~TMIlgwW5TA__&Key-Pair-Id=K14LZCZ9QUI4JL May 01 00:18:29 smsl-n100 volumio[1097]: STREAMING PROXY: Response: 200, length: 28030038 May 01 00:18:30 smsl-n100 volumio[1097]: STREAMING PROXY: Client dropped request, destroying May 01 00:18:32 smsl-n100 volumio[1097]: info: May 01 00:18:32 smsl-n100 volumio[1097]: ---------------------------- MPD announces system playlist update May 01 00:18:32 smsl-n100 volumio[1097]: info: Ignoring MPD Status Update May 01 00:18:32 smsl-n100 volumio[1097]: info: May 01 00:18:32 smsl-n100 volumio[1097]: ---------------------------- MPD announces state update: player May 01 00:18:32 smsl-n100 volumio[1097]: info: ControllerMpd::getState May 01 00:18:32 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand status May 01 00:18:32 smsl-n100 volumio[1097]: info: ------------------------------ 7ms May 01 00:18:33 smsl-n100 volumio[1097]: info: CoreStateMachine::startPlaybackTimer May 01 00:18:33 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 5 May 01 00:18:33 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState May 01 00:18:33 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 5 May 01 00:18:33 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 00:18:33 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState May 01 00:18:33 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device May 01 00:18:33 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output May 01 00:18:33 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path May 01 00:18:33 smsl-n100 volumio[1097]: info: sendMpdCommand status took 1131 milliseconds May 01 00:18:33 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseState May 01 00:18:33 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 00:18:33 smsl-n100 volumio[1097]: info: May 01 00:18:33 smsl-n100 volumio[1097]: ---------------------------- MPD announces state update: player May 01 00:18:33 smsl-n100 volumio[1097]: info: ControllerMpd::getState May 01 00:18:33 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand status May 01 00:18:33 smsl-n100 volumio[1097]: info: sendMpdCommand playlistinfo took 5 milliseconds May 01 00:18:33 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseTrackInfo May 01 00:18:33 smsl-n100 volumio[1097]: info: ControllerMpd::pushState May 01 00:18:33 smsl-n100 volumio[1097]: info: CoreCommandRouter::servicePushState May 01 00:18:33 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 5 May 01 00:18:33 smsl-n100 volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"3958012&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/3958012&soundQuality=LOSSLESS","trackType":"tidal"} May 01 00:18:33 smsl-n100 volumio[1097]: verbose: CURRENT POSITION 5 May 01 00:18:33 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState stateService play May 01 00:18:33 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState currentStatus play May 01 00:18:33 smsl-n100 volumio[1097]: info: Received an update from plugin. extracting info from payload May 01 00:18:33 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState May 01 00:18:33 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 5 May 01 00:18:33 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 00:18:33 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState May 01 00:18:33 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device May 01 00:18:33 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output May 01 00:18:33 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState May 01 00:18:33 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 5 May 01 00:18:33 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState May 01 00:18:33 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device May 01 00:18:33 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output May 01 00:18:33 smsl-n100 volumio[1097]: info: ------------------------------ 1195ms May 01 00:18:33 smsl-n100 volumio[1097]: info: sendMpdCommand status took 57 milliseconds May 01 00:18:33 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseState May 01 00:18:33 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 00:18:33 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path May 01 00:18:33 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path May 01 00:18:33 smsl-n100 volumio[1097]: info: sendMpdCommand playlistinfo took 24 milliseconds May 01 00:18:33 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseTrackInfo May 01 00:18:33 smsl-n100 volumio[1097]: info: ControllerMpd::pushState May 01 00:18:33 smsl-n100 volumio[1097]: info: CoreCommandRouter::servicePushState May 01 00:18:33 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 5 May 01 00:18:33 smsl-n100 volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"766 Kbps","isStreaming":false,"title":"3958012&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/3958012&soundQuality=LOSSLESS","trackType":"tidal"} May 01 00:18:33 smsl-n100 volumio[1097]: verbose: CURRENT POSITION 5 May 01 00:18:33 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState stateService play May 01 00:18:33 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState currentStatus play May 01 00:18:33 smsl-n100 volumio[1097]: info: Received an update from plugin. extracting info from payload May 01 00:18:33 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState May 01 00:18:33 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 5 May 01 00:18:33 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 00:18:33 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState May 01 00:18:33 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device May 01 00:18:33 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output May 01 00:18:33 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState May 01 00:18:33 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 5 May 01 00:18:33 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState May 01 00:18:33 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device May 01 00:18:33 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output May 01 00:18:34 smsl-n100 volumio[1097]: info: ------------------------------ 136ms May 01 00:18:34 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path May 01 00:18:34 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path May 01 00:18:38 smsl-n100 ntpd[1011]: Soliciting pool server 47.254.196.78 May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioNext May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreStateMachine::next May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreStateMachine::stop May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreStateMachine::stPlaybackTimer May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreStateMachine::updateTrackBlock May 01 00:18:39 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrackBlock May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState May 01 00:18:39 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 5 May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState May 01 00:18:39 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device May 01 00:18:39 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreStateMachine::serviceStop May 01 00:18:39 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 5 May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreCommandRouter::serviceStop May 01 00:18:39 smsl-n100 volumio[1097]: info: [1746029919547] ControllerTidal::stop May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreStateMachine::setConsumeUpdateService mpd May 01 00:18:39 smsl-n100 volumio[1097]: info: ControllerMpd::stop May 01 00:18:39 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand stop May 01 00:18:39 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path May 01 00:18:39 smsl-n100 volumio[1097]: info: May 01 00:18:39 smsl-n100 volumio[1097]: ---------------------------- MPD announces state update: player May 01 00:18:39 smsl-n100 volumio[1097]: info: sendMpdCommand stop took 40 milliseconds May 01 00:18:39 smsl-n100 volumio[1097]: info: ControllerMpd::getState May 01 00:18:39 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand status May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreStateMachine::play index undefined May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 00:18:39 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 6 May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreStateMachine::startPlaybackTimer May 01 00:18:39 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 6 May 01 00:18:39 smsl-n100 volumio[1097]: info: [1746029919597] ControllerTidal::clearAddPlayTrack May 01 00:18:39 smsl-n100 volumio[1097]: info: Getting stream with soundQuality LOSSLESS May 01 00:18:39 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand stop May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreStateMachine::updateTrackBlock May 01 00:18:39 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrackBlock May 01 00:18:39 smsl-n100 volumio[1097]: info: sendMpdCommand status took 12 milliseconds May 01 00:18:39 smsl-n100 volumio[1097]: info: sendMpdCommand stop took 5 milliseconds May 01 00:18:39 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseState May 01 00:18:39 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 00:18:39 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand clear May 01 00:18:39 smsl-n100 volumio[1097]: info: May 01 00:18:39 smsl-n100 volumio[1097]: ---------------------------- MPD announces system playlist update May 01 00:18:39 smsl-n100 volumio[1097]: info: Ignoring MPD Status Update May 01 00:18:39 smsl-n100 volumio[1097]: info: sendMpdCommand playlistinfo took 5 milliseconds May 01 00:18:39 smsl-n100 volumio[1097]: info: sendMpdCommand clear took 5 milliseconds May 01 00:18:39 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseTrackInfo May 01 00:18:39 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/107691285&soundQuality=LOSSLESS" May 01 00:18:39 smsl-n100 volumio[1097]: info: ControllerMpd::pushState May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreCommandRouter::servicePushState May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState May 01 00:18:39 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 6 May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState May 01 00:18:39 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device May 01 00:18:39 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output May 01 00:18:39 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 6 May 01 00:18:39 smsl-n100 volumio[1097]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd May 01 00:18:39 smsl-n100 volumio[1097]: info: ------------------------------ 60ms May 01 00:18:39 smsl-n100 volumio[1097]: error: updateQueue error: null May 01 00:18:39 smsl-n100 volumio[1097]: info: May 01 00:18:39 smsl-n100 volumio[1097]: ---------------------------- MPD announces system playlist update May 01 00:18:39 smsl-n100 volumio[1097]: info: Ignoring MPD Status Update May 01 00:18:39 smsl-n100 volumio[1097]: info: ------------------------------ 42ms May 01 00:18:39 smsl-n100 volumio[1097]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/107691285&soundQuality=LOSSLESS" took 38 milliseconds May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreStateMachine::setConsumeUpdateService mpd May 01 00:18:39 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand play May 01 00:18:39 smsl-n100 volumio[1097]: STREAMING PROXY: Handling url /?data=tidal://song/107691285&soundQuality=LOSSLESS May 01 00:18:39 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState May 01 00:18:39 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device May 01 00:18:39 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output May 01 00:18:39 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: 100, message: undefined May 01 00:18:39 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: MAXVOL, message: May 01 00:18:39 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: 100, message: undefined May 01 00:18:39 smsl-n100 volumio[1097]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 01 00:18:39 smsl-n100 volumio[1097]: info: ------------------------------ 58ms May 01 00:18:39 smsl-n100 volumio[1097]: info: sendMpdCommand play took 54 milliseconds May 01 00:18:39 smsl-n100 volumio[1097]: info: Executing endpoint getStreamUrltidal May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState May 01 00:18:39 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device May 01 00:18:39 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output May 01 00:18:39 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: 100, message: undefined May 01 00:18:39 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: MAXVOL, message: May 01 00:18:39 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: 100, message: undefined May 01 00:18:39 smsl-n100 volumio[1097]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 01 00:18:39 smsl-n100 volumio[1097]: info: VolumeController::SetAlsaVolume0 May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 00:18:39 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState May 01 00:18:39 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device May 01 00:18:39 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output May 01 00:18:39 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path May 01 00:18:40 smsl-n100 ntpd[1011]: Soliciting pool server 47.254.196.78 May 01 00:18:40 smsl-n100 volumio[1097]: info: getStreamUrl took 480 milliseconds May 01 00:18:40 smsl-n100 volumio[1097]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEic2ZDZlZDNlMDliNGFmM2EyNjkxNGNiMWZjMWQyYzE3MF82MS5tcDQ/0.flac?Expires=1746033520&Signature=qHDu0Q2Uv6rV0oOt3OUrabVfdacrsNCHKArAmRW25ErecgolYTJhqXPAw1rh23zRzs2Kyuwpl-58paCO25z2saG-4hTMRV-jlvU9wmBNbPirVzcG9u2eXhGeWJ7OnmS443GfW2Pl2zwgfLi7vC5yjuES9SKV0HImc6VwIOGWKGtosKlSLWrjZLK4KoJr8vTPt1MQ79VT0j12R6pM9QDTeiU3pO8rW6JY0acrHuXE3S27UlOd2LjNiO1cSVqBRwyi2Qn23Uauk2aclY0wqzeeGDCOWSVRsYBTZgzvehZNULPMdLhKF8KuQrCMtCN-NY7JGzuW2-KVxdZm3a7IajHUVA__&Key-Pair-Id=K14LZCZ9QUI4JL May 01 00:18:40 smsl-n100 volumio[1097]: STREAMING PROXY: Response: 200, length: 19114788 May 01 00:18:40 smsl-n100 volumio[1097]: info: May 01 00:18:40 smsl-n100 volumio[1097]: ---------------------------- MPD announces state update: player May 01 00:18:40 smsl-n100 volumio[1097]: info: ControllerMpd::getState May 01 00:18:40 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand status May 01 00:18:40 smsl-n100 volumio[1097]: info: sendMpdCommand status took 13 milliseconds May 01 00:18:40 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseState May 01 00:18:40 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 00:18:40 smsl-n100 volumio[1097]: info: May 01 00:18:40 smsl-n100 volumio[1097]: ---------------------------- MPD announces state update: player May 01 00:18:40 smsl-n100 volumio[1097]: info: ControllerMpd::getState May 01 00:18:40 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand status May 01 00:18:40 smsl-n100 volumio[1097]: info: sendMpdCommand playlistinfo took 14 milliseconds May 01 00:18:40 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseTrackInfo May 01 00:18:40 smsl-n100 volumio[1097]: info: ControllerMpd::pushState May 01 00:18:40 smsl-n100 volumio[1097]: info: CoreCommandRouter::servicePushState May 01 00:18:40 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 6 May 01 00:18:40 smsl-n100 volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":179,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"107691285&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/107691285&soundQuality=LOSSLESS","trackType":"tidal"} May 01 00:18:40 smsl-n100 volumio[1097]: verbose: CURRENT POSITION 6 May 01 00:18:40 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState stateService play May 01 00:18:40 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState currentStatus stop May 01 00:18:40 smsl-n100 volumio[1097]: info: ------------------------------ 76ms May 01 00:18:40 smsl-n100 volumio[1097]: info: sendMpdCommand status took 39 milliseconds May 01 00:18:40 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseState May 01 00:18:40 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 00:18:40 smsl-n100 volumio[1097]: info: sendMpdCommand playlistinfo took 7 milliseconds May 01 00:18:40 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseTrackInfo May 01 00:18:40 smsl-n100 volumio[1097]: info: ControllerMpd::pushState May 01 00:18:40 smsl-n100 volumio[1097]: info: CoreCommandRouter::servicePushState May 01 00:18:40 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 6 May 01 00:18:40 smsl-n100 volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":179,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"969 Kbps","isStreaming":false,"title":"107691285&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/107691285&soundQuality=LOSSLESS","trackType":"tidal"} May 01 00:18:40 smsl-n100 volumio[1097]: verbose: CURRENT POSITION 6 May 01 00:18:40 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState stateService play May 01 00:18:40 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState currentStatus play May 01 00:18:40 smsl-n100 volumio[1097]: info: Received an update from plugin. extracting info from payload May 01 00:18:40 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState May 01 00:18:40 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 00:18:40 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState May 01 00:18:40 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device May 01 00:18:40 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output May 01 00:18:40 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState May 01 00:18:40 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState May 01 00:18:40 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device May 01 00:18:40 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output May 01 00:18:40 smsl-n100 volumio[1097]: info: ------------------------------ 271ms May 01 00:18:40 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path May 01 00:18:40 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path May 01 00:18:41 smsl-n100 volumio[1097]: STREAMING PROXY: Client dropped request, destroying May 01 00:18:52 smsl-n100 volumio[1097]: info: Executing endpoint metavolumio May 01 00:18:52 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 01 00:18:52 smsl-n100 volumio[1097]: info: Executing endpoint metavolumio May 01 00:18:52 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 01 00:18:55 smsl-n100 ntpd[1011]: Soliciting pool server 2001:470:ec42:60:49e2:5be6:b73:7449 May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioNext May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreStateMachine::next May 01 00:18:58 smsl-n100 volumio[1097]: info: [1746029938088] ControllerTidal::next May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreStateMachine::next May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreStateMachine::stop May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreStateMachine::stPlaybackTimer May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreStateMachine::updateTrackBlock May 01 00:18:58 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrackBlock May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState May 01 00:18:58 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 6 May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState May 01 00:18:58 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device May 01 00:18:58 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreStateMachine::serviceStop May 01 00:18:58 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 6 May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreCommandRouter::serviceStop May 01 00:18:58 smsl-n100 volumio[1097]: info: [1746029938116] ControllerTidal::stop May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreStateMachine::setConsumeUpdateService mpd May 01 00:18:58 smsl-n100 volumio[1097]: info: ControllerMpd::stop May 01 00:18:58 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand stop May 01 00:18:58 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path May 01 00:18:58 smsl-n100 volumio[1097]: info: May 01 00:18:58 smsl-n100 volumio[1097]: ---------------------------- MPD announces state update: player May 01 00:18:58 smsl-n100 volumio[1097]: info: sendMpdCommand stop took 41 milliseconds May 01 00:18:58 smsl-n100 volumio[1097]: info: ControllerMpd::getState May 01 00:18:58 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand status May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreStateMachine::play index undefined May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 00:18:58 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 7 May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreStateMachine::startPlaybackTimer May 01 00:18:58 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 7 May 01 00:18:58 smsl-n100 volumio[1097]: info: [1746029938166] ControllerTidal::clearAddPlayTrack May 01 00:18:58 smsl-n100 volumio[1097]: info: Getting stream with soundQuality LOSSLESS May 01 00:18:58 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand stop May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreStateMachine::updateTrackBlock May 01 00:18:58 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrackBlock May 01 00:18:58 smsl-n100 volumio[1097]: info: sendMpdCommand status took 12 milliseconds May 01 00:18:58 smsl-n100 volumio[1097]: info: sendMpdCommand stop took 5 milliseconds May 01 00:18:58 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseState May 01 00:18:58 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 00:18:58 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand clear May 01 00:18:58 smsl-n100 volumio[1097]: info: May 01 00:18:58 smsl-n100 volumio[1097]: ---------------------------- MPD announces system playlist update May 01 00:18:58 smsl-n100 volumio[1097]: info: Ignoring MPD Status Update May 01 00:18:58 smsl-n100 volumio[1097]: info: sendMpdCommand playlistinfo took 6 milliseconds May 01 00:18:58 smsl-n100 volumio[1097]: info: sendMpdCommand clear took 6 milliseconds May 01 00:18:58 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseTrackInfo May 01 00:18:58 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/92354194&soundQuality=LOSSLESS" May 01 00:18:58 smsl-n100 volumio[1097]: info: ControllerMpd::pushState May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreCommandRouter::servicePushState May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState May 01 00:18:58 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 7 May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState May 01 00:18:58 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device May 01 00:18:58 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output May 01 00:18:58 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 7 May 01 00:18:58 smsl-n100 volumio[1097]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd May 01 00:18:58 smsl-n100 volumio[1097]: info: ------------------------------ 56ms May 01 00:18:58 smsl-n100 volumio[1097]: error: updateQueue error: null May 01 00:18:58 smsl-n100 volumio[1097]: info: May 01 00:18:58 smsl-n100 volumio[1097]: ---------------------------- MPD announces system playlist update May 01 00:18:58 smsl-n100 volumio[1097]: info: Ignoring MPD Status Update May 01 00:18:58 smsl-n100 volumio[1097]: info: ------------------------------ 46ms May 01 00:18:58 smsl-n100 volumio[1097]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/92354194&soundQuality=LOSSLESS" took 42 milliseconds May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreStateMachine::setConsumeUpdateService mpd May 01 00:18:58 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand play May 01 00:18:58 smsl-n100 volumio[1097]: STREAMING PROXY: Handling url /?data=tidal://song/92354194&soundQuality=LOSSLESS May 01 00:18:58 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path May 01 00:18:58 smsl-n100 volumio[1097]: info: ------------------------------ 26ms May 01 00:18:58 smsl-n100 volumio[1097]: info: sendMpdCommand play took 21 milliseconds May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState May 01 00:18:58 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device May 01 00:18:58 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output May 01 00:18:58 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: 100, message: undefined May 01 00:18:58 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: MAXVOL, message: May 01 00:18:58 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: 100, message: undefined May 01 00:18:58 smsl-n100 volumio[1097]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 01 00:18:58 smsl-n100 volumio[1097]: info: Executing endpoint getStreamUrltidal May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState May 01 00:18:58 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device May 01 00:18:58 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output May 01 00:18:58 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: 100, message: undefined May 01 00:18:58 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: MAXVOL, message: May 01 00:18:58 smsl-n100 volumio[1097]: error: Serial API: Failed to decode command: 100, message: undefined May 01 00:18:58 smsl-n100 volumio[1097]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 01 00:18:58 smsl-n100 volumio[1097]: info: VolumeController::SetAlsaVolume0 May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState May 01 00:18:58 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device May 01 00:18:58 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output May 01 00:18:58 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 00:18:58 smsl-n100 volumio[1097]: info: Discovery: Getting this device information May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioGetState May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 00:18:58 smsl-n100 volumio[1097]: info: getStreamUrl took 479 milliseconds May 01 00:18:58 smsl-n100 volumio[1097]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKAgDEiQwMmI4MWRmY2E0MjYxZTUxMTYyYzE0ODJlYTYwMjA5ZC5tcDQ/0.flac?Expires=1746033538&Signature=CXFqnZqUjT9JjHpVvshFE~If-I6EtLgtkgGA57Oct02Dif3qGYBw1ZWkec3jYdPRgUSfsnmWUgKp5WIkA5IraEolTT8oLbGweGsjG~aIjWwk~X6E0oZ9Birwh6jSUvBKEw~4oP0NH~tWS1G68eueLKpbddw9H3Y39Ouo7yZXYIYzos2BNy8yrmXy1k21GCo49me6gJNxvTR3T~FsVYuEZ6R3DSidq8R7~dxHczYAJreEH~fAZTDBnyeyqjJZRvAdWsPcuEAH5PogatxvHkT9pF20wqmnaMkHzvGAxEVqFdtjnzgt8sZSZfwTte0BhgJJVaxS3uhjsuy57aEeEAOwnw__&Key-Pair-Id=K14LZCZ9QUI4JL May 01 00:18:58 smsl-n100 volumio[1097]: STREAMING PROXY: Response: 200, length: 28788974 May 01 00:18:58 smsl-n100 volumio[1097]: info: May 01 00:18:58 smsl-n100 volumio[1097]: ---------------------------- MPD announces state update: player May 01 00:18:58 smsl-n100 volumio[1097]: info: ControllerMpd::getState May 01 00:18:58 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand status May 01 00:18:58 smsl-n100 volumio[1097]: info: sendMpdCommand status took 12 milliseconds May 01 00:18:58 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseState May 01 00:18:58 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 00:18:58 smsl-n100 volumio[1097]: info: May 01 00:18:58 smsl-n100 volumio[1097]: ---------------------------- MPD announces state update: player May 01 00:18:58 smsl-n100 volumio[1097]: info: ControllerMpd::getState May 01 00:18:58 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand status May 01 00:18:58 smsl-n100 volumio[1097]: info: sendMpdCommand playlistinfo took 8 milliseconds May 01 00:18:58 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseTrackInfo May 01 00:18:58 smsl-n100 volumio[1097]: info: ControllerMpd::pushState May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreCommandRouter::servicePushState May 01 00:18:58 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 7 May 01 00:18:58 smsl-n100 volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"92354194&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/92354194&soundQuality=LOSSLESS","trackType":"tidal"} May 01 00:18:58 smsl-n100 volumio[1097]: verbose: CURRENT POSITION 7 May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState stateService play May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState currentStatus stop May 01 00:18:58 smsl-n100 volumio[1097]: info: ------------------------------ 60ms May 01 00:18:58 smsl-n100 volumio[1097]: info: sendMpdCommand status took 28 milliseconds May 01 00:18:58 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseState May 01 00:18:58 smsl-n100 volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 00:18:58 smsl-n100 volumio[1097]: info: sendMpdCommand playlistinfo took 19 milliseconds May 01 00:18:58 smsl-n100 volumio[1097]: verbose: ControllerMpd::parseTrackInfo May 01 00:18:58 smsl-n100 volumio[1097]: info: ControllerMpd::pushState May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreCommandRouter::servicePushState May 01 00:18:58 smsl-n100 volumio[1097]: info: CorePlayQueue::getTrack 7 May 01 00:18:58 smsl-n100 volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"562 Kbps","isStreaming":false,"title":"92354194&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/92354194&soundQuality=LOSSLESS","trackType":"tidal"} May 01 00:18:58 smsl-n100 volumio[1097]: verbose: CURRENT POSITION 7 May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState stateService play May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreStateMachine::syncState currentStatus play May 01 00:18:58 smsl-n100 volumio[1097]: info: Received an update from plugin. extracting info from payload May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 00:18:58 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState May 01 00:18:58 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device May 01 00:18:59 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output May 01 00:18:59 smsl-n100 volumio[1097]: info: CoreStateMachine::pushState May 01 00:18:59 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioPushState May 01 00:18:59 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output update for this device May 01 00:18:59 smsl-n100 volumio[1097]: info: MRS: Pushing multiroomSync output May 01 00:18:59 smsl-n100 volumio[1097]: info: ------------------------------ 294ms May 01 00:18:59 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path May 01 00:18:59 smsl-n100 volumio[1097]: info: Updating RAAT Signal Path May 01 00:19:00 smsl-n100 volumio[1097]: STREAMING PROXY: Client dropped request, destroying May 01 00:19:07 smsl-n100 volumio[1097]: info: Executing endpoint metavolumio May 01 00:19:07 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 01 00:19:07 smsl-n100 volumio[1097]: info: Executing endpoint metavolumio May 01 00:19:07 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 01 00:19:11 smsl-n100 volumio[1097]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 00:19:11 smsl-n100 volumio[1097]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 00:19:11 smsl-n100 volumio[1097]: info: Retrieving Cloud Streaming UI May 01 00:19:11 smsl-n100 volumio[1097]: info: Getting Tidal Cloud Configuration May 01 00:19:11 smsl-n100 volumio[1097]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 00:19:11 smsl-n100 volumio[1097]: info: Getting Qobuz Cloud Configuration May 01 00:19:11 smsl-n100 volumio[1097]: info: Asking plugin for UI Config May 01 00:19:11 smsl-n100 volumio[1097]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 00:19:11 smsl-n100 volumio[1097]: info: Getting Spotify Cloud Configuration May 01 00:19:11 smsl-n100 volumio[1097]: info: Asking plugin for UI Config May 01 00:19:11 smsl-n100 volumio[1097]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 00:19:11 smsl-n100 volumio[1097]: info: Saving Spotify Acccount May 01 00:19:11 smsl-n100 volumio[1097]: info: Got it May 01 00:19:11 smsl-n100 volumio[1097]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 01 00:19:11 smsl-n100 volumio[1097]: info: Got Tidal Cloud Configuration May 01 00:19:11 smsl-n100 volumio[1097]: info: Got it May 01 00:19:11 smsl-n100 volumio[1097]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 00:19:11 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 01 00:19:11 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 01 00:19:11 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 01 00:19:11 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 01 00:19:11 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 00:19:11 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 00:19:11 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 00:19:11 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 00:19:11 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioGetBrowseSources May 01 00:19:11 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioGetBrowseSources May 01 00:19:11 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioGetBrowseSources May 01 00:19:11 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 01 00:19:11 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 01 00:19:15 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 01 00:19:20 smsl-n100 volumio[1097]: info: Executing endpoint metavolumio May 01 00:19:20 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 01 00:19:20 smsl-n100 volumio[1097]: info: Executing endpoint metavolumio May 01 00:19:20 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 01 00:19:22 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 00:19:22 smsl-n100 volumio[1097]: info: Preload queue cleared May 01 00:19:23 smsl-n100 ntpd[1011]: Soliciting pool server 47.254.196.78 May 01 00:19:25 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 00:19:25 smsl-n100 volumio[1097]: info: Preload queue cleared May 01 00:19:28 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 00:19:28 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 00:19:28 smsl-n100 volumio[1097]: info: Discovery: Getting this device information May 01 00:19:28 smsl-n100 volumio[1097]: info: CoreCommandRouter::volumioGetState May 01 00:19:28 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 00:19:33 smsl-n100 volumio[1097]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 01 00:19:33 smsl-n100 volumio[1097]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 00:19:33 smsl-n100 volumio[1097]: TypeError: Cannot read property 'length' of undefined May 01 00:19:33 smsl-n100 volumio[1097]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 01 00:19:33 smsl-n100 volumio[1097]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 01 00:19:33 smsl-n100 volumio[1097]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 01 00:19:33 smsl-n100 volumio[1097]: at Parser.emit (events.js:400:28) May 01 00:19:33 smsl-n100 volumio[1097]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 01 00:19:33 smsl-n100 volumio[1097]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 01 00:19:33 smsl-n100 volumio[1097]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 01 00:19:33 smsl-n100 volumio[1097]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 01 00:19:33 smsl-n100 volumio[1097]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 01 00:19:33 smsl-n100 volumio[1097]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 01 00:19:33 smsl-n100 volumio[1097]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 01 00:19:33 smsl-n100 volumio[1097]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 01 00:19:33 smsl-n100 volumio[1097]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 01 00:19:33 smsl-n100 volumio[1097]: at IncomingMessage.emit (events.js:412:35) May 01 00:19:33 smsl-n100 volumio[1097]: at endReadableNT (internal/streams/readable.js:1333:12) May 01 00:19:33 smsl-n100 volumio[1097]: at processTicksAndRejections (internal/process/task_queues.js:82:21) May 01 00:19:33 smsl-n100 volumio[1097]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 00:19:34 smsl-n100 sudo[931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-01 00:18 May 01 00:19:34 smsl-n100 sudo[931]: 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"