Mar 26 15:27:13 volumio volumio[1301]: info: Preparing to generate the ALSA configuration file Mar 26 15:27:14 volumio upmpdcli[2559]: writing RSA key Mar 26 15:27:18 volumio volumio[1301]: info: CoreCommandRouter::volumioNext Mar 26 15:27:18 volumio volumio[1301]: info: CoreStateMachine::next Mar 26 15:27:19 volumio volumio[1301]: info: CoreStateMachine::stop Mar 26 15:27:19 volumio volumio[1301]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 15:27:19 volumio volumio[1301]: info: CoreStateMachine::stPlaybackTimer Mar 26 15:27:19 volumio volumio[1301]: info: CoreStateMachine::updateTrackBlock Mar 26 15:27:19 volumio volumio[1301]: info: CorePlayQueue::getTrackBlock Mar 26 15:27:19 volumio volumio[1301]: info: CoreStateMachine::pushState Mar 26 15:27:19 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 Mar 26 15:27:19 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 15:27:19 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState Mar 26 15:27:19 volumio volumio[1301]: info: MRS: Pushing multiroomSync output update for this device Mar 26 15:27:19 volumio volumio[1301]: info: MRS: Pushing multiroomSync output Mar 26 15:27:20 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState Mar 26 15:27:20 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 Mar 26 15:27:20 volumio volumio[1301]: info: CoreStateMachine::serviceStop Mar 26 15:27:20 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 Mar 26 15:27:20 volumio volumio[1301]: info: CoreCommandRouter::serviceStop Mar 26 15:27:21 volumio volumio[1301]: info: ControllerMpd::stop Mar 26 15:27:21 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 15:27:21 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState Mar 26 15:27:21 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 Mar 26 15:27:22 volumio volumio[1301]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 26 15:27:22 volumio volumio[1301]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Mar 26 15:27:22 volumio volumio[1301]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Mar 26 15:27:22 volumio volumio[1301]: info: Reading ALSA contributions from plugins. Mar 26 15:27:22 volumio volumio[1301]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Mar 26 15:27:22 volumio volumio[1301]: Traceback (most recent call last): Mar 26 15:27:22 volumio volumio[1301]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Mar 26 15:27:22 volumio volumio[1301]: from volumio_albumart import AlbumartAnimator Mar 26 15:27:22 volumio volumio[1301]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Mar 26 15:27:22 volumio volumio[1301]: import socketio Mar 26 15:27:22 volumio volumio[1301]: ModuleNotFoundError: No module named 'socketio' Mar 26 15:27:22 volumio volumio[1301]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Mar 26 15:27:22 volumio volumio[1301]: Traceback (most recent call last): Mar 26 15:27:22 volumio volumio[1301]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Mar 26 15:27:22 volumio volumio[1301]: from volumio_albumart import AlbumartAnimator Mar 26 15:27:22 volumio volumio[1301]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Mar 26 15:27:22 volumio volumio[1301]: import socketio Mar 26 15:27:22 volumio volumio[1301]: ModuleNotFoundError: No module named 'socketio' Mar 26 15:27:22 volumio volumio[1301]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Mar 26 15:27:22 volumio volumio[1301]: Traceback (most recent call last): Mar 26 15:27:22 volumio volumio[1301]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Mar 26 15:27:22 volumio volumio[1301]: from volumio_albumart import AlbumartAnimator Mar 26 15:27:22 volumio volumio[1301]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Mar 26 15:27:22 volumio volumio[1301]: import socketio Mar 26 15:27:22 volumio volumio[1301]: ModuleNotFoundError: No module named 'socketio' Mar 26 15:27:22 volumio volumio[1301]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Mar 26 15:27:22 volumio volumio[1301]: Traceback (most recent call last): Mar 26 15:27:22 volumio volumio[1301]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Mar 26 15:27:22 volumio volumio[1301]: from volumio_albumart import AlbumartAnimator Mar 26 15:27:22 volumio volumio[1301]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Mar 26 15:27:22 volumio volumio[1301]: import socketio Mar 26 15:27:22 volumio volumio[1301]: ModuleNotFoundError: No module named 'socketio' Mar 26 15:27:22 volumio volumio[1301]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Mar 26 15:27:22 volumio volumio[1301]: Traceback (most recent call last): Mar 26 15:27:22 volumio volumio[1301]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Mar 26 15:27:22 volumio volumio[1301]: from volumio_albumart import AlbumartAnimator Mar 26 15:27:22 volumio volumio[1301]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Mar 26 15:27:22 volumio volumio[1301]: import socketio Mar 26 15:27:22 volumio volumio[1301]: ModuleNotFoundError: No module named 'socketio' Mar 26 15:27:23 volumio volumio[1301]: info: Mar 26 15:27:23 volumio volumio[1301]: ---------------------------- MPD announces state update: player Mar 26 15:27:23 volumio volumio[1301]: info: sendMpdCommand stop took 2103 milliseconds Mar 26 15:27:23 volumio volumio[1301]: info: ControllerMpd::getState Mar 26 15:27:23 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status Mar 26 15:27:23 volumio volumio[1301]: info: CoreStateMachine::play index undefined Mar 26 15:27:23 volumio volumio[1301]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 15:27:23 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 Mar 26 15:27:23 volumio volumio[1301]: info: CoreStateMachine::startPlaybackTimer Mar 26 15:27:23 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 Mar 26 15:27:23 volumio volumio[1301]: verbose: ControllerMpd::clearAddPlayTracks USB/Musica_Volu/Greta Van Fleet - Anthem Of The Peaceful Army/02 - The Cold Wind.mp3 Mar 26 15:27:23 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 15:27:23 volumio volumio[1301]: info: CoreStateMachine::updateTrackBlock Mar 26 15:27:23 volumio volumio[1301]: info: CorePlayQueue::getTrackBlock Mar 26 15:27:23 volumio volumio[1301]: Upnp client error: Error: This socket has been ended by the other party Mar 26 15:27:24 volumio volumio[1301]: info: sendMpdCommand status took 849 milliseconds Mar 26 15:27:24 volumio volumio[1301]: info: sendMpdCommand stop took 854 milliseconds Mar 26 15:27:24 volumio volumio[1301]: verbose: ControllerMpd::parseState Mar 26 15:27:24 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 15:27:24 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand clear Mar 26 15:27:24 volumio volumio[1301]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 26 15:27:24 volumio volumio[1301]: info: Mar 26 15:27:24 volumio volumio[1301]: ---------------------------- MPD announces system playlist update Mar 26 15:27:24 volumio volumio[1301]: info: Ignoring MPD Status Update Mar 26 15:27:24 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 222 milliseconds Mar 26 15:27:24 volumio volumio[1301]: info: sendMpdCommand clear took 222 milliseconds Mar 26 15:27:24 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo Mar 26 15:27:24 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand add "USB/Musica_Volu/Greta Van Fleet - Anthem Of The Peaceful Army/02 - The Cold Wind.mp3" Mar 26 15:27:24 volumio volumio[1301]: info: ControllerMpd::pushState Mar 26 15:27:24 volumio volumio[1301]: info: CoreCommandRouter::servicePushState Mar 26 15:27:24 volumio volumio[1301]: info: CoreStateMachine::pushState Mar 26 15:27:24 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 Mar 26 15:27:24 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 15:27:24 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState Mar 26 15:27:24 volumio volumio[1301]: info: MRS: Pushing multiroomSync output update for this device Mar 26 15:27:24 volumio volumio[1301]: info: MRS: Pushing multiroomSync output Mar 26 15:27:24 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState Mar 26 15:27:24 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 Mar 26 15:27:24 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 Mar 26 15:27:24 volumio volumio[1301]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Age Of Man","artist":"Greta Van Fleet","album":"Anthem Of The Peaceful Army","uri":"USB/Musica_Volu/Greta Van Fleet - Anthem Of The Peaceful Army/01 - Age Of Man.mp3","trackType":"mp3"} Mar 26 15:27:24 volumio volumio[1301]: verbose: CURRENT POSITION 1 Mar 26 15:27:24 volumio volumio[1301]: info: CoreStateMachine::syncState stateService stop Mar 26 15:27:24 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus stop Mar 26 15:27:24 volumio volumio[1301]: info: CoreStateMachine::pushState Mar 26 15:27:24 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 Mar 26 15:27:24 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState Mar 26 15:27:24 volumio volumio[1301]: info: MRS: Pushing multiroomSync output update for this device Mar 26 15:27:24 volumio volumio[1301]: info: MRS: Pushing multiroomSync output Mar 26 15:27:24 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState Mar 26 15:27:24 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 Mar 26 15:27:24 volumio volumio[1301]: info: No code Mar 26 15:27:24 volumio volumio[1301]: info: CoreStateMachine::pushState Mar 26 15:27:24 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 Mar 26 15:27:24 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState Mar 26 15:27:24 volumio volumio[1301]: info: MRS: Pushing multiroomSync output update for this device Mar 26 15:27:24 volumio volumio[1301]: info: MRS: Pushing multiroomSync output Mar 26 15:27:24 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState Mar 26 15:27:24 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 Mar 26 15:27:25 volumio volumio[1301]: info: ------------------------------ 2162ms Mar 26 15:27:25 volumio volumio[1301]: error: updateQueue error: null Mar 26 15:27:25 volumio volumio[1301]: info: Mar 26 15:27:25 volumio volumio[1301]: ---------------------------- MPD announces system playlist update Mar 26 15:27:25 volumio volumio[1301]: info: Ignoring MPD Status Update Mar 26 15:27:25 volumio volumio[1301]: info: ------------------------------ 1504ms Mar 26 15:27:25 volumio volumio[1301]: info: sendMpdCommand add "USB/Musica_Volu/Greta Van Fleet - Anthem Of The Peaceful Army/02 - The Cold Wind.mp3" took 1334 milliseconds Mar 26 15:27:25 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand play Mar 26 15:27:27 volumio volumio[1301]: info: Mar 26 15:27:27 volumio volumio[1301]: ---------------------------- MPD announces state update: player Mar 26 15:27:27 volumio volumio[1301]: info: ------------------------------ 1357ms Mar 26 15:27:27 volumio volumio[1301]: info: sendMpdCommand play took 1320 milliseconds Mar 26 15:27:27 volumio volumio[1301]: info: ControllerMpd::getState Mar 26 15:27:27 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status Mar 26 15:27:27 volumio volumio[1301]: info: Executing endpoint metavolumio Mar 26 15:27:27 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 26 15:27:27 volumio volumio[1301]: info: Executing endpoint metavolumio Mar 26 15:27:27 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 26 15:27:28 volumio volumio[1301]: info: Mar 26 15:27:28 volumio volumio[1301]: ---------------------------- MPD announces state update: player Mar 26 15:27:28 volumio volumio[1301]: info: ControllerMpd::getState Mar 26 15:27:28 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status Mar 26 15:27:28 volumio volumio[1301]: info: sendMpdCommand status took 964 milliseconds Mar 26 15:27:28 volumio volumio[1301]: verbose: ControllerMpd::parseState Mar 26 15:27:28 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 15:27:28 volumio volumio[1301]: info: Executing endpoint metavolumio Mar 26 15:27:28 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 26 15:27:28 volumio volumio[1301]: info: sendMpdCommand status took 292 milliseconds Mar 26 15:27:28 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 191 milliseconds Mar 26 15:27:28 volumio volumio[1301]: verbose: ControllerMpd::parseState Mar 26 15:27:28 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 26 15:27:28 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo Mar 26 15:27:28 volumio volumio[1301]: info: ControllerMpd::pushState Mar 26 15:27:28 volumio volumio[1301]: info: CoreCommandRouter::servicePushState Mar 26 15:27:28 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 Mar 26 15:27:28 volumio volumio[1301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2475,"duration":197,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Cold Wind","artist":"Greta Van Fleet","album":"Anthem Of The Peaceful Army","uri":"USB/Musica_Volu/Greta Van Fleet - Anthem Of The Peaceful Army/02 - The Cold Wind.mp3","trackType":"mp3"} Mar 26 15:27:28 volumio volumio[1301]: verbose: CURRENT POSITION 1 Mar 26 15:27:28 volumio volumio[1301]: info: CoreStateMachine::syncState stateService play Mar 26 15:27:28 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus stop Mar 26 15:27:28 volumio volumio[1301]: info: ------------------------------ 1264ms Mar 26 15:27:28 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 158 milliseconds Mar 26 15:27:28 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo Mar 26 15:27:28 volumio volumio[1301]: info: ControllerMpd::pushState Mar 26 15:27:28 volumio volumio[1301]: info: CoreCommandRouter::servicePushState Mar 26 15:27:28 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 Mar 26 15:27:28 volumio volumio[1301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3599,"duration":197,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Cold Wind","artist":"Greta Van Fleet","album":"Anthem Of The Peaceful Army","uri":"USB/Musica_Volu/Greta Van Fleet - Anthem Of The Peaceful Army/02 - The Cold Wind.mp3","trackType":"mp3"} Mar 26 15:27:28 volumio volumio[1301]: verbose: CURRENT POSITION 1 Mar 26 15:27:28 volumio volumio[1301]: info: CoreStateMachine::syncState stateService play Mar 26 15:27:28 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus play Mar 26 15:27:28 volumio volumio[1301]: info: Received an update from plugin. extracting info from payload Mar 26 15:27:28 volumio volumio[1301]: info: CoreStateMachine::pushState Mar 26 15:27:28 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 Mar 26 15:27:28 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 15:27:28 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState Mar 26 15:27:28 volumio volumio[1301]: info: MRS: Pushing multiroomSync output update for this device Mar 26 15:27:28 volumio volumio[1301]: info: MRS: Pushing multiroomSync output Mar 26 15:27:28 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState Mar 26 15:27:28 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 Mar 26 15:27:28 volumio volumio[1301]: info: CoreStateMachine::pushState Mar 26 15:27:28 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 Mar 26 15:27:28 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState Mar 26 15:27:28 volumio volumio[1301]: info: MRS: Pushing multiroomSync output update for this device Mar 26 15:27:28 volumio volumio[1301]: info: MRS: Pushing multiroomSync output Mar 26 15:27:28 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState Mar 26 15:27:28 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 Mar 26 15:27:29 volumio volumio[1301]: info: ------------------------------ 1245ms Mar 26 15:27:30 volumio volumio[1301]: info: Asound.conf file written Mar 26 15:27:32 volumio sudo[2616]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 26 15:27:32 volumio sudo[2616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:27:32 volumio sudo[2616]: pam_unix(sudo:session): session closed for user root Mar 26 15:27:33 volumio volumio[1301]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 26 15:27:33 volumio volumio[1301]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 26 15:27:33 volumio volumio[1301]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 26 15:27:33 volumio volumio[1301]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Mar 26 15:27:33 volumio volumio[1301]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy Mar 26 15:27:33 volumio volumio[1301]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy Mar 26 15:27:33 volumio volumio[1301]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy Mar 26 15:27:33 volumio volumio[1301]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Mar 26 15:27:33 volumio volumio[1301]: info: Output device has changed, restarting MPD Mar 26 15:27:33 volumio volumio[1301]: info: Output device has changed, restarting Shairport Sync Mar 26 15:27:33 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:27:33 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 15:27:34 volumio sudo[2624]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 26 15:27:34 volumio sudo[2624]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:27:34 volumio sudo[2622]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 26 15:27:34 volumio sudo[2622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:27:34 volumio volumio[1301]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 26 15:27:34 volumio volumio[1301]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 26 15:27:34 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:27:34 volumio sudo[2622]: pam_unix(sudo:session): session closed for user root Mar 26 15:27:34 volumio volumio[1301]: info: QobuzConnect: setDeactiveState invoked Mar 26 15:27:34 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState Mar 26 15:27:34 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 Mar 26 15:27:35 volumio vtcs[2373]: [2026-03-26 15:27:34.986] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Mar 26 15:27:35 volumio vtcs[2373]: [2026-03-26 15:27:34.987] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Mar 26 15:27:35 volumio volumio[1301]: info: PLUGIN START: peppymeterbasic Mar 26 15:27:35 volumio sudo[2634]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 26 15:27:35 volumio sudo[2634]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:27:35 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Mar 26 15:27:36 volumio sudo[2637]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd_aloop index=7 pcm_substreams=2 Mar 26 15:27:36 volumio sudo[2637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:27:36 volumio volumio[1301]: info: Preparing to generate the ALSA configuration file Mar 26 15:27:36 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 26 15:27:36 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Mar 26 15:27:36 volumio systemd[1]: vtcs.service: Deactivated successfully. Mar 26 15:27:36 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Mar 26 15:27:36 volumio systemd[1]: vtcs.service: Consumed 1.159s CPU time. Mar 26 15:27:37 volumio sudo[2634]: pam_unix(sudo:session): session closed for user root Mar 26 15:27:37 volumio kernel: snd_aloop snd_aloop.0: cannot find the slot for index 7 (range 0-7), error: -16 Mar 26 15:27:37 volumio kernel: snd_aloop snd_aloop.0: probe with driver snd_aloop failed with error -16 Mar 26 15:27:37 volumio kernel: aloop: No loopback enabled Mar 26 15:27:37 volumio sudo[2637]: pam_unix(sudo:session): session closed for user root Mar 26 15:27:39 volumio volumio[1301]: info: Done. Mar 26 15:27:41 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:27:41 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:27:42 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 15:27:42 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 26 15:27:43 volumio sudo[2657]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 26 15:27:43 volumio sudo[2664]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 26 15:27:43 volumio sudo[2664]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:27:43 volumio sudo[2657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:27:44 volumio volumio[1301]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 26 15:27:45 volumio volumio[1301]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Mar 26 15:27:45 volumio volumio[1301]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Mar 26 15:27:45 volumio volumio[1301]: info: Reading ALSA contributions from plugins. Mar 26 15:27:45 volumio sudo[2664]: pam_unix(sudo:session): session closed for user root Mar 26 15:27:45 volumio sudo[2657]: pam_unix(sudo:session): session closed for user root Mar 26 15:27:46 volumio sudo[2671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 26 15:27:46 volumio sudo[2671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:27:46 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Mar 26 15:27:46 volumio qobuz-connect[2285]: 20260326 15:27:46.946 [2285.2285] INFO SampleApp: Stopping Local configuration server Mar 26 15:27:48 volumio qobuz-connect[2285]: 20260326 15:27:48.627 [2285.2285] INFO SampleApp: shat down connection on UNIX socket Mar 26 15:27:49 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Mar 26 15:27:49 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 26 15:27:50 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 26 15:27:50 volumio sudo[2671]: pam_unix(sudo:session): session closed for user root Mar 26 15:27:53 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 26 15:27:53 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 26 15:27:53 volumio systemd[1]: mpd.service: Consumed 11.491s CPU time. Mar 26 15:27:53 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 26 15:27:53 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 26 15:27:53 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 26 15:27:53 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 26 15:27:53 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 26 15:27:54 volumio sudo[2695]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 26 15:27:54 volumio sudo[2695]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 26 15:27:54 volumio sudo[2695]: pam_unix(sudo:session): session closed for user root Mar 26 15:27:56 volumio volumio[1301]: error: failed to load snd_dummy: Error: Command failed: /usr/bin/sudo /sbin/modprobe snd_aloop index=7 pcm_substreams=2 Mar 26 15:27:56 volumio volumio[1301]: modprobe: ERROR: could not insert 'snd_aloop': No such device Mar 26 15:27:57 volumio volumio[1301]: info: TidalConnect service stoped! Mar 26 15:27:58 volumio volumio[1301]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Mar 26 15:27:59 volumio volumio[1301]: info: MPD Permissions set Mar 26 15:28:01 volumio volumio[1301]: info: CoreCommandRouter::volumioNext Mar 26 15:28:01 volumio volumio[1301]: info: CoreStateMachine::next Mar 26 15:28:01 volumio volumio[1301]: info: CoreStateMachine::stop Mar 26 15:28:01 volumio volumio[1301]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 15:28:01 volumio volumio[1301]: info: CoreStateMachine::stPlaybackTimer Mar 26 15:28:01 volumio volumio[1301]: info: CoreStateMachine::updateTrackBlock Mar 26 15:28:01 volumio volumio[1301]: info: CorePlayQueue::getTrackBlock Mar 26 15:28:01 volumio volumio[1301]: info: CoreStateMachine::pushState Mar 26 15:28:01 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 Mar 26 15:28:01 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 15:28:01 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState Mar 26 15:28:03 volumio volumio[1301]: info: MRS: Pushing multiroomSync output update for this device Mar 26 15:28:04 volumio volumio[1301]: info: MRS: Pushing multiroomSync output Mar 26 15:28:05 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState Mar 26 15:28:05 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 Mar 26 15:28:06 volumio volumio[1301]: info: CoreStateMachine::serviceStop Mar 26 15:28:06 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 Mar 26 15:28:06 volumio volumio[1301]: info: CoreCommandRouter::serviceStop Mar 26 15:28:06 volumio volumio[1301]: info: ControllerMpd::stop Mar 26 15:28:06 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 15:28:06 volumio mpd[2697]: 2026-03-26T15:28:06 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 26 15:28:07 volumio systemd[1]: Started mpd.service - Music Player Daemon. Mar 26 15:28:08 volumio volumio[1301]: error: Upnp client error: Error: connect ECONNRESET 127.0.0.1:6600 Mar 26 15:28:08 volumio volumio[1301]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Mar 26 15:28:08 volumio volumio[1301]: Traceback (most recent call last): Mar 26 15:28:08 volumio volumio[1301]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Mar 26 15:28:08 volumio volumio[1301]: from volumio_albumart import AlbumartAnimator Mar 26 15:28:08 volumio volumio[1301]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Mar 26 15:28:08 volumio volumio[1301]: import socketio Mar 26 15:28:08 volumio volumio[1301]: ModuleNotFoundError: No module named 'socketio' Mar 26 15:28:08 volumio volumio[1301]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Mar 26 15:28:08 volumio volumio[1301]: Traceback (most recent call last): Mar 26 15:28:08 volumio volumio[1301]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Mar 26 15:28:08 volumio volumio[1301]: from volumio_albumart import AlbumartAnimator Mar 26 15:28:08 volumio volumio[1301]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Mar 26 15:28:08 volumio volumio[1301]: import socketio Mar 26 15:28:08 volumio volumio[1301]: ModuleNotFoundError: No module named 'socketio' Mar 26 15:28:08 volumio volumio[1301]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Mar 26 15:28:08 volumio volumio[1301]: Traceback (most recent call last): Mar 26 15:28:08 volumio volumio[1301]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Mar 26 15:28:08 volumio volumio[1301]: from volumio_albumart import AlbumartAnimator Mar 26 15:28:08 volumio volumio[1301]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Mar 26 15:28:08 volumio volumio[1301]: import socketio Mar 26 15:28:08 volumio volumio[1301]: ModuleNotFoundError: No module named 'socketio' Mar 26 15:28:08 volumio volumio[1301]: info: TidalConnect service stoped! Mar 26 15:28:08 volumio volumio[1301]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Mar 26 15:28:08 volumio volumio[1301]: info: QobuzConnect: setDeactiveState invoked Mar 26 15:28:08 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState Mar 26 15:28:08 volumio volumio[1301]: info: CorePlayQueue::getTrack 1 Mar 26 15:28:08 volumio volumio[1301]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 26 15:28:09 volumio sudo[2624]: pam_unix(sudo:session): session closed for user root Mar 26 15:28:09 volumio volumio[1301]: info: CoreCommandRouter::volumioNext Mar 26 15:28:09 volumio volumio[1301]: info: CoreStateMachine::next Mar 26 15:28:09 volumio volumio[1301]: info: CoreStateMachine::stop Mar 26 15:28:09 volumio volumio[1301]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 15:28:09 volumio volumio[1301]: info: CoreStateMachine::play index undefined Mar 26 15:28:09 volumio volumio[1301]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 15:28:09 volumio volumio[1301]: info: CorePlayQueue::getTrack 2 Mar 26 15:28:09 volumio volumio[1301]: info: CoreStateMachine::startPlaybackTimer Mar 26 15:28:09 volumio volumio[1301]: info: CorePlayQueue::getTrack 2 Mar 26 15:28:09 volumio volumio[1301]: verbose: ControllerMpd::clearAddPlayTracks USB/Musica_Volu/Greta Van Fleet - Anthem Of The Peaceful Army/03 - When The Curtain Falls.mp3 Mar 26 15:28:09 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 15:28:09 volumio volumio[1301]: info: CoreStateMachine::updateTrackBlock Mar 26 15:28:09 volumio volumio[1301]: info: CorePlayQueue::getTrackBlock Mar 26 15:28:11 volumio volumio[1301]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Mar 26 15:28:12 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:12 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:12 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:12 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:12 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:12 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:12 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:12 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:12 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 15:28:12 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:12 volumio volumio[1301]: info: Executing endpoint qc_getconfig Mar 26 15:28:12 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Mar 26 15:28:13 volumio volumio[1301]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 26 15:28:13 volumio volumio[1301]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 26 15:28:13 volumio qobuz-connect[2679]: 20260326 15:28:13.711 [2679.2679] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Mar 26 15:28:14 volumio qobuz-connect[2679]: 20260326 15:28:14.202 [2679.2679] INFO VolumeManager: [0x1f99058]: Setting new playback volume: 75 Mar 26 15:28:14 volumio qobuz-connect[2679]: 20260326 15:28:14.203 [2679.2679] INFO VolumeManager: [0x1f99058]: Setting new mute state: 0 Mar 26 15:28:14 volumio qobuz-connect[2679]: 20260326 15:28:14.203 [2679.2679] INFO AudioStreamManager: [0x1f98db0]: Setting new audio download buffer size: 1048576 Mar 26 15:28:14 volumio qobuz-connect[2679]: 20260326 15:28:14.203 [2679.2679] INFO QobuzConnect: [0x1f99920]: Client initialized! Mar 26 15:28:14 volumio qobuz-connect[2679]: 20260326 15:28:14.203 [2679.2679] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Mar 26 15:28:14 volumio volumio[1301]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Mar 26 15:28:14 volumio volumio[1301]: info: QobuzConnect: QOBUZ Connect daemon connected Mar 26 15:28:14 volumio volumio[1301]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 26 15:28:14 volumio qobuz-connect[2679]: 20260326 15:28:14.460 [2679.2679] INFO LocalConfigManager: [0x1f98ad8]: Starting Local Configuration server Mar 26 15:28:14 volumio qobuz-connect[2679]: 20260326 15:28:14.460 [2679.2679] INFO SampleApp: Starting Local configuration server Mar 26 15:28:14 volumio qobuz-connect[2679]: 20260326 15:28:14.469 [2679.2679] INFO SampleApp: Connected to UNIX socket client 0x1f83818 Mar 26 15:28:14 volumio qobuz-connect[2679]: 20260326 15:28:14.487 [2679.2679] INFO SampleApp: Playback volume changed: 75 Mar 26 15:28:14 volumio volumio[1301]: error: updateQueue error: null Mar 26 15:28:14 volumio volumio[1301]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Mar 26 15:28:14 volumio volumio[1301]: info: Starting Shairport Sync Mar 26 15:28:14 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState Mar 26 15:28:14 volumio volumio[1301]: info: CorePlayQueue::getTrack 2 Mar 26 15:28:15 volumio sudo[2768]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 26 15:28:15 volumio sudo[2768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:28:16 volumio sudo[2771]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 26 15:28:16 volumio sudo[2771]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:28:16 volumio volumio[1301]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Mar 26 15:28:16 volumio volumio[1301]: Traceback (most recent call last): Mar 26 15:28:16 volumio volumio[1301]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Mar 26 15:28:16 volumio volumio[1301]: from volumio_albumart import AlbumartAnimator Mar 26 15:28:16 volumio volumio[1301]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Mar 26 15:28:16 volumio volumio[1301]: import socketio Mar 26 15:28:16 volumio volumio[1301]: ModuleNotFoundError: No module named 'socketio' Mar 26 15:28:16 volumio volumio[1301]: info: Asound.conf file unchanged, so no further update is needed Mar 26 15:28:16 volumio volumio[1301]: info: Output device has changed, restarting MPD Mar 26 15:28:16 volumio volumio[1301]: info: Output device has changed, restarting Shairport Sync Mar 26 15:28:16 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:16 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 15:28:16 volumio sudo[2777]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 26 15:28:16 volumio sudo[2777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:28:16 volumio sudo[2778]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 26 15:28:16 volumio sudo[2778]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:28:16 volumio sudo[2778]: pam_unix(sudo:session): session closed for user root Mar 26 15:28:16 volumio volumio[1301]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 26 15:28:16 volumio volumio[1301]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 26 15:28:16 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:16 volumio volumio[1301]: info: QobuzConnect: setDeactiveState invoked Mar 26 15:28:16 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState Mar 26 15:28:16 volumio volumio[1301]: info: CorePlayQueue::getTrack 2 Mar 26 15:28:16 volumio volumio[1301]: info: Preparing to generate the ALSA configuration file Mar 26 15:28:17 volumio sudo[2788]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 26 15:28:17 volumio sudo[2788]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:28:18 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:18 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:18 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 26 15:28:18 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 26 15:28:18 volumio volumio[1301]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 26 15:28:18 volumio volumio[1301]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Mar 26 15:28:18 volumio volumio[1301]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Mar 26 15:28:18 volumio volumio[1301]: info: Reading ALSA contributions from plugins. Mar 26 15:28:18 volumio sudo[2795]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 26 15:28:18 volumio sudo[2800]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 26 15:28:18 volumio sudo[2795]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:28:18 volumio sudo[2800]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:28:18 volumio sudo[2771]: pam_unix(sudo:session): session closed for user root Mar 26 15:28:18 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 26 15:28:18 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 26 15:28:18 volumio systemd[1]: shairport-sync.service: Consumed 4.075s CPU time. Mar 26 15:28:18 volumio sudo[2788]: pam_unix(sudo:session): session closed for user root Mar 26 15:28:18 volumio sudo[2800]: pam_unix(sudo:session): session closed for user root Mar 26 15:28:18 volumio sudo[2804]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 26 15:28:18 volumio sudo[2804]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:28:18 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 26 15:28:18 volumio sudo[2768]: pam_unix(sudo:session): session closed for user root Mar 26 15:28:18 volumio sudo[2795]: pam_unix(sudo:session): session closed for user root Mar 26 15:28:18 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Mar 26 15:28:19 volumio qobuz-connect[2679]: 20260326 15:28:18.972 [2679.2679] INFO SampleApp: Stopping Local configuration server Mar 26 15:28:19 volumio volumio[1301]: info: MPD Permissions set Mar 26 15:28:19 volumio volumio[1301]: info: Shairport-Sync Started Mar 26 15:28:19 volumio volumio[1301]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3 Mar 26 15:28:19 volumio volumio[1301]: Job for vtcs.service canceled. Mar 26 15:28:19 volumio volumio[1301]: {"cmd":"/usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl start 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 Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)","stderr":"Job for vtcs.service canceled.\n","stdout":""} Mar 26 15:28:19 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:19 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:19 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:19 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:19 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:19 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:19 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:19 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:20 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 15:28:20 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:20 volumio volumio[1301]: info: Starting Shairport Sync Mar 26 15:28:20 volumio qobuz-connect[2679]: 20260326 15:28:20.564 [2679.2679] INFO SampleApp: shat down connection on UNIX socket Mar 26 15:28:21 volumio volumio[1301]: info: TidalConnect service stoped! Mar 26 15:28:21 volumio volumio[1301]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Mar 26 15:28:21 volumio volumio[1301]: info: QobuzConnect: setDeactiveState invoked Mar 26 15:28:21 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState Mar 26 15:28:21 volumio volumio[1301]: info: CorePlayQueue::getTrack 2 Mar 26 15:28:21 volumio volumio[1301]: info: Asound.conf file unchanged, so no further update is needed Mar 26 15:28:21 volumio volumio[1301]: info: Output device has changed, restarting MPD Mar 26 15:28:21 volumio volumio[1301]: info: Output device has changed, restarting Shairport Sync Mar 26 15:28:21 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:21 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 15:28:23 volumio volumio[1301]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 26 15:28:23 volumio volumio[1301]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 26 15:28:23 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:23 volumio volumio[1301]: info: QobuzConnect: setDeactiveState invoked Mar 26 15:28:23 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState Mar 26 15:28:23 volumio volumio[1301]: info: CorePlayQueue::getTrack 2 Mar 26 15:28:23 volumio volumio[1301]: info: Preparing to generate the ALSA configuration file Mar 26 15:28:23 volumio sudo[2821]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 26 15:28:23 volumio sudo[2826]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 26 15:28:23 volumio sudo[2824]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 26 15:28:23 volumio sudo[2821]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:28:23 volumio sudo[2826]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:28:23 volumio sudo[2824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:28:23 volumio sudo[2856]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 26 15:28:23 volumio sudo[2856]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:28:23 volumio sudo[2824]: pam_unix(sudo:session): session closed for user root Mar 26 15:28:24 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 26 15:28:24 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 26 15:28:24 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 26 15:28:24 volumio systemd[1]: shairport-sync.service: Consumed 1.941s CPU time. Mar 26 15:28:24 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Mar 26 15:28:24 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 26 15:28:24 volumio sudo[2856]: pam_unix(sudo:session): session closed for user root Mar 26 15:28:24 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 26 15:28:24 volumio sudo[2804]: pam_unix(sudo:session): session closed for user root Mar 26 15:28:24 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 26 15:28:24 volumio sudo[2821]: pam_unix(sudo:session): session closed for user root Mar 26 15:28:24 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:24 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:24 volumio sudo[2870]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 26 15:28:24 volumio sudo[2870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:28:24 volumio sudo[2877]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 26 15:28:24 volumio sudo[2877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:28:25 volumio volumio[1301]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 26 15:28:25 volumio volumio[1301]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Mar 26 15:28:25 volumio volumio[1301]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Mar 26 15:28:25 volumio volumio[1301]: info: Reading ALSA contributions from plugins. Mar 26 15:28:25 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState Mar 26 15:28:25 volumio volumio[1301]: info: CorePlayQueue::getTrack 2 Mar 26 15:28:25 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Mar 26 15:28:25 volumio volumio[1301]: info: CoreCommandRouter::volumioPlay Mar 26 15:28:25 volumio volumio[1301]: info: CoreStateMachine::play index undefined Mar 26 15:28:25 volumio volumio[1301]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 15:28:25 volumio volumio[1301]: info: CorePlayQueue::getTrack 2 Mar 26 15:28:25 volumio volumio[1301]: info: CoreStateMachine::startPlaybackTimer Mar 26 15:28:25 volumio volumio[1301]: info: CorePlayQueue::getTrack 2 Mar 26 15:28:25 volumio volumio[1301]: verbose: ControllerMpd::clearAddPlayTracks USB/Musica_Volu/Greta Van Fleet - Anthem Of The Peaceful Army/03 - When The Curtain Falls.mp3 Mar 26 15:28:25 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand stop Mar 26 15:28:25 volumio sudo[2877]: pam_unix(sudo:session): session closed for user root Mar 26 15:28:25 volumio sudo[2870]: pam_unix(sudo:session): session closed for user root Mar 26 15:28:25 volumio sudo[2883]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 26 15:28:25 volumio sudo[2883]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:28:25 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Mar 26 15:28:25 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Mar 26 15:28:25 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 26 15:28:25 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 26 15:28:25 volumio sudo[2883]: pam_unix(sudo:session): session closed for user root Mar 26 15:28:27 volumio volumio[1301]: info: MPD Permissions set Mar 26 15:28:27 volumio volumio[1301]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Mar 26 15:28:27 volumio volumio[1301]: Traceback (most recent call last): Mar 26 15:28:27 volumio volumio[1301]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Mar 26 15:28:27 volumio volumio[1301]: from volumio_albumart import AlbumartAnimator Mar 26 15:28:27 volumio volumio[1301]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Mar 26 15:28:27 volumio volumio[1301]: import socketio Mar 26 15:28:27 volumio volumio[1301]: ModuleNotFoundError: No module named 'socketio' Mar 26 15:28:27 volumio volumio[1301]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Mar 26 15:28:27 volumio volumio[1301]: Traceback (most recent call last): Mar 26 15:28:27 volumio volumio[1301]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Mar 26 15:28:27 volumio volumio[1301]: from volumio_albumart import AlbumartAnimator Mar 26 15:28:27 volumio volumio[1301]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Mar 26 15:28:27 volumio volumio[1301]: import socketio Mar 26 15:28:27 volumio volumio[1301]: ModuleNotFoundError: No module named 'socketio' Mar 26 15:28:27 volumio volumio[1301]: info: Shairport-Sync Started Mar 26 15:28:27 volumio volumio[1301]: info: TidalConnect service stoped! Mar 26 15:28:27 volumio volumio[1301]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Mar 26 15:28:27 volumio volumio[1301]: Traceback (most recent call last): Mar 26 15:28:27 volumio volumio[1301]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Mar 26 15:28:27 volumio volumio[1301]: from volumio_albumart import AlbumartAnimator Mar 26 15:28:27 volumio volumio[1301]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Mar 26 15:28:27 volumio volumio[1301]: import socketio Mar 26 15:28:27 volumio volumio[1301]: ModuleNotFoundError: No module named 'socketio' Mar 26 15:28:27 volumio volumio[1301]: info: Executing endpoint qc_getconfig Mar 26 15:28:27 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Mar 26 15:28:27 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:27 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:27 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:27 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:27 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:27 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:27 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:27 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:27 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 15:28:27 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:27 volumio qobuz-connect[2899]: 20260326 15:28:27.823 [2899.2899] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Mar 26 15:28:27 volumio qobuz-connect[2899]: 20260326 15:28:27.833 [2899.2899] INFO VolumeManager: [0x1818058]: Setting new playback volume: 75 Mar 26 15:28:27 volumio qobuz-connect[2899]: 20260326 15:28:27.833 [2899.2899] INFO VolumeManager: [0x1818058]: Setting new mute state: 0 Mar 26 15:28:27 volumio qobuz-connect[2899]: 20260326 15:28:27.833 [2899.2899] INFO AudioStreamManager: [0x1817db0]: Setting new audio download buffer size: 1048576 Mar 26 15:28:27 volumio qobuz-connect[2899]: 20260326 15:28:27.833 [2899.2899] INFO QobuzConnect: [0x1818920]: Client initialized! Mar 26 15:28:27 volumio qobuz-connect[2899]: 20260326 15:28:27.833 [2899.2899] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Mar 26 15:28:27 volumio qobuz-connect[2899]: 20260326 15:28:27.880 [2899.2899] INFO LocalConfigManager: [0x1817ad8]: Starting Local Configuration server Mar 26 15:28:27 volumio qobuz-connect[2899]: 20260326 15:28:27.880 [2899.2899] INFO SampleApp: Starting Local configuration server Mar 26 15:28:27 volumio qobuz-connect[2899]: 20260326 15:28:27.915 [2899.2899] INFO SampleApp: Connected to UNIX socket client 0x1802818 Mar 26 15:28:27 volumio qobuz-connect[2899]: 20260326 15:28:27.916 [2899.2899] INFO SampleApp: Playback volume changed: 75 Mar 26 15:28:28 volumio volumio[1301]: info: TidalConnect service stoped! Mar 26 15:28:28 volumio volumio[1301]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Mar 26 15:28:28 volumio volumio[1301]: info: QobuzConnect: QOBUZ Connect daemon connected Mar 26 15:28:28 volumio volumio[1301]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Mar 26 15:28:28 volumio volumio[1301]: Traceback (most recent call last): Mar 26 15:28:28 volumio volumio[1301]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Mar 26 15:28:28 volumio volumio[1301]: from volumio_albumart import AlbumartAnimator Mar 26 15:28:28 volumio volumio[1301]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Mar 26 15:28:28 volumio volumio[1301]: import socketio Mar 26 15:28:28 volumio volumio[1301]: ModuleNotFoundError: No module named 'socketio' Mar 26 15:28:28 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState Mar 26 15:28:28 volumio volumio[1301]: info: CorePlayQueue::getTrack 2 Mar 26 15:28:28 volumio volumio[1301]: info: Starting Shairport Sync Mar 26 15:28:28 volumio volumio[1301]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 26 15:28:28 volumio volumio[1301]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 26 15:28:28 volumio volumio[1301]: info: Asound.conf file unchanged, so no further update is needed Mar 26 15:28:28 volumio volumio[1301]: info: Output device has changed, restarting MPD Mar 26 15:28:28 volumio volumio[1301]: info: Output device has changed, restarting Shairport Sync Mar 26 15:28:28 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:28 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 15:28:28 volumio sudo[2924]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 26 15:28:28 volumio sudo[2924]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:28:28 volumio sudo[2928]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 26 15:28:28 volumio sudo[2928]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:28:28 volumio sudo[2926]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 26 15:28:28 volumio sudo[2926]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:28:28 volumio sudo[2926]: pam_unix(sudo:session): session closed for user root Mar 26 15:28:28 volumio volumio[1301]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 26 15:28:28 volumio volumio[1301]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 26 15:28:28 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:28 volumio volumio[1301]: info: QobuzConnect: setDeactiveState invoked Mar 26 15:28:28 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState Mar 26 15:28:28 volumio volumio[1301]: info: CorePlayQueue::getTrack 2 Mar 26 15:28:28 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 26 15:28:28 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 26 15:28:28 volumio systemd[1]: mpd.service: Consumed 9.554s CPU time. Mar 26 15:28:28 volumio sudo[2939]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 26 15:28:28 volumio sudo[2939]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:28:28 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 26 15:28:28 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 26 15:28:28 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 26 15:28:28 volumio sudo[2941]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 26 15:28:28 volumio sudo[2941]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:28:28 volumio sudo[2951]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 26 15:28:28 volumio sudo[2951]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:28:28 volumio volumio[1301]: info: MPD Permissions set Mar 26 15:28:28 volumio volumio[1301]: info: TidalConnect service stoped! Mar 26 15:28:28 volumio sudo[2948]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 26 15:28:28 volumio sudo[2948]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:28:28 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 26 15:28:28 volumio volumio[1301]: error: Upnp client error: Error: read ECONNRESET Mar 26 15:28:28 volumio volumio[1301]: error: Upnp client error: Error: read ECONNRESET Mar 26 15:28:28 volumio volumio[1301]: error: Upnp client error: Error: read ECONNRESET Mar 26 15:28:28 volumio volumio[1301]: error: Upnp client error: Error: read ECONNRESET Mar 26 15:28:28 volumio volumio[1301]: error: Upnp client error: Error: read ECONNRESET Mar 26 15:28:28 volumio volumio[1301]: error: Upnp client error: Error: read ECONNRESET Mar 26 15:28:28 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:28 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:28 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:28 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:28 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:28 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:28 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:28 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:28 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 15:28:28 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 26 15:28:28 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:28 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 26 15:28:28 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 26 15:28:28 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 26 15:28:28 volumio systemd[1]: shairport-sync.service: Consumed 1.637s CPU time. Mar 26 15:28:28 volumio sudo[2939]: pam_unix(sudo:session): session closed for user root Mar 26 15:28:28 volumio sudo[2941]: pam_unix(sudo:session): session closed for user root Mar 26 15:28:28 volumio sudo[2948]: pam_unix(sudo:session): session closed for user root Mar 26 15:28:28 volumio sudo[2970]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 26 15:28:28 volumio sudo[2970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 15:28:28 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 26 15:28:28 volumio sudo[2924]: pam_unix(sudo:session): session closed for user root Mar 26 15:28:28 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Mar 26 15:28:28 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:28 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 15:28:28 volumio volumio[1301]: info: Shairport-Sync Started Mar 26 15:28:28 volumio volumio[1301]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Mar 26 15:28:28 volumio volumio[1301]: Job for vtcs.service canceled. Mar 26 15:28:28 volumio volumio[1301]: {"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 Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)","stderr":"Job for vtcs.service canceled.\n","stdout":""} Mar 26 15:28:28 volumio sudo[2951]: pam_unix(sudo:session): session closed for user root Mar 26 15:28:29 volumio qobuz-connect[2899]: 20260326 15:28:29.088 [2899.2899] INFO SampleApp: Stopping Local configuration server Mar 26 15:28:29 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Mar 26 15:28:29 volumio volumio[1301]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 15:28:29 volumio sudo[2960]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 26 15:28:29 volumio sudo[2960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 26 15:28:29 volumio volumio[1301]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Mar 26 15:28:29 volumio volumio[1301]: Job for vtcs.service canceled. Mar 26 15:28:29 volumio volumio[1301]: at ChildProcess.exithandler (node:child_process:421:12) Mar 26 15:28:29 volumio volumio[1301]: at ChildProcess.emit (node:events:514:28) Mar 26 15:28:29 volumio volumio[1301]: at maybeClose (node:internal/child_process:1105:16) Mar 26 15:28:29 volumio volumio[1301]: at Socket. (node:internal/child_process:457:11) Mar 26 15:28:29 volumio volumio[1301]: at Socket.emit (node:events:514:28) Mar 26 15:28:29 volumio volumio[1301]: at Pipe. (node:net:337:12) { Mar 26 15:28:29 volumio volumio[1301]: code: 1, Mar 26 15:28:29 volumio volumio[1301]: killed: false, Mar 26 15:28:29 volumio volumio[1301]: signal: null, Mar 26 15:28:29 volumio volumio[1301]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Mar 26 15:28:29 volumio volumio[1301]: stdout: '', Mar 26 15:28:29 volumio volumio[1301]: stderr: 'Job for vtcs.service canceled.\n' Mar 26 15:28:29 volumio volumio[1301]: } Mar 26 15:28:29 volumio volumio[1301]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 15:28:29 volumio sudo[2960]: pam_unix(sudo:session): session closed for user root Mar 26 15:28:29 volumio qobuz-connect[2899]: 20260326 15:28:29.924 [2899.2899] INFO SampleApp: shat down connection on UNIX socket Mar 26 15:28:29 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Mar 26 15:28:29 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 26 15:28:30 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 26 15:28:30 volumio sudo[2970]: pam_unix(sudo:session): session closed for user root Mar 26 15:28:35 volumio sudo[3025]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-26 15:27' Mar 26 15:28:35 volumio sudo[3025]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"