Aug 27 21:22:01 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:22:01 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:22:03 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:22:03 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:22:07 volumio volumio[1250]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Aug 27 21:22:10 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:22:10 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:22:11 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:22:11 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:22:17 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:22:17 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:22:19 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:22:19 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:22:20 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:22:20 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:22:25 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:22:25 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:22:26 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:22:26 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:22:27 volumio volumio[1250]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Aug 27 21:22:29 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:22:29 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:22:29 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:22:29 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:22:31 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:22:31 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:22:33 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:22:33 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:22:40 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:22:40 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:22:41 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:22:41 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:22:47 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:22:47 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:22:47 volumio volumio[1250]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Aug 27 21:22:49 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:22:49 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:22:50 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:22:50 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:22:55 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:22:55 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:22:56 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:22:56 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:22:59 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:22:59 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:22:59 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:22:59 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:23:01 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:23:01 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:23:03 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:23:03 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:23:07 volumio volumio[1250]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Aug 27 21:23:10 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:23:10 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:23:11 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:23:11 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:23:13 volumio kernel: usb 3-1: new high-speed USB device number 3 using xhci-hcd Aug 27 21:23:14 volumio kernel: usb 3-1: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 2.06 Aug 27 21:23:14 volumio kernel: usb 3-1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Aug 27 21:23:14 volumio kernel: usb 3-1: Product: SMSL USB AUDIO Aug 27 21:23:14 volumio kernel: usb 3-1: Manufacturer: SMSL Aug 27 21:23:14 volumio kernel: hid-generic 0003:152A:85DD.0002: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-xhci-hcd.1-1/input3 Aug 27 21:23:14 volumio volumio[1250]: info: Aug 27 21:23:14 volumio volumio[1250]: ---------------------------- USB Audio Device Attached Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::Close All Modals sent Aug 27 21:23:14 volumio volumio[1250]: info: Preparing to save Alsa Options, stopping services first Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Aug 27 21:23:14 volumio volumio[1250]: info: CorePlayQueue::getTrack 9 Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::volumioPause Aug 27 21:23:14 volumio volumio[1250]: info: CoreStateMachine::pause Aug 27 21:23:14 volumio volumio[1250]: info: CoreStateMachine::stPlaybackTimer Aug 27 21:23:14 volumio volumio[1250]: info: CoreStateMachine::servicePause Aug 27 21:23:14 volumio volumio[1250]: info: CorePlayQueue::getTrack 9 Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::servicePause Aug 27 21:23:14 volumio volumio[1250]: info: [1787854994841] ControllerTidal::pause Aug 27 21:23:14 volumio volumio[1250]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 27 21:23:14 volumio volumio[1250]: info: ControllerMpd::pause Aug 27 21:23:14 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand pause Aug 27 21:23:14 volumio volumio[1250]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"SMSL USB AUDIO","alsacard":"AUDIO"},"i2s":false} Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 21:23:14 volumio volumio[1250]: info: Device 5 does not have any Mixer Control Available Aug 27 21:23:14 volumio volumio[1250]: info: QobuzConnect: setDeactiveState invoked Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Aug 27 21:23:14 volumio vtcs[2023]: [2026-08-27 21:23:14.864] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Aug 27 21:23:14 volumio vtcs[2023]: [2026-08-27 21:23:14.864] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Aug 27 21:23:14 volumio sudo[16233]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 21:23:14 volumio sudo[16233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 27 21:23:14 volumio volumio[1250]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 27 21:23:14 volumio volumio[1250]: info: Disabling external Volume Control Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 21:23:14 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Aug 27 21:23:14 volumio systemd[1]: vtcs.service: Deactivated successfully. Aug 27 21:23:14 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Aug 27 21:23:14 volumio systemd[1]: vtcs.service: Consumed 1.667s CPU time. Aug 27 21:23:14 volumio volumio[1250]: info: CoreStateMachine::pushState Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Aug 27 21:23:14 volumio volumio[1250]: info: MRS: Pushing multiroomSync output update for this device Aug 27 21:23:14 volumio volumio[1250]: info: MRS: Pushing multiroomSync output Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Aug 27 21:23:14 volumio volumio5-onboarding[1436]: time=2026-08-27T21:23:14.891+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:47724,00:00:00:00:00:00%01 @ 0x31971a0" state=STATUS_PLAYING positionMs=408385 volume=100 Aug 27 21:23:14 volumio volumio5-onboarding[1436]: time=2026-08-27T21:23:14.892+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:47724,00:00:00:00:00:00%01 @ 0x31971a0" id=tidal://song/121542990 title=Vintage Aug 27 21:23:14 volumio sudo[16233]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:14 volumio volumio[1250]: info: Preparing to generate the ALSA configuration file Aug 27 21:23:14 volumio sudo[16240]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 21:23:14 volumio sudo[16240]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:14 volumio volumio[1250]: info: FusionDsp - Volumio is playing Aug 27 21:23:14 volumio volumio[1250]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 27 21:23:14 volumio volumio[1250]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 27 21:23:14 volumio volumio[1250]: info: Reading ALSA contributions from plugins. Aug 27 21:23:14 volumio volumio[1250]: info: Aug 27 21:23:14 volumio volumio[1250]: ---------------------------- MPD announces state update: player Aug 27 21:23:14 volumio volumio[1250]: info: sendMpdCommand pause took 72 milliseconds Aug 27 21:23:14 volumio volumio[1250]: info: ControllerMpd::getState Aug 27 21:23:14 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status Aug 27 21:23:14 volumio volumio[1250]: info: sendMpdCommand status took 1 milliseconds Aug 27 21:23:14 volumio volumio[1250]: verbose: ControllerMpd::parseState Aug 27 21:23:14 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 21:23:14 volumio volumio[1250]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 21:23:14 volumio volumio[1250]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Aug 27 21:23:14 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 27 21:23:14 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo Aug 27 21:23:14 volumio volumio[1250]: info: ControllerMpd::pushState Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Aug 27 21:23:14 volumio volumio[1250]: info: CorePlayQueue::getTrack 9 Aug 27 21:23:14 volumio volumio[1250]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":47202,"duration":483,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"491 Kbps","isStreaming":false,"title":"0.flac?token=1787854591~Y2Q5YjBkNmE3MzMyNDc4NTYyNmRkZTU3Y2ExNjhjODkwYWI5MzlkMg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEiczM2VkNWFkZDRmOGRmYTlkZDM0N2MxOGQ5MDA4MmI5M182MS5tcDQ/0.flac?token=1787854591~Y2Q5YjBkNmE3MzMyNDc4NTYyNmRkZTU3Y2ExNjhjODkwYWI5MzlkMg==","trackType":"tidal"} Aug 27 21:23:14 volumio volumio[1250]: verbose: CURRENT POSITION 9 Aug 27 21:23:14 volumio volumio[1250]: info: CoreStateMachine::syncState stateService pause Aug 27 21:23:14 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus pause Aug 27 21:23:14 volumio volumio[1250]: info: CoreStateMachine::pushState Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Aug 27 21:23:14 volumio volumio[1250]: info: MRS: Pushing multiroomSync output update for this device Aug 27 21:23:14 volumio volumio[1250]: info: MRS: Pushing multiroomSync output Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Aug 27 21:23:14 volumio volumio[1250]: info: CoreStateMachine::stPlaybackTimer Aug 27 21:23:14 volumio volumio5-onboarding[1436]: time=2026-08-27T21:23:14.917+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:47724,00:00:00:00:00:00%01 @ 0x31971a0" state=STATUS_PAUSED positionMs=408385 volume=100 Aug 27 21:23:14 volumio volumio5-onboarding[1436]: time=2026-08-27T21:23:14.917+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:47724,00:00:00:00:00:00%01 @ 0x31971a0" id=tidal://song/121542990 title=Vintage Aug 27 21:23:14 volumio volumio[1250]: info: ------------------------------ 5ms Aug 27 21:23:14 volumio volumio[1250]: info: FusionDsp - Volumio is not playing Aug 27 21:23:14 volumio volumio[1250]: info: FusionDsp - Clipped samples monitor stopped Aug 27 21:23:14 volumio sudo[16247]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 27 21:23:14 volumio sudo[16247]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:14 volumio sudo[16240]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 21:23:14 volumio sudo[16247]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 27 21:23:14 volumio volumio[1250]: info: Asound.conf file unchanged, so no further update is needed Aug 27 21:23:14 volumio volumio[1250]: info: Output device has changed, restarting MPD Aug 27 21:23:14 volumio sudo[16257]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 27 21:23:14 volumio volumio[1250]: info: Output device has changed, restarting Shairport Sync Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 21:23:14 volumio sudo[16257]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:14 volumio sudo[16259]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 21:23:14 volumio sudo[16259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:14 volumio sudo[16261]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 21:23:14 volumio sudo[16261]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:14 volumio volumio[1250]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 21:23:14 volumio volumio[1250]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 27 21:23:14 volumio sudo[16259]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 21:23:14 volumio volumio[1250]: info: QobuzConnect: setDeactiveState invoked Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Aug 27 21:23:14 volumio sudo[16272]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 21:23:14 volumio sudo[16272]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:14 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 27 21:23:14 volumio qobuz-connect[1948]: 20260827 21:23:14.975 [1948.1948] INFO SampleApp: Stopping Local configuration server Aug 27 21:23:14 volumio sudo[16275]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 21:23:14 volumio sudo[16275]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:14 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Aug 27 21:23:14 volumio volumio[1250]: info: MPD Permissions set Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 21:23:14 volumio sudo[16282]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 27 21:23:14 volumio sudo[16282]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:15 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 21:23:15 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 21:23:15 volumio volumio[1250]: info: Starting Shairport Sync Aug 27 21:23:15 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 27 21:23:15 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 21:23:15 volumio systemd[1]: mpd.service: Consumed 23.610s CPU time. Aug 27 21:23:15 volumio sudo[16290]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 21:23:15 volumio sudo[16290]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:15 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 27 21:23:15 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 21:23:15 volumio sudo[16282]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:15 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 21:23:15 volumio sudo[16293]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 27 21:23:15 volumio sudo[16293]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:15 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 21:23:15 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 21:23:15 volumio sudo[16272]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:15 volumio sudo[16275]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:15 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 27 21:23:15 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 27 21:23:15 volumio sudo[16295]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 21:23:15 volumio sudo[16295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 21:23:15 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 21:23:15 volumio systemd[1]: shairport-sync.service: Consumed 9.387s CPU time. Aug 27 21:23:15 volumio sudo[16295]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:15 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 21:23:15 volumio sudo[16290]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:15 volumio volumio[1250]: info: Shairport-Sync Started Aug 27 21:23:15 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Aug 27 21:23:15 volumio mpd[16300]: 2026-08-27T21:23:15 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 27 21:23:15 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 27 21:23:15 volumio sudo[16261]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:15 volumio volumio[1250]: error: updateQueue error: null Aug 27 21:23:15 volumio volumio[1250]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Aug 27 21:23:15 volumio volumio[1250]: info: QobuzConnect: setDeactiveState invoked Aug 27 21:23:15 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Aug 27 21:23:15 volumio qobuz-connect[1948]: 20260827 21:23:15.969 [1948.1948] INFO SampleApp: shat down connection on UNIX socket Aug 27 21:23:15 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Aug 27 21:23:15 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Aug 27 21:23:15 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Aug 27 21:23:15 volumio sudo[16293]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:15 volumio sudo[16257]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:16 volumio volumio[1250]: info: Executing endpoint qc_getconfig Aug 27 21:23:16 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Aug 27 21:23:16 volumio qobuz-connect[16317]: 20260827 21:23:16.013 [16317.16317] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Aug 27 21:23:16 volumio qobuz-connect[16317]: 20260827 21:23:16.014 [16317.16317] INFO VolumeManager: [0x2054f68]: Setting new playback volume: 75 Aug 27 21:23:16 volumio qobuz-connect[16317]: 20260827 21:23:16.014 [16317.16317] INFO VolumeManager: [0x2054f68]: Setting new mute state: 0 Aug 27 21:23:16 volumio qobuz-connect[16317]: 20260827 21:23:16.014 [16317.16317] INFO AudioStreamManager: [0x2054cc0]: Setting new audio download buffer size: 1048576 Aug 27 21:23:16 volumio qobuz-connect[16317]: 20260827 21:23:16.014 [16317.16317] INFO QobuzConnect: [0x2055830]: Client initialized! Aug 27 21:23:16 volumio qobuz-connect[16317]: 20260827 21:23:16.014 [16317.16317] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Aug 27 21:23:16 volumio volumio[1250]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Aug 27 21:23:16 volumio volumio[1250]: info: QobuzConnect: QOBUZ Connect daemon connected Aug 27 21:23:16 volumio qobuz-connect[16317]: 20260827 21:23:16.018 [16317.16317] INFO LocalConfigManager: [0x20549e8]: Starting Local Configuration server Aug 27 21:23:16 volumio qobuz-connect[16317]: 20260827 21:23:16.018 [16317.16317] INFO SampleApp: Starting Local configuration server Aug 27 21:23:16 volumio qobuz-connect[16317]: 20260827 21:23:16.018 [16317.16317] INFO SampleApp: Connected to UNIX socket client 0x203f818 Aug 27 21:23:16 volumio qobuz-connect[16317]: 20260827 21:23:16.163 [16317.16317] INFO SampleApp: Playback volume changed: 75 Aug 27 21:23:16 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Aug 27 21:23:17 volumio volumio[1250]: info: TidalConnect service stoped! Aug 27 21:23:17 volumio volumio[1250]: info: TidalConnect service stoped! Aug 27 21:23:17 volumio volumio[1250]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 27 21:23:17 volumio volumio[1250]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 27 21:23:17 volumio sudo[16346]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 27 21:23:17 volumio sudo[16346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:23:17 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 27 21:23:17 volumio sudo[16346]: pam_unix(sudo:session): session closed for user root Aug 27 21:23:18 volumio volumio[1250]: info: TidalConnect service stoped! Aug 27 21:23:18 volumio volumio[1250]: info: Executing endpoint tc_getconfig Aug 27 21:23:18 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 27 21:23:18 volumio volumio[1250]: info: TidalConnect service stoped! Aug 27 21:23:18 volumio vtcs[16349]: STARTING TidalConnect services, version: 1.6.1 Aug 27 21:23:18 volumio vtcs[16349]: STARTED TidalConnect services. Aug 27 21:23:18 volumio volumio[1250]: info: Executing endpoint tc_connect Aug 27 21:23:18 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Aug 27 21:23:18 volumio volumio[1250]: info: Connecting to TidalConnect Aug 27 21:23:18 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Aug 27 21:23:18 volumio volumio[1250]: info: CoreStateMachine::pushState Aug 27 21:23:18 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 21:23:18 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Aug 27 21:23:18 volumio volumio[1250]: info: MRS: Pushing multiroomSync output update for this device Aug 27 21:23:18 volumio volumio[1250]: info: MRS: Pushing multiroomSync output Aug 27 21:23:18 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Aug 27 21:23:18 volumio volumio[1250]: info: CorePlayQueue::getTrack 9 Aug 27 21:23:18 volumio volumio[1250]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Aug 27 21:23:18 volumio volumio[1250]: info: CoreCommandRouter::servicePushState Aug 27 21:23:18 volumio volumio[1250]: info: CoreStateMachine::pushState Aug 27 21:23:18 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState Aug 27 21:23:18 volumio volumio[1250]: info: MRS: Pushing multiroomSync output update for this device Aug 27 21:23:18 volumio volumio[1250]: info: MRS: Pushing multiroomSync output Aug 27 21:23:18 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Aug 27 21:23:18 volumio volumio[1250]: info: CorePlayQueue::getTrack 9 Aug 27 21:23:18 volumio volumio[1250]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Aug 27 21:23:18 volumio volumio[1250]: info: FusionDsp - Volumio is not playing Aug 27 21:23:18 volumio volumio5-onboarding[1436]: time=2026-08-27T21:23:18.091+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:47724,00:00:00:00:00:00%01 @ 0x31971a0" state=STATUS_PAUSED positionMs=408662 volume=100 Aug 27 21:23:18 volumio volumio5-onboarding[1436]: time=2026-08-27T21:23:18.091+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.114:47724,00:00:00:00:00:00%01 @ 0x31971a0" state=STATUS_PAUSED positionMs=408662 volume=100 Aug 27 21:23:18 volumio volumio5-onboarding[1436]: time=2026-08-27T21:23:18.091+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:47724,00:00:00:00:00:00%01 @ 0x31971a0" id=tidal://song/121542990 title=Vintage Aug 27 21:23:18 volumio volumio5-onboarding[1436]: time=2026-08-27T21:23:18.091+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.114:47724,00:00:00:00:00:00%01 @ 0x31971a0" id=tidal://song/121542990 title=Vintage Aug 27 21:23:18 volumio volumio[1250]: info: FusionDsp - Clipped samples monitor stopped Aug 27 21:23:18 volumio volumio[1250]: info: FusionDsp - Volumio is not playing Aug 27 21:23:18 volumio volumio[1250]: info: FusionDsp - Clipped samples monitor stopped Aug 27 21:23:18 volumio volumio[1250]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 27 21:23:18 volumio volumio[1250]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 27 21:23:18 volumio volumio[1250]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 21:23:18 volumio volumio[1250]: Error: ENOENT: no such file or directory, unlink '/tmp/tI33Dtr1H4OuMbuD41rHkCCkKCGouCAOTa0kDAEYobm31wbDLwnD6ON54IEB6M7cO3gKfjyKNNzV8AJJHEiAKCZVU8r3qkALgKlZ' Aug 27 21:23:18 volumio volumio[1250]: at Object.unlinkSync (node:fs:1899:3) Aug 27 21:23:18 volumio volumio[1250]: at ControllerTidalconnect.deleteCertificate (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:11265) Aug 27 21:23:18 volumio volumio[1250]: at TidalConnectClient. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:2774) Aug 27 21:23:18 volumio volumio[1250]: at TidalConnectClient.emit (node:events:514:28) Aug 27 21:23:18 volumio volumio[1250]: at TidalConnectClient.parseData (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:44713) Aug 27 21:23:18 volumio volumio[1250]: at /myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41683 Aug 27 21:23:18 volumio volumio[1250]: at Array.forEach () Aug 27 21:23:18 volumio volumio[1250]: at Socket. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41561) Aug 27 21:23:18 volumio volumio[1250]: at Socket.emit (node:events:514:28) Aug 27 21:23:18 volumio volumio[1250]: at addChunk (node:internal/streams/readable:343:12) Aug 27 21:23:18 volumio volumio[1250]: at readableAddChunk (node:internal/streams/readable:316:9) Aug 27 21:23:18 volumio volumio[1250]: at Readable.push (node:internal/streams/readable:253:10) Aug 27 21:23:18 volumio volumio[1250]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) { Aug 27 21:23:18 volumio volumio[1250]: errno: -2, Aug 27 21:23:18 volumio volumio[1250]: syscall: 'unlink', Aug 27 21:23:18 volumio volumio[1250]: code: 'ENOENT', Aug 27 21:23:18 volumio volumio[1250]: path: '/tmp/tI33Dtr1H4OuMbuD41rHkCCkKCGouCAOTa0kDAEYobm31wbDLwnD6ON54IEB6M7cO3gKfjyKNNzV8AJJHEiAKCZVU8r3qkALgKlZ' Aug 27 21:23:18 volumio volumio[1250]: } Aug 27 21:23:18 volumio volumio[1250]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 21:23:18 volumio kernel: usb 3-1: USB disconnect, device number 3 Aug 27 21:23:18 volumio sudo[16394]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-27 21:22' Aug 27 21:23:18 volumio sudo[16394]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"