-- Logs begin at Tue 2025-05-27 09:41:43 CEST, end at Thu 2025-05-29 20:46:49 CEST. -- May 29 20:45:07 localhost go-librespot[16304]: time="2025-05-29T20:45:07+02:00" level=error msg="did not receive last pong from dealer, 391050s passed" May 29 20:45:37 localhost go-librespot[16304]: time="2025-05-29T20:45:37+02:00" level=error msg="did not receive last pong from dealer, 391080s passed" May 29 20:46:07 localhost go-librespot[16304]: time="2025-05-29T20:46:07+02:00" level=error msg="did not receive last pong from dealer, 391110s passed" May 29 20:46:11 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 20:46:11 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 20:46:11 localhost volumio[1010]: info: Discovery: Getting this device information May 29 20:46:11 localhost volumio[1010]: info: CoreCommandRouter::volumioGetState May 29 20:46:11 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 20:46:12 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 20:46:12 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 20:46:12 localhost volumio[1010]: info: Discovery: Getting this device information May 29 20:46:12 localhost volumio[1010]: info: CoreCommandRouter::volumioGetState May 29 20:46:12 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 20:46:12 localhost volumio[1010]: verbose: New Socket.io Connection to 192.168.178.32:3000 from 192.168.178.30 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 May 29 20:46:13 localhost volumio[1010]: verbose: New Socket.io Connection to 192.168.178.32 from 192.168.178.30 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 12 May 29 20:46:13 localhost volumio[1010]: info: CoreCommandRouter::volumioGetState May 29 20:46:13 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 29 20:46:13 localhost volumio[1010]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 29 20:46:13 localhost volumio[1010]: info: Listing playlists May 29 20:46:13 localhost volumio[1010]: info: CoreCommandRouter::volumioGetQueue May 29 20:46:13 localhost volumio[1010]: info: CoreStateMachine::getQueue May 29 20:46:13 localhost volumio[1010]: info: CorePlayQueue::getQueue May 29 20:46:14 localhost volumio[1010]: info: CoreCommandRouter::volumioPlay May 29 20:46:14 localhost volumio[1010]: info: CoreStateMachine::play index undefined May 29 20:46:14 localhost volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:46:14 localhost volumio[1010]: info: CorePlayQueue::getTrack 7 May 29 20:46:14 localhost volumio[1010]: info: CoreStateMachine::startPlaybackTimer May 29 20:46:14 localhost volumio[1010]: info: CorePlayQueue::getTrack 7 May 29 20:46:14 localhost volumio[1010]: info: [1748544374491] ControllerQobuz::resume May 29 20:46:14 localhost volumio[1010]: info: CoreStateMachine::setConsumeUpdateService mpd May 29 20:46:14 localhost volumio[1010]: info: ControllerMpd::resume May 29 20:46:14 localhost volumio[1010]: verbose: ControllerMpd::sendMpdCommand play May 29 20:46:14 localhost volumio[1010]: info: May 29 20:46:14 localhost volumio[1010]: ---------------------------- MPD announces state update: player May 29 20:46:14 localhost volumio[1010]: info: sendMpdCommand play took 12 milliseconds May 29 20:46:14 localhost volumio[1010]: info: ControllerMpd::getState May 29 20:46:14 localhost volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 29 20:46:14 localhost volumio[1010]: info: sendMpdCommand status took 1 milliseconds May 29 20:46:14 localhost volumio[1010]: verbose: ControllerMpd::parseState May 29 20:46:14 localhost volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 20:46:14 localhost volumio[1010]: info: sendMpdCommand playlistinfo took 1 milliseconds May 29 20:46:14 localhost volumio[1010]: verbose: ControllerMpd::parseTrackInfo May 29 20:46:14 localhost volumio[1010]: info: ControllerMpd::pushState May 29 20:46:14 localhost volumio[1010]: info: CoreCommandRouter::servicePushState May 29 20:46:14 localhost volumio[1010]: info: CorePlayQueue::getTrack 7 May 29 20:46:14 localhost volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":74144,"duration":178,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"6288 Kbps","isStreaming":false,"title":"61236220","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/61236220","trackType":"qobuz"} May 29 20:46:14 localhost volumio[1010]: verbose: CURRENT POSITION 7 May 29 20:46:14 localhost volumio[1010]: info: CoreStateMachine::syncState stateService play May 29 20:46:14 localhost volumio[1010]: info: CoreStateMachine::syncState currentStatus pause May 29 20:46:14 localhost volumio[1010]: info: CoreStateMachine::pushState May 29 20:46:14 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 20:46:14 localhost volumio[1010]: info: CoreCommandRouter::volumioPushState May 29 20:46:14 localhost volumio[1010]: info: MRS: Pushing multiroomSync output update for this device May 29 20:46:14 localhost volumio[1010]: info: MRS: Pushing multiroomSync output May 29 20:46:14 localhost volumio[1010]: info: CoreCommandRouter::volumioGetState May 29 20:46:14 localhost volumio[1010]: info: ------------------------------ 22ms May 29 20:46:14 localhost volumio[1010]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 29 20:46:18 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 20:46:18 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 20:46:18 localhost volumio[1010]: info: Discovery: Getting this device information May 29 20:46:18 localhost volumio[1010]: info: CoreCommandRouter::volumioGetState May 29 20:46:18 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 20:46:20 localhost volumio[1010]: STREAMING PROXY: Client dropped request, destroying May 29 20:46:23 localhost volumio[1010]: info: CoreCommandRouter::volumioGetVisibleSources May 29 20:46:23 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 20:46:24 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 29 20:46:24 localhost volumio[1010]: info: CURURI: playlists May 29 20:46:24 localhost volumio[1010]: info: Listing playlists May 29 20:46:24 localhost volumio[1010]: info: Preload queue cleared May 29 20:46:26 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 29 20:46:26 localhost volumio[1010]: info: CURURI: playlists/Yoga May 29 20:46:26 localhost volumio[1010]: info: Preload queue cleared May 29 20:46:26 localhost volumio[1010]: info: Preloading song: qobuz://album/y92c1tfbef0sc May 29 20:46:26 localhost volumio[1010]: info: Exploding uri qobuz://album/y92c1tfbef0sc in service qobuz May 29 20:46:26 localhost volumio[1010]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 29 20:46:26 localhost volumio[1010]: info: explodeUri took 561 milliseconds May 29 20:46:27 localhost volumio[1010]: info: Preload queue cleared May 29 20:46:27 localhost volumio[1010]: info: CoreCommandRouter::volumioReplaceandPlayItems May 29 20:46:27 localhost volumio[1010]: info: CoreStateMachine::ClearQueue May 29 20:46:27 localhost volumio[1010]: info: CoreStateMachine::stop May 29 20:46:27 localhost volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:46:27 localhost volumio[1010]: info: CoreStateMachine::stPlaybackTimer May 29 20:46:27 localhost volumio[1010]: info: CoreStateMachine::updateTrackBlock May 29 20:46:27 localhost volumio[1010]: info: CorePlayQueue::getTrackBlock May 29 20:46:27 localhost volumio[1010]: info: CoreStateMachine::pushState May 29 20:46:27 localhost volumio[1010]: info: CorePlayQueue::getTrack 7 May 29 20:46:27 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 20:46:27 localhost volumio[1010]: info: CoreCommandRouter::volumioPushState May 29 20:46:27 localhost volumio[1010]: info: MRS: Pushing multiroomSync output update for this device May 29 20:46:27 localhost volumio[1010]: info: MRS: Pushing multiroomSync output May 29 20:46:27 localhost volumio[1010]: info: CoreCommandRouter::volumioGetState May 29 20:46:27 localhost volumio[1010]: info: CorePlayQueue::getTrack 7 May 29 20:46:27 localhost volumio[1010]: info: CoreStateMachine::serviceStop May 29 20:46:27 localhost volumio[1010]: info: CorePlayQueue::getTrack 7 May 29 20:46:27 localhost volumio[1010]: info: CoreCommandRouter::serviceStop May 29 20:46:27 localhost volumio[1010]: info: [1748544387916] ControllerQobuz::stop May 29 20:46:27 localhost volumio[1010]: info: CoreStateMachine::setConsumeUpdateService mpd May 29 20:46:27 localhost volumio[1010]: info: ControllerMpd::stop May 29 20:46:27 localhost volumio[1010]: verbose: ControllerMpd::sendMpdCommand stop May 29 20:46:27 localhost volumio[1010]: info: CorePlayQueue::clearPlayQueue May 29 20:46:27 localhost volumio[1010]: info: CorePlayQueue::saveQueue May 29 20:46:27 localhost volumio[1010]: info: CoreCommandRouter::volumioPushQueue May 29 20:46:27 localhost volumio[1010]: info: CoreStateMachine::addQueueItems May 29 20:46:27 localhost volumio[1010]: info: CorePlayQueue::addQueueItems May 29 20:46:27 localhost volumio[1010]: info: Preload queue cleared May 29 20:46:27 localhost volumio[1010]: info: Adding Item to queue: qobuz://album/y92c1tfbef0sc May 29 20:46:27 localhost volumio[1010]: info: Using cached record of: qobuz://album/y92c1tfbef0sc May 29 20:46:27 localhost volumio[1010]: info: CoreCommandRouter::volumioPushQueue May 29 20:46:27 localhost volumio[1010]: info: CorePlayQueue::saveQueue May 29 20:46:27 localhost volumio[1010]: info: CoreStateMachine::updateTrackBlock May 29 20:46:27 localhost volumio[1010]: info: CorePlayQueue::getTrackBlock May 29 20:46:27 localhost volumio[1010]: info: CoreCommandRouter::volumioPlay May 29 20:46:27 localhost volumio[1010]: info: CoreStateMachine::play index 0 May 29 20:46:27 localhost volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:46:27 localhost volumio[1010]: info: CoreStateMachine::stop May 29 20:46:27 localhost volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:46:27 localhost volumio[1010]: info: CoreStateMachine::play index undefined May 29 20:46:27 localhost volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 20:46:27 localhost volumio[1010]: info: CorePlayQueue::getTrack 0 May 29 20:46:27 localhost volumio[1010]: info: CoreStateMachine::startPlaybackTimer May 29 20:46:27 localhost volumio[1010]: info: CorePlayQueue::getTrack 0 May 29 20:46:27 localhost volumio[1010]: info: [1748544387942] ControllerQobuz::clearAddPlayTrack May 29 20:46:27 localhost volumio[1010]: verbose: ControllerMpd::sendMpdCommand stop May 29 20:46:27 localhost volumio[1010]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 29 20:46:27 localhost volumio[1010]: info: May 29 20:46:27 localhost volumio[1010]: ---------------------------- MPD announces state update: player May 29 20:46:27 localhost volumio[1010]: info: sendMpdCommand stop took 45 milliseconds May 29 20:46:27 localhost volumio[1010]: info: ControllerMpd::getState May 29 20:46:27 localhost volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 29 20:46:27 localhost volumio[1010]: info: sendMpdCommand stop took 19 milliseconds May 29 20:46:27 localhost volumio[1010]: verbose: ControllerMpd::sendMpdCommand clear May 29 20:46:27 localhost volumio[1010]: info: May 29 20:46:27 localhost volumio[1010]: ---------------------------- MPD announces system playlist update May 29 20:46:27 localhost volumio[1010]: info: Ignoring MPD Status Update May 29 20:46:27 localhost volumio[1010]: info: sendMpdCommand status took 7 milliseconds May 29 20:46:27 localhost volumio[1010]: info: sendMpdCommand clear took 5 milliseconds May 29 20:46:27 localhost volumio[1010]: verbose: ControllerMpd::parseState May 29 20:46:27 localhost volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 20:46:27 localhost volumio[1010]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/89918279" May 29 20:46:27 localhost volumio[1010]: STREAMING PROXY: Handling url /?data=qobuz://song/89918279 May 29 20:46:27 localhost volumio[1010]: error: updateQueue error: null May 29 20:46:27 localhost volumio[1010]: info: ------------------------------ 8ms May 29 20:46:27 localhost volumio[1010]: info: sendMpdCommand playlistinfo took 7 milliseconds May 29 20:46:27 localhost volumio[1010]: verbose: ControllerMpd::parseTrackInfo May 29 20:46:27 localhost volumio[1010]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 29 20:46:27 localhost volumio[1010]: info: ------------------------------ 18ms May 29 20:46:27 localhost volumio[1010]: info: Executing endpoint getStreamUrlqobuz May 29 20:46:27 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 29 20:46:28 localhost volumio[1010]: info: getStreamUrl took 573 milliseconds May 29 20:46:28 localhost volumio[1010]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1864521&eid=89918279&fmt=6&profile=raw&app_id=539451548&cid=2778805&etsp=1748547988&hmac=QzK5yy6X0LV1UjjrNTBikFzoJXw May 29 20:46:29 localhost volumio[1010]: STREAMING PROXY: Response: 200, length: 31549908 May 29 20:46:29 localhost volumio[1010]: STREAMING PROXY: Client dropped request, destroying May 29 20:46:29 localhost volumio[1010]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/89918279" May 29 20:46:29 localhost volumio[1010]: info: May 29 20:46:29 localhost volumio[1010]: ---------------------------- MPD announces system playlist update May 29 20:46:29 localhost volumio[1010]: info: Ignoring MPD Status Update May 29 20:46:29 localhost volumio[1010]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/89918279" took 3 milliseconds May 29 20:46:29 localhost volumio[1010]: info: CoreStateMachine::setConsumeUpdateService mpd May 29 20:46:29 localhost volumio[1010]: verbose: ControllerMpd::sendMpdCommand play May 29 20:46:29 localhost volumio[1010]: info: ------------------------------ 6ms May 29 20:46:29 localhost volumio[1010]: info: sendMpdCommand play took 5 milliseconds May 29 20:46:29 localhost volumio[1010]: STREAMING PROXY: Handling url /?data=qobuz://song/89918279 May 29 20:46:29 localhost volumio[1010]: info: Executing endpoint getStreamUrlqobuz May 29 20:46:29 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 29 20:46:30 localhost volumio[1010]: info: getStreamUrl took 481 milliseconds May 29 20:46:30 localhost volumio[1010]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1864521&eid=89918279&fmt=6&profile=raw&app_id=539451548&cid=2778805&etsp=1748547989&hmac=ltYZPSkmfQbZy_7bu0BYb2qeAT0 May 29 20:46:30 localhost volumio[1010]: STREAMING PROXY: Response: 200, length: 31549908 May 29 20:46:30 localhost volumio[1010]: info: May 29 20:46:30 localhost volumio[1010]: ---------------------------- MPD announces state update: player May 29 20:46:30 localhost volumio[1010]: info: ControllerMpd::getState May 29 20:46:30 localhost volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 29 20:46:30 localhost volumio[1010]: info: May 29 20:46:30 localhost volumio[1010]: ---------------------------- MPD announces state update: player May 29 20:46:30 localhost volumio[1010]: info: sendMpdCommand status took 7 milliseconds May 29 20:46:30 localhost volumio[1010]: info: ControllerMpd::getState May 29 20:46:30 localhost volumio[1010]: verbose: ControllerMpd::sendMpdCommand status May 29 20:46:30 localhost volumio[1010]: verbose: ControllerMpd::parseState May 29 20:46:30 localhost volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 20:46:30 localhost volumio[1010]: info: sendMpdCommand status took 5 milliseconds May 29 20:46:30 localhost volumio[1010]: info: sendMpdCommand playlistinfo took 4 milliseconds May 29 20:46:30 localhost volumio[1010]: verbose: ControllerMpd::parseState May 29 20:46:30 localhost volumio[1010]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 20:46:30 localhost volumio[1010]: verbose: ControllerMpd::parseTrackInfo May 29 20:46:30 localhost volumio[1010]: info: ControllerMpd::pushState May 29 20:46:30 localhost volumio[1010]: info: CoreCommandRouter::servicePushState May 29 20:46:30 localhost volumio[1010]: info: CorePlayQueue::getTrack 0 May 29 20:46:30 localhost volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"89918279","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/89918279","trackType":"qobuz"} May 29 20:46:30 localhost volumio[1010]: verbose: CURRENT POSITION 0 May 29 20:46:30 localhost volumio[1010]: info: CoreStateMachine::syncState stateService play May 29 20:46:30 localhost volumio[1010]: info: CoreStateMachine::syncState currentStatus stop May 29 20:46:30 localhost volumio[1010]: info: ------------------------------ 22ms May 29 20:46:30 localhost volumio[1010]: info: sendMpdCommand playlistinfo took 6 milliseconds May 29 20:46:30 localhost volumio[1010]: verbose: ControllerMpd::parseTrackInfo May 29 20:46:30 localhost volumio[1010]: info: ControllerMpd::pushState May 29 20:46:30 localhost volumio[1010]: info: CoreCommandRouter::servicePushState May 29 20:46:30 localhost volumio[1010]: info: CorePlayQueue::getTrack 0 May 29 20:46:30 localhost volumio[1010]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":358,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"89918279","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/89918279","trackType":"qobuz"} May 29 20:46:30 localhost volumio[1010]: verbose: CURRENT POSITION 0 May 29 20:46:30 localhost volumio[1010]: info: CoreStateMachine::syncState stateService play May 29 20:46:30 localhost volumio[1010]: info: CoreStateMachine::syncState currentStatus play May 29 20:46:30 localhost volumio[1010]: info: Received an update from plugin. extracting info from payload May 29 20:46:30 localhost volumio[1010]: info: CoreStateMachine::pushState May 29 20:46:30 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 20:46:30 localhost volumio[1010]: info: CoreCommandRouter::volumioPushState May 29 20:46:30 localhost volumio[1010]: info: MRS: Pushing multiroomSync output update for this device May 29 20:46:30 localhost volumio[1010]: info: MRS: Pushing multiroomSync output May 29 20:46:30 localhost volumio[1010]: info: CoreCommandRouter::volumioGetState May 29 20:46:30 localhost volumio[1010]: info: CoreStateMachine::pushState May 29 20:46:30 localhost volumio[1010]: info: CoreCommandRouter::volumioPushState May 29 20:46:30 localhost volumio[1010]: info: MRS: Pushing multiroomSync output update for this device May 29 20:46:30 localhost volumio[1010]: info: MRS: Pushing multiroomSync output May 29 20:46:30 localhost volumio[1010]: info: CoreCommandRouter::volumioGetState May 29 20:46:30 localhost volumio[1010]: info: ------------------------------ 72ms May 29 20:46:30 localhost volumio[1010]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 29 20:46:30 localhost volumio[1010]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 29 20:46:30 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 29 20:46:30 localhost volumio[1010]: info: CURURI: playlists/Jazz May 29 20:46:30 localhost volumio[1010]: info: Preload queue cleared May 29 20:46:30 localhost volumio[1010]: info: Preloading song: mnt/USB/512GB/Louis Armstrong & Ella Fitzgerald - Ella and Louis (SACD-R)/1956 The Verve Music Group a division of UMG Recordings, In - Ella and Louis_5645kbps.wav May 29 20:46:30 localhost volumio[1010]: info: Exploding uri mnt/USB/512GB/Louis Armstrong & Ella Fitzgerald - Ella and Louis (SACD-R)/1956 The Verve Music Group a division of UMG Recordings, In - Ella and Louis_5645kbps.wav in service mpd May 29 20:46:30 localhost volumio[1010]: error: scanFolder - failure to stat '/mnt/USB/512GB/Louis Armstrong & Ella Fitzgerald - Ella and Louis (SACD-R)/1956 The Verve Music Group a division of UMG Recordings, In - Ella and Louis_5645kbps.wav' May 29 20:46:32 localhost volumio[1010]: STREAMING PROXY: Client dropped request, destroying May 29 20:46:36 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 29 20:46:36 localhost volumio[1010]: info: Preload queue cleared May 29 20:46:37 localhost go-librespot[16304]: time="2025-05-29T20:46:37+02:00" level=error msg="did not receive last pong ack from accesspoint, 268058s passed" May 29 20:46:37 localhost go-librespot[16304]: time="2025-05-29T20:46:37+02:00" level=error msg="did not receive last pong from dealer, 391140s passed" May 29 20:46:38 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 29 20:46:39 localhost volumio[1010]: info: Preload queue cleared May 29 20:46:41 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 29 20:46:41 localhost volumio[1010]: info: Preload queue cleared May 29 20:46:44 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 29 20:46:44 localhost volumio[1010]: info: Preload queue cleared May 29 20:46:47 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 29 20:46:47 localhost volumio[1010]: info: Preload queue cleared May 29 20:46:48 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 20:46:48 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 20:46:48 localhost volumio[1010]: info: Discovery: Getting this device information May 29 20:46:48 localhost volumio[1010]: info: CoreCommandRouter::volumioGetState May 29 20:46:48 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 20:46:48 localhost volumio[1010]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 29 20:46:48 localhost volumio[1010]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 20:46:48 localhost volumio[1010]: TypeError: Cannot read property 'length' of undefined May 29 20:46:48 localhost volumio[1010]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 29 20:46:48 localhost volumio[1010]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 29 20:46:48 localhost volumio[1010]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 29 20:46:48 localhost volumio[1010]: at Parser.emit (events.js:400:28) May 29 20:46:48 localhost volumio[1010]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 29 20:46:48 localhost volumio[1010]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 29 20:46:48 localhost volumio[1010]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 29 20:46:48 localhost volumio[1010]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 29 20:46:48 localhost volumio[1010]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 29 20:46:48 localhost volumio[1010]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 29 20:46:48 localhost volumio[1010]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 29 20:46:48 localhost volumio[1010]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 29 20:46:48 localhost volumio[1010]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 29 20:46:48 localhost volumio[1010]: at IncomingMessage.emit (events.js:412:35) May 29 20:46:48 localhost volumio[1010]: at endReadableNT (internal/streams/readable.js:1333:12) May 29 20:46:48 localhost volumio[1010]: at processTicksAndRejections (internal/process/task_queues.js:82:21) May 29 20:46:48 localhost volumio[1010]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 20:46:49 localhost sudo[2683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-29 20:45 May 29 20:46:49 localhost sudo[2683]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"