Oct 13 17:21:06 lounge volumio5-onboarding[1911]: time=2026-10-13T17:21:06.187+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.62:54916,00:00:00:00:00:00%01 @ 0x3094e40" latency=16.38603ms timeout=10s Oct 13 17:21:06 lounge volumio5-onboarding[1911]: time=2026-10-13T17:21:06.214+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.62:54916,00:00:00:00:00:00%01 @ 0x3094e40" latency=16.38603ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=27.115184ms Oct 13 17:21:06 lounge volumio5-onboarding[1911]: time=2026-10-13T17:21:06.222+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.62:54916,00:00:00:00:00:00%01 @ 0x3094e40" latency=16.38603ms timeout=10s endpoint=http://pushupdates.volumio.org duration=33.385854ms Oct 13 17:21:06 lounge volumio5-onboarding[1911]: time=2026-10-13T17:21:06.224+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.62:54916,00:00:00:00:00:00%01 @ 0x3094e40" latency=16.38603ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=35.858522ms Oct 13 17:21:06 lounge volumio5-onboarding[1911]: time=2026-10-13T17:21:06.229+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.62:54916,00:00:00:00:00:00%01 @ 0x3094e40" latency=16.38603ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=41.148446ms Oct 13 17:21:06 lounge volumio5-onboarding[1911]: time=2026-10-13T17:21:06.272+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.62:54916,00:00:00:00:00:00%01 @ 0x3094e40" latency=16.38603ms timeout=10s endpoint=https://google.com duration=84.912837ms Oct 13 17:21:06 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 17:21:06 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 17:21:06 lounge volumio[1216]: info: Discovery: Getting this device information Oct 13 17:21:06 lounge volumio[1216]: info: CoreCommandRouter::volumioGetState Oct 13 17:21:06 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 17:21:06 lounge volumio[1216]: verbose: New Socket.io Connection to 192.168.68.65:3000 from 192.168.68.62 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Oct 13 17:21:06 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 13 17:21:06 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 13 17:21:06 lounge volumio5-onboarding[1911]: time=2026-10-13T17:21:06.313+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.62:54916,00:00:00:00:00:00%01 @ 0x3094e40" latency=16.38603ms timeout=10s endpoint=https://www.googleapis.com duration=125.650844ms Oct 13 17:21:06 lounge volumio5-onboarding[1911]: time=2026-10-13T17:21:06.338+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.62:54916,00:00:00:00:00:00%01 @ 0x3094e40" latency=16.38603ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=150.047846ms Oct 13 17:21:06 lounge volumio5-onboarding[1911]: time=2026-10-13T17:21:06.364+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.62:54916,00:00:00:00:00:00%01 @ 0x3094e40" latency=16.38603ms timeout=10s endpoint=https://securetoken.googleapis.com duration=176.103953ms Oct 13 17:21:06 lounge volumio5-onboarding[1911]: time=2026-10-13T17:21:06.369+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.62:54916,00:00:00:00:00:00%01 @ 0x3094e40" latency=16.38603ms timeout=10s endpoint=https://database.volumio.cloud duration=180.012119ms Oct 13 17:21:06 lounge volumio5-onboarding[1911]: time=2026-10-13T17:21:06.376+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.62:54916,00:00:00:00:00:00%01 @ 0x3094e40" latency=16.38603ms timeout=10s endpoint=https://functions.volumio.cloud duration=186.970945ms Oct 13 17:21:06 lounge volumio5-onboarding[1911]: time=2026-10-13T17:21:06.382+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.62:54916,00:00:00:00:00:00%01 @ 0x3094e40" latency=16.38603ms timeout=10s endpoint=https://functions.volumio.cloud duration=194.199842ms Oct 13 17:21:06 lounge volumio5-onboarding[1911]: time=2026-10-13T17:21:06.691+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.62:54916,00:00:00:00:00:00%01 @ 0x3094e40" latency=16.38603ms timeout=10s endpoint=http://cddb.volumio.org duration=503.087074ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 13 17:21:06 lounge volumio5-onboarding[1911]: time=2026-10-13T17:21:06.792+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.62:54916,00:00:00:00:00:00%01 @ 0x3094e40" latency=16.38603ms timeout=10s endpoint=http://plugins.volumio.org duration=603.336431ms Oct 13 17:21:12 lounge volumio[1216]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 17:21:12 lounge volumio[1216]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 17:21:12 lounge volumio[1216]: info: Retrieving Cloud Streaming UI Oct 13 17:21:12 lounge volumio[1216]: info: Getting Tidal Cloud Configuration Oct 13 17:21:12 lounge volumio[1216]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 17:21:12 lounge volumio[1216]: info: Getting Qobuz Cloud Configuration Oct 13 17:21:12 lounge volumio[1216]: info: Asking plugin for UI Config Oct 13 17:21:12 lounge volumio[1216]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 17:21:12 lounge volumio[1216]: info: Getting Spotify Cloud Configuration Oct 13 17:21:12 lounge volumio[1216]: info: Asking plugin for UI Config Oct 13 17:21:12 lounge volumio[1216]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 17:21:12 lounge volumio[1216]: info: Saving Spotify Acccount Oct 13 17:21:12 lounge volumio[1216]: info: Got it Oct 13 17:21:12 lounge volumio[1216]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 13 17:21:12 lounge volumio[1216]: info: Got Tidal Cloud Configuration Oct 13 17:21:12 lounge volumio[1216]: info: Got it Oct 13 17:21:12 lounge volumio[1216]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 17:21:12 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 13 17:21:12 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 13 17:21:12 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 13 17:21:12 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 13 17:21:12 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 17:21:12 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 17:21:12 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 17:21:12 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 17:21:12 lounge volumio[1216]: info: CoreCommandRouter::volumioGetBrowseSources Oct 13 17:21:12 lounge volumio[1216]: info: CoreCommandRouter::volumioGetBrowseSources Oct 13 17:21:12 lounge volumio[1216]: info: CoreCommandRouter::volumioGetBrowseSources Oct 13 17:21:12 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 17:21:12 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 13 17:21:16 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 17:21:20 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 17:21:24 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 17:21:28 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 17:21:32 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 17:21:33 lounge volumio[1216]: info: CorePlayQueue::getTrack 0 Oct 13 17:21:33 lounge volumio[1216]: info: CorePlayQueue::getTrack 1 Oct 13 17:21:33 lounge volumio[1216]: info: Prefetching next song Oct 13 17:21:33 lounge volumio[1216]: info: [1791904893372] ControllerTidal::prefetch Oct 13 17:21:33 lounge volumio[1216]: info: Getting stream with soundQuality LOSSLESS Oct 13 17:21:33 lounge volumio[1216]: info: getStreamUrl took 311 milliseconds Oct 13 17:21:33 lounge volumio[1216]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQyY2Y5NWQ5MGYxM2FmZTY5MmYzZjY0N2Q1MWMyYTQxOC5tcDQ/0.flac?token=1791908493~YWQ1MzQ5NDIwZDFlYzJlZTZiMTJhMTI4ZWE0NDY1NjEwMzg0NDU5Yg==" Oct 13 17:21:33 lounge volumio[1216]: info: Oct 13 17:21:33 lounge volumio[1216]: ---------------------------- MPD announces system playlist update Oct 13 17:21:33 lounge volumio[1216]: info: Ignoring MPD Status Update Oct 13 17:21:33 lounge volumio[1216]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQyY2Y5NWQ5MGYxM2FmZTY5MmYzZjY0N2Q1MWMyYTQxOC5tcDQ/0.flac?token=1791908493~YWQ1MzQ5NDIwZDFlYzJlZTZiMTJhMTI4ZWE0NDY1NjEwMzg0NDU5Yg==" took 1 milliseconds Oct 13 17:21:33 lounge volumio[1216]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 13 17:21:33 lounge volumio[1216]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 13 17:21:33 lounge volumio[1216]: info: ------------------------------ 2ms Oct 13 17:21:33 lounge volumio[1216]: info: sendMpdCommand consume 1 took 1 milliseconds Oct 13 17:21:36 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 17:21:37 lounge volumio[1216]: info: Oct 13 17:21:37 lounge volumio[1216]: ---------------------------- MPD announces system playlist update Oct 13 17:21:37 lounge volumio[1216]: info: Ignoring MPD Status Update Oct 13 17:21:37 lounge volumio[1216]: info: Oct 13 17:21:37 lounge volumio[1216]: ---------------------------- MPD announces state update: player Oct 13 17:21:37 lounge volumio[1216]: info: ControllerMpd::getState Oct 13 17:21:37 lounge volumio[1216]: verbose: ControllerMpd::sendMpdCommand status Oct 13 17:21:37 lounge volumio[1216]: info: ------------------------------ 2ms Oct 13 17:21:37 lounge volumio[1216]: info: sendMpdCommand status took 2 milliseconds Oct 13 17:21:37 lounge volumio[1216]: verbose: ControllerMpd::parseState Oct 13 17:21:37 lounge volumio[1216]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 17:21:37 lounge volumio[1216]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 13 17:21:37 lounge volumio[1216]: verbose: ControllerMpd::parseTrackInfo Oct 13 17:21:37 lounge volumio[1216]: info: ControllerMpd::pushState Oct 13 17:21:37 lounge volumio[1216]: info: CoreCommandRouter::servicePushState Oct 13 17:21:37 lounge volumio[1216]: info: CorePlayQueue::getTrack 0 Oct 13 17:21:37 lounge volumio[1216]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1791908493~YWQ1MzQ5NDIwZDFlYzJlZTZiMTJhMTI4ZWE0NDY1NjEwMzg0NDU5Yg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQyY2Y5NWQ5MGYxM2FmZTY5MmYzZjY0N2Q1MWMyYTQxOC5tcDQ/0.flac?token=1791908493~YWQ1MzQ5NDIwZDFlYzJlZTZiMTJhMTI4ZWE0NDY1NjEwMzg0NDU5Yg==","trackType":"tidal"} Oct 13 17:21:37 lounge volumio[1216]: verbose: CURRENT POSITION 0 Oct 13 17:21:37 lounge volumio[1216]: info: CoreStateMachine::syncState stateService play Oct 13 17:21:37 lounge volumio[1216]: info: CoreStateMachine::syncState currentStatus play Oct 13 17:21:37 lounge volumio[1216]: info: Received an update from plugin. extracting info from payload Oct 13 17:21:37 lounge volumio[1216]: info: CoreStateMachine::pushState Oct 13 17:21:37 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 17:21:37 lounge volumio[1216]: info: CoreCommandRouter::volumioPushState Oct 13 17:21:37 lounge volumio[1216]: info: CoreCommandRouter::volumioGetState Oct 13 17:21:37 lounge volumio[1216]: info: CoreStateMachine::pushState Oct 13 17:21:37 lounge volumio[1216]: info: CoreCommandRouter::volumioPushState Oct 13 17:21:37 lounge volumio[1216]: info: CoreCommandRouter::volumioGetState Oct 13 17:21:37 lounge volumio5-onboarding[1911]: time=2026-10-13T17:21:37.790+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.68.62:54916,00:00:00:00:00:00%01 @ 0x3094e40" state=STATUS_PLAYING positionMs=227258 volume=100 Oct 13 17:21:37 lounge volumio5-onboarding[1911]: time=2026-10-13T17:21:37.791+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.62:54916,00:00:00:00:00:00%01 @ 0x3094e40" id=tidal://song/5267733 title="Rolling in the Deep" Oct 13 17:21:37 lounge volumio5-onboarding[1911]: time=2026-10-13T17:21:37.792+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.68.62:54916,00:00:00:00:00:00%01 @ 0x3094e40" state=STATUS_PLAYING positionMs=227258 volume=100 Oct 13 17:21:37 lounge volumio5-onboarding[1911]: time=2026-10-13T17:21:37.792+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.62:54916,00:00:00:00:00:00%01 @ 0x3094e40" id=tidal://song/5267733 title="Rolling in the Deep" Oct 13 17:21:37 lounge volumio[1216]: info: ------------------------------ 20ms Oct 13 17:21:37 lounge volumio[1216]: info: CoreStateMachine::startPlaybackTimer Oct 13 17:21:37 lounge volumio[1216]: info: CorePlayQueue::getTrack 1 Oct 13 17:21:38 lounge volumio[1216]: info: CoreStateMachine::pushState Oct 13 17:21:38 lounge volumio[1216]: info: CorePlayQueue::getTrack 1 Oct 13 17:21:38 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 17:21:38 lounge volumio[1216]: info: CoreCommandRouter::volumioPushState Oct 13 17:21:38 lounge volumio[1216]: info: CoreCommandRouter::volumioGetState Oct 13 17:21:38 lounge volumio[1216]: info: CorePlayQueue::getTrack 1 Oct 13 17:21:38 lounge volumio5-onboarding[1911]: time=2026-10-13T17:21:38.428+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.68.62:54916,00:00:00:00:00:00%01 @ 0x3094e40" state=STATUS_PLAYING positionMs=500 volume=100 Oct 13 17:21:38 lounge volumio5-onboarding[1911]: time=2026-10-13T17:21:38.429+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.62:54916,00:00:00:00:00:00%01 @ 0x3094e40" id=tidal://song/5267743 title="Someone Like You" Oct 13 17:21:38 lounge volumio[1216]: info: Oct 13 17:21:38 lounge volumio[1216]: ---------------------------- MPD announces state update: player Oct 13 17:21:38 lounge volumio[1216]: info: ControllerMpd::getState Oct 13 17:21:38 lounge volumio[1216]: verbose: ControllerMpd::sendMpdCommand status Oct 13 17:21:38 lounge volumio[1216]: info: sendMpdCommand status took 0 milliseconds Oct 13 17:21:38 lounge volumio[1216]: verbose: ControllerMpd::parseState Oct 13 17:21:38 lounge volumio[1216]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 17:21:38 lounge volumio[1216]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 13 17:21:38 lounge volumio[1216]: verbose: ControllerMpd::parseTrackInfo Oct 13 17:21:38 lounge volumio[1216]: info: ControllerMpd::pushState Oct 13 17:21:38 lounge volumio[1216]: info: CoreCommandRouter::servicePushState Oct 13 17:21:38 lounge volumio[1216]: info: CorePlayQueue::getTrack 1 Oct 13 17:21:38 lounge volumio[1216]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1791908493~YWQ1MzQ5NDIwZDFlYzJlZTZiMTJhMTI4ZWE0NDY1NjEwMzg0NDU5Yg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQyY2Y5NWQ5MGYxM2FmZTY5MmYzZjY0N2Q1MWMyYTQxOC5tcDQ/0.flac?token=1791908493~YWQ1MzQ5NDIwZDFlYzJlZTZiMTJhMTI4ZWE0NDY1NjEwMzg0NDU5Yg==","trackType":"tidal"} Oct 13 17:21:38 lounge volumio[1216]: verbose: CURRENT POSITION 1 Oct 13 17:21:38 lounge volumio[1216]: info: CoreStateMachine::syncState stateService play Oct 13 17:21:38 lounge volumio[1216]: info: CoreStateMachine::syncState currentStatus play Oct 13 17:21:38 lounge volumio[1216]: info: Received an update from plugin. extracting info from payload Oct 13 17:21:38 lounge volumio[1216]: info: CoreStateMachine::pushState Oct 13 17:21:38 lounge volumio[1216]: info: CorePlayQueue::getTrack 1 Oct 13 17:21:38 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 17:21:38 lounge volumio[1216]: info: CoreCommandRouter::volumioPushState Oct 13 17:21:38 lounge volumio[1216]: info: CoreCommandRouter::volumioGetState Oct 13 17:21:38 lounge volumio[1216]: info: CorePlayQueue::getTrack 1 Oct 13 17:21:38 lounge volumio[1216]: info: CoreStateMachine::pushState Oct 13 17:21:38 lounge volumio[1216]: info: CorePlayQueue::getTrack 1 Oct 13 17:21:38 lounge volumio[1216]: info: CoreCommandRouter::volumioPushState Oct 13 17:21:38 lounge volumio[1216]: info: CoreCommandRouter::volumioGetState Oct 13 17:21:38 lounge volumio[1216]: info: CorePlayQueue::getTrack 1 Oct 13 17:21:38 lounge volumio5-onboarding[1911]: time=2026-10-13T17:21:38.805+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.68.62:54916,00:00:00:00:00:00%01 @ 0x3094e40" state=STATUS_PLAYING positionMs=0 volume=100 Oct 13 17:21:38 lounge volumio5-onboarding[1911]: time=2026-10-13T17:21:38.805+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.62:54916,00:00:00:00:00:00%01 @ 0x3094e40" id=tidal://song/5267743 title="Someone Like You" Oct 13 17:21:38 lounge volumio5-onboarding[1911]: time=2026-10-13T17:21:38.807+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.68.62:54916,00:00:00:00:00:00%01 @ 0x3094e40" state=STATUS_PLAYING positionMs=0 volume=100 Oct 13 17:21:38 lounge volumio5-onboarding[1911]: time=2026-10-13T17:21:38.808+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.62:54916,00:00:00:00:00:00%01 @ 0x3094e40" id=tidal://song/5267743 title="Someone Like You" Oct 13 17:21:38 lounge volumio[1216]: info: ------------------------------ 27ms Oct 13 17:21:40 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 17:21:42 lounge systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 13 17:21:44 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 17:21:48 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 17:21:52 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 17:21:56 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 17:21:57 lounge systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 13 17:21:57 lounge systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 13 17:22:00 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 17:22:04 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 17:22:08 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 17:22:12 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 17:22:15 lounge volumio[1216]: info: Disabling MyMusic plugin upnp Oct 13 17:22:15 lounge sudo[3269]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 13 17:22:15 lounge sudo[3269]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 17:22:15 lounge systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Oct 13 17:22:16 lounge volumio[1216]: info: Enabling MyMusic plugin upnp Oct 13 17:22:16 lounge volumio[1216]: info: Enabling plugin upnp Oct 13 17:22:16 lounge volumio[1216]: info: Loading plugin "upnp"... Oct 13 17:22:16 lounge volumio[1216]: info: [1791904936424] Starting Upmpd Daemon Oct 13 17:22:16 lounge volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 17:22:16 lounge volumio[1216]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 17:22:16 lounge volumio[1216]: Error: listen EADDRINUSE: address already in use :::6599 Oct 13 17:22:16 lounge volumio[1216]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Oct 13 17:22:16 lounge volumio[1216]: at listenInCluster (node:net:1920:12) Oct 13 17:22:16 lounge volumio[1216]: at Server.listen (node:net:2008:7) Oct 13 17:22:16 lounge volumio[1216]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 13 17:22:16 lounge volumio[1216]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 13 17:22:16 lounge volumio[1216]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 13 17:22:16 lounge volumio[1216]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 13 17:22:16 lounge volumio[1216]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Oct 13 17:22:16 lounge volumio[1216]: code: 'EADDRINUSE', Oct 13 17:22:16 lounge volumio[1216]: errno: -98, Oct 13 17:22:16 lounge volumio[1216]: syscall: 'listen', Oct 13 17:22:16 lounge volumio[1216]: address: '::', Oct 13 17:22:16 lounge volumio[1216]: port: 6599 Oct 13 17:22:16 lounge volumio[1216]: } Oct 13 17:22:16 lounge volumio[1216]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 17:22:16 lounge sudo[3285]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-13 17:21' Oct 13 17:22:16 lounge sudo[3285]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"