-- Logs begin at Sat 2025-12-13 03:06:53 GMT, end at Thu 2025-12-18 12:52:32 GMT. -- Dec 18 12:51:10 rivoplus volumio[3122]: info: Dec 18 12:51:10 rivoplus volumio[3122]: ---------------------------- MPD announces system playlist update Dec 18 12:51:10 rivoplus volumio[3122]: info: Ignoring MPD Status Update Dec 18 12:51:10 rivoplus volumio[3122]: info: Dec 18 12:51:10 rivoplus volumio[3122]: ---------------------------- MPD announces state update: player Dec 18 12:51:10 rivoplus volumio[3122]: info: ControllerMpd::getState Dec 18 12:51:10 rivoplus volumio[3122]: verbose: ControllerMpd::sendMpdCommand status Dec 18 12:51:10 rivoplus volumio[3122]: info: Dec 18 12:51:10 rivoplus volumio[3122]: ---------------------------- MPD announces system playlist update Dec 18 12:51:10 rivoplus volumio[3122]: info: Ignoring MPD Status Update Dec 18 12:51:10 rivoplus volumio[3122]: info: Dec 18 12:51:10 rivoplus volumio[3122]: ---------------------------- MPD announces state update: player Dec 18 12:51:10 rivoplus volumio[3122]: info: ControllerMpd::getState Dec 18 12:51:10 rivoplus volumio[3122]: verbose: ControllerMpd::sendMpdCommand status Dec 18 12:51:10 rivoplus volumio[3122]: info: ------------------------------ 8ms Dec 18 12:51:10 rivoplus volumio[3122]: info: sendMpdCommand status took 6 milliseconds Dec 18 12:51:10 rivoplus volumio[3122]: info: ------------------------------ 6ms Dec 18 12:51:10 rivoplus volumio[3122]: info: sendMpdCommand status took 4 milliseconds Dec 18 12:51:10 rivoplus volumio[3122]: verbose: ControllerMpd::parseState Dec 18 12:51:10 rivoplus volumio[3122]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 12:51:10 rivoplus volumio[3122]: verbose: ControllerMpd::parseState Dec 18 12:51:10 rivoplus volumio[3122]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 12:51:10 rivoplus volumio[3122]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 18 12:51:10 rivoplus volumio[3122]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 18 12:51:10 rivoplus volumio[3122]: verbose: ControllerMpd::parseTrackInfo Dec 18 12:51:10 rivoplus volumio[3122]: verbose: ControllerMpd::parseTrackInfo Dec 18 12:51:10 rivoplus volumio[3122]: info: ControllerMpd::pushState Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreCommandRouter::servicePushState Dec 18 12:51:10 rivoplus volumio[3122]: verbose: In UPNP mode Dec 18 12:51:10 rivoplus volumio[3122]: verbose: STATE SERVICE {"status":"play","position":2,"seek":91,"duration":211,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"573 Kbps","isStreaming":false,"title":"Cooling Towers","artist":"The Dreaming Spires","album":"Normal Town","uri":"http://192.168.50.200:26125/content/c2/b16/f44100/d3278062616606424809-coB22C0524A3C12D0B.flac","trackType":"flac"} Dec 18 12:51:10 rivoplus volumio[3122]: verbose: CURRENT POSITION 0 Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreStateMachine::syncState stateService play Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreStateMachine::syncState currentStatus play Dec 18 12:51:10 rivoplus volumio[3122]: info: Received an update from plugin. extracting info from payload Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreStateMachine::pushState Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreCommandRouter::volumioPushState Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetState Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreStateMachine::pushState Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreCommandRouter::volumioPushState Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetState Dec 18 12:51:10 rivoplus volumio[3122]: info: ControllerMpd::pushState Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreCommandRouter::servicePushState Dec 18 12:51:10 rivoplus volumio[3122]: verbose: In UPNP mode Dec 18 12:51:10 rivoplus volumio[3122]: verbose: STATE SERVICE {"status":"play","position":2,"seek":91,"duration":211,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"573 Kbps","isStreaming":false,"title":"Cooling Towers","artist":"The Dreaming Spires","album":"Normal Town","uri":"http://192.168.50.200:26125/content/c2/b16/f44100/d3278062616606424809-coB22C0524A3C12D0B.flac","trackType":"flac"} Dec 18 12:51:10 rivoplus volumio[3122]: verbose: CURRENT POSITION 0 Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreStateMachine::syncState stateService play Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreStateMachine::syncState currentStatus play Dec 18 12:51:10 rivoplus volumio[3122]: info: Received an update from plugin. extracting info from payload Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreStateMachine::pushState Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreCommandRouter::volumioPushState Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetState Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreStateMachine::pushState Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreCommandRouter::volumioPushState Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetState Dec 18 12:51:10 rivoplus volumio[3122]: info: ------------------------------ 93ms Dec 18 12:51:10 rivoplus volumio[3122]: info: ------------------------------ 91ms Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetQueue Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreStateMachine::getQueue Dec 18 12:51:10 rivoplus volumio[3122]: info: CorePlayQueue::getQueue Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetQueue Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreStateMachine::getQueue Dec 18 12:51:10 rivoplus volumio[3122]: info: CorePlayQueue::getQueue Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetQueue Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreStateMachine::getQueue Dec 18 12:51:10 rivoplus volumio[3122]: info: CorePlayQueue::getQueue Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetQueue Dec 18 12:51:10 rivoplus volumio[3122]: info: CoreStateMachine::getQueue Dec 18 12:51:10 rivoplus volumio[3122]: info: CorePlayQueue::getQueue Dec 18 12:51:12 rivoplus volumio[3122]: info: Starting UPNP Playback Dec 18 12:51:12 rivoplus volumio[3122]: info: Preparing playback through UPNP Dec 18 12:51:12 rivoplus volumio[3122]: info: Consume mode Dec 18 12:51:12 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetState Dec 18 12:51:12 rivoplus volumio[3122]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 12:51:12 rivoplus volumio[3122]: info: Dec 18 12:51:12 rivoplus volumio[3122]: ---------------------------- MPD announces system playlist update Dec 18 12:51:12 rivoplus volumio[3122]: info: Ignoring MPD Status Update Dec 18 12:51:12 rivoplus volumio[3122]: info: Dec 18 12:51:12 rivoplus volumio[3122]: ---------------------------- MPD announces system playlist update Dec 18 12:51:12 rivoplus volumio[3122]: info: Ignoring MPD Status Update Dec 18 12:51:12 rivoplus volumio[3122]: info: ------------------------------ 11ms Dec 18 12:51:12 rivoplus volumio[3122]: info: Dec 18 12:51:12 rivoplus volumio[3122]: ---------------------------- MPD announces system playlist update Dec 18 12:51:12 rivoplus volumio[3122]: info: Ignoring MPD Status Update Dec 18 12:51:12 rivoplus volumio[3122]: info: ------------------------------ 13ms Dec 18 12:51:12 rivoplus volumio[3122]: info: Dec 18 12:51:12 rivoplus volumio[3122]: ---------------------------- MPD announces system playlist update Dec 18 12:51:12 rivoplus volumio[3122]: info: Ignoring MPD Status Update Dec 18 12:51:12 rivoplus volumio[3122]: info: Dec 18 12:51:12 rivoplus volumio[3122]: ---------------------------- MPD announces system playlist update Dec 18 12:51:12 rivoplus volumio[3122]: info: Ignoring MPD Status Update Dec 18 12:51:12 rivoplus volumio[3122]: info: ------------------------------ 11ms Dec 18 12:51:12 rivoplus volumio[3122]: info: ------------------------------ 6ms Dec 18 12:51:12 rivoplus volumio[3122]: info: Dec 18 12:51:12 rivoplus volumio[3122]: ---------------------------- MPD announces system playlist update Dec 18 12:51:12 rivoplus volumio[3122]: info: Ignoring MPD Status Update Dec 18 12:51:12 rivoplus volumio[3122]: info: ------------------------------ 5ms Dec 18 12:51:12 rivoplus volumio[3122]: info: ------------------------------ 4ms Dec 18 12:51:12 rivoplus volumio[3122]: info: Dec 18 12:51:12 rivoplus volumio[3122]: ---------------------------- MPD announces system playlist update Dec 18 12:51:12 rivoplus volumio[3122]: info: Ignoring MPD Status Update Dec 18 12:51:12 rivoplus volumio[3122]: info: ------------------------------ 3ms Dec 18 12:51:12 rivoplus volumio[3122]: info: Dec 18 12:51:12 rivoplus volumio[3122]: ---------------------------- MPD announces system playlist update Dec 18 12:51:12 rivoplus volumio[3122]: info: Ignoring MPD Status Update Dec 18 12:51:12 rivoplus volumio[3122]: info: Dec 18 12:51:12 rivoplus volumio[3122]: ---------------------------- MPD announces system playlist update Dec 18 12:51:12 rivoplus volumio[3122]: info: Ignoring MPD Status Update Dec 18 12:51:12 rivoplus volumio[3122]: info: ------------------------------ 4ms Dec 18 12:51:12 rivoplus volumio[3122]: info: Dec 18 12:51:12 rivoplus volumio[3122]: ---------------------------- MPD announces system playlist update Dec 18 12:51:12 rivoplus volumio[3122]: info: Ignoring MPD Status Update Dec 18 12:51:12 rivoplus volumio[3122]: info: ------------------------------ 8ms Dec 18 12:51:12 rivoplus volumio[3122]: info: ------------------------------ 7ms Dec 18 12:51:12 rivoplus volumio[3122]: info: Dec 18 12:51:12 rivoplus volumio[3122]: ---------------------------- MPD announces system playlist update Dec 18 12:51:12 rivoplus volumio[3122]: info: Ignoring MPD Status Update Dec 18 12:51:12 rivoplus volumio[3122]: info: Dec 18 12:51:12 rivoplus volumio[3122]: ---------------------------- MPD announces system playlist update Dec 18 12:51:12 rivoplus volumio[3122]: info: Ignoring MPD Status Update Dec 18 12:51:12 rivoplus volumio[3122]: info: ------------------------------ 12ms Dec 18 12:51:12 rivoplus volumio[3122]: info: ------------------------------ 3ms Dec 18 12:51:14 rivoplus volumio[3122]: info: Dec 18 12:51:14 rivoplus volumio[3122]: ---------------------------- MPD announces state update: player Dec 18 12:51:14 rivoplus volumio[3122]: info: ControllerMpd::getState Dec 18 12:51:14 rivoplus volumio[3122]: verbose: ControllerMpd::sendMpdCommand status Dec 18 12:51:14 rivoplus volumio[3122]: info: Dec 18 12:51:14 rivoplus volumio[3122]: ---------------------------- MPD announces state update: player Dec 18 12:51:14 rivoplus volumio[3122]: info: ControllerMpd::getState Dec 18 12:51:14 rivoplus volumio[3122]: verbose: ControllerMpd::sendMpdCommand status Dec 18 12:51:14 rivoplus volumio[3122]: info: sendMpdCommand status took 3 milliseconds Dec 18 12:51:14 rivoplus volumio[3122]: verbose: ControllerMpd::parseState Dec 18 12:51:14 rivoplus volumio[3122]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 12:51:14 rivoplus volumio[3122]: info: sendMpdCommand status took 4 milliseconds Dec 18 12:51:14 rivoplus volumio[3122]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 18 12:51:14 rivoplus volumio[3122]: verbose: ControllerMpd::parseState Dec 18 12:51:14 rivoplus volumio[3122]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 12:51:14 rivoplus volumio[3122]: verbose: ControllerMpd::parseTrackInfo Dec 18 12:51:14 rivoplus volumio[3122]: info: ControllerMpd::pushState Dec 18 12:51:14 rivoplus volumio[3122]: info: CoreCommandRouter::servicePushState Dec 18 12:51:14 rivoplus volumio[3122]: verbose: In UPNP mode Dec 18 12:51:14 rivoplus volumio[3122]: verbose: STATE SERVICE {"status":"pause","position":2,"seek":3843,"duration":211,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"975 Kbps","isStreaming":false,"title":"Cooling Towers","artist":"The Dreaming Spires","album":"Normal Town","uri":"http://192.168.50.200:26125/content/c2/b16/f44100/d3278062616606424809-coB22C0524A3C12D0B.flac","trackType":"flac"} Dec 18 12:51:14 rivoplus volumio[3122]: verbose: CURRENT POSITION 0 Dec 18 12:51:14 rivoplus volumio[3122]: info: CoreStateMachine::syncState stateService pause Dec 18 12:51:14 rivoplus volumio[3122]: info: CoreStateMachine::syncState currentStatus play Dec 18 12:51:14 rivoplus volumio[3122]: info: CoreStateMachine::pushState Dec 18 12:51:14 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 12:51:14 rivoplus volumio[3122]: info: CoreCommandRouter::volumioPushState Dec 18 12:51:14 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetState Dec 18 12:51:14 rivoplus volumio[3122]: info: CoreStateMachine::stPlaybackTimer Dec 18 12:51:14 rivoplus volumio[3122]: info: ------------------------------ 33ms Dec 18 12:51:14 rivoplus volumio[3122]: info: sendMpdCommand playlistinfo took 25 milliseconds Dec 18 12:51:14 rivoplus volumio[3122]: verbose: ControllerMpd::parseTrackInfo Dec 18 12:51:14 rivoplus volumio[3122]: info: ControllerMpd::pushState Dec 18 12:51:14 rivoplus volumio[3122]: info: CoreCommandRouter::servicePushState Dec 18 12:51:14 rivoplus volumio[3122]: verbose: In UPNP mode Dec 18 12:51:14 rivoplus volumio[3122]: verbose: STATE SERVICE {"status":"pause","position":2,"seek":3843,"duration":211,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"975 Kbps","isStreaming":false,"title":"Cooling Towers","artist":"The Dreaming Spires","album":"Normal Town","uri":"http://192.168.50.200:26125/content/c2/b16/f44100/d3278062616606424809-coB22C0524A3C12D0B.flac","trackType":"flac"} Dec 18 12:51:14 rivoplus volumio[3122]: verbose: CURRENT POSITION 0 Dec 18 12:51:14 rivoplus volumio[3122]: info: CoreStateMachine::syncState stateService pause Dec 18 12:51:14 rivoplus volumio[3122]: info: CoreStateMachine::syncState currentStatus play Dec 18 12:51:14 rivoplus volumio[3122]: info: CoreStateMachine::pushState Dec 18 12:51:14 rivoplus volumio[3122]: info: CoreCommandRouter::volumioPushState Dec 18 12:51:14 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetState Dec 18 12:51:14 rivoplus volumio[3122]: info: CoreStateMachine::stPlaybackTimer Dec 18 12:51:14 rivoplus volumio[3122]: info: ------------------------------ 69ms Dec 18 12:51:14 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetQueue Dec 18 12:51:14 rivoplus volumio[3122]: info: CoreStateMachine::getQueue Dec 18 12:51:14 rivoplus volumio[3122]: info: CorePlayQueue::getQueue Dec 18 12:51:14 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetQueue Dec 18 12:51:14 rivoplus volumio[3122]: info: CoreStateMachine::getQueue Dec 18 12:51:14 rivoplus volumio[3122]: info: CorePlayQueue::getQueue Dec 18 12:51:14 rivoplus volumio[3122]: info: MCU Signalled Playback Inactive Dec 18 12:51:15 rivoplus volumio[3122]: info: camilladsp spawned new process with pid 10720, instance 1, run: true Dec 18 12:51:42 rivoplus volumio[3122]: verbose: New Socket.io Connection to 192.168.50.52 from 192.168.50.5 UA: Mozilla/5.0 (Linux; Android 14; SM-A526B Build/UP1A.231005.007; 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 18 12:51:42 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 18 12:51:42 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetVisibleSources Dec 18 12:51:42 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 12:51:42 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetState Dec 18 12:51:42 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 18 12:51:42 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 18 12:51:42 rivoplus volumio[3122]: info: Received Get System Info Dec 18 12:51:42 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 18 12:51:42 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 18 12:51:42 rivoplus volumio[3122]: info: Discovery: Getting this device information Dec 18 12:51:42 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetState Dec 18 12:51:42 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 18 12:51:42 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetState Dec 18 12:51:42 rivoplus volumio[3122]: info: Listing playlists Dec 18 12:51:42 rivoplus volumio[3122]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 12:51:42 rivoplus volumio[3122]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 12:51:42 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 18 12:51:45 rivoplus nmbd[3082]: [2025/12/18 12:51:45.065334, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 18 12:51:45 rivoplus nmbd[3082]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.50.52 for name WORKGROUP<1d>. Dec 18 12:51:45 rivoplus nmbd[3082]: This response was from IP 192.168.50.253, reporting an IP address of 192.168.50.253. Dec 18 12:51:48 rivoplus volumio[3122]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 12:51:48 rivoplus volumio[3122]: No protocol specified Dec 18 12:51:48 rivoplus volumio[3122]: xcb_connection_has_error() returned true Dec 18 12:51:48 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 18 12:51:48 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 12:51:48 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 18 12:51:48 rivoplus volumio[3122]: No protocol specified Dec 18 12:51:48 rivoplus volumio[3122]: xcb_connection_has_error() returned true Dec 18 12:51:48 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 12:51:48 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 12:51:48 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 12:51:48 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 12:51:48 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 12:51:48 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 12:51:48 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 12:51:48 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 18 12:51:48 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 18 12:51:48 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Dec 18 12:51:51 rivoplus volumio[3122]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 18 12:51:51 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 18 12:51:51 rivoplus volumio[3122]: info: Preparing to save Alsa Options, stopping services first Dec 18 12:51:51 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetState Dec 18 12:51:51 rivoplus volumio[3122]: info: CoreCommandRouter::volumioPause Dec 18 12:51:51 rivoplus volumio[3122]: info: CoreStateMachine::pause Dec 18 12:51:51 rivoplus volumio[3122]: info: CoreStateMachine::stPlaybackTimer Dec 18 12:51:51 rivoplus volumio[3122]: info: CoreStateMachine::servicePause Dec 18 12:51:51 rivoplus volumio[3122]: info: CorePlayQueue::getTrack 0 Dec 18 12:51:51 rivoplus volumio[3122]: info: Error: no service or no trackblock to pause Dec 18 12:51:51 rivoplus volumio[3122]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"S/PDIF + AES/EBU + I2S"}} Dec 18 12:51:51 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 18 12:51:51 rivoplus volumio[3122]: No protocol specified Dec 18 12:51:51 rivoplus volumio[3122]: xcb_connection_has_error() returned true Dec 18 12:51:51 rivoplus volumio[3122]: No protocol specified Dec 18 12:51:51 rivoplus volumio[3122]: xcb_connection_has_error() returned true Dec 18 12:51:51 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 18 12:51:51 rivoplus volumio[3122]: No protocol specified Dec 18 12:51:51 rivoplus volumio[3122]: xcb_connection_has_error() returned true Dec 18 12:51:51 rivoplus volumio[3122]: info: Setting mixer PCM for card S/PDIF + AES/EBU + I2S Dec 18 12:51:51 rivoplus volumio[3122]: info: QobuzConnect: setDeactiveState invoked Dec 18 12:51:51 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetState Dec 18 12:51:51 rivoplus vtcs[4189]: [2025-12-18 12:51:51.830] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 18 12:51:51 rivoplus vtcs[4189]: [2025-12-18 12:51:51.836] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Dec 18 12:51:51 rivoplus vtcs[4189]: [2025-12-18 12:51:51.836] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 18 12:51:51 rivoplus sudo[10833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 18 12:51:51 rivoplus sudo[10833]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:51:51 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Dec 18 12:51:51 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Dec 18 12:51:51 rivoplus volumio[3122]: No protocol specified Dec 18 12:51:51 rivoplus volumio[3122]: xcb_connection_has_error() returned true Dec 18 12:51:51 rivoplus volumio[3122]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 18 12:51:51 rivoplus volumio[3122]: info: Updating Volume Controller Parameters: Device: 1 Name: S/PDIF + AES/EBU + I2S Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 18 12:51:51 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 18 12:51:51 rivoplus volumio[3122]: info: Disabling external Volume Control Dec 18 12:51:51 rivoplus systemd[1]: vtcs.service: Succeeded. Dec 18 12:51:51 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Dec 18 12:51:51 rivoplus sudo[10833]: pam_unix(sudo:session): session closed for user root Dec 18 12:51:52 rivoplus volumio[3122]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 12:51:52 rivoplus volumio[3122]: info: Preparing to generate the ALSA configuration file Dec 18 12:51:52 rivoplus sudo[10871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 18 12:51:52 rivoplus sudo[10871]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:51:52 rivoplus sudo[10871]: pam_unix(sudo:session): session closed for user root Dec 18 12:51:52 rivoplus volumio[3122]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 18 12:51:52 rivoplus sudo[10878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 18 12:51:52 rivoplus sudo[10878]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:51:52 rivoplus volumio[3122]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 18 12:51:52 rivoplus volumio[3122]: info: Reading ALSA contributions from plugins. Dec 18 12:51:52 rivoplus volumio[3122]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Dec 18 12:51:52 rivoplus volumio[3122]: xcb_connection_has_error() returned true Dec 18 12:51:52 rivoplus sudo[10878]: pam_unix(sudo:session): session closed for user root Dec 18 12:51:52 rivoplus sudo[10881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 18 12:51:52 rivoplus sudo[10881]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:51:52 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Dec 18 12:51:52 rivoplus qobuz-connect[4150]: 20251218 12:51:52.290 [4150.4150] INFO SampleApp: Stopping Local configuration server Dec 18 12:51:52 rivoplus qobuz-connect[4150]: 20251218 12:51:52.300 [4150.4150] INFO SampleApp: shat down connection on UNIX socket Dec 18 12:51:52 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Dec 18 12:51:52 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Dec 18 12:51:52 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Dec 18 12:51:52 rivoplus sudo[10881]: pam_unix(sudo:session): session closed for user root Dec 18 12:51:52 rivoplus volumio[3122]: No protocol specified Dec 18 12:51:52 rivoplus volumio[3122]: xcb_connection_has_error() returned true Dec 18 12:51:52 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 18 12:51:52 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 12:51:52 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 18 12:51:52 rivoplus volumio[3122]: No protocol specified Dec 18 12:51:52 rivoplus volumio[3122]: xcb_connection_has_error() returned true Dec 18 12:51:52 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 12:51:52 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 18 12:51:52 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 18 12:51:52 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Dec 18 12:51:52 rivoplus volumio[3122]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 18 12:51:52 rivoplus volumio[3122]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 18 12:51:52 rivoplus volumio[3122]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 18 12:51:52 rivoplus volumio[3122]: info: QobuzConnect: setDeactiveState invoked Dec 18 12:51:52 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetState Dec 18 12:51:52 rivoplus volumio[3122]: info: VolumeController:: Volume=undefined Mute =false Dec 18 12:51:52 rivoplus volumio[3122]: info: CoreStateMachine::pushState Dec 18 12:51:52 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 12:51:52 rivoplus volumio[3122]: info: CoreCommandRouter::volumioPushState Dec 18 12:51:52 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetState Dec 18 12:51:52 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetQueue Dec 18 12:51:52 rivoplus volumio[3122]: info: CoreStateMachine::getQueue Dec 18 12:51:52 rivoplus volumio[3122]: info: CorePlayQueue::getQueue Dec 18 12:51:52 rivoplus volumio[3122]: info: Executing endpoint qc_getconfig Dec 18 12:51:52 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 18 12:51:52 rivoplus qobuz-connect[10885]: 20251218 12:51:52.570 [10885.10885] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 18 12:51:52 rivoplus qobuz-connect[10885]: 20251218 12:51:52.577 [10885.10885] INFO VolumeManager: [0xac065b60]: Setting new playback volume: 75 Dec 18 12:51:52 rivoplus qobuz-connect[10885]: 20251218 12:51:52.578 [10885.10885] INFO VolumeManager: [0xac065b60]: Setting new mute state: 0 Dec 18 12:51:52 rivoplus qobuz-connect[10885]: 20251218 12:51:52.578 [10885.10885] INFO QobuzConnect: [0xac066530]: Client initialized! Dec 18 12:51:52 rivoplus qobuz-connect[10885]: 20251218 12:51:52.578 [10885.10885] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Dec 18 12:51:52 rivoplus volumio[3122]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 18 12:51:52 rivoplus volumio[3122]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 18 12:51:52 rivoplus qobuz-connect[10885]: 20251218 12:51:52.593 [10885.10885] INFO LocalConfigManager: [0xac065440]: Starting Local Configuration server Dec 18 12:51:52 rivoplus qobuz-connect[10885]: 20251218 12:51:52.593 [10885.10885] INFO SampleApp: Starting Local configuration server Dec 18 12:51:52 rivoplus qobuz-connect[10885]: 20251218 12:51:52.594 [10885.10885] INFO SampleApp: Connected to UNIX socket client 0xac05aed8 Dec 18 12:51:52 rivoplus volumio[3122]: info: Asound.conf file written Dec 18 12:51:52 rivoplus sudo[10904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 18 12:51:52 rivoplus sudo[10904]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:51:52 rivoplus sudo[10904]: pam_unix(sudo:session): session closed for user root Dec 18 12:51:52 rivoplus qobuz-connect[10885]: 20251218 12:51:52.720 [10885.10885] INFO SampleApp: Playback volume changed: 75 Dec 18 12:51:52 rivoplus kernel: Fine tdm clk setting range (0~2000000), 12287994 Dec 18 12:51:52 rivoplus kernel: Err: can not set rate to 4278967296! Dec 18 12:51:52 rivoplus kernel: Range[3920000 - 500000000] Dec 18 12:51:52 rivoplus kernel: out of value, fixed it Dec 18 12:51:52 rivoplus kernel: id=0 set inskew=0 Dec 18 12:51:52 rivoplus volumio[3122]: No protocol specified Dec 18 12:51:52 rivoplus volumio[3122]: xcb_connection_has_error() returned true Dec 18 12:51:52 rivoplus volumio[3122]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Dec 18 12:51:52 rivoplus volumio[3122]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Dec 18 12:51:52 rivoplus volumio[3122]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Dec 18 12:51:52 rivoplus volumio[3122]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Dec 18 12:51:52 rivoplus volumio[3122]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Dec 18 12:51:52 rivoplus volumio[3122]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Clock Source 1 Validity:0' : Operation not permitted Dec 18 12:51:52 rivoplus volumio[3122]: info: Output device has changed, restarting MPD Dec 18 12:51:52 rivoplus sudo[10909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 18 12:51:52 rivoplus sudo[10909]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:51:52 rivoplus volumio[3122]: info: Output device has changed, restarting Shairport Sync Dec 18 12:51:52 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 12:51:52 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 12:51:52 rivoplus sudo[10909]: pam_unix(sudo:session): session closed for user root Dec 18 12:51:52 rivoplus sudo[10911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 18 12:51:52 rivoplus sudo[10911]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:51:52 rivoplus systemd[1]: Stopping Music Player Daemon... Dec 18 12:51:52 rivoplus systemd[1]: mpd.service: Succeeded. Dec 18 12:51:52 rivoplus volumio[3122]: No protocol specified Dec 18 12:51:52 rivoplus volumio[3122]: xcb_connection_has_error() returned true Dec 18 12:51:52 rivoplus volumio[3122]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 18 12:51:52 rivoplus volumio[3122]: No protocol specified Dec 18 12:51:52 rivoplus volumio[3122]: xcb_connection_has_error() returned true Dec 18 12:51:52 rivoplus volumio[3122]: info: QobuzConnect: setDeactiveState invoked Dec 18 12:51:52 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetState Dec 18 12:51:52 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 12:51:52 rivoplus systemd[1]: Stopped Music Player Daemon. Dec 18 12:51:52 rivoplus systemd[1]: Starting Music Player Daemon... Dec 18 12:51:52 rivoplus volumio[3122]: info: Output device has changed, restarting MPD Dec 18 12:51:52 rivoplus sudo[10920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 18 12:51:52 rivoplus sudo[10920]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:51:53 rivoplus sudo[10923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 18 12:51:53 rivoplus sudo[10923]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:51:53 rivoplus sudo[10920]: pam_unix(sudo:session): session closed for user root Dec 18 12:51:53 rivoplus sudo[10923]: pam_unix(sudo:session): session closed for user root Dec 18 12:51:53 rivoplus volumio[3122]: info: Output device has changed, restarting Shairport Sync Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 12:51:53 rivoplus sudo[10927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 18 12:51:53 rivoplus sudo[10927]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:51:53 rivoplus sudo[10918]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 18 12:51:53 rivoplus sudo[10918]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:51:53 rivoplus sudo[10918]: pam_unix(sudo:session): session closed for user root Dec 18 12:51:53 rivoplus volumio[3122]: No protocol specified Dec 18 12:51:53 rivoplus volumio[3122]: xcb_connection_has_error() returned true Dec 18 12:51:53 rivoplus volumio[3122]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 18 12:51:53 rivoplus volumio[3122]: No protocol specified Dec 18 12:51:53 rivoplus volumio[3122]: xcb_connection_has_error() returned true Dec 18 12:51:53 rivoplus volumio[3122]: info: QobuzConnect: setDeactiveState invoked Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetState Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 12:51:53 rivoplus systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 18 12:51:53 rivoplus systemd[1]: mpd.service: Succeeded. Dec 18 12:51:53 rivoplus systemd[1]: Stopped Music Player Daemon. Dec 18 12:51:53 rivoplus systemd[1]: Starting Music Player Daemon... Dec 18 12:51:53 rivoplus sudo[10934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 18 12:51:53 rivoplus sudo[10934]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:51:53 rivoplus sudo[10936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 18 12:51:53 rivoplus sudo[10936]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:51:53 rivoplus sudo[10934]: pam_unix(sudo:session): session closed for user root Dec 18 12:51:53 rivoplus sudo[10940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 18 12:51:53 rivoplus sudo[10940]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:51:53 rivoplus sudo[10936]: pam_unix(sudo:session): session closed for user root Dec 18 12:51:53 rivoplus sudo[10940]: pam_unix(sudo:session): session closed for user root Dec 18 12:51:53 rivoplus sudo[10933]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 18 12:51:53 rivoplus sudo[10933]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:51:53 rivoplus sudo[10933]: pam_unix(sudo:session): session closed for user root Dec 18 12:51:53 rivoplus sudo[10949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 18 12:51:53 rivoplus sudo[10949]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:51:53 rivoplus sudo[10949]: pam_unix(sudo:session): session closed for user root Dec 18 12:51:53 rivoplus volumio[3122]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 18 12:51:53 rivoplus volumio[3122]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 18 12:51:53 rivoplus volumio[3122]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 18 12:51:53 rivoplus sudo[10957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 18 12:51:53 rivoplus sudo[10957]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:51:53 rivoplus volumio[3122]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 18 12:51:53 rivoplus volumio[3122]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 18 12:51:53 rivoplus volumio[3122]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 18 12:51:53 rivoplus volumio[3122]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 18 12:51:53 rivoplus sudo[10958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 18 12:51:53 rivoplus sudo[10958]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetState Dec 18 12:51:53 rivoplus sudo[10957]: pam_unix(sudo:session): session closed for user root Dec 18 12:51:53 rivoplus volumio[3122]: info: MPD Permissions set Dec 18 12:51:53 rivoplus volumio[3122]: info: MPD Permissions set Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreStateMachine::pushState Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::volumioPushState Dec 18 12:51:53 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Dec 18 12:51:53 rivoplus qobuz-connect[10885]: 20251218 12:51:53.469 [10885.10885] INFO SampleApp: Stopping Local configuration server Dec 18 12:51:53 rivoplus sudo[10961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 18 12:51:53 rivoplus sudo[10961]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:51:53 rivoplus qobuz-connect[10885]: 20251218 12:51:53.482 [10885.10885] INFO SampleApp: shat down connection on UNIX socket Dec 18 12:51:53 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Dec 18 12:51:53 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetState Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreStateMachine::pushState Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::volumioPushState Dec 18 12:51:53 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetState Dec 18 12:51:53 rivoplus sudo[10958]: pam_unix(sudo:session): session closed for user root Dec 18 12:51:53 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Dec 18 12:51:53 rivoplus volumio[3122]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 18 12:51:53 rivoplus systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT Dec 18 12:51:53 rivoplus volumio[3122]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 18 12:51:53 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Dec 18 12:51:53 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetQueue Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreStateMachine::getQueue Dec 18 12:51:53 rivoplus volumio[3122]: info: CorePlayQueue::getQueue Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetQueue Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreStateMachine::getQueue Dec 18 12:51:53 rivoplus volumio[3122]: info: CorePlayQueue::getQueue Dec 18 12:51:53 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Dec 18 12:51:53 rivoplus volumio[3122]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 18 12:51:53 rivoplus volumio[3122]: info: QobuzConnect: setDeactiveState invoked Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetState Dec 18 12:51:53 rivoplus sudo[10961]: pam_unix(sudo:session): session closed for user root Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 12:51:53 rivoplus volumio[3122]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 18 12:51:53 rivoplus volumio[3122]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 18 12:51:53 rivoplus volumio[3122]: info: Executing endpoint qc_getconfig Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 18 12:51:53 rivoplus qobuz-connect[10964]: 20251218 12:51:53.842 [10964.10964] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 18 12:51:53 rivoplus volumio[3122]: info: Starting Shairport Sync Dec 18 12:51:53 rivoplus qobuz-connect[10964]: 20251218 12:51:53.849 [10964.10964] INFO VolumeManager: [0xabc8bb60]: Setting new playback volume: 75 Dec 18 12:51:53 rivoplus qobuz-connect[10964]: 20251218 12:51:53.849 [10964.10964] INFO VolumeManager: [0xabc8bb60]: Setting new mute state: 0 Dec 18 12:51:53 rivoplus qobuz-connect[10964]: 20251218 12:51:53.850 [10964.10964] INFO QobuzConnect: [0xabc8c530]: Client initialized! Dec 18 12:51:53 rivoplus qobuz-connect[10964]: 20251218 12:51:53.850 [10964.10964] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Dec 18 12:51:53 rivoplus qobuz-connect[10964]: 20251218 12:51:53.864 [10964.10964] INFO LocalConfigManager: [0xabc8b440]: Starting Local Configuration server Dec 18 12:51:53 rivoplus qobuz-connect[10964]: 20251218 12:51:53.864 [10964.10964] INFO SampleApp: Starting Local configuration server Dec 18 12:51:53 rivoplus qobuz-connect[10964]: 20251218 12:51:53.865 [10964.10964] INFO SampleApp: Connected to UNIX socket client 0xabc80ed8 Dec 18 12:51:53 rivoplus volumio[3122]: info: Starting Shairport Sync Dec 18 12:51:53 rivoplus sudo[10985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 18 12:51:53 rivoplus sudo[10985]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:51:53 rivoplus volumio[3122]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 18 12:51:53 rivoplus volumio[3122]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 18 12:51:53 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 18 12:51:53 rivoplus sudo[10988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 18 12:51:53 rivoplus sudo[10988]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:51:53 rivoplus systemd[1]: shairport-sync.service: Succeeded. Dec 18 12:51:53 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 18 12:51:53 rivoplus volumio[3122]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 18 12:51:53 rivoplus volumio[3122]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 18 12:51:53 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 18 12:51:53 rivoplus sudo[10985]: pam_unix(sudo:session): session closed for user root Dec 18 12:51:53 rivoplus volumio[3122]: info: Shairport-Sync Started Dec 18 12:51:53 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 18 12:51:53 rivoplus systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 18 12:51:53 rivoplus systemd[1]: shairport-sync.service: Succeeded. Dec 18 12:51:53 rivoplus volumio[3122]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 18 12:51:53 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 18 12:51:53 rivoplus qobuz-connect[10964]: 20251218 12:51:53.991 [10964.10964] INFO SampleApp: Playback volume changed: 75 Dec 18 12:51:53 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetState Dec 18 12:51:53 rivoplus volumio[3122]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 18 12:51:53 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 18 12:51:54 rivoplus sudo[10988]: pam_unix(sudo:session): session closed for user root Dec 18 12:51:54 rivoplus volumio[3122]: info: Shairport-Sync Started Dec 18 12:51:54 rivoplus volumio[3122]: info: TidalConnect service stoped! Dec 18 12:51:55 rivoplus volumio[3122]: info: TidalConnect service stoped! Dec 18 12:51:55 rivoplus volumio[3122]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 18 12:51:55 rivoplus volumio[3122]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 18 12:51:55 rivoplus sudo[11005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 18 12:51:55 rivoplus sudo[11005]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:51:55 rivoplus mpd[10956]: Dec 18 12:51 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 18 12:51:55 rivoplus systemd[1]: Started Volumio Tidal Connect Service. Dec 18 12:51:55 rivoplus systemd[1]: Started Music Player Daemon. Dec 18 12:51:55 rivoplus sudo[10911]: pam_unix(sudo:session): session closed for user root Dec 18 12:51:55 rivoplus sudo[10927]: pam_unix(sudo:session): session closed for user root Dec 18 12:51:55 rivoplus sudo[11005]: pam_unix(sudo:session): session closed for user root Dec 18 12:51:55 rivoplus volumio[3122]: error: MPD error: The expression evaluated to a falsy value: Dec 18 12:51:55 rivoplus volumio[3122]: assert.ok(self.idling) Dec 18 12:51:55 rivoplus volumio[3122]: error: The expression evaluated to a falsy value: Dec 18 12:51:55 rivoplus volumio[3122]: assert.ok(self.idling) Dec 18 12:51:55 rivoplus volumio[3122]: error: updateQueue error: null Dec 18 12:51:55 rivoplus volumio[3122]: info: Executing endpoint tc_getconfig Dec 18 12:51:55 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 18 12:51:55 rivoplus vtcs[11009]: STARTING TidalConnect services, version: 1.5.2.56 Dec 18 12:51:55 rivoplus vtcs[11009]: STARTED TidalConnect services. Dec 18 12:51:55 rivoplus volumio[3122]: info: Executing endpoint tc_connect Dec 18 12:51:55 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 18 12:51:55 rivoplus volumio[3122]: info: Connecting to TidalConnect Dec 18 12:51:55 rivoplus volumio[3122]: info: CoreCommandRouter::servicePushState Dec 18 12:51:55 rivoplus volumio[3122]: info: CoreStateMachine::pushState Dec 18 12:51:55 rivoplus volumio[3122]: info: CoreCommandRouter::volumioPushState Dec 18 12:51:55 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetState Dec 18 12:51:55 rivoplus volumio[3122]: info: CorePlayQueue::getTrack 0 Dec 18 12:51:55 rivoplus volumio[3122]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Dec 18 12:51:55 rivoplus volumio[3122]: info: CoreCommandRouter::servicePushState Dec 18 12:51:55 rivoplus volumio[3122]: info: CoreStateMachine::pushState Dec 18 12:51:55 rivoplus volumio[3122]: info: CoreCommandRouter::volumioPushState Dec 18 12:51:55 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetState Dec 18 12:51:55 rivoplus volumio[3122]: info: CorePlayQueue::getTrack 0 Dec 18 12:51:55 rivoplus volumio[3122]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Dec 18 12:51:55 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetQueue Dec 18 12:51:55 rivoplus volumio[3122]: info: CoreStateMachine::getQueue Dec 18 12:51:55 rivoplus volumio[3122]: info: CorePlayQueue::getQueue Dec 18 12:51:55 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetQueue Dec 18 12:51:55 rivoplus volumio[3122]: info: CoreStateMachine::getQueue Dec 18 12:51:55 rivoplus volumio[3122]: info: CorePlayQueue::getQueue Dec 18 12:51:56 rivoplus volumio[3122]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 18 12:51:56 rivoplus volumio[3122]: info: CoreStateMachine::getcurrentVolume Dec 18 12:51:56 rivoplus volumio[3122]: info: CoreCommandRouter::volumioRetrievevolume Dec 18 12:51:56 rivoplus volumio[3122]: info: TidalConnect service stoped! Dec 18 12:51:56 rivoplus volumio[3122]: info: VolumeController:: Volume=100 Mute =false Dec 18 12:51:56 rivoplus volumio[3122]: info: CoreStateMachine::pushState Dec 18 12:51:56 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 12:51:56 rivoplus volumio[3122]: info: CoreCommandRouter::volumioPushState Dec 18 12:51:56 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetState Dec 18 12:51:56 rivoplus volumio[3122]: info: CoreStateMachine::updateTrackBlock Dec 18 12:51:56 rivoplus volumio[3122]: info: CorePlayQueue::getTrackBlock Dec 18 12:51:56 rivoplus volumio[3122]: info: CoreCommandRouter::volumioRetrievevolume Dec 18 12:51:56 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetQueue Dec 18 12:51:56 rivoplus volumio[3122]: info: CoreStateMachine::getQueue Dec 18 12:51:56 rivoplus volumio[3122]: info: CorePlayQueue::getQueue Dec 18 12:51:56 rivoplus volumio[3122]: info: TidalConnect service stoped! Dec 18 12:51:56 rivoplus volumio[3122]: info: VolumeController:: Volume=100 Mute =false Dec 18 12:51:56 rivoplus volumio[3122]: info: CoreStateMachine::pushState Dec 18 12:51:56 rivoplus volumio[3122]: info: CoreCommandRouter::volumioPushState Dec 18 12:51:56 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetState Dec 18 12:51:56 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetQueue Dec 18 12:51:56 rivoplus volumio[3122]: info: CoreStateMachine::getQueue Dec 18 12:51:56 rivoplus volumio[3122]: info: CorePlayQueue::getQueue Dec 18 12:51:56 rivoplus volumio[3122]: info: TidalConnect service stoped! Dec 18 12:51:56 rivoplus volumio[3122]: info: TidalConnect service stoped! Dec 18 12:51:56 rivoplus volumio[3122]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 18 12:51:56 rivoplus volumio[3122]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 18 12:51:56 rivoplus volumio[3122]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 18 12:51:56 rivoplus volumio[3122]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 18 12:51:56 rivoplus sudo[11051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 18 12:51:56 rivoplus sudo[11051]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:51:56 rivoplus sudo[11053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 18 12:51:56 rivoplus sudo[11053]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:51:56 rivoplus sudo[11051]: pam_unix(sudo:session): session closed for user root Dec 18 12:51:56 rivoplus sudo[11053]: pam_unix(sudo:session): session closed for user root Dec 18 12:51:58 rivoplus volumio[3122]: info: TidalConnect service started! Dec 18 12:51:58 rivoplus sudo[11059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 18 12:51:58 rivoplus sudo[11059]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:51:58 rivoplus systemd[1]: Stopping MPD Monitor Service... Dec 18 12:51:58 rivoplus systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 18 12:51:58 rivoplus systemd[1]: mpd_monitor.service: Succeeded. Dec 18 12:51:58 rivoplus systemd[1]: Stopped MPD Monitor Service. Dec 18 12:51:58 rivoplus volumio[3122]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 18 12:51:58 rivoplus volumio[3122]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 18 12:51:58 rivoplus sudo[11062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 18 12:51:58 rivoplus sudo[11062]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:51:58 rivoplus systemd[1]: Started MPD Monitor Service. Dec 18 12:51:58 rivoplus sudo[11059]: pam_unix(sudo:session): session closed for user root Dec 18 12:51:58 rivoplus volumio[3122]: info: Successfully started MPD Monitor Dec 18 12:51:58 rivoplus mpd_monitor.sh[11063]: MPD Monitor Service: Starting MPD Monitor Service Dec 18 12:51:58 rivoplus systemd[1]: Stopping MPD Monitor Service... Dec 18 12:51:58 rivoplus systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 18 12:51:58 rivoplus systemd[1]: mpd_monitor.service: Succeeded. Dec 18 12:51:58 rivoplus volumio[3122]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 18 12:51:58 rivoplus systemd[1]: Stopped MPD Monitor Service. Dec 18 12:51:58 rivoplus volumio[3122]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 18 12:51:58 rivoplus systemd[1]: Started MPD Monitor Service. Dec 18 12:51:58 rivoplus sudo[11062]: pam_unix(sudo:session): session closed for user root Dec 18 12:51:58 rivoplus volumio[3122]: info: Successfully started MPD Monitor Dec 18 12:51:58 rivoplus mpd_monitor.sh[11066]: MPD Monitor Service: Starting MPD Monitor Service Dec 18 12:51:59 rivoplus volumio[3122]: info: TidalConnect service started! Dec 18 12:51:59 rivoplus volumio[3122]: info: TidalConnect service started! Dec 18 12:52:06 rivoplus volumio[3122]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 12:52:06 rivoplus volumio[3122]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 12:52:06 rivoplus volumio[3122]: info: Retrieving Cloud Streaming UI Dec 18 12:52:06 rivoplus volumio[3122]: info: Getting Tidal Cloud Configuration Dec 18 12:52:06 rivoplus volumio[3122]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 12:52:06 rivoplus volumio[3122]: info: Getting Qobuz Cloud Configuration Dec 18 12:52:06 rivoplus volumio[3122]: info: Asking plugin for UI Config Dec 18 12:52:06 rivoplus volumio[3122]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 12:52:06 rivoplus volumio[3122]: info: Getting Spotify Cloud Configuration Dec 18 12:52:06 rivoplus volumio[3122]: info: Asking plugin for UI Config Dec 18 12:52:06 rivoplus volumio[3122]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 12:52:06 rivoplus volumio[3122]: info: Saving Spotify Acccount Dec 18 12:52:06 rivoplus volumio[3122]: info: Got it Dec 18 12:52:06 rivoplus volumio[3122]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 18 12:52:06 rivoplus volumio[3122]: info: Got Tidal Cloud Configuration Dec 18 12:52:06 rivoplus volumio[3122]: info: Got it Dec 18 12:52:06 rivoplus volumio[3122]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 12:52:06 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 18 12:52:06 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 18 12:52:06 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 18 12:52:06 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 18 12:52:06 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 12:52:06 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 12:52:06 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 12:52:06 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 12:52:06 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetBrowseSources Dec 18 12:52:06 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetBrowseSources Dec 18 12:52:06 rivoplus volumio[3122]: info: CoreCommandRouter::volumioGetBrowseSources Dec 18 12:52:07 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 12:52:07 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 18 12:52:10 rivoplus volumio[3122]: info: Disabling MyMusic plugin upnp Dec 18 12:52:10 rivoplus sudo[11089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Dec 18 12:52:10 rivoplus sudo[11089]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 12:52:10 rivoplus systemd[1]: Stopping UPnP Renderer front-end to MPD... Dec 18 12:52:10 rivoplus volumio[3122]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 18 12:52:10 rivoplus volumio[3122]: error: Upnp client error: Error: This socket has been ended by the other party Dec 18 12:52:11 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 12:52:15 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 12:52:19 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 12:52:23 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 12:52:27 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 12:52:31 rivoplus volumio5-onboarding[3887]: time=2025-12-18T12:52:31.031Z level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=5 Dec 18 12:52:31 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 12:52:31 rivoplus volumio5-onboarding[3887]: time=2025-12-18T12:52:31.442Z level=WARN msg="received acknowledgment for source with no pending writes" component=conn/ble data=0500 addr=5 Dec 18 12:52:31 rivoplus volumio[3122]: info: Enabling MyMusic plugin upnp Dec 18 12:52:31 rivoplus volumio[3122]: info: Enabling plugin upnp Dec 18 12:52:31 rivoplus volumio[3122]: info: Loading plugin "upnp"... Dec 18 12:52:31 rivoplus volumio[3122]: info: [1766062351723] Starting Upmpd Daemon Dec 18 12:52:31 rivoplus volumio[3122]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 18 12:52:31 rivoplus volumio[3122]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 12:52:31 rivoplus volumio[3122]: Error: listen EADDRINUSE: address already in use :::6599 Dec 18 12:52:31 rivoplus volumio[3122]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Dec 18 12:52:31 rivoplus volumio[3122]: at listenInCluster (net.js:1379:12) Dec 18 12:52:31 rivoplus volumio[3122]: at Server.listen (net.js:1465:7) Dec 18 12:52:31 rivoplus volumio[3122]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Dec 18 12:52:31 rivoplus volumio[3122]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Dec 18 12:52:31 rivoplus volumio[3122]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Dec 18 12:52:31 rivoplus volumio[3122]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 18 12:52:31 rivoplus volumio[3122]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Dec 18 12:52:31 rivoplus volumio[3122]: code: 'EADDRINUSE', Dec 18 12:52:31 rivoplus volumio[3122]: errno: -98, Dec 18 12:52:31 rivoplus volumio[3122]: syscall: 'listen', Dec 18 12:52:31 rivoplus volumio[3122]: address: '::', Dec 18 12:52:31 rivoplus volumio[3122]: port: 6599 Dec 18 12:52:31 rivoplus volumio[3122]: } Dec 18 12:52:31 rivoplus volumio[3122]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 12:52:32 rivoplus sudo[11156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-18 12:51 Dec 18 12:52:32 rivoplus sudo[11156]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="2f25e1a98e53e2dbc4f3878604e250b0379f3ea4" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:47:33 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="9e8aa5d6afb5e5d87a87bafae7aa5647"