Aug 27 04:37:28 volumio volumio[1410]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 04:37:28 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 27 04:37:28 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 04:37:28 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 04:37:28 volumio volumio[1410]: info: Getting Alsa Cards List without I2S DAC Aug 27 04:37:28 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 27 04:37:28 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 04:37:28 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 04:37:28 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 04:37:28 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 04:37:28 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 04:37:28 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 04:37:28 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 04:37:28 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 04:37:28 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 27 04:37:28 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 27 04:38:20 volumio volumio[1410]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Aug 27 04:38:20 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Aug 27 04:38:20 volumio volumio[1410]: info: CoreCommandRouter::volumioGetState Aug 27 04:38:20 volumio volumio[1410]: info: CorePlayQueue::getTrack 0 Aug 27 04:38:20 volumio volumio[1410]: info: Restoring Previous Volume level: 100 false true Aug 27 04:38:20 volumio volumio[1410]: info: VolumeController::SetAlsaVolume100 Aug 27 04:38:20 volumio volumio[1410]: info: Enable softmixer device for audio device number 3 Aug 27 04:38:20 volumio volumio[1410]: info: CoreCommandRouter::volumioStop Aug 27 04:38:20 volumio volumio[1410]: info: CoreStateMachine::stop Aug 27 04:38:20 volumio volumio[1410]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 04:38:20 volumio volumio[1410]: info: CoreStateMachine::stPlaybackTimer Aug 27 04:38:20 volumio volumio[1410]: info: CoreStateMachine::updateTrackBlock Aug 27 04:38:20 volumio volumio[1410]: info: CorePlayQueue::getTrackBlock Aug 27 04:38:20 volumio volumio[1410]: info: CoreStateMachine::pushState Aug 27 04:38:20 volumio volumio[1410]: info: CorePlayQueue::getTrack 0 Aug 27 04:38:20 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 04:38:20 volumio volumio[1410]: info: CoreCommandRouter::volumioPushState Aug 27 04:38:20 volumio volumio[1410]: info: CoreStateMachine::serviceStop Aug 27 04:38:20 volumio volumio[1410]: info: CorePlayQueue::getTrack 0 Aug 27 04:38:20 volumio volumio[1410]: info: CoreCommandRouter::serviceStop Aug 27 04:38:20 volumio volumio[1410]: info: [1787780300198] ControllerTidal::stop Aug 27 04:38:20 volumio volumio[1410]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 27 04:38:20 volumio volumio[1410]: info: ControllerMpd::stop Aug 27 04:38:20 volumio volumio[1410]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 04:38:20 volumio volumio[1410]: info: Enable softmixer device for audio device undefined Aug 27 04:38:20 volumio volumio[1410]: info: Output device has changed, restarting MPD Aug 27 04:38:20 volumio volumio[1410]: info: Output device has changed, restarting Shairport Sync Aug 27 04:38:20 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:20 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 04:38:20 volumio sudo[7596]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 04:38:20 volumio sudo[7596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:20 volumio sudo[7596]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:20 volumio sudo[7598]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 04:38:20 volumio sudo[7598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:20 volumio volumio[1410]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 04:38:20 volumio volumio[1410]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 27 04:38:20 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:20 volumio vtcs[2493]: [2026-08-27 04:38:20.272] [tisoc] [error] [SpkconServer.cpp:383] recv error. client fd=8 errorno=104 error=Connection reset by peer Aug 27 04:38:20 volumio vtcs[2493]: [2026-08-27 04:38:20.272] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Aug 27 04:38:20 volumio vtcs[2493]: [2026-08-27 04:38:20.273] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Aug 27 04:38:20 volumio volumio[1410]: info: Volume configurations have been set Aug 27 04:38:20 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 27 04:38:20 volumio sudo[7610]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 04:38:20 volumio sudo[7610]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:20 volumio sudo[7611]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 04:38:20 volumio sudo[7611]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:20 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 27 04:38:20 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 04:38:20 volumio systemd[1]: mpd.service: Consumed 1min 5.612s CPU time. Aug 27 04:38:20 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 27 04:38:20 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 04:38:20 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 04:38:20 volumio volumio[1410]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 27 04:38:20 volumio volumio[1410]: info: Updating Volume Controller Parameters: Device: 3 Name: HiFiBerry DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 27 04:38:20 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 27 04:38:20 volumio volumio[1410]: info: Disabling external Volume Control Aug 27 04:38:20 volumio volumio[1410]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 04:38:20 volumio volumio[1410]: info: CoreStateMachine::pushState Aug 27 04:38:20 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 04:38:20 volumio volumio[1410]: info: CoreCommandRouter::volumioPushState Aug 27 04:38:20 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 04:38:20 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 04:38:20 volumio volumio[1410]: error: MPD error: Error: write EPIPE Aug 27 04:38:20 volumio volumio[1410]: error: write EPIPE {"code":"EPIPE","errno":-32,"syscall":"write"} Aug 27 04:38:20 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Aug 27 04:38:20 volumio systemd[1]: vtcs.service: Deactivated successfully. Aug 27 04:38:20 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Aug 27 04:38:20 volumio systemd[1]: vtcs.service: Consumed 1.358s CPU time. Aug 27 04:38:20 volumio volumio[1410]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Aug 27 04:38:20 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Aug 27 04:38:20 volumio volumio[1410]: info: CoreCommandRouter::volumioGetState Aug 27 04:38:20 volumio volumio[1410]: info: Restoring Previous Volume level: 100 false true Aug 27 04:38:20 volumio volumio[1410]: info: VolumeController::SetAlsaVolume100 Aug 27 04:38:20 volumio sudo[7610]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:20 volumio volumio[1410]: info: Enable softmixer device for audio device number 3 Aug 27 04:38:20 volumio volumio[1410]: info: CoreCommandRouter::volumioStop Aug 27 04:38:20 volumio volumio[1410]: info: CoreStateMachine::stop Aug 27 04:38:20 volumio volumio[1410]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 04:38:20 volumio volumio[1410]: info: Enable softmixer device for audio device undefined Aug 27 04:38:20 volumio volumio[1410]: info: Output device has changed, restarting MPD Aug 27 04:38:20 volumio sudo[7611]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:20 volumio sudo[7626]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 04:38:20 volumio sudo[7626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:20 volumio sudo[7628]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 04:38:20 volumio sudo[7628]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:20 volumio volumio[1410]: info: Output device has changed, restarting Shairport Sync Aug 27 04:38:20 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:20 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 04:38:20 volumio sudo[7626]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:20 volumio sudo[7638]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 04:38:20 volumio sudo[7638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:20 volumio sudo[7624]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 04:38:20 volumio sudo[7624]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 04:38:20 volumio sudo[7624]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:20 volumio sudo[7634]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 04:38:20 volumio sudo[7634]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:20 volumio sudo[7634]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:20 volumio volumio[1410]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 04:38:20 volumio volumio[1410]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 27 04:38:20 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:20 volumio volumio[1410]: info: Volume configurations have been set Aug 27 04:38:20 volumio sudo[7650]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 04:38:20 volumio sudo[7650]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:20 volumio sudo[7628]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:20 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 27 04:38:20 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 04:38:20 volumio sudo[7654]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 04:38:20 volumio sudo[7654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:20 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 27 04:38:20 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 04:38:20 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 04:38:20 volumio volumio[1410]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 27 04:38:20 volumio volumio[1410]: info: Updating Volume Controller Parameters: Device: 3 Name: HiFiBerry DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 27 04:38:20 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 27 04:38:20 volumio volumio[1410]: info: Disabling external Volume Control Aug 27 04:38:20 volumio volumio[1410]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 04:38:20 volumio volumio[1410]: info: CoreStateMachine::pushState Aug 27 04:38:20 volumio volumio[1410]: info: CorePlayQueue::getTrack 0 Aug 27 04:38:20 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 04:38:20 volumio volumio[1410]: info: CoreCommandRouter::volumioPushState Aug 27 04:38:20 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 04:38:20 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 04:38:20 volumio sudo[7671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 04:38:20 volumio sudo[7671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:20 volumio sudo[7650]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:20 volumio volumio[1410]: info: FusionDsp - Volumio is not playing Aug 27 04:38:20 volumio volumio[1410]: info: FusionDsp - Clipped samples monitor stopped Aug 27 04:38:20 volumio volumio[1410]: info: FusionDsp - Volumio is playing Aug 27 04:38:20 volumio volumio[1410]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Aug 27 04:38:20 volumio volumio[1410]: info: FusionDsp - Volumio is not playing Aug 27 04:38:20 volumio volumio[1410]: info: FusionDsp - Clipped samples monitor stopped Aug 27 04:38:20 volumio volumio[1410]: info: PeppyMeterBasic ---peppymeterbasic status stop Aug 27 04:38:20 volumio volumio[1410]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 27 04:38:20 volumio volumio[1410]: info: PeppyMeterBasic ---peppymeterbasic status stop Aug 27 04:38:21 volumio sudo[7674]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 04:38:21 volumio sudo[7674]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:21 volumio volumio[1410]: info: Aligning Spotify Volume to Volumio Volume Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::volumioGetState Aug 27 04:38:21 volumio volumio[1410]: info: CorePlayQueue::getTrack 0 Aug 27 04:38:21 volumio volumio[1410]: info: Setting Spotify Volume from Volumio: 100 Aug 27 04:38:21 volumio sudo[7678]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 27 04:38:21 volumio sudo[7654]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:21 volumio sudo[7678]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:21 volumio sudo[7680]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 27 04:38:21 volumio sudo[7671]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:21 volumio sudo[7680]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:21 volumio sudo[7683]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 27 04:38:21 volumio sudo[7683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:21 volumio sudo[7669]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 04:38:21 volumio sudo[7669]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 04:38:21 volumio sudo[7669]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:21 volumio volumio[1410]: info: CoreStateMachine::pushState Aug 27 04:38:21 volumio volumio[1410]: info: CorePlayQueue::getTrack 0 Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::volumioPushState Aug 27 04:38:21 volumio volumio[1410]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Aug 27 04:38:21 volumio volumio[1410]: info: MPD Permissions set Aug 27 04:38:21 volumio volumio[1410]: info: FusionDsp - Volumio is not playing Aug 27 04:38:21 volumio volumio[1410]: info: FusionDsp - Clipped samples monitor stopped Aug 27 04:38:21 volumio volumio[1410]: info: PeppyMeterBasic ---peppymeterbasic status stop Aug 27 04:38:21 volumio volumio[1410]: info: Setting Spotify Volume from Volumio: 85 Aug 27 04:38:21 volumio sudo[7678]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:21 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Aug 27 04:38:21 volumio volumio[1410]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 27 04:38:21 volumio volumio[1410]: info: touch_display: Setting screensaver timeout to 120 seconds. Aug 27 04:38:21 volumio volumio[1410]: info: touch_display: Setting screensaver timeout to 120 seconds. Aug 27 04:38:21 volumio volumio[1410]: info: CoreStateMachine::pushState Aug 27 04:38:21 volumio volumio[1410]: info: CorePlayQueue::getTrack 0 Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::volumioPushState Aug 27 04:38:21 volumio sudo[7680]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:21 volumio volumio[1410]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Aug 27 04:38:21 volumio volumio[1410]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Aug 27 04:38:21 volumio volumio[1410]: info: MPD Permissions set Aug 27 04:38:21 volumio volumio[1410]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Aug 27 04:38:21 volumio volumio[1410]: info: camilladsp respawn in 100 ms (attempt 1/10) Aug 27 04:38:21 volumio sudo[7674]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:21 volumio sudo[7697]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 27 04:38:21 volumio sudo[7697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:21 volumio volumio[1410]: info: FusionDsp - Volumio is not playing Aug 27 04:38:21 volumio volumio[1410]: info: FusionDsp - Clipped samples monitor stopped Aug 27 04:38:21 volumio volumio[1410]: info: PeppyMeterBasic ---peppymeterbasic status stop Aug 27 04:38:21 volumio volumio[1410]: info: Software Volume ALSA configuration written Aug 27 04:38:21 volumio volumio[1410]: info: Preparing to generate the ALSA configuration file Aug 27 04:38:21 volumio sudo[7701]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 27 04:38:21 volumio sudo[7701]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:21 volumio volumio[1410]: info: Software Volume ALSA configuration written Aug 27 04:38:21 volumio volumio[1410]: info: PeppyMeterBasic ---peppymeterbasic failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppymeterbasic.service Aug 27 04:38:21 volumio volumio[1410]: Job for peppymeterbasic.service canceled. Aug 27 04:38:21 volumio volumio[1410]: info: PeppyMeterBasic ---peppymeterbasic failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppymeterbasic.service Aug 27 04:38:21 volumio volumio[1410]: Job for peppymeterbasic.service canceled. Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:21 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. Aug 27 04:38:21 volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Aug 27 04:38:21 volumio systemd[1]: peppymeterbasic.service: Consumed 6min 29.014s CPU time. Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:21 volumio sudo[7701]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:21 volumio sudo[7697]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:21 volumio sudo[7683]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 04:38:21 volumio volumio[1410]: info: Getting Alsa Cards List without I2S DAC Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 04:38:21 volumio volumio[1410]: info: Getting Alsa Cards List without I2S DAC Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 27 04:38:21 volumio volumio[1410]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 27 04:38:21 volumio volumio[1410]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 27 04:38:21 volumio volumio[1410]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Aug 27 04:38:21 volumio volumio[1410]: info: Reading ALSA contributions from plugins. Aug 27 04:38:21 volumio volumio[1410]: info: peppymeterbasic Daemon Stop Aug 27 04:38:21 volumio volumio[1410]: info: peppymeterbasic Daemon Stop Aug 27 04:38:21 volumio volumio[1410]: info: peppymeterbasic Daemon Stop Aug 27 04:38:21 volumio volumio[1410]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 27 04:38:21 volumio volumio[1410]: info: Starting Shairport Sync Aug 27 04:38:21 volumio volumio[1410]: info: Starting Shairport Sync Aug 27 04:38:21 volumio volumio[1410]: info: Asound.conf file unchanged, so no further update is needed Aug 27 04:38:21 volumio volumio[1410]: info: Output device has changed, restarting MPD Aug 27 04:38:21 volumio sudo[7744]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 04:38:21 volumio sudo[7744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:21 volumio sudo[7746]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 04:38:21 volumio sudo[7746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:21 volumio volumio[1410]: info: Output device has changed, restarting Shairport Sync Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:21 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 04:38:22 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 27 04:38:22 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 27 04:38:22 volumio sudo[7751]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 04:38:22 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 04:38:22 volumio sudo[7751]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:22 volumio systemd[1]: shairport-sync.service: Consumed 10.222s CPU time. Aug 27 04:38:22 volumio sudo[7751]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:22 volumio sudo[7753]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 04:38:22 volumio sudo[7753]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:22 volumio volumio[1410]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 04:38:22 volumio volumio[1410]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 27 04:38:22 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:22 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 04:38:22 volumio sudo[7744]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:22 volumio sudo[7746]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:22 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 27 04:38:22 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 04:38:22 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 27 04:38:22 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 04:38:22 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 04:38:22 volumio volumio[1410]: info: Preparing to generate the ALSA configuration file Aug 27 04:38:22 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 04:38:22 volumio sudo[7763]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 04:38:22 volumio sudo[7763]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:22 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 04:38:22 volumio sudo[7763]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:22 volumio sudo[7777]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 04:38:22 volumio sudo[7777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 04:38:22 volumio sudo[7777]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:22 volumio volumio[1410]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Aug 27 04:38:22 volumio volumio[1410]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 27 04:38:22 volumio volumio[1410]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 27 04:38:22 volumio volumio[1410]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Aug 27 04:38:22 volumio volumio[1410]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Aug 27 04:38:22 volumio volumio[1410]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Aug 27 04:38:22 volumio volumio[1410]: info: Output device has changed, restarting MPD Aug 27 04:38:22 volumio sudo[7798]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 04:38:22 volumio sudo[7798]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:22 volumio volumio[1410]: info: Output device has changed, restarting Shairport Sync Aug 27 04:38:22 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:22 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 04:38:23 volumio sudo[7801]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 04:38:23 volumio sudo[7801]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:23 volumio sudo[7804]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 04:38:23 volumio sudo[7804]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:23 volumio sudo[7801]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:23 volumio volumio[1410]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 04:38:23 volumio volumio[1410]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:23 volumio sudo[7798]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:23 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 27 04:38:23 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 04:38:23 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 27 04:38:23 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 04:38:23 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 27 04:38:23 volumio volumio[1410]: info: Updating Volume Controller Parameters: Device: 3 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 27 04:38:23 volumio sudo[7814]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 04:38:23 volumio sudo[7814]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 27 04:38:23 volumio volumio[1410]: info: Disabling external Volume Control Aug 27 04:38:23 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 04:38:23 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 04:38:23 volumio sudo[7814]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:23 volumio volumio[1410]: info: Sending Spotify command with payload to local API: /player/volume Aug 27 04:38:23 volumio sudo[7831]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 04:38:23 volumio sudo[7831]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:23 volumio sudo[7829]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 04:38:23 volumio sudo[7829]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:23 volumio volumio[1410]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 27 04:38:23 volumio volumio[1410]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 27 04:38:23 volumio volumio[1410]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Aug 27 04:38:23 volumio volumio[1410]: info: Reading ALSA contributions from plugins. Aug 27 04:38:23 volumio sudo[7821]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 04:38:23 volumio sudo[7821]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 04:38:23 volumio sudo[7821]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:23 volumio sudo[7833]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 04:38:23 volumio sudo[7833]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:23 volumio volumio[1410]: info: CoreStateMachine::pushState Aug 27 04:38:23 volumio volumio[1410]: info: CorePlayQueue::getTrack 0 Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::volumioPushState Aug 27 04:38:23 volumio volumio[1410]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Aug 27 04:38:23 volumio volumio[1410]: info: MPD Permissions set Aug 27 04:38:23 volumio volumio[1410]: info: MPD Permissions set Aug 27 04:38:23 volumio volumio[1410]: info: camilladsp respawn in 200 ms (attempt 2/10) Aug 27 04:38:23 volumio volumio[1410]: info: Shairport-Sync Started Aug 27 04:38:23 volumio volumio[1410]: info: Shairport-Sync Started Aug 27 04:38:23 volumio volumio[1410]: error: Failed to send command to Spotify local API: /player/volume: Error: Bad Request Aug 27 04:38:23 volumio volumio[1410]: info: FusionDsp - Volumio is not playing Aug 27 04:38:23 volumio volumio[1410]: info: FusionDsp - Clipped samples monitor stopped Aug 27 04:38:23 volumio volumio[1410]: info: PeppyMeterBasic ---peppymeterbasic status stop Aug 27 04:38:23 volumio sudo[7829]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:23 volumio volumio[1410]: info: TidalConnect service stoped! Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:23 volumio sudo[7842]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:23 volumio sudo[7842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:23 volumio sudo[7831]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:23 volumio sudo[7833]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:23 volumio volumio[1410]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Aug 27 04:38:23 volumio volumio[1410]: info: FusionDsp - ---- read samplerate from file: 44100 Aug 27 04:38:23 volumio volumio[1410]: info: TidalConnect service stoped! Aug 27 04:38:23 volumio sudo[7842]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:23 volumio volumio[1410]: info: TidalConnect service stoped! Aug 27 04:38:23 volumio volumio[1410]: info: peppymeterbasic Daemon Stop Aug 27 04:38:23 volumio volumio[1410]: info: Starting Shairport Sync Aug 27 04:38:23 volumio volumio[1410]: info: Starting Shairport Sync Aug 27 04:38:23 volumio sudo[7865]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 04:38:23 volumio sudo[7865]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:23 volumio sudo[7867]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 04:38:23 volumio sudo[7867]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:23 volumio volumio[1410]: info: TidalConnect service stoped! Aug 27 04:38:23 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 27 04:38:23 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 27 04:38:23 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 04:38:23 volumio systemd[1]: shairport-sync.service: Consumed 1.314s CPU time. Aug 27 04:38:23 volumio volumio[1410]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 27 04:38:23 volumio volumio[1410]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 27 04:38:23 volumio volumio[1410]: info: Asound.conf file unchanged, so no further update is needed Aug 27 04:38:23 volumio volumio[1410]: info: Output device has changed, restarting MPD Aug 27 04:38:23 volumio sudo[7880]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 04:38:23 volumio volumio[1410]: info: Output device has changed, restarting Shairport Sync Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 04:38:23 volumio sudo[7880]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:23 volumio sudo[7882]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 04:38:23 volumio sudo[7882]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:23 volumio sudo[7880]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:23 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 04:38:23 volumio sudo[7865]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:23 volumio volumio[1410]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 04:38:23 volumio volumio[1410]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 27 04:38:23 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:23 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 27 04:38:23 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 27 04:38:24 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 04:38:24 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 04:38:24 volumio volumio[1410]: info: Preparing to generate the ALSA configuration file Aug 27 04:38:24 volumio sudo[7867]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:24 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 27 04:38:24 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 04:38:24 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 27 04:38:24 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 04:38:24 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 04:38:24 volumio sudo[7893]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 04:38:24 volumio sudo[7893]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:24 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 04:38:24 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 04:38:24 volumio sudo[7893]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:24 volumio sudo[7896]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 04:38:24 volumio sudo[7896]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 04:38:24 volumio sudo[7896]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:24 volumio volumio[1410]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Aug 27 04:38:24 volumio volumio[1410]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 27 04:38:24 volumio volumio[1410]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 27 04:38:24 volumio volumio[1410]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Aug 27 04:38:24 volumio volumio[1410]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Aug 27 04:38:24 volumio volumio[1410]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Aug 27 04:38:24 volumio volumio[1410]: info: Output device has changed, restarting MPD Aug 27 04:38:24 volumio sudo[7929]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 04:38:24 volumio sudo[7929]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:24 volumio sudo[7931]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 04:38:24 volumio sudo[7931]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:24 volumio volumio[1410]: info: Output device has changed, restarting Shairport Sync Aug 27 04:38:24 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:24 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 04:38:24 volumio sudo[7931]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:24 volumio sudo[7936]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 04:38:24 volumio sudo[7936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:24 volumio sudo[7929]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:24 volumio volumio[1410]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 04:38:24 volumio volumio[1410]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 27 04:38:24 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:25 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 27 04:38:25 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 04:38:25 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 27 04:38:25 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 04:38:25 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 04:38:25 volumio sudo[7948]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 04:38:25 volumio sudo[7948]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 27 04:38:25 volumio volumio[1410]: info: Updating Volume Controller Parameters: Device: 3 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 27 04:38:25 volumio volumio[1410]: info: Disabling external Volume Control Aug 27 04:38:25 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 04:38:25 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 04:38:25 volumio sudo[7960]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 04:38:25 volumio sudo[7960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:25 volumio volumio[1410]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 27 04:38:25 volumio volumio[1410]: info: VolumeController::SetAlsaVolume100 Aug 27 04:38:25 volumio sudo[7948]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:25 volumio sudo[7965]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 04:38:25 volumio sudo[7965]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:25 volumio sudo[7963]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 04:38:25 volumio sudo[7963]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:25 volumio volumio[1410]: info: CoreStateMachine::pushState Aug 27 04:38:25 volumio volumio[1410]: info: CorePlayQueue::getTrack 0 Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::volumioPushState Aug 27 04:38:25 volumio sudo[7959]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 04:38:25 volumio sudo[7959]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 04:38:25 volumio sudo[7959]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:25 volumio volumio[1410]: info: VolumeController::SetAlsaVolume100 Aug 27 04:38:25 volumio volumio[1410]: info: CoreStateMachine::pushState Aug 27 04:38:25 volumio volumio[1410]: info: CorePlayQueue::getTrack 0 Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::volumioPushState Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Aug 27 04:38:25 volumio volumio[1410]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 27 04:38:25 volumio volumio[1410]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 27 04:38:25 volumio volumio[1410]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Aug 27 04:38:25 volumio volumio[1410]: info: Reading ALSA contributions from plugins. Aug 27 04:38:25 volumio volumio[1410]: info: FusionDsp - Volumio is not playing Aug 27 04:38:25 volumio volumio[1410]: info: FusionDsp - Clipped samples monitor stopped Aug 27 04:38:25 volumio volumio[1410]: info: FusionDsp - Volumio is not playing Aug 27 04:38:25 volumio volumio[1410]: info: FusionDsp - Clipped samples monitor stopped Aug 27 04:38:25 volumio volumio[1410]: info: PeppyMeterBasic ---peppymeterbasic status stop Aug 27 04:38:25 volumio sudo[7974]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 04:38:25 volumio sudo[7974]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:25 volumio volumio[1410]: info: PeppyMeterBasic ---peppymeterbasic status stop Aug 27 04:38:25 volumio sudo[7960]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:25 volumio sudo[7965]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:25 volumio sudo[7977]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 27 04:38:25 volumio sudo[7977]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:25 volumio volumio[1410]: info: Setting Spotify Volume from Volumio: 100 Aug 27 04:38:25 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 27 04:38:25 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 04:38:25 volumio sudo[7963]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:25 volumio sudo[7982]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 27 04:38:25 volumio sudo[7982]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:25 volumio volumio[1410]: info: CoreStateMachine::pushState Aug 27 04:38:25 volumio volumio[1410]: info: CorePlayQueue::getTrack 0 Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::volumioPushState Aug 27 04:38:25 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 27 04:38:25 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 04:38:25 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 04:38:25 volumio volumio[1410]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Aug 27 04:38:25 volumio volumio[1410]: info: MPD Permissions set Aug 27 04:38:25 volumio volumio[1410]: info: MPD Permissions set Aug 27 04:38:25 volumio volumio[1410]: info: TidalConnect service stoped! Aug 27 04:38:25 volumio volumio[1410]: info: TidalConnect service stoped! Aug 27 04:38:25 volumio volumio[1410]: info: Shairport-Sync Started Aug 27 04:38:25 volumio volumio[1410]: info: TidalConnect service stoped! Aug 27 04:38:25 volumio volumio[1410]: info: TidalConnect service stoped! Aug 27 04:38:25 volumio volumio[1410]: info: TidalConnect service stoped! Aug 27 04:38:25 volumio volumio[1410]: info: Shairport-Sync Started Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:25 volumio volumio[1410]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Aug 27 04:38:25 volumio volumio[1410]: info: FusionDsp - ---- read samplerate from file: 44100 Aug 27 04:38:25 volumio volumio[1410]: info: FusionDsp - Volumio is not playing Aug 27 04:38:25 volumio volumio[1410]: info: FusionDsp - Clipped samples monitor stopped Aug 27 04:38:25 volumio volumio[1410]: info: PeppyMeterBasic ---peppymeterbasic status stop Aug 27 04:38:25 volumio sudo[7999]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 27 04:38:25 volumio sudo[7999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:25 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 04:38:25 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 04:38:25 volumio volumio[1410]: info: camilladsp respawn in 400 ms (attempt 3/10) Aug 27 04:38:25 volumio volumio[1410]: error: FusionDsp - Reload WebSocket error: [object Object] Aug 27 04:38:25 volumio volumio[1410]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 27 04:38:25 volumio volumio[1410]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 27 04:38:25 volumio volumio[1410]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 27 04:38:25 volumio volumio[1410]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 27 04:38:25 volumio volumio[1410]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 27 04:38:25 volumio volumio[1410]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:25 volumio sudo[8011]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 27 04:38:25 volumio sudo[8011]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:25 volumio sudo[7977]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 04:38:25 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:25 volumio volumio[1410]: info: peppymeterbasic Daemon Stop Aug 27 04:38:25 volumio sudo[7982]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:25 volumio volumio[1410]: info: Starting Shairport Sync Aug 27 04:38:25 volumio sudo[8024]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 27 04:38:25 volumio sudo[8024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:25 volumio sudo[8005]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 04:38:25 volumio sudo[8005]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 04:38:25 volumio volumio[1410]: info: Starting Shairport Sync Aug 27 04:38:25 volumio sudo[8005]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:25 volumio sudo[8026]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 27 04:38:25 volumio sudo[8026]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:25 volumio volumio[1410]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 27 04:38:25 volumio sudo[8034]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 04:38:25 volumio sudo[8031]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 04:38:25 volumio sudo[8031]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:25 volumio sudo[8034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:25 volumio volumio[1410]: info: peppymeterbasic Daemon Stop Aug 27 04:38:25 volumio sudo[8036]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 27 04:38:25 volumio volumio[1410]: error: FusionDsp - Reload WebSocket error: [object Object] Aug 27 04:38:25 volumio volumio[1410]: info: Asound.conf file unchanged, so no further update is needed Aug 27 04:38:25 volumio volumio[1410]: info: Output device has changed, restarting MPD Aug 27 04:38:25 volumio sudo[8036]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:25 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 27 04:38:25 volumio sudo[8042]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 04:38:25 volumio sudo[8042]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:25 volumio sudo[8042]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:26 volumio sudo[8011]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:26 volumio volumio[1410]: info: Output device has changed, restarting Shairport Sync Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 04:38:26 volumio sudo[8047]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 04:38:26 volumio sudo[8047]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:26 volumio sudo[7999]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:26 volumio volumio[1410]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 04:38:26 volumio volumio[1410]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:26 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 27 04:38:26 volumio sudo[8024]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:26 volumio sudo[8026]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:26 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 27 04:38:26 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 04:38:26 volumio systemd[1]: shairport-sync.service: Consumed 1.352s CPU time. Aug 27 04:38:26 volumio sudo[8036]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:26 volumio volumio[1410]: info: Preparing to generate the ALSA configuration file Aug 27 04:38:26 volumio sudo[8074]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 04:38:26 volumio sudo[8074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:26 volumio volumio[1410]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 27 04:38:26 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 04:38:26 volumio volumio[1410]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 27 04:38:26 volumio volumio[1410]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Aug 27 04:38:26 volumio volumio[1410]: info: Reading ALSA contributions from plugins. Aug 27 04:38:26 volumio sudo[8079]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 04:38:26 volumio sudo[8079]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:26 volumio volumio[1410]: info: MPD Permissions set Aug 27 04:38:26 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 27 04:38:26 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 04:38:26 volumio volumio[1410]: info: TidalConnect service stoped! Aug 27 04:38:26 volumio volumio[1410]: info: peppymeterbasic Daemon Stop Aug 27 04:38:26 volumio volumio[1410]: info: Executing endpoint tc_getconfig Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 27 04:38:26 volumio sudo[8034]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:26 volumio vtcs[8041]: STARTING TidalConnect services, version: 1.6.1 Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:26 volumio sudo[8031]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:26 volumio vtcs[8041]: STARTED TidalConnect services. Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:26 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 27 04:38:26 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 04:38:26 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 04:38:26 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 04:38:26 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 04:38:26 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:26 volumio systemd[1]: vtcs.service: Deactivated successfully. Aug 27 04:38:26 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Aug 27 04:38:26 volumio volumio[1410]: info: Shairport-Sync Started Aug 27 04:38:26 volumio volumio[1410]: (node:1410) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Aug 27 04:38:26 volumio volumio[1410]: (Use `node --trace-warnings ...` to show where the warning was created) Aug 27 04:38:26 volumio volumio[1410]: (node:1410) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Aug 27 04:38:26 volumio volumio[1410]: (node:1410) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Aug 27 04:38:26 volumio volumio[1410]: (node:1410) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Aug 27 04:38:26 volumio volumio[1410]: (node:1410) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Aug 27 04:38:26 volumio volumio[1410]: info: Shairport-Sync Started Aug 27 04:38:26 volumio volumio[1410]: info: Executing endpoint tc_connect Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Aug 27 04:38:26 volumio volumio[1410]: info: Connecting to TidalConnect Aug 27 04:38:26 volumio volumio[1410]: info: TidalConnect service stoped! Aug 27 04:38:26 volumio sudo[8074]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:26 volumio volumio[1410]: info: Starting Shairport Sync Aug 27 04:38:26 volumio sudo[8079]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:26 volumio volumio[1410]: info: Asound.conf file unchanged, so no further update is needed Aug 27 04:38:26 volumio volumio[1410]: info: Output device has changed, restarting MPD Aug 27 04:38:26 volumio sudo[8114]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 04:38:26 volumio sudo[8114]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:26 volumio volumio[1410]: info: Output device has changed, restarting Shairport Sync Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:26 volumio sudo[8110]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 04:38:26 volumio sudo[8110]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 04:38:26 volumio sudo[8110]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:26 volumio sudo[8116]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 04:38:26 volumio sudo[8116]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:26 volumio sudo[8119]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 04:38:26 volumio sudo[8119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:26 volumio sudo[8116]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:26 volumio volumio[1410]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 04:38:26 volumio volumio[1410]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:26 volumio volumio[1410]: info: Preparing to generate the ALSA configuration file Aug 27 04:38:26 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 27 04:38:26 volumio sudo[8131]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 04:38:26 volumio volumio[1410]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 27 04:38:26 volumio volumio[1410]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 27 04:38:26 volumio volumio[1410]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Aug 27 04:38:26 volumio volumio[1410]: info: Reading ALSA contributions from plugins. Aug 27 04:38:26 volumio volumio[1410]: info: MPD Permissions set Aug 27 04:38:26 volumio volumio[1410]: info: TidalConnect service stoped! Aug 27 04:38:26 volumio sudo[8131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:26 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 27 04:38:26 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:26 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 04:38:26 volumio sudo[8114]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:26 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 27 04:38:26 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 04:38:26 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 27 04:38:26 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 04:38:26 volumio sudo[8133]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 04:38:26 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 04:38:26 volumio sudo[8133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:26 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:26 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Aug 27 04:38:26 volumio volumio[1410]: info: TidalConnect service stoped! Aug 27 04:38:26 volumio sudo[8131]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:26 volumio volumio[1410]: info: TidalConnect service stoped! Aug 27 04:38:26 volumio sudo[8162]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 04:38:26 volumio sudo[8162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:26 volumio volumio[1410]: info: Shairport-Sync Started Aug 27 04:38:26 volumio volumio[1410]: info: Starting Shairport Sync Aug 27 04:38:26 volumio sudo[8147]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 04:38:26 volumio sudo[8147]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 04:38:26 volumio sudo[8133]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:26 volumio sudo[8147]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:26 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 27 04:38:26 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 04:38:26 volumio volumio[1410]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 27 04:38:26 volumio volumio[1410]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 27 04:38:26 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 27 04:38:26 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 04:38:26 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 04:38:26 volumio volumio[1410]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 27 04:38:26 volumio volumio[1410]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 27 04:38:26 volumio volumio[1410]: info: Asound.conf file unchanged, so no further update is needed Aug 27 04:38:26 volumio volumio[1410]: info: Output device has changed, restarting MPD Aug 27 04:38:26 volumio sudo[8185]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 04:38:26 volumio sudo[8185]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:26 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 04:38:26 volumio volumio[1410]: info: Output device has changed, restarting Shairport Sync Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 04:38:26 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 04:38:26 volumio sudo[8198]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 04:38:26 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 27 04:38:26 volumio sudo[8198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:26 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 27 04:38:26 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 04:38:26 volumio systemd[1]: shairport-sync.service: Start request repeated too quickly. Aug 27 04:38:26 volumio systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Aug 27 04:38:26 volumio systemd[1]: Failed to start shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 04:38:26 volumio volumio[1410]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 04:38:26 volumio sudo[8185]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:26 volumio sudo[8192]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 04:38:26 volumio volumio[1410]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 27 04:38:26 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:26 volumio sudo[8192]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:26 volumio sudo[8192]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:26 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 27 04:38:26 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 04:38:26 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 27 04:38:27 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 04:38:27 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 04:38:27 volumio volumio[1410]: info: Preparing to generate the ALSA configuration file Aug 27 04:38:27 volumio sudo[8206]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 04:38:27 volumio sudo[8206]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:27 volumio volumio[1410]: info: Sending Spotify command with payload to local API: /player/volume Aug 27 04:38:27 volumio sudo[8210]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 04:38:27 volumio sudo[8210]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:27 volumio volumio[1410]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 27 04:38:27 volumio volumio[1410]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 27 04:38:27 volumio volumio[1410]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Aug 27 04:38:27 volumio volumio[1410]: info: Reading ALSA contributions from plugins. Aug 27 04:38:27 volumio sudo[8212]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 27 04:38:27 volumio sudo[8212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:27 volumio volumio[1410]: info: MPD Permissions set Aug 27 04:38:27 volumio volumio[1410]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Aug 27 04:38:27 volumio volumio[1410]: Job for shairport-sync.service failed because start of the service was attempted too often. Aug 27 04:38:27 volumio volumio[1410]: See "systemctl status shairport-sync.service" and "journalctl -xeu shairport-sync.service" for details. Aug 27 04:38:27 volumio volumio[1410]: To force a start use "systemctl reset-failed shairport-sync.service" Aug 27 04:38:27 volumio volumio[1410]: followed by "systemctl start shairport-sync.service" again. Aug 27 04:38:27 volumio volumio[1410]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 27 04:38:27 volumio volumio[1410]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 27 04:38:27 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 04:38:27 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:27 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:27 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:27 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:27 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:27 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:27 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:27 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:27 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 04:38:27 volumio sudo[8216]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 27 04:38:27 volumio sudo[8216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:27 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 04:38:27 volumio volumio[1410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 04:38:27 volumio volumio[1410]: info: Starting Shairport Sync Aug 27 04:38:27 volumio sudo[8206]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:27 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 27 04:38:27 volumio sudo[8226]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 27 04:38:27 volumio sudo[8226]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:27 volumio sudo[8210]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:27 volumio sudo[8212]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:27 volumio sudo[8216]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:27 volumio sudo[8217]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 04:38:27 volumio sudo[8217]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 04:38:27 volumio volumio[1410]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Aug 27 04:38:27 volumio volumio[1410]: Job for vtcs.service canceled. Aug 27 04:38:27 volumio volumio[1410]: {"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":""} Aug 27 04:38:27 volumio volumio[1410]: info: TidalConnect service stoped! Aug 27 04:38:27 volumio volumio[1410]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 04:38:27 volumio volumio[1410]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Aug 27 04:38:27 volumio volumio[1410]: Job for vtcs.service canceled. Aug 27 04:38:27 volumio volumio[1410]: at ChildProcess.exithandler (node:child_process:421:12) Aug 27 04:38:27 volumio volumio[1410]: at ChildProcess.emit (node:events:514:28) Aug 27 04:38:27 volumio volumio[1410]: at maybeClose (node:internal/child_process:1105:16) Aug 27 04:38:27 volumio volumio[1410]: at Socket. (node:internal/child_process:457:11) Aug 27 04:38:27 volumio volumio[1410]: at Socket.emit (node:events:514:28) Aug 27 04:38:27 volumio volumio[1410]: at Pipe. (node:net:337:12) { Aug 27 04:38:27 volumio volumio[1410]: code: 1, Aug 27 04:38:27 volumio volumio[1410]: killed: false, Aug 27 04:38:27 volumio volumio[1410]: signal: null, Aug 27 04:38:27 volumio volumio[1410]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Aug 27 04:38:27 volumio volumio[1410]: stdout: '', Aug 27 04:38:27 volumio volumio[1410]: stderr: 'Job for vtcs.service canceled.\n' Aug 27 04:38:27 volumio volumio[1410]: } Aug 27 04:38:27 volumio volumio[1410]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 04:38:27 volumio sudo[8217]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:27 volumio sudo[8233]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 04:38:27 volumio sudo[8233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 04:38:27 volumio sudo[8226]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:27 volumio systemd[1]: shairport-sync.service: Start request repeated too quickly. Aug 27 04:38:27 volumio systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Aug 27 04:38:27 volumio systemd[1]: Failed to start shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 04:38:27 volumio sudo[8233]: pam_unix(sudo:session): session closed for user root Aug 27 04:38:27 volumio sudo[8252]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-27 04:37' Aug 27 04:38:27 volumio sudo[8252]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"