Dec 24 23:39:04 stereo volumio[1201]: verbose: New Socket.io Connection to 192.168.1.232 from 192.168.1.202 UA: Mozilla/5.0 (Linux; Android 15; CPH2699 Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 24 23:39:04 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 24 23:39:04 stereo volumio[1201]: info: CoreCommandRouter::volumioGetVisibleSources Dec 24 23:39:04 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 24 23:39:04 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:04 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 24 23:39:04 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 24 23:39:04 stereo volumio[1201]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 24 23:39:04 stereo volumio[1201]: info: Received Get System Info Dec 24 23:39:04 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:39:04 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:39:04 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:39:04 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:04 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:39:04 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:05 stereo volumio[1201]: info: Listing playlists Dec 24 23:39:05 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 24 23:39:07 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 24 23:39:07 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 24 23:39:08 stereo sudo[5718]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 24 23:39:08 stereo sudo[5718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:08 stereo sudo[5718]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:08 stereo sudo[5720]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 24 23:39:08 stereo sudo[5720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:09 stereo sudo[5720]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:09 stereo volumio[1201]: verbose: New Socket.io Connection to 192.168.1.232 from 192.168.1.202 UA: Mozilla/5.0 (Linux; Android 15; CPH2699 Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 24 23:39:09 stereo sudo[5738]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 24 23:39:09 stereo sudo[5738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:09 stereo sudo[5738]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:09 stereo sudo[5740]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 24 23:39:09 stereo sudo[5740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:09 stereo sudo[5740]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:09 stereo volumio[1201]: verbose: New Socket.io Connection to 192.168.1.232 from 192.168.1.202 UA: Mozilla/5.0 (Linux; Android 15; CPH2699 Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 24 23:39:09 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 24 23:39:09 stereo volumio[1201]: info: CoreCommandRouter::volumioGetVisibleSources Dec 24 23:39:09 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 24 23:39:10 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:10 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 24 23:39:10 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 24 23:39:10 stereo volumio[1201]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 24 23:39:10 stereo volumio[1201]: info: Received Get System Info Dec 24 23:39:10 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:39:10 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:39:10 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:39:10 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:10 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:39:10 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:10 stereo volumio[1201]: info: Listing playlists Dec 24 23:39:10 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 24 23:39:10 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 24 23:39:10 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 24 23:39:11 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 24 23:39:11 stereo volumio[1201]: info: Received Get System Info Dec 24 23:39:11 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:39:11 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:39:11 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:39:11 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:11 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:39:11 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 24 23:39:12 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 24 23:39:12 stereo volumio[1201]: info: Received Get System Info Dec 24 23:39:12 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:39:12 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:39:12 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:39:12 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:12 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:39:13 stereo volumio[1201]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 24 23:39:13 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 24 23:39:13 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 23:39:13 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 24 23:39:13 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:39:13 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:39:13 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:39:13 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:39:13 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:39:13 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:39:13 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:39:13 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 24 23:39:13 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 24 23:39:18 stereo kernel: hwmon hwmon1: Voltage normalised Dec 24 23:39:19 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 24 23:39:20 stereo kernel: hwmon hwmon1: Undervoltage detected! Dec 24 23:39:24 stereo kernel: hwmon hwmon1: Voltage normalised Dec 24 23:39:26 stereo kernel: hwmon hwmon1: Undervoltage detected! Dec 24 23:39:27 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 24 23:39:27 stereo volumio[1201]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 24 23:39:27 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 24 23:39:27 stereo volumio[1201]: info: Received Get System Version Dec 24 23:39:27 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 24 23:39:27 stereo volumio[1201]: info: Received Get System Info Dec 24 23:39:27 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:39:27 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:39:27 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:39:27 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:27 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:39:27 stereo volumio[1201]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 24 23:39:27 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 24 23:39:27 stereo volumio[1201]: info: Preparing to save Alsa Options, stopping services first Dec 24 23:39:27 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:27 stereo volumio[1201]: info: CoreCommandRouter::volumioPause Dec 24 23:39:27 stereo volumio[1201]: info: CoreStateMachine::pause Dec 24 23:39:27 stereo volumio[1201]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"Headphones"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Dec 24 23:39:27 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 24 23:39:28 stereo volumio[1201]: info: Found match in Cards Database: setting mixer PCM for card Headphones Dec 24 23:39:28 stereo volumio[1201]: info: QobuzConnect: setDeactiveState invoked Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:28 stereo vtcs[5059]: [2025-12-24 23:39:28.045] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 24 23:39:28 stereo vtcs[5059]: [2025-12-24 23:39:28.045] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 24 23:39:28 stereo sudo[5777]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 23:39:28 stereo sudo[5777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 24 23:39:28 stereo volumio[1201]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 24 23:39:28 stereo volumio[1201]: info: Disabling external Volume Control Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 24 23:39:28 stereo volumio[1201]: info: Preparing to generate the ALSA configuration file Dec 24 23:39:28 stereo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 24 23:39:28 stereo systemd[1]: vtcs.service: Killing process 5078 (vtcs) with signal SIGKILL. Dec 24 23:39:28 stereo systemd[1]: vtcs.service: Deactivated successfully. Dec 24 23:39:28 stereo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 24 23:39:28 stereo sudo[5777]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:28 stereo sudo[5784]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 23:39:28 stereo sudo[5784]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:28 stereo volumio[1201]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 24 23:39:28 stereo volumio[1201]: info: Reading ALSA contributions from plugins. Dec 24 23:39:28 stereo sudo[5792]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 24 23:39:28 stereo sudo[5792]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 24 23:39:28 stereo sudo[5784]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:28 stereo sudo[5792]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 24 23:39:28 stereo sudo[5802]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 24 23:39:28 stereo sudo[5802]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:28 stereo volumio[1201]: info: VolumeController:: Volume=87 Mute =false Dec 24 23:39:28 stereo volumio[1201]: info: CoreStateMachine::pushState Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 24 23:39:28 stereo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 24 23:39:28 stereo volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Dec 24 23:39:28 stereo volumio[1201]: info: MRS: Pushing multiroomSync output Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:28 stereo qobuz-connect[5051]: 20251224 23:39:28.555 [5051.5051] INFO SampleApp: Stopping Local configuration server Dec 24 23:39:28 stereo volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Dec 24 23:39:28 stereo volumio[1201]: info: Asound.conf file unchanged, so no further update is needed Dec 24 23:39:28 stereo volumio[1201]: info: Output device has changed, restarting MPD Dec 24 23:39:28 stereo volumio[1201]: info: Output device has changed, restarting Shairport Sync Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 23:39:28 stereo sudo[5805]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 24 23:39:28 stereo sudo[5805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:28 stereo sudo[5805]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:28 stereo sudo[5807]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 24 23:39:28 stereo sudo[5807]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:28 stereo volumio[1201]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 24 23:39:28 stereo volumio[1201]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:39:28 stereo volumio[1201]: info: QobuzConnect: setDeactiveState invoked Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:28 stereo systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 24 23:39:28 stereo upmpdcli[1336]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Dec 24 23:39:28 stereo sudo[5817]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 23:39:28 stereo sudo[5817]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:28 stereo volumio[1201]: info: MPD Permissions set Dec 24 23:39:28 stereo sudo[5819]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 23:39:28 stereo sudo[5819]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:39:28 stereo qobuz-connect[5051]: 20251224 23:39:28.920 [5051.5051] INFO SampleApp: shat down connection on UNIX socket Dec 24 23:39:28 stereo sudo[5827]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 24 23:39:28 stereo sudo[5827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:28 stereo systemd[1]: mpd.service: Deactivated successfully. Dec 24 23:39:28 stereo systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 24 23:39:28 stereo systemd[1]: mpd.service: Consumed 5.898s CPU time. Dec 24 23:39:28 stereo systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:39:28 stereo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 24 23:39:28 stereo volumio[1201]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 24 23:39:28 stereo volumio[1201]: info: QobuzConnect: setDeactiveState invoked Dec 24 23:39:28 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:28 stereo volumio[1201]: info: Starting Shairport Sync Dec 24 23:39:29 stereo sudo[5836]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 24 23:39:29 stereo sudo[5836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:29 stereo sudo[5827]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:29 stereo systemd[1]: mpd.socket: Deactivated successfully. Dec 24 23:39:29 stereo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 24 23:39:29 stereo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 24 23:39:29 stereo sudo[5838]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 24 23:39:29 stereo sudo[5838]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:29 stereo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 24 23:39:29 stereo systemd[1]: Starting mpd.service - Music Player Daemon... Dec 24 23:39:29 stereo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 24 23:39:29 stereo sudo[5802]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:29 stereo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 24 23:39:29 stereo systemd[1]: shairport-sync.service: Deactivated successfully. Dec 24 23:39:29 stereo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 24 23:39:29 stereo systemd[1]: shairport-sync.service: Consumed 3.732s CPU time. Dec 24 23:39:29 stereo volumio[1201]: info: Executing endpoint qc_getconfig Dec 24 23:39:29 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 24 23:39:29 stereo sudo[5817]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:29 stereo qobuz-connect[5842]: 20251224 23:39:29.448 [5842.5842] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 24 23:39:29 stereo sudo[5819]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:29 stereo volumio[1201]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 24 23:39:29 stereo volumio[1201]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 24 23:39:29 stereo qobuz-connect[5842]: 20251224 23:39:29.471 [5842.5842] INFO VolumeManager: [0x11a7178]: Setting new playback volume: 75 Dec 24 23:39:29 stereo qobuz-connect[5842]: 20251224 23:39:29.471 [5842.5842] INFO VolumeManager: [0x11a7178]: Setting new mute state: 0 Dec 24 23:39:29 stereo qobuz-connect[5842]: 20251224 23:39:29.471 [5842.5842] INFO QobuzConnect: [0x11a7b48]: Client initialized! Dec 24 23:39:29 stereo qobuz-connect[5842]: 20251224 23:39:29.471 [5842.5842] INFO SampleApp: Starting Avahi advertising, name: Stereo, service name: _qobuz-connect._tcp Dec 24 23:39:29 stereo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 24 23:39:29 stereo qobuz-connect[5842]: 20251224 23:39:29.529 [5842.5842] INFO LocalConfigManager: [0x11a6a58]: Starting Local Configuration server Dec 24 23:39:29 stereo qobuz-connect[5842]: 20251224 23:39:29.529 [5842.5842] INFO SampleApp: Starting Local configuration server Dec 24 23:39:29 stereo qobuz-connect[5842]: 20251224 23:39:29.538 [5842.5842] INFO SampleApp: Stopping Local configuration server Dec 24 23:39:29 stereo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 24 23:39:29 stereo sudo[5836]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:29 stereo volumio[1201]: info: Shairport-Sync Started Dec 24 23:39:29 stereo sudo[5841]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 24 23:39:29 stereo sudo[5841]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 24 23:39:29 stereo sudo[5841]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:31 stereo volumio[1201]: info: TidalConnect service stoped! Dec 24 23:39:31 stereo volumio[1201]: info: TidalConnect service stoped! Dec 24 23:39:31 stereo volumio[1201]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 24 23:39:31 stereo volumio[1201]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 24 23:39:31 stereo qobuz-connect[5842]: 20251224 23:39:31.542 [5842.5842] INFO SampleApp: shat down connection on UNIX socket Dec 24 23:39:31 stereo volumio[1201]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 24 23:39:31 stereo volumio[1201]: info: QobuzConnect: setDeactiveState invoked Dec 24 23:39:31 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:31 stereo sudo[5890]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 24 23:39:31 stereo systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV Dec 24 23:39:31 stereo sudo[5890]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:31 stereo systemd[1]: qobuz-connect.service: Failed with result 'signal'. Dec 24 23:39:31 stereo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 24 23:39:31 stereo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 24 23:39:31 stereo sudo[5838]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:31 stereo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 24 23:39:31 stereo sudo[5890]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:31 stereo volumio[1201]: info: Executing endpoint qc_getconfig Dec 24 23:39:31 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 24 23:39:31 stereo qobuz-connect[5892]: 20251224 23:39:31.770 [5892.5892] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 24 23:39:31 stereo volumio[1201]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 24 23:39:31 stereo volumio[1201]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 24 23:39:31 stereo qobuz-connect[5892]: 20251224 23:39:31.786 [5892.5892] INFO VolumeManager: [0x96d178]: Setting new playback volume: 75 Dec 24 23:39:31 stereo qobuz-connect[5892]: 20251224 23:39:31.787 [5892.5892] INFO VolumeManager: [0x96d178]: Setting new mute state: 0 Dec 24 23:39:31 stereo qobuz-connect[5892]: 20251224 23:39:31.787 [5892.5892] INFO QobuzConnect: [0x96db48]: Client initialized! Dec 24 23:39:31 stereo qobuz-connect[5892]: 20251224 23:39:31.787 [5892.5892] INFO SampleApp: Starting Avahi advertising, name: Stereo, service name: _qobuz-connect._tcp Dec 24 23:39:31 stereo qobuz-connect[5892]: 20251224 23:39:31.830 [5892.5892] INFO LocalConfigManager: [0x96ca58]: Starting Local Configuration server Dec 24 23:39:31 stereo qobuz-connect[5892]: 20251224 23:39:31.831 [5892.5892] INFO SampleApp: Starting Local configuration server Dec 24 23:39:31 stereo qobuz-connect[5892]: 20251224 23:39:31.832 [5892.5892] INFO SampleApp: Connected to UNIX socket client 0x957818 Dec 24 23:39:31 stereo qobuz-connect[5892]: 20251224 23:39:31.921 [5892.5892] INFO SampleApp: Playback volume changed: 75 Dec 24 23:39:31 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:32 stereo volumio[1201]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 24 23:39:32 stereo volumio[1201]: info: CoreStateMachine::getcurrentVolume Dec 24 23:39:32 stereo volumio[1201]: info: CoreCommandRouter::volumioRetrievevolume Dec 24 23:39:32 stereo volumio[1201]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 24 23:39:32 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 24 23:39:32 stereo volumio[1201]: info: Preparing to save Alsa Options, stopping services first Dec 24 23:39:32 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:32 stereo volumio[1201]: info: CoreCommandRouter::volumioPause Dec 24 23:39:32 stereo volumio[1201]: info: CoreStateMachine::pause Dec 24 23:39:32 stereo volumio[1201]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"Headphones"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Dec 24 23:39:32 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 24 23:39:32 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 24 23:39:32 stereo volumio[1201]: info: Found match in Cards Database: setting mixer PCM for card Headphones Dec 24 23:39:32 stereo volumio[1201]: info: QobuzConnect: setDeactiveState invoked Dec 24 23:39:32 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:32 stereo sudo[5909]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 23:39:32 stereo sudo[5909]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:32 stereo volumio[1201]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 24 23:39:32 stereo volumio[1201]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 24 23:39:32 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 24 23:39:32 stereo volumio[1201]: info: Disabling external Volume Control Dec 24 23:39:32 stereo volumio[1201]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 24 23:39:32 stereo volumio[1201]: info: Preparing to generate the ALSA configuration file Dec 24 23:39:32 stereo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 24 23:39:32 stereo systemd[1]: vtcs.service: Deactivated successfully. Dec 24 23:39:32 stereo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 24 23:39:32 stereo sudo[5916]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 23:39:32 stereo sudo[5909]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:32 stereo sudo[5916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:32 stereo volumio[1201]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 24 23:39:32 stereo volumio[1201]: info: Reading ALSA contributions from plugins. Dec 24 23:39:32 stereo volumio[1201]: info: TidalConnect service stoped! Dec 24 23:39:32 stereo sudo[5924]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 24 23:39:32 stereo sudo[5924]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:32 stereo volumio[1201]: info: TidalConnect service stoped! Dec 24 23:39:32 stereo sudo[5916]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:32 stereo volumio[1201]: info: Executing endpoint tc_getconfig Dec 24 23:39:32 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 24 23:39:32 stereo volumio[1201]: info: VolumeController:: Volume=87 Mute =false Dec 24 23:39:32 stereo volumio[1201]: info: CoreStateMachine::pushState Dec 24 23:39:32 stereo volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 24 23:39:32 stereo sudo[5924]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:32 stereo volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Dec 24 23:39:32 stereo volumio[1201]: info: MRS: Pushing multiroomSync output Dec 24 23:39:32 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:32 stereo volumio[1201]: info: CoreStateMachine::updateTrackBlock Dec 24 23:39:32 stereo volumio[1201]: info: CorePlayQueue::getTrackBlock Dec 24 23:39:32 stereo volumio[1201]: info: CoreCommandRouter::volumioRetrievevolume Dec 24 23:39:32 stereo sudo[5937]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 24 23:39:32 stereo sudo[5937]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:32 stereo volumio[1201]: info: VolumeController:: Volume=87 Mute =false Dec 24 23:39:32 stereo volumio[1201]: info: CoreStateMachine::pushState Dec 24 23:39:32 stereo volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 24 23:39:32 stereo volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Dec 24 23:39:32 stereo volumio[1201]: info: MRS: Pushing multiroomSync output Dec 24 23:39:32 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:32 stereo qobuz-connect[5892]: 20251224 23:39:32.925 [5892.5892] INFO SampleApp: Stopping Local configuration server Dec 24 23:39:32 stereo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 24 23:39:33 stereo volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Dec 24 23:39:33 stereo volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Dec 24 23:39:33 stereo volumio[1201]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 24 23:39:33 stereo volumio[1201]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 24 23:39:33 stereo volumio[1201]: info: VolumeController:: Volume=87 Mute =false Dec 24 23:39:33 stereo volumio[1201]: info: CoreStateMachine::pushState Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 24 23:39:33 stereo volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Dec 24 23:39:33 stereo volumio[1201]: info: MRS: Pushing multiroomSync output Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:33 stereo volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Dec 24 23:39:33 stereo volumio[1201]: info: Asound.conf file unchanged, so no further update is needed Dec 24 23:39:33 stereo volumio[1201]: info: Output device has changed, restarting MPD Dec 24 23:39:33 stereo sudo[5948]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 24 23:39:33 stereo sudo[5948]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:33 stereo volumio[1201]: info: Output device has changed, restarting Shairport Sync Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 23:39:33 stereo volumio[1201]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 24 23:39:33 stereo volumio[1201]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:39:33 stereo sudo[5951]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 24 23:39:33 stereo sudo[5951]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:33 stereo volumio[1201]: info: QobuzConnect: setDeactiveState invoked Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:33 stereo sudo[5953]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 24 23:39:33 stereo sudo[5953]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:33 stereo sudo[5951]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:33 stereo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 24 23:39:33 stereo sudo[5964]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 23:39:33 stereo sudo[5964]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:33 stereo sudo[5948]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:33 stereo sudo[5968]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 23:39:33 stereo sudo[5968]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:33 stereo systemd[1]: mpd.service: Deactivated successfully. Dec 24 23:39:33 stereo systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 24 23:39:33 stereo systemd[1]: mpd.service: Consumed 4.110s CPU time. Dec 24 23:39:33 stereo volumio[1201]: info: MPD Permissions set Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:39:33 stereo sudo[5976]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 24 23:39:33 stereo sudo[5976]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:33 stereo systemd[1]: mpd.socket: Deactivated successfully. Dec 24 23:39:33 stereo upmpdcli[1336]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Connection reset by peer Dec 24 23:39:33 stereo upmpdcli[1336]: :2:../src/mpdcli.cxx:396::MPDCli::updStatus: connection failed Dec 24 23:39:33 stereo upmpdcli[1336]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Dec 24 23:39:33 stereo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 24 23:39:33 stereo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 24 23:39:33 stereo qobuz-connect[5892]: 20251224 23:39:33.840 [5892.5892] INFO SampleApp: shat down connection on UNIX socket Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:39:33 stereo volumio[1201]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 24 23:39:33 stereo volumio[1201]: info: QobuzConnect: setDeactiveState invoked Dec 24 23:39:33 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:33 stereo volumio[1201]: info: Starting Shairport Sync Dec 24 23:39:33 stereo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 24 23:39:33 stereo systemd[1]: Starting mpd.service - Music Player Daemon... Dec 24 23:39:33 stereo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 24 23:39:33 stereo systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 24 23:39:33 stereo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 24 23:39:33 stereo systemd[1]: vtcs.service: Deactivated successfully. Dec 24 23:39:33 stereo sudo[5987]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 24 23:39:33 stereo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 24 23:39:33 stereo sudo[5987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:34 stereo sudo[5964]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:34 stereo sudo[5968]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:34 stereo sudo[5976]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:34 stereo sudo[5990]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 24 23:39:34 stereo sudo[5990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:34 stereo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 24 23:39:34 stereo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 24 23:39:34 stereo sudo[5937]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:34 stereo systemd[1]: shairport-sync.service: Deactivated successfully. Dec 24 23:39:34 stereo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 24 23:39:34 stereo systemd[1]: shairport-sync.service: Consumed 1.784s CPU time. Dec 24 23:39:34 stereo volumio[1201]: info: Executing endpoint qc_getconfig Dec 24 23:39:34 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 24 23:39:34 stereo sudo[5986]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 24 23:39:34 stereo sudo[5986]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 24 23:39:34 stereo sudo[5986]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:34 stereo qobuz-connect[5993]: 20251224 23:39:34.503 [5993.5993] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 24 23:39:34 stereo volumio[1201]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 24 23:39:34 stereo volumio[1201]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 24 23:39:34 stereo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 24 23:39:34 stereo qobuz-connect[5993]: 20251224 23:39:34.526 [5993.5993] INFO VolumeManager: [0x1b38178]: Setting new playback volume: 75 Dec 24 23:39:34 stereo qobuz-connect[5993]: 20251224 23:39:34.527 [5993.5993] INFO VolumeManager: [0x1b38178]: Setting new mute state: 0 Dec 24 23:39:34 stereo qobuz-connect[5993]: 20251224 23:39:34.528 [5993.5993] INFO QobuzConnect: [0x1b38b48]: Client initialized! Dec 24 23:39:34 stereo qobuz-connect[5993]: 20251224 23:39:34.528 [5993.5993] INFO SampleApp: Starting Avahi advertising, name: Stereo, service name: _qobuz-connect._tcp Dec 24 23:39:34 stereo sudo[5987]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:34 stereo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 24 23:39:34 stereo volumio[1201]: info: Shairport-Sync Started Dec 24 23:39:34 stereo qobuz-connect[5993]: 20251224 23:39:34.604 [5993.5993] INFO LocalConfigManager: [0x1b37a58]: Starting Local Configuration server Dec 24 23:39:34 stereo qobuz-connect[5993]: 20251224 23:39:34.604 [5993.5993] INFO SampleApp: Starting Local configuration server Dec 24 23:39:34 stereo qobuz-connect[5993]: 20251224 23:39:34.609 [5993.5993] INFO SampleApp: Stopping Local configuration server Dec 24 23:39:34 stereo volumio[1201]: info: CoreCommandRouter::volumioVolatilePlay Dec 24 23:39:34 stereo volumio[1201]: ------------------------------------ BT MESSAGE: [FUNC] play Dec 24 23:39:34 stereo volumio[1201]: ------------------------------------ BT MESSAGE: sendPlay skipped: activePlayer not bound or invalid Dec 24 23:39:34 stereo volumio[1201]: info: TidalConnect service started! Dec 24 23:39:35 stereo volumio[1201]: info: TidalConnect service stoped! Dec 24 23:39:35 stereo volumio[1201]: info: TidalConnect service stoped! Dec 24 23:39:35 stereo volumio[1201]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 24 23:39:35 stereo volumio[1201]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 24 23:39:35 stereo sudo[6025]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 24 23:39:35 stereo sudo[6025]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:36 stereo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 24 23:39:36 stereo sudo[6025]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:36 stereo volumio[1201]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 24 23:39:36 stereo volumio[1201]: info: CoreStateMachine::getcurrentVolume Dec 24 23:39:36 stereo volumio[1201]: info: CoreCommandRouter::volumioRetrievevolume Dec 24 23:39:36 stereo volumio[1201]: info: VolumeController:: Volume=87 Mute =false Dec 24 23:39:36 stereo volumio[1201]: info: CoreStateMachine::pushState Dec 24 23:39:36 stereo volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 24 23:39:36 stereo volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Dec 24 23:39:36 stereo volumio[1201]: info: MRS: Pushing multiroomSync output Dec 24 23:39:36 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:36 stereo volumio[1201]: info: CoreStateMachine::updateTrackBlock Dec 24 23:39:36 stereo volumio[1201]: info: CorePlayQueue::getTrackBlock Dec 24 23:39:36 stereo volumio[1201]: info: CoreCommandRouter::volumioRetrievevolume Dec 24 23:39:36 stereo volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Dec 24 23:39:36 stereo volumio[1201]: info: Executing endpoint tc_getconfig Dec 24 23:39:36 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 24 23:39:36 stereo vtcs[6028]: STARTING TidalConnect services, version: 1.5.2.56 Dec 24 23:39:36 stereo qobuz-connect[5993]: 20251224 23:39:36.629 [5993.5993] INFO SampleApp: shat down connection on UNIX socket Dec 24 23:39:36 stereo volumio[1201]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 24 23:39:36 stereo volumio[1201]: info: QobuzConnect: setDeactiveState invoked Dec 24 23:39:36 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:36 stereo systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV Dec 24 23:39:36 stereo systemd[1]: qobuz-connect.service: Failed with result 'signal'. Dec 24 23:39:36 stereo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 24 23:39:36 stereo vtcs[6028]: STARTED TidalConnect services. Dec 24 23:39:36 stereo volumio[1201]: info: Executing endpoint tc_connect Dec 24 23:39:36 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 24 23:39:36 stereo volumio[1201]: info: Connecting to TidalConnect Dec 24 23:39:36 stereo volumio[1201]: info: VolumeController:: Volume=87 Mute =false Dec 24 23:39:36 stereo volumio[1201]: info: CoreStateMachine::pushState Dec 24 23:39:36 stereo volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 24 23:39:36 stereo volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Dec 24 23:39:36 stereo volumio[1201]: info: MRS: Pushing multiroomSync output Dec 24 23:39:36 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:36 stereo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 24 23:39:36 stereo sudo[5990]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:36 stereo volumio[1201]: info: CoreCommandRouter::servicePushState Dec 24 23:39:36 stereo volumio[1201]: info: CoreStateMachine::pushState Dec 24 23:39:36 stereo volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 24 23:39:36 stereo volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Dec 24 23:39:36 stereo volumio[1201]: info: MRS: Pushing multiroomSync output Dec 24 23:39:36 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:36 stereo volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 24 23:39:36 stereo volumio[1201]: 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} Dec 24 23:39:36 stereo volumio[1201]: verbose: CURRENT POSITION 0 Dec 24 23:39:36 stereo volumio[1201]: info: CoreStateMachine::syncState stateService stop Dec 24 23:39:36 stereo volumio[1201]: info: CoreStateMachine::syncState currentStatus pause Dec 24 23:39:36 stereo volumio[1201]: info: CoreStateMachine::pushState Dec 24 23:39:36 stereo volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 24 23:39:36 stereo volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Dec 24 23:39:36 stereo volumio[1201]: info: MRS: Pushing multiroomSync output Dec 24 23:39:36 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:36 stereo volumio[1201]: info: CoreCommandRouter::servicePushState Dec 24 23:39:36 stereo volumio[1201]: info: CoreStateMachine::pushState Dec 24 23:39:36 stereo volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 24 23:39:36 stereo volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Dec 24 23:39:36 stereo volumio[1201]: info: MRS: Pushing multiroomSync output Dec 24 23:39:36 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:36 stereo volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 24 23:39:36 stereo volumio[1201]: 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} Dec 24 23:39:36 stereo volumio[1201]: verbose: CURRENT POSITION 0 Dec 24 23:39:36 stereo volumio[1201]: info: CoreStateMachine::syncState stateService stop Dec 24 23:39:36 stereo volumio[1201]: info: CoreStateMachine::syncState currentStatus pause Dec 24 23:39:36 stereo volumio[1201]: info: CoreStateMachine::pushState Dec 24 23:39:36 stereo volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 24 23:39:36 stereo volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Dec 24 23:39:36 stereo volumio[1201]: info: MRS: Pushing multiroomSync output Dec 24 23:39:36 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:37 stereo volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Dec 24 23:39:37 stereo volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Dec 24 23:39:37 stereo volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Dec 24 23:39:37 stereo volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Dec 24 23:39:37 stereo volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Dec 24 23:39:37 stereo volumio[1201]: info: TidalConnect service started! Dec 24 23:39:37 stereo volumio[1201]: info: Executing endpoint qc_getconfig Dec 24 23:39:37 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 24 23:39:37 stereo qobuz-connect[6040]: 20251224 23:39:37.155 [6040.6040] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 24 23:39:37 stereo volumio[1201]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 24 23:39:37 stereo volumio[1201]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 24 23:39:37 stereo qobuz-connect[6040]: 20251224 23:39:37.168 [6040.6040] INFO VolumeManager: [0x6b0178]: Setting new playback volume: 75 Dec 24 23:39:37 stereo qobuz-connect[6040]: 20251224 23:39:37.168 [6040.6040] INFO VolumeManager: [0x6b0178]: Setting new mute state: 0 Dec 24 23:39:37 stereo qobuz-connect[6040]: 20251224 23:39:37.169 [6040.6040] INFO QobuzConnect: [0x6b0b48]: Client initialized! Dec 24 23:39:37 stereo qobuz-connect[6040]: 20251224 23:39:37.169 [6040.6040] INFO SampleApp: Starting Avahi advertising, name: Stereo, service name: _qobuz-connect._tcp Dec 24 23:39:37 stereo volumio[1201]: info: TidalConnect service stoped! Dec 24 23:39:37 stereo qobuz-connect[6040]: 20251224 23:39:37.205 [6040.6040] INFO LocalConfigManager: [0x6afa58]: Starting Local Configuration server Dec 24 23:39:37 stereo qobuz-connect[6040]: 20251224 23:39:37.205 [6040.6040] INFO SampleApp: Starting Local configuration server Dec 24 23:39:37 stereo qobuz-connect[6040]: 20251224 23:39:37.206 [6040.6040] INFO SampleApp: Connected to UNIX socket client 0x69a818 Dec 24 23:39:37 stereo volumio[1201]: info: TidalConnect service stoped! Dec 24 23:39:37 stereo volumio[1201]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 24 23:39:37 stereo volumio[1201]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 24 23:39:37 stereo qobuz-connect[6040]: 20251224 23:39:37.307 [6040.6040] INFO SampleApp: Playback volume changed: 75 Dec 24 23:39:37 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:37 stereo sudo[6063]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 24 23:39:37 stereo sudo[6063]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:37 stereo sudo[6063]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:39 stereo volumio[1201]: info: TidalConnect service started! Dec 24 23:39:39 stereo mpd[5999]: 2025-12-24T23:39:39 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 24 23:39:39 stereo upmpdcli[1336]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Dec 24 23:39:39 stereo systemd[1]: Started mpd.service - Music Player Daemon. Dec 24 23:39:39 stereo sudo[5807]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:39 stereo sudo[5953]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:39 stereo upmpdcli[1336]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Dec 24 23:39:39 stereo volumio[1201]: error: MPD error: The expression evaluated to a falsy value: Dec 24 23:39:39 stereo volumio[1201]: assert.ok(self.idling) Dec 24 23:39:39 stereo volumio[1201]: error: The expression evaluated to a falsy value: Dec 24 23:39:39 stereo volumio[1201]: assert.ok(self.idling) Dec 24 23:39:39 stereo volumio[1201]: error: updateQueue error: null Dec 24 23:39:40 stereo volumio[1201]: info: TidalConnect service started! Dec 24 23:39:42 stereo sudo[6086]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 24 23:39:42 stereo sudo[6086]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:42 stereo sudo[6089]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 24 23:39:42 stereo sudo[6089]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:39:42 stereo systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 24 23:39:42 stereo systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 24 23:39:42 stereo systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 24 23:39:42 stereo systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 24 23:39:42 stereo sudo[6086]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:42 stereo mpd_monitor.sh[6091]: MPD Monitor Service: Starting MPD Monitor Service Dec 24 23:39:42 stereo volumio[1201]: info: Successfully started MPD Monitor Dec 24 23:39:43 stereo systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 24 23:39:43 stereo systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 24 23:39:43 stereo systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 24 23:39:43 stereo systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 24 23:39:43 stereo mpd_monitor.sh[6096]: MPD Monitor Service: Starting MPD Monitor Service Dec 24 23:39:43 stereo sudo[6089]: pam_unix(sudo:session): session closed for user root Dec 24 23:39:43 stereo volumio[1201]: info: Successfully started MPD Monitor Dec 24 23:39:43 stereo go-librespot[1486]: time="2025-12-24T23:39:43+01:00" level=debug msg="obtained new client token: AABCFw+iJ5wfTC4/7l+xAY2QvPGGMdrPwwCDKUFIiZ6BYbJ4JDE76o1r3YZc6nvMPdc+ObbbBEQ/cNly4LbWkaLkTkL+7FR4FDGk60C1r9LEf9ek0YjHTzjlHvITMVxqaG1MZ7EAwT/d0c5bFKsMe7FS548WvdDNW/JgSJh9aeXAIX+xrrgkGQWcB6mGrqKZGdyIFNlEOlSL9SEBD82eoQWXs00CTOUNLVrPJChA6H6iXn14iO2PqfSjGOs=" Dec 24 23:39:43 stereo go-librespot[1486]: time="2025-12-24T23:39:43+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 24 23:39:43 stereo go-librespot[1486]: time="2025-12-24T23:39:43+01:00" level=debug msg="completed keyexchange" Dec 24 23:39:43 stereo go-librespot[1486]: time="2025-12-24T23:39:43+01:00" level=debug msg="completed challenge" Dec 24 23:39:43 stereo go-librespot[1486]: time="2025-12-24T23:39:43+01:00" level=error msg="failed creating new session from OPPO Reno13 F 5G" error="failed authenticating accesspoint with blob: failed authenticating: accesspoint login failed: BadCredentials " username="bj****11" Dec 24 23:39:43 stereo go-librespot[1486]: time="2025-12-24T23:39:43+01:00" level=info msg="refused zeroconf from OPPO Reno13 F 5G" username="bj****11" Dec 24 23:39:46 stereo go-librespot[1486]: time="2025-12-24T23:39:46+01:00" level=debug msg="obtained new client token: AABpPu+uddnM4zNoSrrdlrPCJJxGwu5r6kjSEIjfS0CKOfHHMl0BNuVJYyNKT65jKo5RdC7XJqddga7oVvim3gzeyreVFDXJRKATYJCBLaWFsCWc3oDHTzPjlyIhDS4EgQMYWhnQra8WP6+lz5UYc1oqJQBBJpSX4kAgdarOxBs3VrYBEQCp866VWcU8I96Mar0YEVg2L46ZIhiQLq6VRjWudE5KVnQohBsDz+U8apCP3J6d2v0iy8TV0gk=" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=debug msg="completed keyexchange" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=debug msg="completed challenge" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=info msg="authenticated AP" username="bj****11" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=info msg="authenticated Login5" username="bj****11" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=info msg="accepted zeroconf from OPPO Reno13 F 5G" username="bj****11" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=debug msg="dealer connection opened" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=trace msg="starting accesspoint recv loop" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=trace msg="starting dealer recv loop" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=trace msg="received accesspoint ping" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=debug msg="received connection id: ZTI3ZThlZWQtZjc1...NDcyQjFFRDIzMw==" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=trace msg="received accesspoint pong ack" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=debug msg="put connect state because NEW_DEVICE" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=debug msg="handling transfer player command from 20f735289b7faebc0deed7bd371055397faaf5fd" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1EQn4jwNIohw50" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" uri="spotify:playlist:37i9dQZF1EQn4jwNIohw50" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=debug msg="shuffled context with seed 11196133775971818075 (len: 50, keep: 43)" uri="spotify:playlist:37i9dQZF1EQn4jwNIohw50" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=debug msg="loading track (paused: true, position: 30983ms)" uri="spotify:track:2aI21FnmY7TJVKeMaoQZ0t" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=trace msg="emitting websocket event: will_play" Dec 24 23:39:47 stereo volumio[1201]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:37i9dQZF1EQn4jwNIohw50","uri":"spotify:track:2aI21FnmY7TJVKeMaoQZ0t","play_origin":"playlist"}} Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1109" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=debug msg="selected format OGG_VORBIS_320 (67acd3cc17e53cb9001f04c8e0fc46562afc7238)" uri="spotify:track:2aI21FnmY7TJVKeMaoQZ0t" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=debug msg="requested aes key for file 67acd3cc17e53cb9001f04c8e0fc46562afc7238, gid: 2aI21FnmY7TJVKeMaoQZ0t" Dec 24 23:39:47 stereo go-librespot[1486]: time="2025-12-24T23:39:47+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:2aI21FnmY7TJVKeMaoQZ0t" Dec 24 23:39:48 stereo go-librespot[1486]: time="2025-12-24T23:39:48+01:00" level=debug msg="fetched first chunk of 19, total size is 9831800 bytes" uri="spotify:track:2aI21FnmY7TJVKeMaoQZ0t" Dec 24 23:39:48 stereo go-librespot[1486]: time="2025-12-24T23:39:48+01:00" level=debug msg="fetched chunk 2/18, size: 524288" uri="spotify:track:2aI21FnmY7TJVKeMaoQZ0t" Dec 24 23:39:48 stereo go-librespot[1486]: time="2025-12-24T23:39:48+01:00" level=trace msg="seek to 30983ms (diff: 159ms, samples: 1366350, bytes: 1343452)" uri="spotify:track:2aI21FnmY7TJVKeMaoQZ0t" Dec 24 23:39:48 stereo go-librespot[1486]: time="2025-12-24T23:39:48+01:00" level=debug msg="created new output device" Dec 24 23:39:48 stereo go-librespot[1486]: time="2025-12-24T23:39:48+01:00" level=info msg="loaded track \"Say It Right\" (paused: true, position: 30983ms, duration: 223080ms, prefetched: false)" uri="spotify:track:2aI21FnmY7TJVKeMaoQZ0t" Dec 24 23:39:48 stereo go-librespot[1486]: time="2025-12-24T23:39:48+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 24 23:39:48 stereo go-librespot[1486]: time="2025-12-24T23:39:48+01:00" level=trace msg="emitting websocket event: metadata" Dec 24 23:39:48 stereo volumio[1201]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2aI21FnmY7TJVKeMaoQZ0t","name":"Say It Right","artist_names":["Nelly Furtado"],"album_name":"Loose","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02a6f439c8957170652f9410e2","position":30983,"duration":223080,"release_date":"year:2006 month:1 day:1","track_number":10,"disc_number":1}} Dec 24 23:39:48 stereo go-librespot[1486]: time="2025-12-24T23:39:48+01:00" level=trace msg="emitting websocket event: active" Dec 24 23:39:48 stereo go-librespot[1486]: time="2025-12-24T23:39:48+01:00" level=debug msg="sending successful reply for dealer request" Dec 24 23:39:48 stereo volumio[1201]: SPOTIFY: received: {"type":"active","data":null} Dec 24 23:39:48 stereo volumio[1201]: info: Aligning Spotify Volume to Volumio Volume Dec 24 23:39:48 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:48 stereo volumio[1201]: info: Setting Spotify Volume from Volumio: 87 Dec 24 23:39:48 stereo go-librespot[1486]: time="2025-12-24T23:39:48+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Dec 24 23:39:48 stereo go-librespot[1486]: time="2025-12-24T23:39:48+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 24 23:39:48 stereo go-librespot[1486]: time="2025-12-24T23:39:48+01:00" level=debug msg="fetched chunk 1/18, size: 524288" uri="spotify:track:2aI21FnmY7TJVKeMaoQZ0t" Dec 24 23:39:48 stereo go-librespot[1486]: time="2025-12-24T23:39:48+01:00" level=debug msg="fetched chunk 5/18, size: 524288" uri="spotify:track:2aI21FnmY7TJVKeMaoQZ0t" Dec 24 23:39:48 stereo go-librespot[1486]: time="2025-12-24T23:39:48+01:00" level=debug msg="fetched chunk 4/18, size: 524288" uri="spotify:track:2aI21FnmY7TJVKeMaoQZ0t" Dec 24 23:39:48 stereo go-librespot[1486]: time="2025-12-24T23:39:48+01:00" level=debug msg="fetched chunk 3/18, size: 524288" uri="spotify:track:2aI21FnmY7TJVKeMaoQZ0t" Dec 24 23:39:48 stereo go-librespot[1486]: time="2025-12-24T23:39:48+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 24 23:39:48 stereo go-librespot[1486]: time="2025-12-24T23:39:48+01:00" level=trace msg="emitting websocket event: paused" Dec 24 23:39:48 stereo volumio[1201]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:37i9dQZF1EQn4jwNIohw50","uri":"spotify:track:2aI21FnmY7TJVKeMaoQZ0t","play_origin":"playlist"}} Dec 24 23:39:48 stereo volumio[1201]: SPOTIFY: PUSH STATE SPOTIFY Dec 24 23:39:48 stereo volumio[1201]: SPOTIFY: {"status":"pause","service":"spop","title":"Say It Right","artist":"Nelly Furtado","album":"Loose","albumart":"https://i.scdn.co/image/ab67616d00001e02a6f439c8957170652f9410e2","uri":"spotify:track:2aI21FnmY7TJVKeMaoQZ0t","trackType":"spotify","seek":30983,"duration":223,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 24 23:39:48 stereo volumio[1201]: info: CoreCommandRouter::servicePushState Dec 24 23:39:48 stereo volumio[1201]: info: CoreStateMachine::pushState Dec 24 23:39:48 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 23:39:48 stereo volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 24 23:39:48 stereo volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Dec 24 23:39:48 stereo volumio[1201]: info: MRS: Pushing multiroomSync output Dec 24 23:39:48 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:48 stereo volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Dec 24 23:39:48 stereo go-librespot[1486]: time="2025-12-24T23:39:48+01:00" level=debug msg="handling resume player command from 20f735289b7faebc0deed7bd371055397faaf5fd" Dec 24 23:39:48 stereo go-librespot[1486]: time="2025-12-24T23:39:48+01:00" level=trace msg="seek to 30983ms (diff: 159ms, samples: 1366350, bytes: 1343452)" uri="spotify:track:2aI21FnmY7TJVKeMaoQZ0t" Dec 24 23:39:48 stereo go-librespot[1486]: time="2025-12-24T23:39:48+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Dec 24 23:39:48 stereo go-librespot[1486]: time="2025-12-24T23:39:48+01:00" level=debug msg="resume track at 30824ms" Dec 24 23:39:49 stereo go-librespot[1486]: time="2025-12-24T23:39:49+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 24 23:39:49 stereo go-librespot[1486]: time="2025-12-24T23:39:49+01:00" level=trace msg="scheduling prefetch in 162s" Dec 24 23:39:49 stereo go-librespot[1486]: time="2025-12-24T23:39:49+01:00" level=debug msg="sending successful reply for dealer request" Dec 24 23:39:49 stereo go-librespot[1486]: time="2025-12-24T23:39:49+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 24 23:39:49 stereo go-librespot[1486]: time="2025-12-24T23:39:49+01:00" level=trace msg="emitting websocket event: playing" Dec 24 23:39:49 stereo volumio[1201]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:playlist:37i9dQZF1EQn4jwNIohw50","uri":"spotify:track:2aI21FnmY7TJVKeMaoQZ0t","resume":true,"play_origin":"playlist"}} Dec 24 23:39:49 stereo volumio[1201]: SPOTIFY: PUSH STATE SPOTIFY Dec 24 23:39:49 stereo volumio[1201]: SPOTIFY: {"status":"play","service":"spop","title":"Say It Right","artist":"Nelly Furtado","album":"Loose","albumart":"https://i.scdn.co/image/ab67616d00001e02a6f439c8957170652f9410e2","uri":"spotify:track:2aI21FnmY7TJVKeMaoQZ0t","trackType":"spotify","seek":30983,"duration":223,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 24 23:39:49 stereo volumio[1201]: info: CoreCommandRouter::servicePushState Dec 24 23:39:49 stereo volumio[1201]: info: CoreStateMachine::pushState Dec 24 23:39:49 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 23:39:49 stereo volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 24 23:39:49 stereo volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Dec 24 23:39:49 stereo volumio[1201]: info: MRS: Pushing multiroomSync output Dec 24 23:39:49 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:49 stereo volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Dec 24 23:39:49 stereo volumio[1201]: SPOTIFY: PUSH STATE SPOTIFY Dec 24 23:39:49 stereo volumio[1201]: SPOTIFY: {"status":"play","service":"spop","title":"Say It Right","artist":"Nelly Furtado","album":"Loose","albumart":"https://i.scdn.co/image/ab67616d00001e02a6f439c8957170652f9410e2","uri":"spotify:track:2aI21FnmY7TJVKeMaoQZ0t","trackType":"spotify","seek":30983,"duration":223,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 24 23:39:49 stereo volumio[1201]: info: CoreCommandRouter::servicePushState Dec 24 23:39:49 stereo volumio[1201]: info: CoreStateMachine::pushState Dec 24 23:39:49 stereo volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 24 23:39:49 stereo volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Dec 24 23:39:49 stereo volumio[1201]: info: MRS: Pushing multiroomSync output Dec 24 23:39:49 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:49 stereo volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Dec 24 23:39:49 stereo volumio[1201]: SPOTIFY: SETTING SPOTIFY VOLUME 87 Dec 24 23:39:49 stereo volumio[1201]: info: Sending Spotify command with payload to local API: /player/volume Dec 24 23:39:49 stereo go-librespot[1486]: time="2025-12-24T23:39:49+01:00" level=debug msg="update volume requested to 57015/65535" Dec 24 23:39:50 stereo go-librespot[1486]: time="2025-12-24T23:39:50+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 24 23:39:50 stereo go-librespot[1486]: time="2025-12-24T23:39:50+01:00" level=trace msg="emitting websocket event: volume" Dec 24 23:39:50 stereo volumio[1201]: SPOTIFY: received: {"type":"volume","data":{"value":87,"max":100}} Dec 24 23:39:50 stereo volumio[1201]: SPOTIFY: RECEIVED SPOTIFY VOLUME 87 Dec 24 23:39:53 stereo go-librespot[1486]: time="2025-12-24T23:39:53+01:00" level=debug msg="fetched chunk 6/18, size: 524288" uri="spotify:track:2aI21FnmY7TJVKeMaoQZ0t" Dec 24 23:39:56 stereo go-librespot[1486]: time="2025-12-24T23:39:56+01:00" level=debug msg="update volume requested to 57670/65535" Dec 24 23:39:57 stereo go-librespot[1486]: time="2025-12-24T23:39:57+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 24 23:39:57 stereo go-librespot[1486]: time="2025-12-24T23:39:57+01:00" level=trace msg="emitting websocket event: volume" Dec 24 23:39:57 stereo volumio[1201]: SPOTIFY: received: {"type":"volume","data":{"value":88,"max":100}} Dec 24 23:39:57 stereo volumio[1201]: SPOTIFY: RECEIVED SPOTIFY VOLUME 88 Dec 24 23:39:57 stereo volumio[1201]: info: Setting Volumio Volume from Spotify: 88 Dec 24 23:39:57 stereo volumio[1201]: info: VolumeController::SetAlsaVolume88 Dec 24 23:39:57 stereo volumio[1201]: info: CoreStateMachine::pushState Dec 24 23:39:57 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 23:39:57 stereo volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 24 23:39:57 stereo volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Dec 24 23:39:57 stereo volumio[1201]: info: MRS: Pushing multiroomSync output Dec 24 23:39:57 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:57 stereo volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Dec 24 23:39:57 stereo go-librespot[1486]: time="2025-12-24T23:39:57+01:00" level=debug msg="update volume requested to 58326/65535" Dec 24 23:39:57 stereo go-librespot[1486]: time="2025-12-24T23:39:57+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 24 23:39:57 stereo go-librespot[1486]: time="2025-12-24T23:39:57+01:00" level=trace msg="emitting websocket event: volume" Dec 24 23:39:57 stereo volumio[1201]: SPOTIFY: received: {"type":"volume","data":{"value":89,"max":100}} Dec 24 23:39:57 stereo volumio[1201]: SPOTIFY: RECEIVED SPOTIFY VOLUME 89 Dec 24 23:39:57 stereo volumio[1201]: info: Setting Volumio Volume from Spotify: 89 Dec 24 23:39:57 stereo volumio[1201]: info: VolumeController::SetAlsaVolume89 Dec 24 23:39:58 stereo volumio[1201]: info: CoreStateMachine::pushState Dec 24 23:39:58 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 23:39:58 stereo volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 24 23:39:58 stereo volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Dec 24 23:39:58 stereo volumio[1201]: info: MRS: Pushing multiroomSync output Dec 24 23:39:58 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:39:58 stereo volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 89 Dec 24 23:40:00 stereo go-librespot[1486]: time="2025-12-24T23:40:00+01:00" level=debug msg="update volume requested to 58981/65535" Dec 24 23:40:00 stereo go-librespot[1486]: time="2025-12-24T23:40:00+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 24 23:40:00 stereo go-librespot[1486]: time="2025-12-24T23:40:00+01:00" level=trace msg="emitting websocket event: volume" Dec 24 23:40:00 stereo volumio[1201]: SPOTIFY: received: {"type":"volume","data":{"value":90,"max":100}} Dec 24 23:40:00 stereo volumio[1201]: SPOTIFY: RECEIVED SPOTIFY VOLUME 90 Dec 24 23:40:00 stereo volumio[1201]: info: Setting Volumio Volume from Spotify: 90 Dec 24 23:40:00 stereo volumio[1201]: info: VolumeController::SetAlsaVolume90 Dec 24 23:40:00 stereo volumio[1201]: info: CoreStateMachine::pushState Dec 24 23:40:00 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 23:40:00 stereo volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 24 23:40:00 stereo volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Dec 24 23:40:00 stereo volumio[1201]: info: MRS: Pushing multiroomSync output Dec 24 23:40:00 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:00 stereo volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Dec 24 23:40:01 stereo go-librespot[1486]: time="2025-12-24T23:40:01+01:00" level=debug msg="update volume requested to 59636/65535" Dec 24 23:40:01 stereo go-librespot[1486]: time="2025-12-24T23:40:01+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 24 23:40:01 stereo go-librespot[1486]: time="2025-12-24T23:40:01+01:00" level=trace msg="emitting websocket event: volume" Dec 24 23:40:01 stereo volumio[1201]: SPOTIFY: received: {"type":"volume","data":{"value":91,"max":100}} Dec 24 23:40:01 stereo volumio[1201]: SPOTIFY: RECEIVED SPOTIFY VOLUME 91 Dec 24 23:40:01 stereo volumio[1201]: info: Setting Volumio Volume from Spotify: 91 Dec 24 23:40:01 stereo volumio[1201]: info: VolumeController::SetAlsaVolume91 Dec 24 23:40:01 stereo volumio[1201]: info: CoreStateMachine::pushState Dec 24 23:40:01 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 23:40:01 stereo volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 24 23:40:01 stereo volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Dec 24 23:40:01 stereo volumio[1201]: info: MRS: Pushing multiroomSync output Dec 24 23:40:01 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:01 stereo volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Dec 24 23:40:01 stereo go-librespot[1486]: time="2025-12-24T23:40:01+01:00" level=debug msg="update volume requested to 60292/65535" Dec 24 23:40:01 stereo go-librespot[1486]: time="2025-12-24T23:40:01+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 24 23:40:01 stereo go-librespot[1486]: time="2025-12-24T23:40:01+01:00" level=trace msg="emitting websocket event: volume" Dec 24 23:40:01 stereo volumio[1201]: SPOTIFY: received: {"type":"volume","data":{"value":92,"max":100}} Dec 24 23:40:01 stereo volumio[1201]: SPOTIFY: RECEIVED SPOTIFY VOLUME 92 Dec 24 23:40:01 stereo volumio[1201]: info: Setting Volumio Volume from Spotify: 92 Dec 24 23:40:01 stereo volumio[1201]: info: VolumeController::SetAlsaVolume92 Dec 24 23:40:01 stereo volumio[1201]: info: CoreStateMachine::pushState Dec 24 23:40:01 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 23:40:01 stereo volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 24 23:40:01 stereo volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Dec 24 23:40:01 stereo volumio[1201]: info: MRS: Pushing multiroomSync output Dec 24 23:40:01 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:01 stereo volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 92 Dec 24 23:40:04 stereo go-librespot[1486]: time="2025-12-24T23:40:04+01:00" level=debug msg="fetched chunk 7/18, size: 524288" uri="spotify:track:2aI21FnmY7TJVKeMaoQZ0t" Dec 24 23:40:15 stereo go-librespot[1486]: time="2025-12-24T23:40:15+01:00" level=debug msg="fetched chunk 8/18, size: 524288" uri="spotify:track:2aI21FnmY7TJVKeMaoQZ0t" Dec 24 23:40:17 stereo go-librespot[1486]: time="2025-12-24T23:40:17+01:00" level=trace msg="sent dealer ping" Dec 24 23:40:17 stereo go-librespot[1486]: time="2025-12-24T23:40:17+01:00" level=trace msg="received dealer pong" Dec 24 23:40:18 stereo volumio[1201]: verbose: New Socket.io Connection to 192.168.1.232:3000 from 192.168.1.202 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 24 23:40:18 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:40:18 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:40:18 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:40:18 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:18 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:40:18 stereo volumio[1201]: verbose: New Socket.io Connection to 192.168.1.232:3000 from 192.168.1.202 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 24 23:40:18 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:40:18 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:40:18 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:40:18 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:18 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:40:18 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:40:18 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:40:18 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:40:18 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:18 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:40:18 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 24 23:40:18 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 24 23:40:18 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 24 23:40:18 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 24 23:40:19 stereo sudo[6156]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 24 23:40:19 stereo sudo[6156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:19 stereo sudo[6167]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 24 23:40:19 stereo sudo[6167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:19 stereo sudo[6156]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:19 stereo sudo[6161]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 24 23:40:19 stereo sudo[6161]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:19 stereo sudo[6172]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 24 23:40:19 stereo sudo[6172]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:19 stereo sudo[6167]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:19 stereo sudo[6161]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:19 stereo sudo[6172]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:19 stereo sudo[6178]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 24 23:40:19 stereo sudo[6178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:19 stereo sudo[6178]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:19 stereo sudo[6182]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 24 23:40:19 stereo sudo[6182]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:19 stereo sudo[6182]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:40:19 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:40:19 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:40:19 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:40:19 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:40:19 stereo volumio[1201]: verbose: New Socket.io Connection to 192.168.1.232:3000 from 192.168.1.202 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 24 23:40:19 stereo volumio[1201]: verbose: New Socket.io Connection to 192.168.1.232:3000 from 192.168.1.202 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 24 23:40:19 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 24 23:40:19 stereo sudo[6188]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 24 23:40:19 stereo sudo[6188]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:19 stereo sudo[6193]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 24 23:40:19 stereo sudo[6193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:19 stereo sudo[6188]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:19 stereo sudo[6204]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 24 23:40:19 stereo sudo[6204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:19 stereo sudo[6193]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:19 stereo sudo[6204]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:19 stereo sudo[6212]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 24 23:40:19 stereo sudo[6212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:19 stereo sudo[6212]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:20 stereo sudo[6227]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 24 23:40:20 stereo sudo[6227]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:20 stereo sudo[6227]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:20 stereo sudo[6230]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 24 23:40:20 stereo sudo[6230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:20 stereo sudo[6230]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:20 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 24 23:40:20 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 24 23:40:20 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:40:20 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:40:20 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:40:20 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:20 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:40:20 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:40:20 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:40:20 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:40:20 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:20 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:40:20 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:20 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 24 23:40:20 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 24 23:40:20 stereo volumio[1201]: verbose: New Socket.io Connection to 192.168.1.232:3000 from 192.168.1.202 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Dec 24 23:40:20 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 24 23:40:20 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 24 23:40:20 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 24 23:40:20 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 24 23:40:22 stereo sudo[6234]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 24 23:40:22 stereo sudo[6234]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:22 stereo sudo[6234]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:22 stereo sudo[6236]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 24 23:40:22 stereo sudo[6236]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:22 stereo sudo[6236]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:22 stereo sudo[6240]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 24 23:40:22 stereo sudo[6240]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:22 stereo sudo[6240]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:22 stereo sudo[6242]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 24 23:40:22 stereo sudo[6242]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:22 stereo sudo[6242]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:23 stereo volumio[1201]: verbose: New Socket.io Connection to 192.168.1.232 from 192.168.1.202 UA: Mozilla/5.0 (Linux; Android 15; CPH2699 Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 24 23:40:23 stereo sudo[6246]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 24 23:40:23 stereo sudo[6246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:23 stereo sudo[6246]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:23 stereo sudo[6248]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 24 23:40:23 stereo sudo[6248]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:23 stereo sudo[6248]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:23 stereo volumio[1201]: verbose: New Socket.io Connection to 192.168.1.232 from 192.168.1.202 UA: Mozilla/5.0 (Linux; Android 15; CPH2699 Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 24 23:40:23 stereo volumio[1201]: verbose: New Socket.io Connection to 192.168.1.232 from 192.168.1.202 UA: Mozilla/5.0 (Linux; Android 15; CPH2699 Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::volumioGetVisibleSources Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 24 23:40:23 stereo sudo[6257]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:23 stereo sudo[6257]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 24 23:40:23 stereo volumio[1201]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 24 23:40:23 stereo volumio[1201]: info: Received Get System Info Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:40:23 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:23 stereo volumio[1201]: info: Listing playlists Dec 24 23:40:23 stereo sudo[6257]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:23 stereo sudo[6259]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 24 23:40:23 stereo sudo[6259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 24 23:40:23 stereo sudo[6259]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::volumioGetVisibleSources Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 24 23:40:23 stereo volumio[1201]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 24 23:40:23 stereo volumio[1201]: info: Received Get System Info Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:40:23 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:23 stereo volumio[1201]: info: Listing playlists Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 24 23:40:23 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 24 23:40:24 stereo volumio[1201]: verbose: New Socket.io Connection to 192.168.1.232 from 192.168.1.202 UA: Mozilla/5.0 (Linux; Android 15; CPH2699 Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Dec 24 23:40:24 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 24 23:40:24 stereo sudo[6263]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 24 23:40:24 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 24 23:40:24 stereo sudo[6263]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:24 stereo sudo[6265]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 24 23:40:24 stereo sudo[6265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:24 stereo sudo[6263]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:24 stereo sudo[6265]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:24 stereo volumio[1201]: info: CoreCommandRouter::volumioGetVisibleSources Dec 24 23:40:24 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 24 23:40:24 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:24 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 24 23:40:24 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 24 23:40:24 stereo volumio[1201]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 24 23:40:24 stereo volumio[1201]: info: Received Get System Info Dec 24 23:40:24 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:40:24 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:40:24 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:40:24 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:24 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:40:24 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:24 stereo volumio[1201]: info: Listing playlists Dec 24 23:40:24 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 24 23:40:24 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 24 23:40:24 stereo volumio[1201]: verbose: New Socket.io Connection to 192.168.1.232 from 192.168.1.202 UA: Mozilla/5.0 (Linux; Android 15; CPH2699 Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Dec 24 23:40:25 stereo sudo[6269]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 24 23:40:25 stereo sudo[6269]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:25 stereo sudo[6269]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:25 stereo sudo[6271]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 24 23:40:25 stereo sudo[6271]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:25 stereo sudo[6271]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 24 23:40:25 stereo volumio[1201]: verbose: New Socket.io Connection to 192.168.1.232 from 192.168.1.202 UA: Mozilla/5.0 (Linux; Android 15; CPH2699 Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::volumioGetVisibleSources Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 24 23:40:25 stereo volumio[1201]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 24 23:40:25 stereo volumio[1201]: info: Received Get System Info Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:40:25 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:25 stereo volumio[1201]: info: Listing playlists Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::volumioGetVisibleSources Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 24 23:40:25 stereo volumio[1201]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 24 23:40:25 stereo volumio[1201]: info: Received Get System Info Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:40:25 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:25 stereo volumio[1201]: info: Listing playlists Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 24 23:40:25 stereo volumio[1201]: info: Received Get System Info Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:40:25 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:40:25 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 24 23:40:26 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 24 23:40:26 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 24 23:40:26 stereo volumio[1201]: info: Received Get System Info Dec 24 23:40:26 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:40:26 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:40:26 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:40:26 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:26 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:40:26 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 24 23:40:27 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 24 23:40:27 stereo volumio[1201]: info: Received Get System Info Dec 24 23:40:27 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:40:27 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:40:27 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:40:27 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:27 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:40:27 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 24 23:40:27 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 24 23:40:27 stereo volumio[1201]: info: Received Get System Info Dec 24 23:40:27 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:40:27 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:40:27 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:40:27 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:27 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:40:27 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 24 23:40:27 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 24 23:40:27 stereo volumio[1201]: info: Received Get System Info Dec 24 23:40:27 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:40:27 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:40:27 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:40:27 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:27 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:40:28 stereo go-librespot[1486]: time="2025-12-24T23:40:28+01:00" level=debug msg="fetched chunk 9/18, size: 524288" uri="spotify:track:2aI21FnmY7TJVKeMaoQZ0t" Dec 24 23:40:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 24 23:40:28 stereo volumio[1201]: info: Received Get System Info Dec 24 23:40:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:40:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:40:28 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:40:28 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:40:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 24 23:40:28 stereo volumio[1201]: info: Received Get System Info Dec 24 23:40:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:40:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:40:28 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:40:28 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:40:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 24 23:40:28 stereo volumio[1201]: info: Received Get System Info Dec 24 23:40:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:40:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:40:28 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:40:28 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:28 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:40:29 stereo volumio[1201]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 24 23:40:29 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 24 23:40:29 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 23:40:29 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 24 23:40:29 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:40:29 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:40:29 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:40:29 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:40:29 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:40:29 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:40:29 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:40:29 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 24 23:40:29 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 24 23:40:30 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 24 23:40:30 stereo volumio[1201]: info: Received Get System Info Dec 24 23:40:30 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:40:30 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:40:30 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:40:30 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:30 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:40:30 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 24 23:40:30 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 24 23:40:30 stereo volumio[1201]: info: Received Get System Info Dec 24 23:40:30 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:40:30 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:40:30 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:40:30 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:30 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:40:30 stereo volumio[1201]: info: Received Get System Info Dec 24 23:40:30 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 24 23:40:30 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 24 23:40:30 stereo volumio[1201]: info: Discovery: Getting this device information Dec 24 23:40:30 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:30 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 24 23:40:32 stereo volumio[1201]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 24 23:40:32 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 24 23:40:32 stereo volumio[1201]: info: Preparing to save Alsa Options, stopping services first Dec 24 23:40:32 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:32 stereo volumio[1201]: info: CoreCommandRouter::volumioPause Dec 24 23:40:32 stereo volumio[1201]: info: CoreStateMachine::pause Dec 24 23:40:32 stereo volumio[1201]: info: CoreStateMachine::stPlaybackTimer Dec 24 23:40:32 stereo volumio[1201]: info: CoreStateMachine::servicePause Dec 24 23:40:32 stereo volumio[1201]: info: CoreCommandRouter::servicePause Dec 24 23:40:32 stereo volumio[1201]: info: Spotify Received pause Dec 24 23:40:32 stereo volumio[1201]: SPOTIFY: SPOTIFY PAUSE Dec 24 23:40:32 stereo volumio[1201]: SPOTIFY: {"status":"play","title":"Say It Right","artist":"Nelly Furtado","album":"Loose","albumart":"https://i.scdn.co/image/ab67616d00001e02a6f439c8957170652f9410e2","uri":"spotify:track:2aI21FnmY7TJVKeMaoQZ0t","trackType":"spotify","codec":"ogg","seek":42983,"duration":223,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":92,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Dec 24 23:40:32 stereo volumio[1201]: info: Sending Spotify command to local API: /player/pause Dec 24 23:40:32 stereo volumio[1201]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"USB Audio CODEC"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Dec 24 23:40:32 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 24 23:40:32 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 24 23:40:32 stereo volumio[1201]: info: Setting mixer PCM for card USB Audio CODEC Dec 24 23:40:32 stereo volumio[1201]: info: QobuzConnect: setDeactiveState invoked Dec 24 23:40:32 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:32 stereo vtcs[6028]: [2025-12-24 23:40:32.469] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 24 23:40:32 stereo vtcs[6028]: [2025-12-24 23:40:32.469] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 24 23:40:32 stereo sudo[6308]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 23:40:32 stereo sudo[6308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:32 stereo volumio[1201]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 24 23:40:32 stereo volumio[1201]: info: Updating Volume Controller Parameters: Device: 5 Name: USB Audio CODEC Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 24 23:40:32 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 24 23:40:32 stereo volumio[1201]: info: Disabling external Volume Control Dec 24 23:40:32 stereo volumio[1201]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 24 23:40:32 stereo volumio[1201]: info: Preparing to generate the ALSA configuration file Dec 24 23:40:32 stereo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 24 23:40:32 stereo systemd[1]: vtcs.service: Killing process 6039 (vtcs) with signal SIGKILL. Dec 24 23:40:32 stereo systemd[1]: vtcs.service: Deactivated successfully. Dec 24 23:40:32 stereo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 24 23:40:32 stereo sudo[6308]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:32 stereo volumio[1201]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 24 23:40:32 stereo sudo[6315]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 23:40:32 stereo volumio[1201]: info: Reading ALSA contributions from plugins. Dec 24 23:40:32 stereo sudo[6315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:32 stereo go-librespot[1486]: time="2025-12-24T23:40:32+01:00" level=debug msg="pause track at 74292ms" Dec 24 23:40:32 stereo sudo[6322]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 24 23:40:32 stereo sudo[6322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:32 stereo go-librespot[1486]: time="2025-12-24T23:40:32+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 24 23:40:32 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 24 23:40:32 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 23:40:32 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 24 23:40:32 stereo sudo[6322]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:32 stereo sudo[6315]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:32 stereo sudo[6331]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 24 23:40:32 stereo sudo[6331]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:32 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:40:32 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:40:32 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:40:32 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:40:32 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:40:32 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:40:32 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 23:40:32 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 24 23:40:32 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 24 23:40:32 stereo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 24 23:40:32 stereo qobuz-connect[6040]: 20251224 23:40:32.969 [6040.6040] INFO SampleApp: Stopping Local configuration server Dec 24 23:40:32 stereo go-librespot[1486]: time="2025-12-24T23:40:32+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 24 23:40:32 stereo go-librespot[1486]: time="2025-12-24T23:40:32+01:00" level=trace msg="emitting websocket event: paused" Dec 24 23:40:32 stereo volumio[1201]: info: VolumeController:: Volume=46 Mute =false Dec 24 23:40:32 stereo volumio[1201]: info: CoreStateMachine::pushState Dec 24 23:40:32 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 23:40:32 stereo volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 24 23:40:33 stereo volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Dec 24 23:40:33 stereo volumio[1201]: info: MRS: Pushing multiroomSync output Dec 24 23:40:33 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:33 stereo volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 24 23:40:33 stereo volumio[1201]: SPOTIFY: SPOTIFY VOLUME 92 Dec 24 23:40:33 stereo volumio[1201]: SPOTIFY: VOLUMIO VOLUME 46 Dec 24 23:40:33 stereo volumio[1201]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 24 23:40:33 stereo volumio[1201]: info: Setting Spotify Volume from Volumio: 46 Dec 24 23:40:33 stereo volumio[1201]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:37i9dQZF1EQn4jwNIohw50","uri":"spotify:track:2aI21FnmY7TJVKeMaoQZ0t","play_origin":"playlist"}} Dec 24 23:40:33 stereo volumio[1201]: SPOTIFY: PUSH STATE SPOTIFY Dec 24 23:40:33 stereo volumio[1201]: SPOTIFY: {"status":"pause","service":"spop","title":"Say It Right","artist":"Nelly Furtado","album":"Loose","albumart":"https://i.scdn.co/image/ab67616d00001e02a6f439c8957170652f9410e2","uri":"spotify:track:2aI21FnmY7TJVKeMaoQZ0t","trackType":"spotify","seek":73983,"duration":223,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 24 23:40:33 stereo volumio[1201]: info: CoreCommandRouter::servicePushState Dec 24 23:40:33 stereo volumio[1201]: info: CoreStateMachine::pushState Dec 24 23:40:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 23:40:33 stereo volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 24 23:40:33 stereo volumio[1201]: info: MRS: Pushing multiroomSync output update for this device Dec 24 23:40:33 stereo volumio[1201]: info: MRS: Pushing multiroomSync output Dec 24 23:40:33 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:33 stereo volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 24 23:40:33 stereo volumio[1201]: info: Asound.conf file written Dec 24 23:40:33 stereo qobuz-connect[6040]: 20251224 23:40:33.263 [6040.6040] INFO SampleApp: shat down connection on UNIX socket Dec 24 23:40:33 stereo sudo[6337]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 24 23:40:33 stereo sudo[6337]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:33 stereo systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 24 23:40:33 stereo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 24 23:40:33 stereo sudo[6337]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:33 stereo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 24 23:40:33 stereo sudo[6331]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:33 stereo volumio[1201]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 24 23:40:33 stereo volumio[1201]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 24 23:40:33 stereo volumio[1201]: No state is present for card CODEC Dec 24 23:40:33 stereo volumio[1201]: Found hardware: "USB-Audio" "USB Mixer" "USB08bb:2900" "" "" Dec 24 23:40:33 stereo volumio[1201]: Hardware is initialized using a generic method Dec 24 23:40:33 stereo volumio[1201]: No state is present for card CODEC Dec 24 23:40:33 stereo volumio[1201]: info: Output device has changed, restarting MPD Dec 24 23:40:33 stereo volumio[1201]: info: Output device has changed, restarting Shairport Sync Dec 24 23:40:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 23:40:33 stereo sudo[6344]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 24 23:40:33 stereo sudo[6344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:33 stereo sudo[6346]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 24 23:40:33 stereo sudo[6346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:33 stereo sudo[6344]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:33 stereo volumio[1201]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 24 23:40:33 stereo volumio[1201]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 24 23:40:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:33 stereo volumio[1201]: info: QobuzConnect: setDeactiveState invoked Dec 24 23:40:33 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:33 stereo volumio[1201]: info: Output device has changed, restarting MPD Dec 24 23:40:33 stereo systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 24 23:40:33 stereo upmpdcli[1336]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Dec 24 23:40:33 stereo volumio[1201]: info: Output device has changed, restarting Shairport Sync Dec 24 23:40:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 23:40:33 stereo sudo[6356]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 23:40:33 stereo sudo[6358]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 24 23:40:33 stereo sudo[6358]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:33 stereo sudo[6356]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:33 stereo sudo[6358]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:33 stereo sudo[6360]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 24 23:40:33 stereo volumio[1201]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 24 23:40:33 stereo volumio[1201]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 24 23:40:33 stereo sudo[6360]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:33 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:33 stereo volumio[1201]: info: QobuzConnect: setDeactiveState invoked Dec 24 23:40:33 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:33 stereo systemd[1]: mpd.service: Deactivated successfully. Dec 24 23:40:33 stereo systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 24 23:40:33 stereo systemd[1]: mpd.service: Consumed 5.720s CPU time. Dec 24 23:40:33 stereo systemd[1]: mpd.socket: Deactivated successfully. Dec 24 23:40:33 stereo sudo[6371]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 23:40:33 stereo sudo[6371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:33 stereo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 24 23:40:33 stereo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 24 23:40:33 stereo sudo[6373]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 23:40:33 stereo sudo[6373]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:33 stereo sudo[6378]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 23:40:33 stereo sudo[6378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:33 stereo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 24 23:40:33 stereo systemd[1]: Starting mpd.service - Music Player Daemon... Dec 24 23:40:33 stereo sudo[6385]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 24 23:40:33 stereo sudo[6385]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 24 23:40:34 stereo sudo[6393]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 24 23:40:34 stereo sudo[6393]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:34 stereo volumio[1201]: info: MPD Permissions set Dec 24 23:40:34 stereo volumio[1201]: info: MPD Permissions set Dec 24 23:40:34 stereo volumio[1201]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 24 23:40:34 stereo volumio[1201]: info: QobuzConnect: setDeactiveState invoked Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:34 stereo sudo[6356]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:34 stereo volumio[1201]: info: Executing endpoint qc_getconfig Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 24 23:40:34 stereo qobuz-connect[6339]: 20251224 23:40:34.145 [6339.6339] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 24 23:40:34 stereo qobuz-connect[6339]: 20251224 23:40:34.158 [6339.6339] INFO VolumeManager: [0x1ec1178]: Setting new playback volume: 75 Dec 24 23:40:34 stereo qobuz-connect[6339]: 20251224 23:40:34.158 [6339.6339] INFO VolumeManager: [0x1ec1178]: Setting new mute state: 0 Dec 24 23:40:34 stereo qobuz-connect[6339]: 20251224 23:40:34.158 [6339.6339] INFO QobuzConnect: [0x1ec1b48]: Client initialized! Dec 24 23:40:34 stereo qobuz-connect[6339]: 20251224 23:40:34.158 [6339.6339] INFO SampleApp: Starting Avahi advertising, name: Stereo, service name: _qobuz-connect._tcp Dec 24 23:40:34 stereo volumio[1201]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 24 23:40:34 stereo volumio[1201]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:34 stereo qobuz-connect[6339]: 20251224 23:40:34.222 [6339.6339] INFO LocalConfigManager: [0x1ec0a58]: Starting Local Configuration server Dec 24 23:40:34 stereo qobuz-connect[6339]: 20251224 23:40:34.222 [6339.6339] INFO SampleApp: Starting Local configuration server Dec 24 23:40:34 stereo qobuz-connect[6339]: 20251224 23:40:34.223 [6339.6339] INFO SampleApp: Connected to UNIX socket client 0x1eab818 Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:34 stereo sudo[6393]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:34 stereo sudo[6385]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:34 stereo systemd[1]: mpd.service: Deactivated successfully. Dec 24 23:40:34 stereo systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 24 23:40:34 stereo qobuz-connect[6339]: 20251224 23:40:34.296 [6339.6339] INFO SampleApp: Playback volume changed: 75 Dec 24 23:40:34 stereo sudo[6408]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 24 23:40:34 stereo sudo[6408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:34 stereo sudo[6407]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 24 23:40:34 stereo sudo[6407]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:34 stereo volumio[1201]: info: Starting Shairport Sync Dec 24 23:40:34 stereo volumio[1201]: info: Starting Shairport Sync Dec 24 23:40:34 stereo volumio[1201]: info: Preparing to generate the ALSA configuration file Dec 24 23:40:34 stereo sudo[6373]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:34 stereo sudo[6417]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 24 23:40:34 stereo sudo[6417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:34 stereo sudo[6419]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 24 23:40:34 stereo sudo[6371]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:34 stereo sudo[6419]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:34 stereo volumio[1201]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 24 23:40:34 stereo volumio[1201]: info: Reading ALSA contributions from plugins. Dec 24 23:40:34 stereo volumio[1201]: info: Asound.conf file written Dec 24 23:40:34 stereo sudo[6378]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:34 stereo sudo[6426]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 24 23:40:34 stereo sudo[6426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:34 stereo sudo[6426]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:34 stereo volumio[1201]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 24 23:40:34 stereo volumio[1201]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 24 23:40:34 stereo volumio[1201]: No state is present for card CODEC Dec 24 23:40:34 stereo volumio[1201]: Found hardware: "USB-Audio" "USB Mixer" "USB08bb:2900" "" "" Dec 24 23:40:34 stereo volumio[1201]: Hardware is initialized using a generic method Dec 24 23:40:34 stereo volumio[1201]: No state is present for card CODEC Dec 24 23:40:34 stereo volumio[1201]: info: Output device has changed, restarting MPD Dec 24 23:40:34 stereo volumio[1201]: info: Output device has changed, restarting Shairport Sync Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 23:40:34 stereo systemd[1]: mpd.socket: Deactivated successfully. Dec 24 23:40:34 stereo sudo[6433]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 24 23:40:34 stereo sudo[6433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:34 stereo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 24 23:40:34 stereo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 24 23:40:34 stereo sudo[6433]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:34 stereo volumio[1201]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 24 23:40:34 stereo sudo[6436]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 24 23:40:34 stereo sudo[6436]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:34 stereo volumio[1201]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:34 stereo volumio[1201]: info: QobuzConnect: setDeactiveState invoked Dec 24 23:40:34 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:34 stereo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 24 23:40:34 stereo systemd[1]: Starting mpd.service - Music Player Daemon... Dec 24 23:40:34 stereo volumio[1201]: info: Preparing to generate the ALSA configuration file Dec 24 23:40:34 stereo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 24 23:40:34 stereo qobuz-connect[6339]: 20251224 23:40:34.876 [6339.6339] INFO SampleApp: Stopping Local configuration server Dec 24 23:40:34 stereo sudo[6446]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 23:40:34 stereo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 24 23:40:34 stereo sudo[6446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:34 stereo systemd[1]: shairport-sync.service: Deactivated successfully. Dec 24 23:40:34 stereo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 24 23:40:34 stereo systemd[1]: shairport-sync.service: Consumed 2.237s CPU time. Dec 24 23:40:35 stereo sudo[6450]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 23:40:35 stereo sudo[6450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:35 stereo volumio[1201]: SPOTIFY: SETTING SPOTIFY VOLUME 46 Dec 24 23:40:35 stereo volumio[1201]: info: Sending Spotify command with payload to local API: /player/volume Dec 24 23:40:35 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 24 23:40:35 stereo volumio[1201]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 24 23:40:35 stereo volumio[1201]: info: Reading ALSA contributions from plugins. Dec 24 23:40:35 stereo go-librespot[1486]: time="2025-12-24T23:40:35+01:00" level=debug msg="update volume requested to 30146/65535" Dec 24 23:40:35 stereo volumio[1201]: info: MPD Permissions set Dec 24 23:40:35 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:35 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:35 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:35 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:35 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:35 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:35 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:35 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:35 stereo sudo[6458]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 24 23:40:35 stereo sudo[6458]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:35 stereo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 24 23:40:35 stereo sudo[6417]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:35 stereo sudo[6419]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:35 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 23:40:35 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:35 stereo volumio[1201]: info: Shairport-Sync Started Dec 24 23:40:35 stereo systemd[1]: mpd.service: Deactivated successfully. Dec 24 23:40:35 stereo systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 24 23:40:35 stereo volumio[1201]: info: Starting Shairport Sync Dec 24 23:40:35 stereo volumio[1201]: info: Shairport-Sync Started Dec 24 23:40:35 stereo volumio[1201]: (node:1201) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 24 23:40:35 stereo volumio[1201]: (Use `node --trace-warnings ...` to show where the warning was created) Dec 24 23:40:35 stereo volumio[1201]: (node:1201) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 24 23:40:35 stereo volumio[1201]: (node:1201) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 24 23:40:35 stereo volumio[1201]: (node:1201) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 24 23:40:35 stereo volumio[1201]: (node:1201) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 24 23:40:35 stereo volumio[1201]: info: Asound.conf file unchanged, so no further update is needed Dec 24 23:40:35 stereo volumio[1201]: info: Output device has changed, restarting MPD Dec 24 23:40:35 stereo sudo[6467]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 24 23:40:35 stereo sudo[6467]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:35 stereo volumio[1201]: info: Output device has changed, restarting Shairport Sync Dec 24 23:40:35 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:35 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 23:40:35 stereo sudo[6458]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:35 stereo sudo[6469]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 24 23:40:35 stereo sudo[6469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:35 stereo sudo[6484]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 24 23:40:35 stereo sudo[6484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:35 stereo volumio[1201]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 24 23:40:35 stereo systemd[1]: mpd.socket: Deactivated successfully. Dec 24 23:40:35 stereo sudo[6469]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:35 stereo volumio[1201]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 24 23:40:35 stereo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 24 23:40:35 stereo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 24 23:40:35 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:35 stereo go-librespot[1486]: time="2025-12-24T23:40:35+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 24 23:40:35 stereo go-librespot[1486]: time="2025-12-24T23:40:35+01:00" level=trace msg="emitting websocket event: volume" Dec 24 23:40:35 stereo volumio[1201]: info: QobuzConnect: setDeactiveState invoked Dec 24 23:40:35 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:35 stereo sudo[6491]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 24 23:40:35 stereo sudo[6491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:35 stereo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 24 23:40:35 stereo systemd[1]: Starting mpd.service - Music Player Daemon... Dec 24 23:40:35 stereo sudo[6499]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 23:40:35 stereo sudo[6499]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:35 stereo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 24 23:40:35 stereo sudo[6501]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 24 23:40:35 stereo sudo[6501]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:35 stereo systemd[1]: shairport-sync.service: Deactivated successfully. Dec 24 23:40:35 stereo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 24 23:40:35 stereo volumio[1201]: SPOTIFY: received: {"type":"volume","data":{"value":46,"max":100}} Dec 24 23:40:35 stereo volumio[1201]: SPOTIFY: RECEIVED SPOTIFY VOLUME 46 Dec 24 23:40:35 stereo volumio[1201]: info: MPD Permissions set Dec 24 23:40:35 stereo volumio[1201]: info: TidalConnect service stoped! Dec 24 23:40:35 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:35 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:35 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:35 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:35 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:35 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:35 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:35 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:35 stereo sudo[6510]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 24 23:40:35 stereo sudo[6510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:35 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 23:40:35 stereo volumio[1201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 23:40:35 stereo volumio[1201]: info: Starting Shairport Sync Dec 24 23:40:35 stereo sudo[6446]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:35 stereo volumio[1201]: info: TidalConnect service stoped! Dec 24 23:40:35 stereo sudo[6519]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 24 23:40:35 stereo sudo[6519]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:35 stereo sudo[6450]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:35 stereo volumio[1201]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 24 23:40:35 stereo volumio[1201]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 24 23:40:36 stereo sudo[6531]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 24 23:40:36 stereo sudo[6531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:36 stereo sudo[6504]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 24 23:40:36 stereo sudo[6504]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 24 23:40:36 stereo sudo[6504]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:36 stereo sudo[6510]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:36 stereo qobuz-connect[6339]: 20251224 23:40:36.233 [6339.6339] INFO SampleApp: shat down connection on UNIX socket Dec 24 23:40:36 stereo sudo[6535]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 24 23:40:36 stereo sudo[6535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 23:40:36 stereo volumio[1201]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 24 23:40:36 stereo volumio[1201]: info: QobuzConnect: setDeactiveState invoked Dec 24 23:40:36 stereo volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 24 23:40:36 stereo sudo[6499]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:36 stereo sudo[6501]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:36 stereo volumio[1201]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Dec 24 23:40:36 stereo volumio[1201]: Job for vtcs.service canceled. Dec 24 23:40:36 stereo volumio[1201]: {"cmd":"/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3\nJob for vtcs.service canceled.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at ChildProcess._handle.onexit (node:internal/child_process:305:5)","stderr":"Job for vtcs.service canceled.\n","stdout":""} Dec 24 23:40:36 stereo systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 24 23:40:36 stereo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 24 23:40:36 stereo volumio[1201]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 24 23:40:36 stereo volumio[1201]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Dec 24 23:40:36 stereo volumio[1201]: Job for vtcs.service canceled. Dec 24 23:40:36 stereo volumio[1201]: at ChildProcess.exithandler (node:child_process:421:12) Dec 24 23:40:36 stereo volumio[1201]: at ChildProcess.emit (node:events:514:28) Dec 24 23:40:36 stereo volumio[1201]: at maybeClose (node:internal/child_process:1105:16) Dec 24 23:40:36 stereo volumio[1201]: at Socket. (node:internal/child_process:457:11) Dec 24 23:40:36 stereo volumio[1201]: at Socket.emit (node:events:514:28) Dec 24 23:40:36 stereo volumio[1201]: at Pipe. (node:net:337:12) { Dec 24 23:40:36 stereo volumio[1201]: code: 1, Dec 24 23:40:36 stereo volumio[1201]: killed: false, Dec 24 23:40:36 stereo volumio[1201]: signal: null, Dec 24 23:40:36 stereo volumio[1201]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Dec 24 23:40:36 stereo volumio[1201]: stdout: '', Dec 24 23:40:36 stereo volumio[1201]: stderr: 'Job for vtcs.service canceled.\n' Dec 24 23:40:36 stereo volumio[1201]: } Dec 24 23:40:36 stereo volumio[1201]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 24 23:40:36 stereo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 24 23:40:36 stereo sudo[6491]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:36 stereo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 24 23:40:36 stereo sudo[6407]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:36 stereo sudo[6519]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:36 stereo sudo[6408]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:36 stereo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 24 23:40:36 stereo sudo[6467]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:36 stereo sudo[6531]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:36 stereo systemd[1]: mpd.service: Deactivated successfully. Dec 24 23:40:36 stereo systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 24 23:40:36 stereo systemd[1]: mpd.socket: Deactivated successfully. Dec 24 23:40:36 stereo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 24 23:40:36 stereo upmpdcli[1336]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Connection refused Dec 24 23:40:36 stereo upmpdcli[1336]: :2:../src/mpdcli.cxx:396::MPDCli::updStatus: connection failed Dec 24 23:40:36 stereo upmpdcli[1336]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Dec 24 23:40:36 stereo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 24 23:40:36 stereo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 24 23:40:36 stereo systemd[1]: Starting mpd.service - Music Player Daemon... Dec 24 23:40:36 stereo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 24 23:40:36 stereo systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 24 23:40:36 stereo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 24 23:40:36 stereo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 24 23:40:36 stereo sudo[6535]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:37 stereo sudo[6571]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 24 23:40:37 stereo sudo[6571]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 24 23:40:37 stereo sudo[6571]: pam_unix(sudo:session): session closed for user root Dec 24 23:40:38 stereo sudo[6577]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-24 23:39' Dec 24 23:40:38 stereo sudo[6577]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"