Feb 21 17:02:17 volumio-pi-5-smsl-su-1 volumio[1326]: error: error Feb 21 17:02:17 volumio-pi-5-smsl-su-1 volumio[1326]: error: error Feb 21 17:02:19 volumio-pi-5-smsl-su-1 go-librespot[1659]: time="2026-02-21T17:02:19-05:00" level=trace msg="sent dealer ping" Feb 21 17:02:19 volumio-pi-5-smsl-su-1 go-librespot[1659]: time="2026-02-21T17:02:19-05:00" level=trace msg="received dealer pong" Feb 21 17:02:49 volumio-pi-5-smsl-su-1 go-librespot[1659]: time="2026-02-21T17:02:49-05:00" level=trace msg="sent dealer ping" Feb 21 17:02:49 volumio-pi-5-smsl-su-1 go-librespot[1659]: time="2026-02-21T17:02:49-05:00" level=trace msg="received dealer pong" Feb 21 17:03:17 volumio-pi-5-smsl-su-1 volumio[1326]: error: error Feb 21 17:03:17 volumio-pi-5-smsl-su-1 volumio[1326]: error: error Feb 21 17:03:19 volumio-pi-5-smsl-su-1 go-librespot[1659]: time="2026-02-21T17:03:19-05:00" level=trace msg="received accesspoint ping" Feb 21 17:03:19 volumio-pi-5-smsl-su-1 go-librespot[1659]: time="2026-02-21T17:03:19-05:00" level=trace msg="received accesspoint pong ack" Feb 21 17:03:19 volumio-pi-5-smsl-su-1 go-librespot[1659]: time="2026-02-21T17:03:19-05:00" level=trace msg="sent dealer ping" Feb 21 17:03:19 volumio-pi-5-smsl-su-1 go-librespot[1659]: time="2026-02-21T17:03:19-05:00" level=trace msg="received dealer pong" Feb 21 17:03:49 volumio-pi-5-smsl-su-1 go-librespot[1659]: time="2026-02-21T17:03:49-05:00" level=trace msg="sent dealer ping" Feb 21 17:03:49 volumio-pi-5-smsl-su-1 go-librespot[1659]: time="2026-02-21T17:03:49-05:00" level=trace msg="received dealer pong" Feb 21 17:03:54 volumio-pi-5-smsl-su-1 kernel: usb 1-2: USB disconnect, device number 2 Feb 21 17:03:55 volumio-pi-5-smsl-su-1 kernel: usb 1-2: new high-speed USB device number 3 using xhci-hcd Feb 21 17:03:55 volumio-pi-5-smsl-su-1 kernel: usb 1-2: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.53 Feb 21 17:03:55 volumio-pi-5-smsl-su-1 kernel: usb 1-2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Feb 21 17:03:55 volumio-pi-5-smsl-su-1 kernel: usb 1-2: Product: SMSL USB AUDIO Feb 21 17:03:55 volumio-pi-5-smsl-su-1 kernel: usb 1-2: Manufacturer: SMSL Feb 21 17:03:56 volumio-pi-5-smsl-su-1 kernel: hid-generic 0003:152A:85DD.0002: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-xhci-hcd.0-2/input3 Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: ---------------------------- USB Audio Device Detached Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Feb 21 17:03:56 volumio-pi-5-smsl-su-1 mtp-probe[3228]: checking bus 1, device 3: "/sys/devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2" Feb 21 17:03:56 volumio-pi-5-smsl-su-1 mtp-probe[3228]: bus: 1, device: 3 was not an MTP device Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: ---------------------------- USB Audio Device Attached Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::Close All Modals sent Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: Preparing to save Alsa Options, stopping services first Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::volumioGetState Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CorePlayQueue::getTrack 0 Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::volumioPause Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreStateMachine::pause Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"SMSL USB AUDIO","alsacard":"AUDIO"},"i2s":false} Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: Setting default mixerSoftMaster for Softvolume device Feb 21 17:03:56 volumio-pi-5-smsl-su-1 vtcs[2045]: [2026-02-21 17:03:56.123] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Feb 21 17:03:56 volumio-pi-5-smsl-su-1 vtcs[2045]: [2026-02-21 17:03:56.123] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Feb 21 17:03:56 volumio-pi-5-smsl-su-1 sudo[3252]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 17:03:56 volumio-pi-5-smsl-su-1 sudo[3252]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: Disabling external Volume Control Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 17:03:56 volumio-pi-5-smsl-su-1 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: Enable softmixer device for audio device undefined Feb 21 17:03:56 volumio-pi-5-smsl-su-1 systemd[1]: vtcs.service: Deactivated successfully. Feb 21 17:03:56 volumio-pi-5-smsl-su-1 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Feb 21 17:03:56 volumio-pi-5-smsl-su-1 sudo[3252]: pam_unix(sudo:session): session closed for user root Feb 21 17:03:56 volumio-pi-5-smsl-su-1 sudo[3265]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 17:03:56 volumio-pi-5-smsl-su-1 sudo[3265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:03:56 volumio-pi-5-smsl-su-1 mtp-probe[3269]: checking bus 1, device 3: "/sys/devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2" Feb 21 17:03:56 volumio-pi-5-smsl-su-1 mtp-probe[3269]: bus: 1, device: 3 was not an MTP device Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: Software Volume ALSA configuration written Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: Preparing to generate the ALSA configuration file Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: Reading ALSA contributions from plugins. Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreStateMachine::pushState Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CorePlayQueue::getTrack 0 Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::volumioPushState Feb 21 17:03:56 volumio-pi-5-smsl-su-1 sudo[3265]: pam_unix(sudo:session): session closed for user root Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: FusionDsp - Volumio is not playing Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: FusionDsp - Clipped samples monitor stopped Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: SPOTIFY: SPOTIFY VOLUME 40 Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: SPOTIFY: VOLUMIO VOLUME 100 Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: Setting Spotify Volume from Volumio: 100 Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: Asound.conf file unchanged, so no further update is needed Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: Output device has changed, restarting MPD Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: Output device has changed, restarting Shairport Sync Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 17:03:56 volumio-pi-5-smsl-su-1 sudo[3279]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 17:03:56 volumio-pi-5-smsl-su-1 sudo[3279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:03:56 volumio-pi-5-smsl-su-1 sudo[3279]: pam_unix(sudo:session): session closed for user root Feb 21 17:03:56 volumio-pi-5-smsl-su-1 sudo[3281]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 17:03:56 volumio-pi-5-smsl-su-1 sudo[3281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 17:03:56 volumio-pi-5-smsl-su-1 sudo[3289]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 17:03:56 volumio-pi-5-smsl-su-1 sudo[3289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:03:56 volumio-pi-5-smsl-su-1 systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: MPD Permissions set Feb 21 17:03:56 volumio-pi-5-smsl-su-1 sudo[3291]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 17:03:56 volumio-pi-5-smsl-su-1 sudo[3291]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: Starting Shairport Sync Feb 21 17:03:56 volumio-pi-5-smsl-su-1 sudo[3289]: pam_unix(sudo:session): session closed for user root Feb 21 17:03:56 volumio-pi-5-smsl-su-1 sudo[3300]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 17:03:56 volumio-pi-5-smsl-su-1 sudo[3300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:03:56 volumio-pi-5-smsl-su-1 sudo[3291]: pam_unix(sudo:session): session closed for user root Feb 21 17:03:56 volumio-pi-5-smsl-su-1 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 21 17:03:56 volumio-pi-5-smsl-su-1 systemd[1]: shairport-sync.service: Deactivated successfully. Feb 21 17:03:56 volumio-pi-5-smsl-su-1 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 17:03:56 volumio-pi-5-smsl-su-1 systemd[1]: shairport-sync.service: Consumed 1.863s CPU time. Feb 21 17:03:56 volumio-pi-5-smsl-su-1 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 17:03:56 volumio-pi-5-smsl-su-1 sudo[3300]: pam_unix(sudo:session): session closed for user root Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: Shairport-Sync Started Feb 21 17:03:56 volumio-pi-5-smsl-su-1 systemd[1]: mpd.service: Deactivated successfully. Feb 21 17:03:56 volumio-pi-5-smsl-su-1 systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 21 17:03:56 volumio-pi-5-smsl-su-1 systemd[1]: mpd.service: Consumed 3.226s CPU time. Feb 21 17:03:56 volumio-pi-5-smsl-su-1 systemd[1]: mpd.socket: Deactivated successfully. Feb 21 17:03:56 volumio-pi-5-smsl-su-1 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 21 17:03:56 volumio-pi-5-smsl-su-1 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 21 17:03:56 volumio-pi-5-smsl-su-1 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 21 17:03:56 volumio-pi-5-smsl-su-1 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 21 17:03:56 volumio-pi-5-smsl-su-1 sudo[3308]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 21 17:03:56 volumio-pi-5-smsl-su-1 sudo[3308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 21 17:03:56 volumio-pi-5-smsl-su-1 sudo[3308]: pam_unix(sudo:session): session closed for user root Feb 21 17:03:56 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Feb 21 17:03:57 volumio-pi-5-smsl-su-1 mpd[3324]: 2026-02-21T17:03:57 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 21 17:03:57 volumio-pi-5-smsl-su-1 systemd[1]: Started mpd.service - Music Player Daemon. Feb 21 17:03:57 volumio-pi-5-smsl-su-1 sudo[3281]: pam_unix(sudo:session): session closed for user root Feb 21 17:03:57 volumio-pi-5-smsl-su-1 volumio[1326]: error: updateQueue error: null Feb 21 17:03:57 volumio-pi-5-smsl-su-1 kernel: usb 1-2: USB disconnect, device number 3 Feb 21 17:03:57 volumio-pi-5-smsl-su-1 kernel: usb 1-2: new high-speed USB device number 4 using xhci-hcd Feb 21 17:03:57 volumio-pi-5-smsl-su-1 volumio[1326]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Feb 21 17:03:57 volumio-pi-5-smsl-su-1 volumio[1326]: info: Sending Spotify command with payload to local API: /player/volume Feb 21 17:03:57 volumio-pi-5-smsl-su-1 volumio[1326]: error: Failed to send command to Spotify local API: /player/volume: Error: Bad Request Feb 21 17:03:58 volumio-pi-5-smsl-su-1 kernel: usb 1-2: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.53 Feb 21 17:03:58 volumio-pi-5-smsl-su-1 kernel: usb 1-2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Feb 21 17:03:58 volumio-pi-5-smsl-su-1 kernel: usb 1-2: Product: SMSL USB AUDIO Feb 21 17:03:58 volumio-pi-5-smsl-su-1 kernel: usb 1-2: Manufacturer: SMSL Feb 21 17:03:58 volumio-pi-5-smsl-su-1 kernel: hid-generic 0003:152A:85DD.0003: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-xhci-hcd.0-2/input3 Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: ---------------------------- USB Audio Device Detached Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 17:03:58 volumio-pi-5-smsl-su-1 kernel: usb 1-2: USB disconnect, device number 4 Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Feb 21 17:03:58 volumio-pi-5-smsl-su-1 kernel: usb 1-2: new high-speed USB device number 5 using xhci-hcd Feb 21 17:03:58 volumio-pi-5-smsl-su-1 kernel: usb 1-2: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.53 Feb 21 17:03:58 volumio-pi-5-smsl-su-1 kernel: usb 1-2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Feb 21 17:03:58 volumio-pi-5-smsl-su-1 kernel: usb 1-2: Product: SMSL USB AUDIO Feb 21 17:03:58 volumio-pi-5-smsl-su-1 kernel: usb 1-2: Manufacturer: SMSL Feb 21 17:03:58 volumio-pi-5-smsl-su-1 kernel: hid-generic 0003:152A:85DD.0004: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-xhci-hcd.0-2/input3 Feb 21 17:03:58 volumio-pi-5-smsl-su-1 mtp-probe[3333]: checking bus 1, device 5: "/sys/devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2" Feb 21 17:03:58 volumio-pi-5-smsl-su-1 mtp-probe[3333]: bus: 1, device: 5 was not an MTP device Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: ---------------------------- USB Audio Device Attached Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::Close All Modals sent Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: Preparing to save Alsa Options, stopping services first Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::volumioGetState Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CorePlayQueue::getTrack 0 Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::volumioPause Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreStateMachine::pause Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"SMSL USB AUDIO","alsacard":"AUDIO"},"i2s":false} Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: Setting default mixerSoftMaster for Softvolume device Feb 21 17:03:58 volumio-pi-5-smsl-su-1 sudo[3353]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 17:03:58 volumio-pi-5-smsl-su-1 sudo[3353]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: Disabling external Volume Control Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: Enable softmixer device for audio device undefined Feb 21 17:03:58 volumio-pi-5-smsl-su-1 sudo[3353]: pam_unix(sudo:session): session closed for user root Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreStateMachine::pushState Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CorePlayQueue::getTrack 0 Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::volumioPushState Feb 21 17:03:58 volumio-pi-5-smsl-su-1 mtp-probe[3368]: checking bus 1, device 5: "/sys/devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2" Feb 21 17:03:58 volumio-pi-5-smsl-su-1 mtp-probe[3368]: bus: 1, device: 5 was not an MTP device Feb 21 17:03:58 volumio-pi-5-smsl-su-1 sudo[3367]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 17:03:58 volumio-pi-5-smsl-su-1 sudo[3367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: FusionDsp - Volumio is not playing Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: FusionDsp - Clipped samples monitor stopped Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: Software Volume ALSA configuration written Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: Preparing to generate the ALSA configuration file Feb 21 17:03:58 volumio-pi-5-smsl-su-1 sudo[3367]: pam_unix(sudo:session): session closed for user root Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: Reading ALSA contributions from plugins. Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: ---------------------------- USB Audio Device Detached Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: Asound.conf file unchanged, so no further update is needed Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: Output device has changed, restarting MPD Feb 21 17:03:58 volumio-pi-5-smsl-su-1 mtp-probe[3383]: checking bus 1, device 5: "/sys/devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2" Feb 21 17:03:58 volumio-pi-5-smsl-su-1 mtp-probe[3383]: bus: 1, device: 5 was not an MTP device Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: Output device has changed, restarting Shairport Sync Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 17:03:58 volumio-pi-5-smsl-su-1 sudo[3386]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 17:03:58 volumio-pi-5-smsl-su-1 sudo[3386]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 17:03:58 volumio-pi-5-smsl-su-1 sudo[3385]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 17:03:58 volumio-pi-5-smsl-su-1 sudo[3385]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:03:58 volumio-pi-5-smsl-su-1 sudo[3386]: pam_unix(sudo:session): session closed for user root Feb 21 17:03:58 volumio-pi-5-smsl-su-1 volumio[1326]: info: MPD Permissions set Feb 21 17:03:58 volumio-pi-5-smsl-su-1 sudo[3400]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 17:03:58 volumio-pi-5-smsl-su-1 sudo[3397]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 17:03:58 volumio-pi-5-smsl-su-1 sudo[3400]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:03:58 volumio-pi-5-smsl-su-1 sudo[3397]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: Starting Shairport Sync Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: ---------------------------- USB Audio Device Attached Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3409]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3409]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:03:59 volumio-pi-5-smsl-su-1 systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::Close All Modals sent Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: Preparing to save Alsa Options, stopping services first Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::volumioGetState Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CorePlayQueue::getTrack 0 Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::volumioPause Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreStateMachine::pause Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"SMSL USB AUDIO","alsacard":"AUDIO"},"i2s":false} Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3397]: pam_unix(sudo:session): session closed for user root Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3400]: pam_unix(sudo:session): session closed for user root Feb 21 17:03:59 volumio-pi-5-smsl-su-1 systemd[1]: mpd.service: Deactivated successfully. Feb 21 17:03:59 volumio-pi-5-smsl-su-1 systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 17:03:59 volumio-pi-5-smsl-su-1 systemd[1]: mpd.socket: Deactivated successfully. Feb 21 17:03:59 volumio-pi-5-smsl-su-1 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 21 17:03:59 volumio-pi-5-smsl-su-1 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: Setting default mixerSoftMaster for Softvolume device Feb 21 17:03:59 volumio-pi-5-smsl-su-1 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3431]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:03:59 volumio-pi-5-smsl-su-1 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 21 17:03:59 volumio-pi-5-smsl-su-1 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 21 17:03:59 volumio-pi-5-smsl-su-1 systemd[1]: shairport-sync.service: Deactivated successfully. Feb 21 17:03:59 volumio-pi-5-smsl-su-1 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 17:03:59 volumio-pi-5-smsl-su-1 systemd[1]: shairport-sync.service: Consumed 1.530s CPU time. Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: Disabling external Volume Control Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 17:03:59 volumio-pi-5-smsl-su-1 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: Enable softmixer device for audio device undefined Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3409]: pam_unix(sudo:session): session closed for user root Feb 21 17:03:59 volumio-pi-5-smsl-su-1 mtp-probe[3447]: checking bus 1, device 5: "/sys/devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1/1-2" Feb 21 17:03:59 volumio-pi-5-smsl-su-1 mtp-probe[3447]: bus: 1, device: 5 was not an MTP device Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: Shairport-Sync Started Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3431]: pam_unix(sudo:session): session closed for user root Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3446]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: Software Volume ALSA configuration written Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: Preparing to generate the ALSA configuration file Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: Reading ALSA contributions from plugins. Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreStateMachine::pushState Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CorePlayQueue::getTrack 0 Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::volumioPushState Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3446]: pam_unix(sudo:session): session closed for user root Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3436]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3436]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3436]: pam_unix(sudo:session): session closed for user root Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: FusionDsp - Volumio is not playing Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: FusionDsp - Clipped samples monitor stopped Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: TidalConnect service stoped! Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: TidalConnect service stoped! Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: Asound.conf file unchanged, so no further update is needed Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: Output device has changed, restarting MPD Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: Output device has changed, restarting Shairport Sync Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3478]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3478]: pam_unix(sudo:session): session closed for user root Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3480]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 17:03:59 volumio-pi-5-smsl-su-1 systemd[1]: mpd.service: Deactivated successfully. Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: MPD Permissions set Feb 21 17:03:59 volumio-pi-5-smsl-su-1 systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 21 17:03:59 volumio-pi-5-smsl-su-1 systemd[1]: mpd.socket: Deactivated successfully. Feb 21 17:03:59 volumio-pi-5-smsl-su-1 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 21 17:03:59 volumio-pi-5-smsl-su-1 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3489]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3489]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3490]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3490]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: TidalConnect service stoped! Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: Starting Shairport Sync Feb 21 17:03:59 volumio-pi-5-smsl-su-1 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3507]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3505]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:03:59 volumio-pi-5-smsl-su-1 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: TidalConnect service stoped! Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3490]: pam_unix(sudo:session): session closed for user root Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3521]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3489]: pam_unix(sudo:session): session closed for user root Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 17:03:59 volumio-pi-5-smsl-su-1 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: Job for vtcs.service canceled. Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: at ChildProcess.exithandler (node:child_process:421:12) Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: at ChildProcess.emit (node:events:514:28) Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: at maybeClose (node:internal/child_process:1105:16) Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) { Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: code: 1, Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: killed: false, Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: signal: null, Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: stdout: '', Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: stderr: 'Job for vtcs.service canceled.\n' Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: } Feb 21 17:03:59 volumio-pi-5-smsl-su-1 volumio[1326]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3510]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3510]: pam_unix(sudo:session): session closed for user root Feb 21 17:03:59 volumio-pi-5-smsl-su-1 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3507]: pam_unix(sudo:session): session closed for user root Feb 21 17:03:59 volumio-pi-5-smsl-su-1 systemd[1]: shairport-sync.service: Deactivated successfully. Feb 21 17:03:59 volumio-pi-5-smsl-su-1 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 17:03:59 volumio-pi-5-smsl-su-1 kernel: usb 1-2: USB disconnect, device number 5 Feb 21 17:03:59 volumio-pi-5-smsl-su-1 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3505]: pam_unix(sudo:session): session closed for user root Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3521]: pam_unix(sudo:session): session closed for user root Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3562]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-21 17:02' Feb 21 17:03:59 volumio-pi-5-smsl-su-1 sudo[3562]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"