-- Logs begin at Thu 2025-05-29 20:03:06 JST, end at Fri 2025-05-30 08:23:22 JST. -- May 30 08:22:02 smsl-n100 volumio[980]: info: getStreamUrl took 2992 milliseconds May 30 08:22:02 smsl-n100 volumio[980]: error: Could not get QOBUZ Stream URL: undefined May 30 08:22:02 smsl-n100 volumio[980]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} May 30 08:22:02 smsl-n100 volumio[980]: info: May 30 08:22:02 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:02 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:02 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:02 smsl-n100 volumio[980]: info: May 30 08:22:02 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:02 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:02 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:02 smsl-n100 volumio[980]: info: May 30 08:22:02 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:02 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:02 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:02 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:02 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:02 smsl-n100 volumio[980]: info: sendMpdCommand status took 24 milliseconds May 30 08:22:02 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:02 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:02 smsl-n100 volumio[980]: info: sendMpdCommand status took 24 milliseconds May 30 08:22:02 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:02 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:02 smsl-n100 volumio[980]: info: sendMpdCommand status took 23 milliseconds May 30 08:22:02 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:02 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:02 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:02 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:02 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:02 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:02 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 34 milliseconds May 30 08:22:02 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 30 milliseconds May 30 08:22:02 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 27 milliseconds May 30 08:22:02 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 12 milliseconds May 30 08:22:02 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 12 milliseconds May 30 08:22:02 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 11 milliseconds May 30 08:22:02 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:02 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:02 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:02 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:02 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:02 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:02 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 08:22:02 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:03 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:03 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:03 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 4 May 30 08:22:03 smsl-n100 volumio[980]: 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":"250419893","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419893","trackType":"qobuz"} May 30 08:22:03 smsl-n100 volumio[980]: verbose: CURRENT POSITION 4 May 30 08:22:03 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:03 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:03 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:03 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:03 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:03 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:03 smsl-n100 volumio[980]: info: No code May 30 08:22:03 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:03 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:03 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:03 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:03 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:03 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:03 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:03 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:03 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:03 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:03 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 4 May 30 08:22:03 smsl-n100 volumio[980]: 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":"250419893","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419893","trackType":"qobuz"} May 30 08:22:03 smsl-n100 volumio[980]: verbose: CURRENT POSITION 4 May 30 08:22:03 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:03 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:03 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:03 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:03 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:03 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:03 smsl-n100 volumio[980]: info: No code May 30 08:22:03 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:03 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:03 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:03 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:03 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:03 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:03 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:03 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:03 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:03 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:03 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 4 May 30 08:22:03 smsl-n100 volumio[980]: 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":"250419893","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419893","trackType":"qobuz"} May 30 08:22:03 smsl-n100 volumio[980]: verbose: CURRENT POSITION 4 May 30 08:22:03 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:03 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:03 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:03 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:03 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:03 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:03 smsl-n100 volumio[980]: info: No code May 30 08:22:03 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:03 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:03 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:03 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:03 smsl-n100 volumio[980]: info: ------------------------------ 328ms May 30 08:22:03 smsl-n100 volumio[980]: info: ------------------------------ 325ms May 30 08:22:03 smsl-n100 volumio[980]: info: ------------------------------ 323ms May 30 08:22:03 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:03 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:03 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:03 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:03 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:03 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:03 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:03 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:03 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:03 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:03 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:03 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:03 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:03 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:03 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:03 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:03 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:03 smsl-n100 volumio[980]: info: Moving to next track after playback error May 30 08:22:03 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPlay May 30 08:22:03 smsl-n100 volumio[980]: info: CoreStateMachine::play index 5 May 30 08:22:03 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:03 smsl-n100 volumio[980]: info: CoreStateMachine::stop May 30 08:22:03 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:03 smsl-n100 volumio[980]: info: CoreStateMachine::play index undefined May 30 08:22:03 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:03 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 5 May 30 08:22:03 smsl-n100 volumio[980]: info: CoreStateMachine::startPlaybackTimer May 30 08:22:03 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 5 May 30 08:22:03 smsl-n100 volumio[980]: info: [1748560923961] ControllerQobuz::clearAddPlayTrack May 30 08:22:03 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand stop May 30 08:22:03 smsl-n100 volumio[980]: info: sendMpdCommand stop took 5 milliseconds May 30 08:22:03 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clear May 30 08:22:03 smsl-n100 volumio[980]: info: May 30 08:22:03 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:03 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:03 smsl-n100 volumio[980]: info: sendMpdCommand clear took 4 milliseconds May 30 08:22:03 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/250419894" May 30 08:22:03 smsl-n100 volumio[980]: info: May 30 08:22:03 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:03 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:03 smsl-n100 volumio[980]: STREAMING PROXY: Handling url /?data=qobuz://song/250419894 May 30 08:22:03 smsl-n100 volumio[980]: info: May 30 08:22:03 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:03 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:03 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:03 smsl-n100 volumio[980]: info: ------------------------------ 17ms May 30 08:22:03 smsl-n100 volumio[980]: info: Executing endpoint getStreamUrlqobuz May 30 08:22:04 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 30 08:22:05 smsl-n100 volumio[980]: info: getStreamUrl took 1968 milliseconds May 30 08:22:05 smsl-n100 volumio[980]: error: Could not get QOBUZ Stream URL: undefined May 30 08:22:05 smsl-n100 volumio[980]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} May 30 08:22:05 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/250419894" May 30 08:22:05 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:05 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:05 smsl-n100 volumio[980]: info: May 30 08:22:05 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:05 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:05 smsl-n100 volumio[980]: info: ------------------------------ 2015ms May 30 08:22:05 smsl-n100 volumio[980]: info: ------------------------------ 2010ms May 30 08:22:05 smsl-n100 volumio[980]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/250419894" took 8 milliseconds May 30 08:22:05 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 08:22:05 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand play May 30 08:22:06 smsl-n100 volumio[980]: info: May 30 08:22:06 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:06 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:06 smsl-n100 volumio[980]: STREAMING PROXY: Handling url /?data=qobuz://song/250419894 May 30 08:22:06 smsl-n100 volumio[980]: info: May 30 08:22:06 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:06 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:06 smsl-n100 volumio[980]: info: ------------------------------ 21ms May 30 08:22:06 smsl-n100 volumio[980]: info: sendMpdCommand play took 15 milliseconds May 30 08:22:06 smsl-n100 volumio[980]: info: ------------------------------ 13ms May 30 08:22:06 smsl-n100 volumio[980]: info: ------------------------------ 10ms May 30 08:22:06 smsl-n100 volumio[980]: info: Executing endpoint getStreamUrlqobuz May 30 08:22:06 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 30 08:22:09 smsl-n100 volumio[980]: info: getStreamUrl took 3010 milliseconds May 30 08:22:09 smsl-n100 volumio[980]: error: Could not get QOBUZ Stream URL: undefined May 30 08:22:09 smsl-n100 volumio[980]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} May 30 08:22:09 smsl-n100 volumio[980]: info: May 30 08:22:09 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:09 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:09 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:09 smsl-n100 volumio[980]: info: May 30 08:22:09 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:09 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:09 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:09 smsl-n100 volumio[980]: info: May 30 08:22:09 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:09 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:09 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:09 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:09 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:09 smsl-n100 volumio[980]: info: sendMpdCommand status took 24 milliseconds May 30 08:22:09 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:09 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:09 smsl-n100 volumio[980]: info: sendMpdCommand status took 29 milliseconds May 30 08:22:09 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:09 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:09 smsl-n100 volumio[980]: info: sendMpdCommand status took 29 milliseconds May 30 08:22:09 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:09 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:09 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:09 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:09 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:09 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:09 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 39 milliseconds May 30 08:22:09 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 30 milliseconds May 30 08:22:09 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 26 milliseconds May 30 08:22:09 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 12 milliseconds May 30 08:22:09 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 11 milliseconds May 30 08:22:09 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 10 milliseconds May 30 08:22:09 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:09 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:09 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:09 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:09 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:09 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:09 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 08:22:09 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:09 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:09 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:09 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 5 May 30 08:22:09 smsl-n100 volumio[980]: 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":"250419894","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419894","trackType":"qobuz"} May 30 08:22:09 smsl-n100 volumio[980]: verbose: CURRENT POSITION 5 May 30 08:22:09 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:09 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:09 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:09 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:09 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:09 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:09 smsl-n100 volumio[980]: info: No code May 30 08:22:09 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:09 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:09 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:09 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:09 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:09 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:09 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:09 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:09 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:09 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:09 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 5 May 30 08:22:09 smsl-n100 volumio[980]: 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":"250419894","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419894","trackType":"qobuz"} May 30 08:22:09 smsl-n100 volumio[980]: verbose: CURRENT POSITION 5 May 30 08:22:09 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:09 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:09 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:09 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:09 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:09 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:09 smsl-n100 volumio[980]: info: No code May 30 08:22:09 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:09 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:09 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:09 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:09 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:09 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:09 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:09 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:09 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:09 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:09 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 5 May 30 08:22:09 smsl-n100 volumio[980]: 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":"250419894","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419894","trackType":"qobuz"} May 30 08:22:09 smsl-n100 volumio[980]: verbose: CURRENT POSITION 5 May 30 08:22:09 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:09 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:09 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:09 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:09 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:09 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:09 smsl-n100 volumio[980]: info: No code May 30 08:22:09 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:09 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:09 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:09 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:09 smsl-n100 volumio[980]: info: ------------------------------ 359ms May 30 08:22:09 smsl-n100 volumio[980]: info: ------------------------------ 357ms May 30 08:22:09 smsl-n100 volumio[980]: info: ------------------------------ 355ms May 30 08:22:09 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:09 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:09 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:09 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:09 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:09 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:09 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:09 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:09 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:09 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:09 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:09 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:09 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:09 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:09 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:09 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:09 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:10 smsl-n100 volumio[980]: info: Moving to next track after playback error May 30 08:22:10 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPlay May 30 08:22:10 smsl-n100 volumio[980]: info: CoreStateMachine::play index 6 May 30 08:22:10 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:10 smsl-n100 volumio[980]: info: CoreStateMachine::stop May 30 08:22:10 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:10 smsl-n100 volumio[980]: info: CoreStateMachine::play index undefined May 30 08:22:10 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:10 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 6 May 30 08:22:10 smsl-n100 volumio[980]: info: CoreStateMachine::startPlaybackTimer May 30 08:22:10 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 6 May 30 08:22:10 smsl-n100 volumio[980]: info: [1748560930109] ControllerQobuz::clearAddPlayTrack May 30 08:22:10 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand stop May 30 08:22:10 smsl-n100 volumio[980]: info: sendMpdCommand stop took 2 milliseconds May 30 08:22:10 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clear May 30 08:22:10 smsl-n100 volumio[980]: info: May 30 08:22:10 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:10 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:10 smsl-n100 volumio[980]: info: sendMpdCommand clear took 5 milliseconds May 30 08:22:10 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/250419895" May 30 08:22:10 smsl-n100 volumio[980]: info: May 30 08:22:10 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:10 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:10 smsl-n100 volumio[980]: info: May 30 08:22:10 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:10 smsl-n100 volumio[980]: STREAMING PROXY: Handling url /?data=qobuz://song/250419895 May 30 08:22:10 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:10 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:10 smsl-n100 volumio[980]: info: ------------------------------ 16ms May 30 08:22:10 smsl-n100 volumio[980]: info: Executing endpoint getStreamUrlqobuz May 30 08:22:10 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 30 08:22:12 smsl-n100 volumio[980]: info: getStreamUrl took 1966 milliseconds May 30 08:22:12 smsl-n100 volumio[980]: error: Could not get QOBUZ Stream URL: undefined May 30 08:22:12 smsl-n100 volumio[980]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} May 30 08:22:12 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/250419895" May 30 08:22:12 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:12 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:12 smsl-n100 volumio[980]: info: May 30 08:22:12 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:12 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:12 smsl-n100 volumio[980]: info: ------------------------------ 2012ms May 30 08:22:12 smsl-n100 volumio[980]: info: ------------------------------ 2009ms May 30 08:22:12 smsl-n100 volumio[980]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/250419895" took 9 milliseconds May 30 08:22:12 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 08:22:12 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand play May 30 08:22:12 smsl-n100 volumio[980]: info: May 30 08:22:12 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:12 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:12 smsl-n100 volumio[980]: STREAMING PROXY: Handling url /?data=qobuz://song/250419895 May 30 08:22:12 smsl-n100 volumio[980]: info: May 30 08:22:12 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:12 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:12 smsl-n100 volumio[980]: info: ------------------------------ 25ms May 30 08:22:12 smsl-n100 volumio[980]: info: sendMpdCommand play took 16 milliseconds May 30 08:22:12 smsl-n100 volumio[980]: info: ------------------------------ 13ms May 30 08:22:12 smsl-n100 volumio[980]: info: ------------------------------ 9ms May 30 08:22:12 smsl-n100 volumio[980]: info: Executing endpoint getStreamUrlqobuz May 30 08:22:12 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 30 08:22:12 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 08:22:12 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 08:22:12 smsl-n100 volumio[980]: info: Discovery: Getting this device information May 30 08:22:12 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioGetState May 30 08:22:12 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 08:22:15 smsl-n100 volumio[980]: info: getStreamUrl took 3007 milliseconds May 30 08:22:15 smsl-n100 volumio[980]: error: Could not get QOBUZ Stream URL: undefined May 30 08:22:15 smsl-n100 volumio[980]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} May 30 08:22:15 smsl-n100 volumio[980]: info: May 30 08:22:15 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:15 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:15 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:15 smsl-n100 volumio[980]: info: May 30 08:22:15 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:15 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:15 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:15 smsl-n100 volumio[980]: info: May 30 08:22:15 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:15 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:15 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:15 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:15 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:15 smsl-n100 volumio[980]: info: sendMpdCommand status took 24 milliseconds May 30 08:22:15 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:15 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:15 smsl-n100 volumio[980]: info: sendMpdCommand status took 22 milliseconds May 30 08:22:15 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:15 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:15 smsl-n100 volumio[980]: info: sendMpdCommand status took 22 milliseconds May 30 08:22:15 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:15 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:15 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:15 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:15 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:15 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:15 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 36 milliseconds May 30 08:22:15 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 33 milliseconds May 30 08:22:15 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 30 milliseconds May 30 08:22:15 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 16 milliseconds May 30 08:22:15 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 15 milliseconds May 30 08:22:15 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 15 milliseconds May 30 08:22:15 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:15 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:15 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:15 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:15 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:15 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:15 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 08:22:15 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:15 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:15 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:15 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 6 May 30 08:22:15 smsl-n100 volumio[980]: 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":"250419895","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419895","trackType":"qobuz"} May 30 08:22:15 smsl-n100 volumio[980]: verbose: CURRENT POSITION 6 May 30 08:22:15 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:15 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:15 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:15 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:15 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:15 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:15 smsl-n100 volumio[980]: info: No code May 30 08:22:15 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:15 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:15 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:15 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:15 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:15 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:15 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:15 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:15 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:15 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:15 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 6 May 30 08:22:15 smsl-n100 volumio[980]: 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":"250419895","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419895","trackType":"qobuz"} May 30 08:22:15 smsl-n100 volumio[980]: verbose: CURRENT POSITION 6 May 30 08:22:15 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:15 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:15 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:15 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:15 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:15 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:15 smsl-n100 volumio[980]: info: No code May 30 08:22:15 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:15 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:15 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:15 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:15 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:15 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:15 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:15 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:15 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:15 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:15 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 6 May 30 08:22:15 smsl-n100 volumio[980]: 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":"250419895","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419895","trackType":"qobuz"} May 30 08:22:15 smsl-n100 volumio[980]: verbose: CURRENT POSITION 6 May 30 08:22:15 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:15 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:15 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:15 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:15 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:15 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:15 smsl-n100 volumio[980]: info: No code May 30 08:22:15 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:15 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:15 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:15 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:15 smsl-n100 volumio[980]: info: ------------------------------ 272ms May 30 08:22:15 smsl-n100 volumio[980]: info: ------------------------------ 268ms May 30 08:22:15 smsl-n100 volumio[980]: info: ------------------------------ 266ms May 30 08:22:15 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:15 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:15 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:15 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:15 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:15 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:15 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:15 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:15 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:15 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:15 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:15 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:15 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:15 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:15 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:15 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:15 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:16 smsl-n100 volumio[980]: info: Moving to next track after playback error May 30 08:22:16 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPlay May 30 08:22:16 smsl-n100 volumio[980]: info: CoreStateMachine::play index 7 May 30 08:22:16 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:16 smsl-n100 volumio[980]: info: CoreStateMachine::stop May 30 08:22:16 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:16 smsl-n100 volumio[980]: info: CoreStateMachine::play index undefined May 30 08:22:16 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:16 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 7 May 30 08:22:16 smsl-n100 volumio[980]: info: CoreStateMachine::startPlaybackTimer May 30 08:22:16 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 7 May 30 08:22:16 smsl-n100 volumio[980]: info: [1748560936248] ControllerQobuz::clearAddPlayTrack May 30 08:22:16 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand stop May 30 08:22:16 smsl-n100 volumio[980]: info: sendMpdCommand stop took 2 milliseconds May 30 08:22:16 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clear May 30 08:22:16 smsl-n100 volumio[980]: info: May 30 08:22:16 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:16 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:16 smsl-n100 volumio[980]: info: sendMpdCommand clear took 17 milliseconds May 30 08:22:16 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/250419896" May 30 08:22:16 smsl-n100 volumio[980]: info: May 30 08:22:16 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:16 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:16 smsl-n100 volumio[980]: STREAMING PROXY: Handling url /?data=qobuz://song/250419896 May 30 08:22:16 smsl-n100 volumio[980]: info: May 30 08:22:16 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:16 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:16 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:16 smsl-n100 volumio[980]: info: ------------------------------ 18ms May 30 08:22:16 smsl-n100 volumio[980]: info: Executing endpoint getStreamUrlqobuz May 30 08:22:16 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 30 08:22:18 smsl-n100 volumio[980]: info: getStreamUrl took 1954 milliseconds May 30 08:22:18 smsl-n100 volumio[980]: error: Could not get QOBUZ Stream URL: undefined May 30 08:22:18 smsl-n100 volumio[980]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} May 30 08:22:18 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/250419896" May 30 08:22:18 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:18 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:18 smsl-n100 volumio[980]: info: May 30 08:22:18 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:18 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:18 smsl-n100 volumio[980]: info: ------------------------------ 2003ms May 30 08:22:18 smsl-n100 volumio[980]: info: ------------------------------ 2000ms May 30 08:22:18 smsl-n100 volumio[980]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/250419896" took 8 milliseconds May 30 08:22:18 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 08:22:18 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand play May 30 08:22:18 smsl-n100 volumio[980]: info: May 30 08:22:18 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:18 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:18 smsl-n100 volumio[980]: STREAMING PROXY: Handling url /?data=qobuz://song/250419896 May 30 08:22:18 smsl-n100 volumio[980]: info: May 30 08:22:18 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:18 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:18 smsl-n100 volumio[980]: info: ------------------------------ 20ms May 30 08:22:18 smsl-n100 volumio[980]: info: sendMpdCommand play took 14 milliseconds May 30 08:22:18 smsl-n100 volumio[980]: info: ------------------------------ 12ms May 30 08:22:18 smsl-n100 volumio[980]: info: ------------------------------ 8ms May 30 08:22:18 smsl-n100 volumio[980]: info: Executing endpoint getStreamUrlqobuz May 30 08:22:18 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 30 08:22:21 smsl-n100 volumio[980]: info: getStreamUrl took 3012 milliseconds May 30 08:22:21 smsl-n100 volumio[980]: error: Could not get QOBUZ Stream URL: undefined May 30 08:22:21 smsl-n100 volumio[980]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} May 30 08:22:21 smsl-n100 volumio[980]: info: May 30 08:22:21 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:21 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:21 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:21 smsl-n100 volumio[980]: info: May 30 08:22:21 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:21 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:21 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:21 smsl-n100 volumio[980]: info: May 30 08:22:21 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:21 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:21 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:21 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:21 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:21 smsl-n100 volumio[980]: info: sendMpdCommand status took 23 milliseconds May 30 08:22:21 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:21 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:21 smsl-n100 volumio[980]: info: sendMpdCommand status took 22 milliseconds May 30 08:22:21 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:21 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:21 smsl-n100 volumio[980]: info: sendMpdCommand status took 21 milliseconds May 30 08:22:21 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:21 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:21 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:21 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:21 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:21 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:21 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 32 milliseconds May 30 08:22:21 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 30 milliseconds May 30 08:22:21 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 27 milliseconds May 30 08:22:21 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 14 milliseconds May 30 08:22:21 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 13 milliseconds May 30 08:22:21 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 13 milliseconds May 30 08:22:21 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:21 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:21 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:21 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:21 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:21 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:21 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 08:22:21 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:21 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:21 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:21 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 7 May 30 08:22:21 smsl-n100 volumio[980]: 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":"250419896","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419896","trackType":"qobuz"} May 30 08:22:21 smsl-n100 volumio[980]: verbose: CURRENT POSITION 7 May 30 08:22:21 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:21 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:21 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:21 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:21 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:21 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:21 smsl-n100 volumio[980]: info: No code May 30 08:22:21 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:21 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:21 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:21 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:21 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:21 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:21 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:21 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:21 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:21 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:21 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 7 May 30 08:22:21 smsl-n100 volumio[980]: 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":"250419896","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419896","trackType":"qobuz"} May 30 08:22:21 smsl-n100 volumio[980]: verbose: CURRENT POSITION 7 May 30 08:22:21 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:21 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:21 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:21 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:21 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:21 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:21 smsl-n100 volumio[980]: info: No code May 30 08:22:21 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:21 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:21 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:21 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:21 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:21 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:21 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:21 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:21 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:21 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:21 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 7 May 30 08:22:21 smsl-n100 volumio[980]: 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":"250419896","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419896","trackType":"qobuz"} May 30 08:22:21 smsl-n100 volumio[980]: verbose: CURRENT POSITION 7 May 30 08:22:21 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:21 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:21 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:21 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:21 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:21 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:21 smsl-n100 volumio[980]: info: No code May 30 08:22:21 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:21 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:21 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:21 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:21 smsl-n100 volumio[980]: info: ------------------------------ 259ms May 30 08:22:21 smsl-n100 volumio[980]: info: ------------------------------ 258ms May 30 08:22:21 smsl-n100 volumio[980]: info: ------------------------------ 256ms May 30 08:22:21 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:21 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:21 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:21 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:21 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:21 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:21 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:21 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:21 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:21 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:21 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:21 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:21 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:21 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:21 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:21 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:21 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:22 smsl-n100 volumio[980]: info: Moving to next track after playback error May 30 08:22:22 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPlay May 30 08:22:22 smsl-n100 volumio[980]: info: CoreStateMachine::play index 8 May 30 08:22:22 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:22 smsl-n100 volumio[980]: info: CoreStateMachine::stop May 30 08:22:22 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:22 smsl-n100 volumio[980]: info: CoreStateMachine::play index undefined May 30 08:22:22 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:22 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 8 May 30 08:22:22 smsl-n100 volumio[980]: info: CoreStateMachine::startPlaybackTimer May 30 08:22:22 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 8 May 30 08:22:22 smsl-n100 volumio[980]: info: [1748560942388] ControllerQobuz::clearAddPlayTrack May 30 08:22:22 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand stop May 30 08:22:22 smsl-n100 volumio[980]: info: sendMpdCommand stop took 2 milliseconds May 30 08:22:22 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clear May 30 08:22:22 smsl-n100 volumio[980]: info: May 30 08:22:22 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:22 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:22 smsl-n100 volumio[980]: info: sendMpdCommand clear took 4 milliseconds May 30 08:22:22 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/250419897" May 30 08:22:22 smsl-n100 volumio[980]: info: May 30 08:22:22 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:22 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:22 smsl-n100 volumio[980]: STREAMING PROXY: Handling url /?data=qobuz://song/250419897 May 30 08:22:22 smsl-n100 volumio[980]: info: May 30 08:22:22 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:22 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:22 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:22 smsl-n100 volumio[980]: info: ------------------------------ 31ms May 30 08:22:22 smsl-n100 volumio[980]: info: Executing endpoint getStreamUrlqobuz May 30 08:22:22 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 30 08:22:24 smsl-n100 volumio[980]: info: getStreamUrl took 1960 milliseconds May 30 08:22:24 smsl-n100 volumio[980]: error: Could not get QOBUZ Stream URL: undefined May 30 08:22:24 smsl-n100 volumio[980]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} May 30 08:22:24 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/250419897" May 30 08:22:24 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:24 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:24 smsl-n100 volumio[980]: info: May 30 08:22:24 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:24 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:24 smsl-n100 volumio[980]: info: ------------------------------ 2021ms May 30 08:22:24 smsl-n100 volumio[980]: info: ------------------------------ 2018ms May 30 08:22:24 smsl-n100 volumio[980]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/250419897" took 9 milliseconds May 30 08:22:24 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 08:22:24 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand play May 30 08:22:24 smsl-n100 volumio[980]: info: May 30 08:22:24 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:24 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:24 smsl-n100 volumio[980]: STREAMING PROXY: Handling url /?data=qobuz://song/250419897 May 30 08:22:24 smsl-n100 volumio[980]: info: May 30 08:22:24 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:24 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:24 smsl-n100 volumio[980]: info: ------------------------------ 35ms May 30 08:22:24 smsl-n100 volumio[980]: info: sendMpdCommand play took 17 milliseconds May 30 08:22:24 smsl-n100 volumio[980]: info: ------------------------------ 15ms May 30 08:22:24 smsl-n100 volumio[980]: info: ------------------------------ 10ms May 30 08:22:24 smsl-n100 volumio[980]: info: Executing endpoint getStreamUrlqobuz May 30 08:22:24 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 30 08:22:27 smsl-n100 volumio[980]: info: getStreamUrl took 2995 milliseconds May 30 08:22:27 smsl-n100 volumio[980]: error: Could not get QOBUZ Stream URL: undefined May 30 08:22:27 smsl-n100 volumio[980]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} May 30 08:22:27 smsl-n100 volumio[980]: info: May 30 08:22:27 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:27 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:27 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:27 smsl-n100 volumio[980]: info: May 30 08:22:27 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:27 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:27 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:27 smsl-n100 volumio[980]: info: May 30 08:22:27 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:27 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:27 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:27 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:27 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:27 smsl-n100 volumio[980]: info: sendMpdCommand status took 24 milliseconds May 30 08:22:27 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:27 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:27 smsl-n100 volumio[980]: info: sendMpdCommand status took 24 milliseconds May 30 08:22:27 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:27 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:27 smsl-n100 volumio[980]: info: sendMpdCommand status took 23 milliseconds May 30 08:22:27 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:27 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:27 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:27 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:27 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:27 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:27 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 41 milliseconds May 30 08:22:27 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 37 milliseconds May 30 08:22:27 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 35 milliseconds May 30 08:22:27 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 19 milliseconds May 30 08:22:27 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 13 milliseconds May 30 08:22:27 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 12 milliseconds May 30 08:22:27 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:27 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:27 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:27 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:27 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:27 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:27 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 08:22:27 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:27 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:27 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:27 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 8 May 30 08:22:27 smsl-n100 volumio[980]: 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":"250419897","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419897","trackType":"qobuz"} May 30 08:22:27 smsl-n100 volumio[980]: verbose: CURRENT POSITION 8 May 30 08:22:27 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:27 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:27 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:27 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:27 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:27 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:27 smsl-n100 volumio[980]: info: No code May 30 08:22:27 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:27 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:27 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:27 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:27 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:27 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:27 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:27 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:27 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:27 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:27 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 8 May 30 08:22:27 smsl-n100 volumio[980]: 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":"250419897","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419897","trackType":"qobuz"} May 30 08:22:27 smsl-n100 volumio[980]: verbose: CURRENT POSITION 8 May 30 08:22:27 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:27 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:27 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:27 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:27 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:27 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:27 smsl-n100 volumio[980]: info: No code May 30 08:22:27 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:27 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:27 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:27 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:27 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:27 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:27 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:27 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:27 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:27 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:27 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 8 May 30 08:22:27 smsl-n100 volumio[980]: 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":"250419897","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419897","trackType":"qobuz"} May 30 08:22:27 smsl-n100 volumio[980]: verbose: CURRENT POSITION 8 May 30 08:22:27 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:27 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:27 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:27 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:27 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:27 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:27 smsl-n100 volumio[980]: info: No code May 30 08:22:27 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:27 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:27 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:27 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:27 smsl-n100 volumio[980]: info: ------------------------------ 313ms May 30 08:22:27 smsl-n100 volumio[980]: info: ------------------------------ 314ms May 30 08:22:27 smsl-n100 volumio[980]: info: ------------------------------ 313ms May 30 08:22:27 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:27 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:27 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:27 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:27 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:27 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:27 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:27 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:27 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:27 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:27 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:27 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:27 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:27 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:27 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:27 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:27 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:28 smsl-n100 volumio[980]: info: Moving to next track after playback error May 30 08:22:28 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPlay May 30 08:22:28 smsl-n100 volumio[980]: info: CoreStateMachine::play index 9 May 30 08:22:28 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:28 smsl-n100 volumio[980]: info: CoreStateMachine::stop May 30 08:22:28 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:28 smsl-n100 volumio[980]: info: CoreStateMachine::play index undefined May 30 08:22:28 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:28 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 9 May 30 08:22:28 smsl-n100 volumio[980]: info: CoreStateMachine::startPlaybackTimer May 30 08:22:28 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 9 May 30 08:22:28 smsl-n100 volumio[980]: info: [1748560948533] ControllerQobuz::clearAddPlayTrack May 30 08:22:28 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand stop May 30 08:22:28 smsl-n100 volumio[980]: info: sendMpdCommand stop took 2 milliseconds May 30 08:22:28 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clear May 30 08:22:28 smsl-n100 volumio[980]: info: May 30 08:22:28 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:28 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:28 smsl-n100 volumio[980]: info: sendMpdCommand clear took 5 milliseconds May 30 08:22:28 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/250419898" May 30 08:22:28 smsl-n100 volumio[980]: info: May 30 08:22:28 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:28 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:28 smsl-n100 volumio[980]: STREAMING PROXY: Handling url /?data=qobuz://song/250419898 May 30 08:22:28 smsl-n100 volumio[980]: info: May 30 08:22:28 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:28 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:28 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:28 smsl-n100 volumio[980]: info: ------------------------------ 15ms May 30 08:22:28 smsl-n100 volumio[980]: info: Executing endpoint getStreamUrlqobuz May 30 08:22:28 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 30 08:22:30 smsl-n100 volumio[980]: info: getStreamUrl took 1974 milliseconds May 30 08:22:30 smsl-n100 volumio[980]: error: Could not get QOBUZ Stream URL: undefined May 30 08:22:30 smsl-n100 volumio[980]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} May 30 08:22:30 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/250419898" May 30 08:22:30 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:30 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:30 smsl-n100 volumio[980]: info: May 30 08:22:30 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:30 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:30 smsl-n100 volumio[980]: info: ------------------------------ 2022ms May 30 08:22:30 smsl-n100 volumio[980]: info: ------------------------------ 2019ms May 30 08:22:30 smsl-n100 volumio[980]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/250419898" took 11 milliseconds May 30 08:22:30 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 08:22:30 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand play May 30 08:22:30 smsl-n100 volumio[980]: info: May 30 08:22:30 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:30 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:30 smsl-n100 volumio[980]: STREAMING PROXY: Handling url /?data=qobuz://song/250419898 May 30 08:22:30 smsl-n100 volumio[980]: info: May 30 08:22:30 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:30 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:30 smsl-n100 volumio[980]: info: ------------------------------ 24ms May 30 08:22:30 smsl-n100 volumio[980]: info: sendMpdCommand play took 18 milliseconds May 30 08:22:30 smsl-n100 volumio[980]: info: ------------------------------ 15ms May 30 08:22:30 smsl-n100 volumio[980]: info: ------------------------------ 11ms May 30 08:22:30 smsl-n100 volumio[980]: info: Executing endpoint getStreamUrlqobuz May 30 08:22:30 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 30 08:22:33 smsl-n100 volumio[980]: info: getStreamUrl took 3002 milliseconds May 30 08:22:33 smsl-n100 volumio[980]: error: Could not get QOBUZ Stream URL: undefined May 30 08:22:33 smsl-n100 volumio[980]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} May 30 08:22:33 smsl-n100 volumio[980]: info: May 30 08:22:33 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:33 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:33 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:33 smsl-n100 volumio[980]: info: May 30 08:22:33 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:33 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:33 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:33 smsl-n100 volumio[980]: info: May 30 08:22:33 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:33 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:33 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:33 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:33 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:33 smsl-n100 volumio[980]: info: sendMpdCommand status took 22 milliseconds May 30 08:22:33 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:33 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:33 smsl-n100 volumio[980]: info: sendMpdCommand status took 21 milliseconds May 30 08:22:33 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:33 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:33 smsl-n100 volumio[980]: info: sendMpdCommand status took 21 milliseconds May 30 08:22:33 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:33 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:33 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:33 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:33 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:33 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:33 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 29 milliseconds May 30 08:22:33 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 26 milliseconds May 30 08:22:33 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 23 milliseconds May 30 08:22:33 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 11 milliseconds May 30 08:22:33 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 10 milliseconds May 30 08:22:33 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 9 milliseconds May 30 08:22:33 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:33 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:33 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:33 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:33 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:33 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:33 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 08:22:33 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:33 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:33 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:33 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 9 May 30 08:22:33 smsl-n100 volumio[980]: 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":"250419898","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419898","trackType":"qobuz"} May 30 08:22:33 smsl-n100 volumio[980]: verbose: CURRENT POSITION 9 May 30 08:22:33 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:33 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:33 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:33 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:33 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:33 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:33 smsl-n100 volumio[980]: info: No code May 30 08:22:33 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:33 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:33 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:33 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:33 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:33 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:33 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:33 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:33 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:33 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:33 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 9 May 30 08:22:33 smsl-n100 volumio[980]: 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":"250419898","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419898","trackType":"qobuz"} May 30 08:22:33 smsl-n100 volumio[980]: verbose: CURRENT POSITION 9 May 30 08:22:33 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:33 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:33 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:33 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:33 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:33 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:33 smsl-n100 volumio[980]: info: No code May 30 08:22:33 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:33 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:33 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:33 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:33 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:33 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:33 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:33 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:33 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:33 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:33 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 9 May 30 08:22:33 smsl-n100 volumio[980]: 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":"250419898","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419898","trackType":"qobuz"} May 30 08:22:33 smsl-n100 volumio[980]: verbose: CURRENT POSITION 9 May 30 08:22:33 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:33 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:33 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:33 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:33 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:33 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:33 smsl-n100 volumio[980]: info: No code May 30 08:22:33 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:33 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:33 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:33 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:33 smsl-n100 volumio[980]: info: ------------------------------ 282ms May 30 08:22:33 smsl-n100 volumio[980]: info: ------------------------------ 280ms May 30 08:22:33 smsl-n100 volumio[980]: info: ------------------------------ 279ms May 30 08:22:33 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:33 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:33 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:33 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:33 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:33 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:33 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:33 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:34 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:34 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:34 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:34 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:34 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:34 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:34 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:34 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:34 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:34 smsl-n100 volumio[980]: info: Moving to next track after playback error May 30 08:22:34 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPlay May 30 08:22:34 smsl-n100 volumio[980]: info: CoreStateMachine::play index 10 May 30 08:22:34 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:34 smsl-n100 volumio[980]: info: CoreStateMachine::stop May 30 08:22:34 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:34 smsl-n100 volumio[980]: info: CoreStateMachine::play index undefined May 30 08:22:34 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:34 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 10 May 30 08:22:34 smsl-n100 volumio[980]: info: CoreStateMachine::startPlaybackTimer May 30 08:22:34 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 10 May 30 08:22:34 smsl-n100 volumio[980]: info: [1748560954674] ControllerQobuz::clearAddPlayTrack May 30 08:22:34 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand stop May 30 08:22:34 smsl-n100 volumio[980]: info: sendMpdCommand stop took 2 milliseconds May 30 08:22:34 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clear May 30 08:22:34 smsl-n100 volumio[980]: info: May 30 08:22:34 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:34 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:34 smsl-n100 volumio[980]: info: sendMpdCommand clear took 4 milliseconds May 30 08:22:34 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/250419899" May 30 08:22:34 smsl-n100 volumio[980]: info: May 30 08:22:34 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:34 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:34 smsl-n100 volumio[980]: STREAMING PROXY: Handling url /?data=qobuz://song/250419899 May 30 08:22:34 smsl-n100 volumio[980]: info: May 30 08:22:34 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:34 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:34 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:34 smsl-n100 volumio[980]: info: ------------------------------ 16ms May 30 08:22:34 smsl-n100 volumio[980]: info: Executing endpoint getStreamUrlqobuz May 30 08:22:34 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 30 08:22:35 smsl-n100 volumio[980]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 08:22:35 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 30 08:22:36 smsl-n100 volumio[980]: info: getStreamUrl took 1979 milliseconds May 30 08:22:36 smsl-n100 volumio[980]: error: Could not get QOBUZ Stream URL: undefined May 30 08:22:36 smsl-n100 volumio[980]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} May 30 08:22:36 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/250419899" May 30 08:22:36 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:36 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:36 smsl-n100 volumio[980]: info: May 30 08:22:36 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:36 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:36 smsl-n100 volumio[980]: info: ------------------------------ 2024ms May 30 08:22:36 smsl-n100 volumio[980]: info: ------------------------------ 2020ms May 30 08:22:36 smsl-n100 volumio[980]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/250419899" took 8 milliseconds May 30 08:22:36 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 08:22:36 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand play May 30 08:22:36 smsl-n100 volumio[980]: info: May 30 08:22:36 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:36 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:36 smsl-n100 volumio[980]: info: May 30 08:22:36 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:36 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:36 smsl-n100 volumio[980]: STREAMING PROXY: Handling url /?data=qobuz://song/250419899 May 30 08:22:36 smsl-n100 volumio[980]: info: ------------------------------ 21ms May 30 08:22:36 smsl-n100 volumio[980]: info: sendMpdCommand play took 15 milliseconds May 30 08:22:36 smsl-n100 volumio[980]: info: ------------------------------ 13ms May 30 08:22:36 smsl-n100 volumio[980]: info: ------------------------------ 9ms May 30 08:22:36 smsl-n100 volumio[980]: info: Executing endpoint getStreamUrlqobuz May 30 08:22:36 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 30 08:22:37 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 30 08:22:37 smsl-n100 sudo[2199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 30 08:22:37 smsl-n100 sudo[2199]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:22:37 smsl-n100 sudo[2199]: pam_unix(sudo:session): session closed for user root May 30 08:22:37 smsl-n100 sudo[2204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 30 08:22:37 smsl-n100 sudo[2204]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:22:37 smsl-n100 sudo[2204]: pam_unix(sudo:session): session closed for user root May 30 08:22:37 smsl-n100 sudo[2212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 30 08:22:37 smsl-n100 sudo[2212]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:22:37 smsl-n100 sudo[2212]: pam_unix(sudo:session): session closed for user root May 30 08:22:37 smsl-n100 sudo[2218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 30 08:22:37 smsl-n100 sudo[2218]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:22:37 smsl-n100 sudo[2218]: pam_unix(sudo:session): session closed for user root May 30 08:22:37 smsl-n100 sudo[2224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 30 08:22:37 smsl-n100 sudo[2224]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:22:37 smsl-n100 sudo[2224]: pam_unix(sudo:session): session closed for user root May 30 08:22:37 smsl-n100 sudo[2227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 08:22:37 smsl-n100 sudo[2227]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:22:37 smsl-n100 sudo[2227]: pam_unix(sudo:session): session closed for user root May 30 08:22:37 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 30 08:22:37 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 30 08:22:37 smsl-n100 sudo[2231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 30 08:22:37 smsl-n100 sudo[2231]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:22:38 smsl-n100 sudo[2231]: pam_unix(sudo:session): session closed for user root May 30 08:22:39 smsl-n100 volumio[980]: info: getStreamUrl took 3012 milliseconds May 30 08:22:39 smsl-n100 volumio[980]: error: Could not get QOBUZ Stream URL: undefined May 30 08:22:39 smsl-n100 volumio[980]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} May 30 08:22:39 smsl-n100 volumio[980]: info: May 30 08:22:39 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:39 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:39 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:39 smsl-n100 volumio[980]: info: May 30 08:22:39 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:39 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:39 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:39 smsl-n100 volumio[980]: info: May 30 08:22:39 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:39 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:39 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:39 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:39 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:39 smsl-n100 volumio[980]: info: sendMpdCommand status took 31 milliseconds May 30 08:22:39 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:39 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:39 smsl-n100 volumio[980]: info: sendMpdCommand status took 30 milliseconds May 30 08:22:39 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:39 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:39 smsl-n100 volumio[980]: info: sendMpdCommand status took 29 milliseconds May 30 08:22:39 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:39 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:39 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:39 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:39 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:39 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:39 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 29 milliseconds May 30 08:22:39 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 26 milliseconds May 30 08:22:39 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 23 milliseconds May 30 08:22:39 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 12 milliseconds May 30 08:22:39 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 11 milliseconds May 30 08:22:39 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 10 milliseconds May 30 08:22:39 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:39 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:39 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:39 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:39 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:39 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:39 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 08:22:39 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:39 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:39 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:39 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 10 May 30 08:22:39 smsl-n100 volumio[980]: 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":"250419899","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419899","trackType":"qobuz"} May 30 08:22:39 smsl-n100 volumio[980]: verbose: CURRENT POSITION 10 May 30 08:22:39 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:39 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:39 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:39 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:39 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:39 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:39 smsl-n100 volumio[980]: info: No code May 30 08:22:39 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:39 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:39 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:39 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:39 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:39 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:39 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:39 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:39 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:39 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:39 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 10 May 30 08:22:39 smsl-n100 volumio[980]: 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":"250419899","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419899","trackType":"qobuz"} May 30 08:22:39 smsl-n100 volumio[980]: verbose: CURRENT POSITION 10 May 30 08:22:39 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:39 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:39 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:39 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:39 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:39 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:39 smsl-n100 volumio[980]: info: No code May 30 08:22:39 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:39 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:39 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:39 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:39 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:39 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:39 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:39 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:39 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:39 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:39 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 10 May 30 08:22:39 smsl-n100 volumio[980]: 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":"250419899","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419899","trackType":"qobuz"} May 30 08:22:39 smsl-n100 volumio[980]: verbose: CURRENT POSITION 10 May 30 08:22:39 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:39 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:39 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:39 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:39 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:39 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:39 smsl-n100 volumio[980]: info: No code May 30 08:22:39 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:39 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:39 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:39 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:40 smsl-n100 volumio[980]: info: ------------------------------ 228ms May 30 08:22:40 smsl-n100 volumio[980]: info: ------------------------------ 226ms May 30 08:22:40 smsl-n100 volumio[980]: info: ------------------------------ 223ms May 30 08:22:40 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:40 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:40 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:40 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:40 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:40 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:40 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:40 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:40 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:40 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:40 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:40 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:40 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:40 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:40 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:40 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:40 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:40 smsl-n100 volumio[980]: info: Moving to next track after playback error May 30 08:22:40 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPlay May 30 08:22:40 smsl-n100 volumio[980]: info: CoreStateMachine::play index 11 May 30 08:22:40 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:40 smsl-n100 volumio[980]: info: CoreStateMachine::stop May 30 08:22:40 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:40 smsl-n100 volumio[980]: info: CoreStateMachine::play index undefined May 30 08:22:40 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:40 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 11 May 30 08:22:40 smsl-n100 volumio[980]: info: CoreStateMachine::startPlaybackTimer May 30 08:22:40 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 11 May 30 08:22:40 smsl-n100 volumio[980]: info: [1748560960828] ControllerQobuz::clearAddPlayTrack May 30 08:22:40 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand stop May 30 08:22:40 smsl-n100 volumio[980]: info: sendMpdCommand stop took 2 milliseconds May 30 08:22:40 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clear May 30 08:22:40 smsl-n100 volumio[980]: info: May 30 08:22:40 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:40 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:40 smsl-n100 volumio[980]: info: sendMpdCommand clear took 4 milliseconds May 30 08:22:40 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/250419900" May 30 08:22:40 smsl-n100 volumio[980]: info: May 30 08:22:40 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:40 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:40 smsl-n100 volumio[980]: STREAMING PROXY: Handling url /?data=qobuz://song/250419900 May 30 08:22:40 smsl-n100 volumio[980]: info: May 30 08:22:40 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:40 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:40 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:40 smsl-n100 volumio[980]: info: ------------------------------ 15ms May 30 08:22:40 smsl-n100 volumio[980]: info: Executing endpoint getStreamUrlqobuz May 30 08:22:40 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 30 08:22:42 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 08:22:42 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 08:22:42 smsl-n100 volumio[980]: info: Discovery: Getting this device information May 30 08:22:42 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioGetState May 30 08:22:42 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 11 May 30 08:22:42 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 08:22:42 smsl-n100 volumio[980]: info: getStreamUrl took 1969 milliseconds May 30 08:22:42 smsl-n100 volumio[980]: error: Could not get QOBUZ Stream URL: undefined May 30 08:22:42 smsl-n100 volumio[980]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} May 30 08:22:42 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/250419900" May 30 08:22:42 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:42 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:42 smsl-n100 volumio[980]: info: May 30 08:22:42 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:42 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:42 smsl-n100 volumio[980]: info: ------------------------------ 2012ms May 30 08:22:42 smsl-n100 volumio[980]: info: ------------------------------ 2008ms May 30 08:22:42 smsl-n100 volumio[980]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/250419900" took 8 milliseconds May 30 08:22:42 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 08:22:42 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand play May 30 08:22:42 smsl-n100 volumio[980]: info: May 30 08:22:42 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:42 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:42 smsl-n100 volumio[980]: STREAMING PROXY: Handling url /?data=qobuz://song/250419900 May 30 08:22:42 smsl-n100 volumio[980]: info: May 30 08:22:42 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:42 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:42 smsl-n100 volumio[980]: info: ------------------------------ 18ms May 30 08:22:42 smsl-n100 volumio[980]: info: sendMpdCommand play took 13 milliseconds May 30 08:22:42 smsl-n100 volumio[980]: info: ------------------------------ 11ms May 30 08:22:42 smsl-n100 volumio[980]: info: ------------------------------ 8ms May 30 08:22:42 smsl-n100 volumio[980]: info: Executing endpoint getStreamUrlqobuz May 30 08:22:42 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 30 08:22:45 smsl-n100 volumio[980]: info: getStreamUrl took 3017 milliseconds May 30 08:22:45 smsl-n100 volumio[980]: error: Could not get QOBUZ Stream URL: undefined May 30 08:22:45 smsl-n100 volumio[980]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} May 30 08:22:45 smsl-n100 volumio[980]: info: May 30 08:22:45 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:45 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:45 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:45 smsl-n100 volumio[980]: info: May 30 08:22:45 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:45 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:45 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:45 smsl-n100 volumio[980]: info: May 30 08:22:45 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:45 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:45 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:45 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:45 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:45 smsl-n100 volumio[980]: info: sendMpdCommand status took 24 milliseconds May 30 08:22:45 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:45 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:45 smsl-n100 volumio[980]: info: sendMpdCommand status took 23 milliseconds May 30 08:22:45 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:45 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:45 smsl-n100 volumio[980]: info: sendMpdCommand status took 20 milliseconds May 30 08:22:45 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:45 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:45 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:45 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:45 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:45 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:45 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 29 milliseconds May 30 08:22:45 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 27 milliseconds May 30 08:22:45 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 23 milliseconds May 30 08:22:45 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 11 milliseconds May 30 08:22:45 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 10 milliseconds May 30 08:22:45 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 9 milliseconds May 30 08:22:45 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:45 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:45 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:45 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:45 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:45 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:45 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 08:22:46 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:46 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:46 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:46 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 11 May 30 08:22:46 smsl-n100 volumio[980]: 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":"250419900","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419900","trackType":"qobuz"} May 30 08:22:46 smsl-n100 volumio[980]: verbose: CURRENT POSITION 11 May 30 08:22:46 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:46 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:46 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:46 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:46 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:46 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:46 smsl-n100 volumio[980]: info: No code May 30 08:22:46 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:46 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:46 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:46 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:46 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:46 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:46 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:46 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:46 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:46 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:46 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 11 May 30 08:22:46 smsl-n100 volumio[980]: 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":"250419900","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419900","trackType":"qobuz"} May 30 08:22:46 smsl-n100 volumio[980]: verbose: CURRENT POSITION 11 May 30 08:22:46 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:46 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:46 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:46 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:46 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:46 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:46 smsl-n100 volumio[980]: info: No code May 30 08:22:46 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:46 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:46 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:46 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:46 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:46 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:46 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:46 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:46 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:46 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:46 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 11 May 30 08:22:46 smsl-n100 volumio[980]: 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":"250419900","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419900","trackType":"qobuz"} May 30 08:22:46 smsl-n100 volumio[980]: verbose: CURRENT POSITION 11 May 30 08:22:46 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:46 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:46 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:46 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:46 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:46 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:46 smsl-n100 volumio[980]: info: No code May 30 08:22:46 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:46 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:46 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:46 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:46 smsl-n100 volumio[980]: info: ------------------------------ 219ms May 30 08:22:46 smsl-n100 volumio[980]: info: ------------------------------ 216ms May 30 08:22:46 smsl-n100 volumio[980]: info: ------------------------------ 212ms May 30 08:22:46 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:46 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:46 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:46 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:46 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:46 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:46 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:46 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:46 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:46 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:46 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:46 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:46 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:46 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:46 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:46 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:46 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:46 smsl-n100 volumio[980]: info: Moving to next track after playback error May 30 08:22:46 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPlay May 30 08:22:46 smsl-n100 volumio[980]: info: CoreStateMachine::play index 12 May 30 08:22:46 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:46 smsl-n100 volumio[980]: info: CoreStateMachine::stop May 30 08:22:46 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:46 smsl-n100 volumio[980]: info: CoreStateMachine::play index undefined May 30 08:22:46 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:46 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 12 May 30 08:22:46 smsl-n100 volumio[980]: info: CoreStateMachine::startPlaybackTimer May 30 08:22:46 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 12 May 30 08:22:46 smsl-n100 volumio[980]: info: [1748560966969] ControllerQobuz::clearAddPlayTrack May 30 08:22:46 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand stop May 30 08:22:46 smsl-n100 volumio[980]: info: sendMpdCommand stop took 1 milliseconds May 30 08:22:46 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clear May 30 08:22:46 smsl-n100 volumio[980]: info: May 30 08:22:46 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:46 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:46 smsl-n100 volumio[980]: info: sendMpdCommand clear took 4 milliseconds May 30 08:22:46 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/250419901" May 30 08:22:46 smsl-n100 volumio[980]: info: May 30 08:22:46 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:46 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:46 smsl-n100 volumio[980]: STREAMING PROXY: Handling url /?data=qobuz://song/250419901 May 30 08:22:46 smsl-n100 volumio[980]: info: May 30 08:22:46 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:46 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:46 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:46 smsl-n100 volumio[980]: info: ------------------------------ 15ms May 30 08:22:47 smsl-n100 volumio[980]: info: Executing endpoint getStreamUrlqobuz May 30 08:22:47 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 30 08:22:48 smsl-n100 volumio[980]: info: getStreamUrl took 1973 milliseconds May 30 08:22:48 smsl-n100 volumio[980]: error: Could not get QOBUZ Stream URL: undefined May 30 08:22:48 smsl-n100 volumio[980]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} May 30 08:22:48 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/250419901" May 30 08:22:48 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:48 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:48 smsl-n100 volumio[980]: info: May 30 08:22:48 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:48 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:48 smsl-n100 volumio[980]: info: ------------------------------ 2017ms May 30 08:22:49 smsl-n100 volumio[980]: info: ------------------------------ 2014ms May 30 08:22:49 smsl-n100 volumio[980]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/250419901" took 9 milliseconds May 30 08:22:49 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 08:22:49 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand play May 30 08:22:49 smsl-n100 volumio[980]: info: May 30 08:22:49 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:49 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:49 smsl-n100 volumio[980]: STREAMING PROXY: Handling url /?data=qobuz://song/250419901 May 30 08:22:49 smsl-n100 volumio[980]: info: May 30 08:22:49 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:49 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:49 smsl-n100 volumio[980]: info: ------------------------------ 19ms May 30 08:22:49 smsl-n100 volumio[980]: info: sendMpdCommand play took 14 milliseconds May 30 08:22:49 smsl-n100 volumio[980]: info: ------------------------------ 12ms May 30 08:22:49 smsl-n100 volumio[980]: info: ------------------------------ 8ms May 30 08:22:49 smsl-n100 volumio[980]: info: Executing endpoint getStreamUrlqobuz May 30 08:22:49 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 30 08:22:52 smsl-n100 volumio[980]: info: getStreamUrl took 3015 milliseconds May 30 08:22:52 smsl-n100 volumio[980]: error: Could not get QOBUZ Stream URL: undefined May 30 08:22:52 smsl-n100 volumio[980]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} May 30 08:22:52 smsl-n100 volumio[980]: info: May 30 08:22:52 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:52 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:52 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:52 smsl-n100 volumio[980]: info: May 30 08:22:52 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:52 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:52 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:52 smsl-n100 volumio[980]: info: May 30 08:22:52 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:52 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:52 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:52 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:52 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:52 smsl-n100 volumio[980]: info: sendMpdCommand status took 21 milliseconds May 30 08:22:52 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:52 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:52 smsl-n100 volumio[980]: info: sendMpdCommand status took 29 milliseconds May 30 08:22:52 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:52 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:52 smsl-n100 volumio[980]: info: sendMpdCommand status took 29 milliseconds May 30 08:22:52 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:52 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:52 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:52 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:52 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:52 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:52 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 44 milliseconds May 30 08:22:52 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 33 milliseconds May 30 08:22:52 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 30 milliseconds May 30 08:22:52 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 14 milliseconds May 30 08:22:52 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 13 milliseconds May 30 08:22:52 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 12 milliseconds May 30 08:22:52 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:52 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:52 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:52 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:52 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:52 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:52 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 08:22:52 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:52 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:52 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:52 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 12 May 30 08:22:52 smsl-n100 volumio[980]: 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":"250419901","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419901","trackType":"qobuz"} May 30 08:22:52 smsl-n100 volumio[980]: verbose: CURRENT POSITION 12 May 30 08:22:52 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:52 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:52 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:52 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:52 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:52 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:52 smsl-n100 volumio[980]: info: No code May 30 08:22:52 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:52 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:52 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:52 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:52 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:52 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:52 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:52 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:52 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:52 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:52 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 12 May 30 08:22:52 smsl-n100 volumio[980]: 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":"250419901","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419901","trackType":"qobuz"} May 30 08:22:52 smsl-n100 volumio[980]: verbose: CURRENT POSITION 12 May 30 08:22:52 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:52 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:52 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:52 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:52 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:52 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:52 smsl-n100 volumio[980]: info: No code May 30 08:22:52 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:52 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:52 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:52 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:52 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:52 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:52 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:52 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:52 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:52 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:52 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 12 May 30 08:22:52 smsl-n100 volumio[980]: 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":"250419901","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419901","trackType":"qobuz"} May 30 08:22:52 smsl-n100 volumio[980]: verbose: CURRENT POSITION 12 May 30 08:22:52 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:52 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:52 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:52 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:52 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:52 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:52 smsl-n100 volumio[980]: info: No code May 30 08:22:52 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:52 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:52 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:52 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:52 smsl-n100 volumio[980]: info: ------------------------------ 228ms May 30 08:22:52 smsl-n100 volumio[980]: info: ------------------------------ 227ms May 30 08:22:52 smsl-n100 volumio[980]: info: ------------------------------ 224ms May 30 08:22:52 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:52 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:52 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:52 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:52 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:52 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:52 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:52 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:52 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:52 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:52 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:52 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:52 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:52 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:52 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:52 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:52 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:53 smsl-n100 volumio[980]: info: Moving to next track after playback error May 30 08:22:53 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPlay May 30 08:22:53 smsl-n100 volumio[980]: info: CoreStateMachine::play index 13 May 30 08:22:53 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:53 smsl-n100 volumio[980]: info: CoreStateMachine::stop May 30 08:22:53 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:53 smsl-n100 volumio[980]: info: CoreStateMachine::play index undefined May 30 08:22:53 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:53 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 13 May 30 08:22:53 smsl-n100 volumio[980]: info: CoreStateMachine::startPlaybackTimer May 30 08:22:53 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 13 May 30 08:22:53 smsl-n100 volumio[980]: info: [1748560973113] ControllerQobuz::clearAddPlayTrack May 30 08:22:53 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand stop May 30 08:22:53 smsl-n100 volumio[980]: info: sendMpdCommand stop took 2 milliseconds May 30 08:22:53 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clear May 30 08:22:53 smsl-n100 volumio[980]: info: May 30 08:22:53 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:53 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:53 smsl-n100 volumio[980]: info: sendMpdCommand clear took 4 milliseconds May 30 08:22:53 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/250419902" May 30 08:22:53 smsl-n100 volumio[980]: info: May 30 08:22:53 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:53 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:53 smsl-n100 volumio[980]: STREAMING PROXY: Handling url /?data=qobuz://song/250419902 May 30 08:22:53 smsl-n100 volumio[980]: info: May 30 08:22:53 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:53 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:53 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:53 smsl-n100 volumio[980]: info: ------------------------------ 14ms May 30 08:22:53 smsl-n100 volumio[980]: info: Executing endpoint getStreamUrlqobuz May 30 08:22:53 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 30 08:22:54 smsl-n100 ntpd[966]: Soliciting pool server 103.131.151.20 May 30 08:22:55 smsl-n100 volumio[980]: info: getStreamUrl took 1970 milliseconds May 30 08:22:55 smsl-n100 volumio[980]: error: Could not get QOBUZ Stream URL: undefined May 30 08:22:55 smsl-n100 volumio[980]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} May 30 08:22:55 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:55 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:55 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/250419902" May 30 08:22:55 smsl-n100 volumio[980]: info: ------------------------------ 2016ms May 30 08:22:55 smsl-n100 volumio[980]: info: ------------------------------ 2013ms May 30 08:22:55 smsl-n100 volumio[980]: info: May 30 08:22:55 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:55 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:55 smsl-n100 volumio[980]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/250419902" took 7 milliseconds May 30 08:22:55 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 08:22:55 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand play May 30 08:22:55 smsl-n100 ntpd[966]: Soliciting pool server 162.159.200.1 May 30 08:22:55 smsl-n100 volumio[980]: info: May 30 08:22:55 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:55 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:55 smsl-n100 volumio[980]: info: May 30 08:22:55 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:55 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:55 smsl-n100 volumio[980]: STREAMING PROXY: Handling url /?data=qobuz://song/250419902 May 30 08:22:55 smsl-n100 volumio[980]: info: ------------------------------ 19ms May 30 08:22:55 smsl-n100 volumio[980]: info: sendMpdCommand play took 15 milliseconds May 30 08:22:55 smsl-n100 volumio[980]: info: ------------------------------ 13ms May 30 08:22:55 smsl-n100 volumio[980]: info: ------------------------------ 10ms May 30 08:22:55 smsl-n100 volumio[980]: info: Executing endpoint getStreamUrlqobuz May 30 08:22:55 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 30 08:22:57 smsl-n100 ntpd[966]: Soliciting pool server 122.215.240.52 May 30 08:22:58 smsl-n100 volumio[980]: info: getStreamUrl took 3009 milliseconds May 30 08:22:58 smsl-n100 volumio[980]: error: Could not get QOBUZ Stream URL: undefined May 30 08:22:58 smsl-n100 volumio[980]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} May 30 08:22:58 smsl-n100 volumio[980]: info: May 30 08:22:58 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:58 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:58 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:58 smsl-n100 volumio[980]: info: May 30 08:22:58 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:58 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:58 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:58 smsl-n100 volumio[980]: info: May 30 08:22:58 smsl-n100 volumio[980]: ---------------------------- MPD announces state update: player May 30 08:22:58 smsl-n100 volumio[980]: info: ControllerMpd::getState May 30 08:22:58 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand status May 30 08:22:58 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:58 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:58 smsl-n100 volumio[980]: info: sendMpdCommand status took 21 milliseconds May 30 08:22:58 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:58 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:58 smsl-n100 volumio[980]: info: sendMpdCommand status took 21 milliseconds May 30 08:22:58 smsl-n100 volumio[980]: error: MPD Playback Error: Failed to decode stream, moving to next track May 30 08:22:58 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 08:22:58 smsl-n100 volumio[980]: info: sendMpdCommand status took 19 milliseconds May 30 08:22:58 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:58 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:58 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:58 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:58 smsl-n100 volumio[980]: verbose: ControllerMpd::parseState May 30 08:22:58 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 08:22:58 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 34 milliseconds May 30 08:22:58 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 31 milliseconds May 30 08:22:58 smsl-n100 volumio[980]: info: sendMpdCommand clearerror took 29 milliseconds May 30 08:22:58 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 11 milliseconds May 30 08:22:58 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 9 milliseconds May 30 08:22:58 smsl-n100 volumio[980]: info: sendMpdCommand playlistinfo took 8 milliseconds May 30 08:22:58 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:58 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:58 smsl-n100 volumio[980]: verbose: ControllerMpd::parseTrackInfo May 30 08:22:58 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:58 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:58 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:58 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 08:22:58 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:58 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:58 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:58 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 13 May 30 08:22:58 smsl-n100 volumio[980]: 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":"250419902","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419902","trackType":"qobuz"} May 30 08:22:58 smsl-n100 volumio[980]: verbose: CURRENT POSITION 13 May 30 08:22:58 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:58 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:58 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:58 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:58 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:58 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:58 smsl-n100 volumio[980]: info: No code May 30 08:22:58 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:58 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:58 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:58 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:58 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:58 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:58 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:58 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:58 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:58 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:58 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 13 May 30 08:22:58 smsl-n100 volumio[980]: 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":"250419902","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419902","trackType":"qobuz"} May 30 08:22:58 smsl-n100 volumio[980]: verbose: CURRENT POSITION 13 May 30 08:22:58 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:58 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:58 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:58 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:58 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:58 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:58 smsl-n100 volumio[980]: info: No code May 30 08:22:58 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:58 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:58 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:58 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:58 smsl-n100 volumio[980]: info: ControllerMpd::pushState May 30 08:22:58 smsl-n100 volumio[980]: info: CoreCommandRouter::servicePushState May 30 08:22:58 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:58 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:58 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:58 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:58 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 13 May 30 08:22:58 smsl-n100 volumio[980]: 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":"250419902","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/250419902","trackType":"qobuz"} May 30 08:22:58 smsl-n100 volumio[980]: verbose: CURRENT POSITION 13 May 30 08:22:58 smsl-n100 volumio[980]: info: CoreStateMachine::syncState stateService stop May 30 08:22:58 smsl-n100 volumio[980]: info: CoreStateMachine::syncState currentStatus stop May 30 08:22:58 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:58 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:58 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:58 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:58 smsl-n100 volumio[980]: info: No code May 30 08:22:58 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:22:58 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:22:58 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:22:58 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:22:58 smsl-n100 volumio[980]: info: ------------------------------ 232ms May 30 08:22:58 smsl-n100 volumio[980]: info: ------------------------------ 230ms May 30 08:22:58 smsl-n100 volumio[980]: info: ------------------------------ 229ms May 30 08:22:58 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:58 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:58 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:58 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:58 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:58 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:58 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:58 smsl-n100 volumio[980]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 30 08:22:58 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:58 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:58 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:58 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:58 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:58 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:58 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:58 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:58 smsl-n100 volumio[980]: info: Updating RAAT Signal Path May 30 08:22:59 smsl-n100 volumio[980]: info: Moving to next track after playback error May 30 08:22:59 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPlay May 30 08:22:59 smsl-n100 volumio[980]: info: CoreStateMachine::play index 14 May 30 08:22:59 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:59 smsl-n100 volumio[980]: info: CoreStateMachine::stop May 30 08:22:59 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:59 smsl-n100 volumio[980]: info: CoreStateMachine::play index undefined May 30 08:22:59 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 08:22:59 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 14 May 30 08:22:59 smsl-n100 volumio[980]: info: CoreStateMachine::startPlaybackTimer May 30 08:22:59 smsl-n100 volumio[980]: info: CorePlayQueue::getTrack 14 May 30 08:22:59 smsl-n100 volumio[980]: info: [1748560979247] ControllerQobuz::clearAddPlayTrack May 30 08:22:59 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand stop May 30 08:22:59 smsl-n100 volumio[980]: info: sendMpdCommand stop took 1 milliseconds May 30 08:22:59 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand clear May 30 08:22:59 smsl-n100 volumio[980]: info: May 30 08:22:59 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:59 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:59 smsl-n100 volumio[980]: info: sendMpdCommand clear took 5 milliseconds May 30 08:22:59 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/250419903" May 30 08:22:59 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:22:59 smsl-n100 volumio[980]: info: ------------------------------ 8ms May 30 08:22:59 smsl-n100 volumio[980]: info: May 30 08:22:59 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:59 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:59 smsl-n100 volumio[980]: STREAMING PROXY: Handling url /?data=qobuz://song/250419903 May 30 08:22:59 smsl-n100 volumio[980]: info: May 30 08:22:59 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:22:59 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:22:59 smsl-n100 volumio[980]: info: Executing endpoint getStreamUrlqobuz May 30 08:22:59 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 30 08:23:01 smsl-n100 volumio[980]: info: getStreamUrl took 1977 milliseconds May 30 08:23:01 smsl-n100 volumio[980]: error: Could not get QOBUZ Stream URL: undefined May 30 08:23:01 smsl-n100 volumio[980]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get QOBUZ Stream URL: undefined"} May 30 08:23:01 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/250419903" May 30 08:23:01 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:23:01 smsl-n100 volumio[980]: error: updateQueue error: null May 30 08:23:01 smsl-n100 volumio[980]: info: May 30 08:23:01 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:23:01 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:23:01 smsl-n100 volumio[980]: info: ------------------------------ 2023ms May 30 08:23:01 smsl-n100 volumio[980]: info: ------------------------------ 2019ms May 30 08:23:01 smsl-n100 volumio[980]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/250419903" took 10 milliseconds May 30 08:23:01 smsl-n100 volumio[980]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 08:23:01 smsl-n100 volumio[980]: verbose: ControllerMpd::sendMpdCommand play May 30 08:23:01 smsl-n100 volumio[980]: info: May 30 08:23:01 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:23:01 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:23:01 smsl-n100 volumio[980]: STREAMING PROXY: Handling url /?data=qobuz://song/250419903 May 30 08:23:01 smsl-n100 volumio[980]: info: May 30 08:23:01 smsl-n100 volumio[980]: ---------------------------- MPD announces system playlist update May 30 08:23:01 smsl-n100 volumio[980]: info: Ignoring MPD Status Update May 30 08:23:01 smsl-n100 volumio[980]: info: ------------------------------ 19ms May 30 08:23:01 smsl-n100 volumio[980]: info: sendMpdCommand play took 13 milliseconds May 30 08:23:01 smsl-n100 volumio[980]: info: ------------------------------ 10ms May 30 08:23:01 smsl-n100 volumio[980]: info: ------------------------------ 13ms May 30 08:23:01 smsl-n100 volumio[980]: info: Executing endpoint getStreamUrlqobuz May 30 08:23:01 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 30 08:23:01 smsl-n100 volumio[980]: info: CALLMETHOD: system_controller network saveDnsSettings [object Object] May 30 08:23:01 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , saveDnsSettings May 30 08:23:01 smsl-n100 sudo[2335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /etc/resolv.conf.tail.tmpl /etc/resolv.conf.tail May 30 08:23:01 smsl-n100 sudo[2335]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:01 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 30 08:23:01 smsl-n100 volumio[980]: info: Discovery: Restarting Advertising due to device name change May 30 08:23:01 smsl-n100 ntpd[966]: Soliciting pool server 2400:8902::f03c:91ff:fe50:f5f0 May 30 08:23:01 smsl-n100 sudo[2335]: pam_unix(sudo:session): session closed for user root May 30 08:23:01 smsl-n100 sudo[2338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service May 30 08:23:01 smsl-n100 sudo[2338]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:01 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 30 08:23:01 smsl-n100 volumio[980]: info: Discovery: Restarting Advertising due to device name change May 30 08:23:01 smsl-n100 systemd[1]: Stopping Wireless Services... May 30 08:23:01 smsl-n100 wpa_supplicant[842]: wlan0: CTRL-EVENT-DISCONNECTED bssid=98:da:c4:61:d8:cd reason=3 locally_generated=1 May 30 08:23:01 smsl-n100 dhcpcd[967]: wlan0: carrier lost May 30 08:23:01 smsl-n100 sudo[2340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ip addr flush dev eth0 May 30 08:23:01 smsl-n100 volumio[980]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 08:23:01 smsl-n100 sudo[2340]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:01 smsl-n100 avahi-daemon[553]: Withdrawing address record for 169.254.31.227 on eth0. May 30 08:23:01 smsl-n100 avahi-daemon[553]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.31.227. May 30 08:23:01 smsl-n100 avahi-daemon[553]: Interface eth0.IPv4 no longer relevant for mDNS. May 30 08:23:01 smsl-n100 wpa_supplicant[842]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 May 30 08:23:01 smsl-n100 sudo[2340]: pam_unix(sudo:session): session closed for user root May 30 08:23:01 smsl-n100 volumio[980]: info: Discovery: A device disappeared from network May 30 08:23:01 smsl-n100 wpa_supplicant[842]: wlan0: CTRL-EVENT-TERMINATING May 30 08:23:01 smsl-n100 avahi-daemon[553]: Interface wlan0.IPv4 no longer relevant for mDNS. May 30 08:23:01 smsl-n100 avahi-daemon[553]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.107. May 30 08:23:01 smsl-n100 avahi-daemon[553]: Withdrawing address record for 192.168.0.107 on wlan0. May 30 08:23:01 smsl-n100 systemd[1]: wireless.service: Succeeded. May 30 08:23:01 smsl-n100 volumio[980]: info: Discovery: A device disappeared from network May 30 08:23:01 smsl-n100 systemd[1]: Stopped Wireless Services. May 30 08:23:01 smsl-n100 volumio[980]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 30 08:23:01 smsl-n100 systemd[1]: Starting Wireless Services... May 30 08:23:01 smsl-n100 sudo[2349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 down May 30 08:23:01 smsl-n100 sudo[2349]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:01 smsl-n100 volumio[980]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 08:23:02 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Down May 30 08:23:02 smsl-n100 dhcpcd[1048]: eth0: carrier lost May 30 08:23:02 smsl-n100 sudo[2349]: pam_unix(sudo:session): session closed for user root May 30 08:23:02 smsl-n100 dhcpcd[967]: eth0: adding default route May 30 08:23:02 smsl-n100 dhcpcd[967]: wlan0: deleting route to 192.168.0.0/24 May 30 08:23:02 smsl-n100 dhcpcd[967]: wlan0: deleting default route via 192.168.0.1 May 30 08:23:02 smsl-n100 sudo[2363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 up May 30 08:23:02 smsl-n100 sudo[2363]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:02 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: PHY [stmmac-0:01] driver [Generic PHY] May 30 08:23:02 smsl-n100 dhcpcd[967]: dhcp_readpacket: eth0: Network is down May 30 08:23:02 smsl-n100 dhcpcd[967]: arp_read: eth0: Network is down May 30 08:23:02 smsl-n100 dhcpcd[967]: eth0: carrier lost May 30 08:23:02 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: No Safety Features support found May 30 08:23:02 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: PTP not supported by HW May 30 08:23:02 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: configuring for phy/rgmii link mode May 30 08:23:02 smsl-n100 sudo[2363]: pam_unix(sudo:session): session closed for user root May 30 08:23:02 smsl-n100 dhcpcd[1048]: eth0: deleting route to 169.254.0.0/16 May 30 08:23:02 smsl-n100 dhcpcd[1048]: eth0: deleting default route May 30 08:23:02 smsl-n100 dhcpcd[967]: eth0: deleting route to 169.254.0.0/16 May 30 08:23:02 smsl-n100 dhcpcd[967]: eth0: deleting default route May 30 08:23:02 smsl-n100 dhcpcd[1048]: eth0: carrier acquired May 30 08:23:02 smsl-n100 dhcpcd[1048]: eth0: IAID a8:77:eb:62 May 30 08:23:02 smsl-n100 dhcpcd[967]: eth0: carrier acquired May 30 08:23:02 smsl-n100 dhcpcd[967]: eth0: IAID a8:77:eb:62 May 30 08:23:02 smsl-n100 ifplugd(eth0)[758]: Link beat lost. May 30 08:23:02 smsl-n100 dhcpcd[1048]: eth0: soliciting a DHCP lease May 30 08:23:02 smsl-n100 dhcpcd[967]: eth0: soliciting an IPv6 router May 30 08:23:02 smsl-n100 dhcpcd[1048]: eth0: soliciting an IPv6 router May 30 08:23:02 smsl-n100 dhcpcd[967]: eth0: carrier lost May 30 08:23:02 smsl-n100 dhcpcd[1048]: eth0: carrier lost May 30 08:23:03 smsl-n100 wireless.js[2354]: Cleaning previous... May 30 08:23:03 smsl-n100 sudo[2472]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 30 08:23:03 smsl-n100 sudo[2472]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:03 smsl-n100 sudo[2472]: pam_unix(sudo:session): session closed for user root May 30 08:23:03 smsl-n100 sudo[2474]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 30 08:23:03 smsl-n100 sudo[2474]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:03 smsl-n100 sudo[2474]: pam_unix(sudo:session): session closed for user root May 30 08:23:03 smsl-n100 wireless.js[2354]: Stopped aP May 30 08:23:03 smsl-n100 sudo[2481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 30 08:23:03 smsl-n100 sudo[2481]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:03 smsl-n100 sudo[2481]: pam_unix(sudo:session): session closed for user root May 30 08:23:03 smsl-n100 sudo[2483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 30 08:23:03 smsl-n100 sudo[2483]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:03 smsl-n100 sudo[2483]: pam_unix(sudo:session): session closed for user root May 30 08:23:03 smsl-n100 sudo[2492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 30 08:23:03 smsl-n100 sudo[2492]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:03 smsl-n100 sudo[2492]: pam_unix(sudo:session): session closed for user root May 30 08:23:03 smsl-n100 sudo[2494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 30 08:23:03 smsl-n100 sudo[2494]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:03 smsl-n100 dhcpcd[967]: wlan0: carrier acquired May 30 08:23:03 smsl-n100 dhcpcd[967]: wlan0: IAID 2b:ee:c7:8d May 30 08:23:04 smsl-n100 dhcpcd[967]: wlan0: soliciting a DHCP lease May 30 08:23:04 smsl-n100 volumio[980]: info: Reporting MCU Network Status: 0 May 30 08:23:04 smsl-n100 volumio[980]: info: Volumio Network Manager: Network status updated: 0 May 30 08:23:04 smsl-n100 volumio[980]: info: CoreStateMachine::pushState May 30 08:23:04 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 08:23:04 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioPushState May 30 08:23:04 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output update for this device May 30 08:23:04 smsl-n100 volumio[980]: info: MRS: Pushing multiroomSync output May 30 08:23:04 smsl-n100 volumio[980]: error: Serial API: Failed to decode command: 100, message: undefined May 30 08:23:04 smsl-n100 volumio[980]: error: Serial API: Failed to decode command: MAXVOL, message: May 30 08:23:04 smsl-n100 volumio[980]: error: Serial API: Failed to decode command: 100, message: undefined May 30 08:23:04 smsl-n100 volumio[980]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 30 08:23:04 smsl-n100 volumio[980]: error: Serial API: Failed to decode command: 0, message: undefined May 30 08:23:04 smsl-n100 dhcpcd[967]: wlan0: soliciting an IPv6 router May 30 08:23:05 smsl-n100 ntpd[966]: Deleting interface #3 wlan0, 192.168.0.107#123, interface stats: received=6, sent=26, dropped=0, active_time=213 secs May 30 08:23:05 smsl-n100 ntpd[966]: 129.250.35.250 local addr 192.168.0.107 -> May 30 08:23:05 smsl-n100 ntpd[966]: 202.181.103.212 local addr 192.168.0.107 -> May 30 08:23:05 smsl-n100 ntpd[966]: Deleting interface #4 eth0, 169.254.31.227#123, interface stats: received=0, sent=19, dropped=0, active_time=208 secs May 30 08:23:05 smsl-n100 ntpd[966]: 172.237.12.44 local addr 169.254.31.227 -> May 30 08:23:05 smsl-n100 ntpd[966]: 162.159.200.123 local addr 169.254.31.227 -> May 30 08:23:06 smsl-n100 volumio[980]: info: Discovery: Started advertising with name: SMSL N100 May 30 08:23:06 smsl-n100 volumio[980]: info: Discovery: Started advertising with name: SMSL N100 May 30 08:23:08 smsl-n100 sudo[2494]: pam_unix(sudo:session): session closed for user root May 30 08:23:08 smsl-n100 wireless.js[2354]: Start wireless flow May 30 08:23:08 smsl-n100 wireless.js[2354]: Stopped hotspot (if there).. May 30 08:23:08 smsl-n100 sudo[2524]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 30 08:23:08 smsl-n100 sudo[2524]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:08 smsl-n100 sudo[2524]: pam_unix(sudo:session): session closed for user root May 30 08:23:08 smsl-n100 sudo[2526]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 30 08:23:08 smsl-n100 sudo[2526]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:08 smsl-n100 dhcpcd[967]: wlan0: carrier lost May 30 08:23:08 smsl-n100 sudo[2526]: pam_unix(sudo:session): session closed for user root May 30 08:23:08 smsl-n100 wireless.js[2354]: DHCP IP May 30 08:23:08 smsl-n100 wireless.js[2354]: Start ap May 30 08:23:08 smsl-n100 wpa_supplicant[2531]: Successfully initialized wpa_supplicant May 30 08:23:08 smsl-n100 sudo[2535]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd May 30 08:23:08 smsl-n100 sudo[2535]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:08 smsl-n100 dhcpcd[967]: wlan0: carrier acquired May 30 08:23:08 smsl-n100 dhcpcd[2544]: sending commands to master dhcpcd process May 30 08:23:08 smsl-n100 sudo[2535]: pam_unix(sudo:session): session closed for user root May 30 08:23:08 smsl-n100 dhcpcd[967]: wlan0: IAID 2b:ee:c7:8d May 30 08:23:08 smsl-n100 dhcpcd[967]: wlan0: carrier lost May 30 08:23:08 smsl-n100 dhcpcd[967]: control command: /sbin/dhcpcd May 30 08:23:09 smsl-n100 wireless.js[2354]: trying... May 30 08:23:09 smsl-n100 sudo[2557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 30 08:23:09 smsl-n100 sudo[2557]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:09 smsl-n100 sudo[2557]: pam_unix(sudo:session): session closed for user root May 30 08:23:10 smsl-n100 wireless.js[2354]: trying... May 30 08:23:10 smsl-n100 sudo[2562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 30 08:23:10 smsl-n100 sudo[2562]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:10 smsl-n100 sudo[2562]: pam_unix(sudo:session): session closed for user root May 30 08:23:11 smsl-n100 wireless.js[2354]: trying... May 30 08:23:11 smsl-n100 sudo[2605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 30 08:23:11 smsl-n100 sudo[2605]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:11 smsl-n100 sudo[2605]: pam_unix(sudo:session): session closed for user root May 30 08:23:12 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart May 30 08:23:12 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart May 30 08:23:12 smsl-n100 volumio[980]: info: Refreshing Cached IP Addresses May 30 08:23:12 smsl-n100 wireless.js[2354]: trying... May 30 08:23:12 smsl-n100 sudo[2613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli May 30 08:23:12 smsl-n100 sudo[2613]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:12 smsl-n100 sudo[2613]: pam_unix(sudo:session): session closed for user root May 30 08:23:12 smsl-n100 sudo[2618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 30 08:23:12 smsl-n100 sudo[2618]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:12 smsl-n100 sudo[2617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 30 08:23:12 smsl-n100 sudo[2617]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:12 smsl-n100 ifplugd(eth0)[758]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. May 30 08:23:12 smsl-n100 sudo[2617]: pam_unix(sudo:session): session closed for user root May 30 08:23:12 smsl-n100 sudo[2618]: pam_unix(sudo:session): session closed for user root May 30 08:23:12 smsl-n100 systemd[1]: upmpdcli.service: Succeeded. May 30 08:23:12 smsl-n100 sudo[2621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 08:23:12 smsl-n100 sudo[2621]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:12 smsl-n100 sudo[2621]: pam_unix(sudo:session): session closed for user root May 30 08:23:12 smsl-n100 ifplugd(eth0)[758]: client: sending commands to master dhcpcd process May 30 08:23:12 smsl-n100 dhcpcd[2632]: sending commands to master dhcpcd process May 30 08:23:12 smsl-n100 dhcpcd[967]: control command: /sbin/dhcpcd -k eth0 May 30 08:23:12 smsl-n100 dhcpcd[967]: eth0: removing interface May 30 08:23:12 smsl-n100 ifplugd(eth0)[758]: Program executed successfully. May 30 08:23:12 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: PHY [stmmac-0:01] driver [Generic PHY] May 30 08:23:12 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: No Safety Features support found May 30 08:23:12 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: PTP not supported by HW May 30 08:23:12 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: configuring for phy/rgmii link mode May 30 08:23:13 smsl-n100 wireless.js[2354]: trying... May 30 08:23:13 smsl-n100 sudo[2653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 30 08:23:13 smsl-n100 sudo[2653]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:13 smsl-n100 sudo[2653]: pam_unix(sudo:session): session closed for user root May 30 08:23:13 smsl-n100 wpa_supplicant[2547]: wlan0: Trying to associate with 98:da:c4:61:d8:cd (SSID='TP-Link_4C84_5G_EXT' freq=5220 MHz) May 30 08:23:13 smsl-n100 wpa_supplicant[2547]: wlan0: Associated with 98:da:c4:61:d8:cd May 30 08:23:13 smsl-n100 wpa_supplicant[2547]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 30 08:23:14 smsl-n100 wpa_supplicant[2547]: wlan0: WPA: Key negotiation completed with 98:da:c4:61:d8:cd [PTK=CCMP GTK=TKIP] May 30 08:23:14 smsl-n100 wpa_supplicant[2547]: wlan0: CTRL-EVENT-CONNECTED - Connection to 98:da:c4:61:d8:cd completed [id=0 id_str=] May 30 08:23:14 smsl-n100 dhcpcd[967]: wlan0: carrier acquired May 30 08:23:14 smsl-n100 dhcpcd[967]: wlan0: IAID 2b:ee:c7:8d May 30 08:23:14 smsl-n100 dhcpcd[967]: wlan0: soliciting an IPv6 router May 30 08:23:14 smsl-n100 wireless.js[2354]: trying... May 30 08:23:14 smsl-n100 dhcpcd[967]: wlan0: soliciting a DHCP lease May 30 08:23:14 smsl-n100 sudo[2657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 30 08:23:14 smsl-n100 sudo[2657]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:14 smsl-n100 sudo[2657]: pam_unix(sudo:session): session closed for user root May 30 08:23:14 smsl-n100 dhcpcd[967]: wlan0: offered 192.168.0.107 from 192.168.0.1 May 30 08:23:14 smsl-n100 dhcpcd[967]: wlan0: probing address 192.168.0.107/24 May 30 08:23:15 smsl-n100 wireless.js[2354]: trying... May 30 08:23:15 smsl-n100 sudo[2674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 30 08:23:15 smsl-n100 sudo[2674]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:15 smsl-n100 sudo[2674]: pam_unix(sudo:session): session closed for user root May 30 08:23:16 smsl-n100 wireless.js[2354]: trying... May 30 08:23:16 smsl-n100 sudo[2677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 30 08:23:16 smsl-n100 sudo[2677]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:16 smsl-n100 sudo[2677]: pam_unix(sudo:session): session closed for user root May 30 08:23:17 smsl-n100 wireless.js[2354]: trying... May 30 08:23:17 smsl-n100 sudo[2680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 30 08:23:17 smsl-n100 sudo[2680]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:17 smsl-n100 sudo[2680]: pam_unix(sudo:session): session closed for user root May 30 08:23:18 smsl-n100 wireless.js[2354]: trying... May 30 08:23:18 smsl-n100 sudo[2683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 30 08:23:18 smsl-n100 sudo[2683]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:18 smsl-n100 sudo[2683]: pam_unix(sudo:session): session closed for user root May 30 08:23:19 smsl-n100 wireless.js[2354]: trying... May 30 08:23:19 smsl-n100 sudo[2686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 30 08:23:19 smsl-n100 sudo[2686]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:19 smsl-n100 sudo[2686]: pam_unix(sudo:session): session closed for user root May 30 08:23:20 smsl-n100 dhcpcd[967]: wlan0: leased 192.168.0.107 for 7200 seconds May 30 08:23:20 smsl-n100 avahi-daemon[553]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.107. May 30 08:23:20 smsl-n100 avahi-daemon[553]: New relevant interface wlan0.IPv4 for mDNS. May 30 08:23:20 smsl-n100 dhcpcd[967]: wlan0: adding route to 192.168.0.0/24 May 30 08:23:20 smsl-n100 avahi-daemon[553]: Registering new address record for 192.168.0.107 on wlan0.IPv4. May 30 08:23:20 smsl-n100 dhcpcd[967]: wlan0: adding default route via 192.168.0.1 May 30 08:23:20 smsl-n100 wireless.js[2354]: trying... May 30 08:23:20 smsl-n100 sudo[2698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 30 08:23:20 smsl-n100 sudo[2698]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:20 smsl-n100 sudo[2698]: pam_unix(sudo:session): session closed for user root May 30 08:23:20 smsl-n100 wireless.js[2354]: Connected to: ----TP-Link_4C84_5G_EXT May 30 08:23:20 smsl-n100 wireless.js[2354]: ---- May 30 08:23:20 smsl-n100 sudo[2713]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 08:23:20 smsl-n100 sudo[2713]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 08:23:20 smsl-n100 sudo[2713]: pam_unix(sudo:session): session closed for user root May 30 08:23:20 smsl-n100 wireless.js[2354]: ... joined AP, wlan0 IPv4 is 192.168.0.107, ipV6 is undefined May 30 08:23:20 smsl-n100 wireless.js[2354]: It's done! AP May 30 08:23:20 smsl-n100 volumio[980]: verbose: New Socket.io Connection to 192.168.0.107 from 192.168.0.113 UA: Mozilla/5.0 (Linux; Android 5.1.1; HWT31 Build/Federer; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/95.0.4638.74 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 30 08:23:20 smsl-n100 volumio[980]: verbose: New Socket.io Connection to 192.168.0.107 from 192.168.0.113 UA: Mozilla/5.0 (Linux; Android 5.1.1; HWT31 Build/Federer; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/95.0.4638.74 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 30 08:23:20 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 08:23:20 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 08:23:20 smsl-n100 volumio[980]: info: Discovery: Getting this device information May 30 08:23:20 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioGetState May 30 08:23:20 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 08:23:20 smsl-n100 iwconfig[2726]: Error for wireless request "Set Power Management" (8B2C) : May 30 08:23:20 smsl-n100 iwconfig[2726]: SET failed on device wlan0 ; Operation not permitted. May 30 08:23:20 smsl-n100 systemd[1]: Started Wireless Services. May 30 08:23:20 smsl-n100 sudo[2338]: pam_unix(sudo:session): session closed for user root May 30 08:23:20 smsl-n100 ntpd[966]: ntpd exiting on signal 15 (Terminated) May 30 08:23:20 smsl-n100 systemd[1]: Stopping Network Time Service... May 30 08:23:20 smsl-n100 systemd[1]: ntp.service: Succeeded. May 30 08:23:20 smsl-n100 systemd[1]: Stopped Network Time Service. May 30 08:23:20 smsl-n100 volumio[980]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 08:23:20 smsl-n100 systemd[1]: Starting Network Time Service... May 30 08:23:20 smsl-n100 volumio[980]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 30 08:23:20 smsl-n100 volumio[980]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 08:23:20 smsl-n100 ntpd[2731]: ntpd 4.2.8p12@1.3728-o (1): Starting May 30 08:23:20 smsl-n100 ntpd[2731]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 30 08:23:20 smsl-n100 systemd[1]: Started Network Time Service. May 30 08:23:20 smsl-n100 ntpd[2737]: proto: precision = 1.166 usec (-20) May 30 08:23:20 smsl-n100 ntpd[2737]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 30 08:23:20 smsl-n100 ntpd[2737]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 30 08:23:20 smsl-n100 ntpd[2737]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 884 days ago May 30 08:23:20 smsl-n100 ntpd[2737]: Listen and drop on 0 v6wildcard [::]:123 May 30 08:23:20 smsl-n100 ntpd[2737]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 30 08:23:20 smsl-n100 ntpd[2737]: Listen normally on 2 lo 127.0.0.1:123 May 30 08:23:20 smsl-n100 ntpd[2737]: Listen normally on 3 wlan0 192.168.0.107:123 May 30 08:23:20 smsl-n100 ntpd[2737]: Listening on routing socket on fd #20 for interface updates May 30 08:23:20 smsl-n100 ntpd[2737]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized May 30 08:23:20 smsl-n100 ntpd[2737]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized May 30 08:23:20 smsl-n100 volumio[980]: verbose: New Socket.io Connection to 192.168.0.107 from 192.168.0.113 UA: Mozilla/5.0 (Linux; Android 5.1.1; HWT31 Build/Federer; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/95.0.4638.74 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 May 30 08:23:20 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioGetState May 30 08:23:20 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 30 08:23:20 smsl-n100 volumio[980]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 30 08:23:20 smsl-n100 volumio[980]: info: Listing playlists May 30 08:23:20 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioGetVisibleSources May 30 08:23:20 smsl-n100 volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 08:23:20 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioGetQueue May 30 08:23:20 smsl-n100 volumio[980]: info: CoreStateMachine::getQueue May 30 08:23:20 smsl-n100 volumio[980]: info: CorePlayQueue::getQueue May 30 08:23:21 smsl-n100 volumio[980]: info: Discovery: adding fcd6295e-1b94-4f49-8ee8-fa83eb0d356e May 30 08:23:21 smsl-n100 volumio[980]: info: Discovery: Found device SMSL N100 May 30 08:23:21 smsl-n100 volumio[980]: info: CoreCommandRouter::volumioGetState May 30 08:23:21 smsl-n100 volumio[980]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 08:23:21 smsl-n100 volumio[980]: Error: The value NaN is not a number May 30 08:23:21 smsl-n100 volumio[980]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) May 30 08:23:21 smsl-n100 volumio[980]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) May 30 08:23:21 smsl-n100 volumio[980]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) May 30 08:23:21 smsl-n100 volumio[980]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) May 30 08:23:21 smsl-n100 volumio[980]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) May 30 08:23:21 smsl-n100 volumio[980]: at Browser.emit (events.js:400:28) May 30 08:23:21 smsl-n100 volumio[980]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) May 30 08:23:21 smsl-n100 volumio[980]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) May 30 08:23:21 smsl-n100 volumio[980]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) May 30 08:23:21 smsl-n100 volumio[980]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) May 30 08:23:21 smsl-n100 volumio[980]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 May 30 08:23:21 smsl-n100 volumio[980]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) May 30 08:23:21 smsl-n100 volumio[980]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) May 30 08:23:21 smsl-n100 volumio[980]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 08:23:21 smsl-n100 ntpd[2737]: Soliciting pool server 162.159.200.123 May 30 08:23:22 smsl-n100 sudo[2751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-30 08:22 May 30 08:23:22 smsl-n100 sudo[2751]: 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"