Apr 11 17:48:01 volumio4a kernel: usb 1-1.2: new high-speed USB device number 8 using xhci_hcd Apr 11 17:48:01 volumio4a kernel: usb 1-1.2: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.14 Apr 11 17:48:01 volumio4a kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Apr 11 17:48:01 volumio4a kernel: usb 1-1.2: Product: E30 II Apr 11 17:48:01 volumio4a kernel: usb 1-1.2: Manufacturer: Topping Apr 11 17:48:01 volumio4a volumio[1360]: info: Apr 11 17:48:01 volumio4a volumio[1360]: ---------------------------- USB Audio Device Attached Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::Close All Modals sent Apr 11 17:48:01 volumio4a volumio[1360]: info: Preparing to save Alsa Options, stopping services first Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::volumioGetState Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPause Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreStateMachine::pause Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreStateMachine::stPlaybackTimer Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreStateMachine::servicePause Apr 11 17:48:01 volumio4a volumio[1360]: info: CorePlayQueue::getTrack 0 Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::servicePause Apr 11 17:48:01 volumio4a volumio[1360]: info: [1775922481327] ControllerQobuz::pause Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 11 17:48:01 volumio4a volumio[1360]: info: ControllerMpd::pause Apr 11 17:48:01 volumio4a volumio[1360]: verbose: ControllerMpd::sendMpdCommand pause Apr 11 17:48:01 volumio4a volumio[1360]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"E30 II","alsacard":"II"},"i2s":false} Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:48:01 volumio4a volumio[1360]: info: Setting mixer E30 II for card E30 II Apr 11 17:48:01 volumio4a vtcs[2301]: [2026-04-11 17:48:01.381] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Apr 11 17:48:01 volumio4a vtcs[2301]: [2026-04-11 17:48:01.381] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 11 17:48:01 volumio4a sudo[2772]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 11 17:48:01 volumio4a sudo[2772]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 11 17:48:01 volumio4a volumio[1360]: info: Updating Volume Controller Parameters: Device: 5 Name: E30 II Mixer: E30 II Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 11 17:48:01 volumio4a volumio[1360]: info: Disabling external Volume Control Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 17:48:01 volumio4a volumio[1360]: info: Preparing to generate the ALSA configuration file Apr 11 17:48:01 volumio4a systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Apr 11 17:48:01 volumio4a systemd[1]: vtcs.service: Killing process 2308 (vtcs) with signal SIGKILL. Apr 11 17:48:01 volumio4a systemd[1]: vtcs.service: Deactivated successfully. Apr 11 17:48:01 volumio4a systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Apr 11 17:48:01 volumio4a sudo[2772]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:01 volumio4a sudo[2779]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 11 17:48:01 volumio4a sudo[2779]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:48:01 volumio4a sudo[2779]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 11 17:48:01 volumio4a volumio[1360]: info: Asound.conf file unchanged, so no further update is needed Apr 11 17:48:01 volumio4a volumio[1360]: info: Output device has changed, restarting MPD Apr 11 17:48:01 volumio4a volumio[1360]: info: Output device has changed, restarting Shairport Sync Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:48:01 volumio4a sudo[2791]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 17:48:01 volumio4a sudo[2791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:01 volumio4a sudo[2791]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:01 volumio4a sudo[2793]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 17:48:01 volumio4a sudo[2793]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:01 volumio4a volumio[1360]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 17:48:01 volumio4a systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 11 17:48:01 volumio4a volumio[1360]: info: Apr 11 17:48:01 volumio4a volumio[1360]: ---------------------------- MPD announces state update: player Apr 11 17:48:01 volumio4a volumio[1360]: info: sendMpdCommand pause took 327 milliseconds Apr 11 17:48:01 volumio4a volumio[1360]: info: ControllerMpd::getState Apr 11 17:48:01 volumio4a volumio[1360]: verbose: ControllerMpd::sendMpdCommand status Apr 11 17:48:01 volumio4a volumio[1360]: info: Apr 11 17:48:01 volumio4a volumio[1360]: ---------------------------- MPD announces state update: player Apr 11 17:48:01 volumio4a volumio[1360]: info: ControllerMpd::getState Apr 11 17:48:01 volumio4a volumio[1360]: verbose: ControllerMpd::sendMpdCommand status Apr 11 17:48:01 volumio4a volumio[1360]: info: Apr 11 17:48:01 volumio4a volumio[1360]: ---------------------------- MPD announces state update: player Apr 11 17:48:01 volumio4a volumio[1360]: info: ControllerMpd::getState Apr 11 17:48:01 volumio4a volumio[1360]: verbose: ControllerMpd::sendMpdCommand status Apr 11 17:48:01 volumio4a volumio[1360]: info: Apr 11 17:48:01 volumio4a volumio[1360]: ---------------------------- MPD announces state update: player Apr 11 17:48:01 volumio4a volumio[1360]: info: ControllerMpd::getState Apr 11 17:48:01 volumio4a volumio[1360]: verbose: ControllerMpd::sendMpdCommand status Apr 11 17:48:01 volumio4a volumio[1360]: info: MPD Permissions set Apr 11 17:48:01 volumio4a volumio[1360]: info: VolumeController:: Volume=100 Mute =false Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreStateMachine::pushState Apr 11 17:48:01 volumio4a sudo[2803]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPushState Apr 11 17:48:01 volumio4a sudo[2803]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:01 volumio4a sudo[2801]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 11 17:48:01 volumio4a sudo[2801]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:01 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:01.688+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" state=STATUS_PAUSED positionMs=114129 volume=100 Apr 11 17:48:01 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:01.690+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" id=qobuz://song/312575202 title="Close to the Edge: i. The Solid Time of Change, ii. Total Mass Retain, iii. I Get up I Get Down, iv. Seasons of Man " Apr 11 17:48:01 volumio4a volumio[1360]: error: MPD error: Error: read ECONNRESET Apr 11 17:48:01 volumio4a volumio[1360]: error: MPD error: Error: read ECONNRESET Apr 11 17:48:01 volumio4a volumio[1360]: error: MPD error: Error: read ECONNRESET Apr 11 17:48:01 volumio4a volumio[1360]: error: MPD error: Error: read ECONNRESET Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:01 volumio4a volumio[1360]: info: Starting Shairport Sync Apr 11 17:48:01 volumio4a sudo[2803]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:01 volumio4a sudo[2801]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:01 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Apr 11 17:48:03 volumio4a kernel: usb 1-1.2: USB disconnect, device number 8 Apr 11 17:48:03 volumio4a volumio[1360]: info: Apr 11 17:48:03 volumio4a volumio[1360]: ---------------------------- USB Audio Device Detached Apr 11 17:48:03 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Apr 11 17:48:03 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:48:04 volumio4a sudo[2814]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 17:48:04 volumio4a sudo[2814]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:04 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Apr 11 17:48:04 volumio4a systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 11 17:48:04 volumio4a systemd[1]: shairport-sync.service: Deactivated successfully. Apr 11 17:48:04 volumio4a systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 17:48:04 volumio4a systemd[1]: shairport-sync.service: Consumed 1.993s CPU time. Apr 11 17:48:04 volumio4a systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 17:48:04 volumio4a sudo[2814]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:04 volumio4a volumio[1360]: info: Shairport-Sync Started Apr 11 17:48:04 volumio4a volumio[1360]: info: TidalConnect service stoped! Apr 11 17:48:04 volumio4a systemd[1]: mpd.service: Deactivated successfully. Apr 11 17:48:04 volumio4a systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 11 17:48:04 volumio4a systemd[1]: mpd.service: Consumed 9.459s CPU time. Apr 11 17:48:04 volumio4a systemd[1]: mpd.socket: Deactivated successfully. Apr 11 17:48:04 volumio4a volumio[1360]: error: Upnp client error: Error: read ECONNRESET Apr 11 17:48:04 volumio4a systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 11 17:48:04 volumio4a systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 11 17:48:04 volumio4a volumio[1360]: info: TidalConnect service stoped! Apr 11 17:48:04 volumio4a systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 11 17:48:04 volumio4a systemd[1]: Starting mpd.service - Music Player Daemon... Apr 11 17:48:04 volumio4a volumio[1360]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 11 17:48:04 volumio4a volumio[1360]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 11 17:48:04 volumio4a sudo[2864]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 11 17:48:04 volumio4a sudo[2864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:04 volumio4a systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Apr 11 17:48:04 volumio4a sudo[2864]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:04 volumio4a sudo[2862]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 11 17:48:04 volumio4a sudo[2862]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 11 17:48:04 volumio4a sudo[2862]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:04 volumio4a volumio[1360]: info: TidalConnect service stoped! Apr 11 17:48:04 volumio4a volumio[1360]: info: TidalConnect service stoped! Apr 11 17:48:04 volumio4a volumio[1360]: info: Executing endpoint tc_getconfig Apr 11 17:48:04 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 11 17:48:04 volumio4a vtcs[2867]: STARTING TidalConnect services, version: 1.6.1 Apr 11 17:48:04 volumio4a vtcs[2867]: STARTED TidalConnect services. Apr 11 17:48:04 volumio4a volumio[1360]: info: Executing endpoint tc_connect Apr 11 17:48:04 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 11 17:48:04 volumio4a volumio[1360]: info: Connecting to TidalConnect Apr 11 17:48:04 volumio4a volumio[1360]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 11 17:48:04 volumio4a volumio[1360]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 11 17:48:04 volumio4a volumio[1360]: info: CoreCommandRouter::servicePushState Apr 11 17:48:04 volumio4a volumio[1360]: info: CoreStateMachine::pushState Apr 11 17:48:04 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPushState Apr 11 17:48:04 volumio4a volumio[1360]: info: CorePlayQueue::getTrack 0 Apr 11 17:48:04 volumio4a volumio[1360]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 11 17:48:04 volumio4a volumio[1360]: info: CoreCommandRouter::servicePushState Apr 11 17:48:04 volumio4a volumio[1360]: info: CoreStateMachine::pushState Apr 11 17:48:04 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPushState Apr 11 17:48:04 volumio4a volumio[1360]: info: CorePlayQueue::getTrack 0 Apr 11 17:48:04 volumio4a volumio[1360]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 11 17:48:04 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:04.857+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" state=STATUS_PAUSED positionMs=114129 volume=100 Apr 11 17:48:04 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:04.859+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" state=STATUS_PAUSED positionMs=114129 volume=100 Apr 11 17:48:04 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:04.861+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" id=qobuz://song/312575202 title="Close to the Edge: i. The Solid Time of Change, ii. Total Mass Retain, iii. I Get up I Get Down, iv. Seasons of Man " Apr 11 17:48:04 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:04.862+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" id=qobuz://song/312575202 title="Close to the Edge: i. The Solid Time of Change, ii. Total Mass Retain, iii. I Get up I Get Down, iv. Seasons of Man " Apr 11 17:48:04 volumio4a sudo[2893]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 11 17:48:04 volumio4a sudo[2893]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:04 volumio4a sudo[2893]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:05 volumio4a volumio[1360]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 11 17:48:05 volumio4a volumio[1360]: info: CoreStateMachine::getcurrentVolume Apr 11 17:48:05 volumio4a volumio[1360]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 17:48:05 volumio4a volumio[1360]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Apr 11 17:48:05 volumio4a volumio[1360]: info: VolumeController:: Volume=undefined Mute =false Apr 11 17:48:05 volumio4a volumio[1360]: info: CoreStateMachine::pushState Apr 11 17:48:05 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPushState Apr 11 17:48:05 volumio4a volumio[1360]: info: CoreStateMachine::updateTrackBlock Apr 11 17:48:05 volumio4a volumio[1360]: info: CorePlayQueue::getTrackBlock Apr 11 17:48:05 volumio4a volumio[1360]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 17:48:05 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:05.459+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" state=STATUS_PAUSED positionMs=114129 volume=100 Apr 11 17:48:05 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:05.461+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" id=qobuz://song/312575202 title="Close to the Edge: i. The Solid Time of Change, ii. Total Mass Retain, iii. I Get up I Get Down, iv. Seasons of Man " Apr 11 17:48:05 volumio4a volumio[1360]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Apr 11 17:48:05 volumio4a volumio[1360]: info: VolumeController:: Volume=undefined Mute =false Apr 11 17:48:05 volumio4a volumio[1360]: info: CoreStateMachine::pushState Apr 11 17:48:05 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPushState Apr 11 17:48:05 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:05.501+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" state=STATUS_PAUSED positionMs=114129 volume=100 Apr 11 17:48:05 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:05.502+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" id=qobuz://song/312575202 title="Close to the Edge: i. The Solid Time of Change, ii. Total Mass Retain, iii. I Get up I Get Down, iv. Seasons of Man " Apr 11 17:48:06 volumio4a mpd[2870]: 2026-04-11T17:48:06 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 11 17:48:06 volumio4a systemd[1]: Started mpd.service - Music Player Daemon. Apr 11 17:48:06 volumio4a sudo[2793]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:06 volumio4a volumio[1360]: error: updateQueue error: null Apr 11 17:48:06 volumio4a volumio[1360]: error: MPD error: The expression evaluated to a falsy value: Apr 11 17:48:06 volumio4a volumio[1360]: assert.ok(self.idling) Apr 11 17:48:06 volumio4a volumio[1360]: error: The expression evaluated to a falsy value: Apr 11 17:48:06 volumio4a volumio[1360]: assert.ok(self.idling) Apr 11 17:48:06 volumio4a volumio[1360]: error: MPD error: The expression evaluated to a falsy value: Apr 11 17:48:06 volumio4a volumio[1360]: assert.ok(self.idling) Apr 11 17:48:06 volumio4a volumio[1360]: error: The expression evaluated to a falsy value: Apr 11 17:48:06 volumio4a volumio[1360]: assert.ok(self.idling) Apr 11 17:48:06 volumio4a volumio[1360]: error: MPD error: The expression evaluated to a falsy value: Apr 11 17:48:06 volumio4a volumio[1360]: assert.ok(self.idling) Apr 11 17:48:06 volumio4a volumio[1360]: error: The expression evaluated to a falsy value: Apr 11 17:48:06 volumio4a volumio[1360]: assert.ok(self.idling) Apr 11 17:48:06 volumio4a volumio[1360]: error: updateQueue error: null Apr 11 17:48:06 volumio4a kernel: usb 1-1.2: new high-speed USB device number 9 using xhci_hcd Apr 11 17:48:07 volumio4a kernel: usb 1-1.2: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.14 Apr 11 17:48:07 volumio4a kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Apr 11 17:48:07 volumio4a kernel: usb 1-1.2: Product: E30 II Apr 11 17:48:07 volumio4a kernel: usb 1-1.2: Manufacturer: Topping Apr 11 17:48:07 volumio4a volumio[1360]: info: Apr 11 17:48:07 volumio4a volumio[1360]: ---------------------------- USB Audio Device Attached Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::Close All Modals sent Apr 11 17:48:07 volumio4a volumio[1360]: info: Preparing to save Alsa Options, stopping services first Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::volumioGetState Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPause Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreStateMachine::pause Apr 11 17:48:07 volumio4a volumio[1360]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"E30 II","alsacard":"II"},"i2s":false} Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:48:07 volumio4a volumio[1360]: info: Setting mixer E30 II for card E30 II Apr 11 17:48:07 volumio4a vtcs[2867]: [2026-04-11 17:48:07.281] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Apr 11 17:48:07 volumio4a vtcs[2867]: [2026-04-11 17:48:07.281] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 11 17:48:07 volumio4a volumio[1360]: info: Updating Volume Controller Parameters: Device: 5 Name: E30 II Mixer: E30 II Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 11 17:48:07 volumio4a volumio[1360]: info: Disabling external Volume Control Apr 11 17:48:07 volumio4a sudo[2925]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 11 17:48:07 volumio4a sudo[2925]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 17:48:07 volumio4a volumio[1360]: info: Preparing to generate the ALSA configuration file Apr 11 17:48:07 volumio4a systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Apr 11 17:48:07 volumio4a systemd[1]: vtcs.service: Deactivated successfully. Apr 11 17:48:07 volumio4a systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Apr 11 17:48:07 volumio4a sudo[2925]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:07 volumio4a sudo[2933]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 11 17:48:07 volumio4a sudo[2933]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:48:07 volumio4a sudo[2933]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 11 17:48:07 volumio4a volumio[1360]: info: Asound.conf file unchanged, so no further update is needed Apr 11 17:48:07 volumio4a volumio[1360]: info: Output device has changed, restarting MPD Apr 11 17:48:07 volumio4a volumio[1360]: info: Output device has changed, restarting Shairport Sync Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:48:07 volumio4a sudo[2944]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 17:48:07 volumio4a sudo[2944]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:07 volumio4a sudo[2944]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:07 volumio4a sudo[2946]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 17:48:07 volumio4a sudo[2946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:07 volumio4a volumio[1360]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 17:48:07 volumio4a systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 11 17:48:07 volumio4a volumio[1360]: info: MPD Permissions set Apr 11 17:48:07 volumio4a volumio[1360]: info: VolumeController:: Volume=100 Mute =false Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreStateMachine::pushState Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPushState Apr 11 17:48:07 volumio4a sudo[2954]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 11 17:48:07 volumio4a sudo[2954]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:07 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:07.502+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" state=STATUS_PAUSED positionMs=114129 volume=100 Apr 11 17:48:07 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:07.502+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" id=qobuz://song/312575202 title="Close to the Edge: i. The Solid Time of Change, ii. Total Mass Retain, iii. I Get up I Get Down, iv. Seasons of Man " Apr 11 17:48:07 volumio4a sudo[2957]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 11 17:48:07 volumio4a sudo[2957]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:07 volumio4a systemd[1]: mpd.service: Deactivated successfully. Apr 11 17:48:07 volumio4a systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 11 17:48:07 volumio4a systemd[1]: mpd.service: Consumed 1.505s CPU time. Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:07 volumio4a volumio[1360]: info: Starting Shairport Sync Apr 11 17:48:07 volumio4a systemd[1]: mpd.socket: Deactivated successfully. Apr 11 17:48:07 volumio4a systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 11 17:48:07 volumio4a systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 11 17:48:07 volumio4a sudo[2966]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 17:48:07 volumio4a sudo[2966]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:07 volumio4a systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 11 17:48:07 volumio4a systemd[1]: Starting mpd.service - Music Player Daemon... Apr 11 17:48:07 volumio4a systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 11 17:48:07 volumio4a volumio[1360]: info: TidalConnect service started! Apr 11 17:48:07 volumio4a systemd[1]: shairport-sync.service: Deactivated successfully. Apr 11 17:48:07 volumio4a systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 17:48:07 volumio4a systemd[1]: shairport-sync.service: Consumed 1.538s CPU time. Apr 11 17:48:07 volumio4a sudo[2957]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:07 volumio4a sudo[2954]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:07 volumio4a systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 17:48:07 volumio4a sudo[2966]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:07 volumio4a volumio[1360]: info: Shairport-Sync Started Apr 11 17:48:07 volumio4a sudo[2969]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 11 17:48:07 volumio4a sudo[2969]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 11 17:48:07 volumio4a sudo[2969]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:07 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Apr 11 17:48:07 volumio4a volumio[1360]: info: TidalConnect service started! Apr 11 17:48:09 volumio4a mpd[2988]: 2026-04-11T17:48:09 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 11 17:48:09 volumio4a systemd[1]: Started mpd.service - Music Player Daemon. Apr 11 17:48:09 volumio4a sudo[2946]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:09 volumio4a volumio[1360]: error: updateQueue error: null Apr 11 17:48:10 volumio4a volumio[1360]: info: TidalConnect service stoped! Apr 11 17:48:10 volumio4a volumio[1360]: info: TidalConnect service stoped! Apr 11 17:48:10 volumio4a volumio[1360]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 11 17:48:10 volumio4a volumio[1360]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 11 17:48:10 volumio4a sudo[3000]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 11 17:48:10 volumio4a sudo[3000]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:10 volumio4a systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Apr 11 17:48:10 volumio4a sudo[3000]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:10 volumio4a volumio[1360]: info: Executing endpoint tc_getconfig Apr 11 17:48:10 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 11 17:48:10 volumio4a vtcs[3003]: STARTING TidalConnect services, version: 1.6.1 Apr 11 17:48:10 volumio4a vtcs[3003]: STARTED TidalConnect services. Apr 11 17:48:10 volumio4a volumio[1360]: info: Executing endpoint tc_connect Apr 11 17:48:10 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 11 17:48:10 volumio4a volumio[1360]: info: Connecting to TidalConnect Apr 11 17:48:10 volumio4a volumio[1360]: info: CoreCommandRouter::servicePushState Apr 11 17:48:10 volumio4a volumio[1360]: info: CoreStateMachine::pushState Apr 11 17:48:10 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPushState Apr 11 17:48:10 volumio4a volumio[1360]: info: CorePlayQueue::getTrack 0 Apr 11 17:48:10 volumio4a volumio[1360]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 11 17:48:10 volumio4a volumio[1360]: info: CoreCommandRouter::servicePushState Apr 11 17:48:10 volumio4a volumio[1360]: info: CoreStateMachine::pushState Apr 11 17:48:10 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPushState Apr 11 17:48:10 volumio4a volumio[1360]: info: CorePlayQueue::getTrack 0 Apr 11 17:48:10 volumio4a volumio[1360]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 11 17:48:10 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:10.694+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" state=STATUS_PAUSED positionMs=114129 volume=100 Apr 11 17:48:10 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:10.694+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" state=STATUS_PAUSED positionMs=114129 volume=100 Apr 11 17:48:10 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:10.694+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" id=qobuz://song/312575202 title="Close to the Edge: i. The Solid Time of Change, ii. Total Mass Retain, iii. I Get up I Get Down, iv. Seasons of Man " Apr 11 17:48:10 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:10.694+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" id=qobuz://song/312575202 title="Close to the Edge: i. The Solid Time of Change, ii. Total Mass Retain, iii. I Get up I Get Down, iv. Seasons of Man " Apr 11 17:48:10 volumio4a volumio[1360]: info: TidalConnect service stoped! Apr 11 17:48:10 volumio4a volumio[1360]: info: TidalConnect service stoped! Apr 11 17:48:10 volumio4a volumio[1360]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 11 17:48:10 volumio4a volumio[1360]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 11 17:48:10 volumio4a sudo[3028]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 11 17:48:10 volumio4a sudo[3028]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:10 volumio4a sudo[3028]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:11 volumio4a volumio[1360]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 11 17:48:11 volumio4a volumio[1360]: info: CoreStateMachine::getcurrentVolume Apr 11 17:48:11 volumio4a volumio[1360]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 17:48:11 volumio4a volumio[1360]: info: VolumeController:: Volume=100 Mute =false Apr 11 17:48:11 volumio4a volumio[1360]: info: CoreStateMachine::pushState Apr 11 17:48:11 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPushState Apr 11 17:48:11 volumio4a volumio[1360]: info: CoreStateMachine::updateTrackBlock Apr 11 17:48:11 volumio4a volumio[1360]: info: CorePlayQueue::getTrackBlock Apr 11 17:48:11 volumio4a volumio[1360]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 17:48:11 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:11.350+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" state=STATUS_PAUSED positionMs=114129 volume=100 Apr 11 17:48:11 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:11.350+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" id=qobuz://song/312575202 title="Close to the Edge: i. The Solid Time of Change, ii. Total Mass Retain, iii. I Get up I Get Down, iv. Seasons of Man " Apr 11 17:48:11 volumio4a volumio[1360]: info: VolumeController:: Volume=100 Mute =false Apr 11 17:48:11 volumio4a volumio[1360]: info: CoreStateMachine::pushState Apr 11 17:48:11 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPushState Apr 11 17:48:11 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:11.383+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" state=STATUS_PAUSED positionMs=114129 volume=100 Apr 11 17:48:11 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:11.384+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" id=qobuz://song/312575202 title="Close to the Edge: i. The Solid Time of Change, ii. Total Mass Retain, iii. I Get up I Get Down, iv. Seasons of Man " Apr 11 17:48:13 volumio4a volumio[1360]: info: TidalConnect service started! Apr 11 17:48:13 volumio4a volumio[1360]: info: TidalConnect service started! Apr 11 17:48:26 volumio4a volumio[1360]: verbose: New Socket.io Connection to 192.168.1.20 from 192.168.1.5 UA: Mozilla/5.0 (Linux; Android 16; A059 Build/BQ2A.250721.001-BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.177 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Apr 11 17:48:26 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 11 17:48:26 volumio4a volumio[1360]: info: CoreCommandRouter::volumioGetVisibleSources Apr 11 17:48:26 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 17:48:26 volumio4a volumio[1360]: info: CoreCommandRouter::volumioGetState Apr 11 17:48:26 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 11 17:48:26 volumio4a volumio[1360]: info: CoreCommandRouter::volumioGetQueue Apr 11 17:48:26 volumio4a volumio[1360]: info: CoreStateMachine::getQueue Apr 11 17:48:26 volumio4a volumio[1360]: info: CorePlayQueue::getQueue Apr 11 17:48:26 volumio4a volumio[1360]: info: Listing playlists Apr 11 17:48:26 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 11 17:48:26 volumio4a volumio[1360]: info: Received Get System Info Apr 11 17:48:26 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 17:48:26 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 17:48:26 volumio4a volumio[1360]: info: Discovery: Getting this device information Apr 11 17:48:26 volumio4a volumio[1360]: info: CoreCommandRouter::volumioGetState Apr 11 17:48:26 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 17:48:26 volumio4a volumio[1360]: info: CoreCommandRouter::volumioGetState Apr 11 17:48:26 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 11 17:48:29 volumio4a volumio[1360]: info: CoreCommandRouter::volumioGetState Apr 11 17:48:29 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Apr 11 17:48:29 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPlay Apr 11 17:48:29 volumio4a volumio[1360]: info: CoreStateMachine::play index undefined Apr 11 17:48:29 volumio4a volumio[1360]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:48:29 volumio4a volumio[1360]: info: CorePlayQueue::getTrack 0 Apr 11 17:48:29 volumio4a volumio[1360]: info: CoreStateMachine::startPlaybackTimer Apr 11 17:48:29 volumio4a volumio[1360]: info: CorePlayQueue::getTrack 0 Apr 11 17:48:29 volumio4a volumio[1360]: info: [1775922509993] ControllerQobuz::resume Apr 11 17:48:29 volumio4a volumio[1360]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 11 17:48:29 volumio4a volumio[1360]: info: ControllerMpd::resume Apr 11 17:48:29 volumio4a volumio[1360]: verbose: ControllerMpd::sendMpdCommand play Apr 11 17:48:29 volumio4a volumio[1360]: info: sendMpdCommand play took 0 milliseconds Apr 11 17:48:39 volumio4a kernel: usb 1-1.2: USB disconnect, device number 9 Apr 11 17:48:39 volumio4a volumio[1360]: info: Apr 11 17:48:39 volumio4a volumio[1360]: ---------------------------- USB Audio Device Detached Apr 11 17:48:39 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Apr 11 17:48:39 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:48:39 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Apr 11 17:48:40 volumio4a kernel: usb 1-1.2: new high-speed USB device number 10 using xhci_hcd Apr 11 17:48:40 volumio4a kernel: usb 1-1.2: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.14 Apr 11 17:48:40 volumio4a kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Apr 11 17:48:40 volumio4a kernel: usb 1-1.2: Product: E30 II Apr 11 17:48:40 volumio4a kernel: usb 1-1.2: Manufacturer: Topping Apr 11 17:48:40 volumio4a volumio[1360]: info: Apr 11 17:48:40 volumio4a volumio[1360]: ---------------------------- USB Audio Device Attached Apr 11 17:48:40 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Apr 11 17:48:40 volumio4a volumio[1360]: info: CoreCommandRouter::Close All Modals sent Apr 11 17:48:40 volumio4a volumio[1360]: info: Preparing to save Alsa Options, stopping services first Apr 11 17:48:40 volumio4a volumio[1360]: info: CoreCommandRouter::volumioGetState Apr 11 17:48:40 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPause Apr 11 17:48:40 volumio4a volumio[1360]: info: CoreStateMachine::pause Apr 11 17:48:40 volumio4a volumio[1360]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"E30 II","alsacard":"II"},"i2s":false} Apr 11 17:48:40 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:48:40 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:48:40 volumio4a volumio[1360]: info: Setting mixer E30 II for card E30 II Apr 11 17:48:40 volumio4a vtcs[3003]: [2026-04-11 17:48:40.810] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 11 17:48:40 volumio4a vtcs[3003]: [2026-04-11 17:48:40.810] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Apr 11 17:48:40 volumio4a sudo[3109]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 11 17:48:40 volumio4a sudo[3109]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:40 volumio4a volumio[1360]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 11 17:48:40 volumio4a volumio[1360]: info: Updating Volume Controller Parameters: Device: 5 Name: E30 II Mixer: E30 II Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 11 17:48:40 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 11 17:48:40 volumio4a volumio[1360]: info: Disabling external Volume Control Apr 11 17:48:40 volumio4a volumio[1360]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 17:48:40 volumio4a volumio[1360]: info: Preparing to generate the ALSA configuration file Apr 11 17:48:40 volumio4a systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Apr 11 17:48:40 volumio4a systemd[1]: vtcs.service: Deactivated successfully. Apr 11 17:48:40 volumio4a systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Apr 11 17:48:40 volumio4a sudo[3109]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:40 volumio4a sudo[3117]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 11 17:48:40 volumio4a sudo[3117]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:40 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 11 17:48:40 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:48:40 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:48:40 volumio4a sudo[3117]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:40 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:48:40 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:48:40 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:48:40 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:48:40 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:48:40 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:48:40 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:48:40 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 11 17:48:40 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 11 17:48:40 volumio4a volumio[1360]: info: Asound.conf file unchanged, so no further update is needed Apr 11 17:48:40 volumio4a volumio[1360]: info: Output device has changed, restarting MPD Apr 11 17:48:40 volumio4a volumio[1360]: info: Output device has changed, restarting Shairport Sync Apr 11 17:48:40 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:40 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:48:40 volumio4a sudo[3128]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 17:48:40 volumio4a sudo[3128]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:40 volumio4a sudo[3128]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:40 volumio4a volumio[1360]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 17:48:40 volumio4a sudo[3132]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 17:48:40 volumio4a sudo[3132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:41 volumio4a sudo[3138]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 11 17:48:41 volumio4a sudo[3138]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:41 volumio4a volumio[1360]: info: MPD Permissions set Apr 11 17:48:41 volumio4a systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 11 17:48:41 volumio4a volumio[1360]: info: VolumeController:: Volume=100 Mute =false Apr 11 17:48:41 volumio4a volumio[1360]: info: CoreStateMachine::pushState Apr 11 17:48:41 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 17:48:41 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPushState Apr 11 17:48:41 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:41.031+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" state=STATUS_PAUSED positionMs=125023 volume=100 Apr 11 17:48:41 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:41.032+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" id=qobuz://song/312575202 title="Close to the Edge: i. The Solid Time of Change, ii. Total Mass Retain, iii. I Get up I Get Down, iv. Seasons of Man " Apr 11 17:48:41 volumio4a sudo[3140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 11 17:48:41 volumio4a sudo[3140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:41 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:41 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:41 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:41 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:41 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:41 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:41 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:41 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:41 volumio4a systemd[1]: mpd.service: Deactivated successfully. Apr 11 17:48:41 volumio4a systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 11 17:48:41 volumio4a systemd[1]: mpd.service: Consumed 1.422s CPU time. Apr 11 17:48:41 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:48:41 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:48:41 volumio4a sudo[3138]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:41 volumio4a volumio[1360]: info: Starting Shairport Sync Apr 11 17:48:41 volumio4a systemd[1]: mpd.socket: Deactivated successfully. Apr 11 17:48:41 volumio4a systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 11 17:48:41 volumio4a systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 11 17:48:41 volumio4a sudo[3150]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 17:48:41 volumio4a sudo[3150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:41 volumio4a systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 11 17:48:41 volumio4a systemd[1]: Starting mpd.service - Music Player Daemon... Apr 11 17:48:41 volumio4a sudo[3140]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:41 volumio4a systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 11 17:48:41 volumio4a systemd[1]: shairport-sync.service: Deactivated successfully. Apr 11 17:48:41 volumio4a systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 17:48:41 volumio4a systemd[1]: shairport-sync.service: Consumed 1.647s CPU time. Apr 11 17:48:41 volumio4a systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 17:48:41 volumio4a sudo[3150]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:41 volumio4a volumio[1360]: info: Shairport-Sync Started Apr 11 17:48:41 volumio4a sudo[3154]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 11 17:48:41 volumio4a sudo[3154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 11 17:48:41 volumio4a sudo[3154]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:41 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Apr 11 17:48:42 volumio4a mpd[3172]: 2026-04-11T17:48:42 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 11 17:48:42 volumio4a systemd[1]: Started mpd.service - Music Player Daemon. Apr 11 17:48:42 volumio4a sudo[3132]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:42 volumio4a volumio[1360]: error: updateQueue error: null Apr 11 17:48:43 volumio4a volumio[1360]: info: TidalConnect service stoped! Apr 11 17:48:43 volumio4a volumio[1360]: info: TidalConnect service stoped! Apr 11 17:48:43 volumio4a volumio[1360]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 11 17:48:43 volumio4a volumio[1360]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 11 17:48:43 volumio4a sudo[3198]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 11 17:48:43 volumio4a sudo[3198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:44 volumio4a volumio[1360]: info: TidalConnect service stoped! Apr 11 17:48:44 volumio4a systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Apr 11 17:48:44 volumio4a sudo[3198]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:44 volumio4a volumio[1360]: info: TidalConnect service stoped! Apr 11 17:48:44 volumio4a volumio[1360]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 11 17:48:44 volumio4a volumio[1360]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 11 17:48:44 volumio4a volumio[1360]: info: Executing endpoint tc_getconfig Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 11 17:48:44 volumio4a vtcs[3201]: STARTING TidalConnect services, version: 1.6.1 Apr 11 17:48:44 volumio4a vtcs[3201]: STARTED TidalConnect services. Apr 11 17:48:44 volumio4a volumio[1360]: info: Executing endpoint tc_connect Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 11 17:48:44 volumio4a volumio[1360]: info: Connecting to TidalConnect Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreCommandRouter::servicePushState Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreStateMachine::pushState Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPushState Apr 11 17:48:44 volumio4a volumio[1360]: info: CorePlayQueue::getTrack 0 Apr 11 17:48:44 volumio4a volumio[1360]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreCommandRouter::servicePushState Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreStateMachine::pushState Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPushState Apr 11 17:48:44 volumio4a sudo[3218]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 11 17:48:44 volumio4a sudo[3218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:48:44 volumio4a volumio[1360]: info: CorePlayQueue::getTrack 0 Apr 11 17:48:44 volumio4a volumio[1360]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 11 17:48:44 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:44.291+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" state=STATUS_PAUSED positionMs=128278 volume=100 Apr 11 17:48:44 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:44.291+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" state=STATUS_PAUSED positionMs=128278 volume=100 Apr 11 17:48:44 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:44.291+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" id=qobuz://song/312575202 title="Close to the Edge: i. The Solid Time of Change, ii. Total Mass Retain, iii. I Get up I Get Down, iv. Seasons of Man " Apr 11 17:48:44 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:44.292+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" id=qobuz://song/312575202 title="Close to the Edge: i. The Solid Time of Change, ii. Total Mass Retain, iii. I Get up I Get Down, iv. Seasons of Man " Apr 11 17:48:44 volumio4a sudo[3218]: pam_unix(sudo:session): session closed for user root Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreStateMachine::getcurrentVolume Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 17:48:44 volumio4a volumio[1360]: info: VolumeController:: Volume=100 Mute =false Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreStateMachine::pushState Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPushState Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreStateMachine::updateTrackBlock Apr 11 17:48:44 volumio4a volumio[1360]: info: CorePlayQueue::getTrackBlock Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 17:48:44 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:44.896+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" state=STATUS_PAUSED positionMs=128779 volume=100 Apr 11 17:48:44 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:44.897+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" id=qobuz://song/312575202 title="Close to the Edge: i. The Solid Time of Change, ii. Total Mass Retain, iii. I Get up I Get Down, iv. Seasons of Man " Apr 11 17:48:44 volumio4a volumio[1360]: info: VolumeController:: Volume=100 Mute =false Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreStateMachine::pushState Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPushState Apr 11 17:48:44 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:44.929+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" state=STATUS_PAUSED positionMs=129036 volume=100 Apr 11 17:48:44 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:48:44.931+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" id=qobuz://song/312575202 title="Close to the Edge: i. The Solid Time of Change, ii. Total Mass Retain, iii. I Get up I Get Down, iv. Seasons of Man " Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreCommandRouter::volumioGetState Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPlay Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreStateMachine::play index undefined Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:48:44 volumio4a volumio[1360]: info: CorePlayQueue::getTrack 0 Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreStateMachine::startPlaybackTimer Apr 11 17:48:44 volumio4a volumio[1360]: info: CorePlayQueue::getTrack 0 Apr 11 17:48:44 volumio4a volumio[1360]: info: [1775922524974] ControllerQobuz::resume Apr 11 17:48:44 volumio4a volumio[1360]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 11 17:48:44 volumio4a volumio[1360]: info: ControllerMpd::resume Apr 11 17:48:44 volumio4a volumio[1360]: verbose: ControllerMpd::sendMpdCommand play Apr 11 17:48:44 volumio4a volumio[1360]: info: sendMpdCommand play took 0 milliseconds Apr 11 17:48:47 volumio4a volumio[1360]: info: TidalConnect service started! Apr 11 17:48:47 volumio4a volumio[1360]: info: TidalConnect service started! Apr 11 17:48:48 volumio4a volumio[1360]: info: CoreCommandRouter::volumioGetState Apr 11 17:48:48 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Apr 11 17:48:48 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPlay Apr 11 17:48:48 volumio4a volumio[1360]: info: CoreStateMachine::play index undefined Apr 11 17:48:48 volumio4a volumio[1360]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:48:48 volumio4a volumio[1360]: info: CorePlayQueue::getTrack 0 Apr 11 17:48:48 volumio4a volumio[1360]: info: CoreStateMachine::startPlaybackTimer Apr 11 17:48:48 volumio4a volumio[1360]: info: CorePlayQueue::getTrack 0 Apr 11 17:48:48 volumio4a volumio[1360]: info: [1775922528137] ControllerQobuz::resume Apr 11 17:48:48 volumio4a volumio[1360]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 11 17:48:48 volumio4a volumio[1360]: info: ControllerMpd::resume Apr 11 17:48:48 volumio4a volumio[1360]: verbose: ControllerMpd::sendMpdCommand play Apr 11 17:48:48 volumio4a volumio[1360]: info: sendMpdCommand play took 0 milliseconds Apr 11 17:48:57 volumio4a kernel: usb 1-1.2: USB disconnect, device number 10 Apr 11 17:48:57 volumio4a volumio[1360]: info: Apr 11 17:48:57 volumio4a volumio[1360]: ---------------------------- USB Audio Device Detached Apr 11 17:48:57 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Apr 11 17:48:57 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:48:57 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Apr 11 17:48:59 volumio4a kernel: usb 1-1.2: new high-speed USB device number 11 using xhci_hcd Apr 11 17:49:00 volumio4a kernel: usb 1-1.2: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.14 Apr 11 17:49:00 volumio4a kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Apr 11 17:49:00 volumio4a kernel: usb 1-1.2: Product: E30 II Apr 11 17:49:00 volumio4a kernel: usb 1-1.2: Manufacturer: Topping Apr 11 17:49:00 volumio4a volumio[1360]: info: Apr 11 17:49:00 volumio4a volumio[1360]: ---------------------------- USB Audio Device Attached Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::Close All Modals sent Apr 11 17:49:00 volumio4a volumio[1360]: info: Preparing to save Alsa Options, stopping services first Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::volumioGetState Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPause Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreStateMachine::pause Apr 11 17:49:00 volumio4a volumio[1360]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"E30 II","alsacard":"II"},"i2s":false} Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:49:00 volumio4a volumio[1360]: info: Setting mixer E30 II for card E30 II Apr 11 17:49:00 volumio4a vtcs[3201]: [2026-04-11 17:49:00.271] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Apr 11 17:49:00 volumio4a vtcs[3201]: [2026-04-11 17:49:00.271] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 11 17:49:00 volumio4a sudo[3273]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 11 17:49:00 volumio4a sudo[3273]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 11 17:49:00 volumio4a volumio[1360]: info: Updating Volume Controller Parameters: Device: 5 Name: E30 II Mixer: E30 II Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 11 17:49:00 volumio4a volumio[1360]: info: Disabling external Volume Control Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 17:49:00 volumio4a volumio[1360]: info: Preparing to generate the ALSA configuration file Apr 11 17:49:00 volumio4a systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Apr 11 17:49:00 volumio4a systemd[1]: vtcs.service: Deactivated successfully. Apr 11 17:49:00 volumio4a systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Apr 11 17:49:00 volumio4a sudo[3273]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:00 volumio4a sudo[3280]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 11 17:49:00 volumio4a sudo[3280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:49:00 volumio4a sudo[3280]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 11 17:49:00 volumio4a volumio[1360]: info: Asound.conf file unchanged, so no further update is needed Apr 11 17:49:00 volumio4a volumio[1360]: info: Output device has changed, restarting MPD Apr 11 17:49:00 volumio4a volumio[1360]: info: Output device has changed, restarting Shairport Sync Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:49:00 volumio4a sudo[3292]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 17:49:00 volumio4a sudo[3292]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:49:00 volumio4a sudo[3292]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:00 volumio4a sudo[3295]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 17:49:00 volumio4a sudo[3295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:49:00 volumio4a volumio[1360]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 17:49:00 volumio4a volumio[1360]: info: MPD Permissions set Apr 11 17:49:00 volumio4a volumio[1360]: info: VolumeController:: Volume=100 Mute =false Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreStateMachine::pushState Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPushState Apr 11 17:49:00 volumio4a sudo[3302]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 11 17:49:00 volumio4a sudo[3302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:49:00 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:49:00.490+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" state=STATUS_PAUSED positionMs=144375 volume=100 Apr 11 17:49:00 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:49:00.491+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" id=qobuz://song/312575202 title="Close to the Edge: i. The Solid Time of Change, ii. Total Mass Retain, iii. I Get up I Get Down, iv. Seasons of Man " Apr 11 17:49:00 volumio4a systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 11 17:49:00 volumio4a sudo[3304]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:00 volumio4a sudo[3304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:00 volumio4a sudo[3302]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:00 volumio4a volumio[1360]: info: Starting Shairport Sync Apr 11 17:49:00 volumio4a systemd[1]: mpd.service: Deactivated successfully. Apr 11 17:49:00 volumio4a systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 11 17:49:00 volumio4a systemd[1]: mpd.service: Consumed 1.416s CPU time. Apr 11 17:49:00 volumio4a systemd[1]: mpd.socket: Deactivated successfully. Apr 11 17:49:00 volumio4a systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 11 17:49:00 volumio4a systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 11 17:49:00 volumio4a sudo[3314]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 17:49:00 volumio4a sudo[3314]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:49:00 volumio4a systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 11 17:49:00 volumio4a systemd[1]: Starting mpd.service - Music Player Daemon... Apr 11 17:49:00 volumio4a systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 11 17:49:00 volumio4a systemd[1]: shairport-sync.service: Deactivated successfully. Apr 11 17:49:00 volumio4a systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 17:49:00 volumio4a systemd[1]: shairport-sync.service: Consumed 1.614s CPU time. Apr 11 17:49:00 volumio4a sudo[3304]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:00 volumio4a systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 17:49:00 volumio4a sudo[3314]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:00 volumio4a volumio[1360]: info: Shairport-Sync Started Apr 11 17:49:00 volumio4a sudo[3317]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 11 17:49:00 volumio4a sudo[3317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 11 17:49:00 volumio4a sudo[3317]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:00 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Apr 11 17:49:02 volumio4a mpd[3335]: 2026-04-11T17:49:01 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 11 17:49:02 volumio4a systemd[1]: Started mpd.service - Music Player Daemon. Apr 11 17:49:02 volumio4a sudo[3295]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:02 volumio4a volumio[1360]: error: updateQueue error: null Apr 11 17:49:02 volumio4a kernel: usb 1-1.2: USB disconnect, device number 11 Apr 11 17:49:02 volumio4a volumio[1360]: info: Apr 11 17:49:02 volumio4a volumio[1360]: ---------------------------- USB Audio Device Detached Apr 11 17:49:02 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Apr 11 17:49:02 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:49:02 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Apr 11 17:49:03 volumio4a volumio[1360]: info: TidalConnect service stoped! Apr 11 17:49:03 volumio4a volumio[1360]: info: TidalConnect service stoped! Apr 11 17:49:03 volumio4a volumio[1360]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 11 17:49:03 volumio4a volumio[1360]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 11 17:49:03 volumio4a sudo[3366]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 11 17:49:03 volumio4a sudo[3366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:49:03 volumio4a systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Apr 11 17:49:03 volumio4a sudo[3366]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:03 volumio4a volumio[1360]: info: TidalConnect service stoped! Apr 11 17:49:03 volumio4a volumio[1360]: info: Executing endpoint tc_getconfig Apr 11 17:49:03 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 11 17:49:03 volumio4a vtcs[3369]: STARTING TidalConnect services, version: 1.6.1 Apr 11 17:49:03 volumio4a vtcs[3369]: STARTED TidalConnect services. Apr 11 17:49:03 volumio4a volumio[1360]: info: Executing endpoint tc_connect Apr 11 17:49:03 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 11 17:49:03 volumio4a volumio[1360]: info: Connecting to TidalConnect Apr 11 17:49:03 volumio4a volumio[1360]: info: CoreCommandRouter::servicePushState Apr 11 17:49:03 volumio4a volumio[1360]: info: CoreStateMachine::pushState Apr 11 17:49:03 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 17:49:03 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPushState Apr 11 17:49:03 volumio4a volumio[1360]: info: CorePlayQueue::getTrack 0 Apr 11 17:49:03 volumio4a volumio[1360]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 11 17:49:03 volumio4a volumio[1360]: info: CoreCommandRouter::servicePushState Apr 11 17:49:03 volumio4a volumio[1360]: info: CoreStateMachine::pushState Apr 11 17:49:03 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPushState Apr 11 17:49:03 volumio4a volumio[1360]: info: CorePlayQueue::getTrack 0 Apr 11 17:49:03 volumio4a volumio[1360]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 11 17:49:03 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:49:03.670+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" state=STATUS_PAUSED positionMs=147497 volume=100 Apr 11 17:49:03 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:49:03.670+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" id=qobuz://song/312575202 title="Close to the Edge: i. The Solid Time of Change, ii. Total Mass Retain, iii. I Get up I Get Down, iv. Seasons of Man " Apr 11 17:49:03 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:49:03.672+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" state=STATUS_PAUSED positionMs=147497 volume=100 Apr 11 17:49:03 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:49:03.673+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" id=qobuz://song/312575202 title="Close to the Edge: i. The Solid Time of Change, ii. Total Mass Retain, iii. I Get up I Get Down, iv. Seasons of Man " Apr 11 17:49:03 volumio4a volumio[1360]: info: TidalConnect service stoped! Apr 11 17:49:03 volumio4a volumio[1360]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 11 17:49:03 volumio4a volumio[1360]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 11 17:49:03 volumio4a sudo[3394]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 11 17:49:03 volumio4a sudo[3394]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:49:03 volumio4a sudo[3394]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:04 volumio4a volumio[1360]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 11 17:49:04 volumio4a volumio[1360]: info: CoreStateMachine::getcurrentVolume Apr 11 17:49:04 volumio4a volumio[1360]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 17:49:04 volumio4a volumio[1360]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Apr 11 17:49:04 volumio4a volumio[1360]: info: VolumeController:: Volume=undefined Mute =false Apr 11 17:49:04 volumio4a volumio[1360]: info: CoreStateMachine::pushState Apr 11 17:49:04 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 17:49:04 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPushState Apr 11 17:49:04 volumio4a volumio[1360]: info: CoreStateMachine::updateTrackBlock Apr 11 17:49:04 volumio4a volumio[1360]: info: CorePlayQueue::getTrackBlock Apr 11 17:49:04 volumio4a volumio[1360]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 17:49:04 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:49:04.335+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" state=STATUS_PAUSED positionMs=148129 volume=100 Apr 11 17:49:04 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:49:04.336+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" id=qobuz://song/312575202 title="Close to the Edge: i. The Solid Time of Change, ii. Total Mass Retain, iii. I Get up I Get Down, iv. Seasons of Man " Apr 11 17:49:04 volumio4a volumio[1360]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Apr 11 17:49:04 volumio4a volumio[1360]: info: VolumeController:: Volume=undefined Mute =false Apr 11 17:49:04 volumio4a volumio[1360]: info: CoreStateMachine::pushState Apr 11 17:49:04 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 17:49:04 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPushState Apr 11 17:49:04 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:49:04.359+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" state=STATUS_PAUSED positionMs=148250 volume=100 Apr 11 17:49:04 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:49:04.359+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" id=qobuz://song/312575202 title="Close to the Edge: i. The Solid Time of Change, ii. Total Mass Retain, iii. I Get up I Get Down, iv. Seasons of Man " Apr 11 17:49:05 volumio4a kernel: usb 1-1.2: new high-speed USB device number 12 using xhci_hcd Apr 11 17:49:05 volumio4a kernel: usb 1-1.2: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.14 Apr 11 17:49:05 volumio4a kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Apr 11 17:49:05 volumio4a kernel: usb 1-1.2: Product: E30 II Apr 11 17:49:05 volumio4a kernel: usb 1-1.2: Manufacturer: Topping Apr 11 17:49:06 volumio4a volumio[1360]: info: Apr 11 17:49:06 volumio4a volumio[1360]: ---------------------------- USB Audio Device Attached Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::Close All Modals sent Apr 11 17:49:06 volumio4a volumio[1360]: info: Preparing to save Alsa Options, stopping services first Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::volumioGetState Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPause Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreStateMachine::pause Apr 11 17:49:06 volumio4a volumio[1360]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"E30 II","alsacard":"II"},"i2s":false} Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:49:06 volumio4a volumio[1360]: info: Setting mixer E30 II for card E30 II Apr 11 17:49:06 volumio4a vtcs[3369]: [2026-04-11 17:49:06.147] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 11 17:49:06 volumio4a vtcs[3369]: [2026-04-11 17:49:06.148] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 11 17:49:06 volumio4a volumio[1360]: info: Updating Volume Controller Parameters: Device: 5 Name: E30 II Mixer: E30 II Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 11 17:49:06 volumio4a volumio[1360]: info: Disabling external Volume Control Apr 11 17:49:06 volumio4a sudo[3426]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 11 17:49:06 volumio4a sudo[3426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 17:49:06 volumio4a systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Apr 11 17:49:06 volumio4a volumio[1360]: info: Preparing to generate the ALSA configuration file Apr 11 17:49:06 volumio4a systemd[1]: vtcs.service: Deactivated successfully. Apr 11 17:49:06 volumio4a systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Apr 11 17:49:06 volumio4a sudo[3426]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:06 volumio4a sudo[3432]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 11 17:49:06 volumio4a sudo[3432]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:49:06 volumio4a sudo[3432]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 11 17:49:06 volumio4a volumio[1360]: info: Asound.conf file unchanged, so no further update is needed Apr 11 17:49:06 volumio4a volumio[1360]: info: Output device has changed, restarting MPD Apr 11 17:49:06 volumio4a volumio[1360]: info: Output device has changed, restarting Shairport Sync Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:49:06 volumio4a sudo[3443]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 17:49:06 volumio4a sudo[3443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:49:06 volumio4a sudo[3447]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 17:49:06 volumio4a volumio[1360]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 17:49:06 volumio4a sudo[3447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:49:06 volumio4a volumio[1360]: info: VolumeController:: Volume=100 Mute =false Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreStateMachine::pushState Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPushState Apr 11 17:49:06 volumio4a sudo[3453]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 11 17:49:06 volumio4a sudo[3453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:49:06 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:49:06.370+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" state=STATUS_PAUSED positionMs=150136 volume=100 Apr 11 17:49:06 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:49:06.370+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" id=qobuz://song/312575202 title="Close to the Edge: i. The Solid Time of Change, ii. Total Mass Retain, iii. I Get up I Get Down, iv. Seasons of Man " Apr 11 17:49:06 volumio4a sudo[3455]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 11 17:49:06 volumio4a sudo[3455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:06 volumio4a volumio[1360]: info: Starting Shairport Sync Apr 11 17:49:06 volumio4a systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 11 17:49:06 volumio4a sudo[3455]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:06 volumio4a sudo[3465]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 17:49:06 volumio4a sudo[3465]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:49:06 volumio4a sudo[3453]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:06 volumio4a systemd[1]: mpd.service: Deactivated successfully. Apr 11 17:49:06 volumio4a systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 11 17:49:06 volumio4a systemd[1]: mpd.service: Consumed 1.422s CPU time. Apr 11 17:49:06 volumio4a systemd[1]: mpd.socket: Deactivated successfully. Apr 11 17:49:06 volumio4a systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 11 17:49:06 volumio4a systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 11 17:49:06 volumio4a volumio[1360]: info: TidalConnect service started! Apr 11 17:49:06 volumio4a systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 11 17:49:06 volumio4a systemd[1]: Starting mpd.service - Music Player Daemon... Apr 11 17:49:06 volumio4a systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 11 17:49:06 volumio4a systemd[1]: shairport-sync.service: Deactivated successfully. Apr 11 17:49:06 volumio4a systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 17:49:06 volumio4a systemd[1]: shairport-sync.service: Consumed 1.575s CPU time. Apr 11 17:49:06 volumio4a systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 17:49:06 volumio4a sudo[3465]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:06 volumio4a volumio[1360]: info: Shairport-Sync Started Apr 11 17:49:06 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Apr 11 17:49:06 volumio4a sudo[3470]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 11 17:49:06 volumio4a sudo[3470]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 11 17:49:06 volumio4a sudo[3470]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:06 volumio4a volumio[1360]: info: TidalConnect service started! Apr 11 17:49:07 volumio4a sudo[3443]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:07 volumio4a volumio[1360]: info: MPD Permissions set Apr 11 17:49:08 volumio4a mpd[3487]: 2026-04-11T17:49:07 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 11 17:49:08 volumio4a systemd[1]: Started mpd.service - Music Player Daemon. Apr 11 17:49:08 volumio4a sudo[3447]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:08 volumio4a volumio[1360]: error: updateQueue error: null Apr 11 17:49:08 volumio4a kernel: usb 1-1.2: USB disconnect, device number 12 Apr 11 17:49:08 volumio4a volumio[1360]: info: Apr 11 17:49:08 volumio4a volumio[1360]: ---------------------------- USB Audio Device Detached Apr 11 17:49:08 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Apr 11 17:49:08 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:49:08 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Apr 11 17:49:08 volumio4a kernel: usb 1-1.2: new high-speed USB device number 13 using xhci_hcd Apr 11 17:49:09 volumio4a kernel: usb 1-1.2: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.14 Apr 11 17:49:09 volumio4a kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Apr 11 17:49:09 volumio4a kernel: usb 1-1.2: Product: E30 II Apr 11 17:49:09 volumio4a kernel: usb 1-1.2: Manufacturer: Topping Apr 11 17:49:09 volumio4a volumio[1360]: info: Apr 11 17:49:09 volumio4a volumio[1360]: ---------------------------- USB Audio Device Attached Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::Close All Modals sent Apr 11 17:49:09 volumio4a volumio[1360]: info: Preparing to save Alsa Options, stopping services first Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::volumioGetState Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPause Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreStateMachine::pause Apr 11 17:49:09 volumio4a volumio[1360]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"E30 II","alsacard":"II"},"i2s":false} Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:49:09 volumio4a volumio[1360]: info: Setting mixer E30 II for card E30 II Apr 11 17:49:09 volumio4a sudo[3512]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 11 17:49:09 volumio4a sudo[3512]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 11 17:49:09 volumio4a volumio[1360]: info: Updating Volume Controller Parameters: Device: 5 Name: E30 II Mixer: E30 II Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 11 17:49:09 volumio4a volumio[1360]: info: Disabling external Volume Control Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 17:49:09 volumio4a volumio[1360]: info: Preparing to generate the ALSA configuration file Apr 11 17:49:09 volumio4a sudo[3512]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:09 volumio4a volumio[1360]: info: TidalConnect service stoped! Apr 11 17:49:09 volumio4a sudo[3519]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 11 17:49:09 volumio4a sudo[3519]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:49:09 volumio4a volumio[1360]: info: TidalConnect service stoped! Apr 11 17:49:09 volumio4a volumio[1360]: info: VolumeController:: Volume=100 Mute =false Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreStateMachine::pushState Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::volumioPushState Apr 11 17:49:09 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:49:09.335+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" state=STATUS_PAUSED positionMs=153196 volume=100 Apr 11 17:49:09 volumio4a volumio5-onboarding[1912]: time=2026-04-11T17:49:09.335+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.5:45546,00:00:00:00:00:00%03 @ 0x1cb8510" id=qobuz://song/312575202 title="Close to the Edge: i. The Solid Time of Change, ii. Total Mass Retain, iii. I Get up I Get Down, iv. Seasons of Man " Apr 11 17:49:09 volumio4a sudo[3519]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 11 17:49:09 volumio4a volumio[1360]: info: Asound.conf file unchanged, so no further update is needed Apr 11 17:49:09 volumio4a volumio[1360]: info: Output device has changed, restarting MPD Apr 11 17:49:09 volumio4a volumio[1360]: info: Output device has changed, restarting Shairport Sync Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:49:09 volumio4a sudo[3541]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 17:49:09 volumio4a sudo[3541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:49:09 volumio4a sudo[3539]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 17:49:09 volumio4a sudo[3539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:49:09 volumio4a sudo[3539]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:09 volumio4a volumio[1360]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 17:49:09 volumio4a systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 11 17:49:09 volumio4a volumio[1360]: info: MPD Permissions set Apr 11 17:49:09 volumio4a volumio[1360]: info: TidalConnect service stoped! Apr 11 17:49:09 volumio4a volumio[1360]: info: TidalConnect service stoped! Apr 11 17:49:09 volumio4a sudo[3549]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 11 17:49:09 volumio4a sudo[3549]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:49:09 volumio4a volumio[1360]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 11 17:49:09 volumio4a volumio[1360]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:09 volumio4a sudo[3552]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 11 17:49:09 volumio4a sudo[3552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:49:09 volumio4a volumio[1360]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:49:09 volumio4a systemd[1]: mpd.service: Deactivated successfully. Apr 11 17:49:09 volumio4a systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 11 17:49:09 volumio4a systemd[1]: mpd.service: Consumed 1.416s CPU time. Apr 11 17:49:09 volumio4a systemd[1]: mpd.socket: Deactivated successfully. Apr 11 17:49:09 volumio4a systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 11 17:49:09 volumio4a systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 11 17:49:09 volumio4a volumio[1360]: info: Starting Shairport Sync Apr 11 17:49:09 volumio4a volumio[1360]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 11 17:49:09 volumio4a volumio[1360]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 11 17:49:09 volumio4a sudo[3572]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 17:49:09 volumio4a sudo[3572]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:49:09 volumio4a sudo[3566]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 11 17:49:09 volumio4a sudo[3566]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:49:09 volumio4a sudo[3574]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 11 17:49:09 volumio4a sudo[3574]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:49:09 volumio4a systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 11 17:49:09 volumio4a systemd[1]: Starting mpd.service - Music Player Daemon... Apr 11 17:49:09 volumio4a sudo[3549]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:09 volumio4a sudo[3552]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:09 volumio4a volumio[1360]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Apr 11 17:49:09 volumio4a volumio[1360]: Job for vtcs.service canceled. Apr 11 17:49:09 volumio4a volumio[1360]: {"cmd":"/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3\nJob for vtcs.service canceled.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at ChildProcess._handle.onexit (node:internal/child_process:305:5)","stderr":"Job for vtcs.service canceled.\n","stdout":""} Apr 11 17:49:09 volumio4a volumio[1360]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 17:49:09 volumio4a systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 11 17:49:09 volumio4a volumio[1360]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Apr 11 17:49:09 volumio4a volumio[1360]: Job for vtcs.service canceled. Apr 11 17:49:09 volumio4a volumio[1360]: at ChildProcess.exithandler (node:child_process:421:12) Apr 11 17:49:09 volumio4a volumio[1360]: at ChildProcess.emit (node:events:514:28) Apr 11 17:49:09 volumio4a volumio[1360]: at maybeClose (node:internal/child_process:1105:16) Apr 11 17:49:09 volumio4a volumio[1360]: at Socket. (node:internal/child_process:457:11) Apr 11 17:49:09 volumio4a volumio[1360]: at Socket.emit (node:events:514:28) Apr 11 17:49:09 volumio4a volumio[1360]: at Pipe. (node:net:337:12) { Apr 11 17:49:09 volumio4a volumio[1360]: code: 1, Apr 11 17:49:09 volumio4a volumio[1360]: killed: false, Apr 11 17:49:09 volumio4a volumio[1360]: signal: null, Apr 11 17:49:09 volumio4a volumio[1360]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Apr 11 17:49:09 volumio4a volumio[1360]: stdout: '', Apr 11 17:49:09 volumio4a volumio[1360]: stderr: 'Job for vtcs.service canceled.\n' Apr 11 17:49:09 volumio4a volumio[1360]: } Apr 11 17:49:09 volumio4a volumio[1360]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 17:49:09 volumio4a systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Apr 11 17:49:09 volumio4a systemd[1]: shairport-sync.service: Deactivated successfully. Apr 11 17:49:09 volumio4a systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 17:49:09 volumio4a systemd[1]: shairport-sync.service: Consumed 1.558s CPU time. Apr 11 17:49:09 volumio4a sudo[3574]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:09 volumio4a systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 17:49:09 volumio4a sudo[3566]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:09 volumio4a sudo[3572]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:09 volumio4a sudo[3578]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 11 17:49:09 volumio4a sudo[3578]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 11 17:49:09 volumio4a sudo[3578]: pam_unix(sudo:session): session closed for user root Apr 11 17:49:10 volumio4a sudo[3614]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-11 17:48' Apr 11 17:49:10 volumio4a sudo[3614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"