-- Logs begin at Mon 2024-03-11 17:55:33 MSK, end at Mon 2024-04-15 16:48:05 MSK. -- Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioNext Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreStateMachine::next Apr 15 16:47:01 volumio-1 volumio[21416]: info: [1713188821786] ControllerQobuz::next Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreStateMachine::next Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreStateMachine::stop Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreStateMachine::stPlaybackTimer Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreStateMachine::updateTrackBlock Apr 15 16:47:01 volumio-1 volumio[21416]: info: CorePlayQueue::getTrackBlock Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:01 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 16 Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreStateMachine::serviceStop Apr 15 16:47:01 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 16 Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreCommandRouter::serviceStop Apr 15 16:47:01 volumio-1 volumio[21416]: info: [1713188821793] ControllerQobuz::stop Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 15 16:47:01 volumio-1 volumio[21416]: info: ControllerMpd::stop Apr 15 16:47:01 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 16:47:01 volumio-1 volumio[21416]: info: sendMpdCommand stop took 7 milliseconds Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreStateMachine::play index undefined Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 16:47:01 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 17 Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreStateMachine::startPlaybackTimer Apr 15 16:47:01 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 17 Apr 15 16:47:01 volumio-1 volumio[21416]: info: [1713188821804] ControllerQobuz::clearAddPlayTrack Apr 15 16:47:01 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreStateMachine::updateTrackBlock Apr 15 16:47:01 volumio-1 volumio[21416]: info: CorePlayQueue::getTrackBlock Apr 15 16:47:01 volumio-1 volumio[21416]: info: Apr 15 16:47:01 volumio-1 volumio[21416]: ---------------------------- MPD announces state update: player Apr 15 16:47:01 volumio-1 volumio[21416]: info: ControllerMpd::getState Apr 15 16:47:01 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand status Apr 15 16:47:01 volumio-1 volumio[21416]: info: sendMpdCommand stop took 2 milliseconds Apr 15 16:47:01 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand clear Apr 15 16:47:01 volumio-1 volumio[21416]: info: Apr 15 16:47:01 volumio-1 volumio[21416]: ---------------------------- MPD announces state update: player Apr 15 16:47:01 volumio-1 volumio[21416]: info: ControllerMpd::getState Apr 15 16:47:01 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand status Apr 15 16:47:01 volumio-1 volumio[21416]: info: Apr 15 16:47:01 volumio-1 volumio[21416]: ---------------------------- MPD announces state update: player Apr 15 16:47:01 volumio-1 volumio[21416]: info: ControllerMpd::getState Apr 15 16:47:01 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand status Apr 15 16:47:01 volumio-1 volumio[21416]: info: Apr 15 16:47:01 volumio-1 volumio[21416]: ---------------------------- MPD announces state update: player Apr 15 16:47:01 volumio-1 volumio[21416]: info: ControllerMpd::getState Apr 15 16:47:01 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand status Apr 15 16:47:01 volumio-1 volumio[21416]: info: Apr 15 16:47:01 volumio-1 volumio[21416]: ---------------------------- MPD announces system playlist update Apr 15 16:47:01 volumio-1 volumio[21416]: info: Ignoring MPD Status Update Apr 15 16:47:01 volumio-1 volumio[21416]: info: sendMpdCommand status took 6 milliseconds Apr 15 16:47:01 volumio-1 volumio[21416]: info: sendMpdCommand clear took 5 milliseconds Apr 15 16:47:01 volumio-1 volumio[21416]: info: sendMpdCommand status took 5 milliseconds Apr 15 16:47:01 volumio-1 volumio[21416]: info: sendMpdCommand status took 4 milliseconds Apr 15 16:47:01 volumio-1 volumio[21416]: info: sendMpdCommand status took 2 milliseconds Apr 15 16:47:01 volumio-1 volumio[21416]: verbose: ControllerMpd::parseState Apr 15 16:47:01 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 16:47:01 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/488230" Apr 15 16:47:01 volumio-1 volumio[21416]: verbose: ControllerMpd::parseState Apr 15 16:47:01 volumio-1 volumio[21416]: verbose: ControllerMpd::parseState Apr 15 16:47:01 volumio-1 volumio[21416]: verbose: ControllerMpd::parseState Apr 15 16:47:01 volumio-1 volumio[21416]: info: ControllerMpd::pushState Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreCommandRouter::servicePushState Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:01 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 17 Apr 15 16:47:01 volumio-1 volumio[21416]: STREAMING PROXY: Handling url /?data=qobuz://song/488230 Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:01 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 17 Apr 15 16:47:01 volumio-1 volumio[21416]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Apr 15 16:47:01 volumio-1 volumio[21416]: info: ControllerMpd::pushState Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreCommandRouter::servicePushState Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:01 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 17 Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:01 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 17 Apr 15 16:47:01 volumio-1 volumio[21416]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Apr 15 16:47:01 volumio-1 volumio[21416]: info: ControllerMpd::pushState Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreCommandRouter::servicePushState Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:01 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 17 Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:01 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 17 Apr 15 16:47:01 volumio-1 volumio[21416]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Apr 15 16:47:01 volumio-1 volumio[21416]: info: ------------------------------ 21ms Apr 15 16:47:01 volumio-1 volumio[21416]: info: ------------------------------ 19ms Apr 15 16:47:01 volumio-1 volumio[21416]: info: ------------------------------ 19ms Apr 15 16:47:01 volumio-1 volumio[21416]: info: Apr 15 16:47:01 volumio-1 volumio[21416]: ---------------------------- MPD announces system playlist update Apr 15 16:47:01 volumio-1 volumio[21416]: info: Ignoring MPD Status Update Apr 15 16:47:01 volumio-1 volumio[21416]: info: Apr 15 16:47:01 volumio-1 volumio[21416]: ---------------------------- MPD announces system playlist update Apr 15 16:47:01 volumio-1 volumio[21416]: info: Ignoring MPD Status Update Apr 15 16:47:01 volumio-1 volumio[21416]: info: Apr 15 16:47:01 volumio-1 volumio[21416]: ---------------------------- MPD announces system playlist update Apr 15 16:47:01 volumio-1 volumio[21416]: info: Ignoring MPD Status Update Apr 15 16:47:01 volumio-1 volumio[21416]: error: updateQueue error: null Apr 15 16:47:01 volumio-1 volumio[21416]: info: ------------------------------ 20ms Apr 15 16:47:01 volumio-1 volumio[21416]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 15 16:47:01 volumio-1 volumio[21416]: verbose: ControllerMpd::parseTrackInfo Apr 15 16:47:01 volumio-1 volumio[21416]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 15 16:47:01 volumio-1 volumio[21416]: info: ------------------------------ 28ms Apr 15 16:47:01 volumio-1 volumio[21416]: info: Executing endpoint getStreamUrlqobuz Apr 15 16:47:01 volumio-1 volumio[21416]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 15 16:47:02 volumio-1 volumio[21416]: info: getStreamUrl took 493 milliseconds Apr 15 16:47:02 volumio-1 volumio[21416]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2152310&eid=488230&fmt=6&profile=raw&app_id=539451548&cid=2118389&etsp=1713192422&hmac=gQX0SzlQladV7phaKME32I1ZD7U Apr 15 16:47:02 volumio-1 volumio[21416]: info: camilladsp spawned new process with pid 26279, instance 1, run: true Apr 15 16:47:03 volumio-1 volumio[21416]: STREAMING PROXY: Response: 200, length: 24220739 Apr 15 16:47:03 volumio-1 volumio[21416]: STREAMING PROXY: Client dropped request, destroying Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/488230" Apr 15 16:47:03 volumio-1 volumio[21416]: error: updateQueue error: null Apr 15 16:47:03 volumio-1 volumio[21416]: error: updateQueue error: null Apr 15 16:47:03 volumio-1 volumio[21416]: error: updateQueue error: null Apr 15 16:47:03 volumio-1 volumio[21416]: info: Apr 15 16:47:03 volumio-1 volumio[21416]: ---------------------------- MPD announces system playlist update Apr 15 16:47:03 volumio-1 volumio[21416]: info: Ignoring MPD Status Update Apr 15 16:47:03 volumio-1 volumio[21416]: info: ------------------------------ 1546ms Apr 15 16:47:03 volumio-1 volumio[21416]: info: ------------------------------ 1545ms Apr 15 16:47:03 volumio-1 volumio[21416]: info: ------------------------------ 1545ms Apr 15 16:47:03 volumio-1 volumio[21416]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/488230" took 2 milliseconds Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand play Apr 15 16:47:03 volumio-1 volumio[21416]: info: Apr 15 16:47:03 volumio-1 volumio[21416]: ---------------------------- MPD announces system playlist update Apr 15 16:47:03 volumio-1 volumio[21416]: info: Ignoring MPD Status Update Apr 15 16:47:03 volumio-1 volumio[21416]: info: Apr 15 16:47:03 volumio-1 volumio[21416]: ---------------------------- MPD announces system playlist update Apr 15 16:47:03 volumio-1 volumio[21416]: STREAMING PROXY: Handling url /?data=qobuz://song/488230 Apr 15 16:47:03 volumio-1 volumio[21416]: info: Ignoring MPD Status Update Apr 15 16:47:03 volumio-1 volumio[21416]: info: Apr 15 16:47:03 volumio-1 volumio[21416]: ---------------------------- MPD announces system playlist update Apr 15 16:47:03 volumio-1 volumio[21416]: info: Ignoring MPD Status Update Apr 15 16:47:03 volumio-1 volumio[21416]: info: ------------------------------ 8ms Apr 15 16:47:03 volumio-1 volumio[21416]: info: sendMpdCommand play took 6 milliseconds Apr 15 16:47:03 volumio-1 volumio[21416]: info: ------------------------------ 6ms Apr 15 16:47:03 volumio-1 volumio[21416]: info: ------------------------------ 5ms Apr 15 16:47:03 volumio-1 volumio[21416]: info: ------------------------------ 4ms Apr 15 16:47:03 volumio-1 volumio[21416]: info: Executing endpoint getStreamUrlqobuz Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 15 16:47:03 volumio-1 volumio[21416]: info: getStreamUrl took 356 milliseconds Apr 15 16:47:03 volumio-1 volumio[21416]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2152310&eid=488230&fmt=6&profile=raw&app_id=539451548&cid=2118389&etsp=1713192423&hmac=q2PdeO33xydsl-_alI_WEk7tEJg Apr 15 16:47:03 volumio-1 volumio[21416]: STREAMING PROXY: Response: 200, length: 24220739 Apr 15 16:47:03 volumio-1 volumio[21416]: info: Apr 15 16:47:03 volumio-1 volumio[21416]: ---------------------------- MPD announces state update: player Apr 15 16:47:03 volumio-1 volumio[21416]: info: ControllerMpd::getState Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand status Apr 15 16:47:03 volumio-1 volumio[21416]: info: Apr 15 16:47:03 volumio-1 volumio[21416]: ---------------------------- MPD announces state update: player Apr 15 16:47:03 volumio-1 volumio[21416]: info: ControllerMpd::getState Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand status Apr 15 16:47:03 volumio-1 volumio[21416]: info: Apr 15 16:47:03 volumio-1 volumio[21416]: ---------------------------- MPD announces state update: player Apr 15 16:47:03 volumio-1 volumio[21416]: info: ControllerMpd::getState Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand status Apr 15 16:47:03 volumio-1 volumio[21416]: info: Apr 15 16:47:03 volumio-1 volumio[21416]: ---------------------------- MPD announces state update: player Apr 15 16:47:03 volumio-1 volumio[21416]: info: ControllerMpd::getState Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand status Apr 15 16:47:03 volumio-1 volumio[21416]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 15 16:47:03 volumio-1 volumio[21416]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 15 16:47:03 volumio-1 volumio[21416]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 15 16:47:03 volumio-1 volumio[21416]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 15 16:47:03 volumio-1 volumio[21416]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 15 16:47:03 volumio-1 volumio[21416]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 15 16:47:03 volumio-1 volumio[21416]: info: Apr 15 16:47:03 volumio-1 volumio[21416]: ---------------------------- MPD announces state update: player Apr 15 16:47:03 volumio-1 volumio[21416]: info: sendMpdCommand status took 82 milliseconds Apr 15 16:47:03 volumio-1 volumio[21416]: info: ControllerMpd::getState Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand status Apr 15 16:47:03 volumio-1 volumio[21416]: info: sendMpdCommand status took 83 milliseconds Apr 15 16:47:03 volumio-1 volumio[21416]: info: sendMpdCommand status took 83 milliseconds Apr 15 16:47:03 volumio-1 volumio[21416]: info: sendMpdCommand status took 82 milliseconds Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::parseState Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::parseState Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::parseState Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::parseState Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 16:47:03 volumio-1 volumio[21416]: info: Apr 15 16:47:03 volumio-1 volumio[21416]: ---------------------------- MPD announces state update: player Apr 15 16:47:03 volumio-1 volumio[21416]: info: ControllerMpd::getState Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand status Apr 15 16:47:03 volumio-1 volumio[21416]: info: Apr 15 16:47:03 volumio-1 volumio[21416]: ---------------------------- MPD announces state update: player Apr 15 16:47:03 volumio-1 volumio[21416]: info: ControllerMpd::getState Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand status Apr 15 16:47:03 volumio-1 volumio[21416]: info: Apr 15 16:47:03 volumio-1 volumio[21416]: ---------------------------- MPD announces state update: player Apr 15 16:47:03 volumio-1 volumio[21416]: info: ControllerMpd::getState Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand status Apr 15 16:47:03 volumio-1 volumio[21416]: info: sendMpdCommand status took 11 milliseconds Apr 15 16:47:03 volumio-1 volumio[21416]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 15 16:47:03 volumio-1 volumio[21416]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 15 16:47:03 volumio-1 volumio[21416]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 15 16:47:03 volumio-1 volumio[21416]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 15 16:47:03 volumio-1 volumio[21416]: info: sendMpdCommand status took 6 milliseconds Apr 15 16:47:03 volumio-1 volumio[21416]: info: sendMpdCommand status took 4 milliseconds Apr 15 16:47:03 volumio-1 volumio[21416]: info: sendMpdCommand status took 4 milliseconds Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::parseState Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::parseTrackInfo Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::parseTrackInfo Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::parseTrackInfo Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::parseTrackInfo Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::parseState Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::parseState Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::parseState Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 16:47:03 volumio-1 volumio[21416]: info: ControllerMpd::pushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreCommandRouter::servicePushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 17 Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":237,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"634 Kbps","isStreaming":false,"title":"488230","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/488230","trackType":"qobuz"} Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: CURRENT POSITION 17 Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::syncState stateService play Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::syncState currentStatus stop Apr 15 16:47:03 volumio-1 volumio[21416]: info: ControllerMpd::pushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreCommandRouter::servicePushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 17 Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: STATE SERVICE {"status":"play","position":0,"seek":457,"duration":237,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"634 Kbps","isStreaming":false,"title":"488230","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/488230","trackType":"qobuz"} Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: CURRENT POSITION 17 Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::syncState stateService play Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::syncState currentStatus play Apr 15 16:47:03 volumio-1 volumio[21416]: info: Received an update from plugin. extracting info from payload Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: ControllerMpd::pushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreCommandRouter::servicePushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 17 Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: STATE SERVICE {"status":"play","position":0,"seek":457,"duration":237,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"634 Kbps","isStreaming":false,"title":"488230","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/488230","trackType":"qobuz"} Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: CURRENT POSITION 17 Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::syncState stateService play Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::syncState currentStatus play Apr 15 16:47:03 volumio-1 volumio[21416]: info: Received an update from plugin. extracting info from payload Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: ControllerMpd::pushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreCommandRouter::servicePushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 17 Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: STATE SERVICE {"status":"play","position":0,"seek":594,"duration":237,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"634 Kbps","isStreaming":false,"title":"488230","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/488230","trackType":"qobuz"} Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: CURRENT POSITION 17 Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::syncState stateService play Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::syncState currentStatus play Apr 15 16:47:03 volumio-1 volumio[21416]: info: Received an update from plugin. extracting info from payload Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: ------------------------------ 123ms Apr 15 16:47:03 volumio-1 volumio[21416]: info: ------------------------------ 131ms Apr 15 16:47:03 volumio-1 volumio[21416]: info: ------------------------------ 131ms Apr 15 16:47:03 volumio-1 volumio[21416]: info: ------------------------------ 132ms Apr 15 16:47:03 volumio-1 volumio[21416]: info: sendMpdCommand playlistinfo took 36 milliseconds Apr 15 16:47:03 volumio-1 volumio[21416]: info: sendMpdCommand playlistinfo took 36 milliseconds Apr 15 16:47:03 volumio-1 volumio[21416]: info: sendMpdCommand playlistinfo took 35 milliseconds Apr 15 16:47:03 volumio-1 volumio[21416]: info: sendMpdCommand playlistinfo took 36 milliseconds Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::parseTrackInfo Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::parseTrackInfo Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::parseTrackInfo Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: ControllerMpd::parseTrackInfo Apr 15 16:47:03 volumio-1 volumio[21416]: info: ControllerMpd::pushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreCommandRouter::servicePushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 17 Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1715,"duration":237,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"625 Kbps","isStreaming":false,"title":"488230","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/488230","trackType":"qobuz"} Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: CURRENT POSITION 17 Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::syncState stateService play Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::syncState currentStatus play Apr 15 16:47:03 volumio-1 volumio[21416]: info: Received an update from plugin. extracting info from payload Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: ControllerMpd::pushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreCommandRouter::servicePushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 17 Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1715,"duration":237,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"625 Kbps","isStreaming":false,"title":"488230","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/488230","trackType":"qobuz"} Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: CURRENT POSITION 17 Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::syncState stateService play Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::syncState currentStatus play Apr 15 16:47:03 volumio-1 volumio[21416]: info: Received an update from plugin. extracting info from payload Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: ControllerMpd::pushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreCommandRouter::servicePushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 17 Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1715,"duration":237,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"625 Kbps","isStreaming":false,"title":"488230","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/488230","trackType":"qobuz"} Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: CURRENT POSITION 17 Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::syncState stateService play Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::syncState currentStatus play Apr 15 16:47:03 volumio-1 volumio[21416]: info: Received an update from plugin. extracting info from payload Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: ControllerMpd::pushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreCommandRouter::servicePushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 17 Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1715,"duration":237,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"625 Kbps","isStreaming":false,"title":"488230","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/488230","trackType":"qobuz"} Apr 15 16:47:03 volumio-1 volumio[21416]: verbose: CURRENT POSITION 17 Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::syncState stateService play Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::syncState currentStatus play Apr 15 16:47:03 volumio-1 volumio[21416]: info: Received an update from plugin. extracting info from payload Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:03 volumio-1 volumio[21416]: info: ------------------------------ 99ms Apr 15 16:47:03 volumio-1 volumio[21416]: info: ------------------------------ 92ms Apr 15 16:47:03 volumio-1 volumio[21416]: info: ------------------------------ 91ms Apr 15 16:47:03 volumio-1 volumio[21416]: info: ------------------------------ 90ms Apr 15 16:47:04 volumio-1 volumio[21416]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 15 16:47:04 volumio-1 volumio[21416]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 15 16:47:05 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioNext Apr 15 16:47:05 volumio-1 volumio[21416]: info: CoreStateMachine::next Apr 15 16:47:05 volumio-1 volumio[21416]: info: [1713188825724] ControllerQobuz::next Apr 15 16:47:05 volumio-1 volumio[21416]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 16:47:05 volumio-1 volumio[21416]: info: CoreStateMachine::next Apr 15 16:47:05 volumio-1 volumio[21416]: info: CoreStateMachine::stop Apr 15 16:47:05 volumio-1 volumio[21416]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 16:47:05 volumio-1 volumio[21416]: info: CoreStateMachine::stPlaybackTimer Apr 15 16:47:05 volumio-1 volumio[21416]: info: CoreStateMachine::updateTrackBlock Apr 15 16:47:05 volumio-1 volumio[21416]: info: CorePlayQueue::getTrackBlock Apr 15 16:47:05 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:05 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 17 Apr 15 16:47:05 volumio-1 volumio[21416]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 16:47:05 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:05 volumio-1 volumio[21416]: info: CoreStateMachine::serviceStop Apr 15 16:47:05 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 17 Apr 15 16:47:05 volumio-1 volumio[21416]: info: CoreCommandRouter::serviceStop Apr 15 16:47:05 volumio-1 volumio[21416]: info: [1713188825728] ControllerQobuz::stop Apr 15 16:47:05 volumio-1 volumio[21416]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 15 16:47:05 volumio-1 volumio[21416]: info: ControllerMpd::stop Apr 15 16:47:05 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 16:47:05 volumio-1 volumio[21416]: STREAMING PROXY: Client dropped request, destroying Apr 15 16:47:05 volumio-1 volumio[21416]: info: sendMpdCommand stop took 9 milliseconds Apr 15 16:47:05 volumio-1 volumio[21416]: info: CoreStateMachine::play index undefined Apr 15 16:47:05 volumio-1 volumio[21416]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 16:47:05 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 18 Apr 15 16:47:05 volumio-1 volumio[21416]: info: CoreStateMachine::startPlaybackTimer Apr 15 16:47:05 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 18 Apr 15 16:47:05 volumio-1 volumio[21416]: info: [1713188825737] ControllerQobuz::clearAddPlayTrack Apr 15 16:47:05 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 16:47:05 volumio-1 volumio[21416]: info: CoreStateMachine::updateTrackBlock Apr 15 16:47:05 volumio-1 volumio[21416]: info: CorePlayQueue::getTrackBlock Apr 15 16:47:05 volumio-1 volumio[21416]: info: Apr 15 16:47:05 volumio-1 volumio[21416]: ---------------------------- MPD announces state update: player Apr 15 16:47:05 volumio-1 volumio[21416]: info: ControllerMpd::getState Apr 15 16:47:05 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand status Apr 15 16:47:05 volumio-1 volumio[21416]: info: Apr 15 16:47:05 volumio-1 volumio[21416]: ---------------------------- MPD announces state update: player Apr 15 16:47:05 volumio-1 volumio[21416]: info: ControllerMpd::getState Apr 15 16:47:05 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand status Apr 15 16:47:05 volumio-1 volumio[21416]: info: Apr 15 16:47:05 volumio-1 volumio[21416]: ---------------------------- MPD announces state update: player Apr 15 16:47:05 volumio-1 volumio[21416]: info: ControllerMpd::getState Apr 15 16:47:05 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand status Apr 15 16:47:05 volumio-1 volumio[21416]: info: Apr 15 16:47:05 volumio-1 volumio[21416]: ---------------------------- MPD announces state update: player Apr 15 16:47:05 volumio-1 volumio[21416]: info: ControllerMpd::getState Apr 15 16:47:05 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand status Apr 15 16:47:05 volumio-1 volumio[21416]: info: sendMpdCommand stop took 3 milliseconds Apr 15 16:47:05 volumio-1 volumio[21416]: info: sendMpdCommand status took 2 milliseconds Apr 15 16:47:05 volumio-1 volumio[21416]: info: sendMpdCommand status took 2 milliseconds Apr 15 16:47:05 volumio-1 volumio[21416]: info: sendMpdCommand status took 1 milliseconds Apr 15 16:47:05 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand clear Apr 15 16:47:05 volumio-1 volumio[21416]: verbose: ControllerMpd::parseState Apr 15 16:47:05 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 16:47:05 volumio-1 volumio[21416]: verbose: ControllerMpd::parseState Apr 15 16:47:05 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 16:47:05 volumio-1 volumio[21416]: verbose: ControllerMpd::parseState Apr 15 16:47:05 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 16:47:05 volumio-1 volumio[21416]: info: Apr 15 16:47:05 volumio-1 volumio[21416]: ---------------------------- MPD announces system playlist update Apr 15 16:47:05 volumio-1 volumio[21416]: info: Ignoring MPD Status Update Apr 15 16:47:05 volumio-1 volumio[21416]: info: sendMpdCommand status took 3 milliseconds Apr 15 16:47:05 volumio-1 volumio[21416]: info: sendMpdCommand clear took 1 milliseconds Apr 15 16:47:05 volumio-1 volumio[21416]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 15 16:47:05 volumio-1 volumio[21416]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 15 16:47:05 volumio-1 volumio[21416]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 15 16:47:05 volumio-1 volumio[21416]: verbose: ControllerMpd::parseState Apr 15 16:47:05 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 16:47:05 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/83380118" Apr 15 16:47:05 volumio-1 volumio[21416]: verbose: ControllerMpd::parseTrackInfo Apr 15 16:47:05 volumio-1 volumio[21416]: verbose: ControllerMpd::parseTrackInfo Apr 15 16:47:05 volumio-1 volumio[21416]: verbose: ControllerMpd::parseTrackInfo Apr 15 16:47:05 volumio-1 volumio[21416]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 15 16:47:05 volumio-1 volumio[21416]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 15 16:47:05 volumio-1 volumio[21416]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 15 16:47:05 volumio-1 volumio[21416]: info: ------------------------------ 8ms Apr 15 16:47:05 volumio-1 volumio[21416]: info: ------------------------------ 7ms Apr 15 16:47:05 volumio-1 volumio[21416]: info: ------------------------------ 6ms Apr 15 16:47:05 volumio-1 volumio[21416]: info: Apr 15 16:47:05 volumio-1 volumio[21416]: ---------------------------- MPD announces system playlist update Apr 15 16:47:05 volumio-1 volumio[21416]: info: Ignoring MPD Status Update Apr 15 16:47:05 volumio-1 volumio[21416]: info: Apr 15 16:47:05 volumio-1 volumio[21416]: ---------------------------- MPD announces system playlist update Apr 15 16:47:05 volumio-1 volumio[21416]: info: Ignoring MPD Status Update Apr 15 16:47:05 volumio-1 volumio[21416]: info: Apr 15 16:47:05 volumio-1 volumio[21416]: ---------------------------- MPD announces system playlist update Apr 15 16:47:05 volumio-1 volumio[21416]: info: Ignoring MPD Status Update Apr 15 16:47:05 volumio-1 volumio[21416]: STREAMING PROXY: Handling url /?data=qobuz://song/83380118 Apr 15 16:47:05 volumio-1 volumio[21416]: error: updateQueue error: null Apr 15 16:47:05 volumio-1 volumio[21416]: info: ------------------------------ 5ms Apr 15 16:47:05 volumio-1 volumio[21416]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 15 16:47:05 volumio-1 volumio[21416]: verbose: ControllerMpd::parseTrackInfo Apr 15 16:47:05 volumio-1 volumio[21416]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 15 16:47:05 volumio-1 volumio[21416]: info: ------------------------------ 9ms Apr 15 16:47:05 volumio-1 volumio[21416]: info: Executing endpoint getStreamUrlqobuz Apr 15 16:47:05 volumio-1 volumio[21416]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 15 16:47:06 volumio-1 volumio[21416]: info: getStreamUrl took 382 milliseconds Apr 15 16:47:06 volumio-1 volumio[21416]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2152310&eid=83380118&fmt=6&profile=raw&app_id=539451548&cid=2118389&etsp=1713192426&hmac=jgi_AmDZCxgxRSYk26i6DxvLejc Apr 15 16:47:06 volumio-1 volumio[21416]: info: camilladsp spawned new process with pid 26300, instance 1, run: true Apr 15 16:47:06 volumio-1 volumio[21416]: STREAMING PROXY: Response: 200, length: 21379857 Apr 15 16:47:06 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/83380118" Apr 15 16:47:06 volumio-1 volumio[21416]: error: updateQueue error: null Apr 15 16:47:06 volumio-1 volumio[21416]: STREAMING PROXY: Client dropped request, destroying Apr 15 16:47:06 volumio-1 volumio[21416]: error: updateQueue error: null Apr 15 16:47:06 volumio-1 volumio[21416]: info: Apr 15 16:47:06 volumio-1 volumio[21416]: ---------------------------- MPD announces system playlist update Apr 15 16:47:06 volumio-1 volumio[21416]: info: Ignoring MPD Status Update Apr 15 16:47:06 volumio-1 volumio[21416]: info: ------------------------------ 965ms Apr 15 16:47:06 volumio-1 volumio[21416]: info: ------------------------------ 964ms Apr 15 16:47:06 volumio-1 volumio[21416]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/83380118" took 1 milliseconds Apr 15 16:47:06 volumio-1 volumio[21416]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 15 16:47:06 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand play Apr 15 16:47:06 volumio-1 volumio[21416]: info: Apr 15 16:47:06 volumio-1 volumio[21416]: ---------------------------- MPD announces system playlist update Apr 15 16:47:06 volumio-1 volumio[21416]: info: Ignoring MPD Status Update Apr 15 16:47:06 volumio-1 volumio[21416]: info: Apr 15 16:47:06 volumio-1 volumio[21416]: ---------------------------- MPD announces system playlist update Apr 15 16:47:06 volumio-1 volumio[21416]: info: Ignoring MPD Status Update Apr 15 16:47:06 volumio-1 volumio[21416]: STREAMING PROXY: Handling url /?data=qobuz://song/83380118 Apr 15 16:47:06 volumio-1 volumio[21416]: info: Apr 15 16:47:06 volumio-1 volumio[21416]: ---------------------------- MPD announces system playlist update Apr 15 16:47:06 volumio-1 volumio[21416]: info: Ignoring MPD Status Update Apr 15 16:47:06 volumio-1 volumio[21416]: info: ------------------------------ 5ms Apr 15 16:47:06 volumio-1 volumio[21416]: info: sendMpdCommand play took 4 milliseconds Apr 15 16:47:06 volumio-1 volumio[21416]: info: ------------------------------ 3ms Apr 15 16:47:06 volumio-1 volumio[21416]: info: ------------------------------ 2ms Apr 15 16:47:06 volumio-1 volumio[21416]: info: ------------------------------ 1ms Apr 15 16:47:06 volumio-1 volumio[21416]: info: Executing endpoint getStreamUrlqobuz Apr 15 16:47:06 volumio-1 volumio[21416]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Apr 15 16:47:07 volumio-1 volumio[21416]: info: getStreamUrl took 362 milliseconds Apr 15 16:47:07 volumio-1 volumio[21416]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=2152310&eid=83380118&fmt=6&profile=raw&app_id=539451548&cid=2118389&etsp=1713192427&hmac=WiDhRoZJ13mKuG2U1gNdW68sODc Apr 15 16:47:07 volumio-1 volumio[21416]: STREAMING PROXY: Response: 200, length: 21379857 Apr 15 16:47:07 volumio-1 volumio[21416]: info: Apr 15 16:47:07 volumio-1 volumio[21416]: ---------------------------- MPD announces state update: player Apr 15 16:47:07 volumio-1 volumio[21416]: info: ControllerMpd::getState Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand status Apr 15 16:47:07 volumio-1 volumio[21416]: info: Apr 15 16:47:07 volumio-1 volumio[21416]: ---------------------------- MPD announces state update: player Apr 15 16:47:07 volumio-1 volumio[21416]: info: ControllerMpd::getState Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand status Apr 15 16:47:07 volumio-1 volumio[21416]: info: Apr 15 16:47:07 volumio-1 volumio[21416]: ---------------------------- MPD announces state update: player Apr 15 16:47:07 volumio-1 volumio[21416]: info: ControllerMpd::getState Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand status Apr 15 16:47:07 volumio-1 volumio[21416]: info: Apr 15 16:47:07 volumio-1 volumio[21416]: ---------------------------- MPD announces state update: player Apr 15 16:47:07 volumio-1 volumio[21416]: info: ControllerMpd::getState Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand status Apr 15 16:47:07 volumio-1 volumio[21416]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 15 16:47:07 volumio-1 volumio[21416]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 15 16:47:07 volumio-1 volumio[21416]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 15 16:47:07 volumio-1 volumio[21416]: info: Apr 15 16:47:07 volumio-1 volumio[21416]: ---------------------------- MPD announces state update: player Apr 15 16:47:07 volumio-1 volumio[21416]: info: sendMpdCommand status took 27 milliseconds Apr 15 16:47:07 volumio-1 volumio[21416]: info: ControllerMpd::getState Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand status Apr 15 16:47:07 volumio-1 volumio[21416]: info: sendMpdCommand status took 27 milliseconds Apr 15 16:47:07 volumio-1 volumio[21416]: info: sendMpdCommand status took 26 milliseconds Apr 15 16:47:07 volumio-1 volumio[21416]: info: sendMpdCommand status took 25 milliseconds Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::parseState Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::parseState Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::parseState Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::parseState Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 16:47:07 volumio-1 volumio[21416]: info: Apr 15 16:47:07 volumio-1 volumio[21416]: ---------------------------- MPD announces state update: player Apr 15 16:47:07 volumio-1 volumio[21416]: info: ControllerMpd::getState Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand status Apr 15 16:47:07 volumio-1 volumio[21416]: info: Apr 15 16:47:07 volumio-1 volumio[21416]: ---------------------------- MPD announces state update: player Apr 15 16:47:07 volumio-1 volumio[21416]: info: ControllerMpd::getState Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand status Apr 15 16:47:07 volumio-1 volumio[21416]: info: Apr 15 16:47:07 volumio-1 volumio[21416]: ---------------------------- MPD announces state update: player Apr 15 16:47:07 volumio-1 volumio[21416]: info: ControllerMpd::getState Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand status Apr 15 16:47:07 volumio-1 volumio[21416]: info: sendMpdCommand status took 7 milliseconds Apr 15 16:47:07 volumio-1 volumio[21416]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 15 16:47:07 volumio-1 volumio[21416]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 15 16:47:07 volumio-1 volumio[21416]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 15 16:47:07 volumio-1 volumio[21416]: info: sendMpdCommand status took 5 milliseconds Apr 15 16:47:07 volumio-1 volumio[21416]: info: sendMpdCommand status took 3 milliseconds Apr 15 16:47:07 volumio-1 volumio[21416]: info: sendMpdCommand status took 2 milliseconds Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::parseState Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::parseTrackInfo Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::parseTrackInfo Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::parseTrackInfo Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::parseTrackInfo Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::parseState Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::parseState Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::parseState Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 16:47:07 volumio-1 volumio[21416]: info: ControllerMpd::pushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreCommandRouter::servicePushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 18 Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"83380118","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/83380118","trackType":"qobuz"} Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: CURRENT POSITION 18 Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::syncState stateService play Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::syncState currentStatus stop Apr 15 16:47:07 volumio-1 volumio[21416]: info: ControllerMpd::pushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreCommandRouter::servicePushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 18 Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"83380118","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/83380118","trackType":"qobuz"} Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: CURRENT POSITION 18 Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::syncState stateService play Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::syncState currentStatus play Apr 15 16:47:07 volumio-1 volumio[21416]: info: Received an update from plugin. extracting info from payload Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: ControllerMpd::pushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreCommandRouter::servicePushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 18 Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"83380118","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/83380118","trackType":"qobuz"} Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: CURRENT POSITION 18 Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::syncState stateService play Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::syncState currentStatus play Apr 15 16:47:07 volumio-1 volumio[21416]: info: Received an update from plugin. extracting info from payload Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: ControllerMpd::pushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreCommandRouter::servicePushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 18 Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"83380118","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/83380118","trackType":"qobuz"} Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: CURRENT POSITION 18 Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::syncState stateService play Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::syncState currentStatus play Apr 15 16:47:07 volumio-1 volumio[21416]: info: Received an update from plugin. extracting info from payload Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: ------------------------------ 48ms Apr 15 16:47:07 volumio-1 volumio[21416]: info: ------------------------------ 57ms Apr 15 16:47:07 volumio-1 volumio[21416]: info: ------------------------------ 58ms Apr 15 16:47:07 volumio-1 volumio[21416]: info: sendMpdCommand playlistinfo took 25 milliseconds Apr 15 16:47:07 volumio-1 volumio[21416]: info: sendMpdCommand playlistinfo took 24 milliseconds Apr 15 16:47:07 volumio-1 volumio[21416]: info: sendMpdCommand playlistinfo took 25 milliseconds Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::parseTrackInfo Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: ControllerMpd::parseTrackInfo Apr 15 16:47:07 volumio-1 volumio[21416]: info: ControllerMpd::pushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreCommandRouter::servicePushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 18 Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":195,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"775 Kbps","isStreaming":false,"title":"83380118","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/83380118","trackType":"qobuz"} Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: CURRENT POSITION 18 Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::syncState stateService play Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::syncState currentStatus play Apr 15 16:47:07 volumio-1 volumio[21416]: info: Received an update from plugin. extracting info from payload Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: ControllerMpd::pushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreCommandRouter::servicePushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 18 Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1327,"duration":195,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"775 Kbps","isStreaming":false,"title":"83380118","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/83380118","trackType":"qobuz"} Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: CURRENT POSITION 18 Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::syncState stateService play Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::syncState currentStatus play Apr 15 16:47:07 volumio-1 volumio[21416]: info: Received an update from plugin. extracting info from payload Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: ControllerMpd::pushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreCommandRouter::servicePushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 18 Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1327,"duration":195,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"775 Kbps","isStreaming":false,"title":"83380118","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/83380118","trackType":"qobuz"} Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: CURRENT POSITION 18 Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::syncState stateService play Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::syncState currentStatus play Apr 15 16:47:07 volumio-1 volumio[21416]: info: Received an update from plugin. extracting info from payload Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: ControllerMpd::pushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreCommandRouter::servicePushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CorePlayQueue::getTrack 18 Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1327,"duration":195,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"775 Kbps","isStreaming":false,"title":"83380118","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/83380118","trackType":"qobuz"} Apr 15 16:47:07 volumio-1 volumio[21416]: verbose: CURRENT POSITION 18 Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::syncState stateService play Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::syncState currentStatus play Apr 15 16:47:07 volumio-1 volumio[21416]: info: Received an update from plugin. extracting info from payload Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreStateMachine::pushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: CoreCommandRouter::volumioPushState Apr 15 16:47:07 volumio-1 volumio[21416]: info: ------------------------------ 63ms Apr 15 16:47:07 volumio-1 volumio[21416]: info: ------------------------------ 60ms Apr 15 16:47:07 volumio-1 volumio[21416]: info: ------------------------------ 59ms Apr 15 16:47:07 volumio-1 volumio[21416]: info: ------------------------------ 59ms Apr 15 16:47:07 volumio-1 volumio[21416]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 15 16:47:09 volumio-1 volumio[21416]: STREAMING PROXY: Client dropped request, destroying Apr 15 16:47:29 volumio-1 volumio[21416]: info: Preload queue cleared Apr 15 16:48:00 volumio-1 volumio[21416]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 15 16:48:00 volumio-1 volumio[21416]: info: Preload queue cleared Apr 15 16:48:03 volumio-1 volumio[21416]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preload queue cleared Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/83380118 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/488230 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/13948296 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/68981781 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/145671247 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/169143493 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/134787747 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/13421032 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/2048395 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/145672465 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/169178293 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/134787757 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/114023 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/9084562 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/11854340 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/50852288 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/169143486 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/25651043 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/11824330 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/143667528 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/143667527 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/168320716 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/100179414 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/96287122 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/218980827 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/170756299 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/170756298 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/170756297 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/170756296 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/170756295 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/116294480 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: qobuz://song/241771979 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22S%C3%A9%20Que%20Te%20Amo%22%2C%22artist%22%3A%22Los%20Temerarios%22%2C%22album%22%3A%22Una%20L%C3%A1grima%20No%20Basta%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FyOmHHWeJ5yz8FaHGjhusRFa5LCXXR6WQ-TP6TJPdmuhcNET2G46D9LqsvWSnqxHy_eOB2erAQm2Zqkw%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22pw0PVm1CcH8%22%2C%22playlistId%22%3A%22RDAMVMpw0PVm1CcH8%22%2C%22params%22%3A%22wAEB%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22pw0PVm1CcH8%22%2C%22playlistId%22%3A%22RDAMVMpw0PVm1CcH8%22%2C%22params%22%3A%22wAEB%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$RIHNW9Q/03 - Hindi Zahra - Just Say I Love Him.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$RIHNW9Q/02 - Keziah Jones - Sinnerman.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$RIHNW9Q/01 - Lianne La Havas - Baltimore.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: cue://USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$RG5V316/Tony Bennett & Diana Krall - Love Is Here To Stay.cue@0 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$RDBE6NB/03 - Branches Break.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$RDBE6NB/02 - Unspeakable World.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$RDBE6NB/01 - All Res.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: cue://USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$RBSGQOT/Halie Loren - After Dark.cue@1 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: cue://USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$RBSGQOT/Halie Loren - After Dark.cue@0 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R5YV3ZL/CD1/04 - Rivers of Love.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R5YV3ZL/CD1/03 - Fragile.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R5YV3ZL/CD1/02 - Quiet Nights of Quiet Stars.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R5YV3ZL/CD1/01 - Tennessee Waltz.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R5DH3X6/2012 - Glad Rag Doll [FLAC]/05 - Glad Rag Doll.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R5DH3X6/2012 - Glad Rag Doll [FLAC]/04 - You Know - I Know Ev'rything's Made For Love.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R5DH3X6/2012 - Glad Rag Doll [FLAC]/03 - Just Like A Butterfly That's Caught In The Rain.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R5DH3X6/2012 - Glad Rag Doll [FLAC]/02 - There Ain't No Sweet Man That's Worth The Salt Of My Tears.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R5DH3X6/2012 - Glad Rag Doll [FLAC]/01 - We Just Couldn't Say Goodbye.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R5DH3X6/2015 - Wallflower [FLAC]/06. If I Take You Home Tonight.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R5DH3X6/2015 - Wallflower [FLAC]/05. Wallflower (feat. Blake Mills).flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R5DH3X6/2015 - Wallflower [FLAC]/04. Alone Again (Naturally) (Duet With Michael Buble).flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R5DH3X6/2015 - Wallflower [FLAC]/03. Superstar.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R5DH3X6/2015 - Wallflower [FLAC]/02. Desperado.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R5DH3X6/2015 - Wallflower [FLAC]/01. California Dreamin'.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R5DH3X6/1995 - Only Thrust Your Heart [FLAC]/09 - CRS Craft.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R5DH3X6/1995 - Only Thrust Your Heart [FLAC]/08 - All Night Long.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R5DH3X6/1995 - Only Thrust Your Heart [FLAC]/07 - Squeeze Me.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R5DH3X6/1995 - Only Thrust Your Heart [FLAC]/06 - I've Got The World On A String.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R5DH3X6/1995 - Only Thrust Your Heart [FLAC]/05 - Folks Who Live On The Hill.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R5DH3X6/1995 - Only Thrust Your Heart [FLAC]/04 - Broadway.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R5DH3X6/1995 - Only Thrust Your Heart [FLAC]/03 - I Love Being Here With You.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R5DH3X6/1995 - Only Thrust Your Heart [FLAC]/02 - Only Trust Your Heart.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R5DH3X6/1995 - Only Thrust Your Heart [FLAC]/01 - Is You Is Or Is You Ain't My Baby.flac Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: cue://USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R5DH3X6/1993 - Stepping Out [FLAC]/Stepping Out.cue@0 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: cue://USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R21L9FZ/Jacintha - Autumn Leaves (2000)/CDImage.cue@2 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: cue://USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R21L9FZ/Jacintha - Autumn Leaves (2000)/CDImage.cue@1 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: cue://USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$R21L9FZ/Jacintha - Autumn Leaves (2000)/CDImage.cue@0 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: cue://USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$RRS9PWU/Malia & Boris Blank - Convergence (flac).cue@2 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: cue://USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$RRS9PWU/Malia & Boris Blank - Convergence (flac).cue@1 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: cue://USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$RRS9PWU/Malia & Boris Blank - Convergence (flac).cue@0 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Preloading song: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=534083 Apr 15 16:48:03 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/83380118 in service qobuz Apr 15 16:48:03 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/488230 in service qobuz Apr 15 16:48:03 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/13948296 in service qobuz Apr 15 16:48:03 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/68981781 in service qobuz Apr 15 16:48:03 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/145671247 in service qobuz Apr 15 16:48:03 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/169143493 in service qobuz Apr 15 16:48:03 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/134787747 in service qobuz Apr 15 16:48:03 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/13421032 in service qobuz Apr 15 16:48:03 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/2048395 in service qobuz Apr 15 16:48:03 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/145672465 in service qobuz Apr 15 16:48:03 volumio-1 volumio[21416]: info: explodeUri took 470 milliseconds Apr 15 16:48:03 volumio-1 volumio[21416]: info: explodeUri took 443 milliseconds Apr 15 16:48:03 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/169178293 in service qobuz Apr 15 16:48:03 volumio-1 volumio[21416]: info: explodeUri took 415 milliseconds Apr 15 16:48:03 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/134787757 in service qobuz Apr 15 16:48:03 volumio-1 volumio[21416]: info: explodeUri took 429 milliseconds Apr 15 16:48:03 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/114023 in service qobuz Apr 15 16:48:03 volumio-1 volumio[21416]: info: explodeUri took 429 milliseconds Apr 15 16:48:04 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/9084562 in service qobuz Apr 15 16:48:04 volumio-1 volumio[21416]: info: explodeUri took 421 milliseconds Apr 15 16:48:04 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/11854340 in service qobuz Apr 15 16:48:04 volumio-1 volumio[21416]: info: explodeUri took 407 milliseconds Apr 15 16:48:04 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/50852288 in service qobuz Apr 15 16:48:04 volumio-1 volumio[21416]: info: explodeUri took 423 milliseconds Apr 15 16:48:04 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/169143486 in service qobuz Apr 15 16:48:04 volumio-1 volumio[21416]: info: explodeUri took 412 milliseconds Apr 15 16:48:04 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/25651043 in service qobuz Apr 15 16:48:04 volumio-1 volumio[21416]: info: explodeUri took 434 milliseconds Apr 15 16:48:04 volumio-1 volumio[21416]: info: explodeUri took 395 milliseconds Apr 15 16:48:04 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/11824330 in service qobuz Apr 15 16:48:04 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/143667528 in service qobuz Apr 15 16:48:04 volumio-1 volumio[21416]: info: explodeUri took 414 milliseconds Apr 15 16:48:04 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/143667527 in service qobuz Apr 15 16:48:04 volumio-1 volumio[21416]: info: explodeUri took 420 milliseconds Apr 15 16:48:04 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/168320716 in service qobuz Apr 15 16:48:04 volumio-1 volumio[21416]: info: explodeUri took 413 milliseconds Apr 15 16:48:04 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/100179414 in service qobuz Apr 15 16:48:04 volumio-1 volumio[21416]: info: explodeUri took 441 milliseconds Apr 15 16:48:04 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/96287122 in service qobuz Apr 15 16:48:04 volumio-1 volumio[21416]: info: explodeUri took 422 milliseconds Apr 15 16:48:04 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/218980827 in service qobuz Apr 15 16:48:04 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/170756299 in service qobuz Apr 15 16:48:04 volumio-1 volumio[21416]: info: explodeUri took 455 milliseconds Apr 15 16:48:04 volumio-1 volumio[21416]: info: explodeUri took 413 milliseconds Apr 15 16:48:04 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/170756298 in service qobuz Apr 15 16:48:04 volumio-1 volumio[21416]: info: explodeUri took 413 milliseconds Apr 15 16:48:04 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/170756297 in service qobuz Apr 15 16:48:04 volumio-1 volumio[21416]: info: explodeUri took 439 milliseconds Apr 15 16:48:04 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/170756296 in service qobuz Apr 15 16:48:04 volumio-1 volumio[21416]: info: explodeUri took 408 milliseconds Apr 15 16:48:04 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/170756295 in service qobuz Apr 15 16:48:04 volumio-1 volumio[21416]: info: explodeUri took 413 milliseconds Apr 15 16:48:04 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/116294480 in service qobuz Apr 15 16:48:04 volumio-1 volumio[21416]: info: explodeUri took 417 milliseconds Apr 15 16:48:04 volumio-1 volumio[21416]: info: Exploding uri qobuz://song/241771979 in service qobuz Apr 15 16:48:04 volumio-1 volumio[21416]: info: Exploding uri ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22S%C3%A9%20Que%20Te%20Amo%22%2C%22artist%22%3A%22Los%20Temerarios%22%2C%22album%22%3A%22Una%20L%C3%A1grima%20No%20Basta%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FyOmHHWeJ5yz8FaHGjhusRFa5LCXXR6WQ-TP6TJPdmuhcNET2G46D9LqsvWSnqxHy_eOB2erAQm2Zqkw%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22pw0PVm1CcH8%22%2C%22playlistId%22%3A%22RDAMVMpw0PVm1CcH8%22%2C%22params%22%3A%22wAEB%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22pw0PVm1CcH8%22%2C%22playlistId%22%3A%22RDAMVMpw0PVm1CcH8%22%2C%22params%22%3A%22wAEB%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D in service ytmusic Apr 15 16:48:04 volumio-1 volumio[21416]: info: [ytmusic-browse] explodeUri: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22S%C3%A9%20Que%20Te%20Amo%22%2C%22artist%22%3A%22Los%20Temerarios%22%2C%22album%22%3A%22Una%20L%C3%A1grima%20No%20Basta%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FyOmHHWeJ5yz8FaHGjhusRFa5LCXXR6WQ-TP6TJPdmuhcNET2G46D9LqsvWSnqxHy_eOB2erAQm2Zqkw%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22pw0PVm1CcH8%22%2C%22playlistId%22%3A%22RDAMVMpw0PVm1CcH8%22%2C%22params%22%3A%22wAEB%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22pw0PVm1CcH8%22%2C%22playlistId%22%3A%22RDAMVMpw0PVm1CcH8%22%2C%22params%22%3A%22wAEB%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Apr 15 16:48:04 volumio-1 volumio[21416]: info: explodeUri took 465 milliseconds Apr 15 16:48:05 volumio-1 volumio[21416]: info: explodeUri took 436 milliseconds Apr 15 16:48:05 volumio-1 volumio[21416]: info: Exploding uri mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$RIHNW9Q/03 - Hindi Zahra - Just Say I Love Him.flac in service mpd Apr 15 16:48:05 volumio-1 volumio[21416]: error: scanFolder - failure to stat '/mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$RIHNW9Q/03 - Hindi Zahra - Just Say I Love Him.flac' Apr 15 16:48:05 volumio-1 volumio[21416]: info: explodeUri took 419 milliseconds Apr 15 16:48:05 volumio-1 volumio[21416]: info: Exploding uri mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$RIHNW9Q/02 - Keziah Jones - Sinnerman.flac in service mpd Apr 15 16:48:05 volumio-1 volumio[21416]: error: scanFolder - failure to stat '/mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$RIHNW9Q/02 - Keziah Jones - Sinnerman.flac' Apr 15 16:48:05 volumio-1 volumio[21416]: info: Exploding uri mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$RIHNW9Q/01 - Lianne La Havas - Baltimore.flac in service mpd Apr 15 16:48:05 volumio-1 volumio[21416]: error: scanFolder - failure to stat '/mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$RIHNW9Q/01 - Lianne La Havas - Baltimore.flac' Apr 15 16:48:05 volumio-1 volumio[21416]: info: Exploding uri cue://USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$RG5V316/Tony Bennett & Diana Krall - Love Is Here To Stay.cue@0 in service mpd Apr 15 16:48:05 volumio-1 volumio[21416]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 16:48:05 volumio-1 volumio[21416]: Error: file /mnt/USB/My_Passport/$RECYCLE.BIN/S-1-5-21-2578386534-924087680-3399613390-1002/$RG5V316/Tony Bennett & Diana Krall - Love Is Here To Stay.cue does not exist Apr 15 16:48:05 volumio-1 volumio[21416]: at Object.parse (/volumio/node_modules/cue-parser/lib/cue.js:37:15) Apr 15 16:48:05 volumio-1 volumio[21416]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:1924:27) Apr 15 16:48:05 volumio-1 volumio[21416]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1359:16) Apr 15 16:48:05 volumio-1 volumio[21416]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Apr 15 16:48:05 volumio-1 volumio[21416]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Apr 15 16:48:05 volumio-1 volumio[21416]: at Timeout._onTimeout (/volumio/app/playqueue.js:146:100) Apr 15 16:48:05 volumio-1 volumio[21416]: at listOnTimeout (internal/timers.js:554:17) Apr 15 16:48:05 volumio-1 volumio[21416]: at processTimers (internal/timers.js:497:7) Apr 15 16:48:05 volumio-1 volumio[21416]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 16:48:05 volumio-1 sudo[26481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-15 16:47 Apr 15 16:48:05 volumio-1 sudo[26481]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"