-- Logs begin at Fri 2025-06-20 12:24:38 MSK, end at Fri 2025-06-20 12:45:33 MSK. -- Jun 20 12:44:05 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 12:44:05 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 12:44:05 smsl-n100 volumio[1172]: info: Discovery: Getting this device information Jun 20 12:44:05 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetState Jun 20 12:44:05 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 177 Jun 20 12:44:05 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 12:44:11 smsl-n100 volumio[1172]: verbose: New Socket.io Connection to 192.168.40.219:3000 from 192.168.40.225 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jun 20 12:44:11 smsl-n100 volumio[1172]: verbose: New Socket.io Connection to 192.168.40.219:3000 from 192.168.40.225 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Jun 20 12:44:18 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 177 Jun 20 12:44:18 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 131 Jun 20 12:44:18 smsl-n100 volumio[1172]: info: Prefetching next song Jun 20 12:44:18 smsl-n100 volumio[1172]: info: [1750412658910] ControllerQobuz::prefetch Jun 20 12:44:18 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/251714511" Jun 20 12:44:18 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/251714511 Jun 20 12:44:18 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:44:18 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:44:19 smsl-n100 volumio[1172]: info: getStreamUrl took 505 milliseconds Jun 20 12:44:19 smsl-n100 volumio[1172]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=5025340&eid=251714511&fmt=6&profile=raw&app_id=539451548&cid=2832409&etsp=1750416259&hmac=VvDVy7xMAC5fJA-VGAN1XNE6iTA Jun 20 12:44:20 smsl-n100 volumio[1172]: STREAMING PROXY: Response: 200, length: 19415568 Jun 20 12:44:20 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/251714511" Jun 20 12:44:20 smsl-n100 volumio[1172]: info: Jun 20 12:44:20 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:44:20 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:44:20 smsl-n100 volumio[1172]: info: Jun 20 12:44:20 smsl-n100 volumio[1172]: ---------------------------- MPD announces state update: player Jun 20 12:44:20 smsl-n100 volumio[1172]: STREAMING PROXY: Client dropped request, destroying Jun 20 12:44:20 smsl-n100 volumio[1172]: info: Jun 20 12:44:20 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:44:20 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:44:20 smsl-n100 volumio[1172]: info: ControllerMpd::getState Jun 20 12:44:20 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jun 20 12:44:20 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/251714511" took 9 milliseconds Jun 20 12:44:20 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:44:20 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 20 12:44:20 smsl-n100 volumio[1172]: info: ------------------------------ 13ms Jun 20 12:44:20 smsl-n100 volumio[1172]: info: sendMpdCommand status took 8 milliseconds Jun 20 12:44:20 smsl-n100 volumio[1172]: info: ------------------------------ 13ms Jun 20 12:44:20 smsl-n100 volumio[1172]: info: sendMpdCommand consume 1 took 6 milliseconds Jun 20 12:44:20 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseState Jun 20 12:44:20 smsl-n100 volumio[1172]: info: ControllerMpd::pushState Jun 20 12:44:20 smsl-n100 volumio[1172]: info: CoreCommandRouter::servicePushState Jun 20 12:44:20 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:44:20 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:44:20 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:44:20 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:44:20 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:44:20 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 177 Jun 20 12:44:20 smsl-n100 volumio[1172]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 20 12:44:20 smsl-n100 volumio[1172]: verbose: CURRENT POSITION 177 Jun 20 12:44:20 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState stateService stop Jun 20 12:44:20 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState currentStatus play Jun 20 12:44:20 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:44:20 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:44:20 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:44:20 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:44:20 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:44:20 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:44:20 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:44:20 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:44:20 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:44:20 smsl-n100 volumio[1172]: info: ------------------------------ 71ms Jun 20 12:44:20 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:44:20 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:44:20 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:44:20 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:44:20 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:44:20 smsl-n100 volumio[1172]: error: Serial API: Failed to decode command: 100, message: undefined Jun 20 12:44:20 smsl-n100 volumio[1172]: error: Serial API: Failed to decode command: MAXVOL, message: Jun 20 12:44:20 smsl-n100 volumio[1172]: error: Serial API: Failed to decode command: 100, message: undefined Jun 20 12:44:20 smsl-n100 volumio[1172]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jun 20 12:44:41 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 12:44:41 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 12:44:41 smsl-n100 volumio[1172]: info: Discovery: Getting this device information Jun 20 12:44:41 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetState Jun 20 12:44:41 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 12:44:42 smsl-n100 volumio[1172]: verbose: New Socket.io Connection to 192.168.40.219:3000 from 192.168.40.225 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jun 20 12:44:42 smsl-n100 volumio[1172]: verbose: New Socket.io Connection to 192.168.40.219:3000 from 192.168.40.225 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Jun 20 12:44:48 smsl-n100 volumio[1172]: verbose: New Socket.io Connection to 192.168.40.219 from 192.168.40.225 UA: Mozilla/5.0 (Linux; Android 12; 21091116UG Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jun 20 12:44:48 smsl-n100 volumio[1172]: info: Received Get System Info Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 12:44:48 smsl-n100 volumio[1172]: info: Discovery: Getting this device information Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetState Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetState Jun 20 12:44:48 smsl-n100 volumio[1172]: info: Received Get System Info Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 12:44:48 smsl-n100 volumio[1172]: info: Discovery: Getting this device information Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetState Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetState Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetVisibleSources Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetState Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetQueue Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreStateMachine::getQueue Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CorePlayQueue::getQueue Jun 20 12:44:48 smsl-n100 volumio[1172]: info: Listing playlists Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 20 12:44:48 smsl-n100 volumio[1172]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 20 12:44:48 smsl-n100 volumio[1172]: info: Received Get System Info Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 12:44:48 smsl-n100 volumio[1172]: info: Discovery: Getting this device information Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetState Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetState Jun 20 12:44:48 smsl-n100 volumio[1172]: info: Received Get System Info Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 12:44:48 smsl-n100 volumio[1172]: info: Discovery: Getting this device information Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetState Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetState Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetVisibleSources Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetState Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetQueue Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreStateMachine::getQueue Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CorePlayQueue::getQueue Jun 20 12:44:48 smsl-n100 volumio[1172]: info: Listing playlists Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 20 12:44:48 smsl-n100 volumio[1172]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 20 12:44:48 smsl-n100 volumio[1172]: info: Received Get System Info Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 12:44:48 smsl-n100 volumio[1172]: info: Discovery: Getting this device information Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetState Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetState Jun 20 12:44:48 smsl-n100 volumio[1172]: info: Received Get System Info Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 12:44:48 smsl-n100 volumio[1172]: info: Discovery: Getting this device information Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetState Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetState Jun 20 12:44:48 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 20 12:44:49 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetVisibleSources Jun 20 12:44:49 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 12:44:49 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetState Jun 20 12:44:49 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 20 12:44:49 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetQueue Jun 20 12:44:49 smsl-n100 volumio[1172]: info: CoreStateMachine::getQueue Jun 20 12:44:49 smsl-n100 volumio[1172]: info: CorePlayQueue::getQueue Jun 20 12:44:49 smsl-n100 volumio[1172]: info: Listing playlists Jun 20 12:44:49 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 20 12:44:49 smsl-n100 volumio[1172]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 20 12:44:49 smsl-n100 volumio[1172]: info: Received Get System Info Jun 20 12:44:49 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 12:44:49 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 12:44:49 smsl-n100 volumio[1172]: info: Discovery: Getting this device information Jun 20 12:44:49 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetState Jun 20 12:44:49 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 12:44:49 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetState Jun 20 12:44:49 smsl-n100 volumio[1172]: info: Received Get System Info Jun 20 12:44:49 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 12:44:49 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 12:44:49 smsl-n100 volumio[1172]: info: Discovery: Getting this device information Jun 20 12:44:49 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetState Jun 20 12:44:49 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 12:44:49 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetState Jun 20 12:44:49 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 20 12:44:49 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 20 12:44:49 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 20 12:44:51 smsl-n100 volumio[1172]: info: VolumeController::SetAlsaVolume+ Jun 20 12:44:51 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:44:51 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:44:51 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:44:51 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:44:51 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:44:51 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:44:55 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 20 12:44:55 smsl-n100 volumio[1172]: info: Received Get System Info Jun 20 12:44:55 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 12:44:55 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 12:44:55 smsl-n100 volumio[1172]: info: Discovery: Getting this device information Jun 20 12:44:55 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetState Jun 20 12:44:55 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 12:44:55 smsl-n100 volumio[1172]: info: VolumeController::SetAlsaVolume100 Jun 20 12:44:56 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:44:56 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:44:56 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:44:56 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:44:56 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:44:56 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:44:56 smsl-n100 volumio[1172]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 20 12:44:56 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 12:44:56 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 12:44:56 smsl-n100 volumio[1172]: info: Discovery: Getting this device information Jun 20 12:44:56 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetState Jun 20 12:44:56 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 12:44:56 smsl-n100 ntpd[18949]: ntpd exiting on signal 15 (Terminated) Jun 20 12:44:56 smsl-n100 ntpd[18949]: 213.108.170.26 local addr 192.168.40.219 -> Jun 20 12:44:56 smsl-n100 systemd[1]: Stopping Network Time Service... Jun 20 12:44:56 smsl-n100 ntpd[18949]: 185.211.244.47 local addr 192.168.40.219 -> Jun 20 12:44:56 smsl-n100 systemd[1]: ntp.service: Succeeded. Jun 20 12:44:56 smsl-n100 ntpd[18949]: 162.159.200.1 local addr 192.168.40.219 -> Jun 20 12:44:56 smsl-n100 systemd[1]: Stopped Network Time Service. Jun 20 12:44:56 smsl-n100 volumio[1172]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 20 12:44:56 smsl-n100 ntpd[18949]: 46.138.254.88 local addr 192.168.40.219 -> Jun 20 12:44:56 smsl-n100 ntpd[18949]: 78.36.199.163 local addr 192.168.40.219 -> Jun 20 12:44:56 smsl-n100 ntpd[18949]: 185.119.57.152 local addr 192.168.40.219 -> Jun 20 12:44:56 smsl-n100 ntpd[18949]: 77.37.142.35 local addr 192.168.40.219 -> Jun 20 12:44:56 smsl-n100 ntpd[18949]: 51.250.53.172 local addr 192.168.40.219 -> Jun 20 12:44:56 smsl-n100 ntpd[18949]: 93.183.81.140 local addr 192.168.40.219 -> Jun 20 12:44:56 smsl-n100 ntpd[18949]: 92.255.126.3 local addr 192.168.40.219 -> Jun 20 12:44:56 smsl-n100 ntpd[18949]: 91.206.16.3 local addr 192.168.40.219 -> Jun 20 12:44:56 smsl-n100 ntpd[18949]: 45.141.102.99 local addr 192.168.40.219 -> Jun 20 12:44:56 smsl-n100 volumio[1172]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 20 12:44:56 smsl-n100 volumio[1172]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 20 12:44:56 smsl-n100 systemd[1]: Starting Network Time Service... Jun 20 12:44:56 smsl-n100 volumio[1172]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 20 12:44:56 smsl-n100 ntpd[19709]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 20 12:44:56 smsl-n100 ntpd[19709]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jun 20 12:44:56 smsl-n100 systemd[1]: Started Network Time Service. Jun 20 12:44:56 smsl-n100 ntpd[19716]: proto: precision = 1.166 usec (-20) Jun 20 12:44:56 smsl-n100 ntpd[19716]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 20 12:44:56 smsl-n100 ntpd[19716]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 20 12:44:56 smsl-n100 ntpd[19716]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 906 days ago Jun 20 12:44:56 smsl-n100 ntpd[19716]: Listen and drop on 0 v6wildcard [::]:123 Jun 20 12:44:56 smsl-n100 ntpd[19716]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 20 12:44:56 smsl-n100 ntpd[19716]: Listen normally on 2 lo 127.0.0.1:123 Jun 20 12:44:56 smsl-n100 ntpd[19716]: Listen normally on 3 eth0 192.168.40.219:123 Jun 20 12:44:56 smsl-n100 ntpd[19716]: Listening on routing socket on fd #20 for interface updates Jun 20 12:44:56 smsl-n100 ntpd[19716]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Jun 20 12:44:56 smsl-n100 ntpd[19716]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Jun 20 12:44:57 smsl-n100 ntpd[19716]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 20 12:44:58 smsl-n100 ntpd[19716]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 20 12:44:59 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 20 12:44:59 smsl-n100 volumio[1172]: info: TuneIn handleBrowseUri: tunein/local Jun 20 12:44:59 smsl-n100 ntpd[19716]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 20 12:45:00 smsl-n100 ntpd[19716]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 20 12:45:02 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPlay Jun 20 12:45:02 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:02 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:02 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 131 Jun 20 12:45:02 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:02 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 131 Jun 20 12:45:02 smsl-n100 volumio[1172]: info: [1750412702473] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:02 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:02 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 3 milliseconds Jun 20 12:45:02 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:02 smsl-n100 volumio[1172]: info: Jun 20 12:45:02 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:02 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:02 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 4 milliseconds Jun 20 12:45:02 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/251714511" Jun 20 12:45:02 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:02 smsl-n100 volumio[1172]: info: ------------------------------ 7ms Jun 20 12:45:02 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/251714511 Jun 20 12:45:02 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:02 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:02 smsl-n100 volumio[1172]: info: getStreamUrl took 13 milliseconds Jun 20 12:45:02 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:02 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:02 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/251714511" Jun 20 12:45:02 smsl-n100 volumio[1172]: info: Jun 20 12:45:02 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:02 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:02 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/251714511" took 3 milliseconds Jun 20 12:45:02 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:02 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:02 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:02 smsl-n100 volumio[1172]: info: sendMpdCommand play took 4 milliseconds Jun 20 12:45:02 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/251714511 Jun 20 12:45:02 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:02 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:02 smsl-n100 volumio[1172]: info: getStreamUrl took 8 milliseconds Jun 20 12:45:02 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:02 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:02 smsl-n100 volumio[1172]: info: Jun 20 12:45:02 smsl-n100 volumio[1172]: ---------------------------- MPD announces state update: player Jun 20 12:45:02 smsl-n100 volumio[1172]: info: ControllerMpd::getState Jun 20 12:45:02 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jun 20 12:45:02 smsl-n100 volumio[1172]: error: MPD Playback Error: Failed to decode stream, moving to next track Jun 20 12:45:02 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 12:45:02 smsl-n100 volumio[1172]: info: sendMpdCommand status took 12 milliseconds Jun 20 12:45:02 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseState Jun 20 12:45:02 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 12:45:02 smsl-n100 volumio[1172]: info: sendMpdCommand clearerror took 8 milliseconds Jun 20 12:45:02 smsl-n100 volumio[1172]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 20 12:45:02 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jun 20 12:45:02 smsl-n100 volumio[1172]: info: ControllerMpd::pushState Jun 20 12:45:02 smsl-n100 volumio[1172]: info: CoreCommandRouter::servicePushState Jun 20 12:45:02 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:02 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:45:02 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:02 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:02 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:02 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 131 Jun 20 12:45:02 smsl-n100 volumio[1172]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"251714511","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/251714511","trackType":"qobuz"} Jun 20 12:45:02 smsl-n100 volumio[1172]: verbose: CURRENT POSITION 131 Jun 20 12:45:02 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState stateService stop Jun 20 12:45:02 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Jun 20 12:45:02 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:02 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:02 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:02 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:02 smsl-n100 volumio[1172]: info: No code Jun 20 12:45:02 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:02 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:02 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:02 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:02 smsl-n100 volumio[1172]: info: ------------------------------ 89ms Jun 20 12:45:02 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:02 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:02 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:03 smsl-n100 volumio[1172]: info: Moving to next track after playback error Jun 20 12:45:03 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPlay Jun 20 12:45:03 smsl-n100 volumio[1172]: info: CoreStateMachine::play index 196 Jun 20 12:45:03 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:03 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:03 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:03 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:03 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:03 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 196 Jun 20 12:45:03 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:03 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 196 Jun 20 12:45:03 smsl-n100 volumio[1172]: info: [1750412703590] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:03 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:03 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 2 milliseconds Jun 20 12:45:03 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:03 smsl-n100 volumio[1172]: info: Jun 20 12:45:03 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:03 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:03 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 6 milliseconds Jun 20 12:45:03 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/220613588" Jun 20 12:45:03 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:03 smsl-n100 volumio[1172]: info: ------------------------------ 7ms Jun 20 12:45:03 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/220613588 Jun 20 12:45:03 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:03 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:03 smsl-n100 volumio[1172]: info: getStreamUrl took 8 milliseconds Jun 20 12:45:03 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:03 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:03 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/220613588" Jun 20 12:45:03 smsl-n100 volumio[1172]: info: Jun 20 12:45:03 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:03 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:03 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/220613588" took 4 milliseconds Jun 20 12:45:03 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:03 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:03 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:03 smsl-n100 volumio[1172]: info: sendMpdCommand play took 4 milliseconds Jun 20 12:45:03 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/220613588 Jun 20 12:45:03 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:03 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:03 smsl-n100 volumio[1172]: info: getStreamUrl took 7 milliseconds Jun 20 12:45:03 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:03 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:03 smsl-n100 volumio[1172]: info: Jun 20 12:45:03 smsl-n100 volumio[1172]: ---------------------------- MPD announces state update: player Jun 20 12:45:03 smsl-n100 volumio[1172]: info: ControllerMpd::getState Jun 20 12:45:03 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jun 20 12:45:03 smsl-n100 volumio[1172]: error: MPD Playback Error: Failed to decode stream, moving to next track Jun 20 12:45:03 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 12:45:03 smsl-n100 volumio[1172]: info: sendMpdCommand status took 9 milliseconds Jun 20 12:45:03 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseState Jun 20 12:45:03 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 12:45:03 smsl-n100 volumio[1172]: info: sendMpdCommand clearerror took 10 milliseconds Jun 20 12:45:03 smsl-n100 volumio[1172]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 20 12:45:03 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jun 20 12:45:03 smsl-n100 volumio[1172]: info: ControllerMpd::pushState Jun 20 12:45:03 smsl-n100 volumio[1172]: info: CoreCommandRouter::servicePushState Jun 20 12:45:03 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:03 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:45:03 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:03 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:03 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:03 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 196 Jun 20 12:45:03 smsl-n100 volumio[1172]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"220613588","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/220613588","trackType":"qobuz"} Jun 20 12:45:03 smsl-n100 volumio[1172]: verbose: CURRENT POSITION 196 Jun 20 12:45:03 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState stateService stop Jun 20 12:45:03 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Jun 20 12:45:03 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:03 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:03 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:03 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:03 smsl-n100 volumio[1172]: info: No code Jun 20 12:45:03 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:03 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:03 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:03 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:03 smsl-n100 volumio[1172]: info: ------------------------------ 84ms Jun 20 12:45:03 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:03 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:03 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:04 smsl-n100 volumio[1172]: info: Moving to next track after playback error Jun 20 12:45:04 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPlay Jun 20 12:45:04 smsl-n100 volumio[1172]: info: CoreStateMachine::play index 190 Jun 20 12:45:04 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:04 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:04 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:04 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:04 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:04 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 190 Jun 20 12:45:04 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:04 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 190 Jun 20 12:45:04 smsl-n100 volumio[1172]: info: [1750412704697] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:04 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:04 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 3 milliseconds Jun 20 12:45:04 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:04 smsl-n100 volumio[1172]: info: Jun 20 12:45:04 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:04 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:04 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 6 milliseconds Jun 20 12:45:04 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/15774701" Jun 20 12:45:04 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/15774701 Jun 20 12:45:04 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:04 smsl-n100 volumio[1172]: info: ------------------------------ 8ms Jun 20 12:45:04 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:04 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:04 smsl-n100 volumio[1172]: info: getStreamUrl took 8 milliseconds Jun 20 12:45:04 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:04 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:04 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/15774701" Jun 20 12:45:04 smsl-n100 volumio[1172]: info: Jun 20 12:45:04 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:04 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:04 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/15774701" took 3 milliseconds Jun 20 12:45:04 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:04 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:04 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:04 smsl-n100 volumio[1172]: info: sendMpdCommand play took 3 milliseconds Jun 20 12:45:04 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/15774701 Jun 20 12:45:04 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:04 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:04 smsl-n100 volumio[1172]: info: getStreamUrl took 9 milliseconds Jun 20 12:45:04 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:04 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:04 smsl-n100 volumio[1172]: info: Jun 20 12:45:04 smsl-n100 volumio[1172]: ---------------------------- MPD announces state update: player Jun 20 12:45:04 smsl-n100 volumio[1172]: info: ControllerMpd::getState Jun 20 12:45:04 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jun 20 12:45:04 smsl-n100 volumio[1172]: error: MPD Playback Error: Failed to decode stream, moving to next track Jun 20 12:45:04 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 12:45:04 smsl-n100 volumio[1172]: info: sendMpdCommand status took 12 milliseconds Jun 20 12:45:04 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseState Jun 20 12:45:04 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 12:45:04 smsl-n100 volumio[1172]: info: sendMpdCommand clearerror took 6 milliseconds Jun 20 12:45:04 smsl-n100 volumio[1172]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 20 12:45:04 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jun 20 12:45:04 smsl-n100 volumio[1172]: info: ControllerMpd::pushState Jun 20 12:45:04 smsl-n100 volumio[1172]: info: CoreCommandRouter::servicePushState Jun 20 12:45:04 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:04 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:45:04 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:04 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:04 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:04 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 190 Jun 20 12:45:04 smsl-n100 volumio[1172]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"15774701","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/15774701","trackType":"qobuz"} Jun 20 12:45:04 smsl-n100 volumio[1172]: verbose: CURRENT POSITION 190 Jun 20 12:45:04 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState stateService stop Jun 20 12:45:04 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Jun 20 12:45:04 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:04 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:04 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:04 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:04 smsl-n100 volumio[1172]: info: No code Jun 20 12:45:04 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:04 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:04 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:04 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:04 smsl-n100 volumio[1172]: info: ------------------------------ 81ms Jun 20 12:45:04 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:04 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:04 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:05 smsl-n100 volumio[1172]: info: Moving to next track after playback error Jun 20 12:45:05 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPlay Jun 20 12:45:05 smsl-n100 volumio[1172]: info: CoreStateMachine::play index 140 Jun 20 12:45:05 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:05 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:05 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:05 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:05 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:05 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 140 Jun 20 12:45:05 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:05 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 140 Jun 20 12:45:05 smsl-n100 volumio[1172]: info: [1750412705812] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:05 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:05 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 3 milliseconds Jun 20 12:45:05 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:05 smsl-n100 volumio[1172]: info: Jun 20 12:45:05 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:05 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:05 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 3 milliseconds Jun 20 12:45:05 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/144700947" Jun 20 12:45:05 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:05 smsl-n100 volumio[1172]: info: ------------------------------ 5ms Jun 20 12:45:05 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/144700947 Jun 20 12:45:05 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:05 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:05 smsl-n100 volumio[1172]: info: getStreamUrl took 7 milliseconds Jun 20 12:45:05 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:05 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:05 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/144700947" Jun 20 12:45:05 smsl-n100 volumio[1172]: info: Jun 20 12:45:05 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:05 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:05 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/144700947" took 4 milliseconds Jun 20 12:45:05 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:05 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:05 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:05 smsl-n100 volumio[1172]: info: sendMpdCommand play took 3 milliseconds Jun 20 12:45:05 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/144700947 Jun 20 12:45:05 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:05 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:05 smsl-n100 volumio[1172]: info: getStreamUrl took 7 milliseconds Jun 20 12:45:05 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:05 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:05 smsl-n100 volumio[1172]: info: Jun 20 12:45:05 smsl-n100 volumio[1172]: ---------------------------- MPD announces state update: player Jun 20 12:45:05 smsl-n100 volumio[1172]: info: ControllerMpd::getState Jun 20 12:45:05 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jun 20 12:45:05 smsl-n100 volumio[1172]: error: MPD Playback Error: Failed to decode stream, moving to next track Jun 20 12:45:05 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 12:45:05 smsl-n100 volumio[1172]: info: sendMpdCommand status took 12 milliseconds Jun 20 12:45:05 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseState Jun 20 12:45:05 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 12:45:05 smsl-n100 volumio[1172]: info: sendMpdCommand clearerror took 5 milliseconds Jun 20 12:45:05 smsl-n100 volumio[1172]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 20 12:45:05 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jun 20 12:45:05 smsl-n100 volumio[1172]: info: ControllerMpd::pushState Jun 20 12:45:05 smsl-n100 volumio[1172]: info: CoreCommandRouter::servicePushState Jun 20 12:45:05 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:05 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:45:05 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:05 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:05 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:05 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 140 Jun 20 12:45:05 smsl-n100 volumio[1172]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"144700947","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/144700947","trackType":"qobuz"} Jun 20 12:45:05 smsl-n100 volumio[1172]: verbose: CURRENT POSITION 140 Jun 20 12:45:05 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState stateService stop Jun 20 12:45:05 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Jun 20 12:45:05 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:05 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:05 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:05 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:05 smsl-n100 volumio[1172]: info: No code Jun 20 12:45:05 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:05 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:05 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:05 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:05 smsl-n100 volumio[1172]: info: ------------------------------ 80ms Jun 20 12:45:05 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:05 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:06 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:06 smsl-n100 volumio[1172]: info: Moving to next track after playback error Jun 20 12:45:06 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPlay Jun 20 12:45:06 smsl-n100 volumio[1172]: info: CoreStateMachine::play index 141 Jun 20 12:45:06 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:06 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:06 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:06 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:06 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:06 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 141 Jun 20 12:45:06 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:06 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 141 Jun 20 12:45:06 smsl-n100 volumio[1172]: info: [1750412706922] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:06 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:06 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 3 milliseconds Jun 20 12:45:06 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:06 smsl-n100 volumio[1172]: info: Jun 20 12:45:06 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:06 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:06 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 17 milliseconds Jun 20 12:45:06 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/7328531" Jun 20 12:45:06 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:06 smsl-n100 volumio[1172]: info: ------------------------------ 7ms Jun 20 12:45:06 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/7328531 Jun 20 12:45:06 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:06 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:06 smsl-n100 volumio[1172]: info: getStreamUrl took 8 milliseconds Jun 20 12:45:06 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:06 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:06 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/7328531" Jun 20 12:45:06 smsl-n100 volumio[1172]: info: Jun 20 12:45:06 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:06 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:06 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/7328531" took 5 milliseconds Jun 20 12:45:06 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:06 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:06 smsl-n100 volumio[1172]: info: ------------------------------ 7ms Jun 20 12:45:06 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/7328531 Jun 20 12:45:06 smsl-n100 volumio[1172]: info: sendMpdCommand play took 6 milliseconds Jun 20 12:45:07 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:07 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:07 smsl-n100 volumio[1172]: info: getStreamUrl took 13 milliseconds Jun 20 12:45:07 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:07 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:07 smsl-n100 volumio[1172]: info: Jun 20 12:45:07 smsl-n100 volumio[1172]: ---------------------------- MPD announces state update: player Jun 20 12:45:07 smsl-n100 volumio[1172]: info: ControllerMpd::getState Jun 20 12:45:07 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jun 20 12:45:07 smsl-n100 volumio[1172]: error: MPD Playback Error: Failed to decode stream, moving to next track Jun 20 12:45:07 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 12:45:07 smsl-n100 volumio[1172]: info: sendMpdCommand status took 12 milliseconds Jun 20 12:45:07 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseState Jun 20 12:45:07 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 12:45:07 smsl-n100 volumio[1172]: info: sendMpdCommand clearerror took 7 milliseconds Jun 20 12:45:07 smsl-n100 volumio[1172]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 20 12:45:07 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jun 20 12:45:07 smsl-n100 volumio[1172]: info: ControllerMpd::pushState Jun 20 12:45:07 smsl-n100 volumio[1172]: info: CoreCommandRouter::servicePushState Jun 20 12:45:07 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:07 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:45:07 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:07 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:07 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:07 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 141 Jun 20 12:45:07 smsl-n100 volumio[1172]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7328531","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/7328531","trackType":"qobuz"} Jun 20 12:45:07 smsl-n100 volumio[1172]: verbose: CURRENT POSITION 141 Jun 20 12:45:07 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState stateService stop Jun 20 12:45:07 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Jun 20 12:45:07 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:07 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:07 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:07 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:07 smsl-n100 volumio[1172]: info: No code Jun 20 12:45:07 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:07 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:07 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:07 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:07 smsl-n100 volumio[1172]: info: ------------------------------ 84ms Jun 20 12:45:07 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:07 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:07 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:07 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioNext Jun 20 12:45:07 smsl-n100 volumio[1172]: info: CoreStateMachine::next Jun 20 12:45:07 smsl-n100 volumio[1172]: info: ControllerMpd::next Jun 20 12:45:07 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand next Jun 20 12:45:08 smsl-n100 volumio[1172]: info: Moving to next track after playback error Jun 20 12:45:08 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPlay Jun 20 12:45:08 smsl-n100 volumio[1172]: info: CoreStateMachine::play index 71 Jun 20 12:45:08 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:08 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:08 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:08 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:08 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:08 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 71 Jun 20 12:45:08 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:08 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 71 Jun 20 12:45:08 smsl-n100 volumio[1172]: info: [1750412708057] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:08 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:08 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 4 milliseconds Jun 20 12:45:08 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:08 smsl-n100 volumio[1172]: info: Jun 20 12:45:08 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:08 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:08 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 4 milliseconds Jun 20 12:45:08 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/313558352" Jun 20 12:45:08 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:08 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:08 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/313558352 Jun 20 12:45:08 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:08 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:08 smsl-n100 volumio[1172]: info: getStreamUrl took 7 milliseconds Jun 20 12:45:08 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:08 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:08 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/313558352" Jun 20 12:45:08 smsl-n100 volumio[1172]: info: Jun 20 12:45:08 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:08 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:08 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/313558352" took 3 milliseconds Jun 20 12:45:08 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:08 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:08 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:08 smsl-n100 volumio[1172]: info: sendMpdCommand play took 3 milliseconds Jun 20 12:45:08 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/313558352 Jun 20 12:45:08 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:08 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:08 smsl-n100 volumio[1172]: info: getStreamUrl took 7 milliseconds Jun 20 12:45:08 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:08 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:08 smsl-n100 volumio[1172]: info: Jun 20 12:45:08 smsl-n100 volumio[1172]: ---------------------------- MPD announces state update: player Jun 20 12:45:08 smsl-n100 volumio[1172]: info: ControllerMpd::getState Jun 20 12:45:08 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jun 20 12:45:08 smsl-n100 volumio[1172]: error: MPD Playback Error: Failed to decode stream, moving to next track Jun 20 12:45:08 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 12:45:08 smsl-n100 volumio[1172]: info: sendMpdCommand status took 10 milliseconds Jun 20 12:45:08 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseState Jun 20 12:45:08 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 12:45:08 smsl-n100 volumio[1172]: info: sendMpdCommand clearerror took 7 milliseconds Jun 20 12:45:08 smsl-n100 volumio[1172]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 20 12:45:08 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jun 20 12:45:08 smsl-n100 volumio[1172]: info: ControllerMpd::pushState Jun 20 12:45:08 smsl-n100 volumio[1172]: info: CoreCommandRouter::servicePushState Jun 20 12:45:08 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:08 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:45:08 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:08 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:08 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:08 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 71 Jun 20 12:45:08 smsl-n100 volumio[1172]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"313558352","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/313558352","trackType":"qobuz"} Jun 20 12:45:08 smsl-n100 volumio[1172]: verbose: CURRENT POSITION 71 Jun 20 12:45:08 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState stateService stop Jun 20 12:45:08 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Jun 20 12:45:08 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:08 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:08 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:08 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:08 smsl-n100 volumio[1172]: info: No code Jun 20 12:45:08 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:08 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:08 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:08 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:08 smsl-n100 volumio[1172]: info: ------------------------------ 98ms Jun 20 12:45:08 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:08 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:08 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Moving to next track after playback error Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPlay Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::play index 112 Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 112 Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 112 Jun 20 12:45:09 smsl-n100 volumio[1172]: info: [1750412709159] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 5 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Jun 20 12:45:09 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 5 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/71179065" Jun 20 12:45:09 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:09 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/71179065 Jun 20 12:45:09 smsl-n100 volumio[1172]: info: ------------------------------ 22ms Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:09 smsl-n100 volumio[1172]: info: getStreamUrl took 11 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioNext Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::next Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:09 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioNext Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::next Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioNext Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::next Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioNext Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::next Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioNext Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::next Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 45 Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 45 Jun 20 12:45:09 smsl-n100 volumio[1172]: info: [1750412709249] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 45 Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 45 Jun 20 12:45:09 smsl-n100 volumio[1172]: info: [1750412709252] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 45 Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 45 Jun 20 12:45:09 smsl-n100 volumio[1172]: info: [1750412709254] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 45 Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 45 Jun 20 12:45:09 smsl-n100 volumio[1172]: info: [1750412709256] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 45 Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 45 Jun 20 12:45:09 smsl-n100 volumio[1172]: info: [1750412709259] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::updateTrackBlock Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrackBlock Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::updateTrackBlock Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrackBlock Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::updateTrackBlock Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrackBlock Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::updateTrackBlock Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrackBlock Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::updateTrackBlock Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrackBlock Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/71179065" Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 25 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 21 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 19 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 18 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 17 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Jun 20 12:45:09 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Jun 20 12:45:09 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Jun 20 12:45:09 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Jun 20 12:45:09 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Jun 20 12:45:09 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Jun 20 12:45:09 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/71179065" took 22 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 17 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 18 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 17 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 18 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 18 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/297890568" Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/297890568" Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/297890568" Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/297890568" Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/297890568" Jun 20 12:45:09 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/297890568 Jun 20 12:45:09 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:09 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:09 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:09 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:09 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:09 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:09 smsl-n100 volumio[1172]: info: ------------------------------ 31ms Jun 20 12:45:09 smsl-n100 volumio[1172]: info: ------------------------------ 30ms Jun 20 12:45:09 smsl-n100 volumio[1172]: info: ------------------------------ 29ms Jun 20 12:45:09 smsl-n100 volumio[1172]: info: ------------------------------ 28ms Jun 20 12:45:09 smsl-n100 volumio[1172]: info: ------------------------------ 27ms Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand play took 20 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:09 smsl-n100 volumio[1172]: info: getStreamUrl took 8 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/297890568" Jun 20 12:45:09 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/297890568 Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:09 smsl-n100 volumio[1172]: info: getStreamUrl took 8 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:09 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/297890568 Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:09 smsl-n100 volumio[1172]: info: getStreamUrl took 10 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:09 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/297890568 Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:09 smsl-n100 volumio[1172]: info: getStreamUrl took 9 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:09 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/297890568 Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:09 smsl-n100 volumio[1172]: info: getStreamUrl took 7 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/297890568" Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/297890568" Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/297890568" Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/297890568" Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Jun 20 12:45:09 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Jun 20 12:45:09 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Jun 20 12:45:09 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Jun 20 12:45:09 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Jun 20 12:45:09 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/297890568" took 162 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/297890568" took 14 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/297890568" took 14 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/297890568" took 14 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/297890568" took 15 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:09 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/297890568 Jun 20 12:45:09 smsl-n100 volumio[1172]: info: ------------------------------ 45ms Jun 20 12:45:09 smsl-n100 volumio[1172]: info: ------------------------------ 45ms Jun 20 12:45:09 smsl-n100 volumio[1172]: info: ------------------------------ 44ms Jun 20 12:45:09 smsl-n100 volumio[1172]: info: ------------------------------ 43ms Jun 20 12:45:09 smsl-n100 volumio[1172]: info: ------------------------------ 42ms Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand play took 36 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand play took 35 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand play took 34 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand play took 34 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand play took 33 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:09 smsl-n100 volumio[1172]: info: getStreamUrl took 10 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Jun 20 12:45:09 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:09 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/297890568 Jun 20 12:45:09 smsl-n100 volumio[1172]: info: ------------------------------ 5ms Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:09 smsl-n100 volumio[1172]: info: getStreamUrl took 7 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Jun 20 12:45:09 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:09 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/297890568 Jun 20 12:45:09 smsl-n100 volumio[1172]: info: ------------------------------ 5ms Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:09 smsl-n100 volumio[1172]: info: getStreamUrl took 8 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:09 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Jun 20 12:45:09 smsl-n100 volumio[1172]: ---------------------------- MPD announces state update: player Jun 20 12:45:09 smsl-n100 volumio[1172]: info: ControllerMpd::getState Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jun 20 12:45:09 smsl-n100 volumio[1172]: error: MPD Playback Error: Failed to decode stream, moving to next track Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand status took 11 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseState Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand clearerror took 4 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jun 20 12:45:09 smsl-n100 volumio[1172]: info: ControllerMpd::pushState Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreCommandRouter::servicePushState Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:09 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:09 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 45 Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"297890568","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/297890568","trackType":"qobuz"} Jun 20 12:45:09 smsl-n100 volumio[1172]: verbose: CURRENT POSITION 45 Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState stateService stop Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:09 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:09 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:09 smsl-n100 volumio[1172]: info: No code Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:09 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:09 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:09 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:09 smsl-n100 volumio[1172]: info: ------------------------------ 80ms Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:09 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioNext Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreStateMachine::next Jun 20 12:45:10 smsl-n100 volumio[1172]: info: ControllerMpd::next Jun 20 12:45:10 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand next Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioNext Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreStateMachine::next Jun 20 12:45:10 smsl-n100 volumio[1172]: info: ControllerMpd::next Jun 20 12:45:10 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand next Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioNext Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreStateMachine::next Jun 20 12:45:10 smsl-n100 volumio[1172]: info: ControllerMpd::next Jun 20 12:45:10 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand next Jun 20 12:45:10 smsl-n100 volumio[1172]: info: Moving to next track after playback error Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPlay Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreStateMachine::play index 127 Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 127 Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 127 Jun 20 12:45:10 smsl-n100 volumio[1172]: info: [1750412710703] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:10 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:10 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 1 milliseconds Jun 20 12:45:10 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:10 smsl-n100 volumio[1172]: info: Jun 20 12:45:10 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:10 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:10 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 3 milliseconds Jun 20 12:45:10 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/823785" Jun 20 12:45:10 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:10 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:10 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/823785 Jun 20 12:45:10 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:10 smsl-n100 volumio[1172]: info: getStreamUrl took 8 milliseconds Jun 20 12:45:10 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:10 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:10 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/823785" Jun 20 12:45:10 smsl-n100 volumio[1172]: info: Jun 20 12:45:10 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:10 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:10 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/823785" took 3 milliseconds Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:10 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:10 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:10 smsl-n100 volumio[1172]: info: sendMpdCommand play took 4 milliseconds Jun 20 12:45:10 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/823785 Jun 20 12:45:10 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:10 smsl-n100 volumio[1172]: info: getStreamUrl took 17 milliseconds Jun 20 12:45:10 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:10 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:10 smsl-n100 volumio[1172]: info: Jun 20 12:45:10 smsl-n100 volumio[1172]: ---------------------------- MPD announces state update: player Jun 20 12:45:10 smsl-n100 volumio[1172]: info: ControllerMpd::getState Jun 20 12:45:10 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jun 20 12:45:10 smsl-n100 volumio[1172]: error: MPD Playback Error: Failed to decode stream, moving to next track Jun 20 12:45:10 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 12:45:10 smsl-n100 volumio[1172]: info: sendMpdCommand status took 10 milliseconds Jun 20 12:45:10 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseState Jun 20 12:45:10 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 12:45:10 smsl-n100 volumio[1172]: info: sendMpdCommand clearerror took 8 milliseconds Jun 20 12:45:10 smsl-n100 volumio[1172]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 20 12:45:10 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jun 20 12:45:10 smsl-n100 volumio[1172]: info: ControllerMpd::pushState Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreCommandRouter::servicePushState Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:10 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:10 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 127 Jun 20 12:45:10 smsl-n100 volumio[1172]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"823785","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/823785","trackType":"qobuz"} Jun 20 12:45:10 smsl-n100 volumio[1172]: verbose: CURRENT POSITION 127 Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState stateService stop Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:10 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:10 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:10 smsl-n100 volumio[1172]: info: No code Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:10 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:10 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:10 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:10 smsl-n100 volumio[1172]: info: ------------------------------ 88ms Jun 20 12:45:10 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:10 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:10 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:11 smsl-n100 volumio[1172]: info: Moving to next track after playback error Jun 20 12:45:11 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPlay Jun 20 12:45:11 smsl-n100 volumio[1172]: info: CoreStateMachine::play index 132 Jun 20 12:45:11 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:11 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:11 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:11 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:11 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:11 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 132 Jun 20 12:45:11 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:11 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 132 Jun 20 12:45:11 smsl-n100 volumio[1172]: info: [1750412711818] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:11 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:11 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 4 milliseconds Jun 20 12:45:11 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:11 smsl-n100 volumio[1172]: info: Jun 20 12:45:11 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:11 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:11 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 5 milliseconds Jun 20 12:45:11 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/169136017" Jun 20 12:45:11 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:11 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:11 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/169136017 Jun 20 12:45:11 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:11 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:11 smsl-n100 volumio[1172]: info: getStreamUrl took 8 milliseconds Jun 20 12:45:11 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:11 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:11 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/169136017" Jun 20 12:45:11 smsl-n100 volumio[1172]: info: Jun 20 12:45:11 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:11 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:11 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/169136017" took 3 milliseconds Jun 20 12:45:11 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:11 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:11 smsl-n100 volumio[1172]: info: ------------------------------ 5ms Jun 20 12:45:11 smsl-n100 volumio[1172]: info: sendMpdCommand play took 3 milliseconds Jun 20 12:45:11 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/169136017 Jun 20 12:45:11 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:11 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:11 smsl-n100 volumio[1172]: info: getStreamUrl took 8 milliseconds Jun 20 12:45:11 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:11 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:11 smsl-n100 volumio[1172]: info: Jun 20 12:45:11 smsl-n100 volumio[1172]: ---------------------------- MPD announces state update: player Jun 20 12:45:11 smsl-n100 volumio[1172]: info: ControllerMpd::getState Jun 20 12:45:11 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jun 20 12:45:11 smsl-n100 volumio[1172]: error: MPD Playback Error: Failed to decode stream, moving to next track Jun 20 12:45:11 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 12:45:11 smsl-n100 volumio[1172]: info: sendMpdCommand status took 9 milliseconds Jun 20 12:45:11 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseState Jun 20 12:45:11 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 12:45:11 smsl-n100 volumio[1172]: info: sendMpdCommand clearerror took 11 milliseconds Jun 20 12:45:11 smsl-n100 volumio[1172]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 20 12:45:11 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jun 20 12:45:11 smsl-n100 volumio[1172]: info: ControllerMpd::pushState Jun 20 12:45:11 smsl-n100 volumio[1172]: info: CoreCommandRouter::servicePushState Jun 20 12:45:11 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:11 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:45:11 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:11 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:11 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:11 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 132 Jun 20 12:45:11 smsl-n100 volumio[1172]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"169136017","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/169136017","trackType":"qobuz"} Jun 20 12:45:11 smsl-n100 volumio[1172]: verbose: CURRENT POSITION 132 Jun 20 12:45:11 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState stateService stop Jun 20 12:45:11 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Jun 20 12:45:11 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:11 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:11 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:11 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:11 smsl-n100 volumio[1172]: info: No code Jun 20 12:45:11 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:11 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:11 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:11 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:11 smsl-n100 volumio[1172]: info: ------------------------------ 90ms Jun 20 12:45:12 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:12 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:12 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:12 smsl-n100 volumio[1172]: info: Moving to next track after playback error Jun 20 12:45:12 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPlay Jun 20 12:45:12 smsl-n100 volumio[1172]: info: CoreStateMachine::play index 139 Jun 20 12:45:12 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:12 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:12 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:12 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:12 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:12 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 139 Jun 20 12:45:12 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:12 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 139 Jun 20 12:45:12 smsl-n100 volumio[1172]: info: [1750412712925] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:12 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:12 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 5 milliseconds Jun 20 12:45:12 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:12 smsl-n100 volumio[1172]: info: Jun 20 12:45:12 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:12 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:12 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 6 milliseconds Jun 20 12:45:12 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/104949474" Jun 20 12:45:12 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:12 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:12 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/104949474 Jun 20 12:45:12 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:12 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:12 smsl-n100 volumio[1172]: info: getStreamUrl took 7 milliseconds Jun 20 12:45:12 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:12 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:12 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/104949474" Jun 20 12:45:12 smsl-n100 volumio[1172]: info: Jun 20 12:45:12 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:12 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:12 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/104949474" took 3 milliseconds Jun 20 12:45:12 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:12 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:12 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:12 smsl-n100 volumio[1172]: info: sendMpdCommand play took 3 milliseconds Jun 20 12:45:12 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/104949474 Jun 20 12:45:12 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:12 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:13 smsl-n100 volumio[1172]: info: getStreamUrl took 8 milliseconds Jun 20 12:45:13 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:13 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:13 smsl-n100 volumio[1172]: info: Jun 20 12:45:13 smsl-n100 volumio[1172]: ---------------------------- MPD announces state update: player Jun 20 12:45:13 smsl-n100 volumio[1172]: info: ControllerMpd::getState Jun 20 12:45:13 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jun 20 12:45:13 smsl-n100 volumio[1172]: error: MPD Playback Error: Failed to decode stream, moving to next track Jun 20 12:45:13 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 12:45:13 smsl-n100 volumio[1172]: info: sendMpdCommand status took 10 milliseconds Jun 20 12:45:13 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseState Jun 20 12:45:13 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 12:45:13 smsl-n100 volumio[1172]: info: sendMpdCommand clearerror took 10 milliseconds Jun 20 12:45:13 smsl-n100 volumio[1172]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 20 12:45:13 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jun 20 12:45:13 smsl-n100 volumio[1172]: info: ControllerMpd::pushState Jun 20 12:45:13 smsl-n100 volumio[1172]: info: CoreCommandRouter::servicePushState Jun 20 12:45:13 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:13 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:45:13 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:13 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:13 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:13 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 139 Jun 20 12:45:13 smsl-n100 volumio[1172]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"104949474","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/104949474","trackType":"qobuz"} Jun 20 12:45:13 smsl-n100 volumio[1172]: verbose: CURRENT POSITION 139 Jun 20 12:45:13 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState stateService stop Jun 20 12:45:13 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Jun 20 12:45:13 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:13 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:13 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:13 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:13 smsl-n100 volumio[1172]: info: No code Jun 20 12:45:13 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:13 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:13 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:13 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:13 smsl-n100 volumio[1172]: info: ------------------------------ 89ms Jun 20 12:45:13 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:13 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:13 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:14 smsl-n100 volumio[1172]: info: Moving to next track after playback error Jun 20 12:45:14 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPlay Jun 20 12:45:14 smsl-n100 volumio[1172]: info: CoreStateMachine::play index 62 Jun 20 12:45:14 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:14 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:14 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:14 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:14 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:14 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 62 Jun 20 12:45:14 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:14 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 62 Jun 20 12:45:14 smsl-n100 volumio[1172]: info: [1750412714033] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:14 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:14 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 4 milliseconds Jun 20 12:45:14 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:14 smsl-n100 volumio[1172]: info: Jun 20 12:45:14 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:14 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:14 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 5 milliseconds Jun 20 12:45:14 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/301221952" Jun 20 12:45:14 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:14 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:14 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/301221952 Jun 20 12:45:14 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:14 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:14 smsl-n100 volumio[1172]: info: getStreamUrl took 7 milliseconds Jun 20 12:45:14 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:14 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:14 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/301221952" Jun 20 12:45:14 smsl-n100 volumio[1172]: info: Jun 20 12:45:14 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:14 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:14 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/301221952" took 3 milliseconds Jun 20 12:45:14 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:14 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:14 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:14 smsl-n100 volumio[1172]: info: sendMpdCommand play took 3 milliseconds Jun 20 12:45:14 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/301221952 Jun 20 12:45:14 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:14 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:14 smsl-n100 volumio[1172]: info: getStreamUrl took 7 milliseconds Jun 20 12:45:14 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:14 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:14 smsl-n100 volumio[1172]: info: Jun 20 12:45:14 smsl-n100 volumio[1172]: ---------------------------- MPD announces state update: player Jun 20 12:45:14 smsl-n100 volumio[1172]: info: ControllerMpd::getState Jun 20 12:45:14 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jun 20 12:45:14 smsl-n100 volumio[1172]: error: MPD Playback Error: Failed to decode stream, moving to next track Jun 20 12:45:14 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 12:45:14 smsl-n100 volumio[1172]: info: sendMpdCommand status took 10 milliseconds Jun 20 12:45:14 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseState Jun 20 12:45:14 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 12:45:14 smsl-n100 volumio[1172]: info: sendMpdCommand clearerror took 8 milliseconds Jun 20 12:45:14 smsl-n100 volumio[1172]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 20 12:45:14 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jun 20 12:45:14 smsl-n100 volumio[1172]: info: ControllerMpd::pushState Jun 20 12:45:14 smsl-n100 volumio[1172]: info: CoreCommandRouter::servicePushState Jun 20 12:45:14 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:14 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:45:14 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:14 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:14 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:14 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 62 Jun 20 12:45:14 smsl-n100 volumio[1172]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"301221952","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/301221952","trackType":"qobuz"} Jun 20 12:45:14 smsl-n100 volumio[1172]: verbose: CURRENT POSITION 62 Jun 20 12:45:14 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState stateService stop Jun 20 12:45:14 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Jun 20 12:45:14 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:14 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:14 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:14 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:14 smsl-n100 volumio[1172]: info: No code Jun 20 12:45:14 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:14 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:14 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:14 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:14 smsl-n100 volumio[1172]: info: ------------------------------ 82ms Jun 20 12:45:14 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:14 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:14 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:15 smsl-n100 volumio[1172]: info: Moving to next track after playback error Jun 20 12:45:15 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPlay Jun 20 12:45:15 smsl-n100 volumio[1172]: info: CoreStateMachine::play index 176 Jun 20 12:45:15 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:15 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:15 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:15 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:15 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:15 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 176 Jun 20 12:45:15 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:15 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 176 Jun 20 12:45:15 smsl-n100 volumio[1172]: info: [1750412715141] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:15 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:15 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 2 milliseconds Jun 20 12:45:15 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:15 smsl-n100 volumio[1172]: info: Jun 20 12:45:15 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:15 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:15 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 3 milliseconds Jun 20 12:45:15 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/52336019" Jun 20 12:45:15 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/52336019 Jun 20 12:45:15 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:15 smsl-n100 volumio[1172]: info: ------------------------------ 9ms Jun 20 12:45:15 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:15 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:15 smsl-n100 volumio[1172]: info: getStreamUrl took 7 milliseconds Jun 20 12:45:15 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:15 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:15 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/52336019" Jun 20 12:45:15 smsl-n100 volumio[1172]: info: Jun 20 12:45:15 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:15 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:15 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/52336019" took 4 milliseconds Jun 20 12:45:15 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:15 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:15 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:15 smsl-n100 volumio[1172]: info: sendMpdCommand play took 4 milliseconds Jun 20 12:45:15 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/52336019 Jun 20 12:45:15 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:15 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:15 smsl-n100 volumio[1172]: info: getStreamUrl took 8 milliseconds Jun 20 12:45:15 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:15 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:15 smsl-n100 volumio[1172]: info: Jun 20 12:45:15 smsl-n100 volumio[1172]: ---------------------------- MPD announces state update: player Jun 20 12:45:15 smsl-n100 volumio[1172]: info: ControllerMpd::getState Jun 20 12:45:15 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jun 20 12:45:15 smsl-n100 volumio[1172]: error: MPD Playback Error: Failed to decode stream, moving to next track Jun 20 12:45:15 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 12:45:15 smsl-n100 volumio[1172]: info: sendMpdCommand status took 13 milliseconds Jun 20 12:45:15 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseState Jun 20 12:45:15 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 12:45:15 smsl-n100 volumio[1172]: info: sendMpdCommand clearerror took 12 milliseconds Jun 20 12:45:15 smsl-n100 volumio[1172]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 20 12:45:15 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jun 20 12:45:15 smsl-n100 volumio[1172]: info: ControllerMpd::pushState Jun 20 12:45:15 smsl-n100 volumio[1172]: info: CoreCommandRouter::servicePushState Jun 20 12:45:15 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:15 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:45:15 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:15 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:15 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:15 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 176 Jun 20 12:45:15 smsl-n100 volumio[1172]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"52336019","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/52336019","trackType":"qobuz"} Jun 20 12:45:15 smsl-n100 volumio[1172]: verbose: CURRENT POSITION 176 Jun 20 12:45:15 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState stateService stop Jun 20 12:45:15 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Jun 20 12:45:15 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:15 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:15 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:15 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:15 smsl-n100 volumio[1172]: info: No code Jun 20 12:45:15 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:15 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:15 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:15 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:15 smsl-n100 volumio[1172]: info: ------------------------------ 89ms Jun 20 12:45:15 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:15 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:15 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:16 smsl-n100 volumio[1172]: info: Moving to next track after playback error Jun 20 12:45:16 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPlay Jun 20 12:45:16 smsl-n100 volumio[1172]: info: CoreStateMachine::play index 174 Jun 20 12:45:16 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:16 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:16 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:16 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:16 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:16 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 174 Jun 20 12:45:16 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:16 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 174 Jun 20 12:45:16 smsl-n100 volumio[1172]: info: [1750412716254] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:16 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:16 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 3 milliseconds Jun 20 12:45:16 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:16 smsl-n100 volumio[1172]: info: Jun 20 12:45:16 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:16 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:16 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 5 milliseconds Jun 20 12:45:16 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/4343214" Jun 20 12:45:16 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/4343214 Jun 20 12:45:16 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:16 smsl-n100 volumio[1172]: info: ------------------------------ 8ms Jun 20 12:45:16 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:16 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:16 smsl-n100 volumio[1172]: info: getStreamUrl took 7 milliseconds Jun 20 12:45:16 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:16 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:16 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/4343214" Jun 20 12:45:16 smsl-n100 volumio[1172]: info: Jun 20 12:45:16 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:16 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:16 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/4343214" took 3 milliseconds Jun 20 12:45:16 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:16 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:16 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:16 smsl-n100 volumio[1172]: info: sendMpdCommand play took 3 milliseconds Jun 20 12:45:16 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/4343214 Jun 20 12:45:16 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:16 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:16 smsl-n100 volumio[1172]: info: getStreamUrl took 7 milliseconds Jun 20 12:45:16 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:16 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:16 smsl-n100 volumio[1172]: info: Jun 20 12:45:16 smsl-n100 volumio[1172]: ---------------------------- MPD announces state update: player Jun 20 12:45:16 smsl-n100 volumio[1172]: info: ControllerMpd::getState Jun 20 12:45:16 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jun 20 12:45:16 smsl-n100 volumio[1172]: error: MPD Playback Error: Failed to decode stream, moving to next track Jun 20 12:45:16 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 12:45:16 smsl-n100 volumio[1172]: info: sendMpdCommand status took 10 milliseconds Jun 20 12:45:16 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseState Jun 20 12:45:16 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 12:45:16 smsl-n100 volumio[1172]: info: sendMpdCommand clearerror took 9 milliseconds Jun 20 12:45:16 smsl-n100 volumio[1172]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 20 12:45:16 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jun 20 12:45:16 smsl-n100 volumio[1172]: info: ControllerMpd::pushState Jun 20 12:45:16 smsl-n100 volumio[1172]: info: CoreCommandRouter::servicePushState Jun 20 12:45:16 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:16 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:45:16 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:16 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:16 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:16 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 174 Jun 20 12:45:16 smsl-n100 volumio[1172]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4343214","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4343214","trackType":"qobuz"} Jun 20 12:45:16 smsl-n100 volumio[1172]: verbose: CURRENT POSITION 174 Jun 20 12:45:16 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState stateService stop Jun 20 12:45:16 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Jun 20 12:45:16 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:16 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:16 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:16 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:16 smsl-n100 volumio[1172]: info: No code Jun 20 12:45:16 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:16 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:16 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:16 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:16 smsl-n100 volumio[1172]: info: ------------------------------ 111ms Jun 20 12:45:16 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:16 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:16 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:17 smsl-n100 volumio[1172]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 20 12:45:17 smsl-n100 volumio[1172]: info: Moving to next track after playback error Jun 20 12:45:17 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPlay Jun 20 12:45:17 smsl-n100 volumio[1172]: info: CoreStateMachine::play index 54 Jun 20 12:45:17 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:17 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:17 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:17 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:17 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:17 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 54 Jun 20 12:45:17 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:17 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 54 Jun 20 12:45:17 smsl-n100 volumio[1172]: info: [1750412717374] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:17 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:17 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 7 milliseconds Jun 20 12:45:17 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:17 smsl-n100 volumio[1172]: info: Jun 20 12:45:17 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:17 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:17 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 6 milliseconds Jun 20 12:45:17 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/312623300" Jun 20 12:45:17 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:17 smsl-n100 volumio[1172]: info: ------------------------------ 8ms Jun 20 12:45:17 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/312623300 Jun 20 12:45:17 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 20 12:45:17 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:17 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:17 smsl-n100 volumio[1172]: info: getStreamUrl took 9 milliseconds Jun 20 12:45:17 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:17 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:17 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/312623300" Jun 20 12:45:17 smsl-n100 volumio[1172]: info: Jun 20 12:45:17 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:17 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:17 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/312623300" took 5 milliseconds Jun 20 12:45:17 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:17 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:17 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:17 smsl-n100 volumio[1172]: info: sendMpdCommand play took 4 milliseconds Jun 20 12:45:17 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/312623300 Jun 20 12:45:17 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:17 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:17 smsl-n100 volumio[1172]: info: getStreamUrl took 8 milliseconds Jun 20 12:45:17 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:17 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:17 smsl-n100 volumio[1172]: info: Jun 20 12:45:17 smsl-n100 volumio[1172]: ---------------------------- MPD announces state update: player Jun 20 12:45:17 smsl-n100 volumio[1172]: info: ControllerMpd::getState Jun 20 12:45:17 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jun 20 12:45:17 smsl-n100 volumio[1172]: error: MPD Playback Error: Failed to decode stream, moving to next track Jun 20 12:45:17 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 12:45:17 smsl-n100 volumio[1172]: info: sendMpdCommand status took 10 milliseconds Jun 20 12:45:17 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseState Jun 20 12:45:17 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 12:45:17 smsl-n100 volumio[1172]: info: sendMpdCommand clearerror took 4 milliseconds Jun 20 12:45:17 smsl-n100 volumio[1172]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 20 12:45:17 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jun 20 12:45:17 smsl-n100 volumio[1172]: info: ControllerMpd::pushState Jun 20 12:45:17 smsl-n100 volumio[1172]: info: CoreCommandRouter::servicePushState Jun 20 12:45:17 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:17 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:45:17 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:17 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:17 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:17 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 54 Jun 20 12:45:17 smsl-n100 volumio[1172]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"312623300","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/312623300","trackType":"qobuz"} Jun 20 12:45:17 smsl-n100 volumio[1172]: verbose: CURRENT POSITION 54 Jun 20 12:45:17 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState stateService stop Jun 20 12:45:17 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Jun 20 12:45:17 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:17 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:17 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:17 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:17 smsl-n100 volumio[1172]: info: No code Jun 20 12:45:17 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:17 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:17 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:17 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:17 smsl-n100 volumio[1172]: info: ------------------------------ 75ms Jun 20 12:45:17 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:17 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:17 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:18 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jun 20 12:45:18 smsl-n100 sudo[19796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jun 20 12:45:18 smsl-n100 sudo[19796]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 12:45:18 smsl-n100 sudo[19796]: pam_unix(sudo:session): session closed for user root Jun 20 12:45:18 smsl-n100 sudo[19802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 20 12:45:18 smsl-n100 sudo[19802]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 12:45:18 smsl-n100 sudo[19802]: pam_unix(sudo:session): session closed for user root Jun 20 12:45:18 smsl-n100 sudo[19811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 20 12:45:18 smsl-n100 sudo[19811]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 12:45:18 smsl-n100 sudo[19811]: pam_unix(sudo:session): session closed for user root Jun 20 12:45:18 smsl-n100 sudo[19816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 20 12:45:18 smsl-n100 sudo[19816]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 12:45:18 smsl-n100 sudo[19816]: pam_unix(sudo:session): session closed for user root Jun 20 12:45:18 smsl-n100 sudo[19822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 20 12:45:18 smsl-n100 sudo[19822]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 12:45:18 smsl-n100 sudo[19822]: pam_unix(sudo:session): session closed for user root Jun 20 12:45:18 smsl-n100 sudo[19825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 20 12:45:18 smsl-n100 sudo[19825]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 12:45:18 smsl-n100 sudo[19825]: pam_unix(sudo:session): session closed for user root Jun 20 12:45:18 smsl-n100 volumio[1172]: info: Moving to next track after playback error Jun 20 12:45:18 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPlay Jun 20 12:45:18 smsl-n100 volumio[1172]: info: CoreStateMachine::play index 105 Jun 20 12:45:18 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:18 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:18 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:18 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:18 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:18 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 105 Jun 20 12:45:18 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:18 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 105 Jun 20 12:45:18 smsl-n100 volumio[1172]: info: [1750412718587] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:18 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:18 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 2 milliseconds Jun 20 12:45:18 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:18 smsl-n100 volumio[1172]: info: Jun 20 12:45:18 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:18 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:18 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 7 milliseconds Jun 20 12:45:18 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/71179058" Jun 20 12:45:18 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/71179058 Jun 20 12:45:18 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:18 smsl-n100 volumio[1172]: info: ------------------------------ 15ms Jun 20 12:45:18 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:18 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:18 smsl-n100 volumio[1172]: info: getStreamUrl took 13 milliseconds Jun 20 12:45:18 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:18 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:18 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/71179058" Jun 20 12:45:18 smsl-n100 volumio[1172]: info: Jun 20 12:45:18 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:18 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:18 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/71179058" took 5 milliseconds Jun 20 12:45:18 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:18 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:18 smsl-n100 volumio[1172]: info: ------------------------------ 9ms Jun 20 12:45:18 smsl-n100 volumio[1172]: info: sendMpdCommand play took 13 milliseconds Jun 20 12:45:18 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/71179058 Jun 20 12:45:18 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:18 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:19 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 20 12:45:19 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 20 12:45:19 smsl-n100 volumio[1172]: info: getStreamUrl took 943 milliseconds Jun 20 12:45:19 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:19 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:19 smsl-n100 volumio[1172]: info: Jun 20 12:45:19 smsl-n100 volumio[1172]: ---------------------------- MPD announces state update: player Jun 20 12:45:19 smsl-n100 volumio[1172]: info: ControllerMpd::getState Jun 20 12:45:19 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jun 20 12:45:19 smsl-n100 sudo[19832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 20 12:45:19 smsl-n100 sudo[19832]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 12:45:19 smsl-n100 volumio[1172]: error: MPD Playback Error: Failed to decode stream, moving to next track Jun 20 12:45:19 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 12:45:19 smsl-n100 volumio[1172]: info: sendMpdCommand status took 23 milliseconds Jun 20 12:45:19 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseState Jun 20 12:45:19 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 12:45:19 smsl-n100 volumio[1172]: info: sendMpdCommand clearerror took 8 milliseconds Jun 20 12:45:19 smsl-n100 volumio[1172]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 20 12:45:19 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jun 20 12:45:19 smsl-n100 volumio[1172]: info: ControllerMpd::pushState Jun 20 12:45:19 smsl-n100 volumio[1172]: info: CoreCommandRouter::servicePushState Jun 20 12:45:19 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:19 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:45:19 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:19 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:19 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:19 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 105 Jun 20 12:45:19 smsl-n100 volumio[1172]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"71179058","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/71179058","trackType":"qobuz"} Jun 20 12:45:19 smsl-n100 volumio[1172]: verbose: CURRENT POSITION 105 Jun 20 12:45:19 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState stateService stop Jun 20 12:45:19 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Jun 20 12:45:19 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:19 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:19 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:19 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:19 smsl-n100 volumio[1172]: info: No code Jun 20 12:45:19 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:19 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:19 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:19 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:19 smsl-n100 volumio[1172]: info: ------------------------------ 114ms Jun 20 12:45:19 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:19 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:19 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:20 smsl-n100 volumio[1172]: info: Moving to next track after playback error Jun 20 12:45:20 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPlay Jun 20 12:45:20 smsl-n100 volumio[1172]: info: CoreStateMachine::play index 125 Jun 20 12:45:20 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:20 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:20 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:20 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:20 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:20 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 125 Jun 20 12:45:20 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:20 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 125 Jun 20 12:45:20 smsl-n100 volumio[1172]: info: [1750412720699] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:20 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:20 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 3 milliseconds Jun 20 12:45:20 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:20 smsl-n100 volumio[1172]: info: Jun 20 12:45:20 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:20 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:20 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 3 milliseconds Jun 20 12:45:20 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/33382287" Jun 20 12:45:20 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:20 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:20 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/33382287 Jun 20 12:45:20 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:20 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:20 smsl-n100 volumio[1172]: info: getStreamUrl took 9 milliseconds Jun 20 12:45:20 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:20 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:20 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/33382287" Jun 20 12:45:20 smsl-n100 volumio[1172]: info: Jun 20 12:45:20 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:20 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:20 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/33382287" took 3 milliseconds Jun 20 12:45:20 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:20 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:20 smsl-n100 volumio[1172]: info: ------------------------------ 7ms Jun 20 12:45:20 smsl-n100 volumio[1172]: info: sendMpdCommand play took 5 milliseconds Jun 20 12:45:20 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/33382287 Jun 20 12:45:20 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:20 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:20 smsl-n100 volumio[1172]: info: getStreamUrl took 8 milliseconds Jun 20 12:45:20 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:20 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:20 smsl-n100 volumio[1172]: info: Jun 20 12:45:20 smsl-n100 volumio[1172]: ---------------------------- MPD announces state update: player Jun 20 12:45:20 smsl-n100 volumio[1172]: info: ControllerMpd::getState Jun 20 12:45:20 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jun 20 12:45:20 smsl-n100 volumio[1172]: error: MPD Playback Error: Failed to decode stream, moving to next track Jun 20 12:45:20 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 12:45:20 smsl-n100 volumio[1172]: info: sendMpdCommand status took 9 milliseconds Jun 20 12:45:20 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseState Jun 20 12:45:20 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 12:45:20 smsl-n100 volumio[1172]: info: sendMpdCommand clearerror took 4 milliseconds Jun 20 12:45:20 smsl-n100 volumio[1172]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 20 12:45:20 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jun 20 12:45:20 smsl-n100 volumio[1172]: info: ControllerMpd::pushState Jun 20 12:45:20 smsl-n100 volumio[1172]: info: CoreCommandRouter::servicePushState Jun 20 12:45:20 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:20 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:45:20 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:20 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:20 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:20 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 125 Jun 20 12:45:20 smsl-n100 volumio[1172]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"33382287","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/33382287","trackType":"qobuz"} Jun 20 12:45:20 smsl-n100 volumio[1172]: verbose: CURRENT POSITION 125 Jun 20 12:45:20 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState stateService stop Jun 20 12:45:20 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Jun 20 12:45:20 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:20 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:20 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:20 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:20 smsl-n100 volumio[1172]: info: No code Jun 20 12:45:20 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:20 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:20 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:20 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:20 smsl-n100 volumio[1172]: info: ------------------------------ 73ms Jun 20 12:45:20 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:20 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:20 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:21 smsl-n100 volumio[1172]: info: Moving to next track after playback error Jun 20 12:45:21 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPlay Jun 20 12:45:21 smsl-n100 volumio[1172]: info: CoreStateMachine::play index 188 Jun 20 12:45:21 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:21 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:21 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:21 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:21 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:21 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 188 Jun 20 12:45:21 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:21 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 188 Jun 20 12:45:21 smsl-n100 volumio[1172]: info: [1750412721805] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:21 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:21 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 2 milliseconds Jun 20 12:45:21 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:21 smsl-n100 volumio[1172]: info: Jun 20 12:45:21 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:21 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:21 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 4 milliseconds Jun 20 12:45:21 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/78246248" Jun 20 12:45:21 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:21 smsl-n100 volumio[1172]: info: ------------------------------ 7ms Jun 20 12:45:21 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/78246248 Jun 20 12:45:21 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:21 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:21 smsl-n100 volumio[1172]: info: getStreamUrl took 8 milliseconds Jun 20 12:45:21 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:21 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:21 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/78246248" Jun 20 12:45:21 smsl-n100 volumio[1172]: info: Jun 20 12:45:21 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:21 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:21 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/78246248" took 4 milliseconds Jun 20 12:45:21 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:21 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:21 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:21 smsl-n100 volumio[1172]: info: sendMpdCommand play took 3 milliseconds Jun 20 12:45:21 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/78246248 Jun 20 12:45:21 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:21 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:21 smsl-n100 volumio[1172]: info: getStreamUrl took 8 milliseconds Jun 20 12:45:21 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:21 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:21 smsl-n100 volumio[1172]: info: Jun 20 12:45:21 smsl-n100 volumio[1172]: ---------------------------- MPD announces state update: player Jun 20 12:45:21 smsl-n100 volumio[1172]: info: ControllerMpd::getState Jun 20 12:45:21 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jun 20 12:45:21 smsl-n100 volumio[1172]: error: MPD Playback Error: Failed to decode stream, moving to next track Jun 20 12:45:21 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 12:45:21 smsl-n100 volumio[1172]: info: sendMpdCommand status took 14 milliseconds Jun 20 12:45:21 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseState Jun 20 12:45:21 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 12:45:21 smsl-n100 volumio[1172]: info: sendMpdCommand clearerror took 13 milliseconds Jun 20 12:45:21 smsl-n100 volumio[1172]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 20 12:45:21 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jun 20 12:45:21 smsl-n100 volumio[1172]: info: ControllerMpd::pushState Jun 20 12:45:21 smsl-n100 volumio[1172]: info: CoreCommandRouter::servicePushState Jun 20 12:45:21 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:21 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:45:21 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:21 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:21 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:21 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 188 Jun 20 12:45:21 smsl-n100 volumio[1172]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"78246248","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/78246248","trackType":"qobuz"} Jun 20 12:45:21 smsl-n100 volumio[1172]: verbose: CURRENT POSITION 188 Jun 20 12:45:21 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState stateService stop Jun 20 12:45:21 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Jun 20 12:45:21 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:21 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:21 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:21 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:21 smsl-n100 volumio[1172]: info: No code Jun 20 12:45:21 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:21 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:21 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:21 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:21 smsl-n100 volumio[1172]: info: ------------------------------ 94ms Jun 20 12:45:22 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:22 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:22 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:22 smsl-n100 volumio[1172]: info: Moving to next track after playback error Jun 20 12:45:22 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPlay Jun 20 12:45:22 smsl-n100 volumio[1172]: info: CoreStateMachine::play index 168 Jun 20 12:45:22 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:22 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:22 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:22 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:22 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:22 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 168 Jun 20 12:45:22 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:22 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 168 Jun 20 12:45:22 smsl-n100 volumio[1172]: info: [1750412722926] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:22 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:22 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 2 milliseconds Jun 20 12:45:22 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:22 smsl-n100 volumio[1172]: info: Jun 20 12:45:22 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:22 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:22 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 3 milliseconds Jun 20 12:45:22 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/4604185" Jun 20 12:45:22 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:22 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:22 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/4604185 Jun 20 12:45:22 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:22 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:22 smsl-n100 volumio[1172]: info: getStreamUrl took 8 milliseconds Jun 20 12:45:22 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:22 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:22 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/4604185" Jun 20 12:45:22 smsl-n100 volumio[1172]: info: Jun 20 12:45:22 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:22 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:22 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/4604185" took 3 milliseconds Jun 20 12:45:22 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:22 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:22 smsl-n100 volumio[1172]: info: ------------------------------ 5ms Jun 20 12:45:22 smsl-n100 volumio[1172]: info: sendMpdCommand play took 3 milliseconds Jun 20 12:45:22 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/4604185 Jun 20 12:45:22 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:22 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:23 smsl-n100 volumio[1172]: info: getStreamUrl took 7 milliseconds Jun 20 12:45:23 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:23 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:23 smsl-n100 volumio[1172]: info: Jun 20 12:45:23 smsl-n100 volumio[1172]: ---------------------------- MPD announces state update: player Jun 20 12:45:23 smsl-n100 volumio[1172]: info: ControllerMpd::getState Jun 20 12:45:23 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jun 20 12:45:23 smsl-n100 volumio[1172]: error: MPD Playback Error: Failed to decode stream, moving to next track Jun 20 12:45:23 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 12:45:23 smsl-n100 volumio[1172]: info: sendMpdCommand status took 9 milliseconds Jun 20 12:45:23 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseState Jun 20 12:45:23 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 12:45:23 smsl-n100 volumio[1172]: info: sendMpdCommand clearerror took 7 milliseconds Jun 20 12:45:23 smsl-n100 volumio[1172]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 20 12:45:23 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jun 20 12:45:23 smsl-n100 volumio[1172]: info: ControllerMpd::pushState Jun 20 12:45:23 smsl-n100 volumio[1172]: info: CoreCommandRouter::servicePushState Jun 20 12:45:23 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:23 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:45:23 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:23 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:23 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:23 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 168 Jun 20 12:45:23 smsl-n100 volumio[1172]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4604185","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4604185","trackType":"qobuz"} Jun 20 12:45:23 smsl-n100 volumio[1172]: verbose: CURRENT POSITION 168 Jun 20 12:45:23 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState stateService stop Jun 20 12:45:23 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Jun 20 12:45:23 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:23 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:23 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:23 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:23 smsl-n100 volumio[1172]: info: No code Jun 20 12:45:23 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:23 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:23 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:23 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:23 smsl-n100 volumio[1172]: info: ------------------------------ 76ms Jun 20 12:45:23 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:23 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:23 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:23 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 12:45:23 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 12:45:23 smsl-n100 volumio[1172]: info: Discovery: Getting this device information Jun 20 12:45:23 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetState Jun 20 12:45:23 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 12:45:23 smsl-n100 volumio[1172]: info: CALLMETHOD: system_controller network saveWiredNet [object Object] Jun 20 12:45:23 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , saveWiredNet Jun 20 12:45:23 smsl-n100 sudo[19847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Jun 20 12:45:23 smsl-n100 sudo[19847]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 12:45:23 smsl-n100 sudo[19847]: pam_unix(sudo:session): session closed for user root Jun 20 12:45:23 smsl-n100 sudo[19849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Jun 20 12:45:23 smsl-n100 sudo[19849]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 12:45:23 smsl-n100 sudo[19849]: pam_unix(sudo:session): session closed for user root Jun 20 12:45:23 smsl-n100 volumio[1172]: info: Permissions for /etc/network/interfaces set Jun 20 12:45:23 smsl-n100 volumio[1172]: info: Restarting Wired Network Jun 20 12:45:23 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jun 20 12:45:23 smsl-n100 volumio[1172]: info: Discovery: Restarting Advertising due to device name change Jun 20 12:45:23 smsl-n100 sudo[19852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ip addr flush dev eth0 Jun 20 12:45:23 smsl-n100 sudo[19852]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 12:45:23 smsl-n100 avahi-daemon[522]: Withdrawing address record for 192.168.40.219 on eth0. Jun 20 12:45:23 smsl-n100 avahi-daemon[522]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.40.219. Jun 20 12:45:23 smsl-n100 avahi-daemon[522]: Interface eth0.IPv4 no longer relevant for mDNS. Jun 20 12:45:23 smsl-n100 dhcpcd[936]: eth0: pid 19853 deleted IP address 192.168.40.219/24 Jun 20 12:45:23 smsl-n100 dhcpcd[936]: eth0: deleting route to 192.168.40.0/24 Jun 20 12:45:23 smsl-n100 dhcpcd[936]: eth0: deleting default route via 192.168.40.1 Jun 20 12:45:23 smsl-n100 sudo[19852]: pam_unix(sudo:session): session closed for user root Jun 20 12:45:23 smsl-n100 dhcpcd[648]: eth0: pid 19853 deleted IP address 192.168.40.219/24 Jun 20 12:45:23 smsl-n100 volumio[1172]: info: Discovery: A device disappeared from network Jun 20 12:45:23 smsl-n100 dhcpcd[648]: eth0: deleting route to 192.168.40.0/24 Jun 20 12:45:23 smsl-n100 dhcpcd[648]: eth0: deleting default route via 192.168.40.1 Jun 20 12:45:23 smsl-n100 sudo[19855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 down Jun 20 12:45:23 smsl-n100 sudo[19855]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 12:45:23 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Down Jun 20 12:45:23 smsl-n100 dhcpcd[936]: eth0: rebinding lease of 192.168.40.219 Jun 20 12:45:23 smsl-n100 dhcpcd[936]: eth0: if_sendraw: Network is down Jun 20 12:45:23 smsl-n100 dhcpcd[936]: dhcp_readpacket: eth0: Network is down Jun 20 12:45:23 smsl-n100 sudo[19855]: pam_unix(sudo:session): session closed for user root Jun 20 12:45:23 smsl-n100 dhcpcd[648]: eth0: rebinding lease of 192.168.40.219 Jun 20 12:45:23 smsl-n100 dhcpcd[648]: eth0: if_sendraw: Network is down Jun 20 12:45:23 smsl-n100 dhcpcd[648]: dhcp_readpacket: eth0: Network is down Jun 20 12:45:23 smsl-n100 dhcpcd[936]: eth0: carrier lost Jun 20 12:45:23 smsl-n100 dhcpcd[648]: eth0: carrier lost Jun 20 12:45:23 smsl-n100 sudo[19877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 up Jun 20 12:45:23 smsl-n100 sudo[19877]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 20 12:45:23 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: PHY [stmmac-0:01] driver [Generic PHY] Jun 20 12:45:23 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: No Safety Features support found Jun 20 12:45:23 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: PTP not supported by HW Jun 20 12:45:23 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: configuring for phy/rgmii link mode Jun 20 12:45:23 smsl-n100 sudo[19877]: pam_unix(sudo:session): session closed for user root Jun 20 12:45:23 smsl-n100 ifplugd(eth0)[715]: Link beat lost. Jun 20 12:45:24 smsl-n100 volumio[1172]: info: Moving to next track after playback error Jun 20 12:45:24 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPlay Jun 20 12:45:24 smsl-n100 volumio[1172]: info: CoreStateMachine::play index 69 Jun 20 12:45:24 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:24 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:24 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:24 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:24 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:24 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 69 Jun 20 12:45:24 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:24 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 69 Jun 20 12:45:24 smsl-n100 volumio[1172]: info: [1750412724028] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:24 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:24 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 3 milliseconds Jun 20 12:45:24 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:24 smsl-n100 volumio[1172]: info: Jun 20 12:45:24 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:24 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:24 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 3 milliseconds Jun 20 12:45:24 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/303109004" Jun 20 12:45:24 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:24 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:24 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/303109004 Jun 20 12:45:24 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:24 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:24 smsl-n100 volumio[1172]: info: getStreamUrl took 10 milliseconds Jun 20 12:45:24 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:24 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:24 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/303109004" Jun 20 12:45:24 smsl-n100 volumio[1172]: info: Jun 20 12:45:24 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:24 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:24 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/303109004" took 4 milliseconds Jun 20 12:45:24 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:24 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:24 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:24 smsl-n100 volumio[1172]: info: sendMpdCommand play took 3 milliseconds Jun 20 12:45:24 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/303109004 Jun 20 12:45:24 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:24 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:24 smsl-n100 volumio[1172]: info: getStreamUrl took 8 milliseconds Jun 20 12:45:24 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:24 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:24 smsl-n100 volumio[1172]: info: Jun 20 12:45:24 smsl-n100 volumio[1172]: ---------------------------- MPD announces state update: player Jun 20 12:45:24 smsl-n100 volumio[1172]: info: ControllerMpd::getState Jun 20 12:45:24 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jun 20 12:45:24 smsl-n100 volumio[1172]: error: MPD Playback Error: Failed to decode stream, moving to next track Jun 20 12:45:24 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 12:45:24 smsl-n100 volumio[1172]: info: sendMpdCommand status took 10 milliseconds Jun 20 12:45:24 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseState Jun 20 12:45:24 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 12:45:24 smsl-n100 volumio[1172]: info: sendMpdCommand clearerror took 5 milliseconds Jun 20 12:45:24 smsl-n100 volumio[1172]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 20 12:45:24 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jun 20 12:45:24 smsl-n100 volumio[1172]: info: ControllerMpd::pushState Jun 20 12:45:24 smsl-n100 volumio[1172]: info: CoreCommandRouter::servicePushState Jun 20 12:45:24 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:24 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:45:24 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:24 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:24 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:24 smsl-n100 sudo[19832]: pam_unix(sudo:session): session closed for user root Jun 20 12:45:24 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 69 Jun 20 12:45:24 smsl-n100 volumio[1172]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"303109004","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/303109004","trackType":"qobuz"} Jun 20 12:45:24 smsl-n100 volumio[1172]: verbose: CURRENT POSITION 69 Jun 20 12:45:24 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState stateService stop Jun 20 12:45:24 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Jun 20 12:45:24 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:24 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:24 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:24 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:24 smsl-n100 volumio[1172]: info: No code Jun 20 12:45:24 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:24 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:24 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:24 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:24 smsl-n100 volumio[1172]: info: ------------------------------ 78ms Jun 20 12:45:24 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:24 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:24 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:25 smsl-n100 volumio[1172]: info: Moving to next track after playback error Jun 20 12:45:25 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPlay Jun 20 12:45:25 smsl-n100 volumio[1172]: info: CoreStateMachine::play index 198 Jun 20 12:45:25 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:25 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:25 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:25 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:25 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:25 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 198 Jun 20 12:45:25 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:25 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 198 Jun 20 12:45:25 smsl-n100 volumio[1172]: info: [1750412725141] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:25 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:25 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 1 milliseconds Jun 20 12:45:25 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:25 smsl-n100 volumio[1172]: info: Jun 20 12:45:25 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:25 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:25 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 3 milliseconds Jun 20 12:45:25 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/117799025" Jun 20 12:45:25 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:25 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:25 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/117799025 Jun 20 12:45:25 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:25 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:25 smsl-n100 volumio[1172]: info: getStreamUrl took 8 milliseconds Jun 20 12:45:25 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:25 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:25 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/117799025" Jun 20 12:45:25 smsl-n100 volumio[1172]: info: Jun 20 12:45:25 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:25 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:25 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/117799025" took 3 milliseconds Jun 20 12:45:25 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:25 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:25 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:25 smsl-n100 volumio[1172]: info: sendMpdCommand play took 4 milliseconds Jun 20 12:45:25 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/117799025 Jun 20 12:45:25 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:25 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:25 smsl-n100 volumio[1172]: info: getStreamUrl took 8 milliseconds Jun 20 12:45:25 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:25 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:25 smsl-n100 volumio[1172]: info: Jun 20 12:45:25 smsl-n100 volumio[1172]: ---------------------------- MPD announces state update: player Jun 20 12:45:25 smsl-n100 volumio[1172]: info: ControllerMpd::getState Jun 20 12:45:25 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jun 20 12:45:25 smsl-n100 volumio[1172]: error: MPD Playback Error: Failed to decode stream, moving to next track Jun 20 12:45:25 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 12:45:25 smsl-n100 volumio[1172]: info: sendMpdCommand status took 10 milliseconds Jun 20 12:45:25 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseState Jun 20 12:45:25 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 12:45:25 smsl-n100 volumio[1172]: info: sendMpdCommand clearerror took 10 milliseconds Jun 20 12:45:25 smsl-n100 volumio[1172]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 20 12:45:25 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jun 20 12:45:25 smsl-n100 volumio[1172]: info: ControllerMpd::pushState Jun 20 12:45:25 smsl-n100 volumio[1172]: info: CoreCommandRouter::servicePushState Jun 20 12:45:25 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:25 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:45:25 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:25 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:25 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:25 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 198 Jun 20 12:45:25 smsl-n100 volumio[1172]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"117799025","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/117799025","trackType":"qobuz"} Jun 20 12:45:25 smsl-n100 volumio[1172]: verbose: CURRENT POSITION 198 Jun 20 12:45:25 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState stateService stop Jun 20 12:45:25 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Jun 20 12:45:25 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:25 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:25 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:25 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:25 smsl-n100 volumio[1172]: info: No code Jun 20 12:45:25 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:25 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:25 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:25 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:25 smsl-n100 volumio[1172]: info: ------------------------------ 73ms Jun 20 12:45:25 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:25 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:25 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:25 smsl-n100 ntpd[19716]: Deleting interface #3 eth0, 192.168.40.219#123, interface stats: received=0, sent=0, dropped=0, active_time=29 secs Jun 20 12:45:26 smsl-n100 volumio[1172]: info: Moving to next track after playback error Jun 20 12:45:26 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPlay Jun 20 12:45:26 smsl-n100 volumio[1172]: info: CoreStateMachine::play index 153 Jun 20 12:45:26 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:26 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:26 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:26 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:26 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:26 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 153 Jun 20 12:45:26 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:26 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 153 Jun 20 12:45:26 smsl-n100 volumio[1172]: info: [1750412726240] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:26 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:26 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 4 milliseconds Jun 20 12:45:26 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:26 smsl-n100 volumio[1172]: info: Jun 20 12:45:26 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:26 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:26 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 7 milliseconds Jun 20 12:45:26 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/53342656" Jun 20 12:45:26 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:26 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:26 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/53342656 Jun 20 12:45:26 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:26 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:26 smsl-n100 volumio[1172]: info: getStreamUrl took 8 milliseconds Jun 20 12:45:26 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:26 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:26 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/53342656" Jun 20 12:45:26 smsl-n100 volumio[1172]: info: Jun 20 12:45:26 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:26 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:26 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/53342656" took 3 milliseconds Jun 20 12:45:26 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:26 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:26 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:26 smsl-n100 volumio[1172]: info: sendMpdCommand play took 3 milliseconds Jun 20 12:45:26 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/53342656 Jun 20 12:45:26 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:26 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:26 smsl-n100 volumio[1172]: info: getStreamUrl took 7 milliseconds Jun 20 12:45:26 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:26 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:26 smsl-n100 volumio[1172]: info: Jun 20 12:45:26 smsl-n100 volumio[1172]: ---------------------------- MPD announces state update: player Jun 20 12:45:26 smsl-n100 volumio[1172]: info: ControllerMpd::getState Jun 20 12:45:26 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jun 20 12:45:26 smsl-n100 volumio[1172]: error: MPD Playback Error: Failed to decode stream, moving to next track Jun 20 12:45:26 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 12:45:26 smsl-n100 volumio[1172]: info: sendMpdCommand status took 12 milliseconds Jun 20 12:45:26 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseState Jun 20 12:45:26 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 12:45:26 smsl-n100 volumio[1172]: info: sendMpdCommand clearerror took 6 milliseconds Jun 20 12:45:26 smsl-n100 volumio[1172]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 20 12:45:26 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jun 20 12:45:26 smsl-n100 volumio[1172]: info: ControllerMpd::pushState Jun 20 12:45:26 smsl-n100 volumio[1172]: info: CoreCommandRouter::servicePushState Jun 20 12:45:26 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:26 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:45:26 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:26 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:26 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:26 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 153 Jun 20 12:45:26 smsl-n100 volumio[1172]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"53342656","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/53342656","trackType":"qobuz"} Jun 20 12:45:26 smsl-n100 volumio[1172]: verbose: CURRENT POSITION 153 Jun 20 12:45:26 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState stateService stop Jun 20 12:45:26 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Jun 20 12:45:26 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:26 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:26 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:26 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:26 smsl-n100 volumio[1172]: info: No code Jun 20 12:45:26 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:26 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:26 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:26 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:26 smsl-n100 volumio[1172]: info: ------------------------------ 72ms Jun 20 12:45:26 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:26 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:26 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:26 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 100Mbps/Full - flow control off Jun 20 12:45:26 smsl-n100 dhcpcd[648]: eth0: carrier acquired Jun 20 12:45:26 smsl-n100 dhcpcd[936]: eth0: carrier acquired Jun 20 12:45:26 smsl-n100 dhcpcd[648]: eth0: confile file changed, expiring leases Jun 20 12:45:26 smsl-n100 dhcpcd[936]: eth0: confile file changed, expiring leases Jun 20 12:45:26 smsl-n100 dhcpcd[648]: eth0: IAID d8:a0:49:60 Jun 20 12:45:26 smsl-n100 dhcpcd[936]: eth0: IAID d8:a0:49:60 Jun 20 12:45:26 smsl-n100 ifplugd(eth0)[715]: Link beat detected. Jun 20 12:45:26 smsl-n100 dhcpcd[648]: eth0: rebinding lease of 192.168.40.219 Jun 20 12:45:26 smsl-n100 dhcpcd[648]: eth0: probing address 192.168.40.219/24 Jun 20 12:45:27 smsl-n100 dhcpcd[936]: eth0: rebinding lease of 192.168.40.219 Jun 20 12:45:27 smsl-n100 dhcpcd[936]: eth0: probing address 192.168.40.219/24 Jun 20 12:45:27 smsl-n100 volumio[1172]: info: Moving to next track after playback error Jun 20 12:45:27 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPlay Jun 20 12:45:27 smsl-n100 volumio[1172]: info: CoreStateMachine::play index 102 Jun 20 12:45:27 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:27 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:27 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:27 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:27 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:27 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 102 Jun 20 12:45:27 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:27 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 102 Jun 20 12:45:27 smsl-n100 volumio[1172]: info: [1750412727349] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:27 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:27 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 2 milliseconds Jun 20 12:45:27 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:27 smsl-n100 volumio[1172]: info: Jun 20 12:45:27 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:27 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:27 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 5 milliseconds Jun 20 12:45:27 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/71179055" Jun 20 12:45:27 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:27 smsl-n100 volumio[1172]: info: ------------------------------ 5ms Jun 20 12:45:27 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/71179055 Jun 20 12:45:27 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:27 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:27 smsl-n100 volumio[1172]: info: getStreamUrl took 7 milliseconds Jun 20 12:45:27 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:27 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:27 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/71179055" Jun 20 12:45:27 smsl-n100 volumio[1172]: info: Jun 20 12:45:27 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:27 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:27 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/71179055" took 3 milliseconds Jun 20 12:45:27 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:27 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:27 smsl-n100 volumio[1172]: info: ------------------------------ 5ms Jun 20 12:45:27 smsl-n100 volumio[1172]: info: sendMpdCommand play took 3 milliseconds Jun 20 12:45:27 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/71179055 Jun 20 12:45:27 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:27 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:27 smsl-n100 volumio[1172]: info: getStreamUrl took 15 milliseconds Jun 20 12:45:27 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:27 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:27 smsl-n100 volumio[1172]: info: Jun 20 12:45:27 smsl-n100 volumio[1172]: ---------------------------- MPD announces state update: player Jun 20 12:45:27 smsl-n100 volumio[1172]: info: ControllerMpd::getState Jun 20 12:45:27 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jun 20 12:45:27 smsl-n100 volumio[1172]: error: MPD Playback Error: Failed to decode stream, moving to next track Jun 20 12:45:27 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 12:45:27 smsl-n100 volumio[1172]: info: sendMpdCommand status took 8 milliseconds Jun 20 12:45:27 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseState Jun 20 12:45:27 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 12:45:27 smsl-n100 volumio[1172]: info: sendMpdCommand clearerror took 4 milliseconds Jun 20 12:45:27 smsl-n100 volumio[1172]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 20 12:45:27 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jun 20 12:45:27 smsl-n100 volumio[1172]: info: ControllerMpd::pushState Jun 20 12:45:27 smsl-n100 volumio[1172]: info: CoreCommandRouter::servicePushState Jun 20 12:45:27 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:27 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:45:27 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:27 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:27 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:27 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 102 Jun 20 12:45:27 smsl-n100 volumio[1172]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"71179055","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/71179055","trackType":"qobuz"} Jun 20 12:45:27 smsl-n100 volumio[1172]: verbose: CURRENT POSITION 102 Jun 20 12:45:27 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState stateService stop Jun 20 12:45:27 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Jun 20 12:45:27 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:27 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:27 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:27 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:27 smsl-n100 volumio[1172]: info: No code Jun 20 12:45:27 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:27 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:27 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:27 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:27 smsl-n100 volumio[1172]: info: ------------------------------ 64ms Jun 20 12:45:27 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:27 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:27 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:27 smsl-n100 dhcpcd[648]: eth0: soliciting an IPv6 router Jun 20 12:45:27 smsl-n100 dhcpcd[936]: eth0: soliciting an IPv6 router Jun 20 12:45:28 smsl-n100 volumio[1172]: info: Discovery: Started advertising with name: SMSL N100 Jun 20 12:45:28 smsl-n100 volumio[1172]: info: Moving to next track after playback error Jun 20 12:45:28 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPlay Jun 20 12:45:28 smsl-n100 volumio[1172]: info: CoreStateMachine::play index 128 Jun 20 12:45:28 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:28 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:28 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:28 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:28 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:28 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 128 Jun 20 12:45:28 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:28 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 128 Jun 20 12:45:28 smsl-n100 volumio[1172]: info: [1750412728460] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:28 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:28 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 2 milliseconds Jun 20 12:45:28 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:28 smsl-n100 volumio[1172]: info: Jun 20 12:45:28 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:28 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:28 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 3 milliseconds Jun 20 12:45:28 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/4686668" Jun 20 12:45:28 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:28 smsl-n100 volumio[1172]: info: ------------------------------ 5ms Jun 20 12:45:28 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/4686668 Jun 20 12:45:28 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:28 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:28 smsl-n100 volumio[1172]: info: getStreamUrl took 7 milliseconds Jun 20 12:45:28 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:28 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:28 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/4686668" Jun 20 12:45:28 smsl-n100 volumio[1172]: info: Jun 20 12:45:28 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:28 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:28 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/4686668" took 3 milliseconds Jun 20 12:45:28 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:28 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:28 smsl-n100 volumio[1172]: info: ------------------------------ 5ms Jun 20 12:45:28 smsl-n100 volumio[1172]: info: sendMpdCommand play took 3 milliseconds Jun 20 12:45:28 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/4686668 Jun 20 12:45:28 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:28 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:28 smsl-n100 volumio[1172]: info: getStreamUrl took 7 milliseconds Jun 20 12:45:28 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:28 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:28 smsl-n100 volumio[1172]: info: Jun 20 12:45:28 smsl-n100 volumio[1172]: ---------------------------- MPD announces state update: player Jun 20 12:45:28 smsl-n100 volumio[1172]: info: ControllerMpd::getState Jun 20 12:45:28 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jun 20 12:45:28 smsl-n100 volumio[1172]: error: MPD Playback Error: Failed to decode stream, moving to next track Jun 20 12:45:28 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 12:45:28 smsl-n100 volumio[1172]: info: sendMpdCommand status took 10 milliseconds Jun 20 12:45:28 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseState Jun 20 12:45:28 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 12:45:28 smsl-n100 volumio[1172]: info: sendMpdCommand clearerror took 5 milliseconds Jun 20 12:45:28 smsl-n100 volumio[1172]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 20 12:45:28 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jun 20 12:45:28 smsl-n100 volumio[1172]: info: ControllerMpd::pushState Jun 20 12:45:28 smsl-n100 volumio[1172]: info: CoreCommandRouter::servicePushState Jun 20 12:45:28 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:28 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:45:28 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:28 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:28 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:28 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 128 Jun 20 12:45:28 smsl-n100 volumio[1172]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4686668","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4686668","trackType":"qobuz"} Jun 20 12:45:28 smsl-n100 volumio[1172]: verbose: CURRENT POSITION 128 Jun 20 12:45:28 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState stateService stop Jun 20 12:45:28 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Jun 20 12:45:28 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:28 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:28 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:28 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:28 smsl-n100 volumio[1172]: info: No code Jun 20 12:45:28 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:28 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:28 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:28 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:28 smsl-n100 volumio[1172]: info: ------------------------------ 67ms Jun 20 12:45:28 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:28 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:28 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:29 smsl-n100 volumio[1172]: info: Moving to next track after playback error Jun 20 12:45:29 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPlay Jun 20 12:45:29 smsl-n100 volumio[1172]: info: CoreStateMachine::play index 116 Jun 20 12:45:29 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:29 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:29 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:29 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:29 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:29 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 116 Jun 20 12:45:29 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:29 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 116 Jun 20 12:45:29 smsl-n100 volumio[1172]: info: [1750412729558] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:29 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:29 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 2 milliseconds Jun 20 12:45:29 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:29 smsl-n100 volumio[1172]: info: Jun 20 12:45:29 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:29 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:29 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 3 milliseconds Jun 20 12:45:29 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/71179069" Jun 20 12:45:29 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:29 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:29 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/71179069 Jun 20 12:45:29 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:29 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:30 smsl-n100 volumio[1172]: info: getStreamUrl took 524 milliseconds Jun 20 12:45:30 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:30 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:30 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/71179069" Jun 20 12:45:30 smsl-n100 volumio[1172]: info: Jun 20 12:45:30 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:30 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:30 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/71179069" took 6 milliseconds Jun 20 12:45:30 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:30 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:30 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/71179069 Jun 20 12:45:30 smsl-n100 volumio[1172]: info: ------------------------------ 14ms Jun 20 12:45:30 smsl-n100 volumio[1172]: info: sendMpdCommand play took 8 milliseconds Jun 20 12:45:30 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:30 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:30 smsl-n100 volumio[1172]: info: getStreamUrl took 10 milliseconds Jun 20 12:45:30 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:30 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:30 smsl-n100 volumio[1172]: info: Reporting MCU Network Status: 0 Jun 20 12:45:30 smsl-n100 volumio[1172]: info: Volumio Network Manager: Network status updated: 0 Jun 20 12:45:30 smsl-n100 volumio[1172]: info: Jun 20 12:45:30 smsl-n100 volumio[1172]: ---------------------------- MPD announces state update: player Jun 20 12:45:30 smsl-n100 volumio[1172]: info: ControllerMpd::getState Jun 20 12:45:30 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jun 20 12:45:30 smsl-n100 volumio[1172]: error: MPD Playback Error: Failed to decode stream, moving to next track Jun 20 12:45:30 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 12:45:30 smsl-n100 volumio[1172]: info: sendMpdCommand status took 9 milliseconds Jun 20 12:45:30 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseState Jun 20 12:45:30 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 12:45:30 smsl-n100 volumio[1172]: info: sendMpdCommand clearerror took 5 milliseconds Jun 20 12:45:30 smsl-n100 volumio[1172]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 20 12:45:30 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jun 20 12:45:30 smsl-n100 volumio[1172]: info: ControllerMpd::pushState Jun 20 12:45:30 smsl-n100 volumio[1172]: info: CoreCommandRouter::servicePushState Jun 20 12:45:30 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:30 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:45:30 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:30 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:30 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:30 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 116 Jun 20 12:45:30 smsl-n100 volumio[1172]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"71179069","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/71179069","trackType":"qobuz"} Jun 20 12:45:30 smsl-n100 volumio[1172]: verbose: CURRENT POSITION 116 Jun 20 12:45:30 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState stateService stop Jun 20 12:45:30 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Jun 20 12:45:30 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:30 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:30 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:30 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:30 smsl-n100 volumio[1172]: info: No code Jun 20 12:45:30 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:30 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:30 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:30 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:30 smsl-n100 volumio[1172]: info: ------------------------------ 67ms Jun 20 12:45:30 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:30 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:30 smsl-n100 volumio[1172]: info: Updating RAAT Signal Path Jun 20 12:45:30 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:30 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:45:30 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:30 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:30 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:30 smsl-n100 volumio[1172]: error: Serial API: Failed to decode command: 100, message: undefined Jun 20 12:45:30 smsl-n100 volumio[1172]: error: Serial API: Failed to decode command: MAXVOL, message: Jun 20 12:45:30 smsl-n100 volumio[1172]: error: Serial API: Failed to decode command: 100, message: undefined Jun 20 12:45:30 smsl-n100 volumio[1172]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jun 20 12:45:30 smsl-n100 volumio[1172]: error: Serial API: Failed to decode command: 0, message: undefined Jun 20 12:45:31 smsl-n100 volumio[1172]: info: Moving to next track after playback error Jun 20 12:45:31 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPlay Jun 20 12:45:31 smsl-n100 volumio[1172]: info: CoreStateMachine::play index 185 Jun 20 12:45:31 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:31 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:31 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:31 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:31 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:31 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 185 Jun 20 12:45:31 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:31 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 185 Jun 20 12:45:31 smsl-n100 volumio[1172]: info: [1750412731206] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:31 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:31 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 3 milliseconds Jun 20 12:45:31 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:31 smsl-n100 volumio[1172]: info: Jun 20 12:45:31 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:31 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:31 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 6 milliseconds Jun 20 12:45:31 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/72038974" Jun 20 12:45:31 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:31 smsl-n100 volumio[1172]: info: ------------------------------ 10ms Jun 20 12:45:31 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/72038974 Jun 20 12:45:31 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:31 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:31 smsl-n100 volumio[1172]: info: getStreamUrl took 8 milliseconds Jun 20 12:45:31 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:31 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:31 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/72038974" Jun 20 12:45:31 smsl-n100 volumio[1172]: info: Jun 20 12:45:31 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:31 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:31 smsl-n100 volumio[1172]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/72038974" took 3 milliseconds Jun 20 12:45:31 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 20 12:45:31 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Jun 20 12:45:31 smsl-n100 volumio[1172]: info: ------------------------------ 6ms Jun 20 12:45:31 smsl-n100 volumio[1172]: info: sendMpdCommand play took 4 milliseconds Jun 20 12:45:31 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/72038974 Jun 20 12:45:31 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:31 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:31 smsl-n100 volumio[1172]: info: getStreamUrl took 12 milliseconds Jun 20 12:45:31 smsl-n100 volumio[1172]: error: Could not get QOBUZ Stream URL: undefined Jun 20 12:45:31 smsl-n100 volumio[1172]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} Jun 20 12:45:31 smsl-n100 volumio[1172]: info: Jun 20 12:45:31 smsl-n100 volumio[1172]: ---------------------------- MPD announces state update: player Jun 20 12:45:31 smsl-n100 volumio[1172]: info: ControllerMpd::getState Jun 20 12:45:31 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Jun 20 12:45:31 smsl-n100 volumio[1172]: error: MPD Playback Error: Failed to decode stream, moving to next track Jun 20 12:45:31 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 20 12:45:31 smsl-n100 volumio[1172]: info: sendMpdCommand status took 9 milliseconds Jun 20 12:45:31 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseState Jun 20 12:45:31 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 20 12:45:31 smsl-n100 volumio[1172]: info: sendMpdCommand clearerror took 9 milliseconds Jun 20 12:45:31 smsl-n100 volumio[1172]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 20 12:45:31 smsl-n100 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Jun 20 12:45:31 smsl-n100 volumio[1172]: info: ControllerMpd::pushState Jun 20 12:45:31 smsl-n100 volumio[1172]: info: CoreCommandRouter::servicePushState Jun 20 12:45:31 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:31 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 12:45:31 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:31 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:31 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:31 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 185 Jun 20 12:45:31 smsl-n100 volumio[1172]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"72038974","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/72038974","trackType":"qobuz"} Jun 20 12:45:31 smsl-n100 volumio[1172]: verbose: CURRENT POSITION 185 Jun 20 12:45:31 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState stateService stop Jun 20 12:45:31 smsl-n100 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Jun 20 12:45:31 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:31 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:31 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:31 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:31 smsl-n100 volumio[1172]: info: No code Jun 20 12:45:31 smsl-n100 volumio[1172]: info: CoreStateMachine::pushState Jun 20 12:45:31 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPushState Jun 20 12:45:31 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Jun 20 12:45:31 smsl-n100 volumio[1172]: info: MRS: Pushing multiroomSync output Jun 20 12:45:31 smsl-n100 volumio[1172]: info: ------------------------------ 71ms Jun 20 12:45:32 smsl-n100 dhcpcd[648]: eth0: leased 192.168.40.219 for 600 seconds Jun 20 12:45:32 smsl-n100 avahi-daemon[522]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.40.219. Jun 20 12:45:32 smsl-n100 avahi-daemon[522]: New relevant interface eth0.IPv4 for mDNS. Jun 20 12:45:32 smsl-n100 dhcpcd[648]: eth0: adding route to 192.168.40.0/24 Jun 20 12:45:32 smsl-n100 avahi-daemon[522]: Registering new address record for 192.168.40.219 on eth0.IPv4. Jun 20 12:45:32 smsl-n100 dhcpcd[648]: eth0: adding default route via 192.168.40.1 Jun 20 12:45:32 smsl-n100 ntpd[19716]: ntpd exiting on signal 15 (Terminated) Jun 20 12:45:32 smsl-n100 systemd[1]: Stopping Network Time Service... Jun 20 12:45:32 smsl-n100 volumio[1172]: info: Moving to next track after playback error Jun 20 12:45:32 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioPlay Jun 20 12:45:32 smsl-n100 volumio[1172]: info: CoreStateMachine::play index 134 Jun 20 12:45:32 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:32 smsl-n100 volumio[1172]: info: CoreStateMachine::stop Jun 20 12:45:32 smsl-n100 systemd[1]: ntp.service: Succeeded. Jun 20 12:45:32 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:32 smsl-n100 volumio[1172]: info: CoreStateMachine::play index undefined Jun 20 12:45:32 smsl-n100 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 12:45:32 smsl-n100 systemd[1]: Stopped Network Time Service. Jun 20 12:45:32 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 134 Jun 20 12:45:32 smsl-n100 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Jun 20 12:45:32 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 134 Jun 20 12:45:32 smsl-n100 volumio[1172]: info: [1750412732321] ControllerQobuz::clearAddPlayTrack Jun 20 12:45:32 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Jun 20 12:45:32 smsl-n100 systemd[1]: Starting Network Time Service... Jun 20 12:45:32 smsl-n100 volumio[1172]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 20 12:45:32 smsl-n100 volumio[1172]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 20 12:45:32 smsl-n100 volumio[1172]: info: sendMpdCommand stop took 54 milliseconds Jun 20 12:45:32 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Jun 20 12:45:32 smsl-n100 volumio[1172]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 20 12:45:32 smsl-n100 volumio[1172]: info: Jun 20 12:45:32 smsl-n100 volumio[1172]: ---------------------------- MPD announces system playlist update Jun 20 12:45:32 smsl-n100 ntpd[19970]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 20 12:45:32 smsl-n100 ntpd[19970]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jun 20 12:45:32 smsl-n100 systemd[1]: Started Network Time Service. Jun 20 12:45:32 smsl-n100 volumio[1172]: info: Ignoring MPD Status Update Jun 20 12:45:32 smsl-n100 volumio[1172]: info: sendMpdCommand clear took 16 milliseconds Jun 20 12:45:32 smsl-n100 volumio[1172]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/44538047" Jun 20 12:45:32 smsl-n100 ntpd[19977]: proto: precision = 1.166 usec (-20) Jun 20 12:45:32 smsl-n100 ntpd[19977]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 20 12:45:32 smsl-n100 volumio[1172]: STREAMING PROXY: Handling url /?data=qobuz://song/44538047 Jun 20 12:45:32 smsl-n100 ntpd[19977]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 20 12:45:32 smsl-n100 ntpd[19977]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 906 days ago Jun 20 12:45:32 smsl-n100 ntpd[19977]: Listen and drop on 0 v6wildcard [::]:123 Jun 20 12:45:32 smsl-n100 ntpd[19977]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 20 12:45:32 smsl-n100 ntpd[19977]: Listen normally on 2 lo 127.0.0.1:123 Jun 20 12:45:32 smsl-n100 ntpd[19977]: Listen normally on 3 eth0 192.168.40.219:123 Jun 20 12:45:32 smsl-n100 ntpd[19977]: Listening on routing socket on fd #20 for interface updates Jun 20 12:45:32 smsl-n100 ntpd[19977]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Jun 20 12:45:32 smsl-n100 ntpd[19977]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Jun 20 12:45:32 smsl-n100 volumio[1172]: error: updateQueue error: null Jun 20 12:45:32 smsl-n100 volumio[1172]: info: ------------------------------ 50ms Jun 20 12:45:32 smsl-n100 volumio[1172]: info: Executing endpoint getStreamUrlqobuz Jun 20 12:45:32 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 20 12:45:32 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 12:45:32 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 12:45:32 smsl-n100 volumio[1172]: info: Discovery: Getting this device information Jun 20 12:45:32 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetState Jun 20 12:45:32 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 134 Jun 20 12:45:32 smsl-n100 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 12:45:32 smsl-n100 dhcpcd[936]: eth0: leased 192.168.40.219 for 600 seconds Jun 20 12:45:32 smsl-n100 dhcpcd[936]: eth0: adding route to 192.168.40.0/24 Jun 20 12:45:32 smsl-n100 dhcpcd[936]: eth0: adding default route via 192.168.40.1 Jun 20 12:45:32 smsl-n100 volumio[1172]: info: Discovery: adding 1df7577f-2dd9-4e13-90a4-2547ecf91573 Jun 20 12:45:32 smsl-n100 volumio[1172]: info: Discovery: Found device SMSL N100 Jun 20 12:45:32 smsl-n100 volumio[1172]: info: CoreCommandRouter::volumioGetState Jun 20 12:45:32 smsl-n100 volumio[1172]: info: CorePlayQueue::getTrack 134 Jun 20 12:45:32 smsl-n100 volumio[1172]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 20 12:45:32 smsl-n100 volumio[1172]: Error: The value NaN is not a number Jun 20 12:45:32 smsl-n100 volumio[1172]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Jun 20 12:45:32 smsl-n100 volumio[1172]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Jun 20 12:45:32 smsl-n100 volumio[1172]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Jun 20 12:45:32 smsl-n100 volumio[1172]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Jun 20 12:45:32 smsl-n100 volumio[1172]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Jun 20 12:45:32 smsl-n100 volumio[1172]: at Browser.emit (events.js:400:28) Jun 20 12:45:32 smsl-n100 volumio[1172]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Jun 20 12:45:32 smsl-n100 volumio[1172]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Jun 20 12:45:32 smsl-n100 volumio[1172]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Jun 20 12:45:32 smsl-n100 volumio[1172]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Jun 20 12:45:32 smsl-n100 volumio[1172]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Jun 20 12:45:32 smsl-n100 volumio[1172]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Jun 20 12:45:32 smsl-n100 volumio[1172]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Jun 20 12:45:32 smsl-n100 volumio[1172]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 20 12:45:33 smsl-n100 ntpd[19977]: ntpd exiting on signal 15 (Terminated) Jun 20 12:45:33 smsl-n100 systemd[1]: Stopping Network Time Service... Jun 20 12:45:33 smsl-n100 systemd[1]: ntp.service: Succeeded. Jun 20 12:45:33 smsl-n100 systemd[1]: Stopped Network Time Service. Jun 20 12:45:33 smsl-n100 systemd[1]: Starting Network Time Service... Jun 20 12:45:33 smsl-n100 ntpd[20017]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 20 12:45:33 smsl-n100 ntpd[20017]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jun 20 12:45:33 smsl-n100 systemd[1]: Started Network Time Service. Jun 20 12:45:33 smsl-n100 ntpd[20025]: proto: precision = 1.166 usec (-20) Jun 20 12:45:33 smsl-n100 ntpd[20025]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 20 12:45:33 smsl-n100 ntpd[20025]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 20 12:45:33 smsl-n100 ntpd[20025]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 906 days ago Jun 20 12:45:33 smsl-n100 ntpd[20025]: Listen and drop on 0 v6wildcard [::]:123 Jun 20 12:45:33 smsl-n100 ntpd[20025]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 20 12:45:33 smsl-n100 ntpd[20025]: Listen normally on 2 lo 127.0.0.1:123 Jun 20 12:45:33 smsl-n100 ntpd[20025]: Listen normally on 3 eth0 192.168.40.219:123 Jun 20 12:45:33 smsl-n100 ntpd[20025]: Listening on routing socket on fd #20 for interface updates Jun 20 12:45:33 smsl-n100 ntpd[20025]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Jun 20 12:45:33 smsl-n100 ntpd[20025]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Jun 20 12:45:33 smsl-n100 sudo[20028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-20 12:44 Jun 20 12:45:33 smsl-n100 sudo[20028]: 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"