-- Logs begin at Thu 2025-10-16 17:05:44 EDT, end at Fri 2025-10-17 11:04:53 EDT. -- Oct 17 11:03:08 volumio volumio[10552]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 17 11:03:08 volumio volumio[10552]: info: Preload queue cleared Oct 17 11:03:08 volumio go-librespot[10735]: time="2025-10-17T11:03:08-04:00" level=trace msg="sent dealer ping" Oct 17 11:03:08 volumio go-librespot[10735]: time="2025-10-17T11:03:08-04:00" level=trace msg="received dealer pong" Oct 17 11:03:08 volumio nmbd[810]: [2025/10/17 11:03:08.795347, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 17 11:03:08 volumio nmbd[810]: query_name_response: Multiple (2) responses received for a query on subnet 10.125.51.18 for name WORKGROUP<1d>. Oct 17 11:03:08 volumio nmbd[810]: This response was from IP 10.125.51.25, reporting an IP address of 10.125.51.25. Oct 17 11:03:08 volumio nmbd[810]: [2025/10/17 11:03:08.795595, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 17 11:03:08 volumio nmbd[810]: query_name_response: Multiple (2) responses received for a query on subnet 10.125.51.21 for name WORKGROUP<1d>. Oct 17 11:03:08 volumio nmbd[810]: This response was from IP 10.125.51.25, reporting an IP address of 10.125.51.25. Oct 17 11:03:09 volumio volumio[10552]: info: CoreCommandRouter::volumioGetState Oct 17 11:03:19 volumio volumio[10552]: info: CoreCommandRouter::volumioGetState Oct 17 11:03:19 volumio volumio[10552]: info: Listing playlists Oct 17 11:03:19 volumio volumio[10552]: info: Listing playlists Oct 17 11:03:21 volumio go-librespot[10735]: time="2025-10-17T11:03:21-04:00" level=trace msg="received accesspoint ping" Oct 17 11:03:21 volumio go-librespot[10735]: time="2025-10-17T11:03:21-04:00" level=trace msg="received accesspoint pong ack" Oct 17 11:03:24 volumio volumio[10552]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 17 11:03:25 volumio volumio[10552]: info: Preload queue cleared Oct 17 11:03:29 volumio volumio[10552]: info: CoreCommandRouter::volumioGetState Oct 17 11:03:34 volumio volumio[10552]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 17 11:03:34 volumio volumio[10552]: info: Preload queue cleared Oct 17 11:03:36 volumio volumio[10552]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 17 11:03:36 volumio volumio[10552]: info: Preload queue cleared Oct 17 11:03:36 volumio volumio[10552]: info: Preloading song: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100261 Oct 17 11:03:36 volumio volumio[10552]: info: Preloading song: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100262 Oct 17 11:03:36 volumio volumio[10552]: info: Preloading song: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100263 Oct 17 11:03:36 volumio volumio[10552]: info: Preloading song: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100264 Oct 17 11:03:36 volumio volumio[10552]: info: Preloading song: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100265 Oct 17 11:03:36 volumio volumio[10552]: info: Preloading song: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100266 Oct 17 11:03:36 volumio volumio[10552]: info: Preloading song: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100267 Oct 17 11:03:36 volumio volumio[10552]: info: Preloading song: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100268 Oct 17 11:03:36 volumio volumio[10552]: info: Preloading song: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100269 Oct 17 11:03:36 volumio volumio[10552]: info: Preloading song: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100270 Oct 17 11:03:36 volumio volumio[10552]: info: Preloading song: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100271 Oct 17 11:03:36 volumio volumio[10552]: info: Preloading song: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100272 Oct 17 11:03:36 volumio volumio[10552]: info: Preloading song: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100273 Oct 17 11:03:36 volumio volumio[10552]: info: Preloading song: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100274 Oct 17 11:03:36 volumio volumio[10552]: info: Preloading song: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100275 Oct 17 11:03:36 volumio volumio[10552]: info: Exploding uri upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100261 in service upnp_browser Oct 17 11:03:36 volumio volumio[10552]: info: Exploding uri upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100262 in service upnp_browser Oct 17 11:03:36 volumio volumio[10552]: info: Exploding uri upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100263 in service upnp_browser Oct 17 11:03:36 volumio volumio[10552]: info: Exploding uri upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100264 in service upnp_browser Oct 17 11:03:36 volumio volumio[10552]: info: Exploding uri upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100265 in service upnp_browser Oct 17 11:03:36 volumio volumio[10552]: info: Exploding uri upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100266 in service upnp_browser Oct 17 11:03:36 volumio volumio[10552]: info: Exploding uri upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100267 in service upnp_browser Oct 17 11:03:36 volumio volumio[10552]: info: Exploding uri upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100268 in service upnp_browser Oct 17 11:03:36 volumio volumio[10552]: info: Exploding uri upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100269 in service upnp_browser Oct 17 11:03:36 volumio volumio[10552]: info: Exploding uri upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100270 in service upnp_browser Oct 17 11:03:36 volumio volumio[10552]: info: Exploding uri upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100271 in service upnp_browser Oct 17 11:03:36 volumio volumio[10552]: info: Exploding uri upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100272 in service upnp_browser Oct 17 11:03:36 volumio volumio[10552]: info: Exploding uri upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100273 in service upnp_browser Oct 17 11:03:36 volumio volumio[10552]: info: Exploding uri upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100274 in service upnp_browser Oct 17 11:03:36 volumio volumio[10552]: info: Exploding uri upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100275 in service upnp_browser Oct 17 11:03:37 volumio volumio[10552]: info: Preload queue cleared Oct 17 11:03:37 volumio volumio[10552]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 17 11:03:37 volumio volumio[10552]: info: CoreStateMachine::ClearQueue Oct 17 11:03:37 volumio volumio[10552]: info: CoreStateMachine::stop Oct 17 11:03:37 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:03:37 volumio volumio[10552]: info: CoreStateMachine::updateTrackBlock Oct 17 11:03:37 volumio volumio[10552]: info: CorePlayQueue::getTrackBlock Oct 17 11:03:37 volumio volumio[10552]: info: CoreStateMachine::stPlaybackTimer Oct 17 11:03:37 volumio volumio[10552]: info: CoreStateMachine::pushState Oct 17 11:03:37 volumio volumio[10552]: info: CorePlayQueue::getTrack 3 Oct 17 11:03:37 volumio volumio[10552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 11:03:37 volumio volumio[10552]: info: CoreCommandRouter::volumioPushState Oct 17 11:03:37 volumio volumio[10552]: info: CoreStateMachine::serviceStop Oct 17 11:03:37 volumio volumio[10552]: info: CorePlayQueue::getTrack 3 Oct 17 11:03:37 volumio volumio[10552]: info: CoreCommandRouter::serviceStop Oct 17 11:03:37 volumio volumio[10552]: info: [1760713417482] ControllerPandora::stop Oct 17 11:03:37 volumio volumio[10552]: info: ControllerMpd::stop Oct 17 11:03:37 volumio volumio[10552]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 11:03:37 volumio volumio[10552]: info: CorePlayQueue::clearPlayQueue Oct 17 11:03:37 volumio volumio[10552]: info: CorePlayQueue::saveQueue Oct 17 11:03:37 volumio volumio[10552]: info: CoreCommandRouter::volumioPushQueue Oct 17 11:03:37 volumio volumio[10552]: info: CoreStateMachine::addQueueItems Oct 17 11:03:37 volumio volumio[10552]: info: CorePlayQueue::addQueueItems Oct 17 11:03:37 volumio volumio[10552]: info: Preload queue cleared Oct 17 11:03:37 volumio volumio[10552]: info: Adding Item to queue: upnp/folder/http://10.125.51.50:50001/ContentDirectory/control@22$8664 Oct 17 11:03:37 volumio volumio[10552]: info: Exploding uri upnp/folder/http://10.125.51.50:50001/ContentDirectory/control@22$8664 in service upnp_browser Oct 17 11:03:37 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100261 Oct 17 11:03:37 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100261 Oct 17 11:03:37 volumio volumio[10552]: info: ONKYO-CONTROL: New state: {"status":"stop","position":3,"title":"Love Me","artist":"Yiruma","album":"Healing Piano","albumart":"http://cont.p-cdn.us/images/f0/24/16/d6/f3d243a5a3645c48d5c46376/1080W_1080H.jpg","uri":"/pandora/stationToken=4620127804298520857trackId=8488494022396840820","trackType":"mp3","seek":0,"duration":245,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"repeat":true,"repeatSingle":false,"consume":false,"volume":30,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"mp3","volatile":false,"service":"pandora"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"10.125.51.14","model":"TX-NR7100"} Oct 17 11:03:37 volumio volumio[10552]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Oct 17 11:03:37 volumio volumio[10552]: info: Oct 17 11:03:37 volumio volumio[10552]: ---------------------------- MPD announces state update: player Oct 17 11:03:37 volumio volumio[10552]: info: sendMpdCommand stop took 14 milliseconds Oct 17 11:03:37 volumio volumio[10552]: info: ControllerMpd::getState Oct 17 11:03:37 volumio volumio[10552]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:03:37 volumio volumio[10552]: info: ControllerMpd::clear Oct 17 11:03:37 volumio volumio[10552]: verbose: ControllerMpd::sendMpdCommand clear Oct 17 11:03:37 volumio volumio[10552]: info: Oct 17 11:03:37 volumio volumio[10552]: ---------------------------- MPD announces system playlist update Oct 17 11:03:37 volumio volumio[10552]: info: Ignoring MPD Status Update Oct 17 11:03:37 volumio volumio[10552]: info: sendMpdCommand status took 1 milliseconds Oct 17 11:03:37 volumio volumio[10552]: info: sendMpdCommand clear took 1 milliseconds Oct 17 11:03:37 volumio volumio[10552]: verbose: ControllerMpd::parseState Oct 17 11:03:37 volumio volumio[10552]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:03:37 volumio volumio[10552]: info: [1760713417500] ControllerPandora::pushState Oct 17 11:03:37 volumio volumio[10552]: info: CoreCommandRouter::servicePushState Oct 17 11:03:37 volumio volumio[10552]: info: CoreStateMachine::pushState Oct 17 11:03:37 volumio volumio[10552]: info: CorePlayQueue::getTrack 3 Oct 17 11:03:37 volumio volumio[10552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 11:03:37 volumio volumio[10552]: info: CoreCommandRouter::volumioPushState Oct 17 11:03:37 volumio volumio[10552]: info: CorePlayQueue::getTrack 3 Oct 17 11:03:37 volumio volumio[10552]: verbose: STATE SERVICE {"service":"pandora","type":"song","trackType":"mp3","title":"Love Me","name":"Love Me","artist":"Yiruma","album":"Healing Piano","albumart":"http://cont.p-cdn.us/images/f0/24/16/d6/f3d243a5a3645c48d5c46376/1080W_1080H.jpg","realUri":"http://audio-usc-mp1-t1-1-v4v6.pandora.com/access/8488494022396840820.mp3?version=5&lid=1881041177&token=OTkFI9%2B5ypySgoAiRHQG3ct1kdszwBFb9sN%2FSAPfhMjdUo80JYFEFlRuTBoTj%2Bi9G3bWMMYONop0HjLz9qysDik%2FwhcAjxQThFw50pVshgjG3kFRsIm4upSKIPusomXPg5LX7cCrwpl363mHkuDLZmokQy7Jx%2BSPDD3bkBF3aspYR4OECucA7nMaFS%2BrR6LNRhxeS2m9kXt4rqFeU1194bVrH4drhmv9LUNHNhp8nn3zccB2rnPeAmH7J4ojacKAEw%2BFdclNyEybEmANwSxK0dfw2ARHVVglPNV0uDKhilOiZUuWQ3txuSSNfNYnzw5HWPrI%2Fonb4qZWiJXOQDeiEA%3D%3D","isStreaming":true,"duration":245,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"uri":"http://audio-usc-mp1-t1-1-v4v6.pandora.com/access/8488494022396840820.mp3?version=5&lid=1881041177&token=OTkFI9%2B5ypySgoAiRHQG3ct1kdszwBFb9sN%2FSAPfhMjdUo80JYFEFlRuTBoTj%2Bi9G3bWMMYONop0HjLz9qysDik%2FwhcAjxQThFw50pVshgjG3kFRsIm4upSKIPusomXPg5LX7cCrwpl363mHkuDLZmokQy7Jx%2BSPDD3bkBF3aspYR4OECucA7nMaFS%2BrR6LNRhxeS2m9kXt4rqFeU1194bVrH4drhmv9LUNHNhp8nn3zccB2rnPeAmH7J4ojacKAEw%2BFdclNyEybEmANwSxK0dfw2ARHVVglPNV0uDKhilOiZUuWQ3txuSSNfNYnzw5HWPrI%2Fonb4qZWiJXOQDeiEA%3D%3D","seek":168127,"status":"stop"} Oct 17 11:03:37 volumio volumio[10552]: verbose: CURRENT POSITION 3 Oct 17 11:03:37 volumio volumio[10552]: info: CoreStateMachine::syncState stateService stop Oct 17 11:03:37 volumio volumio[10552]: info: CoreStateMachine::syncState currentStatus stop Oct 17 11:03:37 volumio volumio[10552]: info: CoreStateMachine::pushState Oct 17 11:03:37 volumio volumio[10552]: info: CorePlayQueue::getTrack 3 Oct 17 11:03:37 volumio volumio[10552]: info: CoreCommandRouter::volumioPushState Oct 17 11:03:37 volumio volumio[10552]: info: No code Oct 17 11:03:37 volumio volumio[10552]: info: CoreStateMachine::pushState Oct 17 11:03:37 volumio volumio[10552]: info: CorePlayQueue::getTrack 3 Oct 17 11:03:37 volumio volumio[10552]: info: CoreCommandRouter::volumioPushState Oct 17 11:03:37 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService pandora Oct 17 11:03:37 volumio volumio[10552]: error: updateQueue error: null Oct 17 11:03:37 volumio volumio[10552]: info: ------------------------------ 14ms Oct 17 11:03:37 volumio volumio[10552]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 17 11:03:37 volumio volumio[10552]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:03:37 volumio volumio[10552]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 17 11:03:37 volumio volumio[10552]: info: ------------------------------ 18ms Oct 17 11:03:37 volumio volumio[10552]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":30,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":false,"repeat":true,"repeatSingle":false,"consume":false} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"10.125.51.14","model":"TX-NR7100"} Oct 17 11:03:37 volumio volumio[10552]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":30,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":false,"repeat":true,"repeatSingle":false,"consume":false} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"10.125.51.14","model":"TX-NR7100"} Oct 17 11:03:37 volumio volumio[10552]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":30,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":false,"repeat":true,"repeatSingle":false,"consume":false} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"10.125.51.14","model":"TX-NR7100"} Oct 17 11:03:37 volumio volumio[10552]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Oct 17 11:03:37 volumio volumio[10552]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Oct 17 11:03:37 volumio volumio[10552]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Oct 17 11:03:37 volumio volumio[10552]: info: CoreCommandRouter::volumioPushQueue Oct 17 11:03:37 volumio volumio[10552]: info: CorePlayQueue::saveQueue Oct 17 11:03:37 volumio volumio[10552]: info: CoreStateMachine::updateTrackBlock Oct 17 11:03:37 volumio volumio[10552]: info: CorePlayQueue::getTrackBlock Oct 17 11:03:37 volumio volumio[10552]: info: CoreCommandRouter::volumioPlay Oct 17 11:03:37 volumio volumio[10552]: info: CoreStateMachine::play index 1 Oct 17 11:03:37 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:03:37 volumio volumio[10552]: info: CoreStateMachine::addQueueItems Oct 17 11:03:37 volumio volumio[10552]: info: CorePlayQueue::addQueueItems Oct 17 11:03:37 volumio volumio[10552]: info: Preload queue cleared Oct 17 11:03:37 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100262 Oct 17 11:03:37 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100262 Oct 17 11:03:37 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100263 Oct 17 11:03:37 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100263 Oct 17 11:03:37 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100264 Oct 17 11:03:37 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100264 Oct 17 11:03:37 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100265 Oct 17 11:03:37 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100265 Oct 17 11:03:37 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100266 Oct 17 11:03:37 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100266 Oct 17 11:03:37 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100267 Oct 17 11:03:37 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100267 Oct 17 11:03:37 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100268 Oct 17 11:03:37 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100268 Oct 17 11:03:37 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100269 Oct 17 11:03:37 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100269 Oct 17 11:03:37 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100270 Oct 17 11:03:37 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100270 Oct 17 11:03:37 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100271 Oct 17 11:03:37 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100271 Oct 17 11:03:37 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100272 Oct 17 11:03:37 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100272 Oct 17 11:03:37 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100273 Oct 17 11:03:37 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100273 Oct 17 11:03:37 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100274 Oct 17 11:03:37 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100274 Oct 17 11:03:37 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100275 Oct 17 11:03:37 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100275 Oct 17 11:03:37 volumio volumio[10552]: info: CoreStateMachine::stop Oct 17 11:03:37 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:03:37 volumio volumio[10552]: info: CoreCommandRouter::volumioPushQueue Oct 17 11:03:37 volumio volumio[10552]: info: CorePlayQueue::saveQueue Oct 17 11:03:37 volumio volumio[10552]: info: CoreStateMachine::play index undefined Oct 17 11:03:37 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:03:37 volumio volumio[10552]: info: CoreStateMachine::updateTrackBlock Oct 17 11:03:37 volumio volumio[10552]: info: CorePlayQueue::getTrackBlock Oct 17 11:03:37 volumio volumio[10552]: info: CorePlayQueue::getTrack 1 Oct 17 11:03:38 volumio go-librespot[10735]: time="2025-10-17T11:03:38-04:00" level=trace msg="sent dealer ping" Oct 17 11:03:38 volumio volumio[10552]: info: Preload queue cleared Oct 17 11:03:38 volumio volumio[10552]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 17 11:03:38 volumio volumio[10552]: info: CoreStateMachine::ClearQueue Oct 17 11:03:38 volumio volumio[10552]: info: CoreStateMachine::stop Oct 17 11:03:38 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:03:38 volumio volumio[10552]: info: CorePlayQueue::clearPlayQueue Oct 17 11:03:38 volumio volumio[10552]: info: CorePlayQueue::saveQueue Oct 17 11:03:38 volumio volumio[10552]: info: CoreCommandRouter::volumioPushQueue Oct 17 11:03:38 volumio volumio[10552]: info: CoreStateMachine::addQueueItems Oct 17 11:03:38 volumio volumio[10552]: info: CorePlayQueue::addQueueItems Oct 17 11:03:38 volumio volumio[10552]: info: Preload queue cleared Oct 17 11:03:38 volumio volumio[10552]: info: Adding Item to queue: upnp/folder/http://10.125.51.50:50001/ContentDirectory/control@22$8664 Oct 17 11:03:38 volumio volumio[10552]: info: Using cached record of: upnp/folder/http://10.125.51.50:50001/ContentDirectory/control@22$8664 Oct 17 11:03:38 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100261 Oct 17 11:03:38 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100261 Oct 17 11:03:38 volumio volumio[10552]: info: CoreCommandRouter::volumioPushQueue Oct 17 11:03:38 volumio volumio[10552]: info: CorePlayQueue::saveQueue Oct 17 11:03:38 volumio volumio[10552]: info: CoreStateMachine::updateTrackBlock Oct 17 11:03:38 volumio volumio[10552]: info: CorePlayQueue::getTrackBlock Oct 17 11:03:38 volumio volumio[10552]: info: CoreCommandRouter::volumioPlay Oct 17 11:03:38 volumio volumio[10552]: info: CoreStateMachine::play index 1 Oct 17 11:03:38 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:03:38 volumio volumio[10552]: info: CoreStateMachine::addQueueItems Oct 17 11:03:38 volumio volumio[10552]: info: CorePlayQueue::addQueueItems Oct 17 11:03:38 volumio volumio[10552]: info: Preload queue cleared Oct 17 11:03:38 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100262 Oct 17 11:03:38 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100262 Oct 17 11:03:38 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100263 Oct 17 11:03:38 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100263 Oct 17 11:03:38 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100264 Oct 17 11:03:38 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100264 Oct 17 11:03:38 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100265 Oct 17 11:03:38 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100265 Oct 17 11:03:38 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100266 Oct 17 11:03:38 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100266 Oct 17 11:03:38 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100267 Oct 17 11:03:38 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100267 Oct 17 11:03:38 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100268 Oct 17 11:03:38 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100268 Oct 17 11:03:38 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100269 Oct 17 11:03:38 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100269 Oct 17 11:03:38 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100270 Oct 17 11:03:38 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100270 Oct 17 11:03:38 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100271 Oct 17 11:03:38 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100271 Oct 17 11:03:38 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100272 Oct 17 11:03:38 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100272 Oct 17 11:03:38 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100273 Oct 17 11:03:38 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100273 Oct 17 11:03:38 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100274 Oct 17 11:03:38 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100274 Oct 17 11:03:38 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100275 Oct 17 11:03:38 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100275 Oct 17 11:03:38 volumio volumio[10552]: info: CoreStateMachine::stop Oct 17 11:03:38 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:03:38 volumio volumio[10552]: info: CoreCommandRouter::volumioPushQueue Oct 17 11:03:38 volumio volumio[10552]: info: CorePlayQueue::saveQueue Oct 17 11:03:38 volumio volumio[10552]: info: CoreStateMachine::play index undefined Oct 17 11:03:38 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:03:38 volumio volumio[10552]: info: CoreStateMachine::updateTrackBlock Oct 17 11:03:38 volumio volumio[10552]: info: CorePlayQueue::getTrackBlock Oct 17 11:03:38 volumio volumio[10552]: info: CorePlayQueue::getTrack 1 Oct 17 11:03:38 volumio go-librespot[10735]: time="2025-10-17T11:03:38-04:00" level=trace msg="received dealer pong" Oct 17 11:03:39 volumio volumio[10552]: info: CoreCommandRouter::volumioGetState Oct 17 11:03:39 volumio volumio[10552]: info: CorePlayQueue::getTrack 0 Oct 17 11:03:39 volumio volumio[10552]: info: Listing playlists Oct 17 11:03:39 volumio volumio[10552]: info: Listing playlists Oct 17 11:03:49 volumio volumio[10552]: info: CoreCommandRouter::volumioGetState Oct 17 11:03:49 volumio volumio[10552]: info: CorePlayQueue::getTrack 0 Oct 17 11:03:59 volumio volumio[10552]: info: CoreCommandRouter::volumioGetState Oct 17 11:03:59 volumio volumio[10552]: info: CorePlayQueue::getTrack 0 Oct 17 11:03:59 volumio volumio[10552]: info: Listing playlists Oct 17 11:03:59 volumio volumio[10552]: info: Listing playlists Oct 17 11:04:07 volumio volumio[10552]: info: Preload queue cleared Oct 17 11:04:07 volumio volumio[10552]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 17 11:04:07 volumio volumio[10552]: info: CoreStateMachine::ClearQueue Oct 17 11:04:07 volumio volumio[10552]: info: CoreStateMachine::stop Oct 17 11:04:07 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:04:07 volumio volumio[10552]: info: CorePlayQueue::clearPlayQueue Oct 17 11:04:07 volumio volumio[10552]: info: CorePlayQueue::saveQueue Oct 17 11:04:07 volumio volumio[10552]: info: CoreCommandRouter::volumioPushQueue Oct 17 11:04:07 volumio volumio[10552]: info: CoreStateMachine::addQueueItems Oct 17 11:04:07 volumio volumio[10552]: info: CorePlayQueue::addQueueItems Oct 17 11:04:07 volumio volumio[10552]: info: Preload queue cleared Oct 17 11:04:07 volumio volumio[10552]: info: Adding Item to queue: upnp/folder/http://10.125.51.50:50001/ContentDirectory/control@22$8664 Oct 17 11:04:07 volumio volumio[10552]: info: Using cached record of: upnp/folder/http://10.125.51.50:50001/ContentDirectory/control@22$8664 Oct 17 11:04:07 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100261 Oct 17 11:04:07 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100261 Oct 17 11:04:07 volumio volumio[10552]: info: CoreCommandRouter::volumioPushQueue Oct 17 11:04:07 volumio volumio[10552]: info: CorePlayQueue::saveQueue Oct 17 11:04:07 volumio volumio[10552]: info: CoreStateMachine::updateTrackBlock Oct 17 11:04:07 volumio volumio[10552]: info: CorePlayQueue::getTrackBlock Oct 17 11:04:07 volumio volumio[10552]: info: CoreCommandRouter::volumioPlay Oct 17 11:04:07 volumio volumio[10552]: info: CoreStateMachine::play index 1 Oct 17 11:04:07 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:04:07 volumio volumio[10552]: info: CoreStateMachine::addQueueItems Oct 17 11:04:07 volumio volumio[10552]: info: CorePlayQueue::addQueueItems Oct 17 11:04:07 volumio volumio[10552]: info: Preload queue cleared Oct 17 11:04:07 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100262 Oct 17 11:04:07 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100262 Oct 17 11:04:07 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100263 Oct 17 11:04:07 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100263 Oct 17 11:04:07 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100264 Oct 17 11:04:07 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100264 Oct 17 11:04:07 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100265 Oct 17 11:04:07 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100265 Oct 17 11:04:07 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100266 Oct 17 11:04:07 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100266 Oct 17 11:04:07 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100267 Oct 17 11:04:07 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100267 Oct 17 11:04:07 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100268 Oct 17 11:04:07 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100268 Oct 17 11:04:07 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100269 Oct 17 11:04:07 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100269 Oct 17 11:04:07 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100270 Oct 17 11:04:07 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100270 Oct 17 11:04:07 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100271 Oct 17 11:04:07 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100271 Oct 17 11:04:07 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100272 Oct 17 11:04:07 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100272 Oct 17 11:04:07 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100273 Oct 17 11:04:07 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100273 Oct 17 11:04:07 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100274 Oct 17 11:04:07 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100274 Oct 17 11:04:07 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100275 Oct 17 11:04:07 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100275 Oct 17 11:04:07 volumio volumio[10552]: info: CoreStateMachine::stop Oct 17 11:04:07 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:04:07 volumio volumio[10552]: info: CoreCommandRouter::volumioPushQueue Oct 17 11:04:07 volumio volumio[10552]: info: CorePlayQueue::saveQueue Oct 17 11:04:07 volumio volumio[10552]: info: CoreStateMachine::play index undefined Oct 17 11:04:07 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:04:07 volumio volumio[10552]: info: CoreStateMachine::updateTrackBlock Oct 17 11:04:07 volumio volumio[10552]: info: CorePlayQueue::getTrackBlock Oct 17 11:04:07 volumio volumio[10552]: info: CorePlayQueue::getTrack 1 Oct 17 11:04:08 volumio go-librespot[10735]: time="2025-10-17T11:04:08-04:00" level=trace msg="sent dealer ping" Oct 17 11:04:08 volumio go-librespot[10735]: time="2025-10-17T11:04:08-04:00" level=trace msg="received dealer pong" Oct 17 11:04:09 volumio volumio[10552]: info: CoreCommandRouter::volumioGetState Oct 17 11:04:09 volumio volumio[10552]: info: CorePlayQueue::getTrack 0 Oct 17 11:04:11 volumio volumio[10552]: info: CoreCommandRouter::volumioPlay Oct 17 11:04:11 volumio volumio[10552]: info: CoreStateMachine::play index undefined Oct 17 11:04:11 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:04:11 volumio volumio[10552]: info: CorePlayQueue::getTrack 0 Oct 17 11:04:15 volumio volumio[10552]: info: Preload queue cleared Oct 17 11:04:15 volumio volumio[10552]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 17 11:04:15 volumio volumio[10552]: info: CoreStateMachine::ClearQueue Oct 17 11:04:15 volumio volumio[10552]: info: CoreStateMachine::stop Oct 17 11:04:15 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:04:15 volumio volumio[10552]: info: CorePlayQueue::clearPlayQueue Oct 17 11:04:15 volumio volumio[10552]: info: CorePlayQueue::saveQueue Oct 17 11:04:15 volumio volumio[10552]: info: CoreCommandRouter::volumioPushQueue Oct 17 11:04:15 volumio volumio[10552]: info: CoreStateMachine::addQueueItems Oct 17 11:04:15 volumio volumio[10552]: info: CorePlayQueue::addQueueItems Oct 17 11:04:15 volumio volumio[10552]: info: Preload queue cleared Oct 17 11:04:15 volumio volumio[10552]: info: Adding Item to queue: upnp/folder/http://10.125.51.50:50001/ContentDirectory/control@22$8664 Oct 17 11:04:15 volumio volumio[10552]: info: Using cached record of: upnp/folder/http://10.125.51.50:50001/ContentDirectory/control@22$8664 Oct 17 11:04:15 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100261 Oct 17 11:04:15 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100261 Oct 17 11:04:15 volumio volumio[10552]: info: CoreCommandRouter::volumioPushQueue Oct 17 11:04:15 volumio volumio[10552]: info: CorePlayQueue::saveQueue Oct 17 11:04:15 volumio volumio[10552]: info: CoreStateMachine::updateTrackBlock Oct 17 11:04:15 volumio volumio[10552]: info: CorePlayQueue::getTrackBlock Oct 17 11:04:15 volumio volumio[10552]: info: CoreCommandRouter::volumioPlay Oct 17 11:04:15 volumio volumio[10552]: info: CoreStateMachine::play index 1 Oct 17 11:04:15 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:04:15 volumio volumio[10552]: info: CoreStateMachine::addQueueItems Oct 17 11:04:15 volumio volumio[10552]: info: CorePlayQueue::addQueueItems Oct 17 11:04:15 volumio volumio[10552]: info: Preload queue cleared Oct 17 11:04:15 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100262 Oct 17 11:04:15 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100262 Oct 17 11:04:15 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100263 Oct 17 11:04:15 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100263 Oct 17 11:04:15 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100264 Oct 17 11:04:15 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100264 Oct 17 11:04:15 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100265 Oct 17 11:04:15 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100265 Oct 17 11:04:15 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100266 Oct 17 11:04:15 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100266 Oct 17 11:04:15 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100267 Oct 17 11:04:15 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100267 Oct 17 11:04:15 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100268 Oct 17 11:04:15 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100268 Oct 17 11:04:15 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100269 Oct 17 11:04:15 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100269 Oct 17 11:04:15 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100270 Oct 17 11:04:15 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100270 Oct 17 11:04:15 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100271 Oct 17 11:04:15 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100271 Oct 17 11:04:15 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100272 Oct 17 11:04:15 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100272 Oct 17 11:04:15 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100273 Oct 17 11:04:15 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100273 Oct 17 11:04:15 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100274 Oct 17 11:04:15 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100274 Oct 17 11:04:15 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100275 Oct 17 11:04:15 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100275 Oct 17 11:04:15 volumio volumio[10552]: info: CoreStateMachine::stop Oct 17 11:04:15 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:04:15 volumio volumio[10552]: info: CoreCommandRouter::volumioPushQueue Oct 17 11:04:15 volumio volumio[10552]: info: CorePlayQueue::saveQueue Oct 17 11:04:15 volumio volumio[10552]: info: CoreStateMachine::play index undefined Oct 17 11:04:15 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:04:15 volumio volumio[10552]: info: CoreStateMachine::updateTrackBlock Oct 17 11:04:15 volumio volumio[10552]: info: CorePlayQueue::getTrackBlock Oct 17 11:04:15 volumio volumio[10552]: info: CorePlayQueue::getTrack 1 Oct 17 11:04:18 volumio volumio[10552]: info: Preload queue cleared Oct 17 11:04:18 volumio volumio[10552]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 17 11:04:18 volumio volumio[10552]: info: CoreStateMachine::ClearQueue Oct 17 11:04:18 volumio volumio[10552]: info: CoreStateMachine::stop Oct 17 11:04:18 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:04:18 volumio volumio[10552]: info: CorePlayQueue::clearPlayQueue Oct 17 11:04:18 volumio volumio[10552]: info: CorePlayQueue::saveQueue Oct 17 11:04:18 volumio volumio[10552]: info: CoreCommandRouter::volumioPushQueue Oct 17 11:04:18 volumio volumio[10552]: info: CoreStateMachine::addQueueItems Oct 17 11:04:18 volumio volumio[10552]: info: CorePlayQueue::addQueueItems Oct 17 11:04:18 volumio volumio[10552]: info: Preload queue cleared Oct 17 11:04:18 volumio volumio[10552]: info: Adding Item to queue: upnp/folder/http://10.125.51.50:50001/ContentDirectory/control@22$8664 Oct 17 11:04:18 volumio volumio[10552]: info: Using cached record of: upnp/folder/http://10.125.51.50:50001/ContentDirectory/control@22$8664 Oct 17 11:04:18 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100261 Oct 17 11:04:18 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100261 Oct 17 11:04:18 volumio volumio[10552]: info: CoreCommandRouter::volumioPushQueue Oct 17 11:04:18 volumio volumio[10552]: info: CorePlayQueue::saveQueue Oct 17 11:04:18 volumio volumio[10552]: info: CoreStateMachine::updateTrackBlock Oct 17 11:04:18 volumio volumio[10552]: info: CorePlayQueue::getTrackBlock Oct 17 11:04:18 volumio volumio[10552]: info: CoreCommandRouter::volumioPlay Oct 17 11:04:18 volumio volumio[10552]: info: CoreStateMachine::play index 1 Oct 17 11:04:18 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:04:18 volumio volumio[10552]: info: CoreStateMachine::addQueueItems Oct 17 11:04:18 volumio volumio[10552]: info: CorePlayQueue::addQueueItems Oct 17 11:04:18 volumio volumio[10552]: info: Preload queue cleared Oct 17 11:04:18 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100262 Oct 17 11:04:18 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100262 Oct 17 11:04:18 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100263 Oct 17 11:04:18 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100263 Oct 17 11:04:18 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100264 Oct 17 11:04:18 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100264 Oct 17 11:04:18 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100265 Oct 17 11:04:18 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100265 Oct 17 11:04:18 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100266 Oct 17 11:04:18 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100266 Oct 17 11:04:18 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100267 Oct 17 11:04:18 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100267 Oct 17 11:04:18 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100268 Oct 17 11:04:18 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100268 Oct 17 11:04:18 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100269 Oct 17 11:04:18 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100269 Oct 17 11:04:18 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100270 Oct 17 11:04:18 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100270 Oct 17 11:04:18 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100271 Oct 17 11:04:18 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100271 Oct 17 11:04:18 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100272 Oct 17 11:04:18 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100272 Oct 17 11:04:18 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100273 Oct 17 11:04:18 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100273 Oct 17 11:04:18 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100274 Oct 17 11:04:18 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100274 Oct 17 11:04:18 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100275 Oct 17 11:04:18 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100275 Oct 17 11:04:18 volumio volumio[10552]: info: CoreStateMachine::stop Oct 17 11:04:18 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:04:18 volumio volumio[10552]: info: CoreCommandRouter::volumioPushQueue Oct 17 11:04:18 volumio volumio[10552]: info: CorePlayQueue::saveQueue Oct 17 11:04:18 volumio volumio[10552]: info: CoreStateMachine::play index undefined Oct 17 11:04:18 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:04:18 volumio volumio[10552]: info: CoreStateMachine::updateTrackBlock Oct 17 11:04:18 volumio volumio[10552]: info: CorePlayQueue::getTrackBlock Oct 17 11:04:18 volumio volumio[10552]: info: CorePlayQueue::getTrack 1 Oct 17 11:04:19 volumio volumio[10552]: info: Preload queue cleared Oct 17 11:04:19 volumio volumio[10552]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 17 11:04:19 volumio volumio[10552]: info: CoreStateMachine::ClearQueue Oct 17 11:04:19 volumio volumio[10552]: info: CoreStateMachine::stop Oct 17 11:04:19 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:04:19 volumio volumio[10552]: info: CorePlayQueue::clearPlayQueue Oct 17 11:04:19 volumio volumio[10552]: info: CorePlayQueue::saveQueue Oct 17 11:04:19 volumio volumio[10552]: info: CoreCommandRouter::volumioPushQueue Oct 17 11:04:19 volumio volumio[10552]: info: CoreStateMachine::addQueueItems Oct 17 11:04:19 volumio volumio[10552]: info: CorePlayQueue::addQueueItems Oct 17 11:04:19 volumio volumio[10552]: info: Preload queue cleared Oct 17 11:04:19 volumio volumio[10552]: info: Adding Item to queue: upnp/folder/http://10.125.51.50:50001/ContentDirectory/control@22$8664 Oct 17 11:04:19 volumio volumio[10552]: info: Using cached record of: upnp/folder/http://10.125.51.50:50001/ContentDirectory/control@22$8664 Oct 17 11:04:19 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100261 Oct 17 11:04:19 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100261 Oct 17 11:04:19 volumio volumio[10552]: info: CoreCommandRouter::volumioPushQueue Oct 17 11:04:19 volumio volumio[10552]: info: CorePlayQueue::saveQueue Oct 17 11:04:19 volumio volumio[10552]: info: CoreStateMachine::updateTrackBlock Oct 17 11:04:19 volumio volumio[10552]: info: CorePlayQueue::getTrackBlock Oct 17 11:04:19 volumio volumio[10552]: info: CoreCommandRouter::volumioPlay Oct 17 11:04:19 volumio volumio[10552]: info: CoreStateMachine::play index 1 Oct 17 11:04:19 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:04:19 volumio volumio[10552]: info: CoreStateMachine::addQueueItems Oct 17 11:04:19 volumio volumio[10552]: info: CorePlayQueue::addQueueItems Oct 17 11:04:19 volumio volumio[10552]: info: Preload queue cleared Oct 17 11:04:19 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100262 Oct 17 11:04:19 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100262 Oct 17 11:04:19 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100263 Oct 17 11:04:19 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100263 Oct 17 11:04:19 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100264 Oct 17 11:04:19 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100264 Oct 17 11:04:19 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100265 Oct 17 11:04:19 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100265 Oct 17 11:04:19 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100266 Oct 17 11:04:19 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100266 Oct 17 11:04:19 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100267 Oct 17 11:04:19 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100267 Oct 17 11:04:19 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100268 Oct 17 11:04:19 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100268 Oct 17 11:04:19 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100269 Oct 17 11:04:19 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100269 Oct 17 11:04:19 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100270 Oct 17 11:04:19 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100270 Oct 17 11:04:19 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100271 Oct 17 11:04:19 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100271 Oct 17 11:04:19 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100272 Oct 17 11:04:19 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100272 Oct 17 11:04:19 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100273 Oct 17 11:04:19 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100273 Oct 17 11:04:19 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100274 Oct 17 11:04:19 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100274 Oct 17 11:04:19 volumio volumio[10552]: info: Adding Item to queue: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100275 Oct 17 11:04:19 volumio volumio[10552]: info: Using cached record of: upnp/http://10.125.51.50:50001/ContentDirectory/control@22$@100275 Oct 17 11:04:19 volumio volumio[10552]: info: CoreStateMachine::stop Oct 17 11:04:19 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:04:19 volumio volumio[10552]: info: CoreCommandRouter::volumioPushQueue Oct 17 11:04:19 volumio volumio[10552]: info: CorePlayQueue::saveQueue Oct 17 11:04:19 volumio volumio[10552]: info: CoreStateMachine::play index undefined Oct 17 11:04:19 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:04:19 volumio volumio[10552]: info: CoreStateMachine::updateTrackBlock Oct 17 11:04:19 volumio volumio[10552]: info: CorePlayQueue::getTrackBlock Oct 17 11:04:19 volumio volumio[10552]: info: CorePlayQueue::getTrack 1 Oct 17 11:04:19 volumio volumio[10552]: info: CoreCommandRouter::volumioGetState Oct 17 11:04:19 volumio volumio[10552]: info: CorePlayQueue::getTrack 0 Oct 17 11:04:19 volumio volumio[10552]: info: Listing playlists Oct 17 11:04:19 volumio volumio[10552]: info: Listing playlists Oct 17 11:04:22 volumio volumio[10552]: info: CoreCommandRouter::volumioPlay Oct 17 11:04:22 volumio volumio[10552]: info: CoreStateMachine::play index undefined Oct 17 11:04:22 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:04:22 volumio volumio[10552]: info: CorePlayQueue::getTrack 0 Oct 17 11:04:27 volumio volumio[10552]: info: CoreCommandRouter::volumioPlay Oct 17 11:04:27 volumio volumio[10552]: info: CoreStateMachine::play index undefined Oct 17 11:04:27 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:04:27 volumio volumio[10552]: info: CorePlayQueue::getTrack 0 Oct 17 11:04:29 volumio volumio[10552]: info: CoreCommandRouter::volumioGetState Oct 17 11:04:29 volumio volumio[10552]: info: CorePlayQueue::getTrack 0 Oct 17 11:04:38 volumio go-librespot[10735]: time="2025-10-17T11:04:38-04:00" level=trace msg="sent dealer ping" Oct 17 11:04:38 volumio go-librespot[10735]: time="2025-10-17T11:04:38-04:00" level=trace msg="received dealer pong" Oct 17 11:04:39 volumio volumio[10552]: info: CoreCommandRouter::volumioGetState Oct 17 11:04:39 volumio volumio[10552]: info: CorePlayQueue::getTrack 0 Oct 17 11:04:39 volumio volumio[10552]: info: Listing playlists Oct 17 11:04:39 volumio volumio[10552]: info: Listing playlists Oct 17 11:04:47 volumio volumio[10552]: info: CoreCommandRouter::volumioPlay Oct 17 11:04:47 volumio volumio[10552]: info: CoreStateMachine::play index undefined Oct 17 11:04:47 volumio volumio[10552]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 11:04:47 volumio volumio[10552]: info: CorePlayQueue::getTrack 0 Oct 17 11:04:49 volumio volumio[10552]: info: CoreCommandRouter::volumioGetState Oct 17 11:04:49 volumio volumio[10552]: info: CorePlayQueue::getTrack 0 Oct 17 11:04:53 volumio volumio[10552]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 17 11:04:53 volumio volumio[10552]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 11:04:53 volumio volumio[10552]: TypeError: Cannot read property 'length' of undefined Oct 17 11:04:53 volumio volumio[10552]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 17 11:04:53 volumio volumio[10552]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 17 11:04:53 volumio volumio[10552]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 17 11:04:53 volumio volumio[10552]: at Parser.emit (events.js:315:20) Oct 17 11:04:53 volumio volumio[10552]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 17 11:04:53 volumio volumio[10552]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 17 11:04:53 volumio volumio[10552]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 17 11:04:53 volumio volumio[10552]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 17 11:04:53 volumio volumio[10552]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 17 11:04:53 volumio volumio[10552]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 17 11:04:53 volumio volumio[10552]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 17 11:04:53 volumio volumio[10552]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 17 11:04:53 volumio volumio[10552]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 17 11:04:53 volumio volumio[10552]: at IncomingMessage.emit (events.js:327:22) Oct 17 11:04:53 volumio volumio[10552]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 17 11:04:53 volumio volumio[10552]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 17 11:04:53 volumio volumio[10552]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 11:04:53 volumio sudo[6978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-17 11:03 Oct 17 11:04:53 volumio sudo[6978]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"