Oct 03 21:33:01 rivoplus kernel: usb 1-1.4.2: new high-speed USB device number 8 using xhci-hcd Oct 03 21:33:01 rivoplus kernel: usb 1-1.4.2: config 1 has an invalid interface number: 3 but max is 2 Oct 03 21:33:01 rivoplus kernel: usb 1-1.4.2: config 1 has an invalid interface number: 3 but max is 2 Oct 03 21:33:01 rivoplus kernel: usb 1-1.4.2: config 1 has an invalid interface number: 3 but max is 2 Oct 03 21:33:01 rivoplus kernel: usb 1-1.4.2: config 1 has no interface number 1 Oct 03 21:33:01 rivoplus kernel: usb 1-1.4.2: New USB device found, idVendor=0644, idProduct=8032 Oct 03 21:33:01 rivoplus kernel: usb 1-1.4.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Oct 03 21:33:01 rivoplus kernel: usb 1-1.4.2: Product: ESOTERIC USB AUDIO DEVICE Oct 03 21:33:01 rivoplus kernel: usb 1-1.4.2: Manufacturer: TEAC Oct 03 21:33:01 rivoplus kernel: input: TEAC ESOTERIC USB AUDIO DEVICE as /devices/platform/ff500000.dwc3/xhci-hcd.0.auto/usb1/1-1/1-1.4/1-1.4.2/1-1.4.2:1.0/0003:0644:8032.0003/input/input9 Oct 03 21:33:01 rivoplus kernel: hid-generic 0003:0644:8032.0003: input,hidraw0: USB HID v1.00 Device [TEAC ESOTERIC USB AUDIO DEVICE] on usb-xhci-hcd.0.auto-1.4.2/input0 Oct 03 21:33:01 rivoplus mtp-probe[1247]: checking bus 1, device 8: "/sys/devices/platform/ff500000.dwc3/xhci-hcd.0.auto/usb1/1-1/1-1.4/1-1.4.2" Oct 03 21:33:01 rivoplus mtp-probe[1247]: bus: 1, device: 8 was not an MTP device Oct 03 21:33:01 rivoplus systemd[1]: Stopping triggerhappy.service - triggerhappy global hotkey daemon... Oct 03 21:33:01 rivoplus systemd[1]: triggerhappy.service: Deactivated successfully. Oct 03 21:33:01 rivoplus systemd[1]: Stopped triggerhappy.service - triggerhappy global hotkey daemon. Oct 03 21:33:01 rivoplus systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon... Oct 03 21:33:01 rivoplus thd[1258]: Unable to parse trigger line: Oct 03 21:33:01 rivoplus thd[1258]: Unable to parse trigger line: Oct 03 21:33:01 rivoplus thd[1258]: Unable to parse trigger line: Oct 03 21:33:02 rivoplus thd[1258]: Device /dev/input/event5 not suitable. Oct 03 21:33:02 rivoplus systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. Oct 03 21:33:03 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:03.538+09:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" latency=34.752474ms timeout=10s Oct 03 21:33:03 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:03.743+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" latency=34.752474ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=203.944419ms Oct 03 21:33:03 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:03.849+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" latency=34.752474ms timeout=10s endpoint=https://www.googleapis.com duration=308.513209ms Oct 03 21:33:03 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:03.917+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" latency=34.752474ms timeout=10s endpoint=https://google.com duration=377.17152ms Oct 03 21:33:03 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:03.948+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" latency=34.752474ms timeout=10s endpoint=https://securetoken.googleapis.com duration=408.662053ms Oct 03 21:33:03 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:03.964+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" latency=34.752474ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=422.343431ms Oct 03 21:33:04 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:04.026+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" latency=34.752474ms timeout=10s endpoint=https://functions.volumio.cloud duration=482.335687ms Oct 03 21:33:04 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:04.036+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" latency=34.752474ms timeout=10s endpoint=https://functions.volumio.cloud duration=491.290952ms Oct 03 21:33:04 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:04.055+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" latency=34.752474ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=513.582343ms Oct 03 21:33:04 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:04.087+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" latency=34.752474ms timeout=10s endpoint=http://plugins.volumio.org duration=544.605873ms Oct 03 21:33:04 rivoplus volumio[3534]: verbose: New Socket.io Connection to 192.168.0.15 from 192.168.0.4 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260911.1 Engine version: 3 Transport: polling Total Clients: 8 Oct 03 21:33:04 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:04.123+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" latency=34.752474ms timeout=10s endpoint=http://pushupdates.volumio.org duration=578.841966ms Oct 03 21:33:04 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:04.142+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" latency=34.752474ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=602.078197ms Oct 03 21:33:04 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 03 21:33:04 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetVisibleSources Oct 03 21:33:04 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 21:33:04 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:04.172+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" latency=34.752474ms timeout=10s endpoint=https://database.volumio.cloud duration=627.79011ms Oct 03 21:33:04 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:04 rivoplus volumio[3534]: info: CorePlayQueue::getTrack 2 Oct 03 21:33:04 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 03 21:33:04 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 03 21:33:04 rivoplus volumio[3534]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 03 21:33:04 rivoplus volumio[3534]: info: Received Get System Info Oct 03 21:33:04 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 21:33:04 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 21:33:04 rivoplus volumio[3534]: info: Discovery: Getting this device information Oct 03 21:33:04 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:04 rivoplus volumio[3534]: info: CorePlayQueue::getTrack 2 Oct 03 21:33:04 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 21:33:04 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:04 rivoplus volumio[3534]: info: CorePlayQueue::getTrack 2 Oct 03 21:33:04 rivoplus volumio[3534]: info: Listing playlists Oct 03 21:33:04 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetQueue Oct 03 21:33:04 rivoplus volumio[3534]: info: CoreStateMachine::getQueue Oct 03 21:33:04 rivoplus volumio[3534]: info: CorePlayQueue::getQueue Oct 03 21:33:04 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 03 21:33:05 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:05.419+09:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" latency=34.752474ms timeout=10s endpoint=http://cddb.volumio.org duration=1.87701804s error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 03 21:33:05 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 21:33:05 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 21:33:05 rivoplus volumio[3534]: info: Discovery: Getting this device information Oct 03 21:33:05 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:05 rivoplus volumio[3534]: info: CorePlayQueue::getTrack 2 Oct 03 21:33:05 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 21:33:05 rivoplus volumio[3534]: verbose: New Socket.io Connection to 192.168.0.15:3000 from 192.168.0.4 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Oct 03 21:33:05 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 03 21:33:05 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 03 21:33:09 rivoplus volumio[3534]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 21:33:09 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 03 21:33:09 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 21:33:09 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 21:33:09 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:09 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:09 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:09 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:09 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:09 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:09 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:09 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 03 21:33:09 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 03 21:33:09 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 03 21:33:09 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 03 21:33:09 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:09 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:12 rivoplus volumio[3534]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 03 21:33:12 rivoplus volumio[3534]: info: Preparing to save Alsa Options, stopping services first Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:12 rivoplus volumio[3534]: info: CorePlayQueue::getTrack 2 Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::volumioPause Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreStateMachine::pause Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreStateMachine::stPlaybackTimer Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreStateMachine::servicePause Oct 03 21:33:12 rivoplus volumio[3534]: info: CorePlayQueue::getTrack 2 Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::servicePause Oct 03 21:33:12 rivoplus volumio[3534]: info: [1791030792007] ControllerQobuz::pause Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 03 21:33:12 rivoplus volumio[3534]: info: ControllerMpd::pause Oct 03 21:33:12 rivoplus volumio[3534]: verbose: ControllerMpd::sendMpdCommand pause Oct 03 21:33:12 rivoplus volumio[3534]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"S/PDIF + AES/EBU + I2S"}} Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 21:33:12 rivoplus volumio[3534]: info: Found match in Cards Database for ignoring default Mixer Oct 03 21:33:12 rivoplus volumio[3534]: info: Setting mixer PCM for card S/PDIF + AES/EBU + I2S Oct 03 21:33:12 rivoplus volumio[3534]: info: QobuzConnect: setDeactiveState invoked Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:12 rivoplus volumio[3534]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 03 21:33:12 rivoplus vtcs[32285]: [2026-10-03 21:33:12.120] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 03 21:33:12 rivoplus vtcs[32285]: [2026-10-03 21:33:12.120] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 03 21:33:12 rivoplus volumio[3534]: info: Ignoring Mixers Options Oct 03 21:33:12 rivoplus volumio[3534]: info: Device 5 does not have any Mixer Control Available Oct 03 21:33:12 rivoplus volumio[3534]: info: QobuzConnect: setDeactiveState invoked Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:12 rivoplus volumio[3534]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 03 21:33:12 rivoplus sudo[1290]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 21:33:12 rivoplus sudo[1290]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:12 rivoplus sudo[1292]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 21:33:12 rivoplus sudo[1292]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 03 21:33:12 rivoplus volumio[3534]: info: Updating Volume Controller Parameters: Device: 5 Name: S/PDIF + AES/EBU + I2S Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 03 21:33:12 rivoplus volumio[3534]: info: Disabling external Volume Control Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreStateMachine::pushState Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::volumioPushState Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:12 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output update for this device Oct 03 21:33:12 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output Oct 03 21:33:12 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:12.208+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" state=STATUS_PLAYING positionMs=173016 volume=100 Oct 03 21:33:12 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:12.208+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" id=qobuz://song/4533262 title="Maria fumaça" Oct 03 21:33:12 rivoplus volumio[3534]: info: Preparing to generate the ALSA configuration file Oct 03 21:33:12 rivoplus systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 03 21:33:12 rivoplus systemd[1]: vtcs.service: Deactivated successfully. Oct 03 21:33:12 rivoplus systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 03 21:33:12 rivoplus sudo[1300]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 21:33:12 rivoplus sudo[1300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:12 rivoplus sudo[1292]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:12 rivoplus sudo[1290]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:12 rivoplus sudo[1308]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 21:33:12 rivoplus sudo[1308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:12 rivoplus sudo[1319]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 21:33:12 rivoplus sudo[1300]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:12 rivoplus sudo[1319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:12 rivoplus volumio[3534]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 21:33:12 rivoplus volumio[3534]: info: Reading ALSA contributions from plugins. Oct 03 21:33:12 rivoplus volumio[3534]: info: Oct 03 21:33:12 rivoplus volumio[3534]: ---------------------------- MPD announces state update: player Oct 03 21:33:12 rivoplus volumio[3534]: info: sendMpdCommand pause took 610 milliseconds Oct 03 21:33:12 rivoplus volumio[3534]: info: ControllerMpd::getState Oct 03 21:33:12 rivoplus volumio[3534]: verbose: ControllerMpd::sendMpdCommand status Oct 03 21:33:12 rivoplus volumio[3534]: info: Signalling Playback active due to playback status change Oct 03 21:33:12 rivoplus volumio[3534]: info: Updating RAAT Signal Path Oct 03 21:33:12 rivoplus volumio[3534]: info: Oct 03 21:33:12 rivoplus volumio[3534]: ---------------------------- MPD announces state update: player Oct 03 21:33:12 rivoplus volumio[3534]: info: ControllerMpd::getState Oct 03 21:33:12 rivoplus volumio[3534]: verbose: ControllerMpd::sendMpdCommand status Oct 03 21:33:12 rivoplus sudo[1334]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 21:33:12 rivoplus sudo[1334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:12 rivoplus sudo[1308]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:12 rivoplus sudo[1319]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:12 rivoplus volumio[3534]: info: Not Reporting Auto name since its the default one Oct 03 21:33:12 rivoplus sudo[1334]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:12 rivoplus sudo[1341]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 21:33:12 rivoplus sudo[1341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:12 rivoplus sudo[1343]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 21:33:12 rivoplus sudo[1343]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:12 rivoplus volumio[3534]: info: Not Reporting Auto name since its the default one Oct 03 21:33:12 rivoplus volumio[3534]: info: sendMpdCommand status took 144 milliseconds Oct 03 21:33:12 rivoplus volumio[3534]: info: sendMpdCommand status took 109 milliseconds Oct 03 21:33:12 rivoplus volumio[3534]: verbose: ControllerMpd::parseState Oct 03 21:33:12 rivoplus volumio[3534]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 21:33:12 rivoplus volumio[3534]: verbose: ControllerMpd::parseState Oct 03 21:33:12 rivoplus volumio[3534]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 21:33:12 rivoplus volumio[3534]: info: sendMpdCommand playlistinfo took 33 milliseconds Oct 03 21:33:12 rivoplus volumio[3534]: info: sendMpdCommand playlistinfo took 33 milliseconds Oct 03 21:33:12 rivoplus volumio[3534]: verbose: ControllerMpd::parseTrackInfo Oct 03 21:33:12 rivoplus volumio[3534]: verbose: ControllerMpd::parseTrackInfo Oct 03 21:33:12 rivoplus volumio[3534]: info: ControllerMpd::pushState Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::servicePushState Oct 03 21:33:12 rivoplus volumio[3534]: info: CorePlayQueue::getTrack 2 Oct 03 21:33:12 rivoplus volumio[3534]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":173085,"duration":573,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"925 Kbps","isStreaming":false,"title":"file?uid=3330030&eid=91722797&fmt=6&profile=raw&app_id=539451548&cid=2780867&etsp=1791034214&hmac=qoJZgUr9vY-xD_ydQscKND2z_qk","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=3330030&eid=91722797&fmt=6&profile=raw&app_id=539451548&cid=2780867&etsp=1791034214&hmac=qoJZgUr9vY-xD_ydQscKND2z_qk","trackType":"qobuz"} Oct 03 21:33:12 rivoplus volumio[3534]: verbose: CURRENT POSITION 2 Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreStateMachine::syncState stateService pause Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreStateMachine::syncState currentStatus pause Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreStateMachine::pushState Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::volumioPushState Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:12 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output update for this device Oct 03 21:33:12 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreStateMachine::stPlaybackTimer Oct 03 21:33:12 rivoplus volumio[3534]: info: ControllerMpd::pushState Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::servicePushState Oct 03 21:33:12 rivoplus volumio[3534]: info: CorePlayQueue::getTrack 2 Oct 03 21:33:12 rivoplus volumio[3534]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":173085,"duration":573,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"925 Kbps","isStreaming":false,"title":"file?uid=3330030&eid=91722797&fmt=6&profile=raw&app_id=539451548&cid=2780867&etsp=1791034214&hmac=qoJZgUr9vY-xD_ydQscKND2z_qk","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=3330030&eid=91722797&fmt=6&profile=raw&app_id=539451548&cid=2780867&etsp=1791034214&hmac=qoJZgUr9vY-xD_ydQscKND2z_qk","trackType":"qobuz"} Oct 03 21:33:12 rivoplus volumio[3534]: verbose: CURRENT POSITION 2 Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreStateMachine::syncState stateService pause Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreStateMachine::syncState currentStatus pause Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreStateMachine::pushState Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::volumioPushState Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:12 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output update for this device Oct 03 21:33:12 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreStateMachine::stPlaybackTimer Oct 03 21:33:12 rivoplus systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 03 21:33:12 rivoplus qobuz-connect[32179]: 20261003 21:33:12.812 [32179.32179] INFO SampleApp: Stopping Local configuration server Oct 03 21:33:12 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:12.822+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" state=STATUS_PAUSED positionMs=173674 volume=100 Oct 03 21:33:12 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:12.824+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" id=qobuz://song/4533262 title="Maria fumaça" Oct 03 21:33:12 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:12.831+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" state=STATUS_PAUSED positionMs=173674 volume=100 Oct 03 21:33:12 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:12.832+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" id=qobuz://song/4533262 title="Maria fumaça" Oct 03 21:33:12 rivoplus volumio[3534]: info: ------------------------------ 219ms Oct 03 21:33:12 rivoplus volumio[3534]: info: ------------------------------ 185ms Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 03 21:33:12 rivoplus volumio[3534]: info: Updating RAAT Signal Path Oct 03 21:33:12 rivoplus volumio[3534]: info: Updating RAAT Signal Path Oct 03 21:33:12 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 21:33:13 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 21:33:13 rivoplus sudo[1362]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 21:33:13 rivoplus sudo[1362]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:13 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:13 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:13 rivoplus sudo[1362]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:13 rivoplus sudo[1367]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 21:33:13 rivoplus sudo[1367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:13 rivoplus sudo[1368]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 21:33:13 rivoplus sudo[1368]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:13 rivoplus sudo[1367]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:13 rivoplus volumio[3534]: info: MCU Signalled Playback Inactive Oct 03 21:33:13 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 21:33:13 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 21:33:13 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 21:33:13 rivoplus volumio[3534]: info: Asound.conf file unchanged, so no further update is needed Oct 03 21:33:13 rivoplus volumio[3534]: info: Output device has changed, restarting MPD Oct 03 21:33:13 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 21:33:13 rivoplus sudo[1368]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:13 rivoplus sudo[1371]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 21:33:13 rivoplus sudo[1371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:13 rivoplus volumio[3534]: info: Output device has changed, restarting Shairport Sync Oct 03 21:33:13 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:13 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 21:33:13 rivoplus sudo[1374]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 21:33:13 rivoplus sudo[1374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:13 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 21:33:13 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 21:33:13 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 21:33:13 rivoplus sudo[1385]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 21:33:13 rivoplus sudo[1385]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:13 rivoplus sudo[1374]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:13 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 21:33:13 rivoplus sudo[1371]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:13 rivoplus volumio[3534]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 21:33:13 rivoplus volumio[3534]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 21:33:13 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:13 rivoplus volumio[3534]: info: QobuzConnect: setDeactiveState invoked Oct 03 21:33:13 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:13 rivoplus volumio[3534]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 21:33:13 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:13 rivoplus systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 03 21:33:13 rivoplus sudo[1408]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 21:33:13 rivoplus sudo[1408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:13 rivoplus sudo[1412]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 21:33:13 rivoplus sudo[1412]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:13 rivoplus volumio[3534]: info: MPD Permissions set Oct 03 21:33:13 rivoplus volumio[3534]: info: Raat Daemon started successfully Oct 03 21:33:13 rivoplus volumio[3534]: info: Raat Daemon started successfully Oct 03 21:33:13 rivoplus sudo[1418]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 21:33:13 rivoplus sudo[1418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:13 rivoplus volumio[3534]: info: Executing endpoint restartRAATSocket Oct 03 21:33:13 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 03 21:33:13 rivoplus sudo[1408]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:13 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:13 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:13 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:13 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:13 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:13 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:13 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:13 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:13 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 03 21:33:13 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 21:33:13 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 03 21:33:13 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 21:33:13 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 21:33:13 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 21:33:13 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 21:33:13 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 21:33:13 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:13 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 21:33:13 rivoplus volumio[3534]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 03 21:33:13 rivoplus volumio[3534]: info: Starting Shairport Sync Oct 03 21:33:13 rivoplus sudo[1418]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:13 rivoplus sudo[1412]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:14 rivoplus sudo[1431]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 21:33:14 rivoplus sudo[1431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:14 rivoplus sudo[1433]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 21:33:14 rivoplus sudo[1433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:14 rivoplus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 03 21:33:14 rivoplus systemd[1]: shairport-sync.service: Deactivated successfully. Oct 03 21:33:14 rivoplus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 21:33:14 rivoplus systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 21:33:14 rivoplus sudo[1433]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:14 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:14 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:14 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:14 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:14 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:14 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:14 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:14 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:14 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:14 rivoplus volumio[3534]: info: Not Reporting Auto name since its the default one Oct 03 21:33:14 rivoplus volumio[3534]: info: Shairport-Sync Started Oct 03 21:33:14 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 21:33:14 rivoplus sudo[1446]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 21:33:14 rivoplus sudo[1446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:14 rivoplus sudo[1446]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:14 rivoplus volumio[3534]: info: Executing endpoint metavolumio Oct 03 21:33:14 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 03 21:33:14 rivoplus volumio[3534]: info: Executing endpoint metavolumio Oct 03 21:33:14 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 03 21:33:14 rivoplus sudo[1464]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 21:33:14 rivoplus sudo[1464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:14 rivoplus volumio[3534]: info: Executing endpoint metavolumio Oct 03 21:33:14 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 03 21:33:14 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 21:33:14 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 21:33:14 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 21:33:14 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 21:33:14 rivoplus sudo[1464]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:14 rivoplus volumio[3534]: info: Raat Daemon started successfully Oct 03 21:33:14 rivoplus qobuz-connect[32179]: 20261003 21:33:14.551 [32179.32179] INFO SampleApp: shat down connection on UNIX socket Oct 03 21:33:14 rivoplus volumio[3534]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 03 21:33:14 rivoplus volumio[3534]: info: QobuzConnect: setDeactiveState invoked Oct 03 21:33:14 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:14 rivoplus systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 03 21:33:14 rivoplus systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 21:33:14 rivoplus volumio[3534]: info: Executing endpoint restartRAATSocket Oct 03 21:33:14 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 03 21:33:14 rivoplus systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 21:33:14 rivoplus sudo[1343]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:14 rivoplus sudo[1431]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:14 rivoplus sudo[1341]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:14 rivoplus sudo[1429]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 21:33:14 rivoplus sudo[1429]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 21:33:14 rivoplus volumio[3534]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 03 21:33:14 rivoplus sudo[1429]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:14 rivoplus volumio[3534]: info: Executing endpoint qc_getconfig Oct 03 21:33:14 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 03 21:33:14 rivoplus qobuz-connect[1490]: 20261003 21:33:14.797 [1490.1490] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 03 21:33:14 rivoplus volumio[3534]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 03 21:33:14 rivoplus volumio[3534]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 03 21:33:14 rivoplus qobuz-connect[1490]: 20261003 21:33:14.823 [1490.1490] INFO VolumeManager: [0xab6a1ac8]: Setting new playback volume: 75 Oct 03 21:33:14 rivoplus qobuz-connect[1490]: 20261003 21:33:14.823 [1490.1490] INFO VolumeManager: [0xab6a1ac8]: Setting new mute state: 0 Oct 03 21:33:14 rivoplus qobuz-connect[1490]: 20261003 21:33:14.823 [1490.1490] INFO AudioStreamManager: [0xab6a1820]: Setting new audio download buffer size: 1048576 Oct 03 21:33:14 rivoplus qobuz-connect[1490]: 20261003 21:33:14.823 [1490.1490] INFO QobuzConnect: [0xab6a2390]: Client initialized! Oct 03 21:33:14 rivoplus qobuz-connect[1490]: 20261003 21:33:14.823 [1490.1490] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Oct 03 21:33:14 rivoplus qobuz-connect[1490]: 20261003 21:33:14.835 [1490.1490] INFO LocalConfigManager: [0xab6a1548]: Starting Local Configuration server Oct 03 21:33:14 rivoplus qobuz-connect[1490]: 20261003 21:33:14.835 [1490.1490] INFO SampleApp: Starting Local configuration server Oct 03 21:33:14 rivoplus qobuz-connect[1490]: 20261003 21:33:14.835 [1490.1490] INFO SampleApp: Connected to UNIX socket client 0xab68c908 Oct 03 21:33:14 rivoplus qobuz-connect[1490]: 20261003 21:33:14.947 [1490.1490] INFO SampleApp: Playback volume changed: 75 Oct 03 21:33:14 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:15 rivoplus volumio[3534]: info: TidalConnect service stoped! Oct 03 21:33:15 rivoplus volumio[3534]: info: TidalConnect service stoped! Oct 03 21:33:15 rivoplus volumio[3534]: info: TidalConnect service stoped! Oct 03 21:33:15 rivoplus volumio[3534]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 21:33:15 rivoplus volumio[3534]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 21:33:15 rivoplus sudo[1509]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 21:33:15 rivoplus sudo[1509]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:15 rivoplus volumio[3534]: info: TidalConnect service stoped! Oct 03 21:33:15 rivoplus systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 03 21:33:15 rivoplus sudo[1509]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:15 rivoplus volumio[3534]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 21:33:15 rivoplus volumio[3534]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 21:33:15 rivoplus sudo[1523]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 21:33:15 rivoplus sudo[1523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:15 rivoplus sudo[1523]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:16 rivoplus volumio[3534]: info: RAAT: Requesting Headphone Status Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 21:33:16 rivoplus volumio[3534]: info: MCU Signalled Headphone Mode Disabled Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 03 21:33:16 rivoplus volumio[3534]: info: Reporting Headphone State: false Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:16 rivoplus volumio[3534]: info: Updating RAAT Signal Path Oct 03 21:33:16 rivoplus volumio[3534]: info: RAAT: Requesting Headphone Status Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreStateMachine::getcurrentVolume Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::volumioRetrievevolume Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreStateMachine::pushState Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::volumioPushState Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:16 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output update for this device Oct 03 21:33:16 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output Oct 03 21:33:16 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:16.194+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" state=STATUS_PAUSED positionMs=173674 volume=100 Oct 03 21:33:16 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:16.195+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" id=qobuz://song/4533262 title="Maria fumaça" Oct 03 21:33:16 rivoplus volumio[3534]: info: Updating RAAT Signal Path Oct 03 21:33:16 rivoplus volumio[3534]: info: MCU Signalled Headphone Mode Disabled Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 03 21:33:16 rivoplus volumio[3534]: info: Reporting Headphone State: false Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:16 rivoplus volumio[3534]: info: Updating RAAT Signal Path Oct 03 21:33:16 rivoplus volumio[3534]: info: Executing endpoint tc_getconfig Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 03 21:33:16 rivoplus vtcs[1517]: STARTING TidalConnect services, version: 1.6.1 Oct 03 21:33:16 rivoplus vtcs[1517]: STARTED TidalConnect services. Oct 03 21:33:16 rivoplus volumio[3534]: info: Executing endpoint tc_connect Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 03 21:33:16 rivoplus volumio[3534]: info: Connecting to TidalConnect Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::servicePushState Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreStateMachine::pushState Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::volumioPushState Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:16 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output update for this device Oct 03 21:33:16 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output Oct 03 21:33:16 rivoplus volumio[3534]: info: CorePlayQueue::getTrack 2 Oct 03 21:33:16 rivoplus volumio[3534]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::servicePushState Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreStateMachine::pushState Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::volumioPushState Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:16 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output update for this device Oct 03 21:33:16 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output Oct 03 21:33:16 rivoplus volumio[3534]: info: CorePlayQueue::getTrack 2 Oct 03 21:33:16 rivoplus volumio[3534]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Oct 03 21:33:16 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:16.268+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" state=STATUS_PAUSED positionMs=173674 volume=100 Oct 03 21:33:16 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:16.269+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" id=qobuz://song/4533262 title="Maria fumaça" Oct 03 21:33:16 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:16.269+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" state=STATUS_PAUSED positionMs=173674 volume=100 Oct 03 21:33:16 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:16.269+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" id=qobuz://song/4533262 title="Maria fumaça" Oct 03 21:33:16 rivoplus volumio[3534]: info: Updating RAAT Signal Path Oct 03 21:33:16 rivoplus volumio[3534]: info: Updating RAAT Signal Path Oct 03 21:33:16 rivoplus volumio[3534]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 03 21:33:16 rivoplus volumio[3534]: info: Preparing to save Alsa Options, stopping services first Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::volumioPause Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreStateMachine::pause Oct 03 21:33:16 rivoplus volumio[3534]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"ESOTERIC USB AUDIO DEVICE"}} Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 21:33:16 rivoplus volumio[3534]: info: Setting mixer Clock Source 12 Validity for card ESOTERIC USB AUDIO DEVICE Oct 03 21:33:16 rivoplus volumio[3534]: info: QobuzConnect: setDeactiveState invoked Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:16 rivoplus volumio[3534]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 03 21:33:16 rivoplus vtcs[1517]: [2026-10-03 21:33:16.576] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 03 21:33:16 rivoplus vtcs[1517]: [2026-10-03 21:33:16.577] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 03 21:33:16 rivoplus sudo[1549]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 21:33:16 rivoplus sudo[1549]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 03 21:33:16 rivoplus volumio[3534]: info: Updating Volume Controller Parameters: Device: 1 Name: ESOTERIC USB AUDIO DEVICE Mixer: Clock Source 12 Validity Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 03 21:33:16 rivoplus volumio[3534]: info: Disabling external Volume Control Oct 03 21:33:16 rivoplus volumio[3534]: info: VolumeController:: Starting alsactl monitor Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 21:33:16 rivoplus systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 03 21:33:16 rivoplus volumio[3534]: info: Preparing to generate the ALSA configuration file Oct 03 21:33:16 rivoplus systemd[1]: vtcs.service: Deactivated successfully. Oct 03 21:33:16 rivoplus systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 03 21:33:16 rivoplus sudo[1549]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:16 rivoplus sudo[1557]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 21:33:16 rivoplus sudo[1557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:16 rivoplus volumio[3534]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 21:33:16 rivoplus volumio[3534]: info: Reading ALSA contributions from plugins. Oct 03 21:33:16 rivoplus volumio[3534]: Unable to parse: Oct 03 21:33:16 rivoplus volumio[3534]: Simple mixer control 'Clock Source 12 Validity',0 Oct 03 21:33:16 rivoplus volumio[3534]: Capabilities: pswitch pswitch-joined Oct 03 21:33:16 rivoplus volumio[3534]: Playback channels: Mono Oct 03 21:33:16 rivoplus volumio[3534]: Mono: Playback [on] Oct 03 21:33:16 rivoplus volumio[3534]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Oct 03 21:33:16 rivoplus sudo[1563]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 21:33:16 rivoplus sudo[1563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 21:33:16 rivoplus sudo[1557]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:16 rivoplus sudo[1563]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:16 rivoplus sudo[1573]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 03 21:33:16 rivoplus sudo[1573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 03 21:33:16 rivoplus volumio[3534]: info: TidalConnect service stoped! Oct 03 21:33:16 rivoplus volumio[3534]: Unable to parse: Oct 03 21:33:16 rivoplus volumio[3534]: Simple mixer control 'Clock Source 12 Validity',0 Oct 03 21:33:16 rivoplus volumio[3534]: Capabilities: pswitch pswitch-joined Oct 03 21:33:16 rivoplus volumio[3534]: Playback channels: Mono Oct 03 21:33:16 rivoplus volumio[3534]: Mono: Playback [on] Oct 03 21:33:16 rivoplus volumio[3534]: info: VolumeController:: Volume=undefined Mute =false Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreStateMachine::pushState Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::volumioPushState Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:16 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output update for this device Oct 03 21:33:16 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output Oct 03 21:33:16 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:16.933+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" state=STATUS_PAUSED positionMs=173674 volume= Oct 03 21:33:16 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:16.933+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" id=qobuz://song/4533262 title="Maria fumaça" Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:16 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:16 rivoplus systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 03 21:33:16 rivoplus volumio[3534]: info: Asound.conf file written Oct 03 21:33:16 rivoplus qobuz-connect[1490]: 20261003 21:33:16.996 [1490.1490] INFO SampleApp: Stopping Local configuration server Oct 03 21:33:17 rivoplus sudo[1576]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 03 21:33:17 rivoplus sudo[1576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:17 rivoplus sudo[1576]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:17 rivoplus kernel: Fine tdm clk setting range (0~2000000), 12287994 Oct 03 21:33:17 rivoplus kernel: Fine spdif sysclk setting range(0~2000000), 6143997 Oct 03 21:33:17 rivoplus kernel: out of value, fixed it Oct 03 21:33:17 rivoplus kernel: id=0 set inskew=0 Oct 03 21:33:17 rivoplus volumio[3534]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 21:33:17 rivoplus volumio[3534]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 21:33:17 rivoplus volumio[3534]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 03 21:33:17 rivoplus volumio[3534]: Hardware is initialized using a generic method Oct 03 21:33:17 rivoplus volumio[3534]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Oct 03 21:33:17 rivoplus volumio[3534]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Oct 03 21:33:17 rivoplus volumio[3534]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Oct 03 21:33:17 rivoplus volumio[3534]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Oct 03 21:33:17 rivoplus volumio[3534]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Clock Source 12 Validity:0' : Operation not permitted Oct 03 21:33:17 rivoplus volumio[3534]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Oct 03 21:33:17 rivoplus volumio[3534]: info: Output device has changed, restarting MPD Oct 03 21:33:17 rivoplus volumio[3534]: info: Output device has changed, restarting Shairport Sync Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 21:33:17 rivoplus sudo[1581]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 21:33:17 rivoplus sudo[1581]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:17 rivoplus sudo[1581]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:17 rivoplus sudo[1583]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 21:33:17 rivoplus sudo[1583]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:17 rivoplus volumio[3534]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 21:33:17 rivoplus volumio[3534]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: QobuzConnect: setDeactiveState invoked Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:17 rivoplus volumio[3534]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: Output device has changed, restarting MPD Oct 03 21:33:17 rivoplus volumio[3534]: info: Output device has changed, restarting Shairport Sync Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 21:33:17 rivoplus sudo[1592]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 21:33:17 rivoplus sudo[1592]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:17 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 03 21:33:17 rivoplus sudo[1594]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 21:33:17 rivoplus sudo[1594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:17 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 21:33:17 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 03 21:33:17 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 21:33:17 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 21:33:17 rivoplus sudo[1594]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:17 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 21:33:17 rivoplus sudo[1598]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 21:33:17 rivoplus sudo[1598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:17 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 21:33:17 rivoplus volumio[3534]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 21:33:17 rivoplus volumio[3534]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: QobuzConnect: setDeactiveState invoked Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:17 rivoplus volumio[3534]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus sudo[1608]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 21:33:17 rivoplus sudo[1608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:17 rivoplus sudo[1610]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 21:33:17 rivoplus sudo[1610]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:17 rivoplus sudo[1592]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:17 rivoplus sudo[1613]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 21:33:17 rivoplus sudo[1613]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:17 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 03 21:33:17 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 21:33:17 rivoplus sudo[1621]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 21:33:17 rivoplus sudo[1621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:17 rivoplus sudo[1627]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 21:33:17 rivoplus sudo[1627]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: Not Reporting Auto name since its the default one Oct 03 21:33:17 rivoplus volumio[3534]: info: RAAT: Requesting Headphone Status Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 21:33:17 rivoplus volumio[3534]: info: VolumeController::SetAlsaVolume0 Oct 03 21:33:17 rivoplus sudo[1621]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreStateMachine::pushState Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::volumioPushState Oct 03 21:33:17 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 03 21:33:17 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:17 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output update for this device Oct 03 21:33:17 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output Oct 03 21:33:17 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 21:33:17 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:17.507+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" state=STATUS_PAUSED positionMs=173674 volume=0 Oct 03 21:33:17 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:17.508+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" id=qobuz://song/4533262 title="Maria fumaça" Oct 03 21:33:17 rivoplus volumio[3534]: info: VolumeController::SetAlsaVolume0 Oct 03 21:33:17 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreStateMachine::pushState Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::volumioPushState Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:17 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output update for this device Oct 03 21:33:17 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output Oct 03 21:33:17 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:17.549+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" state=STATUS_PAUSED positionMs=173674 volume=0 Oct 03 21:33:17 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:17.552+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" id=qobuz://song/4533262 title="Maria fumaça" Oct 03 21:33:17 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 21:33:17 rivoplus sudo[1634]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 21:33:17 rivoplus sudo[1634]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:17 rivoplus volumio[3534]: info: MPD Permissions set Oct 03 21:33:17 rivoplus volumio[3534]: info: MPD Permissions set Oct 03 21:33:17 rivoplus volumio[3534]: info: TidalConnect service stoped! Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreStateMachine::pushState Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::volumioPushState Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:17 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output update for this device Oct 03 21:33:17 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreStateMachine::pushState Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::volumioPushState Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:17 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output update for this device Oct 03 21:33:17 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output Oct 03 21:33:17 rivoplus volumio[3534]: info: MCU Signalled Headphone Mode Disabled Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 03 21:33:17 rivoplus volumio[3534]: info: Reporting Headphone State: false Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: Updating RAAT Signal Path Oct 03 21:33:17 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:17.656+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" state=STATUS_PAUSED positionMs=173674 volume=100 Oct 03 21:33:17 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:17.657+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" id=qobuz://song/4533262 title="Maria fumaça" Oct 03 21:33:17 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:17.659+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" state=STATUS_PAUSED positionMs=173674 volume=100 Oct 03 21:33:17 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:17.659+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" id=qobuz://song/4533262 title="Maria fumaça" Oct 03 21:33:17 rivoplus volumio[3534]: info: Updating RAAT Signal Path Oct 03 21:33:17 rivoplus volumio[3534]: info: Updating RAAT Signal Path Oct 03 21:33:17 rivoplus sudo[1610]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:17 rivoplus sudo[1613]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: Not Reporting Auto name since its the default one Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus sudo[1608]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 21:33:17 rivoplus sudo[1627]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 21:33:17 rivoplus sudo[1663]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 21:33:17 rivoplus sudo[1663]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:17 rivoplus volumio[3534]: info: Not Reporting Auto name since its the default one Oct 03 21:33:17 rivoplus sudo[1675]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 21:33:17 rivoplus sudo[1675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:17 rivoplus volumio[3534]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 03 21:33:17 rivoplus volumio[3534]: info: Preparing to save Alsa Options, stopping services first Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::volumioPause Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreStateMachine::pause Oct 03 21:33:17 rivoplus volumio[3534]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"ESOTERIC USB AUDIO DEVICE"}} Oct 03 21:33:17 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 21:33:17 rivoplus sudo[1675]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:18 rivoplus sudo[1683]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 21:33:18 rivoplus sudo[1683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:18 rivoplus volumio[3534]: info: Setting mixer Clock Source 12 Validity for card ESOTERIC USB AUDIO DEVICE Oct 03 21:33:18 rivoplus volumio[3534]: info: QobuzConnect: setDeactiveState invoked Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:18 rivoplus volumio[3534]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 03 21:33:18 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 21:33:18 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 21:33:18 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 21:33:18 rivoplus sudo[1638]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 21:33:18 rivoplus sudo[1638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 21:33:18 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 21:33:18 rivoplus sudo[1690]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 21:33:18 rivoplus sudo[1683]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:18 rivoplus sudo[1638]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:18 rivoplus sudo[1690]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 03 21:33:18 rivoplus volumio[3534]: info: Updating Volume Controller Parameters: Device: 1 Name: ESOTERIC USB AUDIO DEVICE Mixer: Clock Source 12 Validity Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 03 21:33:18 rivoplus volumio[3534]: info: Disabling external Volume Control Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 21:33:18 rivoplus volumio[3534]: info: Preparing to generate the ALSA configuration file Oct 03 21:33:18 rivoplus sudo[1703]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 21:33:18 rivoplus sudo[1703]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:18 rivoplus volumio[3534]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 21:33:18 rivoplus sudo[1690]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:18 rivoplus volumio[3534]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 21:33:18 rivoplus sudo[1710]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 21:33:18 rivoplus sudo[1710]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:18 rivoplus sudo[1713]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 21:33:18 rivoplus sudo[1713]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:18 rivoplus volumio[3534]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 21:33:18 rivoplus volumio[3534]: info: Reading ALSA contributions from plugins. Oct 03 21:33:18 rivoplus volumio[3534]: Unable to parse: Oct 03 21:33:18 rivoplus volumio[3534]: Simple mixer control 'Clock Source 12 Validity',0 Oct 03 21:33:18 rivoplus volumio[3534]: Capabilities: pswitch pswitch-joined Oct 03 21:33:18 rivoplus volumio[3534]: Playback channels: Mono Oct 03 21:33:18 rivoplus volumio[3534]: Mono: Playback [on] Oct 03 21:33:18 rivoplus volumio[3534]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Oct 03 21:33:18 rivoplus sudo[1710]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:18 rivoplus volumio[3534]: info: VolumeController:: alsactl monitor closed, code: 0 Oct 03 21:33:18 rivoplus volumio[3534]: info: VolumeController:: Starting alsactl monitor Oct 03 21:33:18 rivoplus sudo[1719]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 21:33:18 rivoplus sudo[1719]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:18 rivoplus volumio[3534]: info: Raat Daemon started successfully Oct 03 21:33:18 rivoplus volumio[3534]: info: Starting Shairport Sync Oct 03 21:33:18 rivoplus sudo[1721]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 21:33:18 rivoplus sudo[1721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:18 rivoplus sudo[1703]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:18 rivoplus volumio[3534]: info: Starting Shairport Sync Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 21:33:18 rivoplus sudo[1727]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 21:33:18 rivoplus sudo[1727]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:18 rivoplus sudo[1729]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 21:33:18 rivoplus sudo[1729]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:18 rivoplus sudo[1719]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:18 rivoplus volumio[3534]: Unable to parse: Oct 03 21:33:18 rivoplus volumio[3534]: Simple mixer control 'Clock Source 12 Validity',0 Oct 03 21:33:18 rivoplus volumio[3534]: Capabilities: pswitch pswitch-joined Oct 03 21:33:18 rivoplus volumio[3534]: Playback channels: Mono Oct 03 21:33:18 rivoplus volumio[3534]: Mono: Playback [on] Oct 03 21:33:18 rivoplus volumio[3534]: info: VolumeController:: Volume=undefined Mute =false Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreStateMachine::pushState Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::volumioPushState Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:18 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output update for this device Oct 03 21:33:18 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output Oct 03 21:33:18 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:18.512+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" state=STATUS_PAUSED positionMs=173674 volume=0 Oct 03 21:33:18 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:18.512+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" id=qobuz://song/4533262 title="Maria fumaça" Oct 03 21:33:18 rivoplus sudo[1735]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 21:33:18 rivoplus sudo[1735]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:18 rivoplus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 03 21:33:18 rivoplus sudo[1736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 21:33:18 rivoplus sudo[1736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:18 rivoplus systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 03 21:33:18 rivoplus sudo[1713]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:18 rivoplus systemd[1]: shairport-sync.service: Deactivated successfully. Oct 03 21:33:18 rivoplus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 21:33:18 rivoplus sudo[1736]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 03 21:33:18 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 21:33:18 rivoplus volumio[3534]: info: Updating RAAT Signal Path Oct 03 21:33:18 rivoplus systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 21:33:18 rivoplus sudo[1746]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 21:33:18 rivoplus sudo[1746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:18 rivoplus sudo[1727]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:18 rivoplus sudo[1729]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:18 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 21:33:18 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:18 rivoplus volumio[3534]: info: Not Reporting Auto name since its the default one Oct 03 21:33:18 rivoplus volumio[3534]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 03 21:33:18 rivoplus volumio[3534]: info: Preparing to save Alsa Options, stopping services first Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::volumioPause Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreStateMachine::pause Oct 03 21:33:18 rivoplus volumio[3534]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"ESOTERIC USB AUDIO DEVICE"}} Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 21:33:18 rivoplus qobuz-connect[1490]: 20261003 21:33:18.844 [1490.1490] INFO SampleApp: shat down connection on UNIX socket Oct 03 21:33:18 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 21:33:18 rivoplus systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 03 21:33:18 rivoplus systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 21:33:18 rivoplus sudo[1735]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:18 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 21:33:18 rivoplus systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 21:33:18 rivoplus sudo[1721]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:18 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 21:33:18 rivoplus sudo[1573]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:18 rivoplus sudo[1663]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:18 rivoplus sudo[1634]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:19 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 21:33:19 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 21:33:19 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 21:33:19 rivoplus volumio[3534]: info: Setting mixer Clock Source 12 Validity for card ESOTERIC USB AUDIO DEVICE Oct 03 21:33:19 rivoplus volumio[3534]: info: QobuzConnect: setDeactiveState invoked Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:19 rivoplus volumio[3534]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 03 21:33:19 rivoplus sudo[1746]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:19 rivoplus sudo[1789]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 21:33:19 rivoplus sudo[1789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 03 21:33:19 rivoplus volumio[3534]: info: Updating Volume Controller Parameters: Device: 1 Name: ESOTERIC USB AUDIO DEVICE Mixer: Clock Source 12 Validity Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 03 21:33:19 rivoplus volumio[3534]: info: Disabling external Volume Control Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 21:33:19 rivoplus systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 03 21:33:19 rivoplus systemd[1]: vtcs.service: Deactivated successfully. Oct 03 21:33:19 rivoplus systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 03 21:33:19 rivoplus sudo[1795]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 21:33:19 rivoplus sudo[1795]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:19 rivoplus sudo[1789]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:19 rivoplus volumio[3534]: info: TidalConnect service started! Oct 03 21:33:19 rivoplus volumio[3534]: info: Shairport-Sync Started Oct 03 21:33:19 rivoplus volumio[3534]: info: Shairport-Sync Started Oct 03 21:33:19 rivoplus volumio[3534]: Unable to parse: Oct 03 21:33:19 rivoplus volumio[3534]: Simple mixer control 'Clock Source 12 Validity',0 Oct 03 21:33:19 rivoplus volumio[3534]: Capabilities: pswitch pswitch-joined Oct 03 21:33:19 rivoplus volumio[3534]: Playback channels: Mono Oct 03 21:33:19 rivoplus volumio[3534]: Mono: Playback [on] Oct 03 21:33:19 rivoplus volumio[3534]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Oct 03 21:33:19 rivoplus sudo[1818]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 21:33:19 rivoplus sudo[1818]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:19 rivoplus volumio[3534]: info: VolumeController:: alsactl monitor closed, code: 0 Oct 03 21:33:19 rivoplus volumio[3534]: info: VolumeController:: Starting alsactl monitor Oct 03 21:33:19 rivoplus volumio[3534]: info: Raat Daemon started successfully Oct 03 21:33:19 rivoplus volumio[3534]: info: TidalConnect service started! Oct 03 21:33:19 rivoplus volumio[3534]: info: Raat Daemon started successfully Oct 03 21:33:19 rivoplus volumio[3534]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 03 21:33:19 rivoplus volumio[3534]: info: QobuzConnect: setDeactiveState invoked Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:19 rivoplus volumio[3534]: info: Executing endpoint qc_getconfig Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 03 21:33:19 rivoplus sudo[1795]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:19 rivoplus sudo[1818]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:19 rivoplus qobuz-connect[1776]: 20261003 21:33:19.524 [1776.1776] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:19 rivoplus qobuz-connect[1776]: 20261003 21:33:19.531 [1776.1776] INFO VolumeManager: [0xabeb9ac8]: Setting new playback volume: 75 Oct 03 21:33:19 rivoplus qobuz-connect[1776]: 20261003 21:33:19.532 [1776.1776] INFO VolumeManager: [0xabeb9ac8]: Setting new mute state: 0 Oct 03 21:33:19 rivoplus qobuz-connect[1776]: 20261003 21:33:19.532 [1776.1776] INFO AudioStreamManager: [0xabeb9820]: Setting new audio download buffer size: 1048576 Oct 03 21:33:19 rivoplus qobuz-connect[1776]: 20261003 21:33:19.532 [1776.1776] INFO QobuzConnect: [0xabeba390]: Client initialized! Oct 03 21:33:19 rivoplus qobuz-connect[1776]: 20261003 21:33:19.532 [1776.1776] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Oct 03 21:33:19 rivoplus qobuz-connect[1776]: 20261003 21:33:19.547 [1776.1776] INFO LocalConfigManager: [0xabeb9548]: Starting Local Configuration server Oct 03 21:33:19 rivoplus qobuz-connect[1776]: 20261003 21:33:19.547 [1776.1776] INFO SampleApp: Starting Local configuration server Oct 03 21:33:19 rivoplus qobuz-connect[1776]: 20261003 21:33:19.548 [1776.1776] INFO SampleApp: Connected to UNIX socket client 0xabea4908 Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 21:33:19 rivoplus sudo[1825]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 21:33:19 rivoplus sudo[1825]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:19 rivoplus volumio[3534]: info: Executing endpoint tc_getconfig Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 03 21:33:19 rivoplus qobuz-connect[1776]: 20261003 21:33:19.612 [1776.1776] INFO SampleApp: Stopping Local configuration server Oct 03 21:33:19 rivoplus systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 03 21:33:19 rivoplus volumio[3534]: Unable to parse: Oct 03 21:33:19 rivoplus volumio[3534]: Simple mixer control 'Clock Source 12 Validity',0 Oct 03 21:33:19 rivoplus volumio[3534]: Capabilities: pswitch pswitch-joined Oct 03 21:33:19 rivoplus volumio[3534]: Playback channels: Mono Oct 03 21:33:19 rivoplus volumio[3534]: Mono: Playback [on] Oct 03 21:33:19 rivoplus volumio[3534]: info: VolumeController:: Volume=undefined Mute =false Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreStateMachine::pushState Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::volumioPushState Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:19 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output update for this device Oct 03 21:33:19 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output Oct 03 21:33:19 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:19.637+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" state=STATUS_PAUSED positionMs=173674 volume=0 Oct 03 21:33:19 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:19.638+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" id=qobuz://song/4533262 title="Maria fumaça" Oct 03 21:33:19 rivoplus sudo[1832]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 21:33:19 rivoplus sudo[1832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:19 rivoplus volumio[3534]: info: Not Reporting Auto name since its the default one Oct 03 21:33:19 rivoplus sudo[1832]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 21:33:19 rivoplus sudo[1840]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 21:33:19 rivoplus sudo[1840]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 03 21:33:19 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 03 21:33:19 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 21:33:19 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 21:33:19 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 21:33:19 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 21:33:19 rivoplus sudo[1840]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:19 rivoplus volumio[3534]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 03 21:33:19 rivoplus volumio[3534]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 03 21:33:19 rivoplus volumio[3534]: info: Updating RAAT Signal Path Oct 03 21:33:19 rivoplus volumio[3534]: info: TidalConnect service stoped! Oct 03 21:33:19 rivoplus volumio[3534]: info: Raat Daemon started successfully Oct 03 21:33:19 rivoplus volumio[3534]: info: TidalConnect service stoped! Oct 03 21:33:19 rivoplus volumio[3534]: info: Asound.conf file written Oct 03 21:33:19 rivoplus sudo[1859]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 03 21:33:19 rivoplus sudo[1859]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:19 rivoplus sudo[1859]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:20 rivoplus kernel: Fine tdm clk setting range (0~2000000), 12287994 Oct 03 21:33:20 rivoplus kernel: Fine spdif sysclk setting range(0~2000000), 6143997 Oct 03 21:33:20 rivoplus kernel: out of value, fixed it Oct 03 21:33:20 rivoplus kernel: id=0 set inskew=0 Oct 03 21:33:20 rivoplus volumio[3534]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 21:33:20 rivoplus volumio[3534]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 21:33:20 rivoplus volumio[3534]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 03 21:33:20 rivoplus volumio[3534]: Hardware is initialized using a generic method Oct 03 21:33:20 rivoplus volumio[3534]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Oct 03 21:33:20 rivoplus volumio[3534]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Oct 03 21:33:20 rivoplus volumio[3534]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Oct 03 21:33:20 rivoplus volumio[3534]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Oct 03 21:33:20 rivoplus volumio[3534]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Clock Source 12 Validity:0' : Operation not permitted Oct 03 21:33:20 rivoplus volumio[3534]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Oct 03 21:33:20 rivoplus volumio[3534]: info: Output device has changed, restarting MPD Oct 03 21:33:20 rivoplus volumio[3534]: info: Output device has changed, restarting Shairport Sync Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 21:33:20 rivoplus sudo[1867]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 21:33:20 rivoplus sudo[1867]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:20 rivoplus sudo[1870]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 21:33:20 rivoplus sudo[1870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:20 rivoplus sudo[1867]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:20 rivoplus volumio[3534]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 21:33:20 rivoplus volumio[3534]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: QobuzConnect: setDeactiveState invoked Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:20 rivoplus volumio[3534]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: Preparing to generate the ALSA configuration file Oct 03 21:33:20 rivoplus volumio[3534]: info: Output device has changed, restarting MPD Oct 03 21:33:20 rivoplus sudo[1879]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 21:33:20 rivoplus sudo[1879]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:20 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 03 21:33:20 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 21:33:20 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 03 21:33:20 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 21:33:20 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 21:33:20 rivoplus volumio[3534]: info: Output device has changed, restarting Shairport Sync Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 21:33:20 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 21:33:20 rivoplus sudo[1882]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 21:33:20 rivoplus sudo[1882]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:20 rivoplus volumio[3534]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 21:33:20 rivoplus volumio[3534]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus sudo[1882]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:20 rivoplus sudo[1885]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 21:33:20 rivoplus sudo[1885]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:20 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 21:33:20 rivoplus volumio[3534]: info: QobuzConnect: setDeactiveState invoked Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:20 rivoplus volumio[3534]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus sudo[1895]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 21:33:20 rivoplus sudo[1895]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:20 rivoplus sudo[1898]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 21:33:20 rivoplus sudo[1898]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:20 rivoplus sudo[1900]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 21:33:20 rivoplus sudo[1900]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:20 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 03 21:33:20 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 21:33:20 rivoplus sudo[1908]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 21:33:20 rivoplus sudo[1908]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:20 rivoplus sudo[1879]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:20 rivoplus sudo[1913]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 21:33:20 rivoplus sudo[1913]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:20 rivoplus volumio[3534]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 21:33:20 rivoplus volumio[3534]: info: Reading ALSA contributions from plugins. Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: MPD Permissions set Oct 03 21:33:20 rivoplus sudo[1920]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 21:33:20 rivoplus sudo[1920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:20 rivoplus volumio[3534]: info: MPD Permissions set Oct 03 21:33:20 rivoplus volumio[3534]: info: TidalConnect service stoped! Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreStateMachine::pushState Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::volumioPushState Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:20 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output update for this device Oct 03 21:33:20 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output Oct 03 21:33:20 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:20.550+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" state=STATUS_PAUSED positionMs=173674 volume=100 Oct 03 21:33:20 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:20.551+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" id=qobuz://song/4533262 title="Maria fumaça" Oct 03 21:33:20 rivoplus sudo[1913]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:20 rivoplus sudo[1908]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:20 rivoplus sudo[1920]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreStateMachine::pushState Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::volumioPushState Oct 03 21:33:20 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:20 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output update for this device Oct 03 21:33:20 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output Oct 03 21:33:20 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:20.583+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" state=STATUS_PAUSED positionMs=173674 volume=100 Oct 03 21:33:20 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:20.583+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" id=qobuz://song/4533262 title="Maria fumaça" Oct 03 21:33:20 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 21:33:20 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 21:33:20 rivoplus sudo[1926]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 21:33:20 rivoplus sudo[1926]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:20 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 21:33:20 rivoplus volumio[3534]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 21:33:20 rivoplus volumio[3534]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 21:33:20 rivoplus sudo[1928]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 21:33:20 rivoplus sudo[1928]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:20 rivoplus sudo[1927]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 21:33:20 rivoplus sudo[1927]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:20 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 21:33:20 rivoplus sudo[1934]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 21:33:20 rivoplus sudo[1934]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: Not Reporting Auto name since its the default one Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreStateMachine::getcurrentVolume Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::volumioRetrievevolume Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 21:33:20 rivoplus sudo[1898]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus sudo[1900]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:20 rivoplus volumio[3534]: info: Not Reporting Auto name since its the default one Oct 03 21:33:20 rivoplus sudo[1895]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:20 rivoplus volumio[3534]: info: TidalConnect service stoped! Oct 03 21:33:20 rivoplus volumio[3534]: info: TidalConnect service stoped! Oct 03 21:33:20 rivoplus volumio[3534]: Unable to parse: Oct 03 21:33:20 rivoplus volumio[3534]: Simple mixer control 'Clock Source 12 Validity',0 Oct 03 21:33:20 rivoplus volumio[3534]: Capabilities: pswitch pswitch-joined Oct 03 21:33:20 rivoplus volumio[3534]: Playback channels: Mono Oct 03 21:33:20 rivoplus volumio[3534]: Mono: Playback [on] Oct 03 21:33:20 rivoplus volumio[3534]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Oct 03 21:33:20 rivoplus volumio[3534]: info: TidalConnect service stoped! Oct 03 21:33:20 rivoplus volumio[3534]: info: Starting Shairport Sync Oct 03 21:33:20 rivoplus volumio[3534]: info: Starting Shairport Sync Oct 03 21:33:20 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 21:33:20 rivoplus sudo[1975]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 21:33:20 rivoplus sudo[1975]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:20 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 21:33:20 rivoplus sudo[1980]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 21:33:20 rivoplus sudo[1980]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:20 rivoplus systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 03 21:33:21 rivoplus sudo[1934]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:21 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 21:33:21 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 21:33:21 rivoplus volumio[3534]: Unable to parse: Oct 03 21:33:21 rivoplus volumio[3534]: Simple mixer control 'Clock Source 12 Validity',0 Oct 03 21:33:21 rivoplus volumio[3534]: Capabilities: pswitch pswitch-joined Oct 03 21:33:21 rivoplus volumio[3534]: Playback channels: Mono Oct 03 21:33:21 rivoplus volumio[3534]: Mono: Playback [on] Oct 03 21:33:21 rivoplus volumio[3534]: info: VolumeController:: Volume=undefined Mute =false Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreStateMachine::pushState Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::volumioPushState Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:21 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output update for this device Oct 03 21:33:21 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreStateMachine::updateTrackBlock Oct 03 21:33:21 rivoplus volumio[3534]: info: CorePlayQueue::getTrackBlock Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::volumioRetrievevolume Oct 03 21:33:21 rivoplus sudo[1988]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 21:33:21 rivoplus sudo[1988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:21 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 21:33:21 rivoplus volumio[3534]: info: RAAT: Requesting Headphone Status Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 21:33:21 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:21.145+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" state=STATUS_PAUSED positionMs=173674 volume=0 Oct 03 21:33:21 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:21.170+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" id=qobuz://song/4533262 title="Maria fumaça" Oct 03 21:33:21 rivoplus volumio[3534]: info: Updating RAAT Signal Path Oct 03 21:33:21 rivoplus volumio[3534]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 21:33:21 rivoplus volumio[3534]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 21:33:21 rivoplus sudo[1928]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:21 rivoplus systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 03 21:33:21 rivoplus sudo[2005]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 21:33:21 rivoplus sudo[2005]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:21 rivoplus systemd[1]: shairport-sync.service: Deactivated successfully. Oct 03 21:33:21 rivoplus systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 21:33:21 rivoplus volumio[3534]: info: Asound.conf file unchanged, so no further update is needed Oct 03 21:33:21 rivoplus volumio[3534]: info: Output device has changed, restarting MPD Oct 03 21:33:21 rivoplus sudo[1936]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 21:33:21 rivoplus sudo[1936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 21:33:21 rivoplus sudo[2012]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 21:33:21 rivoplus sudo[2012]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:21 rivoplus sudo[1936]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:21 rivoplus systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 21:33:21 rivoplus volumio[3534]: info: Output device has changed, restarting Shairport Sync Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 21:33:21 rivoplus sudo[1980]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:21 rivoplus sudo[1975]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:21 rivoplus sudo[2015]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 21:33:21 rivoplus sudo[2015]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:21 rivoplus sudo[2015]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:21 rivoplus sudo[1988]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:21 rivoplus sudo[2020]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 21:33:21 rivoplus sudo[2020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:21 rivoplus volumio[3534]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 21:33:21 rivoplus volumio[3534]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:21 rivoplus volumio[3534]: info: QobuzConnect: setDeactiveState invoked Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:21 rivoplus volumio[3534]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:21 rivoplus sudo[2026]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 21:33:21 rivoplus sudo[2026]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:21 rivoplus volumio[3534]: info: Preparing to generate the ALSA configuration file Oct 03 21:33:21 rivoplus sudo[2012]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:21 rivoplus qobuz-connect[1776]: 20261003 21:33:21.569 [1776.1776] INFO SampleApp: shat down connection on UNIX socket Oct 03 21:33:21 rivoplus sudo[2032]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 21:33:21 rivoplus sudo[2032]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:21 rivoplus systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 03 21:33:21 rivoplus systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 21:33:21 rivoplus sudo[2045]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 21:33:21 rivoplus systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 21:33:21 rivoplus sudo[2045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:21 rivoplus sudo[2049]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 21:33:21 rivoplus sudo[2049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:21 rivoplus systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 21:33:21 rivoplus sudo[1825]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:21 rivoplus sudo[1927]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:21 rivoplus sudo[1926]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:21 rivoplus volumio[3534]: Unable to parse: Oct 03 21:33:21 rivoplus volumio[3534]: Simple mixer control 'Clock Source 12 Validity',0 Oct 03 21:33:21 rivoplus volumio[3534]: Capabilities: pswitch pswitch-joined Oct 03 21:33:21 rivoplus volumio[3534]: Playback channels: Mono Oct 03 21:33:21 rivoplus volumio[3534]: Mono: Playback [on] Oct 03 21:33:21 rivoplus volumio[3534]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Oct 03 21:33:21 rivoplus sudo[2005]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:21 rivoplus sudo[2057]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 21:33:21 rivoplus sudo[2057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:21 rivoplus volumio[3534]: info: RAAT: Requesting Headphone Status Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 21:33:21 rivoplus volumio[3534]: info: RAAT: Requesting Headphone Status Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:21 rivoplus volumio[3534]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 21:33:21 rivoplus volumio[3534]: info: Reading ALSA contributions from plugins. Oct 03 21:33:21 rivoplus systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 21:33:21 rivoplus systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 21:33:21 rivoplus volumio[3534]: info: MPD Permissions set Oct 03 21:33:21 rivoplus volumio[3534]: info: TidalConnect service started! Oct 03 21:33:21 rivoplus volumio[3534]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 03 21:33:21 rivoplus volumio[3534]: info: QobuzConnect: setDeactiveState invoked Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:21 rivoplus volumio[3534]: info: TidalConnect service stoped! Oct 03 21:33:21 rivoplus systemd[1]: mpd.service: Deactivated successfully. Oct 03 21:33:21 rivoplus volumio[3534]: info: Shairport-Sync Started Oct 03 21:33:21 rivoplus volumio[3534]: (node:3534) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 03 21:33:21 rivoplus volumio[3534]: (node:3534) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 03 21:33:21 rivoplus volumio[3534]: (node:3534) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 03 21:33:21 rivoplus volumio[3534]: (node:3534) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 03 21:33:21 rivoplus volumio[3534]: (node:3534) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 03 21:33:21 rivoplus volumio[3534]: info: Shairport-Sync Started Oct 03 21:33:21 rivoplus volumio[3534]: info: TidalConnect service stoped! Oct 03 21:33:21 rivoplus volumio[3534]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 21:33:21 rivoplus volumio[3534]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 21:33:21 rivoplus systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 21:33:21 rivoplus volumio[3534]: info: Raat Daemon started successfully Oct 03 21:33:21 rivoplus volumio[3534]: info: MCU Signalled Headphone Mode Disabled Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 03 21:33:21 rivoplus volumio[3534]: info: Reporting Headphone State: false Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:21 rivoplus volumio[3534]: info: Updating RAAT Signal Path Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreStateMachine::pushState Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::volumioPushState Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:21 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output update for this device Oct 03 21:33:21 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output Oct 03 21:33:21 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:21.827+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" state=STATUS_PAUSED positionMs=173674 volume=100 Oct 03 21:33:21 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:21.828+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" id=qobuz://song/4533262 title="Maria fumaça" Oct 03 21:33:21 rivoplus volumio[3534]: info: Executing endpoint restartRAATSocket Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 03 21:33:21 rivoplus volumio[3534]: Unable to parse: Oct 03 21:33:21 rivoplus volumio[3534]: Simple mixer control 'Clock Source 12 Validity',0 Oct 03 21:33:21 rivoplus volumio[3534]: Capabilities: pswitch pswitch-joined Oct 03 21:33:21 rivoplus volumio[3534]: Playback channels: Mono Oct 03 21:33:21 rivoplus volumio[3534]: Mono: Playback [on] Oct 03 21:33:21 rivoplus volumio[3534]: info: VolumeController:: Volume=undefined Mute =false Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreStateMachine::pushState Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::volumioPushState Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::volumioGetState Oct 03 21:33:21 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output update for this device Oct 03 21:33:21 rivoplus volumio[3534]: info: MRS: Pushing multiroomSync output Oct 03 21:33:21 rivoplus sudo[2068]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 21:33:21 rivoplus sudo[2068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:21 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:21.886+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" state=STATUS_PAUSED positionMs=173674 volume=0 Oct 03 21:33:21 rivoplus volumio5-onboarding[3841]: time=2026-10-03T21:33:21.887+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.4:54359,00:00:00:00:00:00%0e @ 0x18ad0b0" id=qobuz://song/4533262 title="Maria fumaça" Oct 03 21:33:21 rivoplus volumio[3534]: info: MCU Signalled Headphone Mode Disabled Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 03 21:33:21 rivoplus volumio[3534]: info: Reporting Headphone State: false Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:21 rivoplus volumio[3534]: info: Updating RAAT Signal Path Oct 03 21:33:21 rivoplus volumio[3534]: info: MCU Signalled Headphone Mode Disabled Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 03 21:33:21 rivoplus volumio[3534]: info: Reporting Headphone State: false Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:21 rivoplus volumio[3534]: info: Updating RAAT Signal Path Oct 03 21:33:21 rivoplus volumio[3534]: info: Updating RAAT Signal Path Oct 03 21:33:21 rivoplus volumio[3534]: info: Executing endpoint tc_getconfig Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:21 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:21 rivoplus vtcs[1985]: STARTING TidalConnect services, version: 1.6.1 Oct 03 21:33:21 rivoplus vtcs[1985]: STARTED TidalConnect services. Oct 03 21:33:21 rivoplus sudo[2057]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:21 rivoplus sudo[2049]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:21 rivoplus sudo[2032]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:21 rivoplus systemd[1]: mpd.socket: Deactivated successfully. Oct 03 21:33:21 rivoplus systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 21:33:21 rivoplus systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 21:33:22 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 21:33:22 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 21:33:22 rivoplus systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 21:33:22 rivoplus sudo[2085]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 21:33:22 rivoplus sudo[2085]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:22 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:22 rivoplus volumio[3534]: info: Executing endpoint qc_getconfig Oct 03 21:33:22 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 03 21:33:22 rivoplus systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 21:33:22 rivoplus sudo[2045]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:22 rivoplus sudo[2026]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:22 rivoplus systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 21:33:22 rivoplus sudo[2068]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:22 rivoplus volumio[3534]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 21:33:22 rivoplus volumio[3534]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 21:33:22 rivoplus qobuz-connect[2055]: 20261003 21:33:22.155 [2055.2055] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 03 21:33:22 rivoplus systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 03 21:33:22 rivoplus systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 03 21:33:22 rivoplus systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 21:33:22 rivoplus systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 21:33:22 rivoplus sudo[2109]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 21:33:22 rivoplus sudo[2109]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 21:33:22 rivoplus sudo[2085]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:22 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:22 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:22 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:22 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:22 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:22 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:22 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:22 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 21:33:22 rivoplus volumio[3534]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 21:33:22 rivoplus volumio[3534]: info: Not Reporting Auto name since its the default one Oct 03 21:33:22 rivoplus volumio[3534]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 03 21:33:22 rivoplus volumio[3534]: info: CoreStateMachine::getcurrentVolume Oct 03 21:33:22 rivoplus volumio[3534]: info: CoreCommandRouter::volumioRetrievevolume Oct 03 21:33:22 rivoplus volumio[3534]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 03 21:33:22 rivoplus volumio[3534]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 03 21:33:22 rivoplus volumio[3534]: info: Raat Daemon started successfully Oct 03 21:33:22 rivoplus volumio[3534]: info: Raat Daemon started successfully Oct 03 21:33:22 rivoplus volumio[3534]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 21:33:22 rivoplus sudo[2109]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:22 rivoplus volumio[3534]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Oct 03 21:33:22 rivoplus volumio[3534]: Job for vtcs.service canceled. Oct 03 21:33:22 rivoplus volumio[3534]: at ChildProcess.exithandler (node:child_process:421:12) Oct 03 21:33:22 rivoplus volumio[3534]: at ChildProcess.emit (node:events:514:28) Oct 03 21:33:22 rivoplus volumio[3534]: at maybeClose (node:internal/child_process:1105:16) Oct 03 21:33:22 rivoplus volumio[3534]: at Socket. (node:internal/child_process:457:11) Oct 03 21:33:22 rivoplus volumio[3534]: at Socket.emit (node:events:514:28) Oct 03 21:33:22 rivoplus volumio[3534]: at Pipe. (node:net:337:12) { Oct 03 21:33:22 rivoplus volumio[3534]: code: 1, Oct 03 21:33:22 rivoplus volumio[3534]: killed: false, Oct 03 21:33:22 rivoplus volumio[3534]: signal: null, Oct 03 21:33:22 rivoplus volumio[3534]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Oct 03 21:33:22 rivoplus volumio[3534]: stdout: '', Oct 03 21:33:22 rivoplus volumio[3534]: stderr: 'Job for vtcs.service canceled.\n' Oct 03 21:33:22 rivoplus volumio[3534]: } Oct 03 21:33:22 rivoplus volumio[3534]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 21:33:22 rivoplus sudo[2096]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 21:33:22 rivoplus sudo[2096]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 21:33:22 rivoplus sudo[2096]: pam_unix(sudo:session): session closed for user root Oct 03 21:33:23 rivoplus sudo[2129]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-03 21:32' Oct 03 21:33:23 rivoplus sudo[2129]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="8ed05bf20f96eb8557ed5652c420f84f39d66325" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0a52c1fb90959ba1872a2fb0225e56fe615b19ac" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Sep 16 16:52:40 UTC 2026" VOLUMIO_VERSION="4.208" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="ba37532a86808e19439a6278b2d45f47"