Dec 10 18:11:00 volumio-4 sudo[2318]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 18:11:00 volumio-4 sudo[2318]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:00 volumio-4 sudo[2319]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 18:11:00 volumio-4 sudo[2319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:00 volumio-4 sudo[2318]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:00 volumio-4 volumio[1303]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 18:11:00 volumio-4 volumio[1303]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 10 18:11:00 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:00 volumio-4 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 10 18:11:00 volumio-4 volumio[1303]: info: QobuzConnect: setDeactiveState invoked Dec 10 18:11:00 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:00 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:00 volumio-4 systemd[1]: mpd.service: Deactivated successfully. Dec 10 18:11:00 volumio-4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 10 18:11:00 volumio-4 systemd[1]: mpd.service: Consumed 3.227s CPU time. Dec 10 18:11:00 volumio-4 systemd[1]: mpd.socket: Deactivated successfully. Dec 10 18:11:00 volumio-4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 10 18:11:00 volumio-4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 10 18:11:00 volumio-4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 10 18:11:00 volumio-4 volumio[1303]: info: Preparing to generate the ALSA configuration file Dec 10 18:11:00 volumio-4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 10 18:11:00 volumio-4 sudo[2330]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:11:00 volumio-4 sudo[2330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:00 volumio-4 sudo[2331]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 10 18:11:00 volumio-4 sudo[2331]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 10 18:11:00 volumio-4 sudo[2331]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:00 volumio-4 volumio[1303]: info: PLUGIN START: mpd_oled Dec 10 18:11:00 volumio-4 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 10 18:11:00 volumio-4 systemd[1]: vtcs.service: Deactivated successfully. Dec 10 18:11:00 volumio-4 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 10 18:11:00 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 10 18:11:01 volumio-4 sudo[2330]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:01 volumio-4 volumio[1303]: info: Done. Dec 10 18:11:01 volumio-4 sudo[2340]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /tmp/mpd_oled_plugin.sh Dec 10 18:11:01 volumio-4 sudo[2340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:01 volumio-4 sudo[2342]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start mpd_oled_plugin.service Dec 10 18:11:01 volumio-4 sudo[2340]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:01 volumio-4 sudo[2344]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:11:01 volumio-4 sudo[2344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:01 volumio-4 sudo[2342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:01 volumio-4 sudo[2344]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:01 volumio-4 volumio[1303]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 10 18:11:01 volumio-4 volumio[1303]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Dec 10 18:11:01 volumio-4 volumio[1303]: info: Reading ALSA contributions from plugins. Dec 10 18:11:01 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Set execute permissions on /tmp/mpd_oled_plugin.sh Dec 10 18:11:01 volumio-4 volumio[1303]: info: MPD Permissions set Dec 10 18:11:02 volumio-4 volumio[1303]: info: TidalConnect service stoped! Dec 10 18:11:02 volumio-4 sudo[2353]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 10 18:11:02 volumio-4 sudo[2353]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:02 volumio-4 volumio[1303]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 10 18:11:02 volumio-4 volumio[1303]: info: QobuzConnect: setDeactiveState invoked Dec 10 18:11:02 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:02 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:02 volumio-4 volumio[1303]: info: Shairport-Sync Started Dec 10 18:11:02 volumio-4 volumio[1303]: info: TidalConnect service stoped! Dec 10 18:11:02 volumio-4 sudo[2353]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:02 volumio-4 volumio[1303]: info: Executing endpoint qc_getconfig Dec 10 18:11:02 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 10 18:11:02 volumio-4 sudo[2360]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 10 18:11:02 volumio-4 sudo[2360]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:02 volumio-4 qobuz-connect[2313]: 20251210 18:11:02.797 [2313.2313] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 10 18:11:02 volumio-4 qobuz-connect[2313]: 20251210 18:11:02.811 [2313.2313] INFO VolumeManager: [0x9ee268]: Setting new playback volume: 75 Dec 10 18:11:02 volumio-4 qobuz-connect[2313]: 20251210 18:11:02.811 [2313.2313] INFO VolumeManager: [0x9ee268]: Setting new mute state: 0 Dec 10 18:11:02 volumio-4 qobuz-connect[2313]: 20251210 18:11:02.811 [2313.2313] INFO QobuzConnect: [0x9eec38]: Client initialized! Dec 10 18:11:02 volumio-4 qobuz-connect[2313]: 20251210 18:11:02.811 [2313.2313] INFO SampleApp: Starting Avahi advertising, name: Volumio-4, service name: _qobuz-connect._tcp Dec 10 18:11:02 volumio-4 qobuz-connect[2313]: 20251210 18:11:02.855 [2313.2313] INFO LocalConfigManager: [0x9edb48]: Starting Local Configuration server Dec 10 18:11:02 volumio-4 qobuz-connect[2313]: 20251210 18:11:02.855 [2313.2313] INFO SampleApp: Starting Local configuration server Dec 10 18:11:02 volumio-4 qobuz-connect[2313]: 20251210 18:11:02.856 [2313.2313] INFO SampleApp: Connected to UNIX socket client 0x9d8818 Dec 10 18:11:02 volumio-4 qobuz-connect[2313]: 20251210 18:11:02.947 [2313.2313] INFO SampleApp: Playback volume changed: 75 Dec 10 18:11:03 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] tmp script created /tmp/mpd_oled_plugin.sh Dec 10 18:11:03 volumio-4 qobuz-connect[2313]: 20251210 18:11:03.108 [2313.2313] INFO SampleApp: Stopping Local configuration server Dec 10 18:11:03 volumio-4 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 10 18:11:03 volumio-4 volumio[1303]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 10 18:11:03 volumio-4 volumio[1303]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 10 18:11:03 volumio-4 volumio[1303]: info: Executing endpoint tc_getconfig Dec 10 18:11:03 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 10 18:11:03 volumio-4 volumio[1303]: info: TidalConnect service started! Dec 10 18:11:03 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:03 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:03 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:03 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:03 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:03 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:03 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:03 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:03 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:11:03 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:03 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:03 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:03 volumio-4 volumio[1303]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 10 18:11:03 volumio-4 volumio[1303]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 10 18:11:03 volumio-4 volumio[1303]: info: Starting Shairport Sync Dec 10 18:11:03 volumio-4 volumio[1303]: info: Asound.conf file unchanged, so no further update is needed Dec 10 18:11:03 volumio-4 volumio[1303]: info: Output device has changed, restarting MPD Dec 10 18:11:03 volumio-4 sudo[2381]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 18:11:03 volumio-4 sudo[2381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:04 volumio-4 mpd[2336]: 2025-12-10T18:11:03 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 10 18:11:04 volumio-4 volumio[1303]: info: Output device has changed, restarting Shairport Sync Dec 10 18:11:04 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:04 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:11:04 volumio-4 systemd[1]: Started mpd.service - Music Player Daemon. Dec 10 18:11:04 volumio-4 sudo[2319]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:04 volumio-4 sudo[2397]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 18:11:04 volumio-4 sudo[2397]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:04 volumio-4 sudo[2390]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 18:11:04 volumio-4 sudo[2390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:04 volumio-4 systemd[1]: Started mpd_oled_plugin.service - MPD OLED Plugin. Dec 10 18:11:04 volumio-4 sudo[2342]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:04 volumio-4 sudo[2390]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:04 volumio-4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 10 18:11:04 volumio-4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 10 18:11:04 volumio-4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:11:04 volumio-4 systemd[1]: shairport-sync.service: Consumed 1.214s CPU time. Dec 10 18:11:04 volumio-4 sudo[2383]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 10 18:11:04 volumio-4 sudo[2383]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:04 volumio-4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:11:04 volumio-4 volumio[1303]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 18:11:04 volumio-4 volumio[1303]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 10 18:11:04 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:04 volumio-4 sudo[2381]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:04 volumio-4 systemd[1]: Stopping mpd_oled_plugin.service - MPD OLED Plugin... Dec 10 18:11:04 volumio-4 systemd[1]: mpd_oled_plugin.service: Deactivated successfully. Dec 10 18:11:04 volumio-4 systemd[1]: Stopped mpd_oled_plugin.service - MPD OLED Plugin. Dec 10 18:11:04 volumio-4 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 10 18:11:04 volumio-4 systemd[1]: mpd.service: Deactivated successfully. Dec 10 18:11:04 volumio-4 volumio[1303]: info: QobuzConnect: setDeactiveState invoked Dec 10 18:11:04 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:04 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:04 volumio-4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 10 18:11:04 volumio-4 systemd[1]: mpd.service: Consumed 3.218s CPU time. Dec 10 18:11:04 volumio-4 systemd[1]: mpd.socket: Deactivated successfully. Dec 10 18:11:04 volumio-4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 10 18:11:04 volumio-4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 10 18:11:04 volumio-4 volumio[1303]: info: Preparing to generate the ALSA configuration file Dec 10 18:11:04 volumio-4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 10 18:11:04 volumio-4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 10 18:11:04 volumio-4 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 10 18:11:04 volumio-4 sudo[2383]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:04 volumio-4 qobuz-connect[2313]: 20251210 18:11:04.871 [2313.2313] INFO SampleApp: shat down connection on UNIX socket Dec 10 18:11:04 volumio-4 systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 10 18:11:04 volumio-4 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 10 18:11:04 volumio-4 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 10 18:11:05 volumio-4 sudo[2360]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:05 volumio-4 sudo[2430]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 10 18:11:05 volumio-4 sudo[2430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 10 18:11:05 volumio-4 sudo[2429]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:11:05 volumio-4 sudo[2429]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:05 volumio-4 sudo[2430]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:05 volumio-4 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 10 18:11:05 volumio-4 systemd[1]: vtcs.service: Deactivated successfully. Dec 10 18:11:05 volumio-4 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 10 18:11:05 volumio-4 sudo[2429]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:05 volumio-4 sudo[2441]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:11:05 volumio-4 sudo[2441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:06 volumio-4 sudo[2446]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 10 18:11:06 volumio-4 sudo[2446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:06 volumio-4 sudo[2441]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:06 volumio-4 volumio[1303]: error: MPD error: The expression evaluated to a falsy value: Dec 10 18:11:06 volumio-4 volumio[1303]: assert.ok(self.idling) Dec 10 18:11:06 volumio-4 volumio[1303]: error: The expression evaluated to a falsy value: Dec 10 18:11:06 volumio-4 volumio[1303]: assert.ok(self.idling) Dec 10 18:11:06 volumio-4 volumio[1303]: error: MPD error: Error: write EPIPE Dec 10 18:11:06 volumio-4 volumio[1303]: error: write EPIPE {"code":"EPIPE","errno":-32,"syscall":"write"} Dec 10 18:11:06 volumio-4 volumio[1303]: error: MPD error: Error: write EPIPE Dec 10 18:11:06 volumio-4 volumio[1303]: error: write EPIPE {"code":"EPIPE","errno":-32,"syscall":"write"} Dec 10 18:11:06 volumio-4 sudo[2449]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 10 18:11:06 volumio-4 sudo[2449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:06 volumio-4 volumio[1303]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 10 18:11:06 volumio-4 volumio[1303]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Dec 10 18:11:06 volumio-4 volumio[1303]: info: Reading ALSA contributions from plugins. Dec 10 18:11:06 volumio-4 sudo[2446]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:06 volumio-4 sudo[2451]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 10 18:11:06 volumio-4 sudo[2451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:06 volumio-4 volumio[1303]: info: MPD Permissions set Dec 10 18:11:06 volumio-4 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 10 18:11:06 volumio-4 volumio[1303]: info: TidalConnect service stoped! Dec 10 18:11:06 volumio-4 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 10 18:11:06 volumio-4 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 10 18:11:06 volumio-4 volumio[1303]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 10 18:11:06 volumio-4 volumio[1303]: info: QobuzConnect: setDeactiveState invoked Dec 10 18:11:06 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:06 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:06 volumio-4 volumio[1303]: info: Shairport-Sync Started Dec 10 18:11:06 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Started mpd_oled_plugin service Dec 10 18:11:06 volumio-4 volumio[1303]: info: TidalConnect service stoped! Dec 10 18:11:06 volumio-4 sudo[2455]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 10 18:11:06 volumio-4 sudo[2455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:07 volumio-4 volumio[1303]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 10 18:11:07 volumio-4 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 10 18:11:07 volumio-4 systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 10 18:11:07 volumio-4 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 10 18:11:07 volumio-4 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 10 18:11:07 volumio-4 sudo[2455]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:07 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:07 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:07 volumio-4 volumio[1303]: info: Executing endpoint qc_getconfig Dec 10 18:11:07 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 10 18:11:07 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:07 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:07 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:07 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:07 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:07 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:07 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:07 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:08 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:11:08 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:08 volumio-4 volumio[1303]: info: Executing endpoint tc_getconfig Dec 10 18:11:08 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 10 18:11:08 volumio-4 volumio[1303]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 10 18:11:08 volumio-4 volumio[1303]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 10 18:11:08 volumio-4 mpd[2437]: decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 10 18:11:08 volumio-4 mpd[2437]: output: No 'audio_output' defined in config file Dec 10 18:11:08 volumio-4 mpd[2437]: output: Successfully detected a sndio audio device Dec 10 18:11:08 volumio-4 mpd[2437]: zeroconf: No global port, disabling zeroconf Dec 10 18:11:08 volumio-4 systemd[1]: Started mpd.service - Music Player Daemon. Dec 10 18:11:08 volumio-4 sudo[2397]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:08 volumio-4 volumio[1303]: info: Executing endpoint qc_getconfig Dec 10 18:11:08 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 10 18:11:08 volumio-4 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 10 18:11:08 volumio-4 mpd_monitor.sh[2477]: MPD Monitor Service: Starting MPD Monitor Service Dec 10 18:11:08 volumio-4 systemd[1]: Started mpd_oled_plugin.service - MPD OLED Plugin. Dec 10 18:11:08 volumio-4 sudo[2449]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:08 volumio-4 sudo[2451]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:08 volumio-4 qobuz-connect[2462]: 20251210 18:11:08.637 [2462.2462] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 10 18:11:08 volumio-4 qobuz-connect[2462]: 20251210 18:11:08.648 [2462.2462] INFO VolumeManager: [0xdf1268]: Setting new playback volume: 75 Dec 10 18:11:08 volumio-4 qobuz-connect[2462]: 20251210 18:11:08.648 [2462.2462] INFO VolumeManager: [0xdf1268]: Setting new mute state: 0 Dec 10 18:11:08 volumio-4 qobuz-connect[2462]: 20251210 18:11:08.648 [2462.2462] INFO QobuzConnect: [0xdf1c38]: Client initialized! Dec 10 18:11:08 volumio-4 qobuz-connect[2462]: 20251210 18:11:08.648 [2462.2462] INFO SampleApp: Starting Avahi advertising, name: Volumio-4, service name: _qobuz-connect._tcp Dec 10 18:11:08 volumio-4 qobuz-connect[2462]: 20251210 18:11:08.681 [2462.2462] INFO LocalConfigManager: [0xdf0b48]: Starting Local Configuration server Dec 10 18:11:08 volumio-4 qobuz-connect[2462]: 20251210 18:11:08.682 [2462.2462] INFO SampleApp: Starting Local configuration server Dec 10 18:11:08 volumio-4 qobuz-connect[2462]: 20251210 18:11:08.682 [2462.2462] INFO SampleApp: Connected to UNIX socket client 0xddb818 Dec 10 18:11:08 volumio-4 volumio[1303]: info: TidalConnect service started! Dec 10 18:11:08 volumio-4 qobuz-connect[2462]: 20251210 18:11:08.787 [2462.2462] INFO SampleApp: Playback volume changed: 75 Dec 10 18:11:08 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: oledType: 6 - 6. SH1106 I2C 128x64 Dec 10 18:11:08 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: numberOfBars: 16 - 16 (default) Dec 10 18:11:08 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: gapBetweenBars: 1 - 1 (default) Dec 10 18:11:08 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: frameRate: 15 - 15 Dec 10 18:11:08 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: scrollRate: 8 - 8 (default) Dec 10 18:11:08 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: scrollDelay: 5 - 5 (default) Dec 10 18:11:08 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: clockFormat: 0 - 24h leading 0 (default) Dec 10 18:11:08 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: pauseScreenType: p - Play (default) Dec 10 18:11:08 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: invertDisplayPeriod: 0 - Disabled (default) Dec 10 18:11:08 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: i2cAddress: 3c - 3c Dec 10 18:11:08 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: i2cBus: 0 - /dev/i2c-0 Dec 10 18:11:08 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: spiResetGPIONumber: 25 - 25 (default) Dec 10 18:11:08 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: spiDCGPIONumber: 24 - 24 (default) Dec 10 18:11:08 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: spiCS: 0 - 0 (default) Dec 10 18:11:08 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: dateFormat: 0 - DD-MM-YYYY (default) Dec 10 18:11:08 volumio-4 volumio[1303]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 10 18:11:08 volumio-4 volumio[1303]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 10 18:11:08 volumio-4 volumio[1303]: info: Successfully started MPD Monitor Dec 10 18:11:08 volumio-4 volumio[1303]: info: Successfully started MPD Monitor Dec 10 18:11:08 volumio-4 volumio[1303]: info: TidalConnect service stoped! Dec 10 18:11:08 volumio-4 volumio[1303]: info: Starting Shairport Sync Dec 10 18:11:09 volumio-4 sudo[2489]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 18:11:09 volumio-4 sudo[2489]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:09 volumio-4 volumio[1303]: error: updateQueue error: null Dec 10 18:11:09 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:09 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:09 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:09 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:09 volumio-4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 10 18:11:09 volumio-4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 10 18:11:09 volumio-4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:11:09 volumio-4 systemd[1]: shairport-sync.service: Consumed 1.291s CPU time. Dec 10 18:11:09 volumio-4 volumio[1303]: info: TidalConnect service stoped! Dec 10 18:11:09 volumio-4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:11:09 volumio-4 sudo[2489]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:09 volumio-4 sudo[2492]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 10 18:11:09 volumio-4 sudo[2492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:09 volumio-4 volumio[1303]: info: Asound.conf file unchanged, so no further update is needed Dec 10 18:11:09 volumio-4 volumio[1303]: info: Output device has changed, restarting MPD Dec 10 18:11:09 volumio-4 volumio[1303]: info: Output device has changed, restarting Shairport Sync Dec 10 18:11:09 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:09 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:11:09 volumio-4 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 10 18:11:09 volumio-4 sudo[2492]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:10 volumio-4 sudo[2514]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 18:11:10 volumio-4 sudo[2514]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:10 volumio-4 sudo[2518]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 18:11:10 volumio-4 sudo[2518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:10 volumio-4 volumio[1303]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 18:11:10 volumio-4 volumio[1303]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 10 18:11:10 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:10 volumio-4 sudo[2514]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:10 volumio-4 volumio[1303]: info: QobuzConnect: setDeactiveState invoked Dec 10 18:11:10 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:10 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:10 volumio-4 systemd[1]: Stopping mpd_oled_plugin.service - MPD OLED Plugin... Dec 10 18:11:10 volumio-4 systemd[1]: mpd_oled_plugin.service: Deactivated successfully. Dec 10 18:11:10 volumio-4 systemd[1]: Stopped mpd_oled_plugin.service - MPD OLED Plugin. Dec 10 18:11:10 volumio-4 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 10 18:11:10 volumio-4 systemd[1]: mpd.service: Deactivated successfully. Dec 10 18:11:10 volumio-4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 10 18:11:10 volumio-4 systemd[1]: mpd.service: Consumed 3.256s CPU time. Dec 10 18:11:10 volumio-4 systemd[1]: mpd.socket: Deactivated successfully. Dec 10 18:11:10 volumio-4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 10 18:11:10 volumio-4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 10 18:11:10 volumio-4 sudo[2529]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:11:10 volumio-4 sudo[2529]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:10 volumio-4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 10 18:11:10 volumio-4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 10 18:11:10 volumio-4 sudo[2534]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:11:10 volumio-4 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 10 18:11:10 volumio-4 systemd[1]: vtcs.service: Deactivated successfully. Dec 10 18:11:10 volumio-4 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 10 18:11:10 volumio-4 sudo[2529]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:10 volumio-4 sudo[2534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:10 volumio-4 sudo[2531]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 10 18:11:10 volumio-4 sudo[2531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 10 18:11:10 volumio-4 sudo[2531]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:10 volumio-4 volumio[1303]: error: updateQueue error: null Dec 10 18:11:11 volumio-4 volumio[1303]: info: Shairport-Sync Started Dec 10 18:11:11 volumio-4 sudo[2534]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:11 volumio-4 volumio[1303]: info: MPD Permissions set Dec 10 18:11:11 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:11 volumio-4 sudo[2544]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 10 18:11:11 volumio-4 sudo[2546]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 10 18:11:11 volumio-4 sudo[2546]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:11 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:11 volumio-4 sudo[2544]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:11 volumio-4 volumio[1303]: info: Executing endpoint tc_getconfig Dec 10 18:11:11 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 10 18:11:11 volumio-4 volumio[1303]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 10 18:11:11 volumio-4 volumio[1303]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 10 18:11:11 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:11 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:11 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:11 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:11 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:11 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:11 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:11 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:11 volumio-4 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 10 18:11:11 volumio-4 sudo[2544]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:11 volumio-4 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 10 18:11:11 volumio-4 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 10 18:11:11 volumio-4 sudo[2555]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 10 18:11:11 volumio-4 sudo[2555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:11 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:11:11 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:12 volumio-4 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 10 18:11:12 volumio-4 qobuz-connect[2462]: 20251210 18:11:12.203 [2462.2462] INFO SampleApp: Stopping Local configuration server Dec 10 18:11:12 volumio-4 volumio[1303]: info: Starting Shairport Sync Dec 10 18:11:12 volumio-4 sudo[2562]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 10 18:11:12 volumio-4 sudo[2562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:12 volumio-4 sudo[2564]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 10 18:11:12 volumio-4 qobuz-connect[2462]: 20251210 18:11:12.693 [2462.2462] INFO SampleApp: shat down connection on UNIX socket Dec 10 18:11:12 volumio-4 systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 10 18:11:12 volumio-4 volumio[1303]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 10 18:11:12 volumio-4 volumio[1303]: info: QobuzConnect: setDeactiveState invoked Dec 10 18:11:12 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:12 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:12 volumio-4 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 10 18:11:12 volumio-4 sudo[2564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:12 volumio-4 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 10 18:11:12 volumio-4 sudo[2555]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:12 volumio-4 sudo[2566]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 18:11:12 volumio-4 sudo[2566]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:12 volumio-4 volumio[1303]: info: Executing endpoint qc_getconfig Dec 10 18:11:13 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 10 18:11:13 volumio-4 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 10 18:11:13 volumio-4 sudo[2564]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:13 volumio-4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 10 18:11:13 volumio-4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 10 18:11:13 volumio-4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:11:13 volumio-4 systemd[1]: shairport-sync.service: Consumed 1.217s CPU time. Dec 10 18:11:13 volumio-4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:11:13 volumio-4 sudo[2566]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:13 volumio-4 qobuz-connect[2568]: 20251210 18:11:13.502 [2568.2568] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 10 18:11:13 volumio-4 qobuz-connect[2568]: 20251210 18:11:13.511 [2568.2568] INFO VolumeManager: [0x13b1268]: Setting new playback volume: 75 Dec 10 18:11:13 volumio-4 qobuz-connect[2568]: 20251210 18:11:13.511 [2568.2568] INFO VolumeManager: [0x13b1268]: Setting new mute state: 0 Dec 10 18:11:13 volumio-4 qobuz-connect[2568]: 20251210 18:11:13.511 [2568.2568] INFO QobuzConnect: [0x13b1c38]: Client initialized! Dec 10 18:11:13 volumio-4 qobuz-connect[2568]: 20251210 18:11:13.511 [2568.2568] INFO SampleApp: Starting Avahi advertising, name: Volumio-4, service name: _qobuz-connect._tcp Dec 10 18:11:13 volumio-4 go-librespot[1647]: time="2025-12-10T18:11:13+01:00" level=trace msg="received accesspoint ping" Dec 10 18:11:13 volumio-4 qobuz-connect[2568]: 20251210 18:11:13.552 [2568.2568] INFO LocalConfigManager: [0x13b0b48]: Starting Local Configuration server Dec 10 18:11:13 volumio-4 qobuz-connect[2568]: 20251210 18:11:13.553 [2568.2568] INFO SampleApp: Starting Local configuration server Dec 10 18:11:13 volumio-4 qobuz-connect[2568]: 20251210 18:11:13.553 [2568.2568] INFO SampleApp: Connected to UNIX socket client 0x139b818 Dec 10 18:11:13 volumio-4 volumio[1303]: info: Shairport-Sync Started Dec 10 18:11:13 volumio-4 volumio[1303]: info: TidalConnect service started! Dec 10 18:11:13 volumio-4 volumio[1303]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 10 18:11:13 volumio-4 volumio[1303]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 10 18:11:13 volumio-4 volumio[1303]: info: Executing endpoint tc_getconfig Dec 10 18:11:13 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 10 18:11:13 volumio-4 vtcs[2573]: STARTING TidalConnect services, version: 1.5.2.56 Dec 10 18:11:13 volumio-4 qobuz-connect[2568]: 20251210 18:11:13.652 [2568.2568] INFO SampleApp: Playback volume changed: 75 Dec 10 18:11:13 volumio-4 vtcs[2573]: STARTED TidalConnect services. Dec 10 18:11:13 volumio-4 go-librespot[1647]: time="2025-12-10T18:11:13+01:00" level=trace msg="sent dealer ping" Dec 10 18:11:13 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:13 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:13 volumio-4 go-librespot[1647]: time="2025-12-10T18:11:13+01:00" level=trace msg="received accesspoint pong ack" Dec 10 18:11:13 volumio-4 go-librespot[1647]: time="2025-12-10T18:11:13+01:00" level=trace msg="received dealer pong" Dec 10 18:11:13 volumio-4 volumio[1303]: info: Executing endpoint tc_connect Dec 10 18:11:13 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 10 18:11:13 volumio-4 volumio[1303]: info: Connecting to TidalConnect Dec 10 18:11:13 volumio-4 volumio[1303]: info: TidalConnect service stoped! Dec 10 18:11:14 volumio-4 volumio[1303]: info: CoreCommandRouter::servicePushState Dec 10 18:11:14 volumio-4 volumio[1303]: info: CoreStateMachine::pushState Dec 10 18:11:14 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:14 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 18:11:14 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioPushState Dec 10 18:11:14 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output update for this device Dec 10 18:11:14 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output Dec 10 18:11:14 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:14 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:14 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:14 volumio-4 volumio[1303]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 10 18:11:14 volumio-4 volumio[1303]: info: CoreCommandRouter::servicePushState Dec 10 18:11:14 volumio-4 volumio[1303]: info: CoreStateMachine::pushState Dec 10 18:11:14 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:14 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioPushState Dec 10 18:11:14 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output update for this device Dec 10 18:11:14 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output Dec 10 18:11:14 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:14 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:14 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:14 volumio-4 volumio[1303]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 10 18:11:14 volumio-4 mpd[2542]: 2025-12-10T18:11:14 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 10 18:11:14 volumio-4 systemd[1]: Started mpd.service - Music Player Daemon. Dec 10 18:11:14 volumio-4 sudo[2518]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:14 volumio-4 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 10 18:11:14 volumio-4 mpd_monitor.sh[2624]: MPD Monitor Service: Starting MPD Monitor Service Dec 10 18:11:14 volumio-4 systemd[1]: Started mpd_oled_plugin.service - MPD OLED Plugin. Dec 10 18:11:14 volumio-4 sudo[2546]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:14 volumio-4 sudo[2562]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:14 volumio-4 volumio[1303]: info: TidalConnect service stoped! Dec 10 18:11:14 volumio-4 volumio[1303]: info: Successfully started MPD Monitor Dec 10 18:11:14 volumio-4 volumio[1303]: info: Successfully started MPD Monitor Dec 10 18:11:15 volumio-4 volumio[1303]: error: updateQueue error: null Dec 10 18:11:15 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:15 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:15 volumio-4 volumio[1303]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 10 18:11:15 volumio-4 volumio[1303]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 10 18:11:15 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:15 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:15 volumio-4 sudo[2640]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 10 18:11:15 volumio-4 sudo[2640]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:15 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:15 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:15 volumio-4 sudo[2640]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:15 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:15 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:15 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:15 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:15 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:15 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:16 volumio-4 volumio[1303]: info: TidalConnect service started! Dec 10 18:11:16 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:16 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:16 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:16 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:16 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:16 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:17 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:17 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:17 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:17 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:17 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:17 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:18 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:18 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:18 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:18 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:18 volumio-4 sudo[2677]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 10 18:11:18 volumio-4 sudo[2677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:18 volumio-4 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 10 18:11:18 volumio-4 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 10 18:11:18 volumio-4 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 10 18:11:18 volumio-4 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 10 18:11:18 volumio-4 mpd_monitor.sh[2685]: MPD Monitor Service: Starting MPD Monitor Service Dec 10 18:11:18 volumio-4 sudo[2677]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:18 volumio-4 volumio[1303]: info: Successfully started MPD Monitor Dec 10 18:11:18 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:18 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:18 volumio-4 volumio[1303]: info: TidalConnect service started! Dec 10 18:11:18 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:18 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:19 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:19 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:19 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:19 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:19 volumio-4 volumio[1303]: info: CALLMETHOD: system_hardware mpd_oled saveConfig [object Object] Dec 10 18:11:19 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: mpd_oled , saveConfig Dec 10 18:11:19 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: oledType: 3 - 3. Adafruit I2C 128x64 Dec 10 18:11:19 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: numberOfBars: 16 - 16 (default) Dec 10 18:11:19 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: gapBetweenBars: 1 - 1 (default) Dec 10 18:11:19 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: frameRate: 15 - 15 Dec 10 18:11:19 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: scrollRate: 8 - 8 (default) Dec 10 18:11:19 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: scrollDelay: 5 - 5 (default) Dec 10 18:11:19 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: clockFormat: 0 - 24h leading 0 (default) Dec 10 18:11:19 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: pauseScreenType: p - Play (default) Dec 10 18:11:19 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: invertDisplayPeriod: 0 - Disabled (default) Dec 10 18:11:19 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: i2cAddress: 3c - 3c Dec 10 18:11:19 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: i2cBus: 0 - /dev/i2c-0 Dec 10 18:11:19 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: spiResetGPIONumber: 25 - 25 (default) Dec 10 18:11:19 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: spiDCGPIONumber: 24 - 24 (default) Dec 10 18:11:19 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: spiCS: 0 - 0 (default) Dec 10 18:11:19 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: dateFormat: 0 - DD-MM-YYYY (default) Dec 10 18:11:19 volumio-4 sudo[2703]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd_oled_plugin.service Dec 10 18:11:19 volumio-4 sudo[2703]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:20 volumio-4 sudo[2705]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /tmp/mpd_oled_plugin.sh Dec 10 18:11:20 volumio-4 sudo[2705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:20 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] tmp script created /tmp/mpd_oled_plugin.sh Dec 10 18:11:20 volumio-4 sudo[2705]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:20 volumio-4 systemd[1]: Stopping mpd_oled_plugin.service - MPD OLED Plugin... Dec 10 18:11:20 volumio-4 systemd[1]: mpd_oled_plugin.service: Killing process 2638 (mpd_oled) with signal SIGKILL. Dec 10 18:11:20 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:20 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:20 volumio-4 systemd[1]: mpd_oled_plugin.service: Deactivated successfully. Dec 10 18:11:20 volumio-4 systemd[1]: Stopped mpd_oled_plugin.service - MPD OLED Plugin. Dec 10 18:11:20 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Set execute permissions on /tmp/mpd_oled_plugin.sh Dec 10 18:11:20 volumio-4 sudo[2703]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:20 volumio-4 sudo[2708]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start mpd_oled_plugin.service Dec 10 18:11:20 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Stopped mpd_oled_plugin service Dec 10 18:11:20 volumio-4 sudo[2708]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:20 volumio-4 systemd[1]: Started mpd_oled_plugin.service - MPD OLED Plugin. Dec 10 18:11:20 volumio-4 sudo[2708]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:20 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Started mpd_oled_plugin service Dec 10 18:11:20 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:20 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:20 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:20 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:20 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:20 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:20 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:20 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:21 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:21 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:21 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:21 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:21 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:21 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:21 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:21 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:22 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:22 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:22 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:22 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:22 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:22 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:22 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:22 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:23 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:23 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:23 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:23 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:23 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:23 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:23 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:23 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:24 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:24 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:24 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:24 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:24 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:24 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:24 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:24 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:25 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:25 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:25 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:25 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:25 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:25 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:25 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:25 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:26 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:26 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:26 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:26 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:26 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:26 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:27 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:27 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:27 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:27 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:27 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:27 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:28 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:28 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:28 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:28 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:28 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:28 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:28 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:28 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:29 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:29 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:29 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:29 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:29 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:29 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:29 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:29 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:30 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:30 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:30 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:30 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:30 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:30 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:30 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:30 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:31 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:31 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:31 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:31 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:31 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:31 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:31 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:31 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:32 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:32 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:32 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:32 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:32 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:32 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:32 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:32 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:33 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:33 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:33 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:33 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:33 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:33 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:33 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:33 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:33 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 10 18:11:33 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 10 18:11:34 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:34 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:34 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:34 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:34 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:34 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:34 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:34 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:35 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:35 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:35 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:35 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:35 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:35 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:35 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:35 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:36 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:36 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:36 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:36 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:36 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:36 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:36 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:36 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:37 volumio-4 volumio[1303]: info: Disabling plugin mpd_oled Dec 10 18:11:37 volumio-4 volumio[1303]: info: Preparing to generate the ALSA configuration file Dec 10 18:11:37 volumio-4 sudo[2938]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd_oled_plugin.service Dec 10 18:11:37 volumio-4 sudo[2938]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:37 volumio-4 volumio[1303]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 10 18:11:37 volumio-4 volumio[1303]: info: Reading ALSA contributions from plugins. Dec 10 18:11:37 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:37 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:37 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:37 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:37 volumio-4 systemd[1]: Stopping mpd_oled_plugin.service - MPD OLED Plugin... Dec 10 18:11:37 volumio-4 systemd[1]: mpd_oled_plugin.service: Deactivated successfully. Dec 10 18:11:37 volumio-4 systemd[1]: Stopped mpd_oled_plugin.service - MPD OLED Plugin. Dec 10 18:11:37 volumio-4 sudo[2938]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:37 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Stopped mpd_oled_plugin service Dec 10 18:11:37 volumio-4 volumio[1303]: info: Asound.conf file written Dec 10 18:11:38 volumio-4 sudo[2947]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 10 18:11:38 volumio-4 sudo[2947]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:38 volumio-4 sudo[2947]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:38 volumio-4 volumio[1303]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 10 18:11:38 volumio-4 volumio[1303]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 10 18:11:38 volumio-4 volumio[1303]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 10 18:11:38 volumio-4 volumio[1303]: info: Output device has changed, restarting MPD Dec 10 18:11:38 volumio-4 volumio[1303]: info: Output device has changed, restarting Shairport Sync Dec 10 18:11:38 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:38 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:11:38 volumio-4 sudo[2953]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 18:11:38 volumio-4 sudo[2953]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:38 volumio-4 volumio[1303]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 18:11:38 volumio-4 volumio[1303]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 10 18:11:38 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:38 volumio-4 sudo[2953]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:38 volumio-4 volumio[1303]: info: QobuzConnect: setDeactiveState invoked Dec 10 18:11:38 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:38 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:38 volumio-4 vtcs[2573]: [2025-12-10 18:11:38.747] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 10 18:11:38 volumio-4 sudo[2959]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 18:11:38 volumio-4 vtcs[2573]: [2025-12-10 18:11:38.763] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Dec 10 18:11:38 volumio-4 vtcs[2573]: [2025-12-10 18:11:38.763] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 10 18:11:38 volumio-4 sudo[2959]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:38 volumio-4 volumio[1303]: info: Done. Dec 10 18:11:38 volumio-4 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 10 18:11:39 volumio-4 systemd[1]: mpd.service: Deactivated successfully. Dec 10 18:11:39 volumio-4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 10 18:11:39 volumio-4 systemd[1]: mpd.service: Consumed 3.233s CPU time. Dec 10 18:11:39 volumio-4 systemd[1]: mpd.socket: Deactivated successfully. Dec 10 18:11:39 volumio-4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 10 18:11:39 volumio-4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 10 18:11:39 volumio-4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 10 18:11:39 volumio-4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 10 18:11:39 volumio-4 sudo[2968]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:11:39 volumio-4 sudo[2968]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:39 volumio-4 volumio[1303]: info: MPD Permissions set Dec 10 18:11:39 volumio-4 sudo[2971]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:11:39 volumio-4 sudo[2971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:39 volumio-4 sudo[2976]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 10 18:11:39 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:39 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:39 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:39 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:39 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:39 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:39 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:39 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:39 volumio-4 sudo[2976]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 10 18:11:39 volumio-4 sudo[2976]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:39 volumio-4 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 10 18:11:39 volumio-4 systemd[1]: vtcs.service: Killing process 2595 (vtcs) with signal SIGKILL. Dec 10 18:11:39 volumio-4 systemd[1]: vtcs.service: Deactivated successfully. Dec 10 18:11:39 volumio-4 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 10 18:11:39 volumio-4 sudo[2979]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 10 18:11:39 volumio-4 sudo[2979]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:39 volumio-4 sudo[2968]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:39 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:11:39 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:39 volumio-4 volumio[1303]: info: Starting Shairport Sync Dec 10 18:11:39 volumio-4 sudo[2971]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:39 volumio-4 sudo[2979]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:40 volumio-4 sudo[2992]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 18:11:40 volumio-4 sudo[2993]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 10 18:11:40 volumio-4 sudo[2993]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:40 volumio-4 sudo[2992]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:40 volumio-4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 10 18:11:40 volumio-4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 10 18:11:40 volumio-4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:11:40 volumio-4 systemd[1]: shairport-sync.service: Consumed 1.460s CPU time. Dec 10 18:11:40 volumio-4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:11:40 volumio-4 sudo[2992]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:40 volumio-4 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 10 18:11:40 volumio-4 qobuz-connect[2568]: 20251210 18:11:40.629 [2568.2568] INFO SampleApp: Stopping Local configuration server Dec 10 18:11:40 volumio-4 volumio[1303]: info: Shairport-Sync Started Dec 10 18:11:40 volumio-4 volumio[1303]: (node:1303) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 10 18:11:40 volumio-4 volumio[1303]: (Use `node --trace-warnings ...` to show where the warning was created) Dec 10 18:11:40 volumio-4 volumio[1303]: (node:1303) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 10 18:11:40 volumio-4 volumio[1303]: (node:1303) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 10 18:11:40 volumio-4 volumio[1303]: (node:1303) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 10 18:11:40 volumio-4 volumio[1303]: (node:1303) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 10 18:11:41 volumio-4 qobuz-connect[2568]: 20251210 18:11:41.604 [2568.2568] INFO SampleApp: shat down connection on UNIX socket Dec 10 18:11:41 volumio-4 volumio[1303]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 10 18:11:41 volumio-4 volumio[1303]: info: QobuzConnect: setDeactiveState invoked Dec 10 18:11:41 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:41 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:41 volumio-4 systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 10 18:11:41 volumio-4 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 10 18:11:41 volumio-4 volumio[1303]: info: Enabling plugin mpd_oled Dec 10 18:11:41 volumio-4 volumio[1303]: info: Loading plugin "mpd_oled"... Dec 10 18:11:41 volumio-4 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 10 18:11:41 volumio-4 sudo[2993]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:41 volumio-4 volumio[1303]: info: Applying required configuration parameters for plugin mpd_oled Dec 10 18:11:41 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Dec 10 18:11:41 volumio-4 volumio[1303]: info: Preparing to generate the ALSA configuration file Dec 10 18:11:42 volumio-4 volumio[1303]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 10 18:11:42 volumio-4 volumio[1303]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Dec 10 18:11:42 volumio-4 volumio[1303]: info: Reading ALSA contributions from plugins. Dec 10 18:11:42 volumio-4 volumio[1303]: info: Executing endpoint qc_getconfig Dec 10 18:11:42 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 10 18:11:42 volumio-4 qobuz-connect[3011]: 20251210 18:11:42.249 [3011.3011] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 10 18:11:42 volumio-4 qobuz-connect[3011]: 20251210 18:11:42.260 [3011.3011] INFO VolumeManager: [0x152268]: Setting new playback volume: 75 Dec 10 18:11:42 volumio-4 qobuz-connect[3011]: 20251210 18:11:42.272 [3011.3011] INFO VolumeManager: [0x152268]: Setting new mute state: 0 Dec 10 18:11:42 volumio-4 qobuz-connect[3011]: 20251210 18:11:42.272 [3011.3011] INFO QobuzConnect: [0x152c38]: Client initialized! Dec 10 18:11:42 volumio-4 qobuz-connect[3011]: 20251210 18:11:42.272 [3011.3011] INFO SampleApp: Starting Avahi advertising, name: Volumio-4, service name: _qobuz-connect._tcp Dec 10 18:11:42 volumio-4 qobuz-connect[3011]: 20251210 18:11:42.306 [3011.3011] INFO LocalConfigManager: [0x151b48]: Starting Local Configuration server Dec 10 18:11:42 volumio-4 qobuz-connect[3011]: 20251210 18:11:42.306 [3011.3011] INFO SampleApp: Starting Local configuration server Dec 10 18:11:42 volumio-4 qobuz-connect[3011]: 20251210 18:11:42.307 [3011.3011] INFO SampleApp: Connected to UNIX socket client 0x13c818 Dec 10 18:11:42 volumio-4 volumio[1303]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 10 18:11:42 volumio-4 volumio[1303]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 10 18:11:42 volumio-4 qobuz-connect[3011]: 20251210 18:11:42.399 [3011.3011] INFO SampleApp: Playback volume changed: 75 Dec 10 18:11:42 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:42 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:42 volumio-4 volumio[1303]: info: Asound.conf file written Dec 10 18:11:42 volumio-4 mpd[2983]: 2025-12-10T18:11:42 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 10 18:11:42 volumio-4 systemd[1]: Started mpd.service - Music Player Daemon. Dec 10 18:11:42 volumio-4 sudo[2959]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:42 volumio-4 sudo[3019]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 10 18:11:42 volumio-4 sudo[3019]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:42 volumio-4 sudo[3019]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:43 volumio-4 volumio[1303]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 10 18:11:43 volumio-4 volumio[1303]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 10 18:11:43 volumio-4 volumio[1303]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 10 18:11:43 volumio-4 volumio[1303]: info: Output device has changed, restarting MPD Dec 10 18:11:43 volumio-4 volumio[1303]: info: Output device has changed, restarting Shairport Sync Dec 10 18:11:43 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:43 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:11:43 volumio-4 sudo[3027]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 18:11:43 volumio-4 sudo[3027]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:43 volumio-4 sudo[3027]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:43 volumio-4 volumio[1303]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 18:11:43 volumio-4 volumio[1303]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 10 18:11:43 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:43 volumio-4 sudo[3029]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 18:11:43 volumio-4 sudo[3029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:43 volumio-4 volumio[1303]: info: QobuzConnect: setDeactiveState invoked Dec 10 18:11:43 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:43 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:43 volumio-4 volumio[1303]: info: PLUGIN START: mpd_oled Dec 10 18:11:43 volumio-4 go-librespot[1647]: time="2025-12-10T18:11:43+01:00" level=trace msg="sent dealer ping" Dec 10 18:11:43 volumio-4 go-librespot[1647]: time="2025-12-10T18:11:43+01:00" level=trace msg="received dealer pong" Dec 10 18:11:43 volumio-4 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 10 18:11:43 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 10 18:11:43 volumio-4 systemd[1]: mpd.service: Deactivated successfully. Dec 10 18:11:43 volumio-4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 10 18:11:43 volumio-4 systemd[1]: mpd.service: Consumed 3.158s CPU time. Dec 10 18:11:43 volumio-4 systemd[1]: mpd.socket: Deactivated successfully. Dec 10 18:11:43 volumio-4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 10 18:11:43 volumio-4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 10 18:11:43 volumio-4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 10 18:11:43 volumio-4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 10 18:11:44 volumio-4 sudo[3040]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:11:44 volumio-4 sudo[3040]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:44 volumio-4 volumio[1303]: info: Preparing to generate the ALSA configuration file Dec 10 18:11:44 volumio-4 sudo[3045]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 10 18:11:44 volumio-4 sudo[3045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 10 18:11:44 volumio-4 sudo[3045]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:44 volumio-4 sudo[3046]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /tmp/mpd_oled_plugin.sh Dec 10 18:11:44 volumio-4 sudo[3046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:44 volumio-4 volumio[1303]: info: Done. Dec 10 18:11:44 volumio-4 sudo[3046]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:44 volumio-4 sudo[3040]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:44 volumio-4 sudo[3049]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start mpd_oled_plugin.service Dec 10 18:11:44 volumio-4 sudo[3049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:44 volumio-4 volumio[1303]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 10 18:11:44 volumio-4 volumio[1303]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Dec 10 18:11:44 volumio-4 volumio[1303]: info: Reading ALSA contributions from plugins. Dec 10 18:11:44 volumio-4 sudo[3057]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:11:44 volumio-4 sudo[3057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:44 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Set execute permissions on /tmp/mpd_oled_plugin.sh Dec 10 18:11:45 volumio-4 volumio[1303]: info: MPD Permissions set Dec 10 18:11:45 volumio-4 volumio[1303]: info: TidalConnect service stoped! Dec 10 18:11:45 volumio-4 sudo[3062]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 10 18:11:45 volumio-4 sudo[3062]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:45 volumio-4 volumio[1303]: info: TidalConnect service stoped! Dec 10 18:11:45 volumio-4 sudo[3057]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:45 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] tmp script created /tmp/mpd_oled_plugin.sh Dec 10 18:11:45 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:45 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:45 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:45 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:45 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:45 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:45 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:45 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:45 volumio-4 sudo[3062]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:46 volumio-4 sudo[3089]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 10 18:11:46 volumio-4 sudo[3089]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:46 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:11:46 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:46 volumio-4 volumio[1303]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 10 18:11:46 volumio-4 volumio[1303]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 10 18:11:46 volumio-4 volumio[1303]: info: Starting Shairport Sync Dec 10 18:11:46 volumio-4 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 10 18:11:46 volumio-4 qobuz-connect[3011]: 20251210 18:11:46.444 [3011.3011] INFO SampleApp: Stopping Local configuration server Dec 10 18:11:46 volumio-4 sudo[3097]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 18:11:46 volumio-4 sudo[3097]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:46 volumio-4 volumio[1303]: info: Asound.conf file unchanged, so no further update is needed Dec 10 18:11:46 volumio-4 volumio[1303]: info: Output device has changed, restarting MPD Dec 10 18:11:47 volumio-4 volumio[1303]: info: Output device has changed, restarting Shairport Sync Dec 10 18:11:47 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:47 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:11:47 volumio-4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 10 18:11:47 volumio-4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 10 18:11:47 volumio-4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:11:47 volumio-4 systemd[1]: shairport-sync.service: Consumed 1.282s CPU time. Dec 10 18:11:47 volumio-4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:11:47 volumio-4 sudo[3097]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:47 volumio-4 sudo[3099]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 10 18:11:47 volumio-4 sudo[3099]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:47 volumio-4 mpd[3051]: 2025-12-10T18:11:47 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 10 18:11:47 volumio-4 systemd[1]: Started mpd.service - Music Player Daemon. Dec 10 18:11:47 volumio-4 sudo[3029]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:47 volumio-4 sudo[3104]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 18:11:47 volumio-4 sudo[3104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:47 volumio-4 systemd[1]: Started mpd_oled_plugin.service - MPD OLED Plugin. Dec 10 18:11:47 volumio-4 sudo[3102]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 18:11:47 volumio-4 sudo[3102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:47 volumio-4 sudo[3049]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:47 volumio-4 sudo[3102]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:47 volumio-4 volumio[1303]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 18:11:47 volumio-4 volumio[1303]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 10 18:11:47 volumio-4 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 10 18:11:47 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:47 volumio-4 sudo[3099]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:48 volumio-4 systemd[1]: Stopping mpd_oled_plugin.service - MPD OLED Plugin... Dec 10 18:11:48 volumio-4 systemd[1]: mpd_oled_plugin.service: Deactivated successfully. Dec 10 18:11:48 volumio-4 systemd[1]: Stopped mpd_oled_plugin.service - MPD OLED Plugin. Dec 10 18:11:48 volumio-4 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 10 18:11:48 volumio-4 systemd[1]: mpd.service: Deactivated successfully. Dec 10 18:11:48 volumio-4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 10 18:11:48 volumio-4 systemd[1]: mpd.service: Consumed 3.266s CPU time. Dec 10 18:11:48 volumio-4 systemd[1]: mpd.socket: Deactivated successfully. Dec 10 18:11:48 volumio-4 volumio[1303]: info: QobuzConnect: setDeactiveState invoked Dec 10 18:11:48 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:48 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:48 volumio-4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 10 18:11:48 volumio-4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 10 18:11:48 volumio-4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 10 18:11:48 volumio-4 qobuz-connect[3011]: 20251210 18:11:48.330 [3011.3011] INFO SampleApp: shat down connection on UNIX socket Dec 10 18:11:48 volumio-4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 10 18:11:48 volumio-4 systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 10 18:11:48 volumio-4 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 10 18:11:48 volumio-4 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 10 18:11:48 volumio-4 sudo[3089]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:48 volumio-4 sudo[3137]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 10 18:11:48 volumio-4 sudo[3137]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 10 18:11:48 volumio-4 sudo[3137]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:48 volumio-4 sudo[3140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:11:48 volumio-4 sudo[3140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:48 volumio-4 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 10 18:11:48 volumio-4 systemd[1]: vtcs.service: Deactivated successfully. Dec 10 18:11:48 volumio-4 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 10 18:11:49 volumio-4 sudo[3140]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:49 volumio-4 sudo[3142]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:11:49 volumio-4 sudo[3142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:49 volumio-4 sudo[3153]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 10 18:11:49 volumio-4 sudo[3153]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:49 volumio-4 sudo[3142]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:49 volumio-4 sudo[3153]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:49 volumio-4 sudo[3158]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 10 18:11:49 volumio-4 sudo[3158]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:49 volumio-4 volumio[1303]: error: MPD error: Error: write EPIPE Dec 10 18:11:49 volumio-4 volumio[1303]: error: write EPIPE {"code":"EPIPE","errno":-32,"syscall":"write"} Dec 10 18:11:49 volumio-4 sudo[3157]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 10 18:11:49 volumio-4 sudo[3157]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:49 volumio-4 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 10 18:11:49 volumio-4 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 10 18:11:49 volumio-4 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 10 18:11:49 volumio-4 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 10 18:11:49 volumio-4 systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 10 18:11:49 volumio-4 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 10 18:11:50 volumio-4 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 10 18:11:50 volumio-4 sudo[3158]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:50 volumio-4 volumio[1303]: info: MPD Permissions set Dec 10 18:11:50 volumio-4 volumio[1303]: info: TidalConnect service stoped! Dec 10 18:11:50 volumio-4 volumio[1303]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 10 18:11:50 volumio-4 volumio[1303]: info: QobuzConnect: setDeactiveState invoked Dec 10 18:11:50 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:50 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:50 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Started mpd_oled_plugin service Dec 10 18:11:50 volumio-4 volumio[1303]: info: TidalConnect service stoped! Dec 10 18:11:50 volumio-4 volumio[1303]: info: Shairport-Sync Started Dec 10 18:11:50 volumio-4 volumio[1303]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 10 18:11:50 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:50 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:50 volumio-4 volumio[1303]: info: Executing endpoint tc_getconfig Dec 10 18:11:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 10 18:11:51 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:51 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:51 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:51 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:51 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:51 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:51 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:51 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:51 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:11:51 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:11:51 volumio-4 volumio[1303]: info: Executing endpoint qc_getconfig Dec 10 18:11:51 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 10 18:11:51 volumio-4 volumio[1303]: info: TidalConnect service started! Dec 10 18:11:51 volumio-4 volumio[1303]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 10 18:11:51 volumio-4 volumio[1303]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 10 18:11:51 volumio-4 mpd[3145]: decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 10 18:11:51 volumio-4 mpd[3145]: output: No 'audio_output' defined in config file Dec 10 18:11:51 volumio-4 mpd[3145]: output: Successfully detected a sndio audio device Dec 10 18:11:51 volumio-4 mpd[3145]: zeroconf: No global port, disabling zeroconf Dec 10 18:11:51 volumio-4 systemd[1]: Started mpd.service - Music Player Daemon. Dec 10 18:11:51 volumio-4 sudo[3104]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:51 volumio-4 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 10 18:11:51 volumio-4 sudo[3157]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:51 volumio-4 mpd_monitor.sh[3180]: MPD Monitor Service: Starting MPD Monitor Service Dec 10 18:11:51 volumio-4 systemd[1]: Started mpd_oled_plugin.service - MPD OLED Plugin. Dec 10 18:11:52 volumio-4 volumio[1303]: info: Executing endpoint qc_getconfig Dec 10 18:11:52 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 10 18:11:52 volumio-4 qobuz-connect[3161]: 20251210 18:11:52.272 [3161.3161] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 10 18:11:52 volumio-4 qobuz-connect[3161]: 20251210 18:11:52.280 [3161.3161] INFO VolumeManager: [0x1b7c268]: Setting new playback volume: 75 Dec 10 18:11:52 volumio-4 qobuz-connect[3161]: 20251210 18:11:52.281 [3161.3161] INFO VolumeManager: [0x1b7c268]: Setting new mute state: 0 Dec 10 18:11:52 volumio-4 qobuz-connect[3161]: 20251210 18:11:52.281 [3161.3161] INFO QobuzConnect: [0x1b7cc38]: Client initialized! Dec 10 18:11:52 volumio-4 qobuz-connect[3161]: 20251210 18:11:52.281 [3161.3161] INFO SampleApp: Starting Avahi advertising, name: Volumio-4, service name: _qobuz-connect._tcp Dec 10 18:11:52 volumio-4 qobuz-connect[3161]: 20251210 18:11:52.322 [3161.3161] INFO LocalConfigManager: [0x1b7bb48]: Starting Local Configuration server Dec 10 18:11:52 volumio-4 qobuz-connect[3161]: 20251210 18:11:52.322 [3161.3161] INFO SampleApp: Starting Local configuration server Dec 10 18:11:52 volumio-4 qobuz-connect[3161]: 20251210 18:11:52.322 [3161.3161] INFO SampleApp: Connected to UNIX socket client 0x1b66818 Dec 10 18:11:52 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: oledType: 3 - 3. Adafruit I2C 128x64 Dec 10 18:11:52 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: numberOfBars: 16 - 16 (default) Dec 10 18:11:52 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: gapBetweenBars: 1 - 1 (default) Dec 10 18:11:52 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: frameRate: 15 - 15 Dec 10 18:11:52 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: scrollRate: 8 - 8 (default) Dec 10 18:11:52 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: scrollDelay: 5 - 5 (default) Dec 10 18:11:52 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: clockFormat: 0 - 24h leading 0 (default) Dec 10 18:11:52 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: pauseScreenType: p - Play (default) Dec 10 18:11:52 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: invertDisplayPeriod: 0 - Disabled (default) Dec 10 18:11:52 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: i2cAddress: 3c - 3c Dec 10 18:11:52 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: i2cBus: 0 - /dev/i2c-0 Dec 10 18:11:52 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: spiResetGPIONumber: 25 - 25 (default) Dec 10 18:11:52 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: spiDCGPIONumber: 24 - 24 (default) Dec 10 18:11:52 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: spiCS: 0 - 0 (default) Dec 10 18:11:52 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Populating: dateFormat: 0 - DD-MM-YYYY (default) Dec 10 18:11:52 volumio-4 volumio[1303]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 10 18:11:52 volumio-4 volumio[1303]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 10 18:11:52 volumio-4 volumio[1303]: info: TidalConnect service stoped! Dec 10 18:11:52 volumio-4 qobuz-connect[3161]: 20251210 18:11:52.422 [3161.3161] INFO SampleApp: Playback volume changed: 75 Dec 10 18:11:52 volumio-4 volumio[1303]: info: TidalConnect service stoped! Dec 10 18:11:52 volumio-4 volumio[1303]: info: Successfully started MPD Monitor Dec 10 18:11:52 volumio-4 volumio[1303]: info: Starting Shairport Sync Dec 10 18:11:52 volumio-4 volumio[1303]: error: updateQueue error: null Dec 10 18:11:52 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:52 volumio-4 sudo[3196]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 18:11:52 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:52 volumio-4 sudo[3196]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:52 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:52 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:52 volumio-4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 10 18:11:52 volumio-4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 10 18:11:52 volumio-4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:11:52 volumio-4 systemd[1]: shairport-sync.service: Consumed 1.422s CPU time. Dec 10 18:11:52 volumio-4 volumio[1303]: error: updateQueue error: null Dec 10 18:11:53 volumio-4 sudo[3199]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 10 18:11:53 volumio-4 sudo[3199]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:53 volumio-4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:11:53 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:53 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:53 volumio-4 sudo[3196]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:53 volumio-4 volumio[1303]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 10 18:11:53 volumio-4 volumio[1303]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 10 18:11:53 volumio-4 volumio[1303]: info: Shairport-Sync Started Dec 10 18:11:53 volumio-4 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 10 18:11:53 volumio-4 sudo[3199]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:53 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:53 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:53 volumio-4 sudo[3226]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 10 18:11:53 volumio-4 sudo[3226]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:53 volumio-4 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 10 18:11:53 volumio-4 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 10 18:11:53 volumio-4 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 10 18:11:53 volumio-4 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 10 18:11:53 volumio-4 mpd_monitor.sh[3228]: MPD Monitor Service: Starting MPD Monitor Service Dec 10 18:11:53 volumio-4 sudo[3226]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:54 volumio-4 volumio[1303]: info: Successfully started MPD Monitor Dec 10 18:11:54 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:54 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:54 volumio-4 volumio[1303]: info: Executing endpoint tc_getconfig Dec 10 18:11:54 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 10 18:11:54 volumio-4 sudo[3230]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 10 18:11:54 volumio-4 vtcs[3223]: STARTING TidalConnect services, version: 1.5.2.56 Dec 10 18:11:54 volumio-4 vtcs[3223]: STARTED TidalConnect services. Dec 10 18:11:54 volumio-4 sudo[3230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:54 volumio-4 volumio[1303]: info: Executing endpoint tc_connect Dec 10 18:11:54 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 10 18:11:54 volumio-4 volumio[1303]: info: Connecting to TidalConnect Dec 10 18:11:54 volumio-4 sudo[3230]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:54 volumio-4 volumio[1303]: info: CoreCommandRouter::servicePushState Dec 10 18:11:54 volumio-4 volumio[1303]: info: CoreStateMachine::pushState Dec 10 18:11:54 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:54 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 18:11:54 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioPushState Dec 10 18:11:54 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output update for this device Dec 10 18:11:54 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output Dec 10 18:11:54 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:54 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:54 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:54 volumio-4 volumio[1303]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 10 18:11:54 volumio-4 volumio[1303]: info: CoreCommandRouter::servicePushState Dec 10 18:11:54 volumio-4 volumio[1303]: info: CoreStateMachine::pushState Dec 10 18:11:54 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:54 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioPushState Dec 10 18:11:54 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output update for this device Dec 10 18:11:54 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output Dec 10 18:11:54 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:54 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:54 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:54 volumio-4 volumio[1303]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 10 18:11:55 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:55 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:55 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:55 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:55 volumio-4 sudo[3266]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 10 18:11:55 volumio-4 sudo[3266]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:11:55 volumio-4 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 10 18:11:55 volumio-4 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 10 18:11:55 volumio-4 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 10 18:11:55 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:55 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:55 volumio-4 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 10 18:11:55 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:55 volumio-4 mpd_monitor.sh[3281]: MPD Monitor Service: Starting MPD Monitor Service Dec 10 18:11:55 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:55 volumio-4 sudo[3266]: pam_unix(sudo:session): session closed for user root Dec 10 18:11:55 volumio-4 volumio[1303]: info: Successfully started MPD Monitor Dec 10 18:11:56 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:56 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:56 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:56 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:56 volumio-4 volumio[1303]: info: TidalConnect service started! Dec 10 18:11:56 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:56 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:56 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:56 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:57 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:57 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:57 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:57 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:57 volumio-4 volumio[1303]: info: TidalConnect service started! Dec 10 18:11:57 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:57 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:57 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:57 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:58 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:58 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:58 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:58 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:58 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:58 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:58 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:58 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:59 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:59 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:59 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:59 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:59 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:59 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:11:59 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:11:59 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:00 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:00 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:00 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:00 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:00 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:00 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:00 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:00 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:01 volumio-4 volumio[1303]: info: CALLMETHOD: system_hardware mpd_oled saveConfig [object Object] Dec 10 18:12:01 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: mpd_oled , saveConfig Dec 10 18:12:01 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: oledType: 4 - 4. Seeed I2C 128x64 Dec 10 18:12:01 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: numberOfBars: 16 - 16 (default) Dec 10 18:12:01 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: gapBetweenBars: 1 - 1 (default) Dec 10 18:12:01 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: frameRate: 15 - 15 Dec 10 18:12:01 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: scrollRate: 8 - 8 (default) Dec 10 18:12:01 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: scrollDelay: 5 - 5 (default) Dec 10 18:12:01 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: clockFormat: 0 - 24h leading 0 (default) Dec 10 18:12:01 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: pauseScreenType: p - Play (default) Dec 10 18:12:01 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: invertDisplayPeriod: 0 - Disabled (default) Dec 10 18:12:01 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: i2cAddress: 3c - 3c Dec 10 18:12:01 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: i2cBus: 0 - /dev/i2c-0 Dec 10 18:12:01 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: spiResetGPIONumber: 25 - 25 (default) Dec 10 18:12:01 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: spiDCGPIONumber: 24 - 24 (default) Dec 10 18:12:01 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: spiCS: 0 - 0 (default) Dec 10 18:12:01 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Saving: dateFormat: 0 - DD-MM-YYYY (default) Dec 10 18:12:01 volumio-4 sudo[3353]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd_oled_plugin.service Dec 10 18:12:01 volumio-4 sudo[3353]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:01 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:01 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:01 volumio-4 sudo[3356]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /tmp/mpd_oled_plugin.sh Dec 10 18:12:01 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] tmp script created /tmp/mpd_oled_plugin.sh Dec 10 18:12:01 volumio-4 sudo[3356]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:01 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:01 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:01 volumio-4 sudo[3356]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:01 volumio-4 sudo[3357]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start mpd_oled_plugin.service Dec 10 18:12:01 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Set execute permissions on /tmp/mpd_oled_plugin.sh Dec 10 18:12:01 volumio-4 sudo[3357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:01 volumio-4 systemd[1]: Stopping mpd_oled_plugin.service - MPD OLED Plugin... Dec 10 18:12:01 volumio-4 sudo[3357]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:01 volumio-4 systemd[1]: mpd_oled_plugin.service: Deactivated successfully. Dec 10 18:12:01 volumio-4 systemd[1]: Stopped mpd_oled_plugin.service - MPD OLED Plugin. Dec 10 18:12:01 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Started mpd_oled_plugin service Dec 10 18:12:01 volumio-4 sudo[3353]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:01 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Stopped mpd_oled_plugin service Dec 10 18:12:13 volumio-4 go-librespot[1647]: time="2025-12-10T18:12:13+01:00" level=trace msg="sent dealer ping" Dec 10 18:12:13 volumio-4 go-librespot[1647]: time="2025-12-10T18:12:13+01:00" level=trace msg="received dealer pong" Dec 10 18:12:13 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 10 18:12:13 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 10 18:12:17 volumio-4 volumio[1303]: info: Disabling plugin mpd_oled Dec 10 18:12:17 volumio-4 volumio[1303]: info: Preparing to generate the ALSA configuration file Dec 10 18:12:17 volumio-4 sudo[3400]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd_oled_plugin.service Dec 10 18:12:17 volumio-4 sudo[3400]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:17 volumio-4 volumio[1303]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 10 18:12:17 volumio-4 volumio[1303]: info: Reading ALSA contributions from plugins. Dec 10 18:12:17 volumio-4 sudo[3400]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:17 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Stopped mpd_oled_plugin service Dec 10 18:12:18 volumio-4 volumio[1303]: info: Asound.conf file written Dec 10 18:12:18 volumio-4 sudo[3407]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 10 18:12:18 volumio-4 sudo[3407]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:18 volumio-4 sudo[3407]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:18 volumio-4 volumio[1303]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 10 18:12:18 volumio-4 volumio[1303]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 10 18:12:18 volumio-4 volumio[1303]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 10 18:12:18 volumio-4 volumio[1303]: info: Output device has changed, restarting MPD Dec 10 18:12:18 volumio-4 volumio[1303]: info: Output device has changed, restarting Shairport Sync Dec 10 18:12:18 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:18 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:12:18 volumio-4 sudo[3413]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 18:12:18 volumio-4 sudo[3413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:18 volumio-4 sudo[3413]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:18 volumio-4 volumio[1303]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 18:12:18 volumio-4 volumio[1303]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 10 18:12:18 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:18 volumio-4 sudo[3417]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 18:12:18 volumio-4 sudo[3417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:18 volumio-4 volumio[1303]: info: QobuzConnect: setDeactiveState invoked Dec 10 18:12:18 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:18 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:18 volumio-4 vtcs[3223]: [2025-12-10 18:12:18.895] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 10 18:12:18 volumio-4 vtcs[3223]: [2025-12-10 18:12:18.896] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 10 18:12:18 volumio-4 vtcs[3223]: [2025-12-10 18:12:18.896] [tisoc] [error] [SpkconServer.cpp:472] socket write error. fd=3 errorno=32 error=Broken pipe Dec 10 18:12:18 volumio-4 volumio[1303]: info: Done. Dec 10 18:12:19 volumio-4 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 10 18:12:19 volumio-4 systemd[1]: mpd.service: Deactivated successfully. Dec 10 18:12:19 volumio-4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 10 18:12:19 volumio-4 systemd[1]: mpd.service: Consumed 3.187s CPU time. Dec 10 18:12:19 volumio-4 systemd[1]: mpd.socket: Deactivated successfully. Dec 10 18:12:19 volumio-4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 10 18:12:19 volumio-4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 10 18:12:19 volumio-4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 10 18:12:19 volumio-4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 10 18:12:19 volumio-4 sudo[3425]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:12:19 volumio-4 sudo[3425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:19 volumio-4 sudo[3429]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:12:19 volumio-4 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 10 18:12:19 volumio-4 systemd[1]: vtcs.service: Killing process 3244 (vtcs) with signal SIGKILL. Dec 10 18:12:19 volumio-4 systemd[1]: vtcs.service: Deactivated successfully. Dec 10 18:12:19 volumio-4 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 10 18:12:19 volumio-4 sudo[3429]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:19 volumio-4 sudo[3430]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 10 18:12:19 volumio-4 sudo[3430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 10 18:12:19 volumio-4 sudo[3425]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:19 volumio-4 sudo[3430]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:19 volumio-4 sudo[3429]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:19 volumio-4 volumio[1303]: info: MPD Permissions set Dec 10 18:12:19 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:19 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:19 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:19 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:19 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:19 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:19 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:19 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:19 volumio-4 sudo[3440]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 10 18:12:19 volumio-4 sudo[3440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:19 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:12:19 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:20 volumio-4 sudo[3440]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:20 volumio-4 volumio[1303]: info: Starting Shairport Sync Dec 10 18:12:20 volumio-4 sudo[3448]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 10 18:12:20 volumio-4 sudo[3448]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:20 volumio-4 sudo[3450]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 18:12:20 volumio-4 sudo[3450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:20 volumio-4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 10 18:12:20 volumio-4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 10 18:12:20 volumio-4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:12:20 volumio-4 systemd[1]: shairport-sync.service: Consumed 1.468s CPU time. Dec 10 18:12:20 volumio-4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:12:20 volumio-4 sudo[3450]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:20 volumio-4 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 10 18:12:20 volumio-4 qobuz-connect[3161]: 20251210 18:12:20.723 [3161.3161] INFO SampleApp: Stopping Local configuration server Dec 10 18:12:20 volumio-4 volumio[1303]: info: Shairport-Sync Started Dec 10 18:12:20 volumio-4 volumio[1303]: info: Enabling plugin mpd_oled Dec 10 18:12:20 volumio-4 volumio[1303]: info: Loading plugin "mpd_oled"... Dec 10 18:12:20 volumio-4 volumio[1303]: info: Applying required configuration parameters for plugin mpd_oled Dec 10 18:12:20 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Dec 10 18:12:21 volumio-4 volumio[1303]: info: Preparing to generate the ALSA configuration file Dec 10 18:12:21 volumio-4 volumio[1303]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 10 18:12:21 volumio-4 volumio[1303]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Dec 10 18:12:21 volumio-4 volumio[1303]: info: Reading ALSA contributions from plugins. Dec 10 18:12:21 volumio-4 volumio[1303]: info: Asound.conf file written Dec 10 18:12:21 volumio-4 sudo[3470]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 10 18:12:22 volumio-4 sudo[3470]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:22 volumio-4 sudo[3470]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:22 volumio-4 volumio[1303]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 10 18:12:22 volumio-4 volumio[1303]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 10 18:12:22 volumio-4 volumio[1303]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 10 18:12:22 volumio-4 volumio[1303]: info: Output device has changed, restarting MPD Dec 10 18:12:22 volumio-4 qobuz-connect[3161]: 20251210 18:12:22.357 [3161.3161] INFO SampleApp: shat down connection on UNIX socket Dec 10 18:12:22 volumio-4 systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 10 18:12:22 volumio-4 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 10 18:12:22 volumio-4 sudo[3476]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 18:12:22 volumio-4 sudo[3476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:22 volumio-4 sudo[3476]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:22 volumio-4 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 10 18:12:22 volumio-4 sudo[3448]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:22 volumio-4 volumio[1303]: info: Output device has changed, restarting Shairport Sync Dec 10 18:12:22 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:22 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:12:22 volumio-4 sudo[3480]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 18:12:22 volumio-4 mpd[3438]: 2025-12-10T18:12:22 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 10 18:12:22 volumio-4 systemd[1]: Started mpd.service - Music Player Daemon. Dec 10 18:12:22 volumio-4 sudo[3480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:22 volumio-4 sudo[3417]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:22 volumio-4 volumio[1303]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 18:12:22 volumio-4 volumio[1303]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 10 18:12:22 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:22 volumio-4 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 10 18:12:22 volumio-4 volumio[1303]: info: QobuzConnect: setDeactiveState invoked Dec 10 18:12:22 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:22 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:22 volumio-4 systemd[1]: mpd.service: Deactivated successfully. Dec 10 18:12:22 volumio-4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 10 18:12:22 volumio-4 systemd[1]: mpd.service: Consumed 3.178s CPU time. Dec 10 18:12:22 volumio-4 systemd[1]: mpd.socket: Deactivated successfully. Dec 10 18:12:22 volumio-4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 10 18:12:22 volumio-4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 10 18:12:23 volumio-4 volumio[1303]: info: PLUGIN START: mpd_oled Dec 10 18:12:23 volumio-4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 10 18:12:23 volumio-4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 10 18:12:23 volumio-4 sudo[3492]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:12:23 volumio-4 sudo[3492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:23 volumio-4 sudo[3493]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 10 18:12:23 volumio-4 sudo[3493]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 10 18:12:23 volumio-4 sudo[3493]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:23 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 10 18:12:23 volumio-4 sudo[3492]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:23 volumio-4 volumio[1303]: info: Preparing to generate the ALSA configuration file Dec 10 18:12:23 volumio-4 sudo[3504]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start mpd_oled_plugin.service Dec 10 18:12:23 volumio-4 sudo[3504]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:23 volumio-4 sudo[3501]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /tmp/mpd_oled_plugin.sh Dec 10 18:12:23 volumio-4 sudo[3501]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:23 volumio-4 sudo[3501]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:24 volumio-4 volumio[1303]: info: Done. Dec 10 18:12:24 volumio-4 sudo[3508]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:12:24 volumio-4 sudo[3508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:24 volumio-4 volumio[1303]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 10 18:12:24 volumio-4 volumio[1303]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Dec 10 18:12:24 volumio-4 volumio[1303]: info: Reading ALSA contributions from plugins. Dec 10 18:12:24 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Set execute permissions on /tmp/mpd_oled_plugin.sh Dec 10 18:12:24 volumio-4 volumio[1303]: info: MPD Permissions set Dec 10 18:12:24 volumio-4 volumio[1303]: info: TidalConnect service stoped! Dec 10 18:12:24 volumio-4 sudo[3514]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 10 18:12:24 volumio-4 sudo[3514]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:25 volumio-4 sudo[3508]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:25 volumio-4 volumio[1303]: info: TidalConnect service stoped! Dec 10 18:12:25 volumio-4 volumio[1303]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 10 18:12:25 volumio-4 volumio[1303]: info: QobuzConnect: setDeactiveState invoked Dec 10 18:12:25 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:25 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:25 volumio-4 sudo[3514]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:25 volumio-4 volumio[1303]: info: Executing endpoint qc_getconfig Dec 10 18:12:25 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 10 18:12:25 volumio-4 qobuz-connect[3477]: 20251210 18:12:25.697 [3477.3477] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 10 18:12:25 volumio-4 qobuz-connect[3477]: 20251210 18:12:25.713 [3477.3477] INFO VolumeManager: [0x1a74268]: Setting new playback volume: 75 Dec 10 18:12:25 volumio-4 qobuz-connect[3477]: 20251210 18:12:25.713 [3477.3477] INFO VolumeManager: [0x1a74268]: Setting new mute state: 0 Dec 10 18:12:25 volumio-4 qobuz-connect[3477]: 20251210 18:12:25.714 [3477.3477] INFO QobuzConnect: [0x1a74c38]: Client initialized! Dec 10 18:12:25 volumio-4 qobuz-connect[3477]: 20251210 18:12:25.714 [3477.3477] INFO SampleApp: Starting Avahi advertising, name: Volumio-4, service name: _qobuz-connect._tcp Dec 10 18:12:25 volumio-4 qobuz-connect[3477]: 20251210 18:12:25.739 [3477.3477] INFO LocalConfigManager: [0x1a73b48]: Starting Local Configuration server Dec 10 18:12:25 volumio-4 qobuz-connect[3477]: 20251210 18:12:25.740 [3477.3477] INFO SampleApp: Starting Local configuration server Dec 10 18:12:25 volumio-4 qobuz-connect[3477]: 20251210 18:12:25.741 [3477.3477] INFO SampleApp: Connected to UNIX socket client 0x1a5e818 Dec 10 18:12:25 volumio-4 sudo[3522]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 10 18:12:25 volumio-4 sudo[3522]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:25 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] tmp script created /tmp/mpd_oled_plugin.sh Dec 10 18:12:25 volumio-4 volumio[1303]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 10 18:12:25 volumio-4 volumio[1303]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 10 18:12:25 volumio-4 qobuz-connect[3477]: 20251210 18:12:25.847 [3477.3477] INFO SampleApp: Playback volume changed: 75 Dec 10 18:12:25 volumio-4 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 10 18:12:25 volumio-4 qobuz-connect[3477]: 20251210 18:12:25.850 [3477.3477] INFO SampleApp: Stopping Local configuration server Dec 10 18:12:25 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:25 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:25 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:25 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:25 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:25 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:25 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:25 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:25 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:12:25 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:26 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:26 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:26 volumio-4 volumio[1303]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 10 18:12:26 volumio-4 volumio[1303]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 10 18:12:26 volumio-4 volumio[1303]: info: Starting Shairport Sync Dec 10 18:12:26 volumio-4 volumio[1303]: info: Asound.conf file unchanged, so no further update is needed Dec 10 18:12:26 volumio-4 volumio[1303]: info: Output device has changed, restarting MPD Dec 10 18:12:26 volumio-4 mpd[3499]: 2025-12-10T18:12:26 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 10 18:12:26 volumio-4 systemd[1]: Started mpd.service - Music Player Daemon. Dec 10 18:12:26 volumio-4 sudo[3480]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:26 volumio-4 sudo[3545]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 18:12:26 volumio-4 sudo[3545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:26 volumio-4 sudo[3545]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:26 volumio-4 systemd[1]: Started mpd_oled_plugin.service - MPD OLED Plugin. Dec 10 18:12:26 volumio-4 sudo[3504]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:26 volumio-4 sudo[3541]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 10 18:12:26 volumio-4 sudo[3541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:26 volumio-4 sudo[3539]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 18:12:26 volumio-4 volumio[1303]: info: Output device has changed, restarting Shairport Sync Dec 10 18:12:26 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:26 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:12:26 volumio-4 sudo[3539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:26 volumio-4 sudo[3550]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 18:12:26 volumio-4 sudo[3550]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:26 volumio-4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 10 18:12:26 volumio-4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 10 18:12:26 volumio-4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:12:26 volumio-4 systemd[1]: shairport-sync.service: Consumed 1.280s CPU time. Dec 10 18:12:26 volumio-4 volumio[1303]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 18:12:26 volumio-4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:12:26 volumio-4 volumio[1303]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 10 18:12:26 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:26 volumio-4 sudo[3539]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:27 volumio-4 systemd[1]: Stopping mpd_oled_plugin.service - MPD OLED Plugin... Dec 10 18:12:27 volumio-4 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 10 18:12:27 volumio-4 systemd[1]: mpd_oled_plugin.service: Deactivated successfully. Dec 10 18:12:27 volumio-4 systemd[1]: Stopped mpd_oled_plugin.service - MPD OLED Plugin. Dec 10 18:12:27 volumio-4 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 10 18:12:27 volumio-4 sudo[3541]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:27 volumio-4 volumio[1303]: info: QobuzConnect: setDeactiveState invoked Dec 10 18:12:27 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:27 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:27 volumio-4 systemd[1]: mpd.service: Deactivated successfully. Dec 10 18:12:27 volumio-4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 10 18:12:27 volumio-4 systemd[1]: mpd.service: Consumed 3.151s CPU time. Dec 10 18:12:27 volumio-4 systemd[1]: mpd.socket: Deactivated successfully. Dec 10 18:12:27 volumio-4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 10 18:12:27 volumio-4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 10 18:12:27 volumio-4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 10 18:12:27 volumio-4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 10 18:12:27 volumio-4 sudo[3595]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:12:27 volumio-4 sudo[3595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:27 volumio-4 sudo[3594]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 10 18:12:27 volumio-4 sudo[3594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 10 18:12:27 volumio-4 sudo[3594]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:27 volumio-4 qobuz-connect[3477]: 20251210 18:12:27.754 [3477.3477] INFO SampleApp: shat down connection on UNIX socket Dec 10 18:12:27 volumio-4 systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV Dec 10 18:12:27 volumio-4 systemd[1]: qobuz-connect.service: Failed with result 'signal'. Dec 10 18:12:27 volumio-4 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 10 18:12:27 volumio-4 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 10 18:12:27 volumio-4 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 10 18:12:27 volumio-4 systemd[1]: vtcs.service: Deactivated successfully. Dec 10 18:12:27 volumio-4 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 10 18:12:27 volumio-4 sudo[3595]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:28 volumio-4 sudo[3598]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:12:28 volumio-4 sudo[3522]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:28 volumio-4 sudo[3598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:28 volumio-4 sudo[3598]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:28 volumio-4 sudo[3611]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 10 18:12:28 volumio-4 sudo[3611]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:28 volumio-4 volumio[1303]: error: MPD error: Error: write EPIPE Dec 10 18:12:28 volumio-4 volumio[1303]: error: write EPIPE {"code":"EPIPE","errno":-32,"syscall":"write"} Dec 10 18:12:28 volumio-4 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 10 18:12:28 volumio-4 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 10 18:12:28 volumio-4 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 10 18:12:28 volumio-4 volumio[1303]: info: MPD Permissions set Dec 10 18:12:28 volumio-4 volumio[1303]: info: TidalConnect service stoped! Dec 10 18:12:28 volumio-4 volumio[1303]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 10 18:12:28 volumio-4 volumio[1303]: info: QobuzConnect: setDeactiveState invoked Dec 10 18:12:28 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:28 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:28 volumio-4 volumio[1303]: info: Shairport-Sync Started Dec 10 18:12:28 volumio-4 volumio[1303]: info: TidalConnect service stoped! Dec 10 18:12:28 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Started mpd_oled_plugin service Dec 10 18:12:28 volumio-4 sudo[3609]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 10 18:12:28 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:28 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:28 volumio-4 volumio[1303]: info: Executing endpoint tc_getconfig Dec 10 18:12:28 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 10 18:12:28 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:28 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:28 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:28 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:28 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:28 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:28 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:28 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:28 volumio-4 sudo[3609]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:29 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:12:29 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:29 volumio-4 volumio[1303]: info: Executing endpoint qc_getconfig Dec 10 18:12:29 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 10 18:12:29 volumio-4 sudo[3609]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:29 volumio-4 qobuz-connect[3603]: 20251210 18:12:29.380 [3603.3603] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 10 18:12:29 volumio-4 qobuz-connect[3603]: 20251210 18:12:29.385 [3603.3603] INFO VolumeManager: [0x1b89268]: Setting new playback volume: 75 Dec 10 18:12:29 volumio-4 qobuz-connect[3603]: 20251210 18:12:29.387 [3603.3603] INFO VolumeManager: [0x1b89268]: Setting new mute state: 0 Dec 10 18:12:29 volumio-4 qobuz-connect[3603]: 20251210 18:12:29.388 [3603.3603] INFO QobuzConnect: [0x1b89c38]: Client initialized! Dec 10 18:12:29 volumio-4 qobuz-connect[3603]: 20251210 18:12:29.388 [3603.3603] INFO SampleApp: Starting Avahi advertising, name: Volumio-4, service name: _qobuz-connect._tcp Dec 10 18:12:29 volumio-4 qobuz-connect[3603]: 20251210 18:12:29.413 [3603.3603] INFO LocalConfigManager: [0x1b88b48]: Starting Local Configuration server Dec 10 18:12:29 volumio-4 qobuz-connect[3603]: 20251210 18:12:29.414 [3603.3603] INFO SampleApp: Starting Local configuration server Dec 10 18:12:29 volumio-4 qobuz-connect[3603]: 20251210 18:12:29.415 [3603.3603] INFO SampleApp: Connected to UNIX socket client 0x1b73818 Dec 10 18:12:29 volumio-4 volumio[1303]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 10 18:12:29 volumio-4 volumio[1303]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 10 18:12:29 volumio-4 volumio[1303]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 10 18:12:29 volumio-4 volumio[1303]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 10 18:12:29 volumio-4 volumio[1303]: info: Starting Shairport Sync Dec 10 18:12:29 volumio-4 qobuz-connect[3603]: 20251210 18:12:29.531 [3603.3603] INFO SampleApp: Playback volume changed: 75 Dec 10 18:12:29 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:29 volumio-4 sudo[3630]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 10 18:12:29 volumio-4 sudo[3630]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:29 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:29 volumio-4 sudo[3635]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 18:12:29 volumio-4 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 10 18:12:29 volumio-4 qobuz-connect[3603]: 20251210 18:12:29.932 [3603.3603] INFO SampleApp: Stopping Local configuration server Dec 10 18:12:29 volumio-4 sudo[3635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:30 volumio-4 sudo[3638]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 10 18:12:30 volumio-4 sudo[3638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:30 volumio-4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 10 18:12:30 volumio-4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 10 18:12:30 volumio-4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:12:30 volumio-4 systemd[1]: shairport-sync.service: Consumed 1.262s CPU time. Dec 10 18:12:30 volumio-4 volumio[1303]: info: TidalConnect service started! Dec 10 18:12:30 volumio-4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:12:30 volumio-4 sudo[3635]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:30 volumio-4 volumio[1303]: info: Shairport-Sync Started Dec 10 18:12:30 volumio-4 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 10 18:12:30 volumio-4 sudo[3638]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:30 volumio-4 volumio[1303]: info: Executing endpoint tc_getconfig Dec 10 18:12:30 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 10 18:12:31 volumio-4 vtcs[3656]: STARTING TidalConnect services, version: 1.5.2.56 Dec 10 18:12:31 volumio-4 vtcs[3656]: STARTED TidalConnect services. Dec 10 18:12:31 volumio-4 mpd[3600]: 2025-12-10T18:12:31 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 10 18:12:31 volumio-4 volumio[1303]: info: Executing endpoint tc_connect Dec 10 18:12:31 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 10 18:12:31 volumio-4 volumio[1303]: info: Connecting to TidalConnect Dec 10 18:12:31 volumio-4 systemd[1]: Started mpd.service - Music Player Daemon. Dec 10 18:12:31 volumio-4 sudo[3550]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:31 volumio-4 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 10 18:12:31 volumio-4 systemd[1]: Started mpd_oled_plugin.service - MPD OLED Plugin. Dec 10 18:12:31 volumio-4 mpd_monitor.sh[3667]: MPD Monitor Service: Starting MPD Monitor Service Dec 10 18:12:31 volumio-4 sudo[3611]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:31 volumio-4 volumio[1303]: error: updateQueue error: null Dec 10 18:12:31 volumio-4 volumio[1303]: info: CoreCommandRouter::servicePushState Dec 10 18:12:31 volumio-4 volumio[1303]: info: CoreStateMachine::pushState Dec 10 18:12:31 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:31 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 18:12:31 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioPushState Dec 10 18:12:31 volumio-4 qobuz-connect[3603]: 20251210 18:12:31.424 [3603.3603] INFO SampleApp: shat down connection on UNIX socket Dec 10 18:12:31 volumio-4 systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 10 18:12:31 volumio-4 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 10 18:12:31 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output update for this device Dec 10 18:12:31 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output Dec 10 18:12:31 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:31 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:31 volumio-4 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 10 18:12:31 volumio-4 sudo[3630]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:31 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:31 volumio-4 volumio[1303]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 10 18:12:31 volumio-4 volumio[1303]: info: CoreCommandRouter::servicePushState Dec 10 18:12:31 volumio-4 volumio[1303]: info: CoreStateMachine::pushState Dec 10 18:12:31 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:31 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioPushState Dec 10 18:12:31 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output update for this device Dec 10 18:12:31 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output Dec 10 18:12:31 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:31 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:31 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:31 volumio-4 volumio[1303]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 10 18:12:32 volumio-4 volumio[1303]: info: TidalConnect service stoped! Dec 10 18:12:32 volumio-4 volumio[1303]: info: Successfully started MPD Monitor Dec 10 18:12:32 volumio-4 volumio[1303]: info: TidalConnect service stoped! Dec 10 18:12:32 volumio-4 sudo[3683]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 10 18:12:32 volumio-4 sudo[3683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:32 volumio-4 volumio[1303]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 10 18:12:32 volumio-4 volumio[1303]: info: QobuzConnect: setDeactiveState invoked Dec 10 18:12:32 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:32 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:32 volumio-4 volumio[1303]: error: updateQueue error: null Dec 10 18:12:32 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:32 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:32 volumio-4 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 10 18:12:32 volumio-4 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 10 18:12:32 volumio-4 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 10 18:12:32 volumio-4 volumio[1303]: info: Executing endpoint qc_getconfig Dec 10 18:12:32 volumio-4 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 10 18:12:32 volumio-4 mpd_monitor.sh[3689]: MPD Monitor Service: Starting MPD Monitor Service Dec 10 18:12:32 volumio-4 sudo[3683]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:32 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 10 18:12:32 volumio-4 qobuz-connect[3680]: 20251210 18:12:32.811 [3680.3680] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 10 18:12:32 volumio-4 qobuz-connect[3680]: 20251210 18:12:32.816 [3680.3680] INFO VolumeManager: [0x1499268]: Setting new playback volume: 75 Dec 10 18:12:32 volumio-4 qobuz-connect[3680]: 20251210 18:12:32.816 [3680.3680] INFO VolumeManager: [0x1499268]: Setting new mute state: 0 Dec 10 18:12:32 volumio-4 qobuz-connect[3680]: 20251210 18:12:32.816 [3680.3680] INFO QobuzConnect: [0x1499c38]: Client initialized! Dec 10 18:12:32 volumio-4 qobuz-connect[3680]: 20251210 18:12:32.817 [3680.3680] INFO SampleApp: Starting Avahi advertising, name: Volumio-4, service name: _qobuz-connect._tcp Dec 10 18:12:32 volumio-4 qobuz-connect[3680]: 20251210 18:12:32.837 [3680.3680] INFO LocalConfigManager: [0x1498b48]: Starting Local Configuration server Dec 10 18:12:32 volumio-4 qobuz-connect[3680]: 20251210 18:12:32.837 [3680.3680] INFO SampleApp: Starting Local configuration server Dec 10 18:12:32 volumio-4 qobuz-connect[3680]: 20251210 18:12:32.838 [3680.3680] INFO SampleApp: Connected to UNIX socket client 0x1483818 Dec 10 18:12:32 volumio-4 volumio[1303]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 10 18:12:32 volumio-4 volumio[1303]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 10 18:12:32 volumio-4 volumio[1303]: info: Successfully started MPD Monitor Dec 10 18:12:32 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:32 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:32 volumio-4 qobuz-connect[3680]: 20251210 18:12:32.961 [3680.3680] INFO SampleApp: Playback volume changed: 75 Dec 10 18:12:32 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:32 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:32 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:33 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:33 volumio-4 volumio[1303]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 10 18:12:33 volumio-4 volumio[1303]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 10 18:12:33 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:33 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:33 volumio-4 sudo[3704]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 10 18:12:33 volumio-4 sudo[3704]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:33 volumio-4 sudo[3704]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:33 volumio-4 volumio[1303]: info: TidalConnect service started! Dec 10 18:12:33 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:33 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:33 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:33 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:34 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:34 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:34 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:34 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:34 volumio-4 sudo[3726]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 10 18:12:34 volumio-4 sudo[3726]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:34 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:34 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:34 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:34 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:34 volumio-4 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 10 18:12:34 volumio-4 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 10 18:12:34 volumio-4 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 10 18:12:34 volumio-4 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 10 18:12:34 volumio-4 mpd_monitor.sh[3733]: MPD Monitor Service: Starting MPD Monitor Service Dec 10 18:12:34 volumio-4 sudo[3726]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:34 volumio-4 volumio[1303]: info: Successfully started MPD Monitor Dec 10 18:12:35 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:35 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:35 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:35 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:35 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:35 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:35 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:35 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:36 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:36 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:36 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:36 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:36 volumio-4 volumio[1303]: info: TidalConnect service started! Dec 10 18:12:36 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:36 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:36 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:36 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:37 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:37 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:37 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:37 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:37 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:37 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:37 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:37 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:38 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:38 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:38 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:38 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:38 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:38 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:38 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:38 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:39 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:39 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:39 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:39 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:39 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioNext Dec 10 18:12:39 volumio-4 volumio[1303]: info: CoreStateMachine::next Dec 10 18:12:39 volumio-4 volumio[1303]: info: CoreStateMachine::stop Dec 10 18:12:39 volumio-4 volumio[1303]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 18:12:39 volumio-4 volumio[1303]: info: CoreStateMachine::stPlaybackTimer Dec 10 18:12:39 volumio-4 volumio[1303]: info: CoreStateMachine::updateTrackBlock Dec 10 18:12:39 volumio-4 volumio[1303]: info: CorePlayQueue::getTrackBlock Dec 10 18:12:39 volumio-4 volumio[1303]: info: CoreStateMachine::pushState Dec 10 18:12:39 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:39 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 18:12:39 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioPushState Dec 10 18:12:39 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output update for this device Dec 10 18:12:39 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output Dec 10 18:12:39 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:39 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:39 volumio-4 volumio[1303]: info: CoreStateMachine::serviceStop Dec 10 18:12:39 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 10 Dec 10 18:12:39 volumio-4 volumio[1303]: info: CoreCommandRouter::serviceStop Dec 10 18:12:39 volumio-4 volumio[1303]: info: ControllerMpd::stop Dec 10 18:12:39 volumio-4 volumio[1303]: verbose: ControllerMpd::sendMpdCommand stop Dec 10 18:12:39 volumio-4 volumio[1303]: info: sendMpdCommand stop took 222 milliseconds Dec 10 18:12:39 volumio-4 volumio[1303]: info: CoreStateMachine::play index undefined Dec 10 18:12:39 volumio-4 volumio[1303]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 18:12:40 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 11 Dec 10 18:12:40 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:40 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:40 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:40 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:40 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:40 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:40 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:40 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:40 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:40 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:40 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:40 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:41 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:41 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:41 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:41 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:41 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioPlay Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreStateMachine::play index undefined Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 18:12:41 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreStateMachine::startPlaybackTimer Dec 10 18:12:41 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:41 volumio-4 volumio[1303]: verbose: ControllerMpd::clearAddPlayTracks USB/ESD-USB/Henrik Freischlader - 2006 - The Blues/01. The Blues.mp3 Dec 10 18:12:41 volumio-4 volumio[1303]: verbose: ControllerMpd::sendMpdCommand stop Dec 10 18:12:41 volumio-4 volumio[1303]: info: sendMpdCommand stop took 1 milliseconds Dec 10 18:12:41 volumio-4 volumio[1303]: verbose: ControllerMpd::sendMpdCommand clear Dec 10 18:12:41 volumio-4 volumio[1303]: info: Dec 10 18:12:41 volumio-4 volumio[1303]: ---------------------------- MPD announces system playlist update Dec 10 18:12:41 volumio-4 volumio[1303]: info: Ignoring MPD Status Update Dec 10 18:12:41 volumio-4 volumio[1303]: info: sendMpdCommand clear took 2 milliseconds Dec 10 18:12:41 volumio-4 volumio[1303]: verbose: ControllerMpd::sendMpdCommand add "USB/ESD-USB/Henrik Freischlader - 2006 - The Blues/01. The Blues.mp3" Dec 10 18:12:41 volumio-4 volumio[1303]: info: Dec 10 18:12:41 volumio-4 volumio[1303]: ---------------------------- MPD announces system playlist update Dec 10 18:12:41 volumio-4 volumio[1303]: info: Ignoring MPD Status Update Dec 10 18:12:41 volumio-4 volumio[1303]: error: updateQueue error: null Dec 10 18:12:41 volumio-4 volumio[1303]: info: Dec 10 18:12:41 volumio-4 volumio[1303]: ---------------------------- MPD announces system playlist update Dec 10 18:12:41 volumio-4 volumio[1303]: info: Ignoring MPD Status Update Dec 10 18:12:41 volumio-4 volumio[1303]: info: ------------------------------ 6ms Dec 10 18:12:41 volumio-4 volumio[1303]: info: sendMpdCommand add "USB/ESD-USB/Henrik Freischlader - 2006 - The Blues/01. The Blues.mp3" took 5 milliseconds Dec 10 18:12:41 volumio-4 volumio[1303]: info: ------------------------------ 4ms Dec 10 18:12:41 volumio-4 volumio[1303]: verbose: ControllerMpd::sendMpdCommand play Dec 10 18:12:41 volumio-4 volumio[1303]: info: Dec 10 18:12:41 volumio-4 volumio[1303]: ---------------------------- MPD announces system playlist update Dec 10 18:12:41 volumio-4 volumio[1303]: info: Ignoring MPD Status Update Dec 10 18:12:41 volumio-4 volumio[1303]: info: ------------------------------ 6ms Dec 10 18:12:41 volumio-4 volumio[1303]: info: sendMpdCommand play took 4 milliseconds Dec 10 18:12:41 volumio-4 volumio[1303]: info: ------------------------------ 5ms Dec 10 18:12:41 volumio-4 volumio[1303]: info: Dec 10 18:12:41 volumio-4 volumio[1303]: ---------------------------- MPD announces state update: player Dec 10 18:12:41 volumio-4 volumio[1303]: info: ControllerMpd::getState Dec 10 18:12:41 volumio-4 volumio[1303]: verbose: ControllerMpd::sendMpdCommand status Dec 10 18:12:41 volumio-4 volumio[1303]: info: Dec 10 18:12:41 volumio-4 volumio[1303]: ---------------------------- MPD announces state update: player Dec 10 18:12:41 volumio-4 volumio[1303]: info: ControllerMpd::getState Dec 10 18:12:41 volumio-4 volumio[1303]: verbose: ControllerMpd::sendMpdCommand status Dec 10 18:12:41 volumio-4 volumio[1303]: info: sendMpdCommand status took 27 milliseconds Dec 10 18:12:41 volumio-4 volumio[1303]: verbose: ControllerMpd::parseState Dec 10 18:12:41 volumio-4 volumio[1303]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 18:12:41 volumio-4 volumio[1303]: info: Dec 10 18:12:41 volumio-4 volumio[1303]: ---------------------------- MPD announces state update: player Dec 10 18:12:41 volumio-4 volumio[1303]: info: ControllerMpd::getState Dec 10 18:12:41 volumio-4 volumio[1303]: verbose: ControllerMpd::sendMpdCommand status Dec 10 18:12:41 volumio-4 volumio[1303]: info: Dec 10 18:12:41 volumio-4 volumio[1303]: ---------------------------- MPD announces state update: player Dec 10 18:12:41 volumio-4 volumio[1303]: info: ControllerMpd::getState Dec 10 18:12:41 volumio-4 volumio[1303]: verbose: ControllerMpd::sendMpdCommand status Dec 10 18:12:41 volumio-4 volumio[1303]: info: sendMpdCommand status took 30 milliseconds Dec 10 18:12:41 volumio-4 volumio[1303]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 10 18:12:41 volumio-4 volumio[1303]: info: sendMpdCommand status took 3 milliseconds Dec 10 18:12:41 volumio-4 volumio[1303]: verbose: ControllerMpd::parseState Dec 10 18:12:41 volumio-4 volumio[1303]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 18:12:41 volumio-4 volumio[1303]: verbose: ControllerMpd::parseTrackInfo Dec 10 18:12:41 volumio-4 volumio[1303]: verbose: ControllerMpd::parseState Dec 10 18:12:41 volumio-4 volumio[1303]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 18:12:41 volumio-4 volumio[1303]: info: ControllerMpd::pushState Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreCommandRouter::servicePushState Dec 10 18:12:41 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:41 volumio-4 volumio[1303]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"The Blues","artist":"Henrik Freischlader Band","album":"The Blues","uri":"USB/ESD-USB/Henrik Freischlader - 2006 - The Blues/01. The Blues.mp3","trackType":"mp3"} Dec 10 18:12:41 volumio-4 volumio[1303]: verbose: CURRENT POSITION 0 Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreStateMachine::syncState stateService play Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreStateMachine::syncState currentStatus stop Dec 10 18:12:41 volumio-4 volumio[1303]: info: ------------------------------ 38ms Dec 10 18:12:41 volumio-4 volumio[1303]: info: sendMpdCommand status took 7 milliseconds Dec 10 18:12:41 volumio-4 volumio[1303]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 10 18:12:41 volumio-4 volumio[1303]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 10 18:12:41 volumio-4 volumio[1303]: verbose: ControllerMpd::parseState Dec 10 18:12:41 volumio-4 volumio[1303]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 18:12:41 volumio-4 volumio[1303]: verbose: ControllerMpd::parseTrackInfo Dec 10 18:12:41 volumio-4 volumio[1303]: verbose: ControllerMpd::parseTrackInfo Dec 10 18:12:41 volumio-4 volumio[1303]: info: ControllerMpd::pushState Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreCommandRouter::servicePushState Dec 10 18:12:41 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:41 volumio-4 volumio[1303]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1100,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"The Blues","artist":"Henrik Freischlader Band","album":"The Blues","uri":"USB/ESD-USB/Henrik Freischlader - 2006 - The Blues/01. The Blues.mp3","trackType":"mp3"} Dec 10 18:12:41 volumio-4 volumio[1303]: verbose: CURRENT POSITION 0 Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreStateMachine::syncState stateService play Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreStateMachine::syncState currentStatus play Dec 10 18:12:41 volumio-4 volumio[1303]: info: Received an update from plugin. extracting info from payload Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreStateMachine::pushState Dec 10 18:12:41 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioPushState Dec 10 18:12:41 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output update for this device Dec 10 18:12:41 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:41 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreStateMachine::pushState Dec 10 18:12:41 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioPushState Dec 10 18:12:41 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output update for this device Dec 10 18:12:41 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:41 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:41 volumio-4 volumio[1303]: info: ControllerMpd::pushState Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreCommandRouter::servicePushState Dec 10 18:12:41 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:41 volumio-4 volumio[1303]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1100,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"The Blues","artist":"Henrik Freischlader Band","album":"The Blues","uri":"USB/ESD-USB/Henrik Freischlader - 2006 - The Blues/01. The Blues.mp3","trackType":"mp3"} Dec 10 18:12:41 volumio-4 volumio[1303]: verbose: CURRENT POSITION 0 Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreStateMachine::syncState stateService play Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreStateMachine::syncState currentStatus play Dec 10 18:12:41 volumio-4 volumio[1303]: info: Received an update from plugin. extracting info from payload Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreStateMachine::pushState Dec 10 18:12:41 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioPushState Dec 10 18:12:41 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output update for this device Dec 10 18:12:41 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:41 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreStateMachine::pushState Dec 10 18:12:41 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioPushState Dec 10 18:12:41 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output update for this device Dec 10 18:12:41 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output Dec 10 18:12:41 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:41 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:42 volumio-4 volumio[1303]: info: ------------------------------ 133ms Dec 10 18:12:42 volumio-4 volumio[1303]: info: ------------------------------ 106ms Dec 10 18:12:42 volumio-4 volumio[1303]: info: sendMpdCommand playlistinfo took 97 milliseconds Dec 10 18:12:42 volumio-4 volumio[1303]: verbose: ControllerMpd::parseTrackInfo Dec 10 18:12:42 volumio-4 volumio[1303]: info: ControllerMpd::pushState Dec 10 18:12:42 volumio-4 volumio[1303]: info: CoreCommandRouter::servicePushState Dec 10 18:12:42 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:42 volumio-4 volumio[1303]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1100,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"The Blues","artist":"Henrik Freischlader Band","album":"The Blues","uri":"USB/ESD-USB/Henrik Freischlader - 2006 - The Blues/01. The Blues.mp3","trackType":"mp3"} Dec 10 18:12:42 volumio-4 volumio[1303]: verbose: CURRENT POSITION 0 Dec 10 18:12:42 volumio-4 volumio[1303]: info: CoreStateMachine::syncState stateService play Dec 10 18:12:42 volumio-4 volumio[1303]: info: CoreStateMachine::syncState currentStatus play Dec 10 18:12:42 volumio-4 volumio[1303]: info: Received an update from plugin. extracting info from payload Dec 10 18:12:42 volumio-4 volumio[1303]: info: CoreStateMachine::pushState Dec 10 18:12:42 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:42 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioPushState Dec 10 18:12:42 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output update for this device Dec 10 18:12:42 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output Dec 10 18:12:42 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:42 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:42 volumio-4 volumio[1303]: info: CoreStateMachine::pushState Dec 10 18:12:42 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:42 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioPushState Dec 10 18:12:42 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output update for this device Dec 10 18:12:42 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output Dec 10 18:12:42 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:42 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:42 volumio-4 volumio[1303]: info: ------------------------------ 162ms Dec 10 18:12:42 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:42 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:42 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:42 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:42 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:42 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:42 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:42 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:43 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:43 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:43 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:43 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:43 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:43 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:43 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:43 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:43 volumio-4 go-librespot[1647]: time="2025-12-10T18:12:43+01:00" level=trace msg="sent dealer ping" Dec 10 18:12:43 volumio-4 go-librespot[1647]: time="2025-12-10T18:12:43+01:00" level=trace msg="received dealer pong" Dec 10 18:12:43 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:43 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:43 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:43 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:44 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:44 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:44 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:44 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:44 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:44 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:44 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:44 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:44 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:44 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:44 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:44 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:45 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:45 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:45 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:45 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:45 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:45 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:45 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:45 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:45 volumio-4 kernel: hwmon hwmon1: Voltage normalised Dec 10 18:12:45 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:45 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:45 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:45 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:46 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:46 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:46 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:46 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:46 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:46 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:46 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:46 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:47 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:47 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:47 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:47 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:47 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:47 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:47 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:47 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:47 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:47 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:47 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:47 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:47 volumio-4 kernel: hwmon hwmon1: Undervoltage detected! Dec 10 18:12:48 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:48 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:48 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:48 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:48 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:48 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:48 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:48 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:49 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:49 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:49 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:49 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:49 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:49 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:49 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:49 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:49 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:49 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:49 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:49 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:49 volumio-4 volumio[1303]: info: Disabling plugin mpd_oled Dec 10 18:12:49 volumio-4 volumio[1303]: info: Preparing to generate the ALSA configuration file Dec 10 18:12:49 volumio-4 sudo[3986]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd_oled_plugin.service Dec 10 18:12:49 volumio-4 sudo[3986]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:49 volumio-4 volumio[1303]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 10 18:12:49 volumio-4 volumio[1303]: info: Reading ALSA contributions from plugins. Dec 10 18:12:49 volumio-4 volumio[1303]: info: Asound.conf file written Dec 10 18:12:49 volumio-4 systemd[1]: Stopping mpd_oled_plugin.service - MPD OLED Plugin... Dec 10 18:12:49 volumio-4 systemd[1]: mpd_oled_plugin.service: Deactivated successfully. Dec 10 18:12:49 volumio-4 systemd[1]: Stopped mpd_oled_plugin.service - MPD OLED Plugin. Dec 10 18:12:49 volumio-4 sudo[3993]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 10 18:12:49 volumio-4 sudo[3993]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:49 volumio-4 sudo[3986]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:49 volumio-4 sudo[3993]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:50 volumio-4 volumio[1303]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 10 18:12:50 volumio-4 volumio[1303]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 10 18:12:50 volumio-4 volumio[1303]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 10 18:12:50 volumio-4 volumio[1303]: info: Output device has changed, restarting MPD Dec 10 18:12:50 volumio-4 volumio[1303]: info: Output device has changed, restarting Shairport Sync Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:12:50 volumio-4 sudo[3999]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 18:12:50 volumio-4 sudo[3999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:50 volumio-4 sudo[3999]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:50 volumio-4 sudo[4002]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 18:12:50 volumio-4 sudo[4002]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:50 volumio-4 volumio[1303]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 18:12:50 volumio-4 volumio[1303]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:50 volumio-4 volumio[1303]: info: QobuzConnect: setDeactiveState invoked Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:50 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:50 volumio-4 vtcs[3656]: [2025-12-10 18:12:50.106] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 10 18:12:50 volumio-4 vtcs[3656]: [2025-12-10 18:12:50.107] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 10 18:12:50 volumio-4 volumio[1303]: info: Done. Dec 10 18:12:50 volumio-4 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 10 18:12:50 volumio-4 sudo[4012]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:12:50 volumio-4 sudo[4012]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:50 volumio-4 sudo[4014]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:12:50 volumio-4 sudo[4014]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:50 volumio-4 volumio[1303]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Dec 10 18:12:50 volumio-4 volumio[1303]: info: MPD Permissions set Dec 10 18:12:50 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Stopped mpd_oled_plugin service Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:50 volumio-4 systemd[1]: mpd.service: Deactivated successfully. Dec 10 18:12:50 volumio-4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 10 18:12:50 volumio-4 systemd[1]: mpd.service: Consumed 4.008s CPU time. Dec 10 18:12:50 volumio-4 sudo[4022]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 10 18:12:50 volumio-4 sudo[4022]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:50 volumio-4 systemd[1]: mpd.socket: Deactivated successfully. Dec 10 18:12:50 volumio-4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 10 18:12:50 volumio-4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:50 volumio-4 volumio[1303]: info: Starting Shairport Sync Dec 10 18:12:50 volumio-4 volumio[1303]: info: Preparing to generate the ALSA configuration file Dec 10 18:12:50 volumio-4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 10 18:12:50 volumio-4 sudo[4031]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 18:12:50 volumio-4 volumio[1303]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 10 18:12:50 volumio-4 sudo[4031]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:50 volumio-4 volumio[1303]: info: Reading ALSA contributions from plugins. Dec 10 18:12:50 volumio-4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 10 18:12:50 volumio-4 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 10 18:12:50 volumio-4 volumio[1303]: info: Asound.conf file unchanged, so no further update is needed Dec 10 18:12:50 volumio-4 volumio[1303]: info: Output device has changed, restarting MPD Dec 10 18:12:50 volumio-4 systemd[1]: vtcs.service: Deactivated successfully. Dec 10 18:12:50 volumio-4 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 10 18:12:50 volumio-4 volumio[1303]: info: Output device has changed, restarting Shairport Sync Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:12:50 volumio-4 sudo[4035]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 18:12:50 volumio-4 sudo[4035]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:50 volumio-4 sudo[4022]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:50 volumio-4 volumio[1303]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 18:12:50 volumio-4 volumio[1303]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:50 volumio-4 sudo[4012]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:50 volumio-4 sudo[4035]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:50 volumio-4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 10 18:12:50 volumio-4 sudo[4037]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 18:12:50 volumio-4 sudo[4037]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:50 volumio-4 volumio[1303]: info: QobuzConnect: setDeactiveState invoked Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:50 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:50 volumio-4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 10 18:12:50 volumio-4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:12:50 volumio-4 systemd[1]: shairport-sync.service: Consumed 1.258s CPU time. Dec 10 18:12:50 volumio-4 sudo[4014]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:50 volumio-4 sudo[4045]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 10 18:12:50 volumio-4 sudo[4045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:50 volumio-4 sudo[4050]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:12:50 volumio-4 sudo[4050]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:50 volumio-4 volumio[1303]: info: MPD Permissions set Dec 10 18:12:50 volumio-4 sudo[4055]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:12:50 volumio-4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:12:50 volumio-4 sudo[4031]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:50 volumio-4 sudo[4055]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:50 volumio-4 sudo[4032]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 10 18:12:50 volumio-4 sudo[4032]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 10 18:12:50 volumio-4 volumio[1303]: info: Shairport-Sync Started Dec 10 18:12:50 volumio-4 sudo[4032]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:50 volumio-4 sudo[4060]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 10 18:12:50 volumio-4 sudo[4060]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:12:50 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:50 volumio-4 volumio[1303]: info: Starting Shairport Sync Dec 10 18:12:50 volumio-4 sudo[4060]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:50 volumio-4 volumio[1303]: info: Preparing to generate the ALSA configuration file Dec 10 18:12:50 volumio-4 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 10 18:12:50 volumio-4 qobuz-connect[3680]: 20251210 18:12:50.944 [3680.3680] INFO SampleApp: Stopping Local configuration server Dec 10 18:12:50 volumio-4 systemd[1]: mpd.service: Deactivated successfully. Dec 10 18:12:50 volumio-4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 10 18:12:50 volumio-4 sudo[4087]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 10 18:12:50 volumio-4 sudo[4087]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:50 volumio-4 sudo[4086]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 18:12:50 volumio-4 sudo[4086]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:51 volumio-4 volumio[1303]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 10 18:12:51 volumio-4 volumio[1303]: info: Reading ALSA contributions from plugins. Dec 10 18:12:51 volumio-4 sudo[4055]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:51 volumio-4 volumio[1303]: info: Asound.conf file unchanged, so no further update is needed Dec 10 18:12:51 volumio-4 volumio[1303]: info: Output device has changed, restarting MPD Dec 10 18:12:51 volumio-4 sudo[4050]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:51 volumio-4 volumio[1303]: info: Output device has changed, restarting Shairport Sync Dec 10 18:12:51 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:51 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:12:51 volumio-4 systemd[1]: mpd.socket: Deactivated successfully. Dec 10 18:12:51 volumio-4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 10 18:12:51 volumio-4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 10 18:12:51 volumio-4 sudo[4093]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 18:12:51 volumio-4 sudo[4093]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:51 volumio-4 sudo[4093]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:51 volumio-4 sudo[4097]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 18:12:51 volumio-4 sudo[4097]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:51 volumio-4 volumio[1303]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 18:12:51 volumio-4 volumio[1303]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 10 18:12:51 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:51 volumio-4 volumio[1303]: info: QobuzConnect: setDeactiveState invoked Dec 10 18:12:51 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:51 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:51 volumio-4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 10 18:12:51 volumio-4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 10 18:12:51 volumio-4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 10 18:12:51 volumio-4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 10 18:12:51 volumio-4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:12:51 volumio-4 sudo[4107]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:12:51 volumio-4 sudo[4107]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:51 volumio-4 sudo[4109]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:12:51 volumio-4 sudo[4109]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:51 volumio-4 volumio[1303]: info: MPD Permissions set Dec 10 18:12:51 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:51 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:51 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:51 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:51 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:51 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:51 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:51 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:51 volumio-4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:12:51 volumio-4 sudo[4086]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:51 volumio-4 systemd[1]: mpd.service: Deactivated successfully. Dec 10 18:12:51 volumio-4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 10 18:12:51 volumio-4 sudo[4118]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 10 18:12:51 volumio-4 sudo[4118]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:51 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:12:51 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:51 volumio-4 volumio[1303]: info: Shairport-Sync Started Dec 10 18:12:51 volumio-4 volumio[1303]: info: Starting Shairport Sync Dec 10 18:12:51 volumio-4 sudo[4118]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:51 volumio-4 sudo[4139]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 18:12:51 volumio-4 sudo[4139]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:51 volumio-4 systemd[1]: mpd.socket: Deactivated successfully. Dec 10 18:12:51 volumio-4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 10 18:12:51 volumio-4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 10 18:12:51 volumio-4 sudo[4142]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 10 18:12:51 volumio-4 sudo[4142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:51 volumio-4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 10 18:12:51 volumio-4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 10 18:12:51 volumio-4 sudo[4109]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:51 volumio-4 sudo[4107]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:51 volumio-4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 10 18:12:51 volumio-4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 10 18:12:51 volumio-4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:12:51 volumio-4 sudo[4146]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 10 18:12:51 volumio-4 sudo[4146]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 10 18:12:51 volumio-4 sudo[4146]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:51 volumio-4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:12:51 volumio-4 sudo[4139]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:51 volumio-4 volumio[1303]: info: Shairport-Sync Started Dec 10 18:12:52 volumio-4 qobuz-connect[3680]: 20251210 18:12:52.861 [3680.3680] INFO SampleApp: shat down connection on UNIX socket Dec 10 18:12:52 volumio-4 volumio[1303]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 10 18:12:52 volumio-4 volumio[1303]: info: QobuzConnect: setDeactiveState invoked Dec 10 18:12:52 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:52 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:52 volumio-4 systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 10 18:12:52 volumio-4 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 10 18:12:52 volumio-4 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 10 18:12:52 volumio-4 sudo[4045]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:52 volumio-4 sudo[4087]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:52 volumio-4 sudo[4142]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:53 volumio-4 volumio[1303]: info: Executing endpoint qc_getconfig Dec 10 18:12:53 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 10 18:12:53 volumio-4 qobuz-connect[4166]: 20251210 18:12:53.107 [4166.4166] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 10 18:12:53 volumio-4 volumio[1303]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 10 18:12:53 volumio-4 volumio[1303]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 10 18:12:53 volumio-4 qobuz-connect[4166]: 20251210 18:12:53.119 [4166.4166] INFO VolumeManager: [0x131e268]: Setting new playback volume: 75 Dec 10 18:12:53 volumio-4 qobuz-connect[4166]: 20251210 18:12:53.120 [4166.4166] INFO VolumeManager: [0x131e268]: Setting new mute state: 0 Dec 10 18:12:53 volumio-4 qobuz-connect[4166]: 20251210 18:12:53.120 [4166.4166] INFO QobuzConnect: [0x131ec38]: Client initialized! Dec 10 18:12:53 volumio-4 qobuz-connect[4166]: 20251210 18:12:53.120 [4166.4166] INFO SampleApp: Starting Avahi advertising, name: Volumio-4, service name: _qobuz-connect._tcp Dec 10 18:12:53 volumio-4 qobuz-connect[4166]: 20251210 18:12:53.151 [4166.4166] INFO LocalConfigManager: [0x131db48]: Starting Local Configuration server Dec 10 18:12:53 volumio-4 qobuz-connect[4166]: 20251210 18:12:53.151 [4166.4166] INFO SampleApp: Starting Local configuration server Dec 10 18:12:53 volumio-4 qobuz-connect[4166]: 20251210 18:12:53.153 [4166.4166] INFO SampleApp: Connected to UNIX socket client 0x1308818 Dec 10 18:12:53 volumio-4 qobuz-connect[4166]: 20251210 18:12:53.259 [4166.4166] INFO SampleApp: Playback volume changed: 75 Dec 10 18:12:53 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:53 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:53 volumio-4 volumio[1303]: info: TidalConnect service stoped! Dec 10 18:12:53 volumio-4 volumio[1303]: info: TidalConnect service stoped! Dec 10 18:12:53 volumio-4 volumio[1303]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 10 18:12:53 volumio-4 volumio[1303]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 10 18:12:53 volumio-4 sudo[4181]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 10 18:12:53 volumio-4 sudo[4181]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:53 volumio-4 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 10 18:12:53 volumio-4 sudo[4181]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:54 volumio-4 volumio[1303]: info: Executing endpoint tc_getconfig Dec 10 18:12:54 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 10 18:12:54 volumio-4 vtcs[4184]: STARTING TidalConnect services, version: 1.5.2.56 Dec 10 18:12:54 volumio-4 vtcs[4184]: STARTED TidalConnect services. Dec 10 18:12:54 volumio-4 volumio[1303]: info: Executing endpoint tc_connect Dec 10 18:12:54 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 10 18:12:54 volumio-4 volumio[1303]: info: Connecting to TidalConnect Dec 10 18:12:54 volumio-4 volumio[1303]: info: TidalConnect service stoped! Dec 10 18:12:54 volumio-4 volumio[1303]: info: CoreCommandRouter::servicePushState Dec 10 18:12:54 volumio-4 volumio[1303]: info: CoreStateMachine::pushState Dec 10 18:12:54 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:54 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 18:12:54 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioPushState Dec 10 18:12:54 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output update for this device Dec 10 18:12:54 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output Dec 10 18:12:54 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:54 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:54 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:54 volumio-4 volumio[1303]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 10 18:12:54 volumio-4 volumio[1303]: info: CoreCommandRouter::servicePushState Dec 10 18:12:54 volumio-4 volumio[1303]: info: CoreStateMachine::pushState Dec 10 18:12:54 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:54 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioPushState Dec 10 18:12:54 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output update for this device Dec 10 18:12:54 volumio-4 volumio[1303]: info: MRS: Pushing multiroomSync output Dec 10 18:12:54 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:54 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:54 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:54 volumio-4 volumio[1303]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 10 18:12:54 volumio-4 volumio[1303]: info: TidalConnect service stoped! Dec 10 18:12:54 volumio-4 mpd[4151]: 2025-12-10T18:12:54 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 10 18:12:54 volumio-4 systemd[1]: Started mpd.service - Music Player Daemon. Dec 10 18:12:54 volumio-4 sudo[4037]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:54 volumio-4 sudo[4097]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:54 volumio-4 volumio[1303]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 10 18:12:54 volumio-4 volumio[1303]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 10 18:12:54 volumio-4 sudo[4002]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:54 volumio-4 volumio[1303]: error: MPD error: The expression evaluated to a falsy value: Dec 10 18:12:54 volumio-4 volumio[1303]: assert.ok(self.idling) Dec 10 18:12:54 volumio-4 volumio[1303]: error: The expression evaluated to a falsy value: Dec 10 18:12:54 volumio-4 volumio[1303]: assert.ok(self.idling) Dec 10 18:12:54 volumio-4 volumio[1303]: error: MPD error: The expression evaluated to a falsy value: Dec 10 18:12:54 volumio-4 volumio[1303]: assert.ok(self.idling) Dec 10 18:12:54 volumio-4 volumio[1303]: error: The expression evaluated to a falsy value: Dec 10 18:12:54 volumio-4 volumio[1303]: assert.ok(self.idling) Dec 10 18:12:54 volumio-4 volumio[1303]: error: updateQueue error: null Dec 10 18:12:54 volumio-4 sudo[4211]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 10 18:12:54 volumio-4 sudo[4211]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:54 volumio-4 sudo[4211]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:54 volumio-4 volumio[1303]: info: Enabling plugin mpd_oled Dec 10 18:12:54 volumio-4 volumio[1303]: info: Loading plugin "mpd_oled"... Dec 10 18:12:54 volumio-4 volumio[1303]: info: Applying required configuration parameters for plugin mpd_oled Dec 10 18:12:54 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Dec 10 18:12:54 volumio-4 volumio[1303]: info: Preparing to generate the ALSA configuration file Dec 10 18:12:54 volumio-4 volumio[1303]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 10 18:12:54 volumio-4 volumio[1303]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Dec 10 18:12:54 volumio-4 volumio[1303]: info: Reading ALSA contributions from plugins. Dec 10 18:12:54 volumio-4 volumio[1303]: info: Asound.conf file written Dec 10 18:12:54 volumio-4 sudo[4216]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 10 18:12:54 volumio-4 sudo[4216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:54 volumio-4 sudo[4216]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:54 volumio-4 volumio[1303]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 10 18:12:54 volumio-4 volumio[1303]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 10 18:12:54 volumio-4 volumio[1303]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 10 18:12:54 volumio-4 volumio[1303]: info: Output device has changed, restarting MPD Dec 10 18:12:54 volumio-4 volumio[1303]: info: Output device has changed, restarting Shairport Sync Dec 10 18:12:54 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:54 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:12:54 volumio-4 sudo[4222]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 18:12:54 volumio-4 sudo[4222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:54 volumio-4 sudo[4222]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:54 volumio-4 sudo[4224]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 18:12:54 volumio-4 volumio[1303]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 18:12:54 volumio-4 volumio[1303]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 10 18:12:54 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:54 volumio-4 sudo[4224]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:54 volumio-4 volumio[1303]: info: QobuzConnect: setDeactiveState invoked Dec 10 18:12:54 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:54 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:54 volumio-4 vtcs[4184]: [2025-12-10 18:12:54.741] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 10 18:12:54 volumio-4 vtcs[4184]: [2025-12-10 18:12:54.741] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 10 18:12:54 volumio-4 volumio[1303]: info: PLUGIN START: mpd_oled Dec 10 18:12:54 volumio-4 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 10 18:12:54 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 10 18:12:54 volumio-4 sudo[4234]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:12:54 volumio-4 sudo[4234]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:54 volumio-4 volumio[1303]: info: Preparing to generate the ALSA configuration file Dec 10 18:12:54 volumio-4 sudo[4240]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /tmp/mpd_oled_plugin.sh Dec 10 18:12:54 volumio-4 sudo[4240]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:54 volumio-4 sudo[4240]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:54 volumio-4 systemd[1]: mpd.service: Deactivated successfully. Dec 10 18:12:54 volumio-4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 10 18:12:54 volumio-4 systemd[1]: mpd.service: Consumed 2.579s CPU time. Dec 10 18:12:54 volumio-4 systemd[1]: mpd.socket: Deactivated successfully. Dec 10 18:12:54 volumio-4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 10 18:12:54 volumio-4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 10 18:12:54 volumio-4 sudo[4242]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start mpd_oled_plugin.service Dec 10 18:12:54 volumio-4 sudo[4242]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:54 volumio-4 volumio[1303]: info: Done. Dec 10 18:12:54 volumio-4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 10 18:12:54 volumio-4 sudo[4249]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:12:54 volumio-4 sudo[4249]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:54 volumio-4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 10 18:12:54 volumio-4 volumio[1303]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 10 18:12:54 volumio-4 volumio[1303]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Dec 10 18:12:54 volumio-4 volumio[1303]: info: Reading ALSA contributions from plugins. Dec 10 18:12:54 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] Set execute permissions on /tmp/mpd_oled_plugin.sh Dec 10 18:12:55 volumio-4 volumio[1303]: info: MPD Permissions set Dec 10 18:12:55 volumio-4 volumio[1303]: info: TidalConnect service stoped! Dec 10 18:12:55 volumio-4 volumio[1303]: info: TidalConnect service stoped! Dec 10 18:12:55 volumio-4 sudo[4257]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 10 18:12:55 volumio-4 sudo[4257]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:55 volumio-4 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 10 18:12:55 volumio-4 systemd[1]: vtcs.service: Killing process 4189 (vtcs) with signal SIGKILL. Dec 10 18:12:55 volumio-4 systemd[1]: vtcs.service: Deactivated successfully. Dec 10 18:12:55 volumio-4 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 10 18:12:55 volumio-4 volumio[1303]: info: [MPD_OLED Plugin] tmp script created /tmp/mpd_oled_plugin.sh Dec 10 18:12:55 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:55 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:55 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:55 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:55 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:55 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:55 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:55 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:55 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:12:55 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:55 volumio-4 sudo[4234]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:55 volumio-4 volumio[1303]: info: Starting Shairport Sync Dec 10 18:12:55 volumio-4 sudo[4257]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:55 volumio-4 volumio[1303]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 10 18:12:55 volumio-4 volumio[1303]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 10 18:12:55 volumio-4 volumio[1303]: info: Asound.conf file unchanged, so no further update is needed Dec 10 18:12:55 volumio-4 sudo[4249]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:55 volumio-4 volumio[1303]: info: Output device has changed, restarting MPD Dec 10 18:12:55 volumio-4 sudo[4275]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 10 18:12:55 volumio-4 sudo[4275]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:55 volumio-4 sudo[4276]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 18:12:55 volumio-4 sudo[4276]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:55 volumio-4 sudo[4254]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 10 18:12:55 volumio-4 sudo[4254]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 10 18:12:55 volumio-4 sudo[4279]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 18:12:55 volumio-4 volumio[1303]: info: Output device has changed, restarting Shairport Sync Dec 10 18:12:55 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:55 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:12:55 volumio-4 sudo[4254]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:55 volumio-4 sudo[4279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:55 volumio-4 sudo[4279]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:55 volumio-4 volumio[1303]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 18:12:55 volumio-4 volumio[1303]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 10 18:12:55 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:55 volumio-4 sudo[4284]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 18:12:55 volumio-4 sudo[4284]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:55 volumio-4 volumio[1303]: info: QobuzConnect: setDeactiveState invoked Dec 10 18:12:55 volumio-4 volumio[1303]: info: CoreCommandRouter::volumioGetState Dec 10 18:12:55 volumio-4 volumio[1303]: info: CorePlayQueue::getTrack 0 Dec 10 18:12:55 volumio-4 sudo[4298]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:12:55 volumio-4 sudo[4298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:55 volumio-4 qobuz-connect[4166]: 20251210 18:12:55.448 [4166.4166] INFO SampleApp: Stopping Local configuration server Dec 10 18:12:55 volumio-4 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 10 18:12:55 volumio-4 volumio[1303]: info: MPD Permissions set Dec 10 18:12:55 volumio-4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 10 18:12:55 volumio-4 sudo[4295]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 10 18:12:55 volumio-4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 10 18:12:55 volumio-4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:12:55 volumio-4 systemd[1]: shairport-sync.service: Consumed 1.662s CPU time. Dec 10 18:12:55 volumio-4 sudo[4295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:55 volumio-4 systemd[1]: mpd.service: Deactivated successfully. Dec 10 18:12:55 volumio-4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 10 18:12:55 volumio-4 sudo[4304]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 10 18:12:55 volumio-4 sudo[4304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:55 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:55 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:55 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:55 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:55 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:55 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:55 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:55 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:55 volumio-4 systemd[1]: mpd.socket: Deactivated successfully. Dec 10 18:12:55 volumio-4 mpd_monitor.sh[4247]: MPD error: Connection reset by peer Dec 10 18:12:55 volumio-4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 10 18:12:55 volumio-4 mpd_monitor.sh[3733]: MPD Monitor Service: MPD Appears to be inactive, restarting Dec 10 18:12:55 volumio-4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 10 18:12:55 volumio-4 sudo[4307]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 10 18:12:55 volumio-4 sudo[4307]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:55 volumio-4 mpd_monitor.sh[4310]: mpd: no process found Dec 10 18:12:55 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 18:12:55 volumio-4 volumio[1303]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 18:12:55 volumio-4 volumio[1303]: info: Starting Shairport Sync Dec 10 18:12:55 volumio-4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 10 18:12:55 volumio-4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 10 18:12:55 volumio-4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:12:55 volumio-4 sudo[4276]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:55 volumio-4 sudo[4319]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 18:12:55 volumio-4 sudo[4319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:55 volumio-4 volumio[1303]: info: Shairport-Sync Started Dec 10 18:12:55 volumio-4 sudo[4304]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:55 volumio-4 sudo[4298]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:55 volumio-4 volumio[1303]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Dec 10 18:12:55 volumio-4 volumio[1303]: Job for vtcs.service canceled. Dec 10 18:12:55 volumio-4 volumio[1303]: {"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 10 18:12:55 volumio-4 sudo[4295]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:55 volumio-4 sudo[4337]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 10 18:12:55 volumio-4 sudo[4337]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:55 volumio-4 volumio[1303]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 18:12:55 volumio-4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 10 18:12:55 volumio-4 volumio[1303]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Dec 10 18:12:55 volumio-4 volumio[1303]: Job for vtcs.service canceled. Dec 10 18:12:55 volumio-4 volumio[1303]: at ChildProcess.exithandler (node:child_process:421:12) Dec 10 18:12:55 volumio-4 volumio[1303]: at ChildProcess.emit (node:events:514:28) Dec 10 18:12:55 volumio-4 volumio[1303]: at maybeClose (node:internal/child_process:1105:16) Dec 10 18:12:55 volumio-4 volumio[1303]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) { Dec 10 18:12:55 volumio-4 volumio[1303]: code: 1, Dec 10 18:12:55 volumio-4 volumio[1303]: killed: false, Dec 10 18:12:55 volumio-4 volumio[1303]: signal: null, Dec 10 18:12:55 volumio-4 volumio[1303]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Dec 10 18:12:55 volumio-4 volumio[1303]: stdout: '', Dec 10 18:12:55 volumio-4 volumio[1303]: stderr: 'Job for vtcs.service canceled.\n' Dec 10 18:12:55 volumio-4 volumio[1303]: } Dec 10 18:12:55 volumio-4 volumio[1303]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 18:12:55 volumio-4 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 10 18:12:55 volumio-4 sudo[4307]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:55 volumio-4 systemd[1]: mpd.service: Deactivated successfully. Dec 10 18:12:55 volumio-4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 10 18:12:55 volumio-4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 10 18:12:55 volumio-4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:12:56 volumio-4 systemd[1]: mpd.socket: Deactivated successfully. Dec 10 18:12:56 volumio-4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 10 18:12:56 volumio-4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 10 18:12:56 volumio-4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 10 18:12:56 volumio-4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 10 18:12:56 volumio-4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 18:12:56 volumio-4 sudo[4319]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:56 volumio-4 sudo[4355]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 10 18:12:56 volumio-4 sudo[4355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 10 18:12:56 volumio-4 sudo[4355]: pam_unix(sudo:session): session closed for user root Dec 10 18:12:57 volumio-4 sudo[4375]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-10 18:11' Dec 10 18:12:57 volumio-4 sudo[4375]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 18:12:57 volumio-4 qobuz-connect[4166]: 20251210 18:12:57.157 [4166.4166] INFO SampleApp: shat down connection on UNIX socket Dec 10 18:12:57 volumio-4 systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 10 18:12:57 volumio-4 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 10 18:12:57 volumio-4 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 10 18:12:57 volumio-4 sudo[4337]: pam_unix(sudo:session): session closed for user root 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"