-- Logs begin at Mon 2025-09-08 16:50:07 JST, end at Sun 2025-09-14 20:07:26 JST. -- Sep 14 20:06:01 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 20:06:06 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 20:06:10 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 20:06:11 rivo volumio[3505]: info: Clearing queue after UPNP request Sep 14 20:06:11 rivo volumio[3505]: info: CoreStateMachine::ClearQueue Sep 14 20:06:11 rivo volumio[3505]: info: CoreStateMachine::stop Sep 14 20:06:11 rivo volumio[3505]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 20:06:11 rivo volumio[3505]: info: CorePlayQueue::clearPlayQueue Sep 14 20:06:11 rivo volumio[3505]: info: CorePlayQueue::saveQueue Sep 14 20:06:11 rivo volumio[3505]: info: CoreCommandRouter::volumioPushState Sep 14 20:06:11 rivo volumio[3505]: info: CoreCommandRouter::volumioGetState Sep 14 20:06:11 rivo volumio[3505]: info: CorePlayQueue::getTrack 0 Sep 14 20:06:11 rivo volumio[3505]: info: MRS: Pushing multiroomSync output update for this device Sep 14 20:06:11 rivo volumio[3505]: info: MRS: Pushing multiroomSync output Sep 14 20:06:11 rivo volumio[3505]: info: CoreCommandRouter::volumioPushQueue Sep 14 20:06:12 rivo volumio[3505]: info: Sep 14 20:06:12 rivo volumio[3505]: ---------------------------- MPD announces system playlist update Sep 14 20:06:12 rivo volumio[3505]: info: Ignoring MPD Status Update Sep 14 20:06:12 rivo volumio[3505]: error: updateQueue error: null Sep 14 20:06:12 rivo volumio[3505]: info: ------------------------------ 2ms Sep 14 20:06:12 rivo volumio[3505]: info: Starting UPNP Playback Sep 14 20:06:12 rivo volumio[3505]: info: Preparing playback through UPNP Sep 14 20:06:12 rivo volumio[3505]: info: CoreCommandRouter::volumioGetState Sep 14 20:06:12 rivo volumio[3505]: info: CorePlayQueue::getTrack 0 Sep 14 20:06:12 rivo volumio[3505]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 20:06:12 rivo volumio[3505]: info: Sep 14 20:06:12 rivo volumio[3505]: ---------------------------- MPD announces system playlist update Sep 14 20:06:12 rivo volumio[3505]: info: Ignoring MPD Status Update Sep 14 20:06:12 rivo volumio[3505]: info: Sep 14 20:06:12 rivo volumio[3505]: ---------------------------- MPD announces system playlist update Sep 14 20:06:12 rivo volumio[3505]: info: Ignoring MPD Status Update Sep 14 20:06:12 rivo volumio[3505]: info: ------------------------------ 3ms Sep 14 20:06:12 rivo volumio[3505]: info: ------------------------------ 3ms Sep 14 20:06:12 rivo volumio[3505]: info: Sep 14 20:06:12 rivo volumio[3505]: ---------------------------- MPD announces system playlist update Sep 14 20:06:12 rivo volumio[3505]: info: Ignoring MPD Status Update Sep 14 20:06:12 rivo volumio[3505]: info: Sep 14 20:06:12 rivo volumio[3505]: ---------------------------- MPD announces system playlist update Sep 14 20:06:12 rivo volumio[3505]: info: Ignoring MPD Status Update Sep 14 20:06:12 rivo volumio[3505]: info: ------------------------------ 2ms Sep 14 20:06:12 rivo volumio[3505]: info: ------------------------------ 2ms Sep 14 20:06:12 rivo volumio[3505]: info: Sep 14 20:06:12 rivo volumio[3505]: ---------------------------- MPD announces system playlist update Sep 14 20:06:12 rivo volumio[3505]: info: Ignoring MPD Status Update Sep 14 20:06:12 rivo volumio[3505]: info: Sep 14 20:06:12 rivo volumio[3505]: ---------------------------- MPD announces system playlist update Sep 14 20:06:12 rivo volumio[3505]: info: Ignoring MPD Status Update Sep 14 20:06:12 rivo volumio[3505]: info: ------------------------------ 3ms Sep 14 20:06:12 rivo volumio[3505]: info: ------------------------------ 2ms Sep 14 20:06:13 rivo volumio[3505]: info: Sep 14 20:06:13 rivo volumio[3505]: ---------------------------- MPD announces state update: player Sep 14 20:06:13 rivo volumio[3505]: info: ControllerMpd::getState Sep 14 20:06:13 rivo volumio[3505]: verbose: ControllerMpd::sendMpdCommand status Sep 14 20:06:13 rivo kernel: aml_spdif_open Sep 14 20:06:13 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Sep 14 20:06:13 rivo kernel: set normal 512 fs /4 fs Sep 14 20:06:13 rivo kernel: set spdifout clk:12288000, mpll:49152000 Sep 14 20:06:13 rivo kernel: get spdifout clk:12287994, mpll:49151974 Sep 14 20:06:13 rivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Sep 14 20:06:13 rivo kernel: set normal 512 fs /4 fs Sep 14 20:06:13 rivo kernel: set spdifout clk:12288000, mpll:49152000 Sep 14 20:06:13 rivo kernel: get spdifout clk:12287994, mpll:49151974 Sep 14 20:06:13 rivo kernel: spdif_info: rate: 96000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xa00, ch1_r:0xa00 Sep 14 20:06:13 rivo kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Sep 14 20:06:13 rivo volumio[3505]: info: Sep 14 20:06:13 rivo volumio[3505]: ---------------------------- MPD announces state update: player Sep 14 20:06:13 rivo volumio[3505]: info: sendMpdCommand status took 5 milliseconds Sep 14 20:06:13 rivo volumio[3505]: info: ControllerMpd::getState Sep 14 20:06:13 rivo volumio[3505]: verbose: ControllerMpd::sendMpdCommand status Sep 14 20:06:13 rivo volumio[3505]: verbose: ControllerMpd::parseState Sep 14 20:06:13 rivo volumio[3505]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 20:06:13 rivo volumio[3505]: info: sendMpdCommand status took 2 milliseconds Sep 14 20:06:13 rivo volumio[3505]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 14 20:06:13 rivo volumio[3505]: verbose: ControllerMpd::parseState Sep 14 20:06:13 rivo volumio[3505]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 20:06:13 rivo volumio[3505]: verbose: ControllerMpd::parseTrackInfo Sep 14 20:06:13 rivo volumio[3505]: info: ControllerMpd::pushState Sep 14 20:06:13 rivo volumio[3505]: info: CoreCommandRouter::servicePushState Sep 14 20:06:13 rivo volumio[3505]: verbose: In UPNP mode Sep 14 20:06:13 rivo volumio[3505]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":196,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"引き潮","artist":"谷村新司","album":"ステージ・セレクション・アルバム「EARLY TIMES」～38年目の昴～","uri":"http://192.168.0.7:16764/qobuz/50253422.flac","trackType":"qobuz"} Sep 14 20:06:13 rivo volumio[3505]: verbose: CURRENT POSITION 0 Sep 14 20:06:13 rivo volumio[3505]: info: CoreStateMachine::syncState stateService play Sep 14 20:06:13 rivo volumio[3505]: info: CoreStateMachine::syncState currentStatus stop Sep 14 20:06:13 rivo volumio[3505]: info: ------------------------------ 14ms Sep 14 20:06:13 rivo volumio[3505]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 14 20:06:13 rivo volumio[3505]: verbose: ControllerMpd::parseTrackInfo Sep 14 20:06:13 rivo volumio[3505]: info: ControllerMpd::pushState Sep 14 20:06:13 rivo volumio[3505]: info: CoreCommandRouter::servicePushState Sep 14 20:06:13 rivo volumio[3505]: verbose: In UPNP mode Sep 14 20:06:13 rivo volumio[3505]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":196,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"引き潮","artist":"谷村新司","album":"ステージ・セレクション・アルバム「EARLY TIMES」～38年目の昴～","uri":"http://192.168.0.7:16764/qobuz/50253422.flac","trackType":"qobuz"} Sep 14 20:06:13 rivo volumio[3505]: verbose: CURRENT POSITION 0 Sep 14 20:06:13 rivo volumio[3505]: info: CoreStateMachine::syncState stateService play Sep 14 20:06:13 rivo volumio[3505]: info: CoreStateMachine::syncState currentStatus play Sep 14 20:06:13 rivo volumio[3505]: info: Received an update from plugin. extracting info from payload Sep 14 20:06:13 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 14 20:06:13 rivo volumio[3505]: info: CoreStateMachine::pushState Sep 14 20:06:13 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 20:06:13 rivo volumio[3505]: info: CoreCommandRouter::volumioPushState Sep 14 20:06:13 rivo volumio[3505]: info: CoreCommandRouter::volumioGetState Sep 14 20:06:13 rivo volumio[3505]: info: MRS: Pushing multiroomSync output update for this device Sep 14 20:06:13 rivo volumio[3505]: info: MRS: Pushing multiroomSync output Sep 14 20:06:13 rivo volumio[3505]: info: CoreStateMachine::pushState Sep 14 20:06:13 rivo volumio[3505]: info: CoreCommandRouter::volumioPushState Sep 14 20:06:13 rivo volumio[3505]: info: CoreCommandRouter::volumioGetState Sep 14 20:06:13 rivo volumio[3505]: info: MRS: Pushing multiroomSync output update for this device Sep 14 20:06:13 rivo volumio[3505]: info: MRS: Pushing multiroomSync output Sep 14 20:06:13 rivo volumio[3505]: info: ------------------------------ 34ms Sep 14 20:06:13 rivo volumio[3505]: info: CoreCommandRouter::volumioGetQueue Sep 14 20:06:13 rivo volumio[3505]: info: CoreStateMachine::getQueue Sep 14 20:06:13 rivo volumio[3505]: info: CorePlayQueue::getQueue Sep 14 20:06:13 rivo volumio[3505]: info: CoreCommandRouter::volumioGetQueue Sep 14 20:06:13 rivo volumio[3505]: info: CoreStateMachine::getQueue Sep 14 20:06:13 rivo volumio[3505]: info: CorePlayQueue::getQueue Sep 14 20:06:13 rivo volumio[3505]: info: Signalling Playback active due to playback status change Sep 14 20:06:13 rivo volumio[3505]: info: MCU Signalled Playback Active Sep 14 20:06:13 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Sep 14 20:06:14 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 20:06:17 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 20:06:17 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 20:06:17 rivo volumio[3505]: info: Discovery: Getting this device information Sep 14 20:06:17 rivo volumio[3505]: info: CoreCommandRouter::volumioGetState Sep 14 20:06:17 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 20:06:23 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 20:06:24 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 20:06:29 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 20:06:33 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 20:06:36 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 20:06:40 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 20:06:44 rivo volumio[3505]: info: Enabling MyMusic plugin multiroom Sep 14 20:06:44 rivo volumio[3505]: info: Enabling plugin multiroom Sep 14 20:06:44 rivo volumio[3505]: info: Loading plugin "multiroom"... Sep 14 20:06:44 rivo volumio[3505]: info: Applying required configuration parameters for plugin multiroom Sep 14 20:06:44 rivo sudo[18104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Sep 14 20:06:44 rivo sudo[18104]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 20:06:44 rivo sudo[18104]: pam_unix(sudo:session): session closed for user root Sep 14 20:06:44 rivo volumio[3505]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Sep 14 20:06:44 rivo volumio[3505]: info: MRS: MultiRoom plugin initialized Sep 14 20:06:44 rivo volumio[3505]: info: MRS: STOPPING SNAPCLIENT Sep 14 20:06:44 rivo volumio[3505]: info: MRS: Snap server stop Sep 14 20:06:44 rivo volumio[3505]: info: MRS: STOPPING volumioStreaming Sep 14 20:06:44 rivo sudo[18121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Sep 14 20:06:44 rivo sudo[18121]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 20:06:44 rivo sudo[18122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Sep 14 20:06:44 rivo sudo[18122]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 20:06:44 rivo sudo[18121]: pam_unix(sudo:session): session closed for user root Sep 14 20:06:44 rivo volumio[3505]: info: Preparing to generate the ALSA configuration file Sep 14 20:06:44 rivo sudo[18126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Sep 14 20:06:44 rivo sudo[18126]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 20:06:44 rivo sudo[18122]: pam_unix(sudo:session): session closed for user root Sep 14 20:06:44 rivo volumio[3505]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 14 20:06:44 rivo volumio[3505]: info: Reading ALSA contributions from plugins. Sep 14 20:06:44 rivo sudo[18128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Sep 14 20:06:44 rivo sudo[18128]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 20:06:44 rivo volumio[3505]: info: MRS: SNAPSERVER STOPPED Sep 14 20:06:44 rivo volumio[3505]: info: MRS: SNAPCLIENT STOPPED Sep 14 20:06:44 rivo volumio[3505]: info: Asound.conf file written Sep 14 20:06:44 rivo sudo[18128]: pam_unix(sudo:session): session closed for user root Sep 14 20:06:44 rivo sudo[18126]: pam_unix(sudo:session): session closed for user root Sep 14 20:06:44 rivo sudo[18134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 14 20:06:44 rivo sudo[18134]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 20:06:44 rivo sudo[18134]: pam_unix(sudo:session): session closed for user root Sep 14 20:06:44 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Sep 14 20:06:44 rivo kernel: out of value, fixed it Sep 14 20:06:44 rivo kernel: id=0 set inskew=0 Sep 14 20:06:44 rivo volumio[3505]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Sep 14 20:06:44 rivo volumio[3505]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Sep 14 20:06:44 rivo volumio[3505]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Sep 14 20:06:44 rivo volumio[3505]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Sep 14 20:06:44 rivo volumio[3505]: No state is present for card Audio Sep 14 20:06:44 rivo volumio[3505]: Found hardware: "USB-Audio" "USB Mixer" "USB040d:340f" "" "" Sep 14 20:06:44 rivo volumio[3505]: Hardware is initialized using a generic method Sep 14 20:06:44 rivo volumio[3505]: No state is present for card Audio Sep 14 20:06:44 rivo volumio[3505]: info: Output device has changed, restarting MPD Sep 14 20:06:44 rivo sudo[18140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 20:06:44 rivo sudo[18140]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 20:06:44 rivo volumio[3505]: info: QobuzConnect: setDeactiveState invoked Sep 14 20:06:44 rivo volumio[3505]: info: CoreCommandRouter::volumioGetState Sep 14 20:06:44 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 20:06:44 rivo volumio[3505]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 14 20:06:44 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 20:06:44 rivo sudo[18140]: pam_unix(sudo:session): session closed for user root Sep 14 20:06:44 rivo sudo[18143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 20:06:44 rivo sudo[18143]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 20:06:44 rivo systemd[1]: Stopping Music Player Daemon... Sep 14 20:06:45 rivo volumio[3505]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 14 20:06:45 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 20:06:45 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Sep 14 20:06:45 rivo kernel: spdif_a keep clk continuous Sep 14 20:06:45 rivo kernel: aml_spdif_close Sep 14 20:06:45 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Sep 14 20:06:45 rivo volumio[3505]: info: PLUGIN START: multiroom Sep 14 20:06:45 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Sep 14 20:06:45 rivo volumio[3505]: info: MRS: Pushing multiroomSync output for this device Sep 14 20:06:45 rivo volumio[3505]: info: MRS: Pushing multiroomSync output Sep 14 20:06:45 rivo volumio[3505]: info: Adding audio output: Sep 14 20:06:45 rivo volumio[3505]: error: Can't add: Sep 14 20:06:45 rivo volumio[3505]: info: Adding audio output: Sep 14 20:06:45 rivo kernel: aml_spdif_open Sep 14 20:06:45 rivo volumio[3505]: error: Can't add: Sep 14 20:06:45 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Sep 14 20:06:45 rivo kernel: spdif_a keep clk continuous Sep 14 20:06:45 rivo kernel: aml_spdif_close Sep 14 20:06:45 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Sep 14 20:06:45 rivo volumio[3505]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 14 20:06:45 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 20:06:45 rivo kernel: aml_spdif_open Sep 14 20:06:45 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Sep 14 20:06:45 rivo kernel: spdif_a keep clk continuous Sep 14 20:06:45 rivo kernel: aml_spdif_close Sep 14 20:06:45 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Sep 14 20:06:45 rivo systemd[1]: mpd.service: Succeeded. Sep 14 20:06:45 rivo systemd[1]: Stopped Music Player Daemon. Sep 14 20:06:45 rivo kernel: aml_spdif_open Sep 14 20:06:45 rivo systemd[1]: Starting Music Player Daemon... Sep 14 20:06:45 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Sep 14 20:06:45 rivo kernel: spdif_a keep clk continuous Sep 14 20:06:45 rivo kernel: aml_spdif_close Sep 14 20:06:45 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Sep 14 20:06:45 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 20:06:45 rivo volumio[3505]: error: MPD error: Error: write EPIPE Sep 14 20:06:45 rivo volumio[3505]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Sep 14 20:06:45 rivo sudo[18167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 14 20:06:45 rivo sudo[18167]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 20:06:45 rivo volumio[3505]: info: MPD Permissions set Sep 14 20:06:45 rivo volumio[3505]: info: MRS: volumioStreaming STOPPED Sep 14 20:06:45 rivo volumio[3505]: info: MRS: Removed streaming files Sep 14 20:06:45 rivo volumio[3505]: info: CoreStateMachine::pushState Sep 14 20:06:45 rivo volumio[3505]: info: CoreCommandRouter::volumioPushState Sep 14 20:06:45 rivo volumio[3505]: info: CoreCommandRouter::volumioGetState Sep 14 20:06:45 rivo volumio[3505]: info: MRS: Pushing multiroomSync output update for this device Sep 14 20:06:45 rivo volumio[3505]: info: MRS: Pushing multiroomSync output Sep 14 20:06:45 rivo volumio[3505]: info: MRS: Pushing multiroomSync output update for this device Sep 14 20:06:45 rivo volumio[3505]: info: MRS: Pushing multiroomSync output Sep 14 20:06:45 rivo sudo[18167]: pam_unix(sudo:session): session closed for user root Sep 14 20:06:45 rivo volumio[3505]: info: CoreCommandRouter::volumioGetQueue Sep 14 20:06:45 rivo volumio[3505]: info: CoreStateMachine::getQueue Sep 14 20:06:45 rivo volumio[3505]: info: CorePlayQueue::getQueue Sep 14 20:06:45 rivo sudo[18169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 14 20:06:45 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 20:06:45 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 20:06:45 rivo sudo[18169]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 20:06:45 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 20:06:45 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 20:06:45 rivo systemd[1]: Stopping Volumio Qobuz Connect Service... Sep 14 20:06:45 rivo qobuz-connect[17663]: 20250914 20:06:45.246 [17663.17663] INFO SampleApp: Stopping Local configuration server Sep 14 20:06:45 rivo qobuz-connect[17663]: 20250914 20:06:45.257 [17663.17663] INFO SampleApp: shat down connection on UNIX socket Sep 14 20:06:45 rivo systemd[1]: qobuz-connect.service: Succeeded. Sep 14 20:06:45 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 20:06:45 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 14 20:06:45 rivo systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 20:06:45 rivo systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 20:06:45 rivo sudo[18169]: pam_unix(sudo:session): session closed for user root Sep 14 20:06:45 rivo sudo[18159]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 20:06:45 rivo sudo[18159]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 20:06:45 rivo sudo[18159]: pam_unix(sudo:session): session closed for user root Sep 14 20:06:45 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 20:06:45 rivo volumio[3505]: info: Done. Sep 14 20:06:45 rivo volumio[3505]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 14 20:06:45 rivo volumio[3505]: info: QobuzConnect: setDeactiveState invoked Sep 14 20:06:45 rivo volumio[3505]: info: CoreCommandRouter::volumioGetState Sep 14 20:06:45 rivo volumio[3505]: info: Executing endpoint qc_getconfig Sep 14 20:06:45 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 20:06:45 rivo qobuz-connect[18176]: 20250914 20:06:45.369 [18176.18176] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 20:06:45 rivo volumio[3505]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 14 20:06:45 rivo volumio[3505]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 14 20:06:45 rivo qobuz-connect[18176]: 20250914 20:06:45.375 [18176.18176] INFO VolumeManager: [0xac230240]: Setting new playback volume: 75 Sep 14 20:06:45 rivo qobuz-connect[18176]: 20250914 20:06:45.375 [18176.18176] INFO VolumeManager: [0xac230240]: Setting new mute state: 0 Sep 14 20:06:45 rivo qobuz-connect[18176]: 20250914 20:06:45.375 [18176.18176] INFO QobuzConnect: [0xac230c10]: Client initialized! Sep 14 20:06:45 rivo qobuz-connect[18176]: 20250914 20:06:45.375 [18176.18176] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Sep 14 20:06:45 rivo volumio[3505]: info: MRS: Found cast device: BRAVIA-4K-VH2-6ed8a527516cf0c3af674fec0c2fc4c0 Sep 14 20:06:45 rivo volumio[3505]: info: Adding audio output: Sep 14 20:06:45 rivo volumio[3505]: error: Can't add: Sep 14 20:06:45 rivo volumio[3505]: info: MRS: Found cast device: BRAVIA-4K-VH2-6ed8a527516cf0c3af674fec0c2fc4c0 Sep 14 20:06:45 rivo qobuz-connect[18176]: 20250914 20:06:45.388 [18176.18176] INFO LocalConfigManager: [0xac22fb20]: Starting Local Configuration server Sep 14 20:06:45 rivo qobuz-connect[18176]: 20250914 20:06:45.388 [18176.18176] INFO SampleApp: Starting Local configuration server Sep 14 20:06:45 rivo qobuz-connect[18176]: 20250914 20:06:45.389 [18176.18176] INFO SampleApp: Connected to UNIX socket client 0xac225ed8 Sep 14 20:06:45 rivo qobuz-connect[18176]: 20250914 20:06:45.519 [18176.18176] INFO SampleApp: Playback volume changed: 75 Sep 14 20:06:45 rivo volumio[3505]: info: CoreCommandRouter::volumioGetState Sep 14 20:06:46 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 20:06:47 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 20:06:47 rivo volumio[3505]: info: Discovery: Getting this device information Sep 14 20:06:47 rivo volumio[3505]: info: CoreCommandRouter::volumioGetState Sep 14 20:06:47 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 20:06:47 rivo mpd[18181]: Sep 14 20:06 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 14 20:06:47 rivo systemd[1]: Started Music Player Daemon. Sep 14 20:06:47 rivo sudo[18143]: pam_unix(sudo:session): session closed for user root Sep 14 20:06:47 rivo volumio[3505]: error: updateQueue error: null Sep 14 20:06:48 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 20:06:50 rivo volumio[3505]: info: MRS: Getting audio outputs on start Sep 14 20:06:50 rivo volumio[3505]: info: MRS: Requesting all other devices output Sep 14 20:06:52 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 20:06:57 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 20:06:57 rivo volumio[3505]: error: Upnp client error: Error: This socket has been ended by the other party Sep 14 20:06:59 rivo volumio[3505]: info: Clearing queue after UPNP request Sep 14 20:06:59 rivo volumio[3505]: info: CoreStateMachine::ClearQueue Sep 14 20:06:59 rivo volumio[3505]: info: CoreStateMachine::stop Sep 14 20:06:59 rivo volumio[3505]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 20:06:59 rivo volumio[3505]: info: CoreStateMachine::stPlaybackTimer Sep 14 20:06:59 rivo volumio[3505]: info: CoreStateMachine::updateTrackBlock Sep 14 20:06:59 rivo volumio[3505]: info: CorePlayQueue::getTrackBlock Sep 14 20:06:59 rivo volumio[3505]: info: CoreStateMachine::pushState Sep 14 20:06:59 rivo volumio[3505]: info: CorePlayQueue::getTrack 0 Sep 14 20:06:59 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 20:06:59 rivo volumio[3505]: info: CoreCommandRouter::volumioPushState Sep 14 20:06:59 rivo volumio[3505]: info: CoreCommandRouter::volumioGetState Sep 14 20:06:59 rivo volumio[3505]: info: CorePlayQueue::getTrack 0 Sep 14 20:06:59 rivo volumio[3505]: info: MRS: Pushing multiroomSync output update for this device Sep 14 20:06:59 rivo volumio[3505]: info: MRS: Pushing multiroomSync output Sep 14 20:06:59 rivo volumio[3505]: info: MRS: Pushing multiroomSync output update for this device Sep 14 20:06:59 rivo volumio[3505]: info: MRS: Pushing multiroomSync output Sep 14 20:06:59 rivo volumio[3505]: info: CoreStateMachine::serviceStop Sep 14 20:06:59 rivo volumio[3505]: info: CorePlayQueue::getTrack 0 Sep 14 20:06:59 rivo volumio[3505]: info: ControllerMpd::stop Sep 14 20:06:59 rivo volumio[3505]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 20:06:59 rivo volumio[3505]: info: CorePlayQueue::clearPlayQueue Sep 14 20:06:59 rivo volumio[3505]: info: CorePlayQueue::saveQueue Sep 14 20:06:59 rivo volumio[3505]: info: CoreCommandRouter::volumioPushState Sep 14 20:06:59 rivo volumio[3505]: info: CoreCommandRouter::volumioGetState Sep 14 20:06:59 rivo volumio[3505]: info: CorePlayQueue::getTrack 0 Sep 14 20:06:59 rivo volumio[3505]: info: MRS: Pushing multiroomSync output update for this device Sep 14 20:06:59 rivo volumio[3505]: info: MRS: Pushing multiroomSync output Sep 14 20:06:59 rivo volumio[3505]: info: MRS: Pushing multiroomSync output update for this device Sep 14 20:06:59 rivo volumio[3505]: info: MRS: Pushing multiroomSync output Sep 14 20:06:59 rivo volumio[3505]: info: CoreCommandRouter::volumioPushQueue Sep 14 20:06:59 rivo volumio[3505]: info: Sep 14 20:06:59 rivo volumio[3505]: ---------------------------- MPD announces system playlist update Sep 14 20:06:59 rivo volumio[3505]: info: Ignoring MPD Status Update Sep 14 20:06:59 rivo volumio[3505]: info: sendMpdCommand stop took 39 milliseconds Sep 14 20:06:59 rivo volumio[3505]: error: updateQueue error: null Sep 14 20:06:59 rivo volumio[3505]: info: ------------------------------ 3ms Sep 14 20:06:59 rivo volumio[3505]: info: Starting UPNP Playback Sep 14 20:06:59 rivo volumio[3505]: info: Preparing playback through UPNP Sep 14 20:06:59 rivo volumio[3505]: info: CoreCommandRouter::volumioGetState Sep 14 20:06:59 rivo volumio[3505]: info: CorePlayQueue::getTrack 0 Sep 14 20:06:59 rivo volumio[3505]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 20:06:59 rivo volumio[3505]: info: MCU Signalled Playback Inactive Sep 14 20:07:00 rivo volumio[3505]: info: Sep 14 20:07:00 rivo volumio[3505]: ---------------------------- MPD announces system playlist update Sep 14 20:07:00 rivo volumio[3505]: info: Ignoring MPD Status Update Sep 14 20:07:00 rivo volumio[3505]: info: ------------------------------ 2ms Sep 14 20:07:00 rivo volumio[3505]: info: Sep 14 20:07:00 rivo volumio[3505]: ---------------------------- MPD announces system playlist update Sep 14 20:07:00 rivo volumio[3505]: info: Ignoring MPD Status Update Sep 14 20:07:00 rivo volumio[3505]: info: ------------------------------ 2ms Sep 14 20:07:00 rivo volumio[3505]: info: Sep 14 20:07:00 rivo volumio[3505]: ---------------------------- MPD announces system playlist update Sep 14 20:07:00 rivo volumio[3505]: info: Ignoring MPD Status Update Sep 14 20:07:00 rivo volumio[3505]: info: Sep 14 20:07:00 rivo volumio[3505]: ---------------------------- MPD announces system playlist update Sep 14 20:07:00 rivo volumio[3505]: info: Ignoring MPD Status Update Sep 14 20:07:00 rivo volumio[3505]: info: ------------------------------ 4ms Sep 14 20:07:00 rivo volumio[3505]: info: ------------------------------ 3ms Sep 14 20:07:00 rivo volumio[3505]: info: Sep 14 20:07:00 rivo volumio[3505]: ---------------------------- MPD announces system playlist update Sep 14 20:07:00 rivo volumio[3505]: info: Ignoring MPD Status Update Sep 14 20:07:00 rivo volumio[3505]: info: ------------------------------ 2ms Sep 14 20:07:00 rivo volumio[3505]: info: Sep 14 20:07:00 rivo volumio[3505]: ---------------------------- MPD announces system playlist update Sep 14 20:07:00 rivo volumio[3505]: info: Ignoring MPD Status Update Sep 14 20:07:00 rivo volumio[3505]: info: ------------------------------ 2ms Sep 14 20:07:01 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 20:07:01 rivo volumio[3505]: info: Sep 14 20:07:01 rivo volumio[3505]: ---------------------------- MPD announces state update: player Sep 14 20:07:01 rivo volumio[3505]: info: ControllerMpd::getState Sep 14 20:07:01 rivo volumio[3505]: verbose: ControllerMpd::sendMpdCommand status Sep 14 20:07:01 rivo kernel: aml_spdif_open Sep 14 20:07:01 rivo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Sep 14 20:07:01 rivo kernel: set normal 512 fs /4 fs Sep 14 20:07:01 rivo kernel: set spdifout clk:12288000, mpll:49152000 Sep 14 20:07:01 rivo kernel: get spdifout clk:12287994, mpll:49151974 Sep 14 20:07:01 rivo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Sep 14 20:07:01 rivo kernel: set normal 512 fs /4 fs Sep 14 20:07:01 rivo kernel: set spdifout clk:12288000, mpll:49152000 Sep 14 20:07:01 rivo kernel: get spdifout clk:12287994, mpll:49151974 Sep 14 20:07:01 rivo kernel: spdif_info: rate: 96000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xa00, ch1_r:0xa00 Sep 14 20:07:01 rivo kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Sep 14 20:07:01 rivo kernel: spdif_info: rate: 96000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xa00, ch1_r:0xa00 Sep 14 20:07:01 rivo kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Sep 14 20:07:01 rivo volumio[3505]: info: Sep 14 20:07:01 rivo volumio[3505]: ---------------------------- MPD announces state update: player Sep 14 20:07:01 rivo volumio[3505]: info: sendMpdCommand status took 7 milliseconds Sep 14 20:07:01 rivo volumio[3505]: info: ControllerMpd::getState Sep 14 20:07:01 rivo volumio[3505]: verbose: ControllerMpd::sendMpdCommand status Sep 14 20:07:01 rivo volumio[3505]: verbose: ControllerMpd::parseState Sep 14 20:07:01 rivo volumio[3505]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 20:07:01 rivo volumio[3505]: info: sendMpdCommand status took 7 milliseconds Sep 14 20:07:01 rivo volumio[3505]: verbose: ControllerMpd::parseState Sep 14 20:07:01 rivo volumio[3505]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 20:07:01 rivo volumio[3505]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 14 20:07:01 rivo volumio[3505]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 14 20:07:01 rivo volumio[3505]: verbose: ControllerMpd::parseTrackInfo Sep 14 20:07:01 rivo volumio[3505]: verbose: ControllerMpd::parseTrackInfo Sep 14 20:07:01 rivo volumio[3505]: info: ControllerMpd::pushState Sep 14 20:07:01 rivo volumio[3505]: info: CoreCommandRouter::servicePushState Sep 14 20:07:01 rivo volumio[3505]: verbose: In UPNP mode Sep 14 20:07:01 rivo volumio[3505]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":328,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Runnin' on","artist":"谷村新司","album":"ステージ・セレクション・アルバム「EARLY TIMES」～38年目の昴～","uri":"http://192.168.0.7:16764/qobuz/50253420.flac","trackType":"qobuz"} Sep 14 20:07:01 rivo volumio[3505]: verbose: CURRENT POSITION 0 Sep 14 20:07:01 rivo volumio[3505]: info: CoreStateMachine::syncState stateService play Sep 14 20:07:01 rivo volumio[3505]: info: CoreStateMachine::syncState currentStatus stop Sep 14 20:07:01 rivo volumio[3505]: info: ControllerMpd::pushState Sep 14 20:07:01 rivo volumio[3505]: info: CoreCommandRouter::servicePushState Sep 14 20:07:01 rivo volumio[3505]: verbose: In UPNP mode Sep 14 20:07:01 rivo volumio[3505]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":328,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Runnin' on","artist":"谷村新司","album":"ステージ・セレクション・アルバム「EARLY TIMES」～38年目の昴～","uri":"http://192.168.0.7:16764/qobuz/50253420.flac","trackType":"qobuz"} Sep 14 20:07:01 rivo volumio[3505]: verbose: CURRENT POSITION 0 Sep 14 20:07:01 rivo volumio[3505]: info: CoreStateMachine::syncState stateService play Sep 14 20:07:01 rivo volumio[3505]: info: CoreStateMachine::syncState currentStatus play Sep 14 20:07:01 rivo volumio[3505]: info: Received an update from plugin. extracting info from payload Sep 14 20:07:01 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 14 20:07:01 rivo volumio[3505]: info: CoreStateMachine::pushState Sep 14 20:07:01 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 20:07:01 rivo volumio[3505]: info: CoreCommandRouter::volumioPushState Sep 14 20:07:01 rivo volumio[3505]: info: CoreCommandRouter::volumioGetState Sep 14 20:07:01 rivo volumio[3505]: info: MRS: Pushing multiroomSync output update for this device Sep 14 20:07:01 rivo volumio[3505]: info: MRS: Pushing multiroomSync output Sep 14 20:07:01 rivo volumio[3505]: info: MRS: Pushing multiroomSync output update for this device Sep 14 20:07:01 rivo volumio[3505]: info: MRS: Pushing multiroomSync output Sep 14 20:07:01 rivo volumio[3505]: info: CoreStateMachine::pushState Sep 14 20:07:01 rivo volumio[3505]: info: CoreCommandRouter::volumioPushState Sep 14 20:07:01 rivo volumio[3505]: info: CoreCommandRouter::volumioGetState Sep 14 20:07:01 rivo volumio[3505]: info: MRS: Pushing multiroomSync output update for this device Sep 14 20:07:01 rivo volumio[3505]: info: MRS: Pushing multiroomSync output Sep 14 20:07:01 rivo volumio[3505]: info: MRS: Pushing multiroomSync output update for this device Sep 14 20:07:01 rivo volumio[3505]: info: MRS: Pushing multiroomSync output Sep 14 20:07:01 rivo volumio[3505]: info: ------------------------------ 48ms Sep 14 20:07:01 rivo volumio[3505]: info: ------------------------------ 52ms Sep 14 20:07:01 rivo volumio[3505]: info: CoreCommandRouter::volumioGetQueue Sep 14 20:07:01 rivo volumio[3505]: info: CoreStateMachine::getQueue Sep 14 20:07:01 rivo volumio[3505]: info: CorePlayQueue::getQueue Sep 14 20:07:01 rivo volumio[3505]: info: CoreCommandRouter::volumioGetQueue Sep 14 20:07:01 rivo volumio[3505]: info: CoreStateMachine::getQueue Sep 14 20:07:01 rivo volumio[3505]: info: CorePlayQueue::getQueue Sep 14 20:07:01 rivo volumio[3505]: info: Signalling Playback active due to playback status change Sep 14 20:07:01 rivo volumio[3505]: info: MCU Signalled Playback Active Sep 14 20:07:01 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Sep 14 20:07:10 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 20:07:14 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Sep 14 20:07:14 rivo kernel: spdif_a keep clk continuous Sep 14 20:07:14 rivo kernel: aml_spdif_close Sep 14 20:07:14 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Sep 14 20:07:14 rivo volumio[3505]: info: Sep 14 20:07:14 rivo volumio[3505]: ---------------------------- MPD announces state update: player Sep 14 20:07:14 rivo volumio[3505]: info: ControllerMpd::getState Sep 14 20:07:14 rivo volumio[3505]: verbose: ControllerMpd::sendMpdCommand status Sep 14 20:07:14 rivo volumio[3505]: info: sendMpdCommand status took 1 milliseconds Sep 14 20:07:14 rivo volumio[3505]: verbose: ControllerMpd::parseState Sep 14 20:07:14 rivo volumio[3505]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 20:07:14 rivo volumio[3505]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 20:07:14 rivo volumio[3505]: verbose: ControllerMpd::parseTrackInfo Sep 14 20:07:14 rivo volumio[3505]: info: ControllerMpd::pushState Sep 14 20:07:14 rivo volumio[3505]: info: CoreCommandRouter::servicePushState Sep 14 20:07:14 rivo volumio[3505]: verbose: In UPNP mode Sep 14 20:07:14 rivo volumio[3505]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":14490,"duration":328,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3028 Kbps","isStreaming":false,"title":"Runnin' on","artist":"谷村新司","album":"ステージ・セレクション・アルバム「EARLY TIMES」～38年目の昴～","uri":"http://192.168.0.7:16764/qobuz/50253420.flac","trackType":"qobuz"} Sep 14 20:07:14 rivo volumio[3505]: verbose: CURRENT POSITION 0 Sep 14 20:07:14 rivo volumio[3505]: info: CoreStateMachine::syncState stateService pause Sep 14 20:07:14 rivo volumio[3505]: info: CoreStateMachine::syncState currentStatus play Sep 14 20:07:14 rivo volumio[3505]: info: CoreStateMachine::pushState Sep 14 20:07:14 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 20:07:14 rivo volumio[3505]: info: CoreCommandRouter::volumioPushState Sep 14 20:07:14 rivo volumio[3505]: info: CoreCommandRouter::volumioGetState Sep 14 20:07:14 rivo volumio[3505]: info: MRS: Pushing multiroomSync output update for this device Sep 14 20:07:14 rivo volumio[3505]: info: MRS: Pushing multiroomSync output Sep 14 20:07:14 rivo volumio[3505]: info: MRS: Pushing multiroomSync output update for this device Sep 14 20:07:14 rivo volumio[3505]: info: MRS: Pushing multiroomSync output Sep 14 20:07:14 rivo volumio[3505]: info: CoreStateMachine::stPlaybackTimer Sep 14 20:07:14 rivo volumio[3505]: info: ------------------------------ 27ms Sep 14 20:07:14 rivo volumio[3505]: info: CoreCommandRouter::volumioGetQueue Sep 14 20:07:14 rivo volumio[3505]: info: CoreStateMachine::getQueue Sep 14 20:07:14 rivo volumio[3505]: info: CorePlayQueue::getQueue Sep 14 20:07:14 rivo volumio[3505]: info: MCU Signalled Playback Inactive Sep 14 20:07:17 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 20:07:17 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 20:07:17 rivo volumio[3505]: info: Discovery: Getting this device information Sep 14 20:07:17 rivo volumio[3505]: info: CoreCommandRouter::volumioGetState Sep 14 20:07:17 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 20:07:18 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 20:07:20 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 20:07:22 rivo volumio[3505]: info: Disabling MyMusic plugin upnp Sep 14 20:07:22 rivo sudo[18383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Sep 14 20:07:22 rivo sudo[18383]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 20:07:22 rivo volumio[3505]: error: Upnp client error: Error: This socket has been ended by the other party Sep 14 20:07:22 rivo systemd[1]: Stopping UPnP Renderer front-end to MPD... Sep 14 20:07:24 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 20:07:25 rivo volumio[3505]: info: Enabling MyMusic plugin upnp Sep 14 20:07:25 rivo volumio[3505]: info: Enabling plugin upnp Sep 14 20:07:25 rivo volumio[3505]: info: Loading plugin "upnp"... Sep 14 20:07:25 rivo volumio[3505]: info: [1757848045586] Starting Upmpd Daemon Sep 14 20:07:25 rivo volumio[3505]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 20:07:25 rivo volumio[3505]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 20:07:25 rivo volumio[3505]: Error: listen EADDRINUSE: address already in use :::6599 Sep 14 20:07:25 rivo volumio[3505]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Sep 14 20:07:25 rivo volumio[3505]: at listenInCluster (net.js:1379:12) Sep 14 20:07:25 rivo volumio[3505]: at Server.listen (net.js:1465:7) Sep 14 20:07:25 rivo volumio[3505]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Sep 14 20:07:25 rivo volumio[3505]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Sep 14 20:07:25 rivo volumio[3505]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Sep 14 20:07:25 rivo volumio[3505]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 14 20:07:25 rivo volumio[3505]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Sep 14 20:07:25 rivo volumio[3505]: code: 'EADDRINUSE', Sep 14 20:07:25 rivo volumio[3505]: errno: -98, Sep 14 20:07:25 rivo volumio[3505]: syscall: 'listen', Sep 14 20:07:25 rivo volumio[3505]: address: '::', Sep 14 20:07:25 rivo volumio[3505]: port: 6599 Sep 14 20:07:25 rivo volumio[3505]: } Sep 14 20:07:25 rivo volumio[3505]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 20:07:26 rivo sudo[18400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-14 20:06 Sep 14 20:07:26 rivo sudo[18400]: 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"