May 31 11:48:04 volumio volumio[1538]: warn: [cd-plugin] cdspeedctl: device or media not ready May 31 11:48:04 volumio volumio[1538]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs May 31 11:48:04 volumio volumio[1538]: info: Adding inputs REST Endpoints May 31 11:48:04 volumio volumio[1538]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs May 31 11:48:04 volumio volumio[1538]: info: Scanning Audio Inputs May 31 11:48:04 volumio volumio[1538]: info: Checking against Known Cards name May 31 11:48:04 volumio volumio[1538]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 11:48:04 volumio volumio[1538]: info: [1780228084335] CoreMusicLibrary::Adding element Logitech Webcam C925e May 31 11:48:04 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 11:48:04 volumio volumio[1538]: Cannot find translation for source Logitech Webcam C925e May 31 11:48:04 volumio volumio[1538]: info: Checking against Known Cards name May 31 11:48:04 volumio volumio[1538]: info: Adding Server instance for streaming May 31 11:48:04 volumio volumio[1538]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio May 31 11:48:04 volumio volumio[1538]: error: Hi Res Audio Failed Login: Missing Login Data May 31 11:48:04 volumio volumio[1538]: info: Adding HIGHRESAUDIO REST API Endpoints May 31 11:48:04 volumio volumio[1538]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio May 31 11:48:04 volumio volumio[1538]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal May 31 11:48:04 volumio volumio[1538]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz May 31 11:48:04 volumio volumio[1538]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect May 31 11:48:04 volumio volumio[1538]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect May 31 11:48:04 volumio volumio[1538]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect May 31 11:48:04 volumio sudo[4497]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 11:48:04 volumio sudo[4497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:04 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:48:04 volumio volumio[1538]: info: QobuzConnect: Starting Qobuz Connect socket and service May 31 11:48:04 volumio sudo[4497]: pam_unix(sudo:session): session closed for user root May 31 11:48:04 volumio volumio[1538]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections May 31 11:48:04 volumio volumio[1538]: info: Adding TIDAL REST API Endpoints May 31 11:48:04 volumio sudo[4504]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 31 11:48:04 volumio volumio[1538]: info: Stopping AccessToken refresher cron for QOBUZ May 31 11:48:04 volumio sudo[4504]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:04 volumio sudo[4504]: pam_unix(sudo:session): session closed for user root May 31 11:48:04 volumio sudo[4507]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 31 11:48:04 volumio sudo[4507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:04 volumio volumio[1538]: info: AccessToken refresher cron started for QOBUZ May 31 11:48:04 volumio volumio[1538]: info: Adding QOBUZ REST API Endpoints May 31 11:48:04 volumio volumio[1538]: info: Updating MyVolumio device info May 31 11:48:04 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:48:04 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:48:04 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:48:04 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. May 31 11:48:04 volumio sudo[4507]: pam_unix(sudo:session): session closed for user root May 31 11:48:04 volumio volumio[1538]: info: MRS: Getting audio outputs on start May 31 11:48:04 volumio volumio[1538]: info: MRS: Requesting all other devices output May 31 11:48:04 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings May 31 11:48:04 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices May 31 11:48:04 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 31 11:48:04 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:48:04 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 11:48:04 volumio volumio[1538]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on May 31 11:48:04 volumio volumio[1538]: info: MPD Permissions set May 31 11:48:04 volumio sudo[4515]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 31 11:48:04 volumio sudo[4515]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:04 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:04 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:04 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:04 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:04 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:04 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:04 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:04 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:04 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:48:04 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 31 11:48:04 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:04 volumio volumio[1538]: error: updateQueue error: null May 31 11:48:04 volumio volumio[1538]: info: Starting Shairport Sync May 31 11:48:04 volumio volumio[1538]: info: Preparing to generate the ALSA configuration file May 31 11:48:04 volumio sudo[4538]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 11:48:04 volumio sudo[4538]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:04 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. May 31 11:48:04 volumio volumio[1538]: info: Adding audio output: May 31 11:48:04 volumio sudo[4515]: pam_unix(sudo:session): session closed for user root May 31 11:48:04 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 31 11:48:04 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 31 11:48:04 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 11:48:04 volumio systemd[1]: shairport-sync.service: Consumed 1.746s CPU time. May 31 11:48:04 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 11:48:04 volumio sudo[4538]: pam_unix(sudo:session): session closed for user root May 31 11:48:04 volumio volumio[1538]: info: Adding audio output: May 31 11:48:04 volumio volumio[1538]: info: Adding audio output: May 31 11:48:04 volumio volumio[1538]: info: Shairport-Sync Started May 31 11:48:04 volumio volumio[1538]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully May 31 11:48:04 volumio volumio[1538]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart May 31 11:48:04 volumio volumio[1538]: info: Asound.conf file unchanged, so no further update is needed May 31 11:48:04 volumio volumio[1538]: info: Output device has changed, restarting MPD May 31 11:48:04 volumio volumio[1538]: info: Output device has changed, restarting Shairport Sync May 31 11:48:04 volumio sudo[4556]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 11:48:04 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:04 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:48:04 volumio sudo[4556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:04 volumio sudo[4556]: pam_unix(sudo:session): session closed for user root May 31 11:48:04 volumio sudo[4558]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 11:48:04 volumio sudo[4558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:04 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... May 31 11:48:04 volumio systemd[1]: mpd.service: Deactivated successfully. May 31 11:48:04 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 31 11:48:04 volumio systemd[1]: mpd.socket: Deactivated successfully. May 31 11:48:04 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 31 11:48:04 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 31 11:48:04 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 31 11:48:04 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 31 11:48:04 volumio sudo[4566]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 11:48:04 volumio sudo[4566]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 11:48:04 volumio sudo[4566]: pam_unix(sudo:session): session closed for user root May 31 11:48:05 volumio mpd[4568]: 2026-05-31T11:48:05 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 31 11:48:05 volumio systemd[1]: Started mpd.service - Music Player Daemon. May 31 11:48:05 volumio sudo[4558]: pam_unix(sudo:session): session closed for user root May 31 11:48:05 volumio volumio[1538]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 11:48:05 volumio volumio[1538]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:05 volumio volumio[1538]: info: QobuzConnect: setDeactiveState invoked May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:05 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:05 volumio volumio[1538]: info: Preparing to generate the ALSA configuration file May 31 11:48:05 volumio volumiobt[4575]: INFO [BTSTART] Ensuring Bluetooth directory exists... May 31 11:48:05 volumio sudo[4576]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth May 31 11:48:05 volumio sudo[4576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:05 volumio sudo[4576]: pam_unix(sudo:session): session closed for user root May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:48:05 volumio sudo[4582]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth May 31 11:48:05 volumio sudo[4582]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:05 volumio sudo[4582]: pam_unix(sudo:session): session closed for user root May 31 11:48:05 volumio volumiobt[4586]: INFO [BTSTART] Powering on Bluetooth if needed... May 31 11:48:05 volumio sudo[4584]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 31 11:48:05 volumio sudo[4584]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:05 volumio volumio[1538]: info: Adding audio output: May 31 11:48:05 volumio volumio[1538]: info: MRS: Found cast device: SHIELD-Android-TV-e05a7ee1222be08d63473d4e654b640e May 31 11:48:05 volumio volumio[1538]: info: Adding audio output: May 31 11:48:05 volumio volumio[1538]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 31 11:48:05 volumio volumio[1538]: info: Reading ALSA contributions from plugins. May 31 11:48:05 volumio sudo[4584]: pam_unix(sudo:session): session closed for user root May 31 11:48:05 volumio volumio[1538]: info: MPD Permissions set May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:05 volumio sudo[4590]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 31 11:48:05 volumio sudo[4590]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 31 11:48:05 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:05 volumio volumio[1538]: error: updateQueue error: null May 31 11:48:05 volumio volumio[1538]: info: Starting Shairport Sync May 31 11:48:05 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. May 31 11:48:05 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. May 31 11:48:05 volumio volumio[1538]: info: Asound.conf file written May 31 11:48:05 volumio sudo[4602]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 11:48:05 volumio sudo[4602]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:05 volumio bluetoothd[1074]: Path / reserved for Adv Monitor app :1.43 May 31 11:48:05 volumio bluetoothd[1074]: Adv Monitor app :1.43 disconnected from D-Bus May 31 11:48:05 volumio sudo[4606]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 31 11:48:05 volumio sudo[4606]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:05 volumio volumiobt[4608]: INFO [BTSTART] Making Bluetooth discoverable and pairable... May 31 11:48:05 volumio sudo[4606]: pam_unix(sudo:session): session closed for user root May 31 11:48:05 volumio volumiobt[4610]: [176B blob data] May 31 11:48:05 volumio volumiobt[4610]: [157B blob data] May 31 11:48:05 volumio volumiobt[4610]: [157B blob data] May 31 11:48:05 volumio volumiobt[4610]: [157B blob data] May 31 11:48:05 volumio volumiobt[4610]: [113B blob data] May 31 11:48:05 volumio volumiobt[4610]: [bluetoothctl]> discoverable on May 31 11:48:05 volumio volumiobt[4610]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended May 31 11:48:05 volumio volumiobt[4610]: [bluetoothctl]> pairable on May 31 11:48:05 volumio volumio[1538]: No state is present for card PCH May 31 11:48:05 volumio volumio[1538]: Found hardware: "HDA-Intel" "Intel Kabylake HDMI" "HDA:8086280b,80860101,00100000" "0x8086" "0x7270" May 31 11:48:05 volumio volumio[1538]: Hardware is initialized using a generic method May 31 11:48:05 volumio volumio[1538]: No state is present for card PCH May 31 11:48:05 volumio volumio[1538]: No state is present for card C925e May 31 11:48:05 volumio volumio[1538]: Found hardware: "USB-Audio" "USB Mixer" "USB046d:085b" "" "" May 31 11:48:05 volumio volumio[1538]: Hardware is initialized using a generic method May 31 11:48:05 volumio volumio[1538]: No state is present for card C925e May 31 11:48:05 volumio volumio[1538]: info: Output device has changed, restarting MPD May 31 11:48:05 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. May 31 11:48:05 volumio bluetoothd[1074]: Path / reserved for Adv Monitor app :1.44 May 31 11:48:05 volumio bluetoothd[1074]: Adv Monitor app :1.44 disconnected from D-Bus May 31 11:48:05 volumio volumiobt[4610]: [bluetoothctl]> May 31 11:48:05 volumio volumiobt[4614]: INFO [BTSTART] Registering Bluetooth agent... May 31 11:48:05 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 31 11:48:05 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 31 11:48:05 volumio sudo[4590]: pam_unix(sudo:session): session closed for user root May 31 11:48:05 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 11:48:05 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 11:48:05 volumio volumio[1538]: info: Output device has changed, restarting Shairport Sync May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:48:05 volumio sudo[4602]: pam_unix(sudo:session): session closed for user root May 31 11:48:05 volumio volumiobt[4615]: [NEW] Media /org/bluez/hci0 May 31 11:48:05 volumio volumiobt[4615]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb May 31 11:48:05 volumio volumiobt[4615]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb May 31 11:48:05 volumio volumiobt[4615]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb May 31 11:48:05 volumio sudo[4617]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 11:48:05 volumio sudo[4617]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:05 volumio sudo[4621]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 11:48:05 volumio sudo[4621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:05 volumio bluetoothd[1074]: Adv Monitor app :1.45 disconnected from D-Bus May 31 11:48:05 volumio sudo[4617]: pam_unix(sudo:session): session closed for user root May 31 11:48:05 volumio volumio[1538]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 11:48:05 volumio volumio[1538]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:05 volumio volumio[1538]: info: QobuzConnect: setDeactiveState invoked May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:05 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:05 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:48:05 volumio volumiobt[4626]: No agent is registered May 31 11:48:05 volumio volumiobt[4626]: [NEW] Media /org/bluez/hci0 May 31 11:48:05 volumio volumiobt[4626]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb May 31 11:48:05 volumio volumiobt[4626]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb May 31 11:48:05 volumio volumiobt[4626]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb May 31 11:48:05 volumio systemd[1]: mpd.service: Deactivated successfully. May 31 11:48:05 volumio volumio[1538]: info: MPD Permissions set May 31 11:48:05 volumio volumio[1538]: info: Shairport-Sync Started May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:05 volumio sudo[4649]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 31 11:48:05 volumio sudo[4649]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:05 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 31 11:48:05 volumio bluetoothd[1074]: Adv Monitor app :1.46 disconnected from D-Bus May 31 11:48:05 volumio systemd[1]: mpd.socket: Deactivated successfully. May 31 11:48:05 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 31 11:48:05 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 31 11:48:05 volumio volumiobt[4650]: INFO [BTSTART] Agent registered successfully. May 31 11:48:05 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 31 11:48:05 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 31 11:48:05 volumio volumiobt[4658]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... May 31 11:48:05 volumio sudo[4649]: pam_unix(sudo:session): session closed for user root May 31 11:48:05 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:06 volumio sudo[4660]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 11:48:06 volumio sudo[4664]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 31 11:48:06 volumio sudo[4660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 11:48:06 volumio sudo[4664]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:06 volumio sudo[4660]: pam_unix(sudo:session): session closed for user root May 31 11:48:06 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... May 31 11:48:06 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. May 31 11:48:06 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. May 31 11:48:06 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. May 31 11:48:06 volumio sudo[4664]: pam_unix(sudo:session): session closed for user root May 31 11:48:06 volumio mpd[4668]: decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 31 11:48:06 volumio mpd[4668]: output: No 'audio_output' defined in config file May 31 11:48:08 volumio mpd[4668]: couldn't open play stream: No such file or directory May 31 11:48:08 volumio mpd[4668]: sndio_output: Error opening default sndio device May 31 11:48:08 volumio mpd[4668]: output: Successfully detected a alsa audio device May 31 11:48:08 volumio mpd[4668]: zeroconf: No global port, disabling zeroconf May 31 11:48:08 volumio systemd[1]: Started mpd.service - Music Player Daemon. May 31 11:48:08 volumio sudo[4621]: pam_unix(sudo:session): session closed for user root May 31 11:48:08 volumio volumio[1538]: info: Executing endpoint qc_getconfig May 31 11:48:08 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 31 11:48:09 volumio volumio[1538]: info: Successfully Updated MyVolumio device May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 31 11:48:09 volumio volumio[1538]: info: Preparing to save Alsa Options, stopping services first May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:09 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::volumioPause May 31 11:48:09 volumio volumio[1538]: info: CoreStateMachine::pause May 31 11:48:09 volumio volumio[1538]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"0,3","label":"HDMI"},"disallowPush":true} May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 11:48:09 volumio volumio[1538]: info: Found match in Cards Database: setting mixer for card HDMI May 31 11:48:09 volumio volumio[1538]: info: QobuzConnect: setDeactiveState invoked May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:09 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:09 volumio volumio[1538]: info: Setting mixer IEC958 for card HDMI May 31 11:48:09 volumio volumio[1538]: info: QobuzConnect: setDeactiveState invoked May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:09 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 31 11:48:09 volumio volumio[1538]: info: Updating Volume Controller Parameters: Device: 0,3 Name: HDMI Mixer: IEC958 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 31 11:48:09 volumio volumio[1538]: info: Disabling external Volume Control May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 11:48:09 volumio volumio[1538]: info: Retrieving Cloud Streaming UI May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 11:48:09 volumio volumio[1538]: info: Retrieving Cloud Streaming UI May 31 11:48:09 volumio volumio[1538]: info: Preparing to generate the ALSA configuration file May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:48:09 volumio volumio[1538]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 31 11:48:09 volumio volumio[1538]: info: Reading ALSA contributions from plugins. May 31 11:48:09 volumio volumio[1538]: info: TidalConnect service stoped! May 31 11:48:09 volumio volumio[1538]: Unable to parse: May 31 11:48:09 volumio volumio[1538]: Simple mixer control 'IEC958',0 May 31 11:48:09 volumio volumio[1538]: Capabilities: pswitch pswitch-joined May 31 11:48:09 volumio volumio[1538]: Playback channels: Mono May 31 11:48:09 volumio volumio[1538]: Mono: Playback [on] May 31 11:48:09 volumio volumio[1538]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 31 11:48:09 volumio volumio[1538]: info: Starting Shairport Sync May 31 11:48:09 volumio volumio[1538]: info: Executing endpoint qc_getconfig May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 31 11:48:09 volumio qobuz-connect[4669]: 20260531 11:48:09.774 [4669.4669] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 31 11:48:09 volumio volumio[1538]: error: updateQueue error: null May 31 11:48:09 volumio volumio[1538]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 31 11:48:09 volumio volumio[1538]: info: QobuzConnect: QOBUZ Connect daemon connected May 31 11:48:09 volumio sudo[4696]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 31 11:48:09 volumio sudo[4711]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 11:48:09 volumio sudo[4711]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:09 volumio sudo[4704]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 31 11:48:09 volumio sudo[4704]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:09 volumio sudo[4696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 31 11:48:09 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 31 11:48:09 volumio sudo[4704]: pam_unix(sudo:session): session closed for user root May 31 11:48:09 volumio volumio[1538]: info: Getting Tidal Cloud Configuration May 31 11:48:09 volumio sudo[4696]: pam_unix(sudo:session): session closed for user root May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 11:48:09 volumio volumio[1538]: info: Getting Qobuz Cloud Configuration May 31 11:48:09 volumio volumio[1538]: info: Asking plugin for UI Config May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 11:48:09 volumio volumio[1538]: info: Getting Spotify Cloud Configuration May 31 11:48:09 volumio volumio[1538]: info: Asking plugin for UI Config May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 11:48:09 volumio volumio[1538]: info: Saving Spotify Acccount May 31 11:48:09 volumio volumio[1538]: info: Got it May 31 11:48:09 volumio volumio[1538]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 31 11:48:09 volumio sudo[4729]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 31 11:48:09 volumio sudo[4729]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:09 volumio volumio[1538]: info: Getting Tidal Cloud Configuration May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 11:48:09 volumio sudo[4728]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 31 11:48:09 volumio sudo[4728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:09 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 31 11:48:09 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 11:48:09 volumio systemd[1]: shairport-sync.service: Consumed 1.521s CPU time. May 31 11:48:09 volumio volumio[1538]: info: Getting Qobuz Cloud Configuration May 31 11:48:09 volumio volumio[1538]: info: Asking plugin for UI Config May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 11:48:09 volumio volumio[1538]: info: Getting Spotify Cloud Configuration May 31 11:48:09 volumio volumio[1538]: info: Asking plugin for UI Config May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 11:48:09 volumio volumio[1538]: info: Saving Spotify Acccount May 31 11:48:09 volumio volumio[1538]: info: Got it May 31 11:48:09 volumio volumio[1538]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 31 11:48:09 volumio volumio[1538]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 31 11:48:09 volumio volumio[1538]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 31 11:48:09 volumio volumio[1538]: Unable to parse: May 31 11:48:09 volumio volumio[1538]: Simple mixer control 'IEC958',0 May 31 11:48:09 volumio volumio[1538]: Capabilities: pswitch pswitch-joined May 31 11:48:09 volumio volumio[1538]: Playback channels: Mono May 31 11:48:09 volumio volumio[1538]: Mono: Playback [on] May 31 11:48:09 volumio volumio[1538]: info: VolumeController:: Volume=undefined Mute =false May 31 11:48:09 volumio volumio[1538]: info: CoreStateMachine::pushState May 31 11:48:09 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::volumioPushState May 31 11:48:09 volumio volumio[1538]: info: MRS: Pushing multiroomSync output update for this device May 31 11:48:09 volumio volumio[1538]: info: MRS: Pushing multiroomSync output May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:09 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:09 volumio volumio[1538]: info: Asound.conf file unchanged, so no further update is needed May 31 11:48:09 volumio volumio[1538]: info: Output device has changed, restarting MPD May 31 11:48:09 volumio sudo[4736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 31 11:48:09 volumio sudo[4736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:09 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 11:48:09 volumio sudo[4711]: pam_unix(sudo:session): session closed for user root May 31 11:48:09 volumio volumio[1538]: info: Output device has changed, restarting Shairport Sync May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:48:09 volumio sudo[4739]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 11:48:09 volumio sudo[4741]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 11:48:09 volumio sudo[4741]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:09 volumio sudo[4739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:09 volumio sudo[4739]: pam_unix(sudo:session): session closed for user root May 31 11:48:09 volumio volumio[1538]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 11:48:09 volumio volumio[1538]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:09 volumio volumio[1538]: info: QobuzConnect: setDeactiveState invoked May 31 11:48:09 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:09 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:09 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 31 11:48:09 volumio sudo[4736]: pam_unix(sudo:session): session closed for user root May 31 11:48:09 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... May 31 11:48:09 volumio sudo[4765]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 11:48:09 volumio sudo[4765]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:09 volumio sudo[4770]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 11:48:09 volumio sudo[4770]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:48:09 volumio systemd[1]: mpd.service: Deactivated successfully. May 31 11:48:09 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 31 11:48:09 volumio systemd[1]: mpd.socket: Deactivated successfully. May 31 11:48:09 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 31 11:48:09 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 31 11:48:09 volumio volumio[1538]: info: MPD Permissions set May 31 11:48:09 volumio volumio[1538]: info: Shairport-Sync Started May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:09 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 31 11:48:09 volumio sudo[4778]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 31 11:48:09 volumio sudo[4778]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:09 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 31 11:48:09 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 31 11:48:09 volumio sudo[4778]: pam_unix(sudo:session): session closed for user root May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:09 volumio volumio[1538]: info: Starting Shairport Sync May 31 11:48:09 volumio sudo[4789]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 31 11:48:09 volumio sudo[4789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:09 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. May 31 11:48:09 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. May 31 11:48:09 volumio systemd[1]: vtcs.service: Deactivated successfully. May 31 11:48:09 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. May 31 11:48:09 volumio volumio[1538]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 31 11:48:09 volumio volumio[1538]: info: QobuzConnect: setDeactiveState invoked May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:09 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:09 volumio volumio[1538]: info: Got it May 31 11:48:09 volumio volumio[1538]: info: Got Tidal Cloud Configuration May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 11:48:09 volumio volumio[1538]: info: Got Tidal Cloud Configuration May 31 11:48:09 volumio volumio[1538]: info: Got it May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 11:48:09 volumio sudo[4795]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 11:48:09 volumio sudo[4795]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:09 volumio sudo[4765]: pam_unix(sudo:session): session closed for user root May 31 11:48:09 volumio sudo[4770]: pam_unix(sudo:session): session closed for user root May 31 11:48:09 volumio sudo[4783]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 11:48:09 volumio sudo[4783]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 11:48:09 volumio sudo[4783]: pam_unix(sudo:session): session closed for user root May 31 11:48:09 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. May 31 11:48:09 volumio sudo[4729]: pam_unix(sudo:session): session closed for user root May 31 11:48:09 volumio sudo[4728]: pam_unix(sudo:session): session closed for user root May 31 11:48:09 volumio sudo[4789]: pam_unix(sudo:session): session closed for user root May 31 11:48:09 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 31 11:48:09 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 31 11:48:09 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 11:48:09 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 11:48:09 volumio sudo[4795]: pam_unix(sudo:session): session closed for user root May 31 11:48:09 volumio volumio[1538]: info: Shairport-Sync Started May 31 11:48:09 volumio volumio[1538]: info: Executing endpoint qc_getconfig May 31 11:48:09 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 31 11:48:09 volumio qobuz-connect[4798]: 20260531 11:48:09.957 [4798.4798] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 31 11:48:09 volumio volumio[1538]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 31 11:48:09 volumio volumio[1538]: info: QobuzConnect: QOBUZ Connect daemon connected May 31 11:48:10 volumio mpd[4803]: 2026-05-31T11:48:10 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 31 11:48:10 volumio systemd[1]: Started mpd.service - Music Player Daemon. May 31 11:48:10 volumio sudo[4741]: pam_unix(sudo:session): session closed for user root May 31 11:48:10 volumio volumio[1538]: error: updateQueue error: null May 31 11:48:10 volumio sudo[4827]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 31 11:48:10 volumio sudo[4827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:10 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 31 11:48:10 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 31 11:48:10 volumio qobuz-connect[4798]: 20260531 11:48:10.791 [4798.4798] INFO VolumeManager: [0x5606a1923560]: Setting new playback volume: 75 May 31 11:48:10 volumio qobuz-connect[4798]: 20260531 11:48:10.791 [4798.4798] INFO VolumeManager: [0x5606a1923560]: Setting new mute state: 0 May 31 11:48:10 volumio qobuz-connect[4798]: 20260531 11:48:10.791 [4798.4798] INFO AudioStreamManager: [0x5606a19230c0]: Setting new audio download buffer size: 1048576 May 31 11:48:10 volumio qobuz-connect[4798]: 20260531 11:48:10.791 [4798.4798] INFO QobuzConnect: [0x5606a1924570]: Client initialized! May 31 11:48:10 volumio qobuz-connect[4798]: 20260531 11:48:10.791 [4798.4798] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp May 31 11:48:10 volumio qobuz-connect[4798]: 20260531 11:48:10.793 [4798.4798] INFO LocalConfigManager: [0x5606a1922ba0]: Starting Local Configuration server May 31 11:48:10 volumio qobuz-connect[4798]: 20260531 11:48:10.793 [4798.4798] INFO SampleApp: Starting Local configuration server May 31 11:48:10 volumio qobuz-connect[4798]: 20260531 11:48:10.794 [4798.4798] INFO SampleApp: Connected to UNIX socket client 0x5606a18f9bb0 May 31 11:48:10 volumio qobuz-connect[4798]: 20260531 11:48:10.794 [4798.4798] INFO SampleApp: Playback volume changed: 75 May 31 11:48:10 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:10 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:10 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. May 31 11:48:10 volumio sudo[4827]: pam_unix(sudo:session): session closed for user root May 31 11:48:10 volumio volumio[1538]: info: Remote SSH Started May 31 11:48:10 volumio autossh[4833]: port set to 0, monitoring disabled May 31 11:48:10 volumio autossh[4833]: starting ssh (count 1) May 31 11:48:10 volumio autossh[4833]: ssh child pid is 4836 May 31 11:48:10 volumio volumio[1538]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 31 11:48:10 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:10 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:10 volumio volumiossh-tunnel[4836]: Warning: Permanently added '[eu2.myvolumio.org]:2222' (RSA) to the list of known hosts. May 31 11:48:11 volumio volumiobt[4663]: 2026-05-31 11:48:11 a2dp-agent [INFO] Connecting to system D-Bus May 31 11:48:11 volumio volumiobt[4663]: 2026-05-31 11:48:11 a2dp-agent [INFO] Connected to system D-Bus May 31 11:48:11 volumio volumiobt[4663]: 2026-05-31 11:48:11 bluezutils [INFO] Found adapter at: /org/bluez/hci0 May 31 11:48:11 volumio volumiobt[4663]: 2026-05-31 11:48:11 a2dp-agent [INFO] Found Bluetooth adapter: /org/bluez/hci0 May 31 11:48:11 volumio volumiobt[4663]: 2026-05-31 11:48:11 a2dp-agent [INFO] Set DiscoverableTimeout to infinite May 31 11:48:11 volumio volumiobt[4663]: 2026-05-31 11:48:11 a2dp-agent [INFO] Enabled Discoverable mode May 31 11:48:11 volumio volumiobt[4663]: 2026-05-31 11:48:11 a2dp-agent [INFO] Agent registered at /local/a2dpagent May 31 11:48:11 volumio volumiobt[4663]: 2026-05-31 11:48:11 a2dp-agent [INFO] Agent set as default May 31 11:48:11 volumio volumiobt[4663]: 2026-05-31 11:48:11 a2dp-agent [INFO] A2DP agent running, waiting for connections... May 31 11:48:11 volumio bluetoothd[1074]: Failed to set mode: Busy (0x0a) May 31 11:48:11 volumio kernel: Bluetooth: hci0: Opcode 0x2036 failed: -16 May 31 11:48:12 volumio volumio[1538]: info: TidalConnect service started! May 31 11:48:12 volumio volumio[1538]: info: TidalConnect service stoped! May 31 11:48:12 volumio volumio[1538]: info: TidalConnect service stoped! May 31 11:48:12 volumio volumio[1538]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 31 11:48:12 volumio volumio[1538]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 31 11:48:12 volumio sudo[4851]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 31 11:48:12 volumio sudo[4851]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:12 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 31 11:48:12 volumio sudo[4851]: pam_unix(sudo:session): session closed for user root May 31 11:48:13 volumio volumio[1538]: info: Executing endpoint tc_getconfig May 31 11:48:13 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 31 11:48:13 volumio vtcs[4854]: STARTING TidalConnect services, version: 1.6.1 May 31 11:48:13 volumio vtcs[4854]: STARTED TidalConnect services. May 31 11:48:13 volumio volumio[1538]: info: Executing endpoint tc_connect May 31 11:48:13 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 31 11:48:13 volumio volumio[1538]: info: Connecting to TidalConnect May 31 11:48:13 volumio volumio[1538]: info: CoreCommandRouter::servicePushState May 31 11:48:13 volumio volumio[1538]: info: CoreStateMachine::pushState May 31 11:48:13 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:13 volumio volumio[1538]: info: CoreCommandRouter::volumioPushState May 31 11:48:13 volumio volumio[1538]: info: MRS: Pushing multiroomSync output update for this device May 31 11:48:13 volumio volumio[1538]: info: MRS: Pushing multiroomSync output May 31 11:48:13 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:13 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:13 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:13 volumio volumio[1538]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} May 31 11:48:13 volumio volumio[1538]: verbose: CURRENT POSITION 0 May 31 11:48:13 volumio volumio[1538]: info: CoreStateMachine::syncState stateService stop May 31 11:48:13 volumio volumio[1538]: info: CoreStateMachine::syncState currentStatus stop May 31 11:48:13 volumio volumio[1538]: info: CoreStateMachine::pushState May 31 11:48:13 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:13 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:48:13 volumio volumio[1538]: info: CoreCommandRouter::volumioPushState May 31 11:48:13 volumio volumio[1538]: info: MRS: Pushing multiroomSync output update for this device May 31 11:48:13 volumio volumio[1538]: info: MRS: Pushing multiroomSync output May 31 11:48:13 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:13 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:13 volumio volumio[1538]: info: No code May 31 11:48:13 volumio volumio[1538]: info: CoreStateMachine::pushState May 31 11:48:13 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:13 volumio volumio[1538]: info: CoreCommandRouter::volumioPushState May 31 11:48:13 volumio volumio[1538]: info: MRS: Pushing multiroomSync output update for this device May 31 11:48:13 volumio volumio[1538]: info: MRS: Pushing multiroomSync output May 31 11:48:13 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:13 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:13 volumio volumio[1538]: info: CoreCommandRouter::servicePushState May 31 11:48:13 volumio volumio[1538]: info: CoreStateMachine::pushState May 31 11:48:13 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:13 volumio volumio[1538]: info: CoreCommandRouter::volumioPushState May 31 11:48:13 volumio volumio[1538]: info: MRS: Pushing multiroomSync output update for this device May 31 11:48:13 volumio volumio[1538]: info: MRS: Pushing multiroomSync output May 31 11:48:13 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:13 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:13 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:13 volumio volumio[1538]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} May 31 11:48:13 volumio volumio[1538]: verbose: CURRENT POSITION 0 May 31 11:48:13 volumio volumio[1538]: info: CoreStateMachine::syncState stateService stop May 31 11:48:13 volumio volumio[1538]: info: CoreStateMachine::syncState currentStatus stop May 31 11:48:13 volumio volumio[1538]: info: CoreStateMachine::pushState May 31 11:48:13 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:13 volumio volumio[1538]: info: CoreCommandRouter::volumioPushState May 31 11:48:13 volumio volumio[1538]: info: MRS: Pushing multiroomSync output update for this device May 31 11:48:13 volumio volumio[1538]: info: MRS: Pushing multiroomSync output May 31 11:48:13 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:13 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:13 volumio volumio[1538]: info: No code May 31 11:48:13 volumio volumio[1538]: info: CoreStateMachine::pushState May 31 11:48:13 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:13 volumio volumio[1538]: info: CoreCommandRouter::volumioPushState May 31 11:48:13 volumio volumio[1538]: info: MRS: Pushing multiroomSync output update for this device May 31 11:48:13 volumio volumio[1538]: info: MRS: Pushing multiroomSync output May 31 11:48:13 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:13 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:13 volumio volumio[1538]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 31 11:48:13 volumio volumio[1538]: info: CoreStateMachine::getcurrentVolume May 31 11:48:13 volumio volumio[1538]: info: CoreCommandRouter::volumioRetrievevolume May 31 11:48:13 volumio volumio[1538]: Unable to parse: May 31 11:48:13 volumio volumio[1538]: Simple mixer control 'IEC958',0 May 31 11:48:13 volumio volumio[1538]: Capabilities: pswitch pswitch-joined May 31 11:48:13 volumio volumio[1538]: Playback channels: Mono May 31 11:48:13 volumio volumio[1538]: Mono: Playback [on] May 31 11:48:13 volumio volumio[1538]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 31 11:48:13 volumio volumio[1538]: Unable to parse: May 31 11:48:13 volumio volumio[1538]: Simple mixer control 'IEC958',0 May 31 11:48:13 volumio volumio[1538]: Capabilities: pswitch pswitch-joined May 31 11:48:13 volumio volumio[1538]: Playback channels: Mono May 31 11:48:13 volumio volumio[1538]: Mono: Playback [on] May 31 11:48:13 volumio volumio[1538]: info: VolumeController:: Volume=undefined Mute =false May 31 11:48:13 volumio volumio[1538]: info: CoreStateMachine::pushState May 31 11:48:13 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:13 volumio volumio[1538]: info: CoreCommandRouter::volumioPushState May 31 11:48:13 volumio volumio[1538]: info: MRS: Pushing multiroomSync output update for this device May 31 11:48:13 volumio volumio[1538]: info: MRS: Pushing multiroomSync output May 31 11:48:13 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:13 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:13 volumio volumio[1538]: info: CoreStateMachine::updateTrackBlock May 31 11:48:13 volumio volumio[1538]: info: CorePlayQueue::getTrackBlock May 31 11:48:13 volumio volumio[1538]: info: CoreCommandRouter::volumioRetrievevolume May 31 11:48:13 volumio volumio[1538]: Unable to parse: May 31 11:48:13 volumio volumio[1538]: Simple mixer control 'IEC958',0 May 31 11:48:13 volumio volumio[1538]: Capabilities: pswitch pswitch-joined May 31 11:48:13 volumio volumio[1538]: Playback channels: Mono May 31 11:48:13 volumio volumio[1538]: Mono: Playback [on] May 31 11:48:13 volumio volumio[1538]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 31 11:48:13 volumio volumio[1538]: Unable to parse: May 31 11:48:13 volumio volumio[1538]: Simple mixer control 'IEC958',0 May 31 11:48:13 volumio volumio[1538]: Capabilities: pswitch pswitch-joined May 31 11:48:13 volumio volumio[1538]: Playback channels: Mono May 31 11:48:13 volumio volumio[1538]: Mono: Playback [on] May 31 11:48:13 volumio volumio[1538]: info: VolumeController:: Volume=undefined Mute =false May 31 11:48:13 volumio volumio[1538]: info: CoreStateMachine::pushState May 31 11:48:13 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:13 volumio volumio[1538]: info: CoreCommandRouter::volumioPushState May 31 11:48:13 volumio volumio[1538]: info: MRS: Pushing multiroomSync output update for this device May 31 11:48:13 volumio volumio[1538]: info: MRS: Pushing multiroomSync output May 31 11:48:13 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:13 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:15 volumio volumio[1538]: info: CALLMETHOD: music_service streaming_services saveHRAccountData [object Object] May 31 11:48:15 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: streaming_services , saveHRAccountData May 31 11:48:15 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: hi_res_audio , saveAccountData May 31 11:48:15 volumio volumio[1538]: error: Hi Res Audio Failed Login: [object Object] May 31 11:48:15 volumio volumio[1538]: error: Could not save HR Config: INVALID_LOGIN May 31 11:48:15 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: my_music , getUIConfig May 31 11:48:15 volumio volumio[1538]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 11:48:15 volumio volumio[1538]: info: Retrieving Cloud Streaming UI May 31 11:48:15 volumio volumio[1538]: info: Getting Tidal Cloud Configuration May 31 11:48:15 volumio volumio[1538]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 11:48:15 volumio volumio[1538]: info: Getting Qobuz Cloud Configuration May 31 11:48:15 volumio volumio[1538]: info: Asking plugin for UI Config May 31 11:48:15 volumio volumio[1538]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 11:48:15 volumio volumio[1538]: info: Getting Spotify Cloud Configuration May 31 11:48:15 volumio volumio[1538]: info: Asking plugin for UI Config May 31 11:48:15 volumio volumio[1538]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 11:48:15 volumio volumio[1538]: info: Saving Spotify Acccount May 31 11:48:15 volumio volumio[1538]: info: Got it May 31 11:48:15 volumio volumio[1538]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 31 11:48:15 volumio volumio[1538]: info: Got Tidal Cloud Configuration May 31 11:48:15 volumio volumio[1538]: info: Got it May 31 11:48:15 volumio volumio[1538]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 11:48:15 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 31 11:48:15 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 31 11:48:15 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 31 11:48:15 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 31 11:48:15 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:48:15 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:48:15 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:48:15 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:48:15 volumio volumio[1538]: info: CoreCommandRouter::volumioGetBrowseSources May 31 11:48:15 volumio volumio[1538]: info: CoreCommandRouter::volumioGetBrowseSources May 31 11:48:15 volumio volumio[1538]: info: CoreCommandRouter::volumioGetBrowseSources May 31 11:48:15 volumio volumio[1538]: info: TidalConnect service started! May 31 11:48:23 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation May 31 11:48:23 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware May 31 11:48:23 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray May 31 11:48:23 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage May 31 11:48:23 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware May 31 11:48:25 volumio volumio[1538]: info: CALLMETHOD: system_controller system saveUpdateSettings [object Object] May 31 11:48:25 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , saveUpdateSettings May 31 11:48:25 volumio volumio[1538]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'value') May 31 11:48:27 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction May 31 11:48:27 volumio volumio[1538]: info: CoreCommandRouter::Reload Ui May 31 11:48:27 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard May 31 11:48:27 volumio volumio[1538]: info: Wizard terminated Successfully May 31 11:48:27 volumio volumio[1538]: info: CoreCommandRouter::volumiosetStartupVolume May 31 11:48:27 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 11:48:27 volumio volumio[1538]: info: Received Get System Info May 31 11:48:27 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 11:48:27 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 11:48:27 volumio volumio[1538]: info: Discovery: Getting this device information May 31 11:48:27 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:27 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:27 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 11:48:27 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 31 11:48:27 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 31 11:48:28 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 31 11:48:28 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 11:48:28 volumio volumio5-onboarding[2210]: time=2026-05-31T11:48:28.359Z level=INFO msg="service successfully established" component=discovery/localnet May 31 11:48:28 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. May 31 11:48:28 volumio volumio-remote-updater[1082]: Test mode disabled May 31 11:48:28 volumio volumio-remote-updater[1082]: Alpha mode disabled May 31 11:48:28 volumio volumio-remote-updater[1082]: Alpha legacy test mode disabled May 31 11:48:28 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 31 11:48:29 volumio volumio[1538]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 31 11:48:29 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 31 11:48:30 volumio volumio[1538]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 31 11:48:30 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 11:48:30 volumio volumio[1538]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 31 11:48:30 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 31 11:48:30 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 11:48:30 volumio volumio[1538]: info: CoreCommandRouter::volumioGetVisibleSources May 31 11:48:30 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 11:48:30 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:30 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:30 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 31 11:48:30 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 11:48:30 volumio volumio[1538]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 31 11:48:30 volumio volumio[1538]: info: Received Get System Info May 31 11:48:30 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 11:48:30 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 11:48:30 volumio volumio[1538]: info: Discovery: Getting this device information May 31 11:48:30 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:30 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:30 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 11:48:30 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:30 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:30 volumio volumio[1538]: info: Listing playlists May 31 11:48:30 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 31 11:48:30 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 11:48:31 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 31 11:48:31 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:31 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:32 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 31 11:48:32 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 11:48:32 volumio volumio[1538]: info: Received Get System Info May 31 11:48:32 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 11:48:32 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 11:48:32 volumio volumio[1538]: info: Discovery: Getting this device information May 31 11:48:32 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:32 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:32 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 11:48:33 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 11:48:33 volumio volumio[1538]: info: Received Get System Info May 31 11:48:33 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 11:48:33 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 11:48:33 volumio volumio[1538]: info: Discovery: Getting this device information May 31 11:48:33 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:33 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:33 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 11:48:40 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 31 11:48:42 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse May 31 11:48:43 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:43 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:43 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] May 31 11:48:43 volumio volumio[1538]: info: CoreCommandRouter::volumioPlay May 31 11:48:43 volumio volumio[1538]: info: CoreStateMachine::play index undefined May 31 11:48:43 volumio volumio[1538]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:48:43 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:47 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:47 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:47 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] May 31 11:48:47 volumio volumio[1538]: info: CoreCommandRouter::volumioPlay May 31 11:48:47 volumio volumio[1538]: info: CoreStateMachine::play index undefined May 31 11:48:47 volumio volumio[1538]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 11:48:47 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:49 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 11:48:49 volumio volumio[1538]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 31 11:48:49 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 31 11:48:49 volumio volumio[1538]: info: Received Get System Version May 31 11:48:49 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 11:48:49 volumio volumio[1538]: info: Received Get System Info May 31 11:48:49 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 11:48:49 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 11:48:49 volumio volumio[1538]: info: Discovery: Getting this device information May 31 11:48:49 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:49 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:49 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 11:48:53 volumio volumio[1538]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 31 11:48:53 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:53 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:53 volumio volumio[1538]: info: Received Get System Info May 31 11:48:53 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 11:48:53 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 11:48:53 volumio volumio[1538]: info: Discovery: Getting this device information May 31 11:48:53 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:53 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:53 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 11:48:53 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:53 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:53 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 11:48:53 volumio volumio[1538]: info: CoreCommandRouter::volumioGetVisibleSources May 31 11:48:53 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 11:48:53 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:53 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:53 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:53 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:53 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 31 11:48:53 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 11:48:53 volumio volumio[1538]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 31 11:48:53 volumio volumio[1538]: info: Received Get System Info May 31 11:48:53 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 11:48:53 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 11:48:53 volumio volumio[1538]: info: Discovery: Getting this device information May 31 11:48:53 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:53 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:53 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 11:48:53 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:48:53 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:48:53 volumio volumio[1538]: info: Listing playlists May 31 11:48:53 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 31 11:48:59 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput May 31 11:48:59 volumio volumio[1538]: info: MRS: Starting browser stream May 31 11:48:59 volumio volumio[1538]: info: MRS: Setting this device as Streaming Server May 31 11:48:59 volumio volumio[1538]: info: May 31 11:48:59 volumio volumio[1538]: [1780228139264] ---------------------------- MRS: Setting Streaming Server May 31 11:48:59 volumio volumio[1538]: info: Enabled audio output: browserPlayback May 31 11:48:59 volumio volumio[1538]: info: MRS: enable multiroom server output May 31 11:48:59 volumio volumio[1538]: info: MRS: Set multiroom target PCM to volumioMultiRoom May 31 11:48:59 volumio volumio[1538]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom May 31 11:48:59 volumio volumio[1538]: info: MRS: Set multiroom target PCM to volumioLocalPlayback May 31 11:48:59 volumio volumio[1538]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback May 31 11:48:59 volumio volumio[1538]: info: MRS: STARTING volumioStreaming May 31 11:48:59 volumio sudo[5038]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 31 11:48:59 volumio sudo[5038]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:59 volumio sudo[5038]: pam_unix(sudo:session): session closed for user root May 31 11:48:59 volumio sudo[5040]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming May 31 11:48:59 volumio sudo[5040]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:59 volumio systemd[1]: Started volumioStreaming.service - VolumioStreamingService. May 31 11:48:59 volumio sudo[5040]: pam_unix(sudo:session): session closed for user root May 31 11:48:59 volumio volumio[1538]: info: MRS: volumioStreaming STARTED May 31 11:48:59 volumio sudo[5044]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 31 11:48:59 volumio sudo[5044]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:48:59 volumio sudo[5044]: pam_unix(sudo:session): session closed for user root May 31 11:49:03 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 31 11:49:09 volumio volumio[1538]: info: CoreCommandRouter::volumioGetQueue May 31 11:49:09 volumio volumio[1538]: info: CoreStateMachine::getQueue May 31 11:49:09 volumio volumio[1538]: info: CorePlayQueue::getQueue May 31 11:49:12 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:49:12 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:49:14 volumio volumio[1538]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 11:49:14 volumio volumio[1538]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 11:49:14 volumio volumio[1538]: info: Retrieving Cloud Streaming UI May 31 11:49:14 volumio volumio[1538]: info: Getting Tidal Cloud Configuration May 31 11:49:14 volumio volumio[1538]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 11:49:14 volumio volumio[1538]: info: Getting Qobuz Cloud Configuration May 31 11:49:14 volumio volumio[1538]: info: Asking plugin for UI Config May 31 11:49:14 volumio volumio[1538]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 11:49:14 volumio volumio[1538]: info: Getting Spotify Cloud Configuration May 31 11:49:14 volumio volumio[1538]: info: Asking plugin for UI Config May 31 11:49:14 volumio volumio[1538]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 11:49:14 volumio volumio[1538]: info: Saving Spotify Acccount May 31 11:49:14 volumio volumio[1538]: info: Got it May 31 11:49:14 volumio volumio[1538]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 31 11:49:14 volumio volumio[1538]: info: Got Tidal Cloud Configuration May 31 11:49:14 volumio volumio[1538]: info: Got it May 31 11:49:14 volumio volumio[1538]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 11:49:14 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 31 11:49:14 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 31 11:49:14 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 31 11:49:14 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 31 11:49:14 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:49:14 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:49:14 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:49:14 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 11:49:14 volumio volumio[1538]: info: CoreCommandRouter::volumioGetBrowseSources May 31 11:49:14 volumio volumio[1538]: info: CoreCommandRouter::volumioGetBrowseSources May 31 11:49:14 volumio volumio[1538]: info: CoreCommandRouter::volumioGetBrowseSources May 31 11:49:14 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 11:49:14 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 31 11:49:18 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 11:49:22 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 11:49:22 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 31 11:49:22 volumio volumio[1538]: verbose: ControllerMpd::sendMpdCommand rescan May 31 11:49:22 volumio volumio[1538]: verbose: ControllerMpd::sendMpdCommand status May 31 11:49:22 volumio volumio[1538]: info: May 31 11:49:22 volumio volumio[1538]: ---------------------------- MPD announces state update: update May 31 11:49:22 volumio volumio[1538]: info: sendMpdCommand rescan took 1 milliseconds May 31 11:49:22 volumio volumio[1538]: info: ControllerMpd::getState May 31 11:49:22 volumio volumio[1538]: verbose: ControllerMpd::sendMpdCommand status May 31 11:49:22 volumio volumio[1538]: info: sendMpdCommand status took 5 milliseconds May 31 11:49:22 volumio volumio[1538]: info: sendMpdCommand status took 4 milliseconds May 31 11:49:22 volumio volumio[1538]: verbose: ControllerMpd::parseState May 31 11:49:25 volumio volumio[1538]: info: Command Router : Notfying DB Updatetrue May 31 11:49:25 volumio volumio[1538]: verbose: ControllerMpd::parseState May 31 11:49:25 volumio volumio[1538]: info: ControllerMpd::pushState May 31 11:49:25 volumio volumio[1538]: info: CoreCommandRouter::servicePushState May 31 11:49:25 volumio volumio[1538]: info: CoreStateMachine::pushState May 31 11:49:25 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:49:25 volumio volumio[1538]: info: CoreCommandRouter::volumioPushState May 31 11:49:25 volumio volumio[1538]: info: MRS: Pushing multiroomSync output update for this device May 31 11:49:25 volumio volumio[1538]: info: MRS: Pushing multiroomSync output May 31 11:49:25 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:49:25 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:49:25 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:49:25 volumio volumio[1538]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 31 11:49:25 volumio volumio[1538]: verbose: CURRENT POSITION 0 May 31 11:49:25 volumio volumio[1538]: info: CoreStateMachine::syncState stateService stop May 31 11:49:25 volumio volumio[1538]: info: CoreStateMachine::syncState currentStatus stop May 31 11:49:25 volumio volumio[1538]: info: CoreStateMachine::pushState May 31 11:49:25 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:49:25 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 11:49:25 volumio volumio[1538]: info: CoreCommandRouter::volumioPushState May 31 11:49:25 volumio volumio[1538]: info: MRS: Pushing multiroomSync output update for this device May 31 11:49:25 volumio volumio[1538]: info: MRS: Pushing multiroomSync output May 31 11:49:25 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:49:25 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:49:25 volumio volumio[1538]: info: No code May 31 11:49:25 volumio volumio[1538]: info: CoreStateMachine::pushState May 31 11:49:25 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:49:25 volumio volumio[1538]: info: CoreCommandRouter::volumioPushState May 31 11:49:25 volumio volumio[1538]: info: MRS: Pushing multiroomSync output update for this device May 31 11:49:25 volumio volumio[1538]: info: MRS: Pushing multiroomSync output May 31 11:49:25 volumio volumio[1538]: info: CoreCommandRouter::volumioGetState May 31 11:49:25 volumio volumio[1538]: info: CorePlayQueue::getTrack 0 May 31 11:49:25 volumio volumio[1538]: info: ------------------------------ 3109ms May 31 11:49:26 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 11:49:28 volumio volumio[1538]: info: CoreCommandRouter::Close All Modals sent May 31 11:49:28 volumio volumio[1538]: info: MRS: STARTING volumioStreaming May 31 11:49:28 volumio sudo[5144]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 31 11:49:28 volumio sudo[5144]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:49:28 volumio sudo[5144]: pam_unix(sudo:session): session closed for user root May 31 11:49:28 volumio sudo[5146]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming May 31 11:49:28 volumio sudo[5146]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:49:28 volumio systemd[1]: Stopping volumioStreaming.service - VolumioStreamingService... May 31 11:49:28 volumio systemd[1]: volumioStreaming.service: Killing process 5046 (ffmpeg) with signal SIGKILL. May 31 11:49:28 volumio systemd[1]: volumioStreaming.service: Deactivated successfully. May 31 11:49:28 volumio systemd[1]: Stopped volumioStreaming.service - VolumioStreamingService. May 31 11:49:28 volumio systemd[1]: Started volumioStreaming.service - VolumioStreamingService. May 31 11:49:28 volumio sudo[5146]: pam_unix(sudo:session): session closed for user root May 31 11:49:28 volumio volumio[1538]: info: MRS: volumioStreaming STARTED May 31 11:49:28 volumio sudo[5150]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 31 11:49:28 volumio sudo[5150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:49:28 volumio sudo[5150]: pam_unix(sudo:session): session closed for user root May 31 11:49:30 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 11:49:31 volumio volumio[1538]: info: Disabling MyMusic plugin upnp May 31 11:49:31 volumio sudo[5157]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service May 31 11:49:31 volumio sudo[5157]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 11:49:31 volumio systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... May 31 11:49:32 volumio volumio[1538]: info: Enabling MyMusic plugin upnp May 31 11:49:32 volumio volumio[1538]: info: Enabling plugin upnp May 31 11:49:32 volumio volumio[1538]: info: Loading plugin "upnp"... May 31 11:49:32 volumio volumio[1538]: info: [1780228172092] Starting Upmpd Daemon May 31 11:49:32 volumio volumio[1538]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 31 11:49:32 volumio volumio[1538]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 11:49:32 volumio volumio[1538]: Error: listen EADDRINUSE: address already in use :::6599 May 31 11:49:32 volumio volumio[1538]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) May 31 11:49:32 volumio volumio[1538]: at listenInCluster (node:net:1920:12) May 31 11:49:32 volumio volumio[1538]: at Server.listen (node:net:2008:7) May 31 11:49:32 volumio volumio[1538]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) May 31 11:49:32 volumio volumio[1538]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) May 31 11:49:32 volumio volumio[1538]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) May 31 11:49:32 volumio volumio[1538]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 31 11:49:32 volumio volumio[1538]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { May 31 11:49:32 volumio volumio[1538]: code: 'EADDRINUSE', May 31 11:49:32 volumio volumio[1538]: errno: -98, May 31 11:49:32 volumio volumio[1538]: syscall: 'listen', May 31 11:49:32 volumio volumio[1538]: address: '::', May 31 11:49:32 volumio volumio[1538]: port: 6599 May 31 11:49:32 volumio volumio[1538]: } May 31 11:49:32 volumio volumio[1538]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 11:49:32 volumio sudo[5173]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-31 11:48' May 31 11:49:32 volumio sudo[5173]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"