Dec 27 18:02:40 volumio---pete vtcs[1745]: [close:85] Entering Dec 27 18:02:40 volumio---pete vtcs[1745]: [close:100] Exiting Dec 27 18:02:40 volumio---pete volumio[1160]: info: CoreCommandRouter::servicePushState Dec 27 18:02:40 volumio---pete volumio[1160]: info: CoreStateMachine::pushState Dec 27 18:02:40 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 18:02:40 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioPushState Dec 27 18:02:40 volumio---pete systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 27 18:02:41 volumio---pete systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 27 18:02:41 volumio---pete systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 27 18:02:41 volumio---pete vtcs[1745]: [2025-12-27 18:02:41.458] [tisoc] [warning] [PlaybackControllerImpl.cpp:220] Ignore play request because player state is already PLAYING. state_=5, playstate_=1, player_state_=0 Dec 27 18:02:44 volumio---pete vtcs[1745]: [2025-12-27 18:02:44.343] [tisoc] [warning] [PlaybackControllerImpl.cpp:220] Ignore play request because player state is already PLAYING. state_=5, playstate_=1, player_state_=0 Dec 27 18:02:45 volumio---pete vtcs[1745]: [2025-12-27 18:02:45.070] [tisoc] [warning] [PlaybackControllerImpl.cpp:220] Ignore play request because player state is already PLAYING. state_=5, playstate_=1, player_state_=0 Dec 27 18:02:47 volumio---pete vtcs[1745]: [2025-12-27 18:02:47.645] [tisoc] [warning] [PlaybackControllerImpl.cpp:220] Ignore play request because player state is already PLAYING. state_=5, playstate_=1, player_state_=0 Dec 27 18:02:51 volumio---pete kernel: usb 1-1.2: USB disconnect, device number 4 Dec 27 18:02:51 volumio---pete volumio[1160]: info: Dec 27 18:02:51 volumio---pete volumio[1160]: ---------------------------- USB Audio Device Detached Dec 27 18:02:51 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 27 18:02:51 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 27 18:02:51 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 27 18:02:51 volumio---pete kernel: usb 1-1.2: new high-speed USB device number 6 using dwc_otg Dec 27 18:02:52 volumio---pete kernel: usb 1-1.2: New USB device found, idVendor=20b1, idProduct=3079, bcdDevice= 0.03 Dec 27 18:02:52 volumio---pete kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 27 18:02:52 volumio---pete kernel: usb 1-1.2: Product: HIFI USB AUDIO Dec 27 18:02:52 volumio---pete kernel: usb 1-1.2: Manufacturer: SUCA Dec 27 18:02:52 volumio---pete (udev-worker)[11471]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 27 18:02:52 volumio---pete volumio[1160]: info: Dec 27 18:02:52 volumio---pete volumio[1160]: ---------------------------- USB Audio Device Attached Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::Close All Modals sent Dec 27 18:02:52 volumio---pete volumio[1160]: info: Preparing to save Alsa Options, stopping services first Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioGetState Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioPause Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreStateMachine::pause Dec 27 18:02:52 volumio---pete volumio[1160]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"HIFI USB AUDIO","alsacard":"AUDIO"},"i2s":false} Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 27 18:02:52 volumio---pete volumio[1160]: info: Setting mixer HIFI USB AUDIO for card HIFI USB AUDIO Dec 27 18:02:52 volumio---pete volumio[1160]: info: Relinquishing Volumio State Dec 27 18:02:52 volumio---pete volumio[1160]: verbose: UNSET VOLATILE: Service: tidalconnect Dec 27 18:02:52 volumio---pete volumio[1160]: info: unSetVolatile called Dec 27 18:02:52 volumio---pete volumio[1160]: info: Relinquishing Volumio State to another service Dec 27 18:02:52 volumio---pete vtcs[1745]: [2025-12-27 18:02:52.282] [tisoc] [critical] [QueueManagerImpl.cpp:1389] [ASSERT] cancelPendingMessages(0) Dec 27 18:02:52 volumio---pete vtcs[1745]: [close:85] Entering Dec 27 18:02:52 volumio---pete vtcs[1745]: [close:100] Exiting Dec 27 18:02:52 volumio---pete vtcs[1745]: [2025-12-27 18:02:52.288] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 27 18:02:52 volumio---pete vtcs[1745]: [2025-12-27 18:02:52.291] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 27 18:02:52 volumio---pete sudo[11496]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 18:02:52 volumio---pete sudo[11496]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 27 18:02:52 volumio---pete volumio[1160]: info: Updating Volume Controller Parameters: Device: 5 Name: HIFI USB AUDIO Mixer: HIFI USB AUDIO Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 27 18:02:52 volumio---pete volumio[1160]: info: Disabling external Volume Control Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 18:02:52 volumio---pete volumio[1160]: info: Preparing to generate the ALSA configuration file Dec 27 18:02:52 volumio---pete systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 27 18:02:52 volumio---pete systemd[1]: vtcs.service: Killing process 1747 (vtcs) with signal SIGKILL. Dec 27 18:02:52 volumio---pete systemd[1]: vtcs.service: Deactivated successfully. Dec 27 18:02:52 volumio---pete systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 27 18:02:52 volumio---pete systemd[1]: vtcs.service: Consumed 53.014s CPU time. Dec 27 18:02:52 volumio---pete sudo[11496]: pam_unix(sudo:session): session closed for user root Dec 27 18:02:52 volumio---pete sudo[11504]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 18:02:52 volumio---pete sudo[11504]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 27 18:02:52 volumio---pete sudo[11504]: pam_unix(sudo:session): session closed for user root Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 27 18:02:52 volumio---pete volumio[1160]: info: Asound.conf file unchanged, so no further update is needed Dec 27 18:02:52 volumio---pete volumio[1160]: info: Output device has changed, restarting MPD Dec 27 18:02:52 volumio---pete volumio[1160]: info: Output device has changed, restarting Shairport Sync Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 18:02:52 volumio---pete sudo[11519]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 18:02:52 volumio---pete sudo[11519]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:02:52 volumio---pete sudo[11519]: pam_unix(sudo:session): session closed for user root Dec 27 18:02:52 volumio---pete sudo[11521]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 18:02:52 volumio---pete sudo[11521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:02:52 volumio---pete volumio[1160]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 18:02:52 volumio---pete volumio[1160]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:02:52 volumio---pete systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 27 18:02:52 volumio---pete volumio[1160]: info: VolumeController:: Volume=86 Mute =false Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreStateMachine::pushState Dec 27 18:02:52 volumio---pete volumio[1160]: info: CorePlayQueue::getTrack 0 Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioPushState Dec 27 18:02:52 volumio---pete sudo[11531]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 18:02:52 volumio---pete sudo[11531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:02:52 volumio---pete sudo[11533]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 18:02:52 volumio---pete sudo[11533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:02:52 volumio---pete volumio[1160]: info: MPD Permissions set Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:02:52 volumio---pete systemd[1]: mpd.service: Deactivated successfully. Dec 27 18:02:52 volumio---pete systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 27 18:02:52 volumio---pete systemd[1]: mpd.service: Consumed 29.882s CPU time. Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:02:52 volumio---pete volumio[1160]: info: Starting Shairport Sync Dec 27 18:02:52 volumio---pete systemd[1]: mpd.socket: Deactivated successfully. Dec 27 18:02:52 volumio---pete systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 27 18:02:52 volumio---pete systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 27 18:02:52 volumio---pete systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 27 18:02:52 volumio---pete sudo[11543]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 18:02:52 volumio---pete systemd[1]: Starting mpd.service - Music Player Daemon... Dec 27 18:02:52 volumio---pete sudo[11543]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:02:52 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 27 18:02:52 volumio---pete sudo[11531]: pam_unix(sudo:session): session closed for user root Dec 27 18:02:52 volumio---pete sudo[11533]: pam_unix(sudo:session): session closed for user root Dec 27 18:02:52 volumio---pete systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 27 18:02:52 volumio---pete systemd[1]: shairport-sync.service: Deactivated successfully. Dec 27 18:02:52 volumio---pete systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 18:02:52 volumio---pete systemd[1]: shairport-sync.service: Consumed 3.135s CPU time. Dec 27 18:02:53 volumio---pete systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 18:02:53 volumio---pete sudo[11543]: pam_unix(sudo:session): session closed for user root Dec 27 18:02:53 volumio---pete volumio[1160]: info: Shairport-Sync Started Dec 27 18:02:53 volumio---pete sudo[11544]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 27 18:02:53 volumio---pete sudo[11544]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 27 18:02:53 volumio---pete sudo[11544]: pam_unix(sudo:session): session closed for user root Dec 27 18:02:55 volumio---pete volumio[1160]: info: TidalConnect service stoped! Dec 27 18:02:55 volumio---pete volumio[1160]: info: TidalConnect service stoped! Dec 27 18:02:55 volumio---pete volumio[1160]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 27 18:02:55 volumio---pete volumio[1160]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 27 18:02:55 volumio---pete sudo[11575]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 27 18:02:55 volumio---pete sudo[11575]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:02:55 volumio---pete systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 27 18:02:55 volumio---pete sudo[11575]: pam_unix(sudo:session): session closed for user root Dec 27 18:02:55 volumio---pete mpd[11563]: 2025-12-27T18:02:55 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 27 18:02:55 volumio---pete systemd[1]: Started mpd.service - Music Player Daemon. Dec 27 18:02:55 volumio---pete sudo[11521]: pam_unix(sudo:session): session closed for user root Dec 27 18:02:55 volumio---pete volumio[1160]: error: updateQueue error: null Dec 27 18:02:55 volumio---pete volumio[1160]: info: Executing endpoint tc_getconfig Dec 27 18:02:55 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 27 18:02:55 volumio---pete vtcs[11578]: STARTING TidalConnect services, version: 1.5.2.56 Dec 27 18:02:55 volumio---pete vtcs[11578]: STARTED TidalConnect services. Dec 27 18:02:55 volumio---pete volumio[1160]: info: Executing endpoint tc_connect Dec 27 18:02:55 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 27 18:02:55 volumio---pete volumio[1160]: info: Connecting to TidalConnect Dec 27 18:02:55 volumio---pete volumio[1160]: info: CoreCommandRouter::servicePushState Dec 27 18:02:55 volumio---pete volumio[1160]: info: CorePlayQueue::getTrack 0 Dec 27 18:02:55 volumio---pete volumio[1160]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Dec 27 18:02:55 volumio---pete volumio[1160]: info: CoreCommandRouter::servicePushState Dec 27 18:02:55 volumio---pete volumio[1160]: info: CorePlayQueue::getTrack 0 Dec 27 18:02:55 volumio---pete volumio[1160]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Dec 27 18:02:55 volumio---pete volumio[1160]: info: TidalConnect service stoped! Dec 27 18:02:55 volumio---pete volumio[1160]: info: TidalConnect service stoped! Dec 27 18:02:55 volumio---pete volumio[1160]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 27 18:02:55 volumio---pete volumio[1160]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 27 18:02:56 volumio---pete sudo[11605]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 27 18:02:56 volumio---pete sudo[11605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:02:56 volumio---pete sudo[11605]: pam_unix(sudo:session): session closed for user root Dec 27 18:02:56 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 27 18:02:56 volumio---pete volumio[1160]: info: CoreStateMachine::getcurrentVolume Dec 27 18:02:56 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 18:02:56 volumio---pete volumio[1160]: info: VolumeController:: Volume=86 Mute =false Dec 27 18:02:56 volumio---pete volumio[1160]: info: CoreStateMachine::pushState Dec 27 18:02:56 volumio---pete volumio[1160]: info: CorePlayQueue::getTrack 0 Dec 27 18:02:56 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioPushState Dec 27 18:02:56 volumio---pete volumio[1160]: info: CoreStateMachine::updateTrackBlock Dec 27 18:02:56 volumio---pete volumio[1160]: info: CorePlayQueue::getTrackBlock Dec 27 18:02:56 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 18:02:56 volumio---pete volumio[1160]: info: VolumeController:: Volume=86 Mute =false Dec 27 18:02:56 volumio---pete volumio[1160]: info: CoreStateMachine::pushState Dec 27 18:02:56 volumio---pete volumio[1160]: info: CorePlayQueue::getTrack 0 Dec 27 18:02:56 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioPushState Dec 27 18:02:58 volumio---pete volumio[1160]: info: TidalConnect service started! Dec 27 18:02:58 volumio---pete sudo[11613]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 27 18:02:58 volumio---pete sudo[11613]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:02:58 volumio---pete systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 27 18:02:58 volumio---pete systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 27 18:02:58 volumio---pete systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 27 18:02:58 volumio---pete systemd[1]: mpd_monitor.service: Consumed 47.378s CPU time. Dec 27 18:02:58 volumio---pete systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 27 18:02:58 volumio---pete mpd_monitor.sh[11615]: MPD Monitor Service: Starting MPD Monitor Service Dec 27 18:02:58 volumio---pete sudo[11613]: pam_unix(sudo:session): session closed for user root Dec 27 18:02:58 volumio---pete volumio[1160]: info: Successfully started MPD Monitor Dec 27 18:02:59 volumio---pete volumio[1160]: info: TidalConnect service started! Dec 27 18:03:09 volumio---pete kernel: usb 1-1.2: USB disconnect, device number 6 Dec 27 18:03:09 volumio---pete volumio[1160]: info: Dec 27 18:03:09 volumio---pete volumio[1160]: ---------------------------- USB Audio Device Detached Dec 27 18:03:09 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 27 18:03:09 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 27 18:03:09 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 27 18:03:09 volumio---pete kernel: usb 1-1.2: new high-speed USB device number 7 using dwc_otg Dec 27 18:03:10 volumio---pete kernel: usb 1-1.2: device descriptor read/64, error -71 Dec 27 18:03:10 volumio---pete kernel: usb 1-1.2: New USB device found, idVendor=20b1, idProduct=3079, bcdDevice= 0.03 Dec 27 18:03:10 volumio---pete kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 27 18:03:10 volumio---pete kernel: usb 1-1.2: Product: HIFI USB AUDIO Dec 27 18:03:10 volumio---pete kernel: usb 1-1.2: Manufacturer: SUCA Dec 27 18:03:10 volumio---pete (udev-worker)[11635]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 27 18:03:10 volumio---pete volumio[1160]: info: Dec 27 18:03:10 volumio---pete volumio[1160]: ---------------------------- USB Audio Device Attached Dec 27 18:03:10 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 27 18:03:10 volumio---pete volumio[1160]: info: CoreCommandRouter::Close All Modals sent Dec 27 18:03:10 volumio---pete volumio[1160]: info: Preparing to save Alsa Options, stopping services first Dec 27 18:03:10 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioGetState Dec 27 18:03:10 volumio---pete volumio[1160]: info: CorePlayQueue::getTrack 0 Dec 27 18:03:10 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioPause Dec 27 18:03:10 volumio---pete volumio[1160]: info: CoreStateMachine::pause Dec 27 18:03:10 volumio---pete volumio[1160]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"HIFI USB AUDIO","alsacard":"AUDIO"},"i2s":false} Dec 27 18:03:10 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 27 18:03:11 volumio---pete volumio[1160]: info: Setting mixer HIFI USB AUDIO for card HIFI USB AUDIO Dec 27 18:03:11 volumio---pete vtcs[11578]: [2025-12-27 18:03:11.036] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 27 18:03:11 volumio---pete vtcs[11578]: [2025-12-27 18:03:11.036] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 27 18:03:11 volumio---pete sudo[11660]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 18:03:11 volumio---pete sudo[11660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 27 18:03:11 volumio---pete volumio[1160]: info: Updating Volume Controller Parameters: Device: 5 Name: HIFI USB AUDIO Mixer: HIFI USB AUDIO Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 27 18:03:11 volumio---pete volumio[1160]: info: Disabling external Volume Control Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 18:03:11 volumio---pete volumio[1160]: info: Preparing to generate the ALSA configuration file Dec 27 18:03:11 volumio---pete systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 27 18:03:11 volumio---pete systemd[1]: vtcs.service: Killing process 11585 (vtcs) with signal SIGKILL. Dec 27 18:03:11 volumio---pete systemd[1]: vtcs.service: Deactivated successfully. Dec 27 18:03:11 volumio---pete systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 27 18:03:11 volumio---pete sudo[11660]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:11 volumio---pete sudo[11670]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 18:03:11 volumio---pete sudo[11670]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 27 18:03:11 volumio---pete sudo[11670]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:11 volumio---pete kernel: usb 1-1.2: USB disconnect, device number 7 Dec 27 18:03:11 volumio---pete volumio[1160]: ALSA lib confmisc.c:855:(parse_card) cannot find card '5' Dec 27 18:03:11 volumio---pete volumio[1160]: ALSA lib conf.c:5180:(_snd_config_evaluate) function snd_func_card_inum returned error: No such file or directory Dec 27 18:03:11 volumio---pete volumio[1160]: ALSA lib confmisc.c:422:(snd_func_concat) error evaluating strings Dec 27 18:03:11 volumio---pete volumio[1160]: ALSA lib conf.c:5180:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory Dec 27 18:03:11 volumio---pete volumio[1160]: ALSA lib confmisc.c:1334:(snd_func_refer) error evaluating name Dec 27 18:03:11 volumio---pete volumio[1160]: ALSA lib conf.c:5180:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory Dec 27 18:03:11 volumio---pete volumio[1160]: ALSA lib conf.c:5703:(snd_config_expand) Evaluate error: No such file or directory Dec 27 18:03:11 volumio---pete volumio[1160]: ALSA lib control.c:1528:(snd_ctl_open_noupdate) Invalid CTL sysdefault:5 Dec 27 18:03:11 volumio---pete volumio[1160]: amixer: Mixer attach sysdefault:5 error: No such file or directory Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 27 18:03:11 volumio---pete volumio[1160]: info: Asound.conf file unchanged, so no further update is needed Dec 27 18:03:11 volumio---pete volumio[1160]: info: Output device has changed, restarting MPD Dec 27 18:03:11 volumio---pete volumio[1160]: info: Output device has changed, restarting Shairport Sync Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 18:03:11 volumio---pete sudo[11697]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 18:03:11 volumio---pete sudo[11697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:11 volumio---pete sudo[11697]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:11 volumio---pete sudo[11699]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 18:03:11 volumio---pete sudo[11699]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:11 volumio---pete volumio[1160]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 18:03:11 volumio---pete volumio[1160]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:11 volumio---pete systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 27 18:03:11 volumio---pete volumio[1160]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Dec 27 18:03:11 volumio---pete volumio[1160]: info: MPD Permissions set Dec 27 18:03:11 volumio---pete volumio[1160]: info: VolumeController:: Volume=86 Mute =false Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreStateMachine::pushState Dec 27 18:03:11 volumio---pete volumio[1160]: info: CorePlayQueue::getTrack 0 Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioPushState Dec 27 18:03:11 volumio---pete sudo[11709]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 18:03:11 volumio---pete sudo[11709]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:11 volumio---pete volumio[1160]: info: Dec 27 18:03:11 volumio---pete volumio[1160]: ---------------------------- USB Audio Device Detached Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 27 18:03:11 volumio---pete sudo[11711]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 18:03:11 volumio---pete sudo[11711]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 27 18:03:11 volumio---pete systemd[1]: mpd.service: Deactivated successfully. Dec 27 18:03:11 volumio---pete systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 27 18:03:11 volumio---pete systemd[1]: mpd.service: Consumed 2.790s CPU time. Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:11 volumio---pete systemd[1]: mpd.socket: Deactivated successfully. Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:11 volumio---pete systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 27 18:03:11 volumio---pete systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: Starting Shairport Sync Dec 27 18:03:11 volumio---pete volumio[1160]: info: Preparing to generate the ALSA configuration file Dec 27 18:03:11 volumio---pete kernel: usb 1-1.2: new high-speed USB device number 8 using dwc_otg Dec 27 18:03:11 volumio---pete volumio[1160]: info: Asound.conf file unchanged, so no further update is needed Dec 27 18:03:11 volumio---pete volumio[1160]: info: Output device has changed, restarting MPD Dec 27 18:03:11 volumio---pete sudo[11724]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 18:03:11 volumio---pete sudo[11724]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:11 volumio---pete systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 27 18:03:11 volumio---pete volumio[1160]: info: Output device has changed, restarting Shairport Sync Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 18:03:11 volumio---pete sudo[11727]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 18:03:11 volumio---pete systemd[1]: Starting mpd.service - Music Player Daemon... Dec 27 18:03:11 volumio---pete sudo[11727]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:11 volumio---pete sudo[11727]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:11 volumio---pete sudo[11730]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 18:03:11 volumio---pete sudo[11730]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:11 volumio---pete volumio[1160]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 18:03:11 volumio---pete volumio[1160]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:11 volumio---pete kernel: usb 1-1.2: New USB device found, idVendor=20b1, idProduct=3079, bcdDevice= 0.03 Dec 27 18:03:11 volumio---pete kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 27 18:03:11 volumio---pete kernel: usb 1-1.2: Product: HIFI USB AUDIO Dec 27 18:03:11 volumio---pete kernel: usb 1-1.2: Manufacturer: SUCA Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 27 18:03:11 volumio---pete volumio[1160]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Dec 27 18:03:11 volumio---pete sudo[11709]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:11 volumio---pete volumio[1160]: info: MPD Permissions set Dec 27 18:03:11 volumio---pete (udev-worker)[11634]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 27 18:03:11 volumio---pete systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:11 volumio---pete systemd[1]: shairport-sync.service: Deactivated successfully. Dec 27 18:03:11 volumio---pete systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 18:03:11 volumio---pete systemd[1]: shairport-sync.service: Consumed 1.659s CPU time. Dec 27 18:03:11 volumio---pete systemd[1]: mpd.service: Deactivated successfully. Dec 27 18:03:11 volumio---pete systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 27 18:03:11 volumio---pete sudo[11740]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 18:03:11 volumio---pete sudo[11740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:11 volumio---pete sudo[11744]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 18:03:11 volumio---pete sudo[11744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:11 volumio---pete volumio[1160]: info: Starting Shairport Sync Dec 27 18:03:11 volumio---pete sudo[11711]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:11 volumio---pete volumio[1160]: info: Dec 27 18:03:11 volumio---pete volumio[1160]: ---------------------------- USB Audio Device Attached Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 27 18:03:11 volumio---pete sudo[11756]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 18:03:11 volumio---pete sudo[11756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:11 volumio---pete systemd[1]: mpd.socket: Deactivated successfully. Dec 27 18:03:11 volumio---pete systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 27 18:03:11 volumio---pete systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::Close All Modals sent Dec 27 18:03:11 volumio---pete volumio[1160]: info: Preparing to save Alsa Options, stopping services first Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioGetState Dec 27 18:03:11 volumio---pete volumio[1160]: info: CorePlayQueue::getTrack 0 Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioPause Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreStateMachine::pause Dec 27 18:03:11 volumio---pete volumio[1160]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"HIFI USB AUDIO","alsacard":"AUDIO"},"i2s":false} Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 27 18:03:11 volumio---pete systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 27 18:03:11 volumio---pete volumio[1160]: info: Setting mixer HIFI USB AUDIO for card HIFI USB AUDIO Dec 27 18:03:11 volumio---pete systemd[1]: Starting mpd.service - Music Player Daemon... Dec 27 18:03:11 volumio---pete systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 18:03:11 volumio---pete sudo[11724]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 27 18:03:11 volumio---pete volumio[1160]: info: Updating Volume Controller Parameters: Device: 5 Name: HIFI USB AUDIO Mixer: HIFI USB AUDIO Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 27 18:03:11 volumio---pete volumio[1160]: info: Disabling external Volume Control Dec 27 18:03:11 volumio---pete volumio[1160]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 18:03:12 volumio---pete volumio[1160]: info: Preparing to generate the ALSA configuration file Dec 27 18:03:12 volumio---pete sudo[11777]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 18:03:12 volumio---pete sudo[11777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:12 volumio---pete systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 27 18:03:12 volumio---pete systemd[1]: shairport-sync.service: Deactivated successfully. Dec 27 18:03:12 volumio---pete systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 18:03:12 volumio---pete volumio[1160]: info: Shairport-Sync Started Dec 27 18:03:12 volumio---pete sudo[11796]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 18:03:12 volumio---pete sudo[11796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:12 volumio---pete sudo[11744]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:12 volumio---pete sudo[11740]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 27 18:03:12 volumio---pete sudo[11773]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 27 18:03:12 volumio---pete sudo[11773]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 27 18:03:12 volumio---pete sudo[11773]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 27 18:03:12 volumio---pete volumio[1160]: info: Asound.conf file unchanged, so no further update is needed Dec 27 18:03:12 volumio---pete volumio[1160]: info: Output device has changed, restarting MPD Dec 27 18:03:12 volumio---pete volumio[1160]: info: Output device has changed, restarting Shairport Sync Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 18:03:12 volumio---pete sudo[11810]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 18:03:12 volumio---pete sudo[11810]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:12 volumio---pete sudo[11810]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:12 volumio---pete sudo[11812]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 18:03:12 volumio---pete sudo[11812]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:12 volumio---pete systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 18:03:12 volumio---pete sudo[11756]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:12 volumio---pete volumio[1160]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 18:03:12 volumio---pete volumio[1160]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:12 volumio---pete sudo[11823]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 18:03:12 volumio---pete sudo[11823]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:12 volumio---pete sudo[11777]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:12 volumio---pete sudo[11837]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 18:03:12 volumio---pete systemd[1]: mpd.service: Deactivated successfully. Dec 27 18:03:12 volumio---pete systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 27 18:03:12 volumio---pete sudo[11837]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:12 volumio---pete volumio[1160]: info: MPD Permissions set Dec 27 18:03:12 volumio---pete volumio[1160]: info: Shairport-Sync Started Dec 27 18:03:12 volumio---pete volumio[1160]: info: VolumeController:: Volume=86 Mute =false Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreStateMachine::pushState Dec 27 18:03:12 volumio---pete volumio[1160]: info: CorePlayQueue::getTrack 0 Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioPushState Dec 27 18:03:12 volumio---pete sudo[11796]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:12 volumio---pete systemd[1]: mpd.socket: Deactivated successfully. Dec 27 18:03:12 volumio---pete systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 27 18:03:12 volumio---pete systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 27 18:03:12 volumio---pete volumio[1160]: info: Starting Shairport Sync Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 27 18:03:12 volumio---pete volumio[1160]: info: Preparing to generate the ALSA configuration file Dec 27 18:03:12 volumio---pete volumio[1160]: info: Asound.conf file unchanged, so no further update is needed Dec 27 18:03:12 volumio---pete volumio[1160]: info: Output device has changed, restarting MPD Dec 27 18:03:12 volumio---pete sudo[11851]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 18:03:12 volumio---pete sudo[11851]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:12 volumio---pete volumio[1160]: info: Output device has changed, restarting Shairport Sync Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 18:03:12 volumio---pete systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 27 18:03:12 volumio---pete systemd[1]: Starting mpd.service - Music Player Daemon... Dec 27 18:03:12 volumio---pete volumio[1160]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 18:03:12 volumio---pete sudo[11854]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 18:03:12 volumio---pete volumio[1160]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:12 volumio---pete sudo[11854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:12 volumio---pete sudo[11856]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 18:03:12 volumio---pete sudo[11854]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:12 volumio---pete sudo[11856]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:12 volumio---pete sudo[11837]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:12 volumio---pete volumio[1160]: info: MPD Permissions set Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:12 volumio---pete sudo[11823]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:12 volumio---pete sudo[11869]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 18:03:12 volumio---pete sudo[11869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:12 volumio---pete sudo[11867]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 18:03:12 volumio---pete sudo[11867]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:12 volumio---pete systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:12 volumio---pete systemd[1]: shairport-sync.service: Deactivated successfully. Dec 27 18:03:12 volumio---pete systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 18:03:12 volumio---pete systemd[1]: mpd.service: Deactivated successfully. Dec 27 18:03:12 volumio---pete systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 27 18:03:12 volumio---pete kernel: usb 1-1.2: USB disconnect, device number 8 Dec 27 18:03:12 volumio---pete volumio[1160]: info: Starting Shairport Sync Dec 27 18:03:12 volumio---pete sudo[11881]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 18:03:12 volumio---pete sudo[11881]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:12 volumio---pete volumio[1160]: info: Dec 27 18:03:12 volumio---pete volumio[1160]: ---------------------------- USB Audio Device Detached Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 27 18:03:12 volumio---pete systemd[1]: mpd.socket: Deactivated successfully. Dec 27 18:03:12 volumio---pete systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 27 18:03:12 volumio---pete systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 27 18:03:12 volumio---pete systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 27 18:03:12 volumio---pete systemd[1]: Starting mpd.service - Music Player Daemon... Dec 27 18:03:12 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 27 18:03:12 volumio---pete systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 18:03:12 volumio---pete sudo[11851]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:13 volumio---pete volumio[1160]: info: Shairport-Sync Started Dec 27 18:03:13 volumio---pete sudo[11867]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:13 volumio---pete sudo[11869]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:13 volumio---pete systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 27 18:03:13 volumio---pete systemd[1]: shairport-sync.service: Deactivated successfully. Dec 27 18:03:13 volumio---pete systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 18:03:13 volumio---pete sudo[11890]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 27 18:03:13 volumio---pete sudo[11890]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 27 18:03:13 volumio---pete sudo[11890]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:13 volumio---pete systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 18:03:13 volumio---pete sudo[11881]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:13 volumio---pete volumio[1160]: info: Shairport-Sync Started Dec 27 18:03:14 volumio---pete volumio[1160]: info: TidalConnect service stoped! Dec 27 18:03:14 volumio---pete volumio[1160]: info: TidalConnect service stoped! Dec 27 18:03:14 volumio---pete volumio[1160]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 27 18:03:14 volumio---pete volumio[1160]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 27 18:03:14 volumio---pete sudo[11936]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 27 18:03:14 volumio---pete sudo[11936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:14 volumio---pete systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 27 18:03:14 volumio---pete sudo[11936]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:14 volumio---pete volumio[1160]: info: Executing endpoint tc_getconfig Dec 27 18:03:14 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 27 18:03:14 volumio---pete vtcs[11939]: STARTING TidalConnect services, version: 1.5.2.56 Dec 27 18:03:14 volumio---pete vtcs[11939]: STARTED TidalConnect services. Dec 27 18:03:14 volumio---pete volumio[1160]: info: Executing endpoint tc_connect Dec 27 18:03:14 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 27 18:03:14 volumio---pete volumio[1160]: info: Connecting to TidalConnect Dec 27 18:03:14 volumio---pete volumio[1160]: info: CoreCommandRouter::servicePushState Dec 27 18:03:14 volumio---pete volumio[1160]: info: CorePlayQueue::getTrack 0 Dec 27 18:03:14 volumio---pete volumio[1160]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Dec 27 18:03:14 volumio---pete volumio[1160]: info: CoreCommandRouter::servicePushState Dec 27 18:03:14 volumio---pete volumio[1160]: info: CorePlayQueue::getTrack 0 Dec 27 18:03:14 volumio---pete volumio[1160]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Dec 27 18:03:14 volumio---pete volumio[1160]: info: TidalConnect service stoped! Dec 27 18:03:14 volumio---pete volumio[1160]: info: TidalConnect service stoped! Dec 27 18:03:14 volumio---pete kernel: usb 1-1.2: new high-speed USB device number 9 using dwc_otg Dec 27 18:03:14 volumio---pete volumio[1160]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 27 18:03:14 volumio---pete volumio[1160]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 27 18:03:14 volumio---pete sudo[11965]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 27 18:03:14 volumio---pete sudo[11965]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:14 volumio---pete sudo[11965]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreStateMachine::getcurrentVolume Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 18:03:15 volumio---pete volumio[1160]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 27 18:03:15 volumio---pete volumio[1160]: info: VolumeController:: Volume=undefined Mute =false Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreStateMachine::pushState Dec 27 18:03:15 volumio---pete volumio[1160]: info: CorePlayQueue::getTrack 0 Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioPushState Dec 27 18:03:15 volumio---pete kernel: usb 1-1.2: device descriptor read/64, error -71 Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreStateMachine::updateTrackBlock Dec 27 18:03:15 volumio---pete volumio[1160]: info: CorePlayQueue::getTrackBlock Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 18:03:15 volumio---pete volumio[1160]: info: TidalConnect service stoped! Dec 27 18:03:15 volumio---pete volumio[1160]: info: TidalConnect service stoped! Dec 27 18:03:15 volumio---pete volumio[1160]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 27 18:03:15 volumio---pete volumio[1160]: info: VolumeController:: Volume=undefined Mute =false Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreStateMachine::pushState Dec 27 18:03:15 volumio---pete volumio[1160]: info: CorePlayQueue::getTrack 0 Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioPushState Dec 27 18:03:15 volumio---pete volumio[1160]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 27 18:03:15 volumio---pete volumio[1160]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 27 18:03:15 volumio---pete sudo[11981]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 27 18:03:15 volumio---pete sudo[11981]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:15 volumio---pete sudo[11981]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:15 volumio---pete kernel: usb 1-1.2: New USB device found, idVendor=20b1, idProduct=3079, bcdDevice= 0.03 Dec 27 18:03:15 volumio---pete kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 27 18:03:15 volumio---pete kernel: usb 1-1.2: Product: HIFI USB AUDIO Dec 27 18:03:15 volumio---pete kernel: usb 1-1.2: Manufacturer: SUCA Dec 27 18:03:15 volumio---pete volumio[1160]: info: TidalConnect service stoped! Dec 27 18:03:15 volumio---pete (udev-worker)[11635]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Dec 27 18:03:15 volumio---pete volumio[1160]: info: TidalConnect service stoped! Dec 27 18:03:15 volumio---pete volumio[1160]: info: Dec 27 18:03:15 volumio---pete volumio[1160]: ---------------------------- USB Audio Device Attached Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::Close All Modals sent Dec 27 18:03:15 volumio---pete volumio[1160]: info: Preparing to save Alsa Options, stopping services first Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioGetState Dec 27 18:03:15 volumio---pete volumio[1160]: info: CorePlayQueue::getTrack 0 Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioPause Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreStateMachine::pause Dec 27 18:03:15 volumio---pete volumio[1160]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"HIFI USB AUDIO","alsacard":"AUDIO"},"i2s":false} Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 27 18:03:15 volumio---pete volumio[1160]: info: Setting mixer HIFI USB AUDIO for card HIFI USB AUDIO Dec 27 18:03:15 volumio---pete vtcs[11939]: [2025-12-27 18:03:15.588] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 27 18:03:15 volumio---pete vtcs[11939]: [2025-12-27 18:03:15.589] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Dec 27 18:03:15 volumio---pete vtcs[11939]: [2025-12-27 18:03:15.589] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 27 18:03:15 volumio---pete sudo[12004]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 18:03:15 volumio---pete sudo[12004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 27 18:03:15 volumio---pete volumio[1160]: info: Updating Volume Controller Parameters: Device: 5 Name: HIFI USB AUDIO Mixer: HIFI USB AUDIO Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 27 18:03:15 volumio---pete volumio[1160]: info: Disabling external Volume Control Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 18:03:15 volumio---pete systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 27 18:03:15 volumio---pete volumio[1160]: info: Preparing to generate the ALSA configuration file Dec 27 18:03:15 volumio---pete systemd[1]: vtcs.service: Deactivated successfully. Dec 27 18:03:15 volumio---pete systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 27 18:03:15 volumio---pete sudo[12004]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:15 volumio---pete volumio[1160]: info: TidalConnect service stoped! Dec 27 18:03:15 volumio---pete sudo[12011]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 18:03:15 volumio---pete sudo[12011]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:15 volumio---pete volumio[1160]: info: VolumeController:: Volume=100 Mute =false Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreStateMachine::pushState Dec 27 18:03:15 volumio---pete volumio[1160]: info: CorePlayQueue::getTrack 0 Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioPushState Dec 27 18:03:15 volumio---pete volumio[1160]: info: TidalConnect service stoped! Dec 27 18:03:15 volumio---pete volumio[1160]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 27 18:03:15 volumio---pete volumio[1160]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 27 18:03:15 volumio---pete volumio[1160]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 27 18:03:15 volumio---pete volumio[1160]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 27 18:03:15 volumio---pete sudo[12011]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 27 18:03:15 volumio---pete volumio[1160]: info: Asound.conf file unchanged, so no further update is needed Dec 27 18:03:15 volumio---pete volumio[1160]: info: Output device has changed, restarting MPD Dec 27 18:03:15 volumio---pete volumio[1160]: info: Output device has changed, restarting Shairport Sync Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:15 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 18:03:15 volumio---pete sudo[12036]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 18:03:15 volumio---pete sudo[12036]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:15 volumio---pete sudo[12036]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:15 volumio---pete sudo[12038]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 18:03:16 volumio---pete sudo[12038]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:16 volumio---pete volumio[1160]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 18:03:16 volumio---pete volumio[1160]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 27 18:03:16 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:16 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 27 18:03:16 volumio---pete volumio[1160]: info: CoreStateMachine::getcurrentVolume Dec 27 18:03:16 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 18:03:16 volumio---pete systemd[1]: mpd.service: Deactivated successfully. Dec 27 18:03:16 volumio---pete systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 27 18:03:16 volumio---pete systemd[1]: mpd.service: Consumed 2.996s CPU time. Dec 27 18:03:16 volumio---pete systemd[1]: mpd.socket: Deactivated successfully. Dec 27 18:03:16 volumio---pete systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 27 18:03:16 volumio---pete systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 27 18:03:16 volumio---pete volumio[1160]: info: MPD Permissions set Dec 27 18:03:16 volumio---pete sudo[12048]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 18:03:16 volumio---pete sudo[12048]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:16 volumio---pete sudo[12050]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 18:03:16 volumio---pete sudo[12050]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:16 volumio---pete sudo[12058]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 27 18:03:16 volumio---pete sudo[12058]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:16 volumio---pete systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 27 18:03:16 volumio---pete sudo[12060]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 27 18:03:16 volumio---pete sudo[12060]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:16 volumio---pete systemd[1]: Starting mpd.service - Music Player Daemon... Dec 27 18:03:16 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 27 18:03:16 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:16 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:16 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:16 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:16 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:16 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:16 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:16 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:16 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 18:03:16 volumio---pete volumio[1160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 18:03:16 volumio---pete volumio[1160]: info: TidalConnect service stoped! Dec 27 18:03:16 volumio---pete volumio[1160]: info: TidalConnect service stoped! Dec 27 18:03:16 volumio---pete volumio[1160]: info: VolumeController:: Volume=100 Mute =false Dec 27 18:03:16 volumio---pete volumio[1160]: info: CoreStateMachine::pushState Dec 27 18:03:16 volumio---pete volumio[1160]: info: CorePlayQueue::getTrack 0 Dec 27 18:03:16 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioPushState Dec 27 18:03:16 volumio---pete volumio[1160]: info: CoreStateMachine::updateTrackBlock Dec 27 18:03:16 volumio---pete volumio[1160]: info: CorePlayQueue::getTrackBlock Dec 27 18:03:16 volumio---pete volumio[1160]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 18:03:16 volumio---pete volumio[1160]: info: Starting Shairport Sync Dec 27 18:03:16 volumio---pete sudo[12058]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:16 volumio---pete sudo[12050]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:16 volumio---pete sudo[12048]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:16 volumio---pete sudo[12077]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 18:03:16 volumio---pete sudo[12077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 18:03:16 volumio---pete volumio[1160]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3 Dec 27 18:03:16 volumio---pete volumio[1160]: Job for vtcs.service canceled. Dec 27 18:03:16 volumio---pete volumio[1160]: {"cmd":"/usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3\nJob for vtcs.service canceled.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)","stderr":"Job for vtcs.service canceled.\n","stdout":""} Dec 27 18:03:16 volumio---pete volumio[1160]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Dec 27 18:03:16 volumio---pete volumio[1160]: Job for vtcs.service canceled. Dec 27 18:03:16 volumio---pete volumio[1160]: {"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":""} Dec 27 18:03:16 volumio---pete volumio[1160]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 27 18:03:16 volumio---pete volumio[1160]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 27 18:03:16 volumio---pete volumio[1160]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 18:03:16 volumio---pete volumio[1160]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Dec 27 18:03:16 volumio---pete volumio[1160]: Job for vtcs.service canceled. Dec 27 18:03:16 volumio---pete volumio[1160]: at ChildProcess.exithandler (node:child_process:421:12) Dec 27 18:03:16 volumio---pete volumio[1160]: at ChildProcess.emit (node:events:514:28) Dec 27 18:03:16 volumio---pete volumio[1160]: at maybeClose (node:internal/child_process:1105:16) Dec 27 18:03:16 volumio---pete volumio[1160]: at Socket. (node:internal/child_process:457:11) Dec 27 18:03:16 volumio---pete volumio[1160]: at Socket.emit (node:events:514:28) Dec 27 18:03:16 volumio---pete volumio[1160]: at Pipe. (node:net:337:12) { Dec 27 18:03:16 volumio---pete volumio[1160]: code: 1, Dec 27 18:03:16 volumio---pete volumio[1160]: killed: false, Dec 27 18:03:16 volumio---pete volumio[1160]: signal: null, Dec 27 18:03:16 volumio---pete volumio[1160]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Dec 27 18:03:16 volumio---pete volumio[1160]: stdout: '', Dec 27 18:03:16 volumio---pete volumio[1160]: stderr: 'Job for vtcs.service canceled.\n' Dec 27 18:03:16 volumio---pete volumio[1160]: } Dec 27 18:03:16 volumio---pete volumio[1160]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 18:03:16 volumio---pete systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 27 18:03:16 volumio---pete sudo[12060]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:16 volumio---pete sudo[12064]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 27 18:03:16 volumio---pete sudo[12064]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 27 18:03:16 volumio---pete sudo[12064]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:16 volumio---pete systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 27 18:03:16 volumio---pete systemd[1]: shairport-sync.service: Deactivated successfully. Dec 27 18:03:16 volumio---pete systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 18:03:16 volumio---pete systemd[1]: shairport-sync.service: Consumed 1.631s CPU time. Dec 27 18:03:16 volumio---pete systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 18:03:16 volumio---pete sudo[12077]: pam_unix(sudo:session): session closed for user root Dec 27 18:03:17 volumio---pete sudo[12120]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-27 18:02' Dec 27 18:03:17 volumio---pete sudo[12120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"