Dec 06 16:31:40 volumiolab4 kernel: usb 3-1: USB disconnect, device number 2 Dec 06 16:31:40 volumiolab4 kernel: usb 3-1: new high-speed USB device number 3 using xhci-hcd Dec 06 16:31:40 volumiolab4 kernel: usb 3-1: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.12 Dec 06 16:31:40 volumiolab4 kernel: usb 3-1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Dec 06 16:31:40 volumiolab4 kernel: usb 3-1: Product: SMSL USB AUDIO Dec 06 16:31:40 volumiolab4 kernel: usb 3-1: Manufacturer: SMSL Dec 06 16:31:41 volumiolab4 kernel: hid-generic 0003:152A:85DD.0002: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-xhci-hcd.1-1/input3 Dec 06 16:31:41 volumiolab4 volumio[1496]: info: Dec 06 16:31:41 volumiolab4 volumio[1496]: ---------------------------- USB Audio Device Detached Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 06 16:31:41 volumiolab4 volumio[1496]: info: Dec 06 16:31:41 volumiolab4 volumio[1496]: ---------------------------- USB Audio Device Attached Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::Close All Modals sent Dec 06 16:31:41 volumiolab4 volumio[1496]: info: Preparing to save Alsa Options, stopping services first Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::volumioGetState Dec 06 16:31:41 volumiolab4 volumio[1496]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"SMSL USB AUDIO","alsacard":"AUDIO"},"i2s":false} Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 06 16:31:41 volumiolab4 volumio[1496]: info: Device 5 does not have any Mixer Control Available Dec 06 16:31:41 volumiolab4 volumio[1496]: info: QobuzConnect: setDeactiveState invoked Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::volumioGetState Dec 06 16:31:41 volumiolab4 vtcs[2333]: [2025-12-06 16:31:41.269] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 06 16:31:41 volumiolab4 vtcs[2333]: [2025-12-06 16:31:41.269] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 06 16:31:41 volumiolab4 sudo[5717]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 06 16:31:41 volumiolab4 sudo[5717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 06 16:31:41 volumiolab4 volumio[1496]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 06 16:31:41 volumiolab4 volumio[1496]: info: Disabling external Volume Control Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreStateMachine::pushState Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::volumioPushState Dec 06 16:31:41 volumiolab4 volumio[1496]: info: MRS: Pushing multiroomSync output update for this device Dec 06 16:31:41 volumiolab4 volumio[1496]: info: MRS: Pushing multiroomSync output Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::volumioGetState Dec 06 16:31:41 volumiolab4 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 06 16:31:41 volumiolab4 volumio[1496]: info: Preparing to generate the ALSA configuration file Dec 06 16:31:41 volumiolab4 systemd[1]: vtcs.service: Deactivated successfully. Dec 06 16:31:41 volumiolab4 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 06 16:31:41 volumiolab4 systemd[1]: vtcs.service: Consumed 3.084s CPU time. Dec 06 16:31:41 volumiolab4 sudo[5717]: pam_unix(sudo:session): session closed for user root Dec 06 16:31:41 volumiolab4 sudo[5724]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 06 16:31:41 volumiolab4 sudo[5724]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 16:31:41 volumiolab4 volumio[1496]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 06 16:31:41 volumiolab4 volumio[1496]: info: Reading ALSA contributions from plugins. Dec 06 16:31:41 volumiolab4 volumio[1496]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.3qbKtL72MhWBCPDOAzwzpq4UuEj1.f8a863caa97bf8ce3d8331c113aaa7c8.state.status' Dec 06 16:31:41 volumiolab4 sudo[5732]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 06 16:31:41 volumiolab4 sudo[5724]: pam_unix(sudo:session): session closed for user root Dec 06 16:31:41 volumiolab4 sudo[5732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 06 16:31:41 volumiolab4 sudo[5732]: pam_unix(sudo:session): session closed for user root Dec 06 16:31:41 volumiolab4 sudo[5739]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 06 16:31:41 volumiolab4 sudo[5739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 06 16:31:41 volumiolab4 volumio[1496]: info: Asound.conf file unchanged, so no further update is needed Dec 06 16:31:41 volumiolab4 volumio[1496]: info: Output device has changed, restarting MPD Dec 06 16:31:41 volumiolab4 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 06 16:31:41 volumiolab4 qobuz-connect[2255]: 20251206 16:31:41.401 [2255.2255] INFO SampleApp: Stopping Local configuration server Dec 06 16:31:41 volumiolab4 volumio[1496]: info: Output device has changed, restarting Shairport Sync Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 16:31:41 volumiolab4 sudo[5745]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 06 16:31:41 volumiolab4 sudo[5745]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 16:31:41 volumiolab4 sudo[5745]: pam_unix(sudo:session): session closed for user root Dec 06 16:31:41 volumiolab4 volumio[1496]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 06 16:31:41 volumiolab4 volumio[1496]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:31:41 volumiolab4 sudo[5746]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 06 16:31:41 volumiolab4 sudo[5746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 16:31:41 volumiolab4 volumio[1496]: info: QobuzConnect: setDeactiveState invoked Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::volumioGetState Dec 06 16:31:41 volumiolab4 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 06 16:31:41 volumiolab4 sudo[5756]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 06 16:31:41 volumiolab4 sudo[5756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 16:31:41 volumiolab4 sudo[5758]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 06 16:31:41 volumiolab4 sudo[5758]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 16:31:41 volumiolab4 systemd[1]: mpd.service: Deactivated successfully. Dec 06 16:31:41 volumiolab4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 06 16:31:41 volumiolab4 systemd[1]: mpd.socket: Deactivated successfully. Dec 06 16:31:41 volumiolab4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 06 16:31:41 volumiolab4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 06 16:31:41 volumiolab4 volumio[1496]: info: MPD Permissions set Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:31:41 volumiolab4 sudo[5768]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 06 16:31:41 volumiolab4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 06 16:31:41 volumiolab4 sudo[5768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 16:31:41 volumiolab4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 16:31:41 volumiolab4 volumio[1496]: info: Starting Shairport Sync Dec 06 16:31:41 volumiolab4 sudo[5777]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 06 16:31:41 volumiolab4 sudo[5777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 16:31:41 volumiolab4 sudo[5756]: pam_unix(sudo:session): session closed for user root Dec 06 16:31:41 volumiolab4 sudo[5768]: pam_unix(sudo:session): session closed for user root Dec 06 16:31:41 volumiolab4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 06 16:31:41 volumiolab4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 06 16:31:41 volumiolab4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 16:31:41 volumiolab4 systemd[1]: shairport-sync.service: Consumed 21.195s CPU time. Dec 06 16:31:41 volumiolab4 sudo[5780]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 06 16:31:41 volumiolab4 sudo[5780]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 16:31:41 volumiolab4 sudo[5758]: pam_unix(sudo:session): session closed for user root Dec 06 16:31:41 volumiolab4 sudo[5775]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 06 16:31:41 volumiolab4 sudo[5775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 06 16:31:41 volumiolab4 sudo[5775]: pam_unix(sudo:session): session closed for user root Dec 06 16:31:41 volumiolab4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 16:31:41 volumiolab4 sudo[5777]: pam_unix(sudo:session): session closed for user root Dec 06 16:31:41 volumiolab4 volumio[1496]: info: Shairport-Sync Started Dec 06 16:31:41 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 06 16:31:42 volumiolab4 mpd[5785]: 2025-12-06T16:31:42 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 06 16:31:42 volumiolab4 systemd[1]: Started mpd.service - Music Player Daemon. Dec 06 16:31:42 volumiolab4 sudo[5746]: pam_unix(sudo:session): session closed for user root Dec 06 16:31:42 volumiolab4 volumio[1496]: error: updateQueue error: null Dec 06 16:31:42 volumiolab4 qobuz-connect[2255]: 20251206 16:31:42.965 [2255.2255] INFO SampleApp: shat down connection on UNIX socket Dec 06 16:31:42 volumiolab4 volumio[1496]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 06 16:31:42 volumiolab4 volumio[1496]: info: QobuzConnect: setDeactiveState invoked Dec 06 16:31:42 volumiolab4 volumio[1496]: info: CoreCommandRouter::volumioGetState Dec 06 16:31:42 volumiolab4 systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 06 16:31:42 volumiolab4 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 06 16:31:42 volumiolab4 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 06 16:31:43 volumiolab4 sudo[5739]: pam_unix(sudo:session): session closed for user root Dec 06 16:31:43 volumiolab4 sudo[5780]: pam_unix(sudo:session): session closed for user root Dec 06 16:31:43 volumiolab4 volumio[1496]: info: Executing endpoint qc_getconfig Dec 06 16:31:43 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 06 16:31:43 volumiolab4 qobuz-connect[5802]: 20251206 16:31:43.024 [5802.5802] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 06 16:31:43 volumiolab4 volumio[1496]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 06 16:31:43 volumiolab4 volumio[1496]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 06 16:31:43 volumiolab4 qobuz-connect[5802]: 20251206 16:31:43.026 [5802.5802] INFO VolumeManager: [0x79a348]: Setting new playback volume: 75 Dec 06 16:31:43 volumiolab4 qobuz-connect[5802]: 20251206 16:31:43.026 [5802.5802] INFO VolumeManager: [0x79a348]: Setting new mute state: 0 Dec 06 16:31:43 volumiolab4 qobuz-connect[5802]: 20251206 16:31:43.026 [5802.5802] INFO QobuzConnect: [0x79ad18]: Client initialized! Dec 06 16:31:43 volumiolab4 qobuz-connect[5802]: 20251206 16:31:43.026 [5802.5802] INFO SampleApp: Starting Avahi advertising, name: VolumioLAB4, service name: _qobuz-connect._tcp Dec 06 16:31:43 volumiolab4 qobuz-connect[5802]: 20251206 16:31:43.032 [5802.5802] INFO LocalConfigManager: [0x799c28]: Starting Local Configuration server Dec 06 16:31:43 volumiolab4 qobuz-connect[5802]: 20251206 16:31:43.032 [5802.5802] INFO SampleApp: Starting Local configuration server Dec 06 16:31:43 volumiolab4 qobuz-connect[5802]: 20251206 16:31:43.032 [5802.5802] INFO SampleApp: Connected to UNIX socket client 0x784818 Dec 06 16:31:43 volumiolab4 qobuz-connect[5802]: 20251206 16:31:43.174 [5802.5802] INFO SampleApp: Playback volume changed: 75 Dec 06 16:31:43 volumiolab4 volumio[1496]: info: CoreCommandRouter::volumioGetState Dec 06 16:31:44 volumiolab4 volumio[1496]: info: TidalConnect service stoped! Dec 06 16:31:44 volumiolab4 volumio[1496]: info: TidalConnect service stoped! Dec 06 16:31:44 volumiolab4 volumio[1496]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 06 16:31:44 volumiolab4 volumio[1496]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 06 16:31:44 volumiolab4 sudo[5817]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 06 16:31:44 volumiolab4 sudo[5817]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 16:31:44 volumiolab4 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 06 16:31:44 volumiolab4 sudo[5817]: pam_unix(sudo:session): session closed for user root Dec 06 16:31:44 volumiolab4 volumio[1496]: info: TidalConnect service stoped! Dec 06 16:31:44 volumiolab4 volumio[1496]: info: TidalConnect service stoped! Dec 06 16:31:44 volumiolab4 volumio[1496]: info: Executing endpoint tc_getconfig Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 06 16:31:44 volumiolab4 vtcs[5820]: STARTING TidalConnect services, version: 1.5.1-beta1 Dec 06 16:31:44 volumiolab4 vtcs[5820]: STARTED TidalConnect services. Dec 06 16:31:44 volumiolab4 volumio[1496]: info: Executing endpoint tc_connect Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 06 16:31:44 volumiolab4 volumio[1496]: info: Connecting to TidalConnect Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CoreCommandRouter::servicePushState Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CoreStateMachine::pushState Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CoreCommandRouter::volumioPushState Dec 06 16:31:44 volumiolab4 volumio[1496]: info: MRS: Pushing multiroomSync output update for this device Dec 06 16:31:44 volumiolab4 volumio[1496]: info: MRS: Pushing multiroomSync output Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CoreCommandRouter::volumioGetState Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CorePlayQueue::getTrack 0 Dec 06 16:31:44 volumiolab4 volumio[1496]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 06 16:31:44 volumiolab4 volumio[1496]: verbose: CURRENT POSITION 0 Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CoreStateMachine::syncState stateService stop Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CoreStateMachine::syncState currentStatus stop Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CoreStateMachine::pushState Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CoreCommandRouter::volumioPushState Dec 06 16:31:44 volumiolab4 volumio[1496]: info: MRS: Pushing multiroomSync output update for this device Dec 06 16:31:44 volumiolab4 volumio[1496]: info: MRS: Pushing multiroomSync output Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CoreCommandRouter::volumioGetState Dec 06 16:31:44 volumiolab4 volumio[1496]: info: No code Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CoreStateMachine::pushState Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CoreCommandRouter::volumioPushState Dec 06 16:31:44 volumiolab4 volumio[1496]: info: MRS: Pushing multiroomSync output update for this device Dec 06 16:31:44 volumiolab4 volumio[1496]: info: MRS: Pushing multiroomSync output Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CoreCommandRouter::volumioGetState Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CoreCommandRouter::servicePushState Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CoreStateMachine::pushState Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CoreCommandRouter::volumioPushState Dec 06 16:31:44 volumiolab4 volumio[1496]: info: MRS: Pushing multiroomSync output update for this device Dec 06 16:31:44 volumiolab4 volumio[1496]: info: MRS: Pushing multiroomSync output Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CoreCommandRouter::volumioGetState Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CorePlayQueue::getTrack 0 Dec 06 16:31:44 volumiolab4 volumio[1496]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 06 16:31:44 volumiolab4 volumio[1496]: verbose: CURRENT POSITION 0 Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CoreStateMachine::syncState stateService stop Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CoreStateMachine::syncState currentStatus stop Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CoreStateMachine::pushState Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CoreCommandRouter::volumioPushState Dec 06 16:31:44 volumiolab4 volumio[1496]: info: MRS: Pushing multiroomSync output update for this device Dec 06 16:31:44 volumiolab4 volumio[1496]: info: MRS: Pushing multiroomSync output Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CoreCommandRouter::volumioGetState Dec 06 16:31:44 volumiolab4 volumio[1496]: info: No code Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CoreStateMachine::pushState Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CoreCommandRouter::volumioPushState Dec 06 16:31:44 volumiolab4 volumio[1496]: info: MRS: Pushing multiroomSync output update for this device Dec 06 16:31:44 volumiolab4 volumio[1496]: info: MRS: Pushing multiroomSync output Dec 06 16:31:44 volumiolab4 volumio[1496]: info: CoreCommandRouter::volumioGetState Dec 06 16:31:44 volumiolab4 volumio[1496]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.3qbKtL72MhWBCPDOAzwzpq4UuEj1.f8a863caa97bf8ce3d8331c113aaa7c8.state.status' Dec 06 16:31:44 volumiolab4 volumio[1496]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.3qbKtL72MhWBCPDOAzwzpq4UuEj1.f8a863caa97bf8ce3d8331c113aaa7c8.state.status' Dec 06 16:31:44 volumiolab4 volumio[1496]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.3qbKtL72MhWBCPDOAzwzpq4UuEj1.f8a863caa97bf8ce3d8331c113aaa7c8.state.status' Dec 06 16:31:44 volumiolab4 volumio[1496]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.3qbKtL72MhWBCPDOAzwzpq4UuEj1.f8a863caa97bf8ce3d8331c113aaa7c8.state.status' Dec 06 16:31:44 volumiolab4 volumio[1496]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.3qbKtL72MhWBCPDOAzwzpq4UuEj1.f8a863caa97bf8ce3d8331c113aaa7c8.state.status' Dec 06 16:31:44 volumiolab4 volumio[1496]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.3qbKtL72MhWBCPDOAzwzpq4UuEj1.f8a863caa97bf8ce3d8331c113aaa7c8.state.status' Dec 06 16:31:44 volumiolab4 volumio[1496]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 06 16:31:44 volumiolab4 volumio[1496]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 06 16:31:44 volumiolab4 volumio[1496]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 16:31:44 volumiolab4 volumio[1496]: Error: ENOENT: no such file or directory, unlink '/tmp/TnmwRZGq1aPwRc0k5j4gAL03ukby9Dy1tLFDfyaZfgIF9qm2erDpdrc0W2ZM9orwYuwOZEw7LheppeHPkbkYr9N0ve2OEcZAAPdH' Dec 06 16:31:44 volumiolab4 volumio[1496]: at Object.unlinkSync (node:fs:1899:3) Dec 06 16:31:44 volumiolab4 volumio[1496]: at ControllerTidalconnect.deleteCertificate (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:11238) Dec 06 16:31:44 volumiolab4 volumio[1496]: at TidalConnectClient. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:2774) Dec 06 16:31:44 volumiolab4 volumio[1496]: at TidalConnectClient.emit (node:events:514:28) Dec 06 16:31:44 volumiolab4 volumio[1496]: at TidalConnectClient.parseData (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:44661) Dec 06 16:31:44 volumiolab4 volumio[1496]: at /myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41631 Dec 06 16:31:44 volumiolab4 volumio[1496]: at Array.forEach () Dec 06 16:31:44 volumiolab4 volumio[1496]: at Socket. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41509) Dec 06 16:31:44 volumiolab4 volumio[1496]: at Socket.emit (node:events:514:28) Dec 06 16:31:44 volumiolab4 volumio[1496]: at addChunk (node:internal/streams/readable:343:12) Dec 06 16:31:44 volumiolab4 volumio[1496]: at readableAddChunk (node:internal/streams/readable:316:9) Dec 06 16:31:44 volumiolab4 volumio[1496]: at Readable.push (node:internal/streams/readable:253:10) Dec 06 16:31:44 volumiolab4 volumio[1496]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) { Dec 06 16:31:44 volumiolab4 volumio[1496]: errno: -2, Dec 06 16:31:44 volumiolab4 volumio[1496]: syscall: 'unlink', Dec 06 16:31:44 volumiolab4 volumio[1496]: code: 'ENOENT', Dec 06 16:31:44 volumiolab4 volumio[1496]: path: '/tmp/TnmwRZGq1aPwRc0k5j4gAL03ukby9Dy1tLFDfyaZfgIF9qm2erDpdrc0W2ZM9orwYuwOZEw7LheppeHPkbkYr9N0ve2OEcZAAPdH' Dec 06 16:31:44 volumiolab4 volumio[1496]: } Dec 06 16:31:44 volumiolab4 volumio[1496]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 16:31:44 volumiolab4 sudo[5860]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-06 16:30' Dec 06 16:31:44 volumiolab4 sudo[5860]: 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="5ef3d56f987d109f3e21bfa246dbbb723cf6a917" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="c7bff9654d2984a81c5720476c2c8ac531869c54" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Oct 9 18:08:11 UTC 2025" VOLUMIO_VERSION="4.062" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="00524295c246b7d72f125e4c54b86a88"