-- Logs begin at Thu 2019-02-14 19:11:58 JST, end at Sat 2025-11-01 16:32:11 JST. -- Nov 01 16:31:02 rivo nmbd[3047]: [2025/11/01 16:31:02.707578, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Nov 01 16:31:02 rivo nmbd[3047]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.21 for name WORKGROUP<1d>. Nov 01 16:31:02 rivo nmbd[3047]: This response was from IP 192.168.0.1, reporting an IP address of 192.168.0.1. Nov 01 16:31:02 rivo nmbd[3047]: [2025/11/01 16:31:02.708375, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Nov 01 16:31:02 rivo nmbd[3047]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.20 for name WORKGROUP<1d>. Nov 01 16:31:02 rivo nmbd[3047]: This response was from IP 192.168.0.1, reporting an IP address of 192.168.0.1. Nov 01 16:31:04 rivo volumio[3511]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 16:31:04 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 01 16:31:04 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 16:31:04 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 01 16:31:04 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 16:31:04 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 16:31:04 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 16:31:04 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 16:31:04 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 16:31:04 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 16:31:04 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 16:31:04 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 01 16:31:04 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 01 16:31:09 rivo volumio[3511]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 16:31:09 rivo volumio[3511]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 16:31:09 rivo volumio[3511]: info: Retrieving Cloud Streaming UI Nov 01 16:31:09 rivo volumio[3511]: info: Getting Tidal Cloud Configuration Nov 01 16:31:09 rivo volumio[3511]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 16:31:09 rivo volumio[3511]: info: Getting Qobuz Cloud Configuration Nov 01 16:31:09 rivo volumio[3511]: info: Asking plugin for UI Config Nov 01 16:31:09 rivo volumio[3511]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 16:31:09 rivo volumio[3511]: info: Getting Spotify Cloud Configuration Nov 01 16:31:09 rivo volumio[3511]: info: Asking plugin for UI Config Nov 01 16:31:09 rivo volumio[3511]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 16:31:09 rivo volumio[3511]: info: Saving Spotify Acccount Nov 01 16:31:09 rivo volumio[3511]: info: Got it Nov 01 16:31:09 rivo volumio[3511]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 01 16:31:09 rivo volumio[3511]: info: Got Tidal Cloud Configuration Nov 01 16:31:09 rivo volumio[3511]: info: Got it Nov 01 16:31:09 rivo volumio[3511]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 16:31:09 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 01 16:31:09 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 01 16:31:09 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 01 16:31:09 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 01 16:31:09 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 16:31:09 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 16:31:09 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 16:31:09 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 16:31:09 rivo volumio[3511]: info: CoreCommandRouter::volumioGetBrowseSources Nov 01 16:31:09 rivo volumio[3511]: info: CoreCommandRouter::volumioGetBrowseSources Nov 01 16:31:09 rivo volumio[3511]: info: CoreCommandRouter::volumioGetBrowseSources Nov 01 16:31:10 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 16:31:11 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 01 16:31:14 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 16:31:18 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 16:31:21 rivo volumio[3511]: info: Disabling MyMusic plugin multiroom Nov 01 16:31:21 rivo volumio[3511]: info: Disabling plugin multiroom Nov 01 16:31:21 rivo volumio[3511]: info: Preparing to generate the ALSA configuration file Nov 01 16:31:21 rivo volumio[3511]: info: Asound.conf file written Nov 01 16:31:21 rivo sudo[17744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 01 16:31:21 rivo sudo[17744]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 16:31:21 rivo sudo[17744]: pam_unix(sudo:session): session closed for user root Nov 01 16:31:21 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Nov 01 16:31:21 rivo kernel: Fine spdif sysclk setting range(0~2000000), 3644800 Nov 01 16:31:21 rivo kernel: out of value, fixed it Nov 01 16:31:21 rivo kernel: id=0 set inskew=0 Nov 01 16:31:21 rivo volumio[3511]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Nov 01 16:31:21 rivo volumio[3511]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Nov 01 16:31:21 rivo volumio[3511]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Nov 01 16:31:21 rivo volumio[3511]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Nov 01 16:31:21 rivo volumio[3511]: info: Output device has changed, restarting MPD Nov 01 16:31:21 rivo sudo[17749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 16:31:21 rivo sudo[17749]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 16:31:21 rivo volumio[3511]: info: QobuzConnect: setDeactiveState invoked Nov 01 16:31:21 rivo volumio[3511]: info: CoreCommandRouter::volumioGetState Nov 01 16:31:21 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 16:31:21 rivo volumio[3511]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 01 16:31:21 rivo sudo[17749]: pam_unix(sudo:session): session closed for user root Nov 01 16:31:21 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 16:31:21 rivo sudo[17752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 16:31:21 rivo sudo[17752]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 16:31:21 rivo volumio[3511]: info: Done. Nov 01 16:31:21 rivo systemd[1]: Stopping Music Player Daemon... Nov 01 16:31:21 rivo kernel: aml_spdif_open Nov 01 16:31:21 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Nov 01 16:31:21 rivo kernel: spdif_a keep clk continuous Nov 01 16:31:21 rivo kernel: aml_spdif_close Nov 01 16:31:21 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Nov 01 16:31:21 rivo volumio[3511]: info: MPD Permissions set Nov 01 16:31:21 rivo volumio[3511]: info: CoreStateMachine::pushState Nov 01 16:31:21 rivo systemd[1]: mpd.service: Succeeded. Nov 01 16:31:21 rivo volumio[3511]: info: CoreCommandRouter::volumioPushState Nov 01 16:31:21 rivo systemd[1]: Stopped Music Player Daemon. Nov 01 16:31:21 rivo volumio[3511]: info: CoreCommandRouter::volumioGetState Nov 01 16:31:21 rivo volumio[3511]: info: MRS: Pushing multiroomSync output update for this device Nov 01 16:31:21 rivo volumio[3511]: info: MRS: Pushing multiroomSync output Nov 01 16:31:21 rivo sudo[17762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Nov 01 16:31:21 rivo sudo[17762]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 16:31:21 rivo systemd[1]: Starting Music Player Daemon... Nov 01 16:31:21 rivo volumio[3511]: info: CoreCommandRouter::volumioGetQueue Nov 01 16:31:21 rivo volumio[3511]: info: CoreStateMachine::getQueue Nov 01 16:31:21 rivo volumio[3511]: info: CorePlayQueue::getQueue Nov 01 16:31:21 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 16:31:21 rivo sudo[17762]: pam_unix(sudo:session): session closed for user root Nov 01 16:31:22 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 16:31:22 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 01 16:31:22 rivo sudo[17776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Nov 01 16:31:22 rivo sudo[17776]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 16:31:22 rivo systemd[1]: Stopping Volumio Qobuz Connect Service... Nov 01 16:31:22 rivo qobuz-connect[13017]: 20251101 16:31:22.052 [13017.13017] INFO SampleApp: Stopping Local configuration server Nov 01 16:31:22 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 16:31:22 rivo qobuz-connect[13017]: 20251101 16:31:22.062 [13017.13017] INFO SampleApp: shat down connection on UNIX socket Nov 01 16:31:22 rivo volumio[3511]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Nov 01 16:31:22 rivo volumio[3511]: info: QobuzConnect: setDeactiveState invoked Nov 01 16:31:22 rivo volumio[3511]: info: CoreCommandRouter::volumioGetState Nov 01 16:31:22 rivo systemd[1]: qobuz-connect.service: Succeeded. Nov 01 16:31:22 rivo systemd[1]: Stopped Volumio Qobuz Connect Service. Nov 01 16:31:22 rivo systemd[1]: Started Volumio Qobuz Connect Service. Nov 01 16:31:22 rivo sudo[17776]: pam_unix(sudo:session): session closed for user root Nov 01 16:31:22 rivo sudo[17763]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 01 16:31:22 rivo sudo[17763]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 16:31:22 rivo sudo[17763]: pam_unix(sudo:session): session closed for user root Nov 01 16:31:22 rivo volumio[3511]: info: Executing endpoint qc_getconfig Nov 01 16:31:22 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Nov 01 16:31:22 rivo qobuz-connect[17784]: 20251101 16:31:22.264 [17784.17784] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Nov 01 16:31:22 rivo volumio[3511]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Nov 01 16:31:22 rivo volumio[3511]: info: QobuzConnect: QOBUZ Connect daemon connected Nov 01 16:31:22 rivo qobuz-connect[17784]: 20251101 16:31:22.274 [17784.17784] INFO VolumeManager: [0xab6f0240]: Setting new playback volume: 75 Nov 01 16:31:22 rivo qobuz-connect[17784]: 20251101 16:31:22.274 [17784.17784] INFO VolumeManager: [0xab6f0240]: Setting new mute state: 0 Nov 01 16:31:22 rivo qobuz-connect[17784]: 20251101 16:31:22.274 [17784.17784] INFO QobuzConnect: [0xab6f0c10]: Client initialized! Nov 01 16:31:22 rivo qobuz-connect[17784]: 20251101 16:31:22.274 [17784.17784] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Nov 01 16:31:22 rivo qobuz-connect[17784]: 20251101 16:31:22.287 [17784.17784] INFO LocalConfigManager: [0xab6efb20]: Starting Local Configuration server Nov 01 16:31:22 rivo qobuz-connect[17784]: 20251101 16:31:22.287 [17784.17784] INFO SampleApp: Starting Local configuration server Nov 01 16:31:22 rivo qobuz-connect[17784]: 20251101 16:31:22.288 [17784.17784] INFO SampleApp: Connected to UNIX socket client 0xab6e5ed8 Nov 01 16:31:22 rivo qobuz-connect[17784]: 20251101 16:31:22.414 [17784.17784] INFO SampleApp: Playback volume changed: 75 Nov 01 16:31:22 rivo volumio[3511]: info: CoreCommandRouter::volumioGetState Nov 01 16:31:22 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 16:31:22 rivo volumio[3511]: error: MPD error: Error: This socket has been ended by the other party Nov 01 16:31:22 rivo volumio[3511]: error: This socket has been ended by the other party {"code":"EPIPE"} Nov 01 16:31:22 rivo volumio[3511]: error: MPD error: Error: This socket has been ended by the other party Nov 01 16:31:22 rivo volumio[3511]: error: This socket has been ended by the other party {"code":"EPIPE"} Nov 01 16:31:22 rivo volumio[3511]: error: MPD error: Error: This socket has been ended by the other party Nov 01 16:31:22 rivo volumio[3511]: error: This socket has been ended by the other party {"code":"EPIPE"} Nov 01 16:31:23 rivo mpd[17790]: Nov 01 16:31 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 01 16:31:24 rivo systemd[1]: Started Music Player Daemon. Nov 01 16:31:24 rivo sudo[17752]: pam_unix(sudo:session): session closed for user root Nov 01 16:31:24 rivo volumio[3511]: error: updateQueue error: null Nov 01 16:31:26 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 16:31:26 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 16:31:26 rivo volumio[3511]: info: Discovery: Getting this device information Nov 01 16:31:26 rivo volumio[3511]: info: CoreCommandRouter::volumioGetState Nov 01 16:31:26 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 16:31:26 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 16:31:30 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 16:31:35 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 16:31:39 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 16:31:40 rivo volumio[3511]: error: Upnp client error: Error: This socket has been ended by the other party Nov 01 16:31:42 rivo volumio[3511]: info: Clearing queue after UPNP request Nov 01 16:31:43 rivo volumio[3511]: info: CoreStateMachine::ClearQueue Nov 01 16:31:43 rivo volumio[3511]: info: CoreStateMachine::stop Nov 01 16:31:43 rivo volumio[3511]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 16:31:43 rivo volumio[3511]: info: CoreStateMachine::stPlaybackTimer Nov 01 16:31:43 rivo volumio[3511]: info: CoreStateMachine::updateTrackBlock Nov 01 16:31:43 rivo volumio[3511]: info: CorePlayQueue::getTrackBlock Nov 01 16:31:43 rivo volumio[3511]: info: CoreStateMachine::pushState Nov 01 16:31:43 rivo volumio[3511]: info: CorePlayQueue::getTrack 0 Nov 01 16:31:43 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 16:31:43 rivo volumio[3511]: info: CoreCommandRouter::volumioPushState Nov 01 16:31:43 rivo volumio[3511]: info: CoreCommandRouter::volumioGetState Nov 01 16:31:43 rivo volumio[3511]: info: CorePlayQueue::getTrack 0 Nov 01 16:31:43 rivo volumio[3511]: info: MRS: Pushing multiroomSync output update for this device Nov 01 16:31:43 rivo volumio[3511]: info: MRS: Pushing multiroomSync output Nov 01 16:31:43 rivo volumio[3511]: info: CoreStateMachine::serviceStop Nov 01 16:31:43 rivo volumio[3511]: info: CorePlayQueue::getTrack 0 Nov 01 16:31:43 rivo volumio[3511]: info: ControllerMpd::stop Nov 01 16:31:43 rivo volumio[3511]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 16:31:43 rivo volumio[3511]: info: CorePlayQueue::clearPlayQueue Nov 01 16:31:43 rivo volumio[3511]: info: CorePlayQueue::saveQueue Nov 01 16:31:43 rivo volumio[3511]: info: CoreCommandRouter::volumioPushState Nov 01 16:31:43 rivo volumio[3511]: info: CoreCommandRouter::volumioGetState Nov 01 16:31:43 rivo volumio[3511]: info: CorePlayQueue::getTrack 0 Nov 01 16:31:43 rivo volumio[3511]: info: MRS: Pushing multiroomSync output update for this device Nov 01 16:31:43 rivo volumio[3511]: info: MRS: Pushing multiroomSync output Nov 01 16:31:43 rivo volumio[3511]: info: CoreCommandRouter::volumioPushQueue Nov 01 16:31:43 rivo volumio[3511]: info: Nov 01 16:31:43 rivo volumio[3511]: ---------------------------- MPD announces system playlist update Nov 01 16:31:43 rivo volumio[3511]: info: Ignoring MPD Status Update Nov 01 16:31:43 rivo volumio[3511]: info: sendMpdCommand stop took 36 milliseconds Nov 01 16:31:43 rivo volumio[3511]: error: updateQueue error: null Nov 01 16:31:43 rivo volumio[3511]: info: ------------------------------ 4ms Nov 01 16:31:43 rivo volumio[3511]: info: Starting UPNP Playback Nov 01 16:31:43 rivo volumio[3511]: info: Preparing playback through UPNP Nov 01 16:31:43 rivo volumio[3511]: info: CoreCommandRouter::volumioGetState Nov 01 16:31:43 rivo volumio[3511]: info: CorePlayQueue::getTrack 0 Nov 01 16:31:43 rivo volumio[3511]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 01 16:31:43 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 16:31:43 rivo volumio[3511]: info: Nov 01 16:31:43 rivo volumio[3511]: ---------------------------- MPD announces system playlist update Nov 01 16:31:43 rivo volumio[3511]: info: Ignoring MPD Status Update Nov 01 16:31:43 rivo volumio[3511]: info: Nov 01 16:31:43 rivo volumio[3511]: ---------------------------- MPD announces system playlist update Nov 01 16:31:43 rivo volumio[3511]: info: Ignoring MPD Status Update Nov 01 16:31:43 rivo volumio[3511]: info: ------------------------------ 30ms Nov 01 16:31:43 rivo volumio[3511]: info: ------------------------------ 7ms Nov 01 16:31:43 rivo volumio[3511]: info: Nov 01 16:31:43 rivo volumio[3511]: ---------------------------- MPD announces system playlist update Nov 01 16:31:43 rivo volumio[3511]: info: Ignoring MPD Status Update Nov 01 16:31:43 rivo volumio[3511]: info: ------------------------------ 3ms Nov 01 16:31:43 rivo volumio[3511]: info: Nov 01 16:31:43 rivo volumio[3511]: ---------------------------- MPD announces system playlist update Nov 01 16:31:43 rivo volumio[3511]: info: Ignoring MPD Status Update Nov 01 16:31:43 rivo volumio[3511]: info: Nov 01 16:31:43 rivo volumio[3511]: ---------------------------- MPD announces system playlist update Nov 01 16:31:43 rivo volumio[3511]: info: Ignoring MPD Status Update Nov 01 16:31:43 rivo volumio[3511]: info: ------------------------------ 5ms Nov 01 16:31:43 rivo volumio[3511]: info: ------------------------------ 4ms Nov 01 16:31:43 rivo volumio[3511]: info: Nov 01 16:31:43 rivo volumio[3511]: ---------------------------- MPD announces system playlist update Nov 01 16:31:43 rivo volumio[3511]: info: Ignoring MPD Status Update Nov 01 16:31:43 rivo volumio[3511]: info: ------------------------------ 3ms Nov 01 16:31:44 rivo volumio[3511]: info: Nov 01 16:31:44 rivo volumio[3511]: ---------------------------- MPD announces state update: player Nov 01 16:31:44 rivo volumio[3511]: info: ControllerMpd::getState Nov 01 16:31:44 rivo volumio[3511]: verbose: ControllerMpd::sendMpdCommand status Nov 01 16:31:44 rivo kernel: aml_spdif_open Nov 01 16:31:44 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Nov 01 16:31:44 rivo kernel: set normal 512 fs /4 fs Nov 01 16:31:44 rivo kernel: set spdifout clk:12288000, mpll:49152000 Nov 01 16:31:44 rivo kernel: get spdifout clk:12287994, mpll:49151974 Nov 01 16:31:44 rivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Nov 01 16:31:44 rivo kernel: set normal 512 fs /4 fs Nov 01 16:31:44 rivo kernel: set spdifout clk:12288000, mpll:49152000 Nov 01 16:31:44 rivo kernel: get spdifout clk:12287994, mpll:49151974 Nov 01 16:31:44 rivo kernel: spdif_info: rate: 96000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xa00, ch1_r:0xa00 Nov 01 16:31:44 rivo kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Nov 01 16:31:44 rivo volumio[3511]: info: Nov 01 16:31:44 rivo volumio[3511]: ---------------------------- MPD announces state update: player Nov 01 16:31:44 rivo volumio[3511]: info: sendMpdCommand status took 9 milliseconds Nov 01 16:31:44 rivo volumio[3511]: info: ControllerMpd::getState Nov 01 16:31:44 rivo volumio[3511]: verbose: ControllerMpd::sendMpdCommand status Nov 01 16:31:44 rivo volumio[3511]: verbose: ControllerMpd::parseState Nov 01 16:31:44 rivo volumio[3511]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 16:31:44 rivo volumio[3511]: info: sendMpdCommand status took 4 milliseconds Nov 01 16:31:44 rivo volumio[3511]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 01 16:31:44 rivo volumio[3511]: verbose: ControllerMpd::parseState Nov 01 16:31:44 rivo volumio[3511]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 16:31:44 rivo volumio[3511]: verbose: ControllerMpd::parseTrackInfo Nov 01 16:31:44 rivo volumio[3511]: info: ControllerMpd::pushState Nov 01 16:31:44 rivo volumio[3511]: info: CoreCommandRouter::servicePushState Nov 01 16:31:44 rivo volumio[3511]: verbose: In UPNP mode Nov 01 16:31:44 rivo volumio[3511]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":407,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Haven't Got Anything Better To Do","artist":"Alma Micic","album":"Lilac Wine 〜Tribute to Helen Merrill","uri":"http://192.168.0.7:12395/qobuz/362792271.flac","trackType":"qobuz"} Nov 01 16:31:44 rivo volumio[3511]: verbose: CURRENT POSITION 0 Nov 01 16:31:44 rivo volumio[3511]: info: CoreStateMachine::syncState stateService play Nov 01 16:31:44 rivo volumio[3511]: info: CoreStateMachine::syncState currentStatus stop Nov 01 16:31:44 rivo volumio[3511]: info: ------------------------------ 23ms Nov 01 16:31:44 rivo volumio[3511]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 01 16:31:44 rivo volumio[3511]: verbose: ControllerMpd::parseTrackInfo Nov 01 16:31:44 rivo volumio[3511]: info: ControllerMpd::pushState Nov 01 16:31:44 rivo volumio[3511]: info: CoreCommandRouter::servicePushState Nov 01 16:31:44 rivo volumio[3511]: verbose: In UPNP mode Nov 01 16:31:44 rivo volumio[3511]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":407,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Haven't Got Anything Better To Do","artist":"Alma Micic","album":"Lilac Wine 〜Tribute to Helen Merrill","uri":"http://192.168.0.7:12395/qobuz/362792271.flac","trackType":"qobuz"} Nov 01 16:31:44 rivo volumio[3511]: verbose: CURRENT POSITION 0 Nov 01 16:31:44 rivo volumio[3511]: info: CoreStateMachine::syncState stateService play Nov 01 16:31:44 rivo volumio[3511]: info: CoreStateMachine::syncState currentStatus play Nov 01 16:31:44 rivo volumio[3511]: info: Received an update from plugin. extracting info from payload Nov 01 16:31:44 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 01 16:31:44 rivo volumio[3511]: info: CoreStateMachine::pushState Nov 01 16:31:44 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 16:31:44 rivo volumio[3511]: info: CoreCommandRouter::volumioPushState Nov 01 16:31:44 rivo volumio[3511]: info: CoreCommandRouter::volumioGetState Nov 01 16:31:44 rivo volumio[3511]: info: MRS: Pushing multiroomSync output update for this device Nov 01 16:31:44 rivo volumio[3511]: info: MRS: Pushing multiroomSync output Nov 01 16:31:44 rivo volumio[3511]: info: CoreStateMachine::pushState Nov 01 16:31:44 rivo volumio[3511]: info: CoreCommandRouter::volumioPushState Nov 01 16:31:44 rivo volumio[3511]: info: CoreCommandRouter::volumioGetState Nov 01 16:31:44 rivo volumio[3511]: info: MRS: Pushing multiroomSync output update for this device Nov 01 16:31:44 rivo volumio[3511]: info: MRS: Pushing multiroomSync output Nov 01 16:31:44 rivo volumio[3511]: info: ------------------------------ 49ms Nov 01 16:31:44 rivo volumio[3511]: info: CoreCommandRouter::volumioGetQueue Nov 01 16:31:44 rivo volumio[3511]: info: CoreStateMachine::getQueue Nov 01 16:31:44 rivo volumio[3511]: info: CorePlayQueue::getQueue Nov 01 16:31:44 rivo volumio[3511]: info: CoreCommandRouter::volumioGetQueue Nov 01 16:31:44 rivo volumio[3511]: info: CoreStateMachine::getQueue Nov 01 16:31:44 rivo volumio[3511]: info: CorePlayQueue::getQueue Nov 01 16:31:44 rivo volumio[3511]: info: Signalling Playback active due to playback status change Nov 01 16:31:44 rivo volumio[3511]: info: MCU Signalled Playback Active Nov 01 16:31:45 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Nov 01 16:31:47 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 16:31:51 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 16:31:55 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 16:31:56 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 16:31:56 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 16:31:56 rivo volumio[3511]: info: Discovery: Getting this device information Nov 01 16:31:56 rivo volumio[3511]: info: CoreCommandRouter::volumioGetState Nov 01 16:31:56 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 16:31:59 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 16:32:02 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 16:32:05 rivo volumio[3511]: info: Disabling MyMusic plugin upnp Nov 01 16:32:05 rivo sudo[18019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Nov 01 16:32:05 rivo sudo[18019]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 16:32:05 rivo volumio[3511]: error: Upnp client error: Error: This socket has been ended by the other party Nov 01 16:32:05 rivo systemd[1]: Stopping UPnP Renderer front-end to MPD... Nov 01 16:32:06 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 16:32:10 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 16:32:11 rivo volumio[3511]: info: Enabling MyMusic plugin upnp Nov 01 16:32:11 rivo volumio[3511]: info: Enabling plugin upnp Nov 01 16:32:11 rivo volumio[3511]: info: Loading plugin "upnp"... Nov 01 16:32:11 rivo volumio[3511]: info: [1761982331207] Starting Upmpd Daemon Nov 01 16:32:11 rivo volumio[3511]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 16:32:11 rivo volumio[3511]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 16:32:11 rivo volumio[3511]: Error: listen EADDRINUSE: address already in use :::6599 Nov 01 16:32:11 rivo volumio[3511]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Nov 01 16:32:11 rivo volumio[3511]: at listenInCluster (net.js:1379:12) Nov 01 16:32:11 rivo volumio[3511]: at Server.listen (net.js:1465:7) Nov 01 16:32:11 rivo volumio[3511]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Nov 01 16:32:11 rivo volumio[3511]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Nov 01 16:32:11 rivo volumio[3511]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Nov 01 16:32:11 rivo volumio[3511]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 01 16:32:11 rivo volumio[3511]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Nov 01 16:32:11 rivo volumio[3511]: code: 'EADDRINUSE', Nov 01 16:32:11 rivo volumio[3511]: errno: -98, Nov 01 16:32:11 rivo volumio[3511]: syscall: 'listen', Nov 01 16:32:11 rivo volumio[3511]: address: '::', Nov 01 16:32:11 rivo volumio[3511]: port: 6599 Nov 01 16:32:11 rivo volumio[3511]: } Nov 01 16:32:11 rivo volumio[3511]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 16:32:11 rivo sudo[18045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-01 16:31 Nov 01 16:32:11 rivo sudo[18045]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:43:09 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="fc7d09bc07ffd188bff4808d8f79c746"