-- Logs begin at Thu 2026-10-15 01:38:50 CEST, end at Fri 2026-10-16 16:19:50 CEST. -- Oct 16 16:18:01 volumio mpd[1168]: Oct 16 16:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 16 16:18:01 volumio systemd[1]: Started Music Player Daemon. Oct 16 16:18:01 volumio volumio[826]: Upnp client error: Error: This socket has been ended by the other party Oct 16 16:18:01 volumio sudo[1102]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:01 volumio sudo[1091]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:01 volumio volumio[826]: Upnp client error: Error: This socket has been ended by the other party Oct 16 16:18:01 volumio volumio[826]: info: Completed starting Core Plugins Oct 16 16:18:01 volumio volumio[826]: info: ------------------------------------------- Oct 16 16:18:01 volumio volumio[826]: info: ----- MyVolumio plugins startup ---- Oct 16 16:18:01 volumio volumio[826]: info: ------------------------------------------- Oct 16 16:18:01 volumio volumio[826]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 16 16:18:02 volumio volumio[826]: error: MPD error: The expression evaluated to a falsy value: Oct 16 16:18:02 volumio volumio[826]: assert.ok(self.idling) Oct 16 16:18:02 volumio volumio[826]: error: The expression evaluated to a falsy value: Oct 16 16:18:02 volumio volumio[826]: assert.ok(self.idling) Oct 16 16:18:02 volumio volumio[826]: error: updateQueue error: null Oct 16 16:18:02 volumio volumio[826]: info: MPD running with PID1168 Oct 16 16:18:02 volumio volumio[826]: ,establishing connection Oct 16 16:18:02 volumio volumio[826]: error: updateQueue error: null Oct 16 16:18:04 volumio volumio[826]: info: AutoStart - Plugin is starting Oct 16 16:18:04 volumio volumio[826]: info: CoreCommandRouter::volumioGetQueue Oct 16 16:18:04 volumio volumio[826]: info: CoreStateMachine::getQueue Oct 16 16:18:04 volumio volumio[826]: info: CorePlayQueue::getQueue Oct 16 16:18:04 volumio volumio[826]: info: AutoStart - start playing Oct 16 16:18:04 volumio volumio[826]: info: AutoStart - start playing with no specific position Oct 16 16:18:04 volumio volumio[826]: info: CoreCommandRouter::volumioPlay Oct 16 16:18:04 volumio volumio[826]: info: CoreStateMachine::play index 0 Oct 16 16:18:04 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 16:18:04 volumio volumio[826]: info: CoreStateMachine::stop Oct 16 16:18:04 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 16:18:04 volumio volumio[826]: info: CoreStateMachine::play index undefined Oct 16 16:18:04 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 16:18:04 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 16 16:18:04 volumio volumio[826]: info: CoreStateMachine::startPlaybackTimer Oct 16 16:18:04 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 16 16:18:04 volumio volumio[826]: info: [1792160284437] ControllerWebradio::clearAddPlayTrack Oct 16 16:18:04 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 16:18:04 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 16:18:04 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 16:18:04 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 16 16:18:04 volumio volumio[826]: info: sendMpdCommand stop took 199 milliseconds Oct 16 16:18:04 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 16:18:04 volumio volumio[826]: info: Oct 16 16:18:04 volumio volumio[826]: ---------------------------- MPD announces system playlist update Oct 16 16:18:04 volumio volumio[826]: info: Ignoring MPD Status Update Oct 16 16:18:04 volumio volumio[826]: info: sendMpdCommand clear took 26 milliseconds Oct 16 16:18:04 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand load "http://bcast.vigormultimedia.com:8888/sjcompl320mp3" Oct 16 16:18:04 volumio volumio[826]: info: Oct 16 16:18:04 volumio volumio[826]: ---------------------------- MPD announces system playlist update Oct 16 16:18:04 volumio volumio[826]: info: Ignoring MPD Status Update Oct 16 16:18:04 volumio volumio[826]: info: Oct 16 16:18:04 volumio volumio[826]: ---------------------------- MPD announces system playlist update Oct 16 16:18:04 volumio volumio[826]: info: Ignoring MPD Status Update Oct 16 16:18:04 volumio volumio[826]: error: updateQueue error: null Oct 16 16:18:04 volumio volumio[826]: info: ------------------------------ 99ms Oct 16 16:18:05 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 16 16:18:05 volumio volumio[826]: error: updateQueue error: null Oct 16 16:18:06 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand add "http://bcast.vigormultimedia.com:8888/sjcompl320mp3" Oct 16 16:18:06 volumio volumio[826]: info: ------------------------------ 1443ms Oct 16 16:18:06 volumio volumio[826]: info: ------------------------------ 1423ms Oct 16 16:18:06 volumio volumio[826]: info: Oct 16 16:18:06 volumio volumio[826]: ---------------------------- MPD announces system playlist update Oct 16 16:18:06 volumio volumio[826]: info: Ignoring MPD Status Update Oct 16 16:18:06 volumio volumio[826]: info: sendMpdCommand add "http://bcast.vigormultimedia.com:8888/sjcompl320mp3" took 124 milliseconds Oct 16 16:18:06 volumio volumio[826]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 16 16:18:06 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand play Oct 16 16:18:06 volumio volumio[826]: info: Oct 16 16:18:06 volumio volumio[826]: ---------------------------- MPD announces system playlist update Oct 16 16:18:06 volumio volumio[826]: info: Ignoring MPD Status Update Oct 16 16:18:06 volumio volumio[826]: info: Oct 16 16:18:06 volumio volumio[826]: ---------------------------- MPD announces system playlist update Oct 16 16:18:06 volumio volumio[826]: info: Ignoring MPD Status Update Oct 16 16:18:06 volumio volumio[826]: info: ------------------------------ 225ms Oct 16 16:18:06 volumio volumio[826]: info: sendMpdCommand play took 171 milliseconds Oct 16 16:18:06 volumio volumio[826]: info: ------------------------------ 150ms Oct 16 16:18:06 volumio volumio[826]: info: ------------------------------ 101ms Oct 16 16:18:06 volumio volumio[826]: info: Oct 16 16:18:06 volumio volumio[826]: ---------------------------- MPD announces state update: player Oct 16 16:18:06 volumio volumio[826]: info: ControllerMpd::getState Oct 16 16:18:06 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:18:06 volumio volumio[826]: info: Oct 16 16:18:06 volumio volumio[826]: ---------------------------- MPD announces state update: player Oct 16 16:18:06 volumio volumio[826]: info: ControllerMpd::getState Oct 16 16:18:06 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:18:06 volumio volumio[826]: info: Oct 16 16:18:06 volumio volumio[826]: ---------------------------- MPD announces state update: player Oct 16 16:18:07 volumio volumio[826]: info: ControllerMpd::getState Oct 16 16:18:07 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:18:07 volumio volumio[826]: info: Oct 16 16:18:07 volumio volumio[826]: ---------------------------- MPD announces state update: player Oct 16 16:18:07 volumio volumio[826]: info: Oct 16 16:18:07 volumio volumio[826]: ---------------------------- MPD announces system playlist update Oct 16 16:18:07 volumio volumio[826]: info: Ignoring MPD Status Update Oct 16 16:18:07 volumio volumio[826]: info: Oct 16 16:18:07 volumio volumio[826]: ---------------------------- MPD announces state update: player Oct 16 16:18:07 volumio volumio[826]: info: sendMpdCommand status took 460 milliseconds Oct 16 16:18:07 volumio volumio[826]: info: ControllerMpd::getState Oct 16 16:18:07 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:18:07 volumio volumio[826]: info: sendMpdCommand status took 187 milliseconds Oct 16 16:18:07 volumio volumio[826]: info: ControllerMpd::getState Oct 16 16:18:07 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:18:07 volumio volumio[826]: verbose: ControllerMpd::parseState Oct 16 16:18:07 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:18:07 volumio volumio[826]: verbose: ControllerMpd::parseState Oct 16 16:18:07 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:18:07 volumio volumio[826]: info: Oct 16 16:18:07 volumio volumio[826]: ---------------------------- MPD announces state update: player Oct 16 16:18:07 volumio volumio[826]: info: ControllerMpd::getState Oct 16 16:18:07 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:18:07 volumio volumio[826]: info: Oct 16 16:18:07 volumio volumio[826]: ---------------------------- MPD announces system playlist update Oct 16 16:18:07 volumio volumio[826]: info: Ignoring MPD Status Update Oct 16 16:18:07 volumio volumio[826]: info: Oct 16 16:18:07 volumio volumio[826]: ---------------------------- MPD announces state update: player Oct 16 16:18:07 volumio volumio[826]: info: ControllerMpd::getState Oct 16 16:18:07 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:18:07 volumio volumio[826]: info: sendMpdCommand status took 452 milliseconds Oct 16 16:18:07 volumio volumio[826]: info: sendMpdCommand status took 367 milliseconds Oct 16 16:18:07 volumio volumio[826]: info: ------------------------------ 425ms Oct 16 16:18:07 volumio volumio[826]: info: sendMpdCommand status took 318 milliseconds Oct 16 16:18:07 volumio volumio[826]: info: sendMpdCommand playlistinfo took 276 milliseconds Oct 16 16:18:07 volumio volumio[826]: info: sendMpdCommand playlistinfo took 242 milliseconds Oct 16 16:18:07 volumio volumio[826]: info: sendMpdCommand status took 201 milliseconds Oct 16 16:18:07 volumio volumio[826]: info: ------------------------------ 172ms Oct 16 16:18:07 volumio volumio[826]: info: sendMpdCommand status took 152 milliseconds Oct 16 16:18:07 volumio volumio[826]: verbose: ControllerMpd::parseState Oct 16 16:18:07 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:18:07 volumio volumio[826]: verbose: ControllerMpd::parseState Oct 16 16:18:07 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:18:07 volumio volumio[826]: verbose: ControllerMpd::parseState Oct 16 16:18:07 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:18:07 volumio volumio[826]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:18:07 volumio volumio[826]: verbose: ControllerMpd::parseState Oct 16 16:18:07 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:18:07 volumio volumio[826]: verbose: ControllerMpd::parseState Oct 16 16:18:07 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:18:07 volumio volumio[826]: info: ControllerMpd::pushState Oct 16 16:18:07 volumio volumio[826]: info: CoreCommandRouter::servicePushState Oct 16 16:18:07 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 16 16:18:07 volumio volumio[826]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Norman Brown - Places In The Heart","artist":"SmoothJazz.com.pl","album":null,"uri":"http://bcast.vigormultimedia.com:8888/sjcompl320mp3","trackType":""} Oct 16 16:18:07 volumio volumio[826]: verbose: CURRENT POSITION 0 Oct 16 16:18:07 volumio volumio[826]: info: CoreStateMachine::syncState stateService play Oct 16 16:18:07 volumio volumio[826]: info: CoreStateMachine::syncState currentStatus stop Oct 16 16:18:07 volumio volumio[826]: info: ControllerMpd::pushState Oct 16 16:18:07 volumio volumio[826]: info: CoreCommandRouter::servicePushState Oct 16 16:18:07 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 16 16:18:07 volumio volumio[826]: verbose: STATE SERVICE {"status":"play","position":0,"seek":829,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Norman Brown - Places In The Heart","artist":"SmoothJazz.com.pl","album":null,"uri":"http://bcast.vigormultimedia.com:8888/sjcompl320mp3","trackType":""} Oct 16 16:18:07 volumio volumio[826]: verbose: CURRENT POSITION 0 Oct 16 16:18:07 volumio volumio[826]: info: CoreStateMachine::syncState stateService play Oct 16 16:18:07 volumio volumio[826]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:18:07 volumio volumio[826]: info: Received an update from plugin. extracting info from payload Oct 16 16:18:07 volumio volumio[826]: info: CoreStateMachine::pushState Oct 16 16:18:07 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 16:18:07 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:07 volumio volumio[826]: info: CoreStateMachine::pushState Oct 16 16:18:07 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:07 volumio volumio[826]: info: ------------------------------ 1098ms Oct 16 16:18:07 volumio volumio[826]: info: ------------------------------ 929ms Oct 16 16:18:07 volumio volumio[826]: info: Oct 16 16:18:07 volumio volumio[826]: ---------------------------- MPD announces system playlist update Oct 16 16:18:07 volumio volumio[826]: info: Ignoring MPD Status Update Oct 16 16:18:07 volumio volumio[826]: info: Oct 16 16:18:07 volumio volumio[826]: ---------------------------- MPD announces state update: player Oct 16 16:18:07 volumio volumio[826]: info: ControllerMpd::getState Oct 16 16:18:07 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand status Oct 16 16:18:07 volumio volumio[826]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 16 16:18:07 volumio volumio[826]: info: sendMpdCommand playlistinfo took 437 milliseconds Oct 16 16:18:07 volumio volumio[826]: info: sendMpdCommand playlistinfo took 429 milliseconds Oct 16 16:18:07 volumio volumio[826]: info: sendMpdCommand playlistinfo took 425 milliseconds Oct 16 16:18:07 volumio volumio[826]: info: sendMpdCommand playlistinfo took 421 milliseconds Oct 16 16:18:07 volumio volumio[826]: info: sendMpdCommand playlistinfo took 415 milliseconds Oct 16 16:18:07 volumio volumio[826]: info: ------------------------------ 127ms Oct 16 16:18:07 volumio volumio[826]: info: sendMpdCommand status took 106 milliseconds Oct 16 16:18:07 volumio volumio[826]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:18:07 volumio volumio[826]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:18:08 volumio volumio[826]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:18:08 volumio volumio[826]: verbose: ControllerMpd::parseState Oct 16 16:18:08 volumio volumio[826]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 16:18:08 volumio volumio[826]: info: ControllerMpd::pushState Oct 16 16:18:08 volumio volumio[826]: info: CoreCommandRouter::servicePushState Oct 16 16:18:08 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 16 16:18:08 volumio volumio[826]: verbose: STATE SERVICE {"status":"play","position":0,"seek":886,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Norman Brown - Places In The Heart","artist":"SmoothJazz.com.pl","album":null,"uri":"http://bcast.vigormultimedia.com:8888/sjcompl320mp3","trackType":""} Oct 16 16:18:08 volumio volumio[826]: verbose: CURRENT POSITION 0 Oct 16 16:18:08 volumio volumio[826]: info: CoreStateMachine::syncState stateService play Oct 16 16:18:08 volumio volumio[826]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:18:08 volumio volumio[826]: info: Received an update from plugin. extracting info from payload Oct 16 16:18:08 volumio volumio[826]: info: CoreStateMachine::pushState Oct 16 16:18:08 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 16:18:08 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:08 volumio volumio[826]: info: CoreStateMachine::pushState Oct 16 16:18:08 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:08 volumio volumio[826]: info: ControllerMpd::pushState Oct 16 16:18:08 volumio volumio[826]: info: CoreCommandRouter::servicePushState Oct 16 16:18:08 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 16 16:18:08 volumio volumio[826]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1080,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Norman Brown - Places In The Heart","artist":"SmoothJazz.com.pl","album":null,"uri":"http://bcast.vigormultimedia.com:8888/sjcompl320mp3","trackType":""} Oct 16 16:18:08 volumio volumio[826]: verbose: CURRENT POSITION 0 Oct 16 16:18:08 volumio volumio[826]: info: CoreStateMachine::syncState stateService play Oct 16 16:18:08 volumio volumio[826]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:18:08 volumio volumio[826]: info: Received an update from plugin. extracting info from payload Oct 16 16:18:08 volumio volumio[826]: info: CoreStateMachine::pushState Oct 16 16:18:08 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:08 volumio volumio[826]: info: CoreStateMachine::pushState Oct 16 16:18:08 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:08 volumio volumio[826]: info: ControllerMpd::pushState Oct 16 16:18:08 volumio volumio[826]: info: CoreCommandRouter::servicePushState Oct 16 16:18:08 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 16 16:18:08 volumio volumio[826]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1080,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Norman Brown - Places In The Heart","artist":"SmoothJazz.com.pl","album":null,"uri":"http://bcast.vigormultimedia.com:8888/sjcompl320mp3","trackType":""} Oct 16 16:18:08 volumio volumio[826]: verbose: CURRENT POSITION 0 Oct 16 16:18:08 volumio volumio[826]: info: CoreStateMachine::syncState stateService play Oct 16 16:18:08 volumio volumio[826]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:18:08 volumio volumio[826]: info: Received an update from plugin. extracting info from payload Oct 16 16:18:08 volumio volumio[826]: info: CoreStateMachine::pushState Oct 16 16:18:08 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:08 volumio volumio[826]: info: CoreStateMachine::pushState Oct 16 16:18:08 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:08 volumio volumio[826]: info: ControllerMpd::pushState Oct 16 16:18:08 volumio volumio[826]: info: CoreCommandRouter::servicePushState Oct 16 16:18:08 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 16 16:18:08 volumio volumio[826]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1183,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Norman Brown - Places In The Heart","artist":"SmoothJazz.com.pl","album":null,"uri":"http://bcast.vigormultimedia.com:8888/sjcompl320mp3","trackType":""} Oct 16 16:18:08 volumio volumio[826]: verbose: CURRENT POSITION 0 Oct 16 16:18:08 volumio volumio[826]: info: CoreStateMachine::syncState stateService play Oct 16 16:18:08 volumio volumio[826]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:18:08 volumio volumio[826]: info: Received an update from plugin. extracting info from payload Oct 16 16:18:08 volumio volumio[826]: info: CoreStateMachine::pushState Oct 16 16:18:08 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:08 volumio volumio[826]: info: CoreStateMachine::pushState Oct 16 16:18:08 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:08 volumio volumio[826]: info: ControllerMpd::pushState Oct 16 16:18:08 volumio volumio[826]: info: CoreCommandRouter::servicePushState Oct 16 16:18:08 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 16 16:18:08 volumio volumio[826]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1183,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Norman Brown - Places In The Heart","artist":"SmoothJazz.com.pl","album":null,"uri":"http://bcast.vigormultimedia.com:8888/sjcompl320mp3","trackType":""} Oct 16 16:18:08 volumio volumio[826]: verbose: CURRENT POSITION 0 Oct 16 16:18:08 volumio volumio[826]: info: CoreStateMachine::syncState stateService play Oct 16 16:18:08 volumio volumio[826]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:18:08 volumio volumio[826]: info: Received an update from plugin. extracting info from payload Oct 16 16:18:08 volumio volumio[826]: info: CoreStateMachine::pushState Oct 16 16:18:08 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:08 volumio volumio[826]: info: CoreStateMachine::pushState Oct 16 16:18:08 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:08 volumio volumio[826]: info: ------------------------------ 1987ms Oct 16 16:18:08 volumio volumio[826]: info: ------------------------------ 1930ms Oct 16 16:18:08 volumio volumio[826]: info: ------------------------------ 1890ms Oct 16 16:18:08 volumio volumio[826]: info: ------------------------------ 1687ms Oct 16 16:18:08 volumio volumio[826]: info: ------------------------------ 1635ms Oct 16 16:18:09 volumio volumio[826]: info: sendMpdCommand playlistinfo took 1099 milliseconds Oct 16 16:18:09 volumio volumio[826]: verbose: ControllerMpd::parseTrackInfo Oct 16 16:18:09 volumio volumio[826]: info: ControllerMpd::pushState Oct 16 16:18:09 volumio volumio[826]: info: CoreCommandRouter::servicePushState Oct 16 16:18:09 volumio volumio[826]: info: CorePlayQueue::getTrack 0 Oct 16 16:18:09 volumio volumio[826]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1686,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Norman Brown - Places In The Heart","artist":"SmoothJazz.com.pl","album":null,"uri":"http://bcast.vigormultimedia.com:8888/sjcompl320mp3","trackType":""} Oct 16 16:18:09 volumio volumio[826]: verbose: CURRENT POSITION 0 Oct 16 16:18:09 volumio volumio[826]: info: CoreStateMachine::syncState stateService play Oct 16 16:18:09 volumio volumio[826]: info: CoreStateMachine::syncState currentStatus play Oct 16 16:18:09 volumio volumio[826]: info: Received an update from plugin. extracting info from payload Oct 16 16:18:09 volumio volumio[826]: info: CoreStateMachine::pushState Oct 16 16:18:09 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 16:18:09 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:09 volumio volumio[826]: info: CoreStateMachine::pushState Oct 16 16:18:09 volumio volumio[826]: info: CoreCommandRouter::volumioPushState Oct 16 16:18:09 volumio volumio[826]: info: ------------------------------ 1464ms Oct 16 16:18:09 volumio volumio[1087]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 16 16:18:11 volumio volumio[826]: info: Adding plugin bluetooth to MyMusic Plugins Oct 16 16:18:11 volumio volumio[826]: info: Adding plugin multiroom to MyMusic Plugins Oct 16 16:18:11 volumio volumio[826]: info: Adding plugin metavolumio to MyMusic Plugins Oct 16 16:18:11 volumio volumio[826]: info: Adding plugin cd_controller to MyMusic Plugins Oct 16 16:18:11 volumio volumio[826]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 16 16:18:11 volumio volumio[826]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 16 16:18:11 volumio volumio[826]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 16 16:18:11 volumio volumio[826]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 16 16:18:21 volumio volumio-remote-updater[438]: [2026-10-16 16:18:21] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Oct 16 16:18:26 volumio volumio-remote-updater[438]: [2026-10-16 16:18:26] [connect] Successful connection Oct 16 16:18:29 volumio volumio[826]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 16 16:18:29 volumio volumio[826]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 16 16:18:29 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 16:18:29 volumio volumio[826]: info: Starting MyVolumio Remote Streaming Endpoints Oct 16 16:18:31 volumio volumio[826]: info: MyVolumio login type: Token Oct 16 16:18:32 volumio volumio[826]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 16 16:18:32 volumio volumio[826]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 16 16:18:39 volumio volumio-remote-updater[438]: [2026-10-16 16:18:39] [connect] Successful connection Oct 16 16:18:53 volumio volumio[826]: info: Starting Streaming Service Transparent Proxy Oct 16 16:18:53 volumio volumio[826]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 16 16:18:53 volumio volumio[826]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 16 16:18:53 volumio volumio[826]: info: Streaming services startup Oct 16 16:18:53 volumio volumio[826]: info: Starting Streaming Daemon Oct 16 16:18:54 volumio volumio-remote-updater[438]: [2026-10-16 16:18:54] [connect] Successful connection Oct 16 16:18:54 volumio volumio[826]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 16 16:18:55 volumio sudo[1393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 16 16:18:55 volumio sudo[1393]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:18:55 volumio sudo[1393]: pam_unix(sudo:session): session closed for user root Oct 16 16:18:56 volumio volumio5-onboarding[1104]: time=2026-10-16T16:18:56.953+02:00 level=ERROR msg="failed reading message" error="websocket: close 1000 (normal)" Oct 16 16:18:57 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:57 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:18:57 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 16 16:18:57 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 16 16:18:57 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 16 16:18:57 volumio volumio[826]: info: CoreCommandRouter::volumioGetBrowseSources Oct 16 16:18:57 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 16:18:57 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 16 16:18:59 volumio volumio[826]: error: Cannot start Volumio Streaming Daemon Oct 16 16:18:59 volumio volumio[826]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 16 16:18:59 volumio volumio[826]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 16 16:19:00 volumio volumio[826]: Upnp client error: Error: This socket has been ended by the other party Oct 16 16:19:00 volumio volumio[826]: verbose: New Socket.io Connection to 192.168.137.76 from 192.168.137.1 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Oct 16 16:19:01 volumio volumio[826]: verbose: New Socket.io Connection to 192.168.137.76 from 192.168.137.1 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Oct 16 16:19:01 volumio volumio[826]: verbose: New Socket.io Connection to 192.168.137.76 from 192.168.137.1 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Oct 16 16:19:01 volumio volumio[826]: verbose: New Socket.io Connection to 192.168.137.76 from 192.168.137.1 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 16 16:19:01 volumio volumio[826]: verbose: New Socket.io Connection to 192.168.137.76 from 192.168.137.1 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 16 16:19:02 volumio volumio[826]: verbose: New Socket.io Connection to 192.168.137.76 from 192.168.137.1 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Oct 16 16:19:02 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Oct 16 16:19:03 volumio volumio[826]: Upnp client error: Error: This socket has been ended by the other party Oct 16 16:19:03 volumio volumio[826]: Upnp client error: Error: This socket has been ended by the other party Oct 16 16:19:03 volumio volumio[826]: Upnp client error: Error: This socket has been ended by the other party Oct 16 16:19:03 volumio volumio[826]: Upnp client error: Error: This socket has been ended by the other party Oct 16 16:19:03 volumio volumio[826]: Upnp client error: Error: This socket has been ended by the other party Oct 16 16:19:03 volumio volumio[826]: Upnp client error: Error: This socket has been ended by the other party Oct 16 16:19:03 volumio volumio[826]: Upnp client error: Error: This socket has been ended by the other party Oct 16 16:19:03 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 16 16:19:04 volumio volumio[826]: error: MyVolumio Custom Token format not valid, refreshing it Oct 16 16:19:05 volumio volumio[826]: verbose: New Socket.io Connection to 192.168.137.76 from 192.168.137.1 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 16 16:19:05 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Oct 16 16:19:06 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Oct 16 16:19:07 volumio volumio[826]: info: CoreCommandRouter::volumioGetState Oct 16 16:19:08 volumio volumio[826]: error: Cannot refresh My Volumio Token: [object Object] Oct 16 16:19:09 volumio volumio[826]: verbose: New Socket.io Connection to 192.168.137.76 from 192.168.137.1 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 16 16:19:09 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Oct 16 16:19:09 volumio volumio-remote-updater[438]: [2026-10-16 16:19:09] [connect] Successful connection Oct 16 16:19:09 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Oct 16 16:19:10 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 16 16:19:10 volumio volumio[826]: Upnp client error: Error: This socket has been ended by the other party Oct 16 16:19:11 volumio volumio[826]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 16:19:11 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 16:19:11 volumio volumio[826]: info: CoreCommandRouter::volumioGetState Oct 16 16:19:11 volumio volumio[826]: info: CoreCommandRouter::volumioGetState Oct 16 16:19:11 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 16 16:19:11 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 16 16:19:11 volumio volumio[826]: info: Received Get System Info Oct 16 16:19:11 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 16:19:11 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 16:19:11 volumio volumio[826]: info: Discovery: Getting this device information Oct 16 16:19:11 volumio volumio[826]: info: CoreCommandRouter::volumioGetState Oct 16 16:19:11 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 16:19:11 volumio volumio[826]: info: CoreCommandRouter::volumioGetState Oct 16 16:19:11 volumio volumio[826]: info: Listing playlists Oct 16 16:19:13 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 11 Oct 16 16:19:13 volumio volumio-remote-updater[438]: [2026-10-16 16:19:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1792160349 101 Oct 16 16:19:13 volumio volumio[826]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 11 Oct 16 16:19:14 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 16 16:19:15 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Oct 16 16:19:15 volumio volumio[826]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 16 16:19:15 volumio volumio[826]: info: Completed starting MyVolumio Plugin Oct 16 16:19:15 volumio volumio[826]: [Metrics] CommandRouter: 152s 630.60ms Oct 16 16:19:15 volumio volumio[826]: info: CoreCommandRouter::volumiosetStartupVolume Oct 16 16:19:15 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 16:19:15 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 16:19:16 volumio volumio[826]: info: CoreCommandRouter::Close All Modals sent Oct 16 16:19:16 volumio volumio[826]: info: CoreCommandRouter::Close All Modals sent Oct 16 16:19:17 volumio volumio[826]: Upnp client error: Error: This socket has been ended by the other party Oct 16 16:19:17 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 16 16:19:17 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 16 16:19:17 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 16 16:19:18 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Oct 16 16:19:19 volumio volumio[826]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Oct 16 16:19:19 volumio volumio[826]: aplay: main:828: audio open error: Device or resource busy Oct 16 16:19:20 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Oct 16 16:19:20 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Oct 16 16:19:20 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Oct 16 16:19:21 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Oct 16 16:19:21 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Oct 16 16:19:21 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Oct 16 16:19:22 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Oct 16 16:19:22 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Oct 16 16:19:22 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Oct 16 16:19:23 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Oct 16 16:19:24 volumio volumio[826]: info: BOOT COMPLETED Oct 16 16:19:24 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Oct 16 16:19:24 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Oct 16 16:19:25 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Oct 16 16:19:25 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Oct 16 16:19:25 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Oct 16 16:19:26 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Oct 16 16:19:26 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Oct 16 16:19:26 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Oct 16 16:19:27 volumio volumio[826]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 12 Oct 16 16:19:29 volumio volumio[826]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 16:19:29 volumio volumio[826]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 16:19:29 volumio volumio[826]: info: Retrieving Cloud Streaming UI Oct 16 16:19:30 volumio volumio[826]: info: Getting Tidal Cloud Configuration Oct 16 16:19:30 volumio volumio[826]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 16:19:30 volumio volumio[826]: info: Getting Qobuz Cloud Configuration Oct 16 16:19:30 volumio volumio[826]: info: Asking plugin for UI Config Oct 16 16:19:30 volumio volumio[826]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 16:19:30 volumio volumio[826]: info: Getting Spotify Cloud Configuration Oct 16 16:19:30 volumio volumio[826]: info: Asking plugin for UI Config Oct 16 16:19:30 volumio volumio[826]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 16:19:30 volumio volumio[826]: info: Saving Spotify Acccount Oct 16 16:19:30 volumio volumio[826]: info: Got Tidal Cloud Configuration Oct 16 16:19:30 volumio volumio[826]: info: Got it Oct 16 16:19:30 volumio volumio[826]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 16 16:19:30 volumio volumio[826]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 16:19:31 volumio volumio[826]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 16 16:19:31 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 16 16:19:31 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 16 16:19:31 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 16 16:19:31 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 16:19:31 volumio volumio[826]: info: CoreCommandRouter::volumioGetBrowseSources Oct 16 16:19:31 volumio volumio[826]: info: CoreCommandRouter::volumioGetBrowseSources Oct 16 16:19:31 volumio volumio[826]: info: CoreCommandRouter::volumioGetBrowseSources Oct 16 16:19:32 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 16:19:32 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 16 16:19:33 volumio volumio[826]: STREAMING PROXY: Starting server on port 3245 Oct 16 16:19:33 volumio volumio[826]: Node JS runtime: 14 Oct 16 16:19:35 volumio volumio[826]: info: Disabling MyMusic plugin upnp Oct 16 16:19:35 volumio sudo[1462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 16 16:19:35 volumio sudo[1462]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 16:19:36 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 16:19:36 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Oct 16 16:19:39 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 16:19:43 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 16:19:45 volumio volumio[826]: info: Enabling MyMusic plugin upnp Oct 16 16:19:45 volumio volumio[826]: info: Enabling plugin upnp Oct 16 16:19:45 volumio volumio[826]: info: Loading plugin "upnp"... Oct 16 16:19:45 volumio volumio[826]: info: [1792160385576] Starting Upmpd Daemon Oct 16 16:19:45 volumio volumio[826]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 16 16:19:45 volumio volumio[826]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 16:19:45 volumio volumio[826]: Error: listen EADDRINUSE: address already in use :::6599 Oct 16 16:19:45 volumio volumio[826]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Oct 16 16:19:45 volumio volumio[826]: at listenInCluster (net.js:1366:12) Oct 16 16:19:45 volumio volumio[826]: at Server.listen (net.js:1452:7) Oct 16 16:19:45 volumio volumio[826]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 16 16:19:45 volumio volumio[826]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 16 16:19:45 volumio volumio[826]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 16 16:19:45 volumio volumio[826]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 16 16:19:45 volumio volumio[826]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Oct 16 16:19:45 volumio volumio[826]: code: 'EADDRINUSE', Oct 16 16:19:45 volumio volumio[826]: errno: -98, Oct 16 16:19:45 volumio volumio[826]: syscall: 'listen', Oct 16 16:19:45 volumio volumio[826]: address: '::', Oct 16 16:19:45 volumio volumio[826]: port: 6599 Oct 16 16:19:45 volumio volumio[826]: } Oct 16 16:19:45 volumio volumio[826]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 16:19:50 volumio sudo[1545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-16 16:18 Oct 16 16:19:50 volumio sudo[1545]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"