Dec 30 11:05:01 volumio2 volumio[1018]: info: PLUGIN START: spop Dec 30 11:05:01 volumio2 volumio[1018]: info: Creating Spotify config file Dec 30 11:05:01 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:05:01 volumio2 volumio[1018]: info: Done. Dec 30 11:05:01 volumio2 volumio[1018]: info: Spotify config file written Dec 30 11:05:01 volumio2 volumio[1018]: info: No need to fix Spotify hosts Dec 30 11:05:01 volumio2 sudo[5058]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 30 11:05:01 volumio2 sudo[5058]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:05:01 volumio2 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 30 11:05:01 volumio2 systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 30 11:05:01 volumio2 systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 30 11:05:01 volumio2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 30 11:05:01 volumio2 sudo[5058]: pam_unix(sudo:session): session closed for user root Dec 30 11:05:01 volumio2 go-librespot[5062]: go-librespot daemon starting... Dec 30 11:05:01 volumio2 go-librespot[5068]: time="2025-12-30T11:05:01+01:00" level=info msg="running go-librespot 0.4.0" Dec 30 11:05:01 volumio2 go-librespot[5068]: time="2025-12-30T11:05:01+01:00" level=debug msg="no app state found" Dec 30 11:05:01 volumio2 go-librespot[5068]: time="2025-12-30T11:05:01+01:00" level=debug msg="stored credentials not found" Dec 30 11:05:01 volumio2 go-librespot[5068]: time="2025-12-30T11:05:01+01:00" level=info msg="generated new device id: ec1a760477381cd466fa5130f9c5ef6cb07ab394" Dec 30 11:05:01 volumio2 go-librespot[5068]: time="2025-12-30T11:05:01+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 30 11:05:02 volumio2 go-librespot[5068]: time="2025-12-30T11:05:02+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 30 11:05:02 volumio2 go-librespot[5068]: time="2025-12-30T11:05:02+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 30 11:05:02 volumio2 go-librespot[5068]: time="2025-12-30T11:05:02+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 30 11:05:02 volumio2 go-librespot[5068]: time="2025-12-30T11:05:02+01:00" level=info msg="zeroconf server listening on port 36807" Dec 30 11:05:04 volumio2 volumio[1018]: info: go-librespot daemon successfully initialized Dec 30 11:05:07 volumio2 volumio[1018]: info: Initializing connection to go-librespot Websocket Dec 30 11:05:07 volumio2 volumio[1018]: info: Connection to go-librespot Websocket established Dec 30 11:05:07 volumio2 go-librespot[5068]: time="2025-12-30T11:05:07+01:00" level=debug msg="new websocket client" Dec 30 11:05:08 volumio2 systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 30 11:05:08 volumio2 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 30 11:05:08 volumio2 systemd[1]: setdatetime-helper.service: Consumed 1.505s CPU time. Dec 30 11:05:10 volumio2 volumio[1018]: info: Getting Spotify volume Dec 30 11:05:10 volumio2 volumio[1018]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 30 11:05:10 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:05:10 volumio2 volumio[1018]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 30 11:05:10 volumio2 volumio[1018]: SPOTIFY: SPOTIFY VOLUME undefined Dec 30 11:05:10 volumio2 volumio[1018]: SPOTIFY: VOLUMIO VOLUME 46 Dec 30 11:05:10 volumio2 volumio[1018]: info: Aligning Spotify Volume to Volumio Volume Dec 30 11:05:10 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:05:10 volumio2 volumio[1018]: info: Setting Spotify Volume from Volumio: 46 Dec 30 11:05:12 volumio2 volumio[1018]: SPOTIFY: SETTING SPOTIFY VOLUME 46 Dec 30 11:05:12 volumio2 volumio[1018]: info: Sending Spotify command with payload to local API: /player/volume Dec 30 11:05:36 volumio2 wpa_supplicant[3146]: wlan0: WPA: Group rekeying completed with 60:b5:8d:84:7e:52 [GTK=CCMP] Dec 30 11:06:04 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:06:06 volumio2 volumio[1018]: info: Executing endpoint metavolumio Dec 30 11:06:06 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 30 11:06:07 volumio2 volumio[1018]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 11:06:07 volumio2 volumio[1018]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 11:06:07 volumio2 volumio[1018]: info: Retrieving Cloud Streaming UI Dec 30 11:06:07 volumio2 volumio[1018]: info: Getting Tidal Cloud Configuration Dec 30 11:06:07 volumio2 volumio[1018]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 11:06:07 volumio2 volumio[1018]: info: Getting Qobuz Cloud Configuration Dec 30 11:06:07 volumio2 volumio[1018]: info: Asking plugin for UI Config Dec 30 11:06:07 volumio2 volumio[1018]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 11:06:07 volumio2 volumio[1018]: info: Getting Spotify Cloud Configuration Dec 30 11:06:07 volumio2 volumio[1018]: info: Asking plugin for UI Config Dec 30 11:06:07 volumio2 volumio[1018]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 11:06:07 volumio2 volumio[1018]: info: Saving Spotify Acccount Dec 30 11:06:07 volumio2 volumio[1018]: info: Got it Dec 30 11:06:07 volumio2 volumio[1018]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 30 11:06:07 volumio2 volumio[1018]: info: Got Tidal Cloud Configuration Dec 30 11:06:07 volumio2 volumio[1018]: info: Got it Dec 30 11:06:07 volumio2 volumio[1018]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 11:06:07 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 30 11:06:07 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 30 11:06:07 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 30 11:06:07 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 30 11:06:07 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 11:06:07 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 11:06:07 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 11:06:07 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 11:06:07 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetBrowseSources Dec 30 11:06:07 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetBrowseSources Dec 30 11:06:07 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetBrowseSources Dec 30 11:06:07 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 11:06:07 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 30 11:06:11 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 11:06:12 volumio2 volumio[1018]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 11:06:25 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:06:27 volumio2 volumio[1018]: info: Executing endpoint metavolumio Dec 30 11:06:27 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 30 11:06:28 volumio2 volumio[1018]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 11:06:28 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 30 11:06:28 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 11:06:28 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 30 11:06:28 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 11:06:28 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 11:06:28 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 11:06:28 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 11:06:28 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 11:06:28 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 11:06:28 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 11:06:28 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 30 11:06:28 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 30 11:06:30 volumio2 volumio[1018]: info: Dec 30 11:06:30 volumio2 volumio[1018]: ---------------------------- MPD announces system playlist update Dec 30 11:06:30 volumio2 volumio[1018]: info: Ignoring MPD Status Update Dec 30 11:06:30 volumio2 volumio[1018]: info: Dec 30 11:06:30 volumio2 volumio[1018]: ---------------------------- MPD announces state update: player Dec 30 11:06:30 volumio2 volumio[1018]: info: ControllerMpd::getState Dec 30 11:06:30 volumio2 volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Dec 30 11:06:30 volumio2 volumio[1018]: info: Dec 30 11:06:30 volumio2 volumio[1018]: ---------------------------- MPD announces system playlist update Dec 30 11:06:30 volumio2 volumio[1018]: info: Ignoring MPD Status Update Dec 30 11:06:30 volumio2 volumio[1018]: info: Dec 30 11:06:30 volumio2 volumio[1018]: ---------------------------- MPD announces state update: player Dec 30 11:06:30 volumio2 volumio[1018]: info: ControllerMpd::getState Dec 30 11:06:30 volumio2 volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Dec 30 11:06:30 volumio2 volumio[1018]: info: ------------------------------ 9ms Dec 30 11:06:30 volumio2 volumio[1018]: info: sendMpdCommand status took 8 milliseconds Dec 30 11:06:30 volumio2 volumio[1018]: info: ------------------------------ 7ms Dec 30 11:06:30 volumio2 volumio[1018]: info: sendMpdCommand status took 5 milliseconds Dec 30 11:06:30 volumio2 volumio[1018]: verbose: ControllerMpd::parseState Dec 30 11:06:30 volumio2 volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 11:06:30 volumio2 volumio[1018]: verbose: ControllerMpd::parseState Dec 30 11:06:30 volumio2 volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 11:06:30 volumio2 volumio[1018]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 30 11:06:30 volumio2 volumio[1018]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 30 11:06:30 volumio2 volumio[1018]: verbose: ControllerMpd::parseTrackInfo Dec 30 11:06:30 volumio2 volumio[1018]: verbose: ControllerMpd::parseTrackInfo Dec 30 11:06:30 volumio2 volumio[1018]: info: ControllerMpd::pushState Dec 30 11:06:30 volumio2 volumio[1018]: info: CoreCommandRouter::servicePushState Dec 30 11:06:30 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:30 volumio2 volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":208862,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"The Killers - Human","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe3-q2a","trackType":"at/oe3-q2a"} Dec 30 11:06:30 volumio2 volumio[1018]: verbose: CURRENT POSITION 0 Dec 30 11:06:30 volumio2 volumio[1018]: info: CoreStateMachine::syncState stateService play Dec 30 11:06:30 volumio2 volumio[1018]: info: CoreStateMachine::syncState currentStatus play Dec 30 11:06:30 volumio2 volumio[1018]: info: Received an update from plugin. extracting info from payload Dec 30 11:06:30 volumio2 volumio[1018]: info: CoreStateMachine::pushState Dec 30 11:06:30 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 11:06:30 volumio2 volumio[1018]: info: CoreCommandRouter::volumioPushState Dec 30 11:06:30 volumio2 volumio[1018]: info: MRS: Pushing multiroomSync output update for this device Dec 30 11:06:30 volumio2 volumio[1018]: info: MRS: Pushing multiroomSync output Dec 30 11:06:30 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:06:30 volumio2 volumio[1018]: info: CoreStateMachine::pushState Dec 30 11:06:30 volumio2 volumio[1018]: info: CoreCommandRouter::volumioPushState Dec 30 11:06:30 volumio2 volumio[1018]: info: MRS: Pushing multiroomSync output update for this device Dec 30 11:06:30 volumio2 volumio[1018]: info: MRS: Pushing multiroomSync output Dec 30 11:06:30 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:06:30 volumio2 volumio[1018]: info: ControllerMpd::pushState Dec 30 11:06:30 volumio2 volumio[1018]: info: CoreCommandRouter::servicePushState Dec 30 11:06:30 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:30 volumio2 volumio[1018]: verbose: STATE SERVICE {"status":"play","position":0,"seek":208862,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"The Killers - Human","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe3-q2a","trackType":"at/oe3-q2a"} Dec 30 11:06:30 volumio2 volumio[1018]: verbose: CURRENT POSITION 0 Dec 30 11:06:30 volumio2 volumio[1018]: info: CoreStateMachine::syncState stateService play Dec 30 11:06:30 volumio2 volumio[1018]: info: CoreStateMachine::syncState currentStatus play Dec 30 11:06:30 volumio2 volumio[1018]: info: Received an update from plugin. extracting info from payload Dec 30 11:06:30 volumio2 volumio[1018]: info: CoreStateMachine::pushState Dec 30 11:06:30 volumio2 volumio[1018]: info: CoreCommandRouter::volumioPushState Dec 30 11:06:30 volumio2 volumio[1018]: info: MRS: Pushing multiroomSync output update for this device Dec 30 11:06:30 volumio2 volumio[1018]: info: MRS: Pushing multiroomSync output Dec 30 11:06:30 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:06:30 volumio2 volumio[1018]: info: CoreStateMachine::pushState Dec 30 11:06:30 volumio2 volumio[1018]: info: CoreCommandRouter::volumioPushState Dec 30 11:06:30 volumio2 volumio[1018]: info: MRS: Pushing multiroomSync output update for this device Dec 30 11:06:30 volumio2 volumio[1018]: info: MRS: Pushing multiroomSync output Dec 30 11:06:30 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:06:30 volumio2 volumio[1018]: info: ------------------------------ 133ms Dec 30 11:06:30 volumio2 volumio[1018]: info: ------------------------------ 129ms Dec 30 11:06:30 volumio2 volumio[1018]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 30 11:06:30 volumio2 volumio[1018]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 30 11:06:30 volumio2 volumio[1018]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 30 11:06:30 volumio2 volumio[1018]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 30 11:06:42 volumio2 volumio[1018]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:06:42 volumio2 volumio[1018]: info: Restoring Previous Volume level: 46 false true Dec 30 11:06:42 volumio2 volumio[1018]: info: VolumeController::SetAlsaVolume100 Dec 30 11:06:42 volumio2 volumio[1018]: info: Enable softmixer device for audio device number 5 Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreCommandRouter::volumioStop Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreStateMachine::stop Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreStateMachine::stPlaybackTimer Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreStateMachine::updateTrackBlock Dec 30 11:06:42 volumio2 volumio[1018]: info: CorePlayQueue::getTrackBlock Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreStateMachine::pushState Dec 30 11:06:42 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreCommandRouter::volumioPushState Dec 30 11:06:42 volumio2 volumio[1018]: info: MRS: Pushing multiroomSync output update for this device Dec 30 11:06:42 volumio2 volumio[1018]: info: MRS: Pushing multiroomSync output Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:06:42 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreStateMachine::serviceStop Dec 30 11:06:42 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreCommandRouter::serviceStop Dec 30 11:06:42 volumio2 volumio[1018]: info: [1767089202590] ControllerWebradio::stop Dec 30 11:06:42 volumio2 volumio[1018]: verbose: ControllerMpd::sendMpdCommand stop Dec 30 11:06:42 volumio2 volumio[1018]: info: Enable softmixer device for audio device undefined Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 30 11:06:42 volumio2 volumio[1018]: info: Updating Volume Controller Parameters: Device: 5 Name: USB Audio Device Mixer: Speaker Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 30 11:06:42 volumio2 volumio[1018]: info: Disabling external Volume Control Dec 30 11:06:42 volumio2 volumio[1018]: info: Output device has changed, restarting MPD Dec 30 11:06:42 volumio2 volumio[1018]: info: Output device has changed, restarting Shairport Sync Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 11:06:42 volumio2 sudo[5277]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 30 11:06:42 volumio2 sudo[5277]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:42 volumio2 sudo[5279]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 30 11:06:42 volumio2 sudo[5279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:42 volumio2 sudo[5277]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:42 volumio2 volumio[1018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 30 11:06:42 volumio2 volumio[1018]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:42 volumio2 volumio[1018]: info: QobuzConnect: setDeactiveState invoked Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:06:42 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:42 volumio2 vtcs[4325]: [2025-12-30 11:06:42.736] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Dec 30 11:06:42 volumio2 vtcs[4325]: [2025-12-30 11:06:42.736] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 30 11:06:42 volumio2 vtcs[4325]: [2025-12-30 11:06:42.736] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 30 11:06:42 volumio2 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 30 11:06:42 volumio2 volumio[1018]: info: Volume configurations have been set Dec 30 11:06:42 volumio2 volumio[1018]: info: QobuzConnect: setDeactiveState invoked Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:06:42 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:42 volumio2 sudo[5289]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 11:06:42 volumio2 sudo[5289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:42 volumio2 sudo[5291]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 11:06:42 volumio2 sudo[5291]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 30 11:06:42 volumio2 volumio[1018]: info: Updating Volume Controller Parameters: Device: 5 Name: USB Audio Device Mixer: Speaker Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 30 11:06:42 volumio2 volumio[1018]: info: Disabling external Volume Control Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreStateMachine::pushState Dec 30 11:06:42 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreCommandRouter::volumioPushState Dec 30 11:06:42 volumio2 volumio[1018]: info: MRS: Pushing multiroomSync output update for this device Dec 30 11:06:42 volumio2 volumio[1018]: info: MRS: Pushing multiroomSync output Dec 30 11:06:42 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:06:42 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:42 volumio2 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 30 11:06:42 volumio2 systemd[1]: vtcs.service: Killing process 4332 (vtcs) with signal SIGKILL. Dec 30 11:06:42 volumio2 systemd[1]: vtcs.service: Deactivated successfully. Dec 30 11:06:42 volumio2 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 30 11:06:42 volumio2 sudo[5302]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 11:06:42 volumio2 sudo[5302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:42 volumio2 sudo[5304]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 11:06:42 volumio2 sudo[5304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:43 volumio2 sudo[5289]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:43 volumio2 sudo[5291]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:43 volumio2 sudo[5315]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 30 11:06:43 volumio2 sudo[5315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:43 volumio2 volumio[1018]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 30 11:06:43 volumio2 sudo[5323]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 30 11:06:43 volumio2 volumio[1018]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 30 11:06:43 volumio2 sudo[5323]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:43 volumio2 volumio[1018]: SPOTIFY: SPOTIFY VOLUME 46 Dec 30 11:06:43 volumio2 volumio[1018]: SPOTIFY: VOLUMIO VOLUME 100 Dec 30 11:06:43 volumio2 volumio[1018]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 30 11:06:43 volumio2 volumio[1018]: info: Setting Spotify Volume from Volumio: 100 Dec 30 11:06:43 volumio2 sudo[5315]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:43 volumio2 sudo[5323]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:43 volumio2 sudo[5329]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 30 11:06:43 volumio2 sudo[5329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:43 volumio2 sudo[5302]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:43 volumio2 sudo[5304]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:43 volumio2 sudo[5331]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 30 11:06:43 volumio2 sudo[5331]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:43 volumio2 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 30 11:06:43 volumio2 qobuz-connect[4298]: 20251230 11:06:43.390 [4298.4298] INFO SampleApp: Stopping Local configuration server Dec 30 11:06:44 volumio2 volumio[1018]: error: MPD error: Error: read ECONNRESET Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreStateMachine::pushState Dec 30 11:06:44 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::volumioPushState Dec 30 11:06:44 volumio2 volumio[1018]: info: MRS: Pushing multiroomSync output update for this device Dec 30 11:06:44 volumio2 volumio[1018]: info: MRS: Pushing multiroomSync output Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:06:44 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:44 volumio2 volumio[1018]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Dec 30 11:06:44 volumio2 volumio[1018]: info: MPD Permissions set Dec 30 11:06:44 volumio2 volumio[1018]: info: Software Volume ALSA configuration written Dec 30 11:06:44 volumio2 volumio[1018]: info: Preparing to generate the ALSA configuration file Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 30 11:06:44 volumio2 volumio[1018]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 30 11:06:44 volumio2 volumio[1018]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 30 11:06:44 volumio2 volumio[1018]: info: Reading ALSA contributions from plugins. Dec 30 11:06:44 volumio2 volumio[1018]: info: VolumeController::SetAlsaVolume0 Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreStateMachine::pushState Dec 30 11:06:44 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::volumioPushState Dec 30 11:06:44 volumio2 volumio[1018]: info: MRS: Pushing multiroomSync output update for this device Dec 30 11:06:44 volumio2 volumio[1018]: info: MRS: Pushing multiroomSync output Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:06:44 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:44 volumio2 volumio[1018]: info: VolumeController:: Volume=100 Mute =false Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreStateMachine::pushState Dec 30 11:06:44 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::volumioPushState Dec 30 11:06:44 volumio2 volumio[1018]: info: MRS: Pushing multiroomSync output update for this device Dec 30 11:06:44 volumio2 volumio[1018]: info: MRS: Pushing multiroomSync output Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:06:44 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:44 volumio2 volumio[1018]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Dec 30 11:06:44 volumio2 volumio[1018]: info: Sending Spotify command with payload to local API: /player/volume Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 11:06:44 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:44 volumio2 volumio[1018]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 30 11:06:44 volumio2 volumio[1018]: info: Starting Shairport Sync Dec 30 11:06:45 volumio2 volumio[1018]: info: Asound.conf file written Dec 30 11:06:45 volumio2 sudo[5360]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 30 11:06:45 volumio2 sudo[5356]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 30 11:06:45 volumio2 sudo[5356]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:45 volumio2 sudo[5360]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:45 volumio2 sudo[5360]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:45 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 30 11:06:45 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 30 11:06:45 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 30 11:06:45 volumio2 systemd[1]: shairport-sync.service: Consumed 3.475s CPU time. Dec 30 11:06:45 volumio2 qobuz-connect[4298]: 20251230 11:06:45.136 [4298.4298] INFO SampleApp: shat down connection on UNIX socket Dec 30 11:06:45 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 30 11:06:45 volumio2 systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 30 11:06:45 volumio2 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 30 11:06:45 volumio2 sudo[5356]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:45 volumio2 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 30 11:06:45 volumio2 sudo[5331]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:45 volumio2 sudo[5329]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:45 volumio2 volumio[1018]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 30 11:06:45 volumio2 volumio[1018]: No state is present for card b1 Dec 30 11:06:45 volumio2 volumio[1018]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 30 11:06:45 volumio2 volumio[1018]: /usr/share/alsa/init/bcm2835:14: control element not found Dec 30 11:06:45 volumio2 volumio[1018]: No state is present for card b1 Dec 30 11:06:45 volumio2 volumio[1018]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 30 11:06:45 volumio2 volumio[1018]: No state is present for card Headphones Dec 30 11:06:45 volumio2 volumio[1018]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 30 11:06:45 volumio2 volumio[1018]: /usr/share/alsa/init/bcm2835:20: control element not found Dec 30 11:06:45 volumio2 volumio[1018]: No state is present for card Headphones Dec 30 11:06:45 volumio2 volumio[1018]: No state is present for card Device Dec 30 11:06:45 volumio2 volumio[1018]: Found hardware: "USB-Audio" "USB Mixer" "USB0d8c:0014" "" "" Dec 30 11:06:45 volumio2 volumio[1018]: Hardware is initialized using a generic method Dec 30 11:06:45 volumio2 volumio[1018]: No state is present for card Device Dec 30 11:06:45 volumio2 volumio[1018]: info: Output device has changed, restarting MPD Dec 30 11:06:45 volumio2 volumio[1018]: info: Output device has changed, restarting Shairport Sync Dec 30 11:06:45 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:45 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 11:06:45 volumio2 sudo[5370]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 30 11:06:45 volumio2 sudo[5371]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 30 11:06:45 volumio2 sudo[5371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:45 volumio2 sudo[5370]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:45 volumio2 volumio[1018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 30 11:06:45 volumio2 volumio[1018]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 30 11:06:45 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:45 volumio2 sudo[5370]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:45 volumio2 volumio[1018]: info: QobuzConnect: setDeactiveState invoked Dec 30 11:06:45 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:06:45 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:45 volumio2 volumio[1018]: info: Preparing to generate the ALSA configuration file Dec 30 11:06:45 volumio2 sudo[5382]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 11:06:45 volumio2 sudo[5382]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:45 volumio2 volumio[1018]: ALSA lib pcm.c:2666:(snd_pcm_open_noupdate) Unknown PCM volumioMultiRoomServer Dec 30 11:06:45 volumio2 volumio[1018]: aplay: main:831: audio open error: No such file or directory Dec 30 11:06:45 volumio2 sudo[5382]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:45 volumio2 volumio[1018]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 30 11:06:45 volumio2 volumio[1018]: No state is present for card b1 Dec 30 11:06:45 volumio2 volumio[1018]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 30 11:06:45 volumio2 volumio[1018]: /usr/share/alsa/init/bcm2835:14: control element not found Dec 30 11:06:45 volumio2 volumio[1018]: No state is present for card b1 Dec 30 11:06:45 volumio2 volumio[1018]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 30 11:06:45 volumio2 volumio[1018]: No state is present for card Headphones Dec 30 11:06:45 volumio2 volumio[1018]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 30 11:06:45 volumio2 volumio[1018]: /usr/share/alsa/init/bcm2835:20: control element not found Dec 30 11:06:45 volumio2 volumio[1018]: No state is present for card Headphones Dec 30 11:06:45 volumio2 volumio[1018]: No state is present for card Device Dec 30 11:06:45 volumio2 volumio[1018]: Found hardware: "USB-Audio" "USB Mixer" "USB0d8c:0014" "" "" Dec 30 11:06:45 volumio2 volumio[1018]: Hardware is initialized using a generic method Dec 30 11:06:45 volumio2 volumio[1018]: No state is present for card Device Dec 30 11:06:45 volumio2 volumio[1018]: info: QobuzConnect: setDeactiveState invoked Dec 30 11:06:45 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:06:45 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:45 volumio2 volumio[1018]: info: Output device has changed, restarting MPD Dec 30 11:06:45 volumio2 volumio[1018]: info: Output device has changed, restarting Shairport Sync Dec 30 11:06:45 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:45 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 11:06:46 volumio2 sudo[5402]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 30 11:06:46 volumio2 sudo[5402]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:46 volumio2 sudo[5400]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 11:06:46 volumio2 sudo[5400]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:46 volumio2 sudo[5405]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 30 11:06:46 volumio2 sudo[5402]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:46 volumio2 sudo[5405]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:46 volumio2 volumio[1018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 30 11:06:46 volumio2 volumio[1018]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 30 11:06:46 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:46 volumio2 volumio[1018]: info: QobuzConnect: setDeactiveState invoked Dec 30 11:06:46 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:06:46 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:46 volumio2 sudo[5421]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 11:06:46 volumio2 sudo[5421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:46 volumio2 volumio[1018]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 30 11:06:46 volumio2 volumio[1018]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 30 11:06:46 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 30 11:06:46 volumio2 volumio[1018]: info: Disabling external Volume Control Dec 30 11:06:46 volumio2 sudo[5400]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:46 volumio2 sudo[5435]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 11:06:46 volumio2 sudo[5435]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:46 volumio2 sudo[5421]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:46 volumio2 sudo[5437]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 11:06:46 volumio2 sudo[5437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:46 volumio2 sudo[5440]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 11:06:46 volumio2 sudo[5440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:46 volumio2 sudo[5435]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:46 volumio2 sudo[5447]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 30 11:06:46 volumio2 sudo[5447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:46 volumio2 volumio[1018]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 30 11:06:46 volumio2 volumio[1018]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 30 11:06:46 volumio2 volumio[1018]: info: Reading ALSA contributions from plugins. Dec 30 11:06:46 volumio2 sudo[5456]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 30 11:06:46 volumio2 sudo[5456]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:46 volumio2 volumio[1018]: info: CoreStateMachine::pushState Dec 30 11:06:46 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:46 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 11:06:46 volumio2 volumio[1018]: info: CoreCommandRouter::volumioPushState Dec 30 11:06:46 volumio2 sudo[5463]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 30 11:06:46 volumio2 sudo[5463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:46 volumio2 sudo[5447]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:46 volumio2 volumio[1018]: info: MRS: Pushing multiroomSync output update for this device Dec 30 11:06:46 volumio2 volumio[1018]: info: MRS: Pushing multiroomSync output Dec 30 11:06:46 volumio2 sudo[5440]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:46 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:06:46 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:46 volumio2 systemd[1]: mpd.service: Deactivated successfully. Dec 30 11:06:46 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 30 11:06:46 volumio2 systemd[1]: mpd.service: Consumed 18.558s CPU time. Dec 30 11:06:46 volumio2 sudo[5466]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 30 11:06:46 volumio2 sudo[5466]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:46 volumio2 volumio[1018]: info: MPD Permissions set Dec 30 11:06:46 volumio2 volumio[1018]: info: MPD Permissions set Dec 30 11:06:46 volumio2 volumio[1018]: info: TidalConnect service stoped! Dec 30 11:06:46 volumio2 volumio[1018]: info: TidalConnect service stoped! Dec 30 11:06:46 volumio2 volumio[1018]: info: TidalConnect service stoped! Dec 30 11:06:46 volumio2 volumio[1018]: info: TidalConnect service stoped! Dec 30 11:06:46 volumio2 volumio[1018]: info: Shairport-Sync Started Dec 30 11:06:46 volumio2 volumio[1018]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 30 11:06:46 volumio2 volumio[1018]: info: QobuzConnect: setDeactiveState invoked Dec 30 11:06:46 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:06:46 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:46 volumio2 sudo[5437]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:46 volumio2 sudo[5456]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:46 volumio2 sudo[5463]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:46 volumio2 volumio[1018]: info: Executing endpoint qc_getconfig Dec 30 11:06:46 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 30 11:06:46 volumio2 systemd[1]: mpd.socket: Deactivated successfully. Dec 30 11:06:46 volumio2 sudo[5478]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 30 11:06:46 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 30 11:06:46 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 30 11:06:46 volumio2 sudo[5478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:46 volumio2 sudo[5481]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 30 11:06:46 volumio2 qobuz-connect[5367]: 20251230 11:06:46.984 [5367.5367] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 30 11:06:46 volumio2 sudo[5481]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:47 volumio2 qobuz-connect[5367]: 20251230 11:06:47.001 [5367.5367] INFO VolumeManager: [0x18fe208]: Setting new playback volume: 75 Dec 30 11:06:47 volumio2 qobuz-connect[5367]: 20251230 11:06:47.001 [5367.5367] INFO VolumeManager: [0x18fe208]: Setting new mute state: 0 Dec 30 11:06:47 volumio2 qobuz-connect[5367]: 20251230 11:06:47.001 [5367.5367] INFO QobuzConnect: [0x18febd8]: Client initialized! Dec 30 11:06:47 volumio2 qobuz-connect[5367]: 20251230 11:06:47.001 [5367.5367] INFO SampleApp: Starting Avahi advertising, name: Volumio2, service name: _qobuz-connect._tcp Dec 30 11:06:47 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 30 11:06:47 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 30 11:06:47 volumio2 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 30 11:06:47 volumio2 qobuz-connect[5367]: 20251230 11:06:47.047 [5367.5367] INFO LocalConfigManager: [0x18fdae8]: Starting Local Configuration server Dec 30 11:06:47 volumio2 qobuz-connect[5367]: 20251230 11:06:47.050 [5367.5367] INFO SampleApp: Starting Local configuration server Dec 30 11:06:47 volumio2 qobuz-connect[5367]: 20251230 11:06:47.055 [5367.5367] INFO SampleApp: Stopping Local configuration server Dec 30 11:06:47 volumio2 volumio[1018]: info: VolumeController::SetAlsaVolume0 Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreStateMachine::pushState Dec 30 11:06:47 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::volumioPushState Dec 30 11:06:47 volumio2 volumio[1018]: info: MRS: Pushing multiroomSync output update for this device Dec 30 11:06:47 volumio2 volumio[1018]: info: MRS: Pushing multiroomSync output Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:06:47 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:47 volumio2 volumio[1018]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 30 11:06:47 volumio2 volumio[1018]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 30 11:06:47 volumio2 volumio[1018]: info: VolumeController::SetAlsaVolume46 Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreStateMachine::pushState Dec 30 11:06:47 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::volumioPushState Dec 30 11:06:47 volumio2 volumio[1018]: info: MRS: Pushing multiroomSync output update for this device Dec 30 11:06:47 volumio2 volumio[1018]: info: MRS: Pushing multiroomSync output Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:06:47 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:47 volumio2 sudo[5486]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 30 11:06:47 volumio2 sudo[5486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:47 volumio2 sudo[5486]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:47 volumio2 volumio[1018]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Dec 30 11:06:47 volumio2 volumio[1018]: SPOTIFY: SPOTIFY VOLUME 100 Dec 30 11:06:47 volumio2 volumio[1018]: SPOTIFY: VOLUMIO VOLUME 46 Dec 30 11:06:47 volumio2 volumio[1018]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 30 11:06:47 volumio2 volumio[1018]: info: Setting Spotify Volume from Volumio: 46 Dec 30 11:06:47 volumio2 volumio[1018]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Dec 30 11:06:47 volumio2 volumio[1018]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 30 11:06:47 volumio2 volumio[1018]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 30 11:06:47 volumio2 volumio[1018]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Dec 30 11:06:47 volumio2 volumio[1018]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 30 11:06:47 volumio2 volumio[1018]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 30 11:06:47 volumio2 volumio[1018]: info: Starting Shairport Sync Dec 30 11:06:47 volumio2 volumio[1018]: info: Starting Shairport Sync Dec 30 11:06:47 volumio2 sudo[5513]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 30 11:06:47 volumio2 sudo[5513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:47 volumio2 sudo[5518]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 30 11:06:47 volumio2 sudo[5518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:47 volumio2 sudo[5515]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 30 11:06:47 volumio2 sudo[5515]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:47 volumio2 sudo[5520]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 30 11:06:47 volumio2 sudo[5520]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:47 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 30 11:06:47 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 30 11:06:47 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 30 11:06:47 volumio2 volumio[1018]: info: Asound.conf file written Dec 30 11:06:47 volumio2 systemd[1]: shairport-sync.service: Consumed 1.455s CPU time. Dec 30 11:06:47 volumio2 sudo[5526]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 30 11:06:47 volumio2 sudo[5526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:47 volumio2 sudo[5526]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:47 volumio2 volumio[1018]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 30 11:06:47 volumio2 volumio[1018]: No state is present for card b1 Dec 30 11:06:47 volumio2 volumio[1018]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 30 11:06:47 volumio2 volumio[1018]: /usr/share/alsa/init/bcm2835:14: control element not found Dec 30 11:06:47 volumio2 volumio[1018]: No state is present for card b1 Dec 30 11:06:47 volumio2 volumio[1018]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 30 11:06:47 volumio2 volumio[1018]: No state is present for card Headphones Dec 30 11:06:47 volumio2 volumio[1018]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 30 11:06:47 volumio2 volumio[1018]: /usr/share/alsa/init/bcm2835:20: control element not found Dec 30 11:06:47 volumio2 volumio[1018]: No state is present for card Headphones Dec 30 11:06:47 volumio2 volumio[1018]: No state is present for card Device Dec 30 11:06:47 volumio2 volumio[1018]: Found hardware: "USB-Audio" "USB Mixer" "USB0d8c:0014" "" "" Dec 30 11:06:47 volumio2 volumio[1018]: Hardware is initialized using a generic method Dec 30 11:06:47 volumio2 volumio[1018]: No state is present for card Device Dec 30 11:06:47 volumio2 volumio[1018]: info: Output device has changed, restarting MPD Dec 30 11:06:47 volumio2 volumio[1018]: info: Output device has changed, restarting Shairport Sync Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 11:06:47 volumio2 sudo[5533]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 30 11:06:47 volumio2 sudo[5533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:47 volumio2 sudo[5535]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 30 11:06:47 volumio2 sudo[5535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:47 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 30 11:06:47 volumio2 sudo[5513]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:47 volumio2 sudo[5533]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:47 volumio2 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 30 11:06:47 volumio2 volumio[1018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 30 11:06:47 volumio2 volumio[1018]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 30 11:06:47 volumio2 sudo[5518]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:47 volumio2 volumio[1018]: info: QobuzConnect: setDeactiveState invoked Dec 30 11:06:47 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:06:47 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:47 volumio2 sudo[5520]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:47 volumio2 sudo[5515]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:47 volumio2 volumio[1018]: info: Preparing to generate the ALSA configuration file Dec 30 11:06:47 volumio2 systemd[1]: mpd.service: Deactivated successfully. Dec 30 11:06:47 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 30 11:06:47 volumio2 systemd[1]: mpd.socket: Deactivated successfully. Dec 30 11:06:47 volumio2 sudo[5561]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 11:06:47 volumio2 sudo[5561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:48 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 30 11:06:48 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 30 11:06:48 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 30 11:06:48 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 30 11:06:48 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Dec 30 11:06:48 volumio2 sudo[5568]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 11:06:48 volumio2 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 30 11:06:48 volumio2 sudo[5568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:48 volumio2 volumio[1018]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 30 11:06:48 volumio2 systemd[1]: vtcs.service: Deactivated successfully. Dec 30 11:06:48 volumio2 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 30 11:06:48 volumio2 volumio[1018]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 30 11:06:48 volumio2 volumio[1018]: info: Reading ALSA contributions from plugins. Dec 30 11:06:48 volumio2 volumio[1018]: info: MPD Permissions set Dec 30 11:06:48 volumio2 volumio[1018]: info: Shairport-Sync Started Dec 30 11:06:48 volumio2 sudo[5577]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 30 11:06:48 volumio2 sudo[5577]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:48 volumio2 sudo[5561]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:48 volumio2 volumio[1018]: (node:1018) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 30 11:06:48 volumio2 volumio[1018]: (Use `node --trace-warnings ...` to show where the warning was created) Dec 30 11:06:48 volumio2 volumio[1018]: (node:1018) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 30 11:06:48 volumio2 volumio[1018]: (node:1018) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 30 11:06:48 volumio2 volumio[1018]: (node:1018) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 30 11:06:48 volumio2 volumio[1018]: (node:1018) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 30 11:06:48 volumio2 volumio[1018]: info: Shairport-Sync Started Dec 30 11:06:48 volumio2 sudo[5575]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 30 11:06:48 volumio2 sudo[5575]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:48 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:48 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:48 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:48 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:48 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:48 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:48 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:48 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:48 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 11:06:48 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:48 volumio2 volumio[1018]: info: Starting Shairport Sync Dec 30 11:06:48 volumio2 sudo[5575]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:48 volumio2 volumio[1018]: info: Asound.conf file written Dec 30 11:06:48 volumio2 sudo[5588]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 30 11:06:48 volumio2 sudo[5588]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:48 volumio2 systemd[1]: mpd.service: Deactivated successfully. Dec 30 11:06:48 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 30 11:06:48 volumio2 sudo[5593]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 30 11:06:48 volumio2 sudo[5593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:48 volumio2 sudo[5589]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 30 11:06:48 volumio2 sudo[5589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:48 volumio2 sudo[5593]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:48 volumio2 sudo[5568]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:48 volumio2 volumio[1018]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 30 11:06:48 volumio2 volumio[1018]: No state is present for card b1 Dec 30 11:06:48 volumio2 volumio[1018]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 30 11:06:48 volumio2 volumio[1018]: /usr/share/alsa/init/bcm2835:14: control element not found Dec 30 11:06:48 volumio2 volumio[1018]: No state is present for card b1 Dec 30 11:06:48 volumio2 volumio[1018]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 30 11:06:48 volumio2 volumio[1018]: No state is present for card Headphones Dec 30 11:06:48 volumio2 volumio[1018]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 30 11:06:48 volumio2 volumio[1018]: /usr/share/alsa/init/bcm2835:20: control element not found Dec 30 11:06:48 volumio2 volumio[1018]: No state is present for card Headphones Dec 30 11:06:48 volumio2 volumio[1018]: No state is present for card Device Dec 30 11:06:48 volumio2 volumio[1018]: Found hardware: "USB-Audio" "USB Mixer" "USB0d8c:0014" "" "" Dec 30 11:06:48 volumio2 volumio[1018]: Hardware is initialized using a generic method Dec 30 11:06:48 volumio2 volumio[1018]: No state is present for card Device Dec 30 11:06:48 volumio2 volumio[1018]: info: Output device has changed, restarting MPD Dec 30 11:06:48 volumio2 systemd[1]: mpd.socket: Deactivated successfully. Dec 30 11:06:48 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 30 11:06:48 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 30 11:06:48 volumio2 volumio[1018]: info: Output device has changed, restarting Shairport Sync Dec 30 11:06:48 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:48 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 11:06:48 volumio2 sudo[5601]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 30 11:06:48 volumio2 sudo[5601]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:48 volumio2 sudo[5601]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:48 volumio2 sudo[5604]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 30 11:06:48 volumio2 sudo[5604]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:48 volumio2 volumio[1018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 30 11:06:48 volumio2 volumio[1018]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 30 11:06:48 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:48 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 30 11:06:48 volumio2 volumio[1018]: info: QobuzConnect: setDeactiveState invoked Dec 30 11:06:48 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:06:48 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:48 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 30 11:06:48 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 30 11:06:48 volumio2 volumio[1018]: info: Preparing to generate the ALSA configuration file Dec 30 11:06:48 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 30 11:06:48 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 30 11:06:48 volumio2 sudo[5617]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 11:06:48 volumio2 sudo[5617]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:48 volumio2 sudo[5620]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 11:06:48 volumio2 sudo[5620]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:48 volumio2 volumio[1018]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 30 11:06:48 volumio2 volumio[1018]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 30 11:06:48 volumio2 volumio[1018]: info: Reading ALSA contributions from plugins. Dec 30 11:06:48 volumio2 volumio[1018]: info: MPD Permissions set Dec 30 11:06:48 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:48 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:48 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:48 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:48 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:48 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:48 volumio2 sudo[5626]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 30 11:06:48 volumio2 sudo[5626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:48 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:48 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:48 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 30 11:06:48 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 11:06:48 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:48 volumio2 volumio[1018]: SPOTIFY: SETTING SPOTIFY VOLUME 46 Dec 30 11:06:48 volumio2 volumio[1018]: info: Sending Spotify command with payload to local API: /player/volume Dec 30 11:06:48 volumio2 volumio[1018]: info: TidalConnect service stoped! Dec 30 11:06:48 volumio2 sudo[5588]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:48 volumio2 volumio[1018]: info: Starting Shairport Sync Dec 30 11:06:48 volumio2 systemd[1]: mpd.service: Deactivated successfully. Dec 30 11:06:48 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 30 11:06:48 volumio2 volumio[1018]: info: Asound.conf file unchanged, so no further update is needed Dec 30 11:06:48 volumio2 volumio[1018]: info: Output device has changed, restarting MPD Dec 30 11:06:49 volumio2 sudo[5636]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 30 11:06:49 volumio2 sudo[5636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:49 volumio2 qobuz-connect[5367]: 20251230 11:06:49.059 [5367.5367] INFO SampleApp: shat down connection on UNIX socket Dec 30 11:06:49 volumio2 sudo[5638]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 30 11:06:49 volumio2 sudo[5638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:49 volumio2 volumio[1018]: info: Output device has changed, restarting Shairport Sync Dec 30 11:06:49 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:49 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 11:06:49 volumio2 sudo[5638]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:49 volumio2 systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV Dec 30 11:06:49 volumio2 systemd[1]: qobuz-connect.service: Failed with result 'signal'. Dec 30 11:06:49 volumio2 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 30 11:06:49 volumio2 sudo[5654]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 30 11:06:49 volumio2 volumio[1018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 30 11:06:49 volumio2 volumio[1018]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 30 11:06:49 volumio2 sudo[5654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:49 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:49 volumio2 sudo[5626]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:49 volumio2 volumio[1018]: info: QobuzConnect: setDeactiveState invoked Dec 30 11:06:49 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:06:49 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:49 volumio2 sudo[5664]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 30 11:06:49 volumio2 sudo[5664]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:49 volumio2 systemd[1]: mpd.socket: Deactivated successfully. Dec 30 11:06:49 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 30 11:06:49 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 30 11:06:49 volumio2 sudo[5666]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 11:06:49 volumio2 sudo[5666]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:49 volumio2 sudo[5671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 30 11:06:49 volumio2 sudo[5671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:49 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 30 11:06:49 volumio2 volumio[1018]: info: MPD Permissions set Dec 30 11:06:49 volumio2 volumio[1018]: info: TidalConnect service stoped! Dec 30 11:06:49 volumio2 volumio[1018]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 30 11:06:49 volumio2 volumio[1018]: info: QobuzConnect: setDeactiveState invoked Dec 30 11:06:49 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:06:49 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:49 volumio2 volumio[1018]: info: Shairport-Sync Started Dec 30 11:06:49 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:49 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 30 11:06:49 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:49 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:49 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:49 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:49 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:49 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:49 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:49 volumio2 sudo[5677]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 30 11:06:49 volumio2 sudo[5677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:49 volumio2 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 30 11:06:49 volumio2 sudo[5478]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:49 volumio2 sudo[5466]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:49 volumio2 sudo[5481]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:49 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 11:06:49 volumio2 sudo[5589]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:49 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 11:06:49 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 30 11:06:49 volumio2 volumio[1018]: info: Starting Shairport Sync Dec 30 11:06:49 volumio2 volumio[1018]: info: TidalConnect service stoped! Dec 30 11:06:49 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 30 11:06:49 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 30 11:06:49 volumio2 sudo[5688]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 30 11:06:49 volumio2 sudo[5688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:49 volumio2 volumio[1018]: info: TidalConnect service stoped! Dec 30 11:06:49 volumio2 sudo[5617]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:49 volumio2 volumio[1018]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 30 11:06:49 volumio2 volumio[1018]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 30 11:06:49 volumio2 sudo[5620]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:49 volumio2 sudo[5700]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 30 11:06:49 volumio2 sudo[5700]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:49 volumio2 volumio[1018]: info: Executing endpoint qc_getconfig Dec 30 11:06:49 volumio2 volumio[1018]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 30 11:06:49 volumio2 qobuz-connect[5680]: 20251230 11:06:49.727 [5680.5680] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 30 11:06:49 volumio2 volumio[1018]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 30 11:06:49 volumio2 volumio[1018]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 30 11:06:49 volumio2 qobuz-connect[5680]: 20251230 11:06:49.737 [5680.5680] INFO VolumeManager: [0x1c3e208]: Setting new playback volume: 75 Dec 30 11:06:49 volumio2 qobuz-connect[5680]: 20251230 11:06:49.737 [5680.5680] INFO VolumeManager: [0x1c3e208]: Setting new mute state: 0 Dec 30 11:06:49 volumio2 qobuz-connect[5680]: 20251230 11:06:49.737 [5680.5680] INFO QobuzConnect: [0x1c3ebd8]: Client initialized! Dec 30 11:06:49 volumio2 qobuz-connect[5680]: 20251230 11:06:49.737 [5680.5680] INFO SampleApp: Starting Avahi advertising, name: Volumio2, service name: _qobuz-connect._tcp Dec 30 11:06:49 volumio2 qobuz-connect[5680]: 20251230 11:06:49.760 [5680.5680] INFO LocalConfigManager: [0x1c3dae8]: Starting Local Configuration server Dec 30 11:06:49 volumio2 qobuz-connect[5680]: 20251230 11:06:49.760 [5680.5680] INFO SampleApp: Starting Local configuration server Dec 30 11:06:49 volumio2 qobuz-connect[5680]: 20251230 11:06:49.762 [5680.5680] INFO SampleApp: Connected to UNIX socket client 0x1c28818 Dec 30 11:06:49 volumio2 volumio[1018]: info: TidalConnect service stoped! Dec 30 11:06:49 volumio2 sudo[5677]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:49 volumio2 volumio[1018]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 30 11:06:49 volumio2 volumio[1018]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 30 11:06:49 volumio2 sudo[5666]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:49 volumio2 sudo[5671]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:49 volumio2 qobuz-connect[5680]: 20251230 11:06:49.877 [5680.5680] INFO SampleApp: Playback volume changed: 75 Dec 30 11:06:49 volumio2 sudo[5716]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 30 11:06:49 volumio2 sudo[5679]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 30 11:06:49 volumio2 sudo[5679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 30 11:06:49 volumio2 sudo[5716]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 11:06:49 volumio2 sudo[5679]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:49 volumio2 qobuz-connect[5680]: 20251230 11:06:49.920 [5680.5680] INFO SampleApp: Stopping Local configuration server Dec 30 11:06:49 volumio2 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 30 11:06:49 volumio2 volumio[1018]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Dec 30 11:06:49 volumio2 volumio[1018]: Job for vtcs.service canceled. Dec 30 11:06:49 volumio2 volumio[1018]: {"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 30 11:06:50 volumio2 volumio[1018]: info: CoreCommandRouter::volumioGetState Dec 30 11:06:50 volumio2 volumio[1018]: info: CorePlayQueue::getTrack 0 Dec 30 11:06:50 volumio2 volumio[1018]: info: TidalConnect service stoped! Dec 30 11:06:50 volumio2 volumio[1018]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 11:06:50 volumio2 sudo[5636]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:49 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 30 11:06:50 volumio2 sudo[5688]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:50 volumio2 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 30 11:06:50 volumio2 systemd[1]: mpd.service: Deactivated successfully. Dec 30 11:06:50 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 30 11:06:50 volumio2 volumio[1018]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Dec 30 11:06:50 volumio2 volumio[1018]: Job for vtcs.service canceled. Dec 30 11:06:50 volumio2 volumio[1018]: at ChildProcess.exithandler (node:child_process:421:12) Dec 30 11:06:50 volumio2 volumio[1018]: at ChildProcess.emit (node:events:514:28) Dec 30 11:06:50 volumio2 volumio[1018]: at maybeClose (node:internal/child_process:1105:16) Dec 30 11:06:50 volumio2 volumio[1018]: at Socket. (node:internal/child_process:457:11) Dec 30 11:06:50 volumio2 volumio[1018]: at Socket.emit (node:events:514:28) Dec 30 11:06:50 volumio2 volumio[1018]: at Pipe. (node:net:337:12) { Dec 30 11:06:50 volumio2 volumio[1018]: code: 1, Dec 30 11:06:50 volumio2 volumio[1018]: killed: false, Dec 30 11:06:50 volumio2 volumio[1018]: signal: null, Dec 30 11:06:50 volumio2 volumio[1018]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Dec 30 11:06:50 volumio2 volumio[1018]: stdout: '', Dec 30 11:06:50 volumio2 volumio[1018]: stderr: 'Job for vtcs.service canceled.\n' Dec 30 11:06:50 volumio2 volumio[1018]: } Dec 30 11:06:50 volumio2 volumio[1018]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 11:06:50 volumio2 systemd[1]: mpd.socket: Deactivated successfully. Dec 30 11:06:50 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 30 11:06:50 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 30 11:06:50 volumio2 sudo[5700]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:50 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 30 11:06:50 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 30 11:06:50 volumio2 sudo[5760]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 30 11:06:50 volumio2 sudo[5760]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 30 11:06:50 volumio2 sudo[5760]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:51 volumio2 qobuz-connect[5680]: 20251230 11:06:51.770 [5680.5680] INFO SampleApp: shat down connection on UNIX socket Dec 30 11:06:51 volumio2 systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 30 11:06:51 volumio2 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 30 11:06:51 volumio2 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 30 11:06:51 volumio2 sudo[5716]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:51 volumio2 sudo[5664]: pam_unix(sudo:session): session closed for user root Dec 30 11:06:56 volumio2 sudo[5775]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-30 11:05' Dec 30 11:06:56 volumio2 sudo[5775]: 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"