-- 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"