-- Logs begin at Thu 2025-12-11 22:36:15 CET, end at Thu 2025-12-11 23:31:52 CET. -- Dec 11 23:30:23 volumio go-librespot[1791]: time="2025-12-11T23:30:23+01:00" level=trace msg="received accesspoint ping" Dec 11 23:30:23 volumio go-librespot[1791]: time="2025-12-11T23:30:23+01:00" level=trace msg="received accesspoint pong ack" Dec 11 23:30:23 volumio go-librespot[1791]: time="2025-12-11T23:30:23+01:00" level=trace msg="sent dealer ping" Dec 11 23:30:23 volumio go-librespot[1791]: time="2025-12-11T23:30:23+01:00" level=trace msg="received dealer pong" Dec 11 23:30:23 volumio vtcs[2057]: [close:85] Entering Dec 11 23:30:23 volumio vtcs[2057]: [feedThread:276] Exiting Dec 11 23:30:23 volumio vtcs[2057]: [close:100] Exiting Dec 11 23:30:23 volumio vtcs[2057]: [2025-12-11 23:30:23.374] [tisoc] [warning] [PlaybackControllerImpl.cpp:220] Ignore play request because player state is already PLAYING. state_=2, playstate_=1, player_state_=1 Dec 11 23:30:23 volumio volumio[993]: info: Pushing metadata Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::servicePushState Dec 11 23:30:23 volumio volumio[993]: info: CoreStateMachine::pushState Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Dec 11 23:30:23 volumio volumio[993]: info: MRS: Pushing multiroomSync output update for this device Dec 11 23:30:23 volumio volumio[993]: info: MRS: Pushing multiroomSync output Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::servicePushState Dec 11 23:30:23 volumio volumio[993]: info: CoreStateMachine::pushState Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Dec 11 23:30:23 volumio volumio[993]: info: MRS: Pushing multiroomSync output update for this device Dec 11 23:30:23 volumio volumio[993]: info: MRS: Pushing multiroomSync output Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::servicePushState Dec 11 23:30:23 volumio volumio[993]: info: CoreStateMachine::pushState Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Dec 11 23:30:23 volumio volumio[993]: info: MRS: Pushing multiroomSync output update for this device Dec 11 23:30:23 volumio volumio[993]: info: MRS: Pushing multiroomSync output Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: SPOTIFY VOLUME 79 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: VOLUMIO VOLUME 80 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: SPOTIFY VOLUME 79 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: VOLUMIO VOLUME 80 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: SPOTIFY VOLUME 79 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: VOLUMIO VOLUME 80 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 11 23:30:23 volumio vtcs[2057]: [close:85] Entering Dec 11 23:30:23 volumio vtcs[2057]: [close:100] Exiting Dec 11 23:30:23 volumio volumio[993]: info: Pushing metadata Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::servicePushState Dec 11 23:30:23 volumio volumio[993]: info: CoreStateMachine::pushState Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Dec 11 23:30:23 volumio volumio[993]: info: MRS: Pushing multiroomSync output update for this device Dec 11 23:30:23 volumio volumio[993]: info: MRS: Pushing multiroomSync output Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::servicePushState Dec 11 23:30:23 volumio volumio[993]: info: CoreStateMachine::pushState Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Dec 11 23:30:23 volumio volumio[993]: info: MRS: Pushing multiroomSync output update for this device Dec 11 23:30:23 volumio volumio[993]: info: MRS: Pushing multiroomSync output Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::servicePushState Dec 11 23:30:23 volumio volumio[993]: info: CoreStateMachine::pushState Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Dec 11 23:30:23 volumio volumio[993]: info: MRS: Pushing multiroomSync output update for this device Dec 11 23:30:23 volumio volumio[993]: info: MRS: Pushing multiroomSync output Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::servicePushState Dec 11 23:30:23 volumio volumio[993]: info: CoreStateMachine::pushState Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Dec 11 23:30:23 volumio volumio[993]: info: MRS: Pushing multiroomSync output update for this device Dec 11 23:30:23 volumio volumio[993]: info: MRS: Pushing multiroomSync output Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::servicePushState Dec 11 23:30:23 volumio volumio[993]: info: CoreStateMachine::pushState Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Dec 11 23:30:23 volumio volumio[993]: info: MRS: Pushing multiroomSync output update for this device Dec 11 23:30:23 volumio volumio[993]: info: MRS: Pushing multiroomSync output Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: SPOTIFY VOLUME 79 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: VOLUMIO VOLUME 80 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: SPOTIFY VOLUME 79 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: VOLUMIO VOLUME 80 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: SPOTIFY VOLUME 79 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: VOLUMIO VOLUME 80 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: SPOTIFY VOLUME 79 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: VOLUMIO VOLUME 80 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: SPOTIFY VOLUME 79 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: VOLUMIO VOLUME 80 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 11 23:30:23 volumio vtcs[2057]: [close:85] Entering Dec 11 23:30:23 volumio vtcs[2057]: [close:100] Exiting Dec 11 23:30:23 volumio vtcs[2057]: [open:50] Opening with sample rate: 44100, type: 0 Dec 11 23:30:23 volumio vtcs[2057]: [start:106] Entering Dec 11 23:30:23 volumio vtcs[2057]: [start:113] Reopening PCM device... Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::servicePushState Dec 11 23:30:23 volumio volumio[993]: info: CoreStateMachine::pushState Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Dec 11 23:30:23 volumio volumio[993]: info: MRS: Pushing multiroomSync output update for this device Dec 11 23:30:23 volumio volumio[993]: info: MRS: Pushing multiroomSync output Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:30:23 volumio vtcs[2057]: [start:115] PCM opened successfully. Dec 11 23:30:23 volumio vtcs[2057]: [start:120] Exiting Dec 11 23:30:23 volumio vtcs[2057]: [feedThread:223] Entering Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: SPOTIFY VOLUME 79 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: VOLUMIO VOLUME 80 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::servicePushState Dec 11 23:30:23 volumio volumio[993]: info: CoreStateMachine::pushState Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Dec 11 23:30:23 volumio volumio[993]: info: MRS: Pushing multiroomSync output update for this device Dec 11 23:30:23 volumio volumio[993]: info: MRS: Pushing multiroomSync output Dec 11 23:30:23 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: SPOTIFY VOLUME 79 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: VOLUMIO VOLUME 80 Dec 11 23:30:23 volumio volumio[993]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 11 23:30:24 volumio volumio[993]: info: CoreCommandRouter::servicePushState Dec 11 23:30:24 volumio volumio[993]: info: CoreStateMachine::pushState Dec 11 23:30:24 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 23:30:24 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Dec 11 23:30:24 volumio volumio[993]: info: MRS: Pushing multiroomSync output update for this device Dec 11 23:30:24 volumio volumio[993]: info: MRS: Pushing multiroomSync output Dec 11 23:30:24 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:30:24 volumio volumio[993]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Dec 11 23:30:24 volumio volumio[993]: SPOTIFY: SPOTIFY VOLUME 79 Dec 11 23:30:24 volumio volumio[993]: SPOTIFY: VOLUMIO VOLUME 80 Dec 11 23:30:24 volumio volumio[993]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 11 23:30:45 volumio vtcs[2057]: [pause:147] Entering Dec 11 23:30:45 volumio volumio[993]: info: CoreCommandRouter::servicePushState Dec 11 23:30:45 volumio volumio[993]: info: CoreStateMachine::pushState Dec 11 23:30:45 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 23:30:45 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Dec 11 23:30:45 volumio volumio[993]: info: MRS: Pushing multiroomSync output update for this device Dec 11 23:30:45 volumio volumio[993]: info: MRS: Pushing multiroomSync output Dec 11 23:30:45 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:30:45 volumio volumio[993]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Dec 11 23:30:45 volumio volumio[993]: SPOTIFY: SPOTIFY VOLUME 79 Dec 11 23:30:45 volumio volumio[993]: SPOTIFY: VOLUMIO VOLUME 80 Dec 11 23:30:45 volumio volumio[993]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 11 23:30:45 volumio vtcs[2057]: [feedThread:276] Exiting Dec 11 23:30:45 volumio vtcs[2057]: [pause:161] Exiting Dec 11 23:30:53 volumio go-librespot[1791]: time="2025-12-11T23:30:53+01:00" level=trace msg="sent dealer ping" Dec 11 23:30:53 volumio go-librespot[1791]: time="2025-12-11T23:30:53+01:00" level=trace msg="received dealer pong" Dec 11 23:31:11 volumio volumio[993]: info: Clearing queue after UPNP request Dec 11 23:31:11 volumio volumio[993]: verbose: UNSET VOLATILE: Service: tidalconnect Dec 11 23:31:11 volumio volumio[993]: info: unSetVolatile called Dec 11 23:31:11 volumio volumio[993]: info: Relinquishing Volumio State to another service Dec 11 23:31:11 volumio vtcs[2057]: [close:85] Entering Dec 11 23:31:11 volumio vtcs[2057]: [close:100] Exiting Dec 11 23:31:11 volumio volumio[993]: info: PlaybackInactive Dec 11 23:31:11 volumio volumio[993]: info: Device Session is_active: true Dec 11 23:31:11 volumio volumio[993]: info: DeviceInactive Dec 11 23:31:11 volumio volumio[993]: info: Relinquishing Volumio State Dec 11 23:31:11 volumio vtcs[2057]: [2025-12-11 23:31:11] [info] asio async_shutdown error: asio.misc:2 (End of file) Dec 11 23:31:11 volumio volumio[993]: info: CoreStateMachine::ClearQueue Dec 11 23:31:11 volumio volumio[993]: info: CoreStateMachine::stop Dec 11 23:31:11 volumio volumio[993]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:31:11 volumio volumio[993]: info: CoreStateMachine::updateTrackBlock Dec 11 23:31:11 volumio volumio[993]: info: CorePlayQueue::getTrackBlock Dec 11 23:31:11 volumio volumio[993]: info: CoreStateMachine::stPlaybackTimer Dec 11 23:31:11 volumio volumio[993]: info: CoreStateMachine::pushState Dec 11 23:31:11 volumio volumio[993]: info: CorePlayQueue::getTrack 0 Dec 11 23:31:11 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 23:31:11 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Dec 11 23:31:11 volumio volumio[993]: info: MRS: Pushing multiroomSync output update for this device Dec 11 23:31:11 volumio volumio[993]: info: MRS: Pushing multiroomSync output Dec 11 23:31:11 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:31:11 volumio volumio[993]: info: CorePlayQueue::getTrack 0 Dec 11 23:31:11 volumio volumio[993]: info: CoreStateMachine::serviceStop Dec 11 23:31:11 volumio volumio[993]: info: CorePlayQueue::getTrack 0 Dec 11 23:31:11 volumio volumio[993]: info: ControllerMpd::stop Dec 11 23:31:11 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 23:31:11 volumio volumio[993]: info: CorePlayQueue::clearPlayQueue Dec 11 23:31:11 volumio volumio[993]: info: CorePlayQueue::saveQueue Dec 11 23:31:11 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Dec 11 23:31:11 volumio volumio[993]: info: MRS: Pushing multiroomSync output update for this device Dec 11 23:31:11 volumio volumio[993]: info: MRS: Pushing multiroomSync output Dec 11 23:31:11 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:31:11 volumio volumio[993]: info: CorePlayQueue::getTrack 0 Dec 11 23:31:11 volumio volumio[993]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:31:11 volumio volumio[993]: error: Upnp client error: Error: This socket has been ended by the other party Dec 11 23:31:11 volumio volumio[993]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Dec 11 23:31:11 volumio volumio[993]: SPOTIFY: SPOTIFY VOLUME 79 Dec 11 23:31:11 volumio volumio[993]: SPOTIFY: VOLUMIO VOLUME 80 Dec 11 23:31:11 volumio volumio[993]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 11 23:31:11 volumio volumio[993]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Dec 11 23:31:11 volumio volumio[993]: SPOTIFY: SPOTIFY VOLUME 79 Dec 11 23:31:11 volumio volumio[993]: SPOTIFY: VOLUMIO VOLUME 80 Dec 11 23:31:11 volumio volumio[993]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 11 23:31:11 volumio volumio[993]: info: sendMpdCommand stop took 4 milliseconds Dec 11 23:31:13 volumio volumio[993]: info: Starting UPNP Playback Dec 11 23:31:13 volumio volumio[993]: info: Preparing playback through UPNP Dec 11 23:31:13 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:31:13 volumio volumio[993]: info: CorePlayQueue::getTrack 0 Dec 11 23:31:13 volumio volumio[993]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 11 23:31:13 volumio volumio[993]: info: Dec 11 23:31:13 volumio volumio[993]: ---------------------------- MPD announces system playlist update Dec 11 23:31:13 volumio volumio[993]: info: Ignoring MPD Status Update Dec 11 23:31:13 volumio volumio[993]: info: ------------------------------ 1ms Dec 11 23:31:13 volumio volumio[993]: info: Dec 11 23:31:13 volumio volumio[993]: ---------------------------- MPD announces system playlist update Dec 11 23:31:13 volumio volumio[993]: info: Ignoring MPD Status Update Dec 11 23:31:13 volumio volumio[993]: info: ------------------------------ 0ms Dec 11 23:31:13 volumio volumio[993]: info: Dec 11 23:31:13 volumio volumio[993]: ---------------------------- MPD announces system playlist update Dec 11 23:31:13 volumio volumio[993]: info: Ignoring MPD Status Update Dec 11 23:31:13 volumio volumio[993]: info: ------------------------------ 0ms Dec 11 23:31:13 volumio volumio[993]: info: Dec 11 23:31:13 volumio volumio[993]: ---------------------------- MPD announces system playlist update Dec 11 23:31:13 volumio volumio[993]: info: Ignoring MPD Status Update Dec 11 23:31:13 volumio volumio[993]: info: ------------------------------ 0ms Dec 11 23:31:13 volumio volumio[993]: info: Dec 11 23:31:13 volumio volumio[993]: ---------------------------- MPD announces system playlist update Dec 11 23:31:13 volumio volumio[993]: info: Ignoring MPD Status Update Dec 11 23:31:13 volumio volumio[993]: info: ------------------------------ 0ms Dec 11 23:31:13 volumio volumio[993]: info: Dec 11 23:31:13 volumio volumio[993]: ---------------------------- MPD announces system playlist update Dec 11 23:31:13 volumio volumio[993]: info: Ignoring MPD Status Update Dec 11 23:31:13 volumio volumio[993]: info: ------------------------------ 0ms Dec 11 23:31:22 volumio volumio[993]: info: Dec 11 23:31:22 volumio volumio[993]: ---------------------------- MPD announces state update: player Dec 11 23:31:22 volumio volumio[993]: info: ControllerMpd::getState Dec 11 23:31:22 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand status Dec 11 23:31:22 volumio volumio[993]: info: Dec 11 23:31:22 volumio volumio[993]: ---------------------------- MPD announces state update: player Dec 11 23:31:22 volumio volumio[993]: info: sendMpdCommand status took 822 milliseconds Dec 11 23:31:22 volumio volumio[993]: info: ControllerMpd::getState Dec 11 23:31:22 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand status Dec 11 23:31:22 volumio volumio[993]: verbose: ControllerMpd::parseState Dec 11 23:31:22 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 23:31:22 volumio volumio[993]: info: sendMpdCommand status took 1 milliseconds Dec 11 23:31:22 volumio volumio[993]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 11 23:31:22 volumio volumio[993]: verbose: ControllerMpd::parseState Dec 11 23:31:22 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 23:31:22 volumio volumio[993]: verbose: ControllerMpd::parseTrackInfo Dec 11 23:31:22 volumio volumio[993]: info: ControllerMpd::pushState Dec 11 23:31:22 volumio volumio[993]: info: CoreCommandRouter::servicePushState Dec 11 23:31:22 volumio volumio[993]: verbose: In UPNP mode Dec 11 23:31:22 volumio volumio[993]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"88.2 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sad Waters (Live at Alexandra Palace, 2020)","artist":"Nick Cave & The Bad Seeds","album":"Idiot Prayer (Nick Cave Alone at Alexandra Palace)","uri":"http://192.168.1.22:43047/ea0707ba-b141-44de-9ee1-cba894e5fd04.wav","trackType":"wav"} Dec 11 23:31:22 volumio volumio[993]: verbose: CURRENT POSITION 0 Dec 11 23:31:22 volumio volumio[993]: info: CoreStateMachine::syncState stateService play Dec 11 23:31:22 volumio volumio[993]: info: CoreStateMachine::syncState currentStatus stop Dec 11 23:31:22 volumio volumio[993]: info: ------------------------------ 824ms Dec 11 23:31:22 volumio volumio[993]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 11 23:31:22 volumio volumio[993]: verbose: ControllerMpd::parseTrackInfo Dec 11 23:31:22 volumio volumio[993]: info: ControllerMpd::pushState Dec 11 23:31:22 volumio volumio[993]: info: CoreCommandRouter::servicePushState Dec 11 23:31:22 volumio volumio[993]: verbose: In UPNP mode Dec 11 23:31:22 volumio volumio[993]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"88.2 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sad Waters (Live at Alexandra Palace, 2020)","artist":"Nick Cave & The Bad Seeds","album":"Idiot Prayer (Nick Cave Alone at Alexandra Palace)","uri":"http://192.168.1.22:43047/ea0707ba-b141-44de-9ee1-cba894e5fd04.wav","trackType":"wav"} Dec 11 23:31:22 volumio volumio[993]: verbose: CURRENT POSITION 0 Dec 11 23:31:22 volumio volumio[993]: info: CoreStateMachine::syncState stateService play Dec 11 23:31:22 volumio volumio[993]: info: CoreStateMachine::syncState currentStatus play Dec 11 23:31:22 volumio volumio[993]: info: Received an update from plugin. extracting info from payload Dec 11 23:31:22 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 11 23:31:22 volumio volumio[993]: info: CoreStateMachine::pushState Dec 11 23:31:22 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 23:31:22 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Dec 11 23:31:22 volumio volumio[993]: info: MRS: Pushing multiroomSync output update for this device Dec 11 23:31:22 volumio volumio[993]: info: MRS: Pushing multiroomSync output Dec 11 23:31:22 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:31:22 volumio volumio[993]: info: CoreStateMachine::pushState Dec 11 23:31:22 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Dec 11 23:31:22 volumio volumio[993]: info: MRS: Pushing multiroomSync output update for this device Dec 11 23:31:22 volumio volumio[993]: info: MRS: Pushing multiroomSync output Dec 11 23:31:22 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:31:22 volumio volumio[993]: info: ------------------------------ 4ms Dec 11 23:31:22 volumio volumio[993]: info: CoreCommandRouter::volumioGetQueue Dec 11 23:31:22 volumio volumio[993]: info: CoreStateMachine::getQueue Dec 11 23:31:22 volumio volumio[993]: info: CorePlayQueue::getQueue Dec 11 23:31:22 volumio volumio[993]: info: CoreCommandRouter::volumioGetQueue Dec 11 23:31:22 volumio volumio[993]: info: CoreStateMachine::getQueue Dec 11 23:31:22 volumio volumio[993]: info: CorePlayQueue::getQueue Dec 11 23:31:22 volumio volumio[993]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Dec 11 23:31:22 volumio volumio[993]: SPOTIFY: SPOTIFY VOLUME 79 Dec 11 23:31:22 volumio volumio[993]: SPOTIFY: VOLUMIO VOLUME 80 Dec 11 23:31:22 volumio volumio[993]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 11 23:31:22 volumio volumio[993]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Dec 11 23:31:22 volumio volumio[993]: SPOTIFY: SPOTIFY VOLUME 79 Dec 11 23:31:22 volumio volumio[993]: SPOTIFY: VOLUMIO VOLUME 80 Dec 11 23:31:22 volumio volumio[993]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 11 23:31:23 volumio go-librespot[1791]: time="2025-12-11T23:31:23+01:00" level=trace msg="sent dealer ping" Dec 11 23:31:23 volumio go-librespot[1791]: time="2025-12-11T23:31:23+01:00" level=trace msg="received dealer pong" Dec 11 23:31:24 volumio volumio[993]: info: Dec 11 23:31:24 volumio volumio[993]: ---------------------------- MPD announces state update: player Dec 11 23:31:24 volumio volumio[993]: info: ControllerMpd::getState Dec 11 23:31:24 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand status Dec 11 23:31:24 volumio volumio[993]: info: sendMpdCommand status took 1 milliseconds Dec 11 23:31:24 volumio volumio[993]: verbose: ControllerMpd::parseState Dec 11 23:31:24 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 23:31:24 volumio volumio[993]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 11 23:31:24 volumio volumio[993]: verbose: ControllerMpd::parseTrackInfo Dec 11 23:31:24 volumio volumio[993]: info: ControllerMpd::pushState Dec 11 23:31:24 volumio volumio[993]: info: CoreCommandRouter::servicePushState Dec 11 23:31:24 volumio volumio[993]: info: CoreStateMachine::pushState Dec 11 23:31:24 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Dec 11 23:31:24 volumio volumio[993]: info: MRS: Pushing multiroomSync output update for this device Dec 11 23:31:24 volumio volumio[993]: info: MRS: Pushing multiroomSync output Dec 11 23:31:24 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:31:24 volumio volumio[993]: info: CorePlayQueue::getTrack 0 Dec 11 23:31:24 volumio volumio[993]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sad Waters (Live at Alexandra Palace, 2020)","artist":"Nick Cave & The Bad Seeds","album":"Idiot Prayer (Nick Cave Alone at Alexandra Palace)","uri":"http://192.168.1.22:43047/ea0707ba-b141-44de-9ee1-cba894e5fd04.wav","trackType":"wav"} Dec 11 23:31:24 volumio volumio[993]: verbose: CURRENT POSITION 0 Dec 11 23:31:24 volumio volumio[993]: info: CoreStateMachine::syncState stateService stop Dec 11 23:31:24 volumio volumio[993]: info: CoreStateMachine::syncState currentStatus play Dec 11 23:31:24 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Dec 11 23:31:24 volumio volumio[993]: info: MRS: Pushing multiroomSync output update for this device Dec 11 23:31:24 volumio volumio[993]: info: MRS: Pushing multiroomSync output Dec 11 23:31:24 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:31:24 volumio volumio[993]: info: CoreStateMachine::stPlaybackTimer Dec 11 23:31:24 volumio volumio[993]: info: ------------------------------ 3ms Dec 11 23:31:24 volumio volumio[993]: info: CoreCommandRouter::volumioGetQueue Dec 11 23:31:24 volumio volumio[993]: info: CoreStateMachine::getQueue Dec 11 23:31:24 volumio volumio[993]: info: CorePlayQueue::getQueue Dec 11 23:31:24 volumio volumio[993]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Dec 11 23:31:24 volumio volumio[993]: SPOTIFY: SPOTIFY VOLUME 79 Dec 11 23:31:24 volumio volumio[993]: SPOTIFY: VOLUMIO VOLUME 80 Dec 11 23:31:24 volumio volumio[993]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 11 23:31:24 volumio volumio[993]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Dec 11 23:31:24 volumio volumio[993]: SPOTIFY: SPOTIFY VOLUME 79 Dec 11 23:31:24 volumio volumio[993]: SPOTIFY: VOLUMIO VOLUME 80 Dec 11 23:31:24 volumio volumio[993]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 11 23:31:25 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 11 23:31:25 volumio volumio[993]: info: Received Get System Info Dec 11 23:31:25 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 23:31:25 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 23:31:25 volumio volumio[993]: info: Discovery: Getting this device information Dec 11 23:31:25 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:31:25 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 23:31:25 volumio volumio[993]: info: Clearing queue after UPNP request Dec 11 23:31:25 volumio volumio[993]: info: CoreStateMachine::ClearQueue Dec 11 23:31:25 volumio volumio[993]: info: CoreStateMachine::stop Dec 11 23:31:25 volumio volumio[993]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:31:25 volumio volumio[993]: info: CorePlayQueue::clearPlayQueue Dec 11 23:31:25 volumio volumio[993]: info: CorePlayQueue::saveQueue Dec 11 23:31:25 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Dec 11 23:31:25 volumio volumio[993]: info: MRS: Pushing multiroomSync output update for this device Dec 11 23:31:25 volumio volumio[993]: info: MRS: Pushing multiroomSync output Dec 11 23:31:25 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:31:25 volumio volumio[993]: info: CorePlayQueue::getTrack 0 Dec 11 23:31:25 volumio volumio[993]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:31:25 volumio volumio[993]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Dec 11 23:31:25 volumio volumio[993]: SPOTIFY: SPOTIFY VOLUME 79 Dec 11 23:31:25 volumio volumio[993]: SPOTIFY: VOLUMIO VOLUME 80 Dec 11 23:31:25 volumio volumio[993]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 11 23:31:25 volumio volumio[993]: info: Dec 11 23:31:25 volumio volumio[993]: ---------------------------- MPD announces system playlist update Dec 11 23:31:25 volumio volumio[993]: info: Ignoring MPD Status Update Dec 11 23:31:25 volumio volumio[993]: error: updateQueue error: null Dec 11 23:31:25 volumio volumio[993]: info: ------------------------------ 0ms Dec 11 23:31:25 volumio volumio[993]: info: Starting UPNP Playback Dec 11 23:31:25 volumio volumio[993]: info: Preparing playback through UPNP Dec 11 23:31:25 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:31:25 volumio volumio[993]: info: CorePlayQueue::getTrack 0 Dec 11 23:31:25 volumio volumio[993]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 11 23:31:26 volumio volumio[993]: info: Dec 11 23:31:26 volumio volumio[993]: ---------------------------- MPD announces system playlist update Dec 11 23:31:26 volumio volumio[993]: info: Ignoring MPD Status Update Dec 11 23:31:26 volumio volumio[993]: info: ------------------------------ 1ms Dec 11 23:31:26 volumio volumio[993]: info: Dec 11 23:31:26 volumio volumio[993]: ---------------------------- MPD announces system playlist update Dec 11 23:31:26 volumio volumio[993]: info: Ignoring MPD Status Update Dec 11 23:31:26 volumio volumio[993]: info: ------------------------------ 0ms Dec 11 23:31:26 volumio volumio[993]: info: Dec 11 23:31:26 volumio volumio[993]: ---------------------------- MPD announces system playlist update Dec 11 23:31:26 volumio volumio[993]: info: Ignoring MPD Status Update Dec 11 23:31:26 volumio volumio[993]: info: ------------------------------ 0ms Dec 11 23:31:26 volumio volumio[993]: info: Dec 11 23:31:26 volumio volumio[993]: ---------------------------- MPD announces system playlist update Dec 11 23:31:26 volumio volumio[993]: info: Ignoring MPD Status Update Dec 11 23:31:26 volumio volumio[993]: info: ------------------------------ 1ms Dec 11 23:31:26 volumio volumio[993]: info: Dec 11 23:31:26 volumio volumio[993]: ---------------------------- MPD announces system playlist update Dec 11 23:31:26 volumio volumio[993]: info: Ignoring MPD Status Update Dec 11 23:31:26 volumio volumio[993]: info: ------------------------------ 0ms Dec 11 23:31:26 volumio volumio[993]: info: Dec 11 23:31:26 volumio volumio[993]: ---------------------------- MPD announces system playlist update Dec 11 23:31:26 volumio volumio[993]: info: Ignoring MPD Status Update Dec 11 23:31:26 volumio volumio[993]: info: ------------------------------ 1ms Dec 11 23:31:28 volumio volumio[993]: info: Dec 11 23:31:28 volumio volumio[993]: ---------------------------- MPD announces state update: player Dec 11 23:31:28 volumio volumio[993]: info: ControllerMpd::getState Dec 11 23:31:28 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand status Dec 11 23:31:29 volumio volumio[993]: info: sendMpdCommand status took 421 milliseconds Dec 11 23:31:29 volumio volumio[993]: verbose: ControllerMpd::parseState Dec 11 23:31:29 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 23:31:29 volumio volumio[993]: info: Dec 11 23:31:29 volumio volumio[993]: ---------------------------- MPD announces state update: player Dec 11 23:31:29 volumio volumio[993]: info: ControllerMpd::getState Dec 11 23:31:29 volumio volumio[993]: verbose: ControllerMpd::sendMpdCommand status Dec 11 23:31:29 volumio volumio[993]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 11 23:31:29 volumio volumio[993]: verbose: ControllerMpd::parseTrackInfo Dec 11 23:31:29 volumio volumio[993]: info: ControllerMpd::pushState Dec 11 23:31:29 volumio volumio[993]: info: CoreCommandRouter::servicePushState Dec 11 23:31:29 volumio volumio[993]: verbose: In UPNP mode Dec 11 23:31:29 volumio volumio[993]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":314,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Long Dark Night (Live God)","artist":"Nick Cave & The Bad Seeds","album":"Live God","uri":"http://192.168.1.22:43047/35c0f466-980d-4117-80f4-854d6fd7fa3c.wav","trackType":"wav"} Dec 11 23:31:29 volumio volumio[993]: verbose: CURRENT POSITION 0 Dec 11 23:31:29 volumio volumio[993]: info: CoreStateMachine::syncState stateService play Dec 11 23:31:29 volumio volumio[993]: info: CoreStateMachine::syncState currentStatus stop Dec 11 23:31:29 volumio volumio[993]: info: ------------------------------ 423ms Dec 11 23:31:29 volumio volumio[993]: info: sendMpdCommand status took 0 milliseconds Dec 11 23:31:29 volumio volumio[993]: verbose: ControllerMpd::parseState Dec 11 23:31:29 volumio volumio[993]: info: ControllerMpd::pushState Dec 11 23:31:29 volumio volumio[993]: info: CoreCommandRouter::servicePushState Dec 11 23:31:29 volumio volumio[993]: info: CoreStateMachine::pushState Dec 11 23:31:29 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 23:31:29 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Dec 11 23:31:29 volumio volumio[993]: info: MRS: Pushing multiroomSync output update for this device Dec 11 23:31:29 volumio volumio[993]: info: MRS: Pushing multiroomSync output Dec 11 23:31:29 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:31:29 volumio volumio[993]: info: CorePlayQueue::getTrack 0 Dec 11 23:31:29 volumio volumio[993]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 11 23:31:29 volumio volumio[993]: verbose: CURRENT POSITION 0 Dec 11 23:31:29 volumio volumio[993]: info: CoreStateMachine::syncState stateService stop Dec 11 23:31:29 volumio volumio[993]: info: CoreStateMachine::syncState currentStatus play Dec 11 23:31:29 volumio volumio[993]: info: CoreCommandRouter::volumioPushState Dec 11 23:31:29 volumio volumio[993]: info: MRS: Pushing multiroomSync output update for this device Dec 11 23:31:29 volumio volumio[993]: info: MRS: Pushing multiroomSync output Dec 11 23:31:29 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:31:29 volumio volumio[993]: info: CoreStateMachine::stPlaybackTimer Dec 11 23:31:29 volumio volumio[993]: info: ------------------------------ 2ms Dec 11 23:31:29 volumio volumio[993]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Dec 11 23:31:29 volumio volumio[993]: SPOTIFY: SPOTIFY VOLUME 79 Dec 11 23:31:29 volumio volumio[993]: SPOTIFY: VOLUMIO VOLUME 80 Dec 11 23:31:29 volumio volumio[993]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 11 23:31:29 volumio volumio[993]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Dec 11 23:31:29 volumio volumio[993]: SPOTIFY: SPOTIFY VOLUME 79 Dec 11 23:31:29 volumio volumio[993]: SPOTIFY: VOLUMIO VOLUME 80 Dec 11 23:31:29 volumio volumio[993]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 11 23:31:35 volumio volumio[993]: verbose: New Socket.io Connection to 192.168.1.16:3000 from 192.168.1.22 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 23:31:35 volumio volumio[993]: info: Discovery: Getting this device information Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 23:31:35 volumio volumio[993]: info: Discovery: Getting this device information Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 23:31:35 volumio volumio[993]: info: Discovery: Getting this device information Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 11 23:31:35 volumio volumio[993]: verbose: New Socket.io Connection to 192.168.1.16:3000 from 192.168.1.22 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 11 23:31:35 volumio sudo[10113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 11 23:31:35 volumio sudo[10113]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 23:31:35 volumio sudo[10113]: pam_unix(sudo:session): session closed for user root Dec 11 23:31:35 volumio sudo[10119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 11 23:31:35 volumio sudo[10119]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 23:31:35 volumio sudo[10128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 11 23:31:35 volumio sudo[10128]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 23:31:35 volumio sudo[10119]: pam_unix(sudo:session): session closed for user root Dec 11 23:31:35 volumio sudo[10137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 11 23:31:35 volumio sudo[10137]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 23:31:35 volumio sudo[10128]: pam_unix(sudo:session): session closed for user root Dec 11 23:31:35 volumio sudo[10148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 11 23:31:35 volumio sudo[10148]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 23:31:35 volumio sudo[10148]: pam_unix(sudo:session): session closed for user root Dec 11 23:31:35 volumio sudo[10137]: pam_unix(sudo:session): session closed for user root Dec 11 23:31:35 volumio sudo[10154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 11 23:31:35 volumio sudo[10154]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 23:31:35 volumio sudo[10154]: pam_unix(sudo:session): session closed for user root Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 23:31:35 volumio volumio[993]: info: Discovery: Getting this device information Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 23:31:35 volumio volumio[993]: info: Discovery: Getting this device information Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:31:35 volumio volumio[993]: verbose: New Socket.io Connection to 192.168.1.16:3000 from 192.168.1.22 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 11 23:31:35 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 11 23:31:36 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 11 23:31:36 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 11 23:31:38 volumio sudo[10159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 11 23:31:38 volumio sudo[10159]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 23:31:38 volumio sudo[10159]: pam_unix(sudo:session): session closed for user root Dec 11 23:31:38 volumio sudo[10162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 11 23:31:38 volumio sudo[10162]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 23:31:38 volumio sudo[10162]: pam_unix(sudo:session): session closed for user root Dec 11 23:31:38 volumio volumio[993]: verbose: New Socket.io Connection to 192.168.1.16 from 192.168.1.22 UA: Mozilla/5.0 (Linux; Android 10; CLT-L29 Build/HUAWEICLT-L29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.171 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 11 23:31:38 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 11 23:31:38 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 11 23:31:38 volumio sudo[10167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 11 23:31:38 volumio sudo[10167]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 23:31:38 volumio sudo[10167]: pam_unix(sudo:session): session closed for user root Dec 11 23:31:38 volumio sudo[10170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 11 23:31:38 volumio sudo[10170]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 23:31:38 volumio sudo[10170]: pam_unix(sudo:session): session closed for user root Dec 11 23:31:38 volumio volumio[993]: verbose: New Socket.io Connection to 192.168.1.16 from 192.168.1.22 UA: Mozilla/5.0 (Linux; Android 10; CLT-L29 Build/HUAWEICLT-L29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.171 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 11 23:31:39 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 11 23:31:39 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 11 23:31:39 volumio volumio[993]: info: CoreCommandRouter::volumioGetVisibleSources Dec 11 23:31:39 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 23:31:39 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:31:39 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 11 23:31:39 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 11 23:31:39 volumio volumio[993]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 11 23:31:39 volumio volumio[993]: info: Received Get System Info Dec 11 23:31:39 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 23:31:39 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 23:31:39 volumio volumio[993]: info: Discovery: Getting this device information Dec 11 23:31:39 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:31:39 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 23:31:39 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:31:39 volumio volumio[993]: info: Listing playlists Dec 11 23:31:39 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 11 23:31:39 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 11 23:31:40 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 11 23:31:40 volumio volumio[993]: info: Received Get System Info Dec 11 23:31:40 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 23:31:40 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 23:31:40 volumio volumio[993]: info: Discovery: Getting this device information Dec 11 23:31:40 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:31:40 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 23:31:40 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 11 23:31:42 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 11 23:31:42 volumio volumio[993]: info: Received Get System Info Dec 11 23:31:42 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 23:31:42 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 23:31:42 volumio volumio[993]: info: Discovery: Getting this device information Dec 11 23:31:42 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Dec 11 23:31:42 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 23:31:46 volumio volumio[993]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 23:31:46 volumio volumio[993]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 23:31:46 volumio volumio[993]: info: Retrieving Cloud Streaming UI Dec 11 23:31:46 volumio volumio[993]: info: Getting Tidal Cloud Configuration Dec 11 23:31:46 volumio volumio[993]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 23:31:46 volumio volumio[993]: info: Getting Qobuz Cloud Configuration Dec 11 23:31:46 volumio volumio[993]: info: Asking plugin for UI Config Dec 11 23:31:46 volumio volumio[993]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 23:31:46 volumio volumio[993]: info: Getting Spotify Cloud Configuration Dec 11 23:31:46 volumio volumio[993]: info: Asking plugin for UI Config Dec 11 23:31:46 volumio volumio[993]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 23:31:46 volumio volumio[993]: info: Saving Spotify Acccount Dec 11 23:31:46 volumio volumio[993]: info: Got it Dec 11 23:31:46 volumio volumio[993]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 11 23:31:46 volumio volumio[993]: info: Got Tidal Cloud Configuration Dec 11 23:31:46 volumio volumio[993]: info: Got it Dec 11 23:31:46 volumio volumio[993]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 23:31:46 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 11 23:31:46 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 11 23:31:46 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 11 23:31:46 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 11 23:31:46 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 23:31:46 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 23:31:46 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 23:31:46 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 23:31:46 volumio volumio[993]: info: CoreCommandRouter::volumioGetBrowseSources Dec 11 23:31:46 volumio volumio[993]: info: CoreCommandRouter::volumioGetBrowseSources Dec 11 23:31:46 volumio volumio[993]: info: CoreCommandRouter::volumioGetBrowseSources Dec 11 23:31:46 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 11 23:31:47 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 11 23:31:48 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 11 23:31:50 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 11 23:31:51 volumio volumio[993]: info: Disabling MyMusic plugin upnp Dec 11 23:31:51 volumio sudo[10204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Dec 11 23:31:51 volumio sudo[10204]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 23:31:51 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Dec 11 23:31:51 volumio volumio[993]: error: Upnp client error: Error: This socket has been ended by the other party Dec 11 23:31:51 volumio volumio[993]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 11 23:31:52 volumio volumio[993]: info: Enabling MyMusic plugin upnp Dec 11 23:31:52 volumio volumio[993]: info: Enabling plugin upnp Dec 11 23:31:52 volumio volumio[993]: info: Loading plugin "upnp"... Dec 11 23:31:52 volumio volumio[993]: info: [1765492312179] Starting Upmpd Daemon Dec 11 23:31:52 volumio volumio[993]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 11 23:31:52 volumio volumio[993]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 23:31:52 volumio volumio[993]: Error: listen EADDRINUSE: address already in use :::6599 Dec 11 23:31:52 volumio volumio[993]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Dec 11 23:31:52 volumio volumio[993]: at listenInCluster (net.js:1379:12) Dec 11 23:31:52 volumio volumio[993]: at Server.listen (net.js:1465:7) Dec 11 23:31:52 volumio volumio[993]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Dec 11 23:31:52 volumio volumio[993]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Dec 11 23:31:52 volumio volumio[993]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Dec 11 23:31:52 volumio volumio[993]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 11 23:31:52 volumio volumio[993]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Dec 11 23:31:52 volumio volumio[993]: code: 'EADDRINUSE', Dec 11 23:31:52 volumio volumio[993]: errno: -98, Dec 11 23:31:52 volumio volumio[993]: syscall: 'listen', Dec 11 23:31:52 volumio volumio[993]: address: '::', Dec 11 23:31:52 volumio volumio[993]: port: 6599 Dec 11 23:31:52 volumio volumio[993]: } Dec 11 23:31:52 volumio volumio[993]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 23:31:52 volumio sudo[10215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-11 23:30 Dec 11 23:31:52 volumio sudo[10215]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 07:31:13 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="674d725e3f749f2a23785c9f94a28aa3"