-- Logs begin at Fri 2026-10-02 12:57:52 CEST, end at Fri 2026-10-02 17:52:14 CEST. -- Oct 02 17:51:52 lenovio volumio[981]: info: Preload queue cleared Oct 02 17:51:52 lenovio volumio[981]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 02 17:51:52 lenovio volumio[981]: info: CoreStateMachine::ClearQueue Oct 02 17:51:52 lenovio volumio[981]: info: CoreStateMachine::stop Oct 02 17:51:52 lenovio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:51:52 lenovio volumio[981]: info: CoreStateMachine::stPlaybackTimer Oct 02 17:51:52 lenovio volumio[981]: info: CoreStateMachine::updateTrackBlock Oct 02 17:51:52 lenovio volumio[981]: info: CorePlayQueue::getTrackBlock Oct 02 17:51:52 lenovio volumio[981]: info: CoreStateMachine::pushState Oct 02 17:51:52 lenovio volumio[981]: info: CorePlayQueue::getTrack 0 Oct 02 17:51:52 lenovio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:51:52 lenovio volumio[981]: info: CoreCommandRouter::volumioPushState Oct 02 17:51:52 lenovio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Oct 02 17:51:52 lenovio volumio[981]: info: MRS: Pushing multiroomSync output Oct 02 17:51:52 lenovio volumio[981]: info: CoreCommandRouter::volumioGetState Oct 02 17:51:52 lenovio volumio[981]: info: CorePlayQueue::getTrack 0 Oct 02 17:51:52 lenovio volumio[981]: info: CoreStateMachine::serviceStop Oct 02 17:51:52 lenovio volumio[981]: info: CorePlayQueue::getTrack 0 Oct 02 17:51:52 lenovio volumio[981]: info: CoreCommandRouter::serviceStop Oct 02 17:51:52 lenovio volumio[981]: info: [1790956312584] ControllerQobuz::stop Oct 02 17:51:52 lenovio volumio[981]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 17:51:52 lenovio volumio[981]: info: ControllerMpd::stop Oct 02 17:51:52 lenovio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 17:51:52 lenovio volumio[981]: info: CorePlayQueue::clearPlayQueue Oct 02 17:51:52 lenovio volumio[981]: info: CorePlayQueue::saveQueue Oct 02 17:51:52 lenovio volumio[981]: info: CoreCommandRouter::volumioPushQueue Oct 02 17:51:52 lenovio volumio[981]: info: CoreStateMachine::addQueueItems Oct 02 17:51:52 lenovio volumio[981]: info: CorePlayQueue::addQueueItems Oct 02 17:51:52 lenovio volumio[981]: info: Preload queue cleared Oct 02 17:51:52 lenovio volumio[981]: info: Adding Item to queue: qobuz://song/179893501 Oct 02 17:51:52 lenovio volumio[981]: info: Using cached record of: qobuz://song/179893501 Oct 02 17:51:52 lenovio volumio[981]: info: Adding Item to queue: qobuz://song/179893504 Oct 02 17:51:52 lenovio volumio[981]: info: Using cached record of: qobuz://song/179893504 Oct 02 17:51:52 lenovio volumio[981]: info: Adding Item to queue: qobuz://song/179893506 Oct 02 17:51:52 lenovio volumio[981]: info: Using cached record of: qobuz://song/179893506 Oct 02 17:51:52 lenovio volumio[981]: info: Adding Item to queue: qobuz://song/91255093 Oct 02 17:51:52 lenovio volumio[981]: info: Using cached record of: qobuz://song/91255093 Oct 02 17:51:52 lenovio volumio[981]: info: Adding Item to queue: qobuz://song/91255095 Oct 02 17:51:52 lenovio volumio[981]: info: Using cached record of: qobuz://song/91255095 Oct 02 17:51:52 lenovio volumio[981]: info: Adding Item to queue: qobuz://song/91255098 Oct 02 17:51:52 lenovio volumio[981]: info: Using cached record of: qobuz://song/91255098 Oct 02 17:51:52 lenovio volumio[981]: info: Adding Item to queue: qobuz://song/179893423 Oct 02 17:51:52 lenovio volumio[981]: info: Using cached record of: qobuz://song/179893423 Oct 02 17:51:52 lenovio volumio[981]: info: Adding Item to queue: qobuz://song/179891393 Oct 02 17:51:52 lenovio volumio[981]: info: Using cached record of: qobuz://song/179891393 Oct 02 17:51:52 lenovio volumio[981]: info: Adding Item to queue: qobuz://song/179891395 Oct 02 17:51:52 lenovio volumio[981]: info: Using cached record of: qobuz://song/179891395 Oct 02 17:51:52 lenovio volumio[981]: info: Adding Item to queue: qobuz://song/179891396 Oct 02 17:51:52 lenovio volumio[981]: info: Using cached record of: qobuz://song/179891396 Oct 02 17:51:52 lenovio volumio[981]: info: Adding Item to queue: qobuz://song/179893536 Oct 02 17:51:52 lenovio volumio[981]: info: Using cached record of: qobuz://song/179893536 Oct 02 17:51:52 lenovio volumio[981]: info: Adding Item to queue: qobuz://song/179893535 Oct 02 17:51:52 lenovio volumio[981]: info: Using cached record of: qobuz://song/179893535 Oct 02 17:51:52 lenovio volumio[981]: info: Adding Item to queue: qobuz://song/42070550 Oct 02 17:51:52 lenovio volumio[981]: info: Using cached record of: qobuz://song/42070550 Oct 02 17:51:52 lenovio volumio[981]: info: Adding Item to queue: qobuz://song/91255196 Oct 02 17:51:52 lenovio volumio[981]: info: Using cached record of: qobuz://song/91255196 Oct 02 17:51:52 lenovio volumio[981]: info: Adding Item to queue: qobuz://song/91255200 Oct 02 17:51:52 lenovio volumio[981]: info: Using cached record of: qobuz://song/91255200 Oct 02 17:51:52 lenovio volumio[981]: info: Adding Item to queue: qobuz://song/179890960 Oct 02 17:51:52 lenovio volumio[981]: info: Using cached record of: qobuz://song/179890960 Oct 02 17:51:52 lenovio volumio[981]: info: Adding Item to queue: qobuz://song/180130154 Oct 02 17:51:52 lenovio volumio[981]: info: Using cached record of: qobuz://song/180130154 Oct 02 17:51:52 lenovio volumio5-onboarding[1428]: time=2026-10-02T17:51:52.586+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.167:38148 @ 0xc0005a3800" state=STATUS_STOPPED positionMs=0 volume= Oct 02 17:51:52 lenovio volumio5-onboarding[1428]: time=2026-10-02T17:51:52.586+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.167:38148 @ 0xc0005a3800" id=qobuz://song/179893536 title="Vieux style" Oct 02 17:51:52 lenovio volumio[981]: info: CoreCommandRouter::volumioPushQueue Oct 02 17:51:52 lenovio volumio[981]: info: CorePlayQueue::saveQueue Oct 02 17:51:52 lenovio volumio[981]: info: CoreStateMachine::updateTrackBlock Oct 02 17:51:52 lenovio volumio[981]: info: CorePlayQueue::getTrackBlock Oct 02 17:51:52 lenovio volumio[981]: info: CoreCommandRouter::volumioPlay Oct 02 17:51:52 lenovio volumio[981]: info: CoreStateMachine::play index 16 Oct 02 17:51:52 lenovio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:51:52 lenovio volumio[981]: info: CoreStateMachine::addQueueItems Oct 02 17:51:52 lenovio volumio[981]: info: CorePlayQueue::addQueueItems Oct 02 17:51:52 lenovio volumio[981]: info: Preload queue cleared Oct 02 17:51:52 lenovio volumio[981]: info: Adding Item to queue: qobuz://song/91255128 Oct 02 17:51:52 lenovio volumio[981]: info: Using cached record of: qobuz://song/91255128 Oct 02 17:51:52 lenovio volumio[981]: info: Adding Item to queue: qobuz://song/179890818 Oct 02 17:51:52 lenovio volumio[981]: info: Using cached record of: qobuz://song/179890818 Oct 02 17:51:52 lenovio volumio[981]: info: Adding Item to queue: qobuz://song/179890820 Oct 02 17:51:52 lenovio volumio[981]: info: Using cached record of: qobuz://song/179890820 Oct 02 17:51:52 lenovio volumio[981]: info: Adding Item to queue: qobuz://song/179890824 Oct 02 17:51:52 lenovio volumio[981]: info: Using cached record of: qobuz://song/179890824 Oct 02 17:51:52 lenovio volumio[981]: info: Adding Item to queue: qobuz://song/179890825 Oct 02 17:51:52 lenovio volumio[981]: info: Using cached record of: qobuz://song/179890825 Oct 02 17:51:52 lenovio volumio[981]: info: CoreStateMachine::stop Oct 02 17:51:52 lenovio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:51:52 lenovio volumio[981]: info: CoreCommandRouter::volumioPushQueue Oct 02 17:51:52 lenovio volumio[981]: info: CorePlayQueue::saveQueue Oct 02 17:51:52 lenovio volumio[981]: info: CoreStateMachine::play index undefined Oct 02 17:51:52 lenovio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:51:52 lenovio volumio[981]: info: CoreStateMachine::updateTrackBlock Oct 02 17:51:52 lenovio volumio[981]: info: CorePlayQueue::getTrackBlock Oct 02 17:51:52 lenovio volumio[981]: info: CorePlayQueue::getTrack 16 Oct 02 17:51:52 lenovio volumio[981]: info: CoreStateMachine::startPlaybackTimer Oct 02 17:51:52 lenovio volumio[981]: info: CorePlayQueue::getTrack 16 Oct 02 17:51:52 lenovio volumio[981]: info: [1790956312591] ControllerQobuz::clearAddPlayTrack Oct 02 17:51:52 lenovio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 17:51:52 lenovio volumio[981]: info: Oct 02 17:51:52 lenovio volumio[981]: ---------------------------- MPD announces state update: player Oct 02 17:51:52 lenovio volumio[981]: info: sendMpdCommand stop took 10 milliseconds Oct 02 17:51:52 lenovio volumio[981]: info: ControllerMpd::getState Oct 02 17:51:52 lenovio volumio[981]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:51:52 lenovio volumio[981]: info: sendMpdCommand stop took 3 milliseconds Oct 02 17:51:52 lenovio volumio[981]: verbose: ControllerMpd::sendMpdCommand clear Oct 02 17:51:52 lenovio volumio[981]: info: Oct 02 17:51:52 lenovio volumio[981]: ---------------------------- MPD announces system playlist update Oct 02 17:51:52 lenovio volumio[981]: info: Ignoring MPD Status Update Oct 02 17:51:52 lenovio volumio[981]: info: sendMpdCommand status took 1 milliseconds Oct 02 17:51:52 lenovio volumio[981]: info: sendMpdCommand clear took 0 milliseconds Oct 02 17:51:52 lenovio volumio[981]: verbose: ControllerMpd::parseState Oct 02 17:51:52 lenovio volumio[981]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:51:52 lenovio volumio[981]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/180130154" Oct 02 17:51:52 lenovio volumio[981]: error: updateQueue error: null Oct 02 17:51:52 lenovio volumio[981]: info: ------------------------------ 1ms Oct 02 17:51:52 lenovio volumio[981]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 17:51:52 lenovio volumio[981]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:51:52 lenovio volumio[981]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 02 17:51:52 lenovio volumio[981]: info: ------------------------------ 2ms Oct 02 17:51:52 lenovio volumio[981]: STREAMING PROXY: Handling url /?data=qobuz://song/180130154 Oct 02 17:51:52 lenovio volumio[981]: info: Executing endpoint getStreamUrlqobuz Oct 02 17:51:52 lenovio volumio[981]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 02 17:51:52 lenovio volumio[981]: info: getStreamUrl took 247 milliseconds Oct 02 17:51:52 lenovio volumio[981]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=243890&eid=180130154&fmt=6&profile=raw&app_id=539451548&cid=1362678&etsp=1790959912&hmac=KHPsYDZygO4y6lmd_GhxkSmBj-0 Oct 02 17:51:52 lenovio volumio[981]: STREAMING PROXY: Requesting upstream with range: none Oct 02 17:51:53 lenovio volumio[981]: STREAMING PROXY: Upstream status code: 200 Oct 02 17:51:53 lenovio volumio[981]: STREAMING PROXY: Client closed connection Oct 02 17:51:53 lenovio volumio[981]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/180130154" Oct 02 17:51:53 lenovio volumio[981]: STREAMING PROXY: Upstream closed. Received 16384 bytes Oct 02 17:51:53 lenovio volumio[981]: STREAMING PROXY: Incomplete transfer. Expected 33202229, got 16384 Oct 02 17:51:53 lenovio volumio[981]: STREAMING PROXY: Retrying with range: bytes=16384- (attempt 1) Oct 02 17:51:53 lenovio volumio[981]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/180130154" took 0 milliseconds Oct 02 17:51:53 lenovio volumio[981]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 17:51:53 lenovio volumio[981]: verbose: ControllerMpd::sendMpdCommand play Oct 02 17:51:53 lenovio volumio[981]: info: Oct 02 17:51:53 lenovio volumio[981]: ---------------------------- MPD announces system playlist update Oct 02 17:51:53 lenovio volumio[981]: info: Ignoring MPD Status Update Oct 02 17:51:53 lenovio volumio[981]: info: sendMpdCommand play took 0 milliseconds Oct 02 17:51:53 lenovio volumio[981]: info: ------------------------------ 0ms Oct 02 17:51:53 lenovio volumio[981]: STREAMING PROXY: Handling url /?data=qobuz://song/180130154 Oct 02 17:51:53 lenovio volumio[981]: info: Executing endpoint getStreamUrlqobuz Oct 02 17:51:53 lenovio volumio[981]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 02 17:51:53 lenovio volumio[981]: info: getStreamUrl took 214 milliseconds Oct 02 17:51:53 lenovio volumio[981]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=243890&eid=180130154&fmt=6&profile=raw&app_id=539451548&cid=1362678&etsp=1790959913&hmac=bjOM5-pTmMDLCMSJr4I7mPdmhBo Oct 02 17:51:53 lenovio volumio[981]: STREAMING PROXY: Requesting upstream with range: none Oct 02 17:51:53 lenovio volumio[981]: STREAMING PROXY: Upstream status code: 200 Oct 02 17:51:53 lenovio volumio[981]: info: Oct 02 17:51:53 lenovio volumio[981]: ---------------------------- MPD announces state update: player Oct 02 17:51:53 lenovio volumio[981]: info: ControllerMpd::getState Oct 02 17:51:53 lenovio volumio[981]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:51:53 lenovio volumio[981]: info: Oct 02 17:51:53 lenovio volumio[981]: ---------------------------- MPD announces state update: player Oct 02 17:51:53 lenovio volumio[981]: info: sendMpdCommand status took 3 milliseconds Oct 02 17:51:53 lenovio volumio[981]: info: ControllerMpd::getState Oct 02 17:51:53 lenovio volumio[981]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:51:53 lenovio volumio[981]: verbose: ControllerMpd::parseState Oct 02 17:51:53 lenovio volumio[981]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:51:53 lenovio volumio[981]: info: sendMpdCommand status took 0 milliseconds Oct 02 17:51:53 lenovio volumio[981]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 02 17:51:53 lenovio volumio[981]: verbose: ControllerMpd::parseState Oct 02 17:51:53 lenovio volumio[981]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:51:53 lenovio volumio[981]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:51:53 lenovio volumio[981]: info: ControllerMpd::pushState Oct 02 17:51:53 lenovio volumio[981]: info: CoreCommandRouter::servicePushState Oct 02 17:51:53 lenovio volumio[981]: info: CorePlayQueue::getTrack 16 Oct 02 17:51:53 lenovio volumio[981]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":322,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"180130154","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/180130154","trackType":"qobuz"} Oct 02 17:51:53 lenovio volumio[981]: verbose: CURRENT POSITION 16 Oct 02 17:51:53 lenovio volumio[981]: info: CoreStateMachine::syncState stateService play Oct 02 17:51:53 lenovio volumio[981]: info: CoreStateMachine::syncState currentStatus stop Oct 02 17:51:53 lenovio volumio[981]: info: ------------------------------ 4ms Oct 02 17:51:53 lenovio volumio[981]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 02 17:51:53 lenovio volumio[981]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:51:53 lenovio volumio[981]: info: ControllerMpd::pushState Oct 02 17:51:53 lenovio volumio[981]: info: CoreCommandRouter::servicePushState Oct 02 17:51:53 lenovio volumio[981]: info: CorePlayQueue::getTrack 16 Oct 02 17:51:53 lenovio volumio[981]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":322,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"180130154","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/180130154","trackType":"qobuz"} Oct 02 17:51:53 lenovio volumio[981]: verbose: CURRENT POSITION 16 Oct 02 17:51:53 lenovio volumio[981]: info: CoreStateMachine::syncState stateService play Oct 02 17:51:53 lenovio volumio[981]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:51:53 lenovio volumio[981]: info: Received an update from plugin. extracting info from payload Oct 02 17:51:53 lenovio volumio[981]: info: CoreStateMachine::pushState Oct 02 17:51:53 lenovio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:51:53 lenovio volumio[981]: info: CoreCommandRouter::volumioPushState Oct 02 17:51:53 lenovio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Oct 02 17:51:53 lenovio volumio[981]: info: MRS: Pushing multiroomSync output Oct 02 17:51:53 lenovio volumio[981]: info: CoreCommandRouter::volumioGetState Oct 02 17:51:53 lenovio volumio[981]: info: CoreStateMachine::pushState Oct 02 17:51:53 lenovio volumio[981]: info: CoreCommandRouter::volumioPushState Oct 02 17:51:53 lenovio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Oct 02 17:51:53 lenovio volumio[981]: info: MRS: Pushing multiroomSync output Oct 02 17:51:53 lenovio volumio[981]: info: CoreCommandRouter::volumioGetState Oct 02 17:51:53 lenovio volumio5-onboarding[1428]: time=2026-10-02T17:51:53.311+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.167:38148 @ 0xc0005a3800" state=STATUS_PLAYING positionMs=0 volume= Oct 02 17:51:53 lenovio volumio5-onboarding[1428]: time=2026-10-02T17:51:53.311+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.167:38148 @ 0xc0005a3800" id=qobuz://song/180130154 title="Les vacances au bord de la mer (Live au Casino de Paris, 2009)" Oct 02 17:51:53 lenovio volumio5-onboarding[1428]: time=2026-10-02T17:51:53.311+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.167:38148 @ 0xc0005a3800" state=STATUS_PLAYING positionMs=0 volume= Oct 02 17:51:53 lenovio volumio5-onboarding[1428]: time=2026-10-02T17:51:53.311+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.167:38148 @ 0xc0005a3800" id=qobuz://song/180130154 title="Les vacances au bord de la mer (Live au Casino de Paris, 2009)" Oct 02 17:51:53 lenovio volumio[981]: info: ------------------------------ 7ms Oct 02 17:51:53 lenovio volumio[981]: STREAMING PROXY: Upstream closed. Received 33202229 bytes Oct 02 17:51:53 lenovio volumio[981]: STREAMING PROXY: Client closed connection Oct 02 17:51:54 lenovio volumio[981]: STREAMING PROXY: Resuming with range: bytes=16384- Oct 02 17:51:54 lenovio volumio[981]: STREAMING PROXY: Resume upstream status code: 206 Oct 02 17:51:54 lenovio volumio[981]: STREAMING PROXY: Resume ended. Received 33185845 bytes Oct 02 17:51:55 lenovio volumio[981]: info: Executing endpoint metavolumio Oct 02 17:51:55 lenovio volumio[981]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 02 17:51:55 lenovio volumio[981]: info: Executing endpoint metavolumio Oct 02 17:51:55 lenovio volumio[981]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 02 17:51:55 lenovio volumio[981]: info: Executing endpoint metavolumio Oct 02 17:51:55 lenovio volumio[981]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 02 17:51:58 lenovio volumio[981]: info: CoreCommandRouter::volumioSeek Oct 02 17:51:58 lenovio volumio[981]: info: CoreStateMachine::seek Oct 02 17:51:58 lenovio volumio[981]: info: CorePlayQueue::getTrack 16 Oct 02 17:51:58 lenovio volumio[981]: info: TRACKBLOCK {"album":"Michel Jonasz Trio","albumUri":"qobuz://album/p0cdx7l602kya","albumart":"https://static.qobuz.com/images/covers/ya/2k/p0cdx7l602kya_600.jpg","artist":"Michel Jonasz","artistUri":"qobuz://artist/40318","audioQuality":"","duration":321,"explicit":false,"name":"Les vacances au bord de la mer (Live au Casino de Paris, 2009)","service":"qobuz","title":"Les vacances au bord de la mer (Live au Casino de Paris, 2009)","tracknumber":7,"type":"track","uri":"qobuz://song/180130154","volumeNumber":1,"tagImage":""} Oct 02 17:51:58 lenovio volumio[981]: info: CoreStateMachine::startPlaybackTimer Oct 02 17:51:58 lenovio volumio[981]: info: CorePlayQueue::getTrack 16 Oct 02 17:51:58 lenovio volumio[981]: info: [1790956318874] ControllerQobuz::seek Oct 02 17:51:58 lenovio volumio[981]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 17:51:58 lenovio volumio[981]: info: ControllerMpd::seek Oct 02 17:51:58 lenovio volumio[981]: info: CoreStateMachine::pushState Oct 02 17:51:58 lenovio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:51:58 lenovio volumio[981]: info: CoreCommandRouter::volumioPushState Oct 02 17:51:58 lenovio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Oct 02 17:51:58 lenovio volumio[981]: info: MRS: Pushing multiroomSync output Oct 02 17:51:58 lenovio volumio[981]: info: CoreCommandRouter::volumioGetState Oct 02 17:51:58 lenovio volumio5-onboarding[1428]: time=2026-10-02T17:51:58.877+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.167:38148 @ 0xc0005a3800" state=STATUS_PLAYING positionMs=74000 volume= Oct 02 17:51:58 lenovio volumio5-onboarding[1428]: time=2026-10-02T17:51:58.877+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.167:38148 @ 0xc0005a3800" id=qobuz://song/180130154 title="Les vacances au bord de la mer (Live au Casino de Paris, 2009)" Oct 02 17:51:58 lenovio volumio[981]: error: null Oct 02 17:51:58 lenovio volumio[981]: info: Oct 02 17:51:58 lenovio volumio[981]: ---------------------------- MPD announces state update: player Oct 02 17:51:58 lenovio volumio[981]: info: ControllerMpd::getState Oct 02 17:51:58 lenovio volumio[981]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:51:58 lenovio volumio[981]: info: sendMpdCommand status took 2 milliseconds Oct 02 17:51:58 lenovio volumio[981]: verbose: ControllerMpd::parseState Oct 02 17:51:58 lenovio volumio[981]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:51:58 lenovio volumio[981]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 02 17:51:58 lenovio volumio[981]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:51:58 lenovio volumio[981]: info: ControllerMpd::pushState Oct 02 17:51:58 lenovio volumio[981]: info: CoreCommandRouter::servicePushState Oct 02 17:51:58 lenovio volumio[981]: info: CorePlayQueue::getTrack 16 Oct 02 17:51:58 lenovio volumio[981]: verbose: STATE SERVICE {"status":"play","position":0,"seek":75206,"duration":322,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"803 Kbps","isStreaming":false,"title":"180130154","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/180130154","trackType":"qobuz"} Oct 02 17:51:58 lenovio volumio[981]: verbose: CURRENT POSITION 16 Oct 02 17:51:58 lenovio volumio[981]: info: CoreStateMachine::syncState stateService play Oct 02 17:51:58 lenovio volumio[981]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:51:58 lenovio volumio[981]: info: Received an update from plugin. extracting info from payload Oct 02 17:51:58 lenovio volumio[981]: info: CoreStateMachine::pushState Oct 02 17:51:58 lenovio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:51:58 lenovio volumio[981]: info: CoreCommandRouter::volumioPushState Oct 02 17:51:58 lenovio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Oct 02 17:51:58 lenovio volumio[981]: info: MRS: Pushing multiroomSync output Oct 02 17:51:58 lenovio volumio[981]: info: CoreCommandRouter::volumioGetState Oct 02 17:51:58 lenovio volumio[981]: info: CoreStateMachine::pushState Oct 02 17:51:58 lenovio volumio[981]: info: CoreCommandRouter::volumioPushState Oct 02 17:51:58 lenovio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Oct 02 17:51:58 lenovio volumio[981]: info: MRS: Pushing multiroomSync output Oct 02 17:51:58 lenovio volumio[981]: info: CoreCommandRouter::volumioGetState Oct 02 17:51:58 lenovio volumio5-onboarding[1428]: time=2026-10-02T17:51:58.884+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.167:38148 @ 0xc0005a3800" state=STATUS_PLAYING positionMs=74000 volume= Oct 02 17:51:58 lenovio volumio5-onboarding[1428]: time=2026-10-02T17:51:58.884+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.167:38148 @ 0xc0005a3800" id=qobuz://song/180130154 title="Les vacances au bord de la mer (Live au Casino de Paris, 2009)" Oct 02 17:51:58 lenovio volumio[981]: info: ------------------------------ 7ms Oct 02 17:52:02 lenovio volumio[981]: info: CoreCommandRouter::volumioSeek Oct 02 17:52:02 lenovio volumio[981]: info: CoreStateMachine::seek Oct 02 17:52:02 lenovio volumio[981]: info: CorePlayQueue::getTrack 16 Oct 02 17:52:02 lenovio volumio[981]: info: TRACKBLOCK {"album":"Michel Jonasz Trio","albumUri":"qobuz://album/p0cdx7l602kya","albumart":"https://static.qobuz.com/images/covers/ya/2k/p0cdx7l602kya_600.jpg","artist":"Michel Jonasz","artistUri":"qobuz://artist/40318","audioQuality":"","duration":321,"explicit":false,"name":"Les vacances au bord de la mer (Live au Casino de Paris, 2009)","service":"qobuz","title":"Les vacances au bord de la mer (Live au Casino de Paris, 2009)","tracknumber":7,"type":"track","uri":"qobuz://song/180130154","volumeNumber":1,"tagImage":""} Oct 02 17:52:02 lenovio volumio[981]: info: CoreStateMachine::startPlaybackTimer Oct 02 17:52:02 lenovio volumio[981]: info: CorePlayQueue::getTrack 16 Oct 02 17:52:02 lenovio volumio[981]: info: [1790956322630] ControllerQobuz::seek Oct 02 17:52:02 lenovio volumio[981]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 17:52:02 lenovio volumio[981]: info: ControllerMpd::seek Oct 02 17:52:02 lenovio volumio[981]: info: CoreStateMachine::pushState Oct 02 17:52:02 lenovio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:52:02 lenovio volumio[981]: info: CoreCommandRouter::volumioPushState Oct 02 17:52:02 lenovio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Oct 02 17:52:02 lenovio volumio[981]: info: MRS: Pushing multiroomSync output Oct 02 17:52:02 lenovio volumio[981]: info: CoreCommandRouter::volumioGetState Oct 02 17:52:02 lenovio volumio5-onboarding[1428]: time=2026-10-02T17:52:02.632+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.167:38148 @ 0xc0005a3800" state=STATUS_PLAYING positionMs=107000 volume= Oct 02 17:52:02 lenovio volumio5-onboarding[1428]: time=2026-10-02T17:52:02.632+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.167:38148 @ 0xc0005a3800" id=qobuz://song/180130154 title="Les vacances au bord de la mer (Live au Casino de Paris, 2009)" Oct 02 17:52:02 lenovio volumio[981]: error: null Oct 02 17:52:02 lenovio volumio[981]: info: Oct 02 17:52:02 lenovio volumio[981]: ---------------------------- MPD announces state update: player Oct 02 17:52:02 lenovio volumio[981]: info: ControllerMpd::getState Oct 02 17:52:02 lenovio volumio[981]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:52:02 lenovio volumio[981]: info: sendMpdCommand status took 1 milliseconds Oct 02 17:52:02 lenovio volumio[981]: verbose: ControllerMpd::parseState Oct 02 17:52:02 lenovio volumio[981]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:52:02 lenovio volumio[981]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 02 17:52:02 lenovio volumio[981]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:52:02 lenovio volumio[981]: info: ControllerMpd::pushState Oct 02 17:52:02 lenovio volumio[981]: info: CoreCommandRouter::servicePushState Oct 02 17:52:02 lenovio volumio[981]: info: CorePlayQueue::getTrack 16 Oct 02 17:52:02 lenovio volumio[981]: verbose: STATE SERVICE {"status":"play","position":0,"seek":108206,"duration":322,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"791 Kbps","isStreaming":false,"title":"180130154","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/180130154","trackType":"qobuz"} Oct 02 17:52:02 lenovio volumio[981]: verbose: CURRENT POSITION 16 Oct 02 17:52:02 lenovio volumio[981]: info: CoreStateMachine::syncState stateService play Oct 02 17:52:02 lenovio volumio[981]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:52:02 lenovio volumio[981]: info: Received an update from plugin. extracting info from payload Oct 02 17:52:02 lenovio volumio[981]: info: CoreStateMachine::pushState Oct 02 17:52:02 lenovio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:52:02 lenovio volumio[981]: info: CoreCommandRouter::volumioPushState Oct 02 17:52:02 lenovio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Oct 02 17:52:02 lenovio volumio[981]: info: MRS: Pushing multiroomSync output Oct 02 17:52:02 lenovio volumio[981]: info: CoreCommandRouter::volumioGetState Oct 02 17:52:02 lenovio volumio[981]: info: CoreStateMachine::pushState Oct 02 17:52:02 lenovio volumio[981]: info: CoreCommandRouter::volumioPushState Oct 02 17:52:02 lenovio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Oct 02 17:52:02 lenovio volumio[981]: info: MRS: Pushing multiroomSync output Oct 02 17:52:02 lenovio volumio[981]: info: CoreCommandRouter::volumioGetState Oct 02 17:52:02 lenovio volumio5-onboarding[1428]: time=2026-10-02T17:52:02.639+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.167:38148 @ 0xc0005a3800" state=STATUS_PLAYING positionMs=107000 volume= Oct 02 17:52:02 lenovio volumio5-onboarding[1428]: time=2026-10-02T17:52:02.639+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.167:38148 @ 0xc0005a3800" id=qobuz://song/180130154 title="Les vacances au bord de la mer (Live au Casino de Paris, 2009)" Oct 02 17:52:02 lenovio volumio5-onboarding[1428]: time=2026-10-02T17:52:02.639+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.167:38148 @ 0xc0005a3800" state=STATUS_PLAYING positionMs=107000 volume= Oct 02 17:52:02 lenovio volumio5-onboarding[1428]: time=2026-10-02T17:52:02.639+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.167:38148 @ 0xc0005a3800" id=qobuz://song/180130154 title="Les vacances au bord de la mer (Live au Casino de Paris, 2009)" Oct 02 17:52:02 lenovio volumio[981]: info: ------------------------------ 6ms Oct 02 17:52:14 lenovio volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 17:52:14 lenovio volumio[981]: info: CURURI: playlists/justanotherPL Oct 02 17:52:14 lenovio volumio[981]: info: Preload queue cleared Oct 02 17:52:14 lenovio volumio[981]: info: Preloading song: qobuz://song/91838024 Oct 02 17:52:14 lenovio volumio[981]: info: Preloading song: NAS/NAS-MUSIC/1975 Murray Head - Say It Ain t So 2017 24-96/01. Say It Ain't So.flac Oct 02 17:52:14 lenovio volumio[981]: info: Preloading song: NAS/NAS-MUSIC/1976 Genesis - A Trick Of The Tail-DSD/05 - Robbery, Assault And Battery.dsf Oct 02 17:52:14 lenovio volumio[981]: info: Preloading song: NAS/NAS-MUSIC/1976 Genesis - A Trick Of The Tail-DSD/06 - Ripples.dsf Oct 02 17:52:14 lenovio volumio[981]: info: Preloading song: cue://NAS/NAS-MUSIC/1977 - Fleetwood Mac - Rumours (1988, Warner-Pioneer, Japan, 20P2-2036)/Fleetwood Mac - Rumours.cue@4 Oct 02 17:52:14 lenovio volumio[981]: info: Preloading song: NAS/NAS-MUSIC/1980 Eric Clapton - Just One Night 2014 24-192/04 - Wonderful Tonight.flac Oct 02 17:52:14 lenovio volumio[981]: info: Preloading song: NAS/NAS-MUSIC/1984 Bruce Springsteen - Born In The U S A 2014 24-96/10. Glory Days.flac Oct 02 17:52:14 lenovio volumio[981]: info: Preloading song: NAS/NAS-MUSIC/Ben Harper - Fight For Your Mind (2016) [24bit Hi-Res]/06. Burn One Down.flac Oct 02 17:52:14 lenovio volumio[981]: info: Preloading song: NAS/NAS-MUSIC/Bill_Withers_-_Bill_Withers__Greatest_Hits__1981___SACD___2016_MFSL_Remaster_ISO/03 - BILL WITHERS - Ain't No Sunshine.dsf Oct 02 17:52:14 lenovio volumio[981]: info: Preloading song: NAS/NAS-MUSIC/Bill_Withers_-_Bill_Withers__Greatest_Hits__1981___SACD___2016_MFSL_Remaster_ISO/04 - BILL WITHERS - Lovely Day.dsf Oct 02 17:52:14 lenovio volumio[981]: info: Preloading song: NAS/NAS-MUSIC/Bill_Withers_-_Bill_Withers__Greatest_Hits__1981___SACD___2016_MFSL_Remaster_ISO/01 - BILL WITHERS - Just The Two Of Us.dsf Oct 02 17:52:14 lenovio volumio[981]: info: Preloading song: NAS/NAS-MUSIC/Billy Paul - 360 Degrees of Billy Paul/04 Billy Paul - Me and Mrs. Jones.flac Oct 02 17:52:14 lenovio volumio[981]: info: Exploding uri qobuz://song/91838024 in service qobuz Oct 02 17:52:14 lenovio volumio[981]: https://prod.vlmapi.io/v2/qobuz/explodeUri Oct 02 17:52:14 lenovio volumio[981]: info: Exploding uri NAS/NAS-MUSIC/1975 Murray Head - Say It Ain t So 2017 24-96/01. Say It Ain't So.flac in service mpd Oct 02 17:52:14 lenovio volumio[981]: error: scanFolder - failure to stat '/mnt/NAS/NAS-MUSIC/1975 Murray Head - Say It Ain t So 2017 24-96/01. Say It Ain't So.flac' Oct 02 17:52:14 lenovio volumio[981]: info: Exploding uri NAS/NAS-MUSIC/1976 Genesis - A Trick Of The Tail-DSD/05 - Robbery, Assault And Battery.dsf in service mpd Oct 02 17:52:14 lenovio volumio[981]: error: scanFolder - failure to stat '/mnt/NAS/NAS-MUSIC/1976 Genesis - A Trick Of The Tail-DSD/05 - Robbery, Assault And Battery.dsf' Oct 02 17:52:14 lenovio volumio[981]: info: Exploding uri NAS/NAS-MUSIC/1976 Genesis - A Trick Of The Tail-DSD/06 - Ripples.dsf in service mpd Oct 02 17:52:14 lenovio volumio[981]: error: scanFolder - failure to stat '/mnt/NAS/NAS-MUSIC/1976 Genesis - A Trick Of The Tail-DSD/06 - Ripples.dsf' Oct 02 17:52:14 lenovio volumio[981]: info: explodeUri took 186 milliseconds Oct 02 17:52:14 lenovio volumio[981]: info: Exploding uri cue://NAS/NAS-MUSIC/1977 - Fleetwood Mac - Rumours (1988, Warner-Pioneer, Japan, 20P2-2036)/Fleetwood Mac - Rumours.cue@4 in service mpd Oct 02 17:52:14 lenovio volumio[981]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 17:52:14 lenovio volumio[981]: Error: file /mnt/NAS/NAS-MUSIC/1977 - Fleetwood Mac - Rumours (1988, Warner-Pioneer, Japan, 20P2-2036)/Fleetwood Mac - Rumours.cue does not exist Oct 02 17:52:14 lenovio volumio[981]: at Object.parse (/volumio/node_modules/cue-parser/lib/cue.js:37:15) Oct 02 17:52:14 lenovio volumio[981]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:1938:27) Oct 02 17:52:14 lenovio volumio[981]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:16) Oct 02 17:52:14 lenovio volumio[981]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Oct 02 17:52:14 lenovio volumio[981]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Oct 02 17:52:14 lenovio volumio[981]: at Timeout._onTimeout (/volumio/app/playqueue.js:146:100) Oct 02 17:52:14 lenovio volumio[981]: at listOnTimeout (internal/timers.js:557:17) Oct 02 17:52:14 lenovio volumio[981]: at processTimers (internal/timers.js:500:7) Oct 02 17:52:14 lenovio volumio[981]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 17:52:14 lenovio sudo[9361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-02 17:51 Oct 02 17:52:14 lenovio sudo[9361]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="3dada8b1e619a5feb94867e0865ace17474d7bce" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="30045b259d1a704a832ca7c1460c1fdfa4b723f9" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Mar 2026 09:58:18 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="54b3518c7dd8feba4753e0ad124ce801"