Dec 28 18:12:25 volumiolabo ntpd[984]: CLOCK: time stepped by 83635.880385 Dec 28 18:12:25 volumiolabo ntpd[984]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 28 18:12:25 volumiolabo systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Dec 28 18:12:25 volumiolabo systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Dec 28 18:12:25 volumiolabo systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Dec 28 18:12:25 volumiolabo systemd[1]: dpkg-db-backup.service: Deactivated successfully. Dec 28 18:12:25 volumiolabo systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Dec 28 18:12:26 volumiolabo volumio[1150]: verbose: New Socket.io Connection to 192.168.0.67:3000 from 192.168.0.82 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 28 18:12:26 volumiolabo volumio[1150]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Dec 28 18:12:26 volumiolabo volumio[1150]: info: Reloading queue from file Dec 28 18:12:26 volumiolabo volumio[1150]: verbose: New Socket.io Connection to 192.168.0.67:3000 from 192.168.0.82 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 28 18:12:26 volumiolabo volumio[1150]: info: VolumeController:: Volume=undefined Mute =false Dec 28 18:12:26 volumiolabo volumio[1150]: info: CoreStateMachine::pushState Dec 28 18:12:26 volumiolabo volumio[1150]: info: CorePlayQueue::getTrack 0 Dec 28 18:12:26 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 18:12:26 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioPushState Dec 28 18:12:26 volumiolabo volumio[1150]: info: CoreStateMachine::updateTrackBlock Dec 28 18:12:26 volumiolabo volumio[1150]: info: CorePlayQueue::getTrackBlock Dec 28 18:12:26 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 18:12:26 volumiolabo volumio[1150]: info: CoreStateMachine::setRepeat null single undefined Dec 28 18:12:26 volumiolabo volumio[1150]: info: CoreStateMachine::pushState Dec 28 18:12:26 volumiolabo volumio[1150]: info: CorePlayQueue::getTrack 0 Dec 28 18:12:26 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioPushState Dec 28 18:12:26 volumiolabo volumio[1150]: info: CoreStateMachine::setRandom null Dec 28 18:12:26 volumiolabo volumio[1150]: info: CoreStateMachine::pushState Dec 28 18:12:26 volumiolabo volumio[1150]: info: CorePlayQueue::getTrack 0 Dec 28 18:12:26 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioPushState Dec 28 18:12:26 volumiolabo volumio[1150]: info: Setting Device type: Raspberry PI Dec 28 18:12:27 volumiolabo volumio[1150]: verbose: New Socket.io Connection to 192.168.0.67:3000 from 192.168.0.82 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 28 18:12:27 volumiolabo volumio[1150]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 28 18:12:27 volumiolabo volumio[1150]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Dec 28 18:12:27 volumiolabo volumio[1150]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 28 18:12:27 volumiolabo volumio[1150]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Dec 28 18:12:27 volumiolabo volumio[1150]: info: Discovery: A device disappeared from network Dec 28 18:12:27 volumiolabo volumio[1150]: info: Discovery: Browse raised the following error TypeError: Cannot read properties of undefined (reading 'toLowerCase') Dec 28 18:12:27 volumiolabo volumio[1150]: info: Discovery: A device disappeared from network Dec 28 18:12:27 volumiolabo volumio[1150]: info: Discovery: Browse raised the following error TypeError: Cannot read properties of undefined (reading 'toLowerCase') Dec 28 18:12:27 volumiolabo volumio[1150]: info: Discovery: adding c6a87427-2d13-4943-9cab-8f71b7afbc1a Dec 28 18:12:27 volumiolabo volumio[1150]: info: Discovery: Found device Volumio_LaBo Dec 28 18:12:27 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:12:27 volumiolabo volumio[1150]: info: CorePlayQueue::getTrack 0 Dec 28 18:12:27 volumiolabo volumio[1150]: info: Discovery: this is already registered, c6a87427-2d13-4943-9cab-8f71b7afbc1a Dec 28 18:12:27 volumiolabo volumio[1150]: info: Discovery: Found device Volumio_LaBo Dec 28 18:12:27 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:12:27 volumiolabo volumio[1150]: info: CorePlayQueue::getTrack 0 Dec 28 18:12:27 volumiolabo volumio[1150]: info: VolumeController:: Volume=undefined Mute =false Dec 28 18:12:27 volumiolabo volumio[1150]: info: CoreStateMachine::pushState Dec 28 18:12:27 volumiolabo volumio[1150]: info: CorePlayQueue::getTrack 0 Dec 28 18:12:27 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioPushState Dec 28 18:12:27 volumiolabo volumio[1150]: info: Discovery: A device disappeared from network Dec 28 18:12:27 volumiolabo volumio[1150]: info: Completed loading Core Plugins Dec 28 18:12:27 volumiolabo volumio[1150]: info: Preparing to generate the ALSA configuration file Dec 28 18:12:27 volumiolabo volumio[1150]: info: Discovery: adding 6388b31d-e944-4347-8dd6-376612044663 Dec 28 18:12:27 volumiolabo volumio[1150]: info: Discovery: Found device Volumio Dec 28 18:12:27 volumiolabo volumio[1150]: info: Discovery: Connecting to remote: 192.168.0.82 Dec 28 18:12:27 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 28 18:12:28 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:12:28 volumiolabo volumio[1150]: info: CorePlayQueue::getTrack 0 Dec 28 18:12:28 volumiolabo volumio[1150]: info: Discovery: Connected to remote: 192.168.0.82 Dec 28 18:12:28 volumiolabo volumio[1150]: info: Discovery: adding c6a87427-2d13-4943-9cab-8f71b7afbc1a Dec 28 18:12:28 volumiolabo volumio[1150]: info: Discovery: Found device Volumio_LaBo Dec 28 18:12:28 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:12:28 volumiolabo volumio[1150]: info: CorePlayQueue::getTrack 0 Dec 28 18:12:28 volumiolabo volumio[1150]: info: Discovery: this is already registered, c6a87427-2d13-4943-9cab-8f71b7afbc1a Dec 28 18:12:28 volumiolabo volumio[1150]: info: Discovery: Found device Volumio_LaBo Dec 28 18:12:28 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:12:28 volumiolabo volumio[1150]: info: CorePlayQueue::getTrack 0 Dec 28 18:12:28 volumiolabo volumio[1150]: info: Discovery: this is already registered, 6388b31d-e944-4347-8dd6-376612044663 Dec 28 18:12:28 volumiolabo volumio[1150]: info: Discovery: Found device Volumio Dec 28 18:12:28 volumiolabo volumio[1150]: info: Asound.conf file written Dec 28 18:12:29 volumiolabo sudo[1408]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 28 18:12:29 volumiolabo sudo[1408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:12:29 volumiolabo sudo[1408]: pam_unix(sudo:session): session closed for user root Dec 28 18:12:29 volumiolabo volumio[1150]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 28 18:12:29 volumiolabo volumio[1150]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 28 18:12:29 volumiolabo volumio[1150]: info: Output device has changed, restarting MPD Dec 28 18:12:29 volumiolabo sudo[1414]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 18:12:29 volumiolabo sudo[1414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:12:29 volumiolabo volumio[1150]: info: Output device has changed, restarting Shairport Sync Dec 28 18:12:29 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:12:29 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:12:29 volumiolabo sudo[1414]: pam_unix(sudo:session): session closed for user root Dec 28 18:12:29 volumiolabo sudo[1416]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 18:12:29 volumiolabo sudo[1416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:12:29 volumiolabo volumio[1150]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 18:12:29 volumiolabo volumio[1150]: info: ___________ START PLUGINS ___________ Dec 28 18:12:29 volumiolabo volumio[1150]: info: ControllerMpd::onStart: Initializing MPD Dec 28 18:12:29 volumiolabo volumio[1150]: info: Creating MPD Configuration file Dec 28 18:12:29 volumiolabo sudo[1424]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 18:12:29 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 18:12:29 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 18:12:29 volumiolabo volumio[1150]: info: [1766941949646] CoreMusicLibrary::Adding element Server multimediali Dec 28 18:12:29 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 18:12:29 volumiolabo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 28 18:12:29 volumiolabo systemd[1]: Starting mpd.service - Music Player Daemon... Dec 28 18:12:29 volumiolabo sudo[1424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:12:29 volumiolabo sudo[1424]: pam_unix(sudo:session): session closed for user root Dec 28 18:12:29 volumiolabo volumio[1150]: info: UPNP Browser: Client initialized successfully Dec 28 18:12:29 volumiolabo sudo[1428]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 28 18:12:29 volumiolabo sudo[1428]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 28 18:12:29 volumiolabo sudo[1430]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 28 18:12:29 volumiolabo sudo[1428]: pam_unix(sudo:session): session closed for user root Dec 28 18:12:29 volumiolabo sudo[1427]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 18:12:29 volumiolabo sudo[1427]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:12:29 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:12:29 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:12:30 volumiolabo systemd[1]: mpd.service: Deactivated successfully. Dec 28 18:12:30 volumiolabo systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 28 18:12:30 volumiolabo systemd[1]: mpd.socket: Deactivated successfully. Dec 28 18:12:30 volumiolabo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 28 18:12:30 volumiolabo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 28 18:12:30 volumiolabo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 28 18:12:30 volumiolabo systemd[1]: Starting mpd.service - Music Player Daemon... Dec 28 18:12:30 volumiolabo volumio[1150]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 18:12:30 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:12:30 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:12:30 volumiolabo sudo[1438]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 28 18:12:30 volumiolabo sudo[1438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 28 18:12:30 volumiolabo volumio[1150]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 18:12:30 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 18:12:30 volumiolabo volumio[1150]: info: [1766941950254] CoreMusicLibrary::Adding element Last_100 Dec 28 18:12:30 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 18:12:30 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 18:12:30 volumiolabo volumio[1150]: info: [1766941950256] CoreMusicLibrary::Adding element Webradio Dec 28 18:12:30 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 18:12:30 volumiolabo sudo[1443]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 28 18:12:30 volumiolabo sudo[1438]: pam_unix(sudo:session): session closed for user root Dec 28 18:12:30 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 18:12:30 volumiolabo volumio[1150]: info: Initializing BBC Radios Dec 28 18:12:30 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 18:12:30 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:12:30 volumiolabo volumio[1344]: Starting albumart workers Dec 28 18:12:30 volumiolabo volumio[1150]: info: Volumio Calling Home Dec 28 18:12:30 volumiolabo volumio[1345]: Starting albumart workers Dec 28 18:12:31 volumiolabo volumio[1343]: Starting albumart workers Dec 28 18:12:34 volumiolabo sudo[1494]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 28 18:12:34 volumiolabo sudo[1496]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 28 18:12:34 volumiolabo sudo[1494]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:12:34 volumiolabo sudo[1496]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:12:34 volumiolabo sudo[1494]: pam_unix(sudo:session): session closed for user root Dec 28 18:12:34 volumiolabo sudo[1496]: pam_unix(sudo:session): session closed for user root Dec 28 18:12:34 volumiolabo volumio[1150]: info: MPD Permissions set Dec 28 18:12:34 volumiolabo volumio[1150]: info: MPD Permissions set Dec 28 18:12:34 volumiolabo volumio[1150]: info: Volumio called home Dec 28 18:12:35 volumiolabo mpd[1444]: 2025-12-28T18:12:35 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 28 18:12:35 volumiolabo volumio[1150]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 28 18:12:35 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:12:35 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:12:35 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:12:35 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:12:35 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:12:35 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:12:35 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:12:35 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:12:35 volumiolabo systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Dec 28 18:12:35 volumiolabo systemd[1]: Started mpd.service - Music Player Daemon. Dec 28 18:12:35 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:12:35 volumiolabo sudo[1427]: pam_unix(sudo:session): session closed for user root Dec 28 18:12:35 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:12:35 volumiolabo systemd[1]: e2scrub_all.service: Deactivated successfully. Dec 28 18:12:35 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:12:35 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:12:35 volumiolabo systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Dec 28 18:12:35 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:12:35 volumiolabo sudo[1416]: pam_unix(sudo:session): session closed for user root Dec 28 18:12:35 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:12:35 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:12:35 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:12:35 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:12:35 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:12:35 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:12:35 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:12:35 volumiolabo volumio[1150]: info: Completed starting Core Plugins Dec 28 18:12:35 volumiolabo volumio[1150]: info: ------------------------------------------- Dec 28 18:12:35 volumiolabo volumio[1150]: info: ----- MyVolumio plugins startup ---- Dec 28 18:12:35 volumiolabo volumio[1150]: info: ------------------------------------------- Dec 28 18:12:35 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 28 18:12:35 volumiolabo volumio-remote-updater[806]: [2025-12-28 18:12:35] [connect] Successful connection Dec 28 18:12:35 volumiolabo sudo[1518]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 28 18:12:35 volumiolabo sudo[1518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:12:35 volumiolabo volumio[1150]: error: MPD error: The expression evaluated to a falsy value: Dec 28 18:12:35 volumiolabo volumio[1150]: assert.ok(self.idling) Dec 28 18:12:35 volumiolabo volumio[1150]: error: The expression evaluated to a falsy value: Dec 28 18:12:35 volumiolabo volumio[1150]: assert.ok(self.idling) Dec 28 18:12:35 volumiolabo volumio[1150]: info: Starting Shairport Sync Dec 28 18:12:35 volumiolabo volumio[1150]: info: Starting Shairport Sync Dec 28 18:12:35 volumiolabo volumio[1150]: info: Starting Shairport Sync Dec 28 18:12:35 volumiolabo systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 28 18:12:35 volumiolabo volumio[1150]: error: updateQueue error: null Dec 28 18:12:35 volumiolabo sudo[1521]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 28 18:12:35 volumiolabo sudo[1521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:12:35 volumiolabo sudo[1523]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 28 18:12:35 volumiolabo sudo[1525]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 28 18:12:35 volumiolabo sudo[1523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:12:35 volumiolabo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 28 18:12:35 volumiolabo systemd[1]: shairport-sync.service: Deactivated successfully. Dec 28 18:12:36 volumiolabo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 28 18:12:36 volumiolabo systemd[1]: shairport-sync.service: Consumed 1.521s CPU time. Dec 28 18:12:36 volumiolabo sudo[1525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:12:36 volumiolabo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 28 18:12:36 volumiolabo sudo[1521]: pam_unix(sudo:session): session closed for user root Dec 28 18:12:36 volumiolabo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 28 18:12:36 volumiolabo systemd[1]: shairport-sync.service: Deactivated successfully. Dec 28 18:12:36 volumiolabo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 28 18:12:36 volumiolabo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 28 18:12:36 volumiolabo sudo[1523]: pam_unix(sudo:session): session closed for user root Dec 28 18:12:36 volumiolabo sudo[1525]: pam_unix(sudo:session): session closed for user root Dec 28 18:12:36 volumiolabo volumio[1150]: info: MPD running with PID1444 Dec 28 18:12:36 volumiolabo volumio[1150]: ,establishing connection Dec 28 18:12:36 volumiolabo volumio-remote-updater[806]: [2025-12-28 18:12:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1766941955 101 Dec 28 18:12:36 volumiolabo volumio[1150]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 5 Dec 28 18:12:36 volumiolabo volumio[1150]: info: Shairport-Sync Started Dec 28 18:12:36 volumiolabo volumio[1150]: Error adding Membership: Error: addMembership EINVAL Dec 28 18:12:36 volumiolabo volumio[1150]: info: Shairport-Sync Started Dec 28 18:12:36 volumiolabo volumio[1150]: info: Shairport-Sync Started Dec 28 18:12:36 volumiolabo volumio[1150]: error: updateQueue error: null Dec 28 18:12:36 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:12:36 volumiolabo volumio[1150]: info: CorePlayQueue::getTrack 0 Dec 28 18:12:38 volumiolabo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Dec 28 18:12:38 volumiolabo systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 28 18:12:38 volumiolabo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 28 18:12:38 volumiolabo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 28 18:12:38 volumiolabo sudo[1518]: pam_unix(sudo:session): session closed for user root Dec 28 18:12:38 volumiolabo volumio[1150]: info: Upmpdcli Daemon Started Dec 28 18:12:38 volumiolabo sudo[1554]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 28 18:12:38 volumiolabo sudo[1554]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:12:38 volumiolabo sudo[1553]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 28 18:12:38 volumiolabo sudo[1553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:12:38 volumiolabo systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 28 18:12:38 volumiolabo mpd_monitor.sh[1574]: MPD Monitor Service: Starting MPD Monitor Service Dec 28 18:12:38 volumiolabo sudo[1554]: pam_unix(sudo:session): session closed for user root Dec 28 18:12:38 volumiolabo volumio[1150]: info: Successfully started MPD Monitor Dec 28 18:12:38 volumiolabo systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 28 18:12:38 volumiolabo systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 28 18:12:38 volumiolabo systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 28 18:12:38 volumiolabo systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 28 18:12:38 volumiolabo mpd_monitor.sh[1578]: MPD Monitor Service: Starting MPD Monitor Service Dec 28 18:12:38 volumiolabo sudo[1553]: pam_unix(sudo:session): session closed for user root Dec 28 18:12:38 volumiolabo volumio[1150]: info: Successfully started MPD Monitor Dec 28 18:12:40 volumiolabo volumio[1150]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 28 18:12:44 volumiolabo volumio[1150]: info: Adding plugin bluetooth to MyMusic Plugins Dec 28 18:12:44 volumiolabo volumio[1150]: info: Adding plugin multiroom to MyMusic Plugins Dec 28 18:12:44 volumiolabo volumio[1150]: info: Adding plugin metavolumio to MyMusic Plugins Dec 28 18:12:44 volumiolabo volumio[1150]: info: Adding plugin cd_controller to MyMusic Plugins Dec 28 18:12:44 volumiolabo volumio[1150]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 28 18:12:44 volumiolabo volumio[1150]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 28 18:12:44 volumiolabo volumio[1150]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 28 18:12:44 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 28 18:12:47 volumiolabo upmpdcli[1597]: writing RSA key Dec 28 18:12:48 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 28 18:12:48 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 28 18:12:48 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:12:48 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:12:48 volumiolabo volumio[1150]: info: Starting MyVolumio Remote Streaming Endpoints Dec 28 18:12:48 volumiolabo volumio[1150]: info: MyVolumio login type: Token Dec 28 18:12:48 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 28 18:12:48 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 28 18:12:50 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 28 18:12:50 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 28 18:12:50 volumiolabo volumio[1150]: info: Streaming services startup Dec 28 18:12:50 volumiolabo volumio[1150]: info: Starting Streaming Daemon Dec 28 18:12:50 volumiolabo sudo[1608]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 28 18:12:50 volumiolabo sudo[1608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:12:50 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 28 18:12:50 volumiolabo sudo[1608]: pam_unix(sudo:session): session closed for user root Dec 28 18:12:50 volumiolabo volumio[1150]: error: Cannot start Volumio Streaming Daemon Dec 28 18:12:50 volumiolabo volumio[1150]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 28 18:12:50 volumiolabo volumio[1150]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 28 18:12:50 volumiolabo volumio[1150]: error: MyVolumio Custom Token format not valid, refreshing it Dec 28 18:12:51 volumiolabo volumio[1150]: info: MyVolumio login type: Token Dec 28 18:12:52 volumiolabo volumio[1150]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 28 18:12:53 volumiolabo volumio[1150]: info: MyVolumio token set successfully Dec 28 18:12:53 volumiolabo volumio[1150]: info: MYVOLUMIO: Adding device Dec 28 18:12:53 volumiolabo volumio[1150]: info: MYVOLUMIO: Evaluating Server Dec 28 18:12:54 volumiolabo volumio[1150]: info: MyVolumio Plan changed: premium Dec 28 18:12:54 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Dec 28 18:12:54 volumiolabo volumio[1150]: info: Removing browser output: myVolumio user plan is not superstar Dec 28 18:12:54 volumiolabo volumio[1150]: info: Removing audio output: Dec 28 18:12:54 volumiolabo volumio[1150]: info: MYVOLUMIO: Adding device Dec 28 18:12:54 volumiolabo volumio[1150]: info: MYVOLUMIO: Evaluating Server Dec 28 18:12:54 volumiolabo volumio[1150]: info: Remote config written successfully Dec 28 18:12:54 volumiolabo volumio[1150]: info: Starting Tunnel 1 Dec 28 18:12:54 volumiolabo volumio[1150]: info: Starting Tunnel Connection Checker Dec 28 18:12:54 volumiolabo volumio[1150]: info: Completed starting MyVolumio Plugin Dec 28 18:12:54 volumiolabo volumio[1150]: info: MYVolumio Device enabled Dec 28 18:12:54 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 28 18:12:54 volumiolabo volumio[1150]: info: MyVolumio status changed Dec 28 18:12:54 volumiolabo volumio[1150]: info: Streaming services startup Dec 28 18:12:54 volumiolabo volumio[1150]: info: Starting Streaming Daemon Dec 28 18:12:55 volumiolabo volumio[1150]: info: Setting Geolocation for MyVolumio to eu7 Dec 28 18:12:55 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:12:55 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:12:55 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:12:55 volumiolabo sudo[1667]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 28 18:12:55 volumiolabo sudo[1667]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:12:55 volumiolabo volumio[1150]: info: Setting Geolocation for MyVolumio to eu7 Dec 28 18:12:55 volumiolabo systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 28 18:12:55 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:12:55 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:12:55 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:12:55 volumiolabo sudo[1667]: pam_unix(sudo:session): session closed for user root Dec 28 18:12:55 volumiolabo volumio[1150]: error: Cannot start Volumio Streaming Daemon Dec 28 18:12:55 volumiolabo volumio[1150]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 28 18:12:55 volumiolabo volumio[1150]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 28 18:12:55 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 28 18:12:55 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Dec 28 18:12:56 volumiolabo volumio[1150]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Dec 28 18:12:56 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Dec 28 18:12:59 volumiolabo systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 28 18:12:59 volumiolabo systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 28 18:13:01 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Dec 28 18:13:01 volumiolabo sudo[1700]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 28 18:13:01 volumiolabo sudo[1700]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:01 volumiolabo sudo[1700]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:01 volumiolabo volumio[1150]: info: MRS: MultiRoom plugin initialized Dec 28 18:13:01 volumiolabo volumio[1150]: info: MRS: STOPPING SNAPCLIENT Dec 28 18:13:01 volumiolabo volumio[1150]: info: MRS: Snap server stop Dec 28 18:13:01 volumiolabo volumio[1150]: info: MRS: STOPPING volumioStreaming Dec 28 18:13:02 volumiolabo sudo[1719]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Dec 28 18:13:02 volumiolabo sudo[1717]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 28 18:13:02 volumiolabo sudo[1717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:02 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Dec 28 18:13:02 volumiolabo sudo[1719]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:02 volumiolabo sudo[1723]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 28 18:13:02 volumiolabo sudo[1723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:02 volumiolabo sudo[1723]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:02 volumiolabo sudo[1721]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Dec 28 18:13:02 volumiolabo sudo[1721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:02 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Dec 28 18:13:02 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Dec 28 18:13:02 volumiolabo sudo[1721]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:02 volumiolabo sudo[1719]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:02 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Dec 28 18:13:02 volumiolabo sudo[1717]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:02 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Dec 28 18:13:02 volumiolabo kernel: usb 1-1.1: USB disconnect, device number 3 Dec 28 18:13:02 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Dec 28 18:13:02 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Dec 28 18:13:02 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Dec 28 18:13:02 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Dec 28 18:13:02 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Dec 28 18:13:02 volumiolabo kernel: usb 1-1.1: new high-speed USB device number 4 using xhci_hcd Dec 28 18:13:02 volumiolabo volumio[1150]: info: Preparing to generate the ALSA configuration file Dec 28 18:13:03 volumiolabo kernel: usb 1-1.1: New USB device found, idVendor=2772, idProduct=0221, bcdDevice= 3.43 Dec 28 18:13:03 volumiolabo kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 28 18:13:03 volumiolabo kernel: usb 1-1.1: Product: Project RS USB Audio 2.0 Dec 28 18:13:03 volumiolabo kernel: usb 1-1.1: Manufacturer: Project RS Dec 28 18:13:03 volumiolabo kernel: usb 1-1.1: SerialNumber: 0000 Dec 28 18:13:03 volumiolabo volumio[1150]: info: Updating MyVolumio device info Dec 28 18:13:03 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:13:03 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:13:03 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:13:03 volumiolabo volumio[1150]: info: Updating MyVolumio device info Dec 28 18:13:03 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:13:03 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:13:03 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:13:03 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 18:13:03 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:13:03 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 28 18:13:03 volumiolabo volumio[1150]: info: Successfully Added MyVolumio device Dec 28 18:13:03 volumiolabo volumio[1150]: info: Successfully Added MyVolumio device Dec 28 18:13:03 volumiolabo volumio[1150]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 28 18:13:03 volumiolabo volumio[1150]: info: Reading ALSA contributions from plugins. Dec 28 18:13:03 volumiolabo volumio[1150]: info: MRS: Removed streaming files Dec 28 18:13:03 volumiolabo volumio[1150]: info: MRS: volumioStreaming STOPPED Dec 28 18:13:03 volumiolabo volumio[1150]: info: MRS: SNAPSERVER STOPPED Dec 28 18:13:03 volumiolabo volumio[1150]: info: MRS: SNAPCLIENT STOPPED Dec 28 18:13:03 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 28 18:13:03 volumiolabo volumio-remote-updater[806]: Test mode disabled Dec 28 18:13:03 volumiolabo volumio-remote-updater[806]: Alpha mode disabled Dec 28 18:13:03 volumiolabo volumio-remote-updater[806]: Alpha legacy test mode disabled Dec 28 18:13:03 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 28 18:13:03 volumiolabo volumio[1150]: info: Dec 28 18:13:03 volumiolabo volumio[1150]: ---------------------------- USB Audio Device Detached Dec 28 18:13:03 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 28 18:13:03 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 28 18:13:03 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 28 18:13:04 volumiolabo volumio[1150]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 28 18:13:04 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 28 18:13:04 volumiolabo volumio[1150]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 28 18:13:04 volumiolabo volumio[1150]: info: Dec 28 18:13:04 volumiolabo volumio[1150]: ---------------------------- USB Audio Device Attached Dec 28 18:13:04 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 28 18:13:04 volumiolabo sudo[1764]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 28 18:13:04 volumiolabo sudo[1764]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:04 volumiolabo volumio[1150]: info: CoreCommandRouter::Close All Modals sent Dec 28 18:13:04 volumiolabo volumio[1150]: info: Preparing to save Alsa Options, stopping services first Dec 28 18:13:04 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:04 volumiolabo volumio[1150]: info: CorePlayQueue::getTrack 0 Dec 28 18:13:04 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioPause Dec 28 18:13:04 volumiolabo volumio[1150]: info: CoreStateMachine::pause Dec 28 18:13:04 volumiolabo volumio[1150]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Project RS USB Audio 2.0","alsacard":"P20"},"i2s":false} Dec 28 18:13:04 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 28 18:13:04 volumiolabo systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 28 18:13:04 volumiolabo systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 28 18:13:04 volumiolabo systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 28 18:13:04 volumiolabo autossh[1776]: port set to 0, monitoring disabled Dec 28 18:13:04 volumiolabo autossh[1776]: starting ssh (count 1) Dec 28 18:13:04 volumiolabo autossh[1776]: ssh child pid is 1779 Dec 28 18:13:04 volumiolabo sudo[1764]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:04 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 28 18:13:04 volumiolabo volumiossh-tunnel[1779]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Dec 28 18:13:04 volumiolabo autossh[1776]: ssh exited prematurely with status 255; autossh exiting Dec 28 18:13:04 volumiolabo systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Dec 28 18:13:04 volumiolabo systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Dec 28 18:13:05 volumiolabo volumio[1150]: info: Setting mixer Project RS Audio 2.0 Output for card Project RS USB Audio 2.0 Dec 28 18:13:05 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 28 18:13:05 volumiolabo volumio[1150]: info: Updating Volume Controller Parameters: Device: 5 Name: Project RS USB Audio 2.0 Mixer: Project RS Audio 2.0 Output Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 28 18:13:05 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 28 18:13:05 volumiolabo volumio[1150]: info: Disabling external Volume Control Dec 28 18:13:05 volumiolabo volumio[1150]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 18:13:05 volumiolabo volumio[1150]: info: Successfully Updated MyVolumio device Dec 28 18:13:05 volumiolabo systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 1. Dec 28 18:13:05 volumiolabo systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Dec 28 18:13:05 volumiolabo volumio[1150]: info: Remote SSH Started Dec 28 18:13:05 volumiolabo systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 28 18:13:05 volumiolabo autossh[1789]: port set to 0, monitoring disabled Dec 28 18:13:05 volumiolabo autossh[1789]: starting ssh (count 1) Dec 28 18:13:05 volumiolabo autossh[1789]: ssh child pid is 1792 Dec 28 18:13:05 volumiolabo volumiossh-tunnel[1792]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Dec 28 18:13:05 volumiolabo autossh[1789]: ssh exited prematurely with status 255; autossh exiting Dec 28 18:13:05 volumiolabo systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Dec 28 18:13:05 volumiolabo systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Dec 28 18:13:05 volumiolabo systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 2. Dec 28 18:13:05 volumiolabo systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Dec 28 18:13:05 volumiolabo systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 28 18:13:05 volumiolabo autossh[1794]: port set to 0, monitoring disabled Dec 28 18:13:05 volumiolabo autossh[1794]: starting ssh (count 1) Dec 28 18:13:05 volumiolabo autossh[1794]: ssh child pid is 1797 Dec 28 18:13:05 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 28 18:13:05 volumiolabo volumiossh-tunnel[1797]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Dec 28 18:13:05 volumiolabo autossh[1794]: ssh exited prematurely with status 255; autossh exiting Dec 28 18:13:05 volumiolabo systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Dec 28 18:13:05 volumiolabo systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Dec 28 18:13:05 volumiolabo volumio[1150]: info: Successfully Updated MyVolumio device Dec 28 18:13:06 volumiolabo volumio[1150]: info: VolumeController:: Volume=100 Mute =false Dec 28 18:13:06 volumiolabo volumio[1150]: info: CoreStateMachine::pushState Dec 28 18:13:06 volumiolabo volumio[1150]: info: CorePlayQueue::getTrack 0 Dec 28 18:13:06 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 18:13:06 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioPushState Dec 28 18:13:06 volumiolabo systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 3. Dec 28 18:13:06 volumiolabo systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Dec 28 18:13:06 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 28 18:13:06 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:13:06 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 28 18:13:06 volumiolabo systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 28 18:13:06 volumiolabo autossh[1803]: port set to 0, monitoring disabled Dec 28 18:13:06 volumiolabo autossh[1803]: starting ssh (count 1) Dec 28 18:13:06 volumiolabo autossh[1803]: ssh child pid is 1807 Dec 28 18:13:06 volumiolabo volumiossh-tunnel[1807]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Dec 28 18:13:06 volumiolabo autossh[1803]: ssh exited prematurely with status 255; autossh exiting Dec 28 18:13:06 volumiolabo systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Dec 28 18:13:06 volumiolabo systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Dec 28 18:13:06 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 18:13:06 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 18:13:06 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 18:13:06 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 18:13:06 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 18:13:06 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 18:13:06 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 18:13:06 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 28 18:13:06 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 28 18:13:06 volumiolabo volumio[1150]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 28 18:13:06 volumiolabo volumio[1150]: info: Asound.conf file written Dec 28 18:13:06 volumiolabo systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 4. Dec 28 18:13:06 volumiolabo systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Dec 28 18:13:06 volumiolabo sudo[1811]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 28 18:13:06 volumiolabo sudo[1811]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:06 volumiolabo sudo[1811]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:06 volumiolabo systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 28 18:13:06 volumiolabo autossh[1814]: port set to 0, monitoring disabled Dec 28 18:13:06 volumiolabo autossh[1814]: starting ssh (count 1) Dec 28 18:13:06 volumiolabo autossh[1814]: ssh child pid is 1819 Dec 28 18:13:06 volumiolabo volumiossh-tunnel[1819]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Dec 28 18:13:06 volumiolabo autossh[1814]: ssh exited prematurely with status 255; autossh exiting Dec 28 18:13:06 volumiolabo systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Dec 28 18:13:06 volumiolabo systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Dec 28 18:13:06 volumiolabo volumio[1150]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 28 18:13:06 volumiolabo volumio[1150]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 28 18:13:06 volumiolabo volumio[1150]: info: Output device has changed, restarting MPD Dec 28 18:13:07 volumiolabo volumio[1150]: info: Output device has changed, restarting Shairport Sync Dec 28 18:13:07 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:07 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:13:07 volumiolabo sudo[1822]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 18:13:07 volumiolabo sudo[1822]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:07 volumiolabo sudo[1824]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 18:13:07 volumiolabo sudo[1824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:07 volumiolabo sudo[1822]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:07 volumiolabo volumio[1150]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 18:13:07 volumiolabo volumio[1150]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 28 18:13:07 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:07 volumiolabo systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 5. Dec 28 18:13:07 volumiolabo systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Dec 28 18:13:07 volumiolabo systemd[1]: sshtunnel.service: Start request repeated too quickly. Dec 28 18:13:07 volumiolabo systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Dec 28 18:13:07 volumiolabo systemd[1]: Failed to start sshtunnel.service - MyVolumio SSH Tunnel. Dec 28 18:13:07 volumiolabo volumio[1150]: info: Preparing to generate the ALSA configuration file Dec 28 18:13:07 volumiolabo systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 28 18:13:07 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Dec 28 18:13:07 volumiolabo volumio[1150]: ------------------------------------ BT MESSAGE: [FUNC] onStart Dec 28 18:13:07 volumiolabo volumio[1150]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Dec 28 18:13:07 volumiolabo volumio[1150]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Dec 28 18:13:07 volumiolabo volumio[1150]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Dec 28 18:13:07 volumiolabo volumio[1150]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Dec 28 18:13:07 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Dec 28 18:13:07 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Dec 28 18:13:07 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output for this device Dec 28 18:13:07 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output Dec 28 18:13:07 volumiolabo volumio[1150]: info: Adding audio output: Dec 28 18:13:07 volumiolabo volumio[1150]: info: Adding audio output: Dec 28 18:13:07 volumiolabo systemd[1]: mpd.service: Deactivated successfully. Dec 28 18:13:07 volumiolabo systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 28 18:13:07 volumiolabo systemd[1]: mpd.service: Consumed 3.077s CPU time. Dec 28 18:13:07 volumiolabo systemd[1]: mpd.socket: Deactivated successfully. Dec 28 18:13:07 volumiolabo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 28 18:13:07 volumiolabo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 28 18:13:07 volumiolabo bluetoothd[774]: Path / reserved for Adv Monitor app :1.21 Dec 28 18:13:07 volumiolabo bluetoothd[774]: Adv Monitor app :1.21 disconnected from D-Bus Dec 28 18:13:07 volumiolabo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 28 18:13:07 volumiolabo systemd[1]: Starting mpd.service - Music Player Daemon... Dec 28 18:13:07 volumiolabo volumio[1150]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 28 18:13:07 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:07 volumiolabo sudo[1836]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 28 18:13:07 volumiolabo sudo[1836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 28 18:13:07 volumiolabo sudo[1836]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:07 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Dec 28 18:13:07 volumiolabo volumio[1150]: info: Adding METAVOLUMIO REST API Endpoints Dec 28 18:13:07 volumiolabo volumio[1150]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Dec 28 18:13:07 volumiolabo volumio[1150]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Dec 28 18:13:07 volumiolabo volumio[1150]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Dec 28 18:13:07 volumiolabo volumio[1150]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Dec 28 18:13:07 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Dec 28 18:13:07 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Dec 28 18:13:07 volumiolabo volumio[1150]: info: Preparing CD Folders Dec 28 18:13:07 volumiolabo volumio[1150]: info: Adding CD REST API Endpoints Dec 28 18:13:07 volumiolabo volumio[1150]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Dec 28 18:13:07 volumiolabo volumio[1150]: info: Starting UDEV Watcher for CD Dec 28 18:13:07 volumiolabo volumio[1150]: info: Detecting CD presence with UDEV Dec 28 18:13:07 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Dec 28 18:13:08 volumiolabo kernel: usb 1-1.1: USB disconnect, device number 4 Dec 28 18:13:09 volumiolabo mpd[1841]: 2025-12-28T18:13:09 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 28 18:13:09 volumiolabo systemd[1]: Started mpd.service - Music Player Daemon. Dec 28 18:13:09 volumiolabo sudo[1824]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:09 volumiolabo kernel: usb 1-1.1: new high-speed USB device number 5 using xhci_hcd Dec 28 18:13:09 volumiolabo kernel: usb 1-1.1: New USB device found, idVendor=2772, idProduct=0221, bcdDevice= 3.43 Dec 28 18:13:09 volumiolabo kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 28 18:13:09 volumiolabo kernel: usb 1-1.1: Product: Project RS USB Audio 2.0 Dec 28 18:13:09 volumiolabo kernel: usb 1-1.1: Manufacturer: Project RS Dec 28 18:13:09 volumiolabo kernel: usb 1-1.1: SerialNumber: 0000 Dec 28 18:13:11 volumiolabo kernel: usb 1-1.1: USB disconnect, device number 5 Dec 28 18:13:11 volumiolabo kernel: usb 1-1.1: new high-speed USB device number 6 using xhci_hcd Dec 28 18:13:11 volumiolabo kernel: usb 1-1.1: New USB device found, idVendor=2772, idProduct=0221, bcdDevice= 3.43 Dec 28 18:13:11 volumiolabo kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 28 18:13:11 volumiolabo kernel: usb 1-1.1: Product: Project RS USB Audio 2.0 Dec 28 18:13:11 volumiolabo kernel: usb 1-1.1: Manufacturer: Project RS Dec 28 18:13:11 volumiolabo kernel: usb 1-1.1: SerialNumber: 0000 Dec 28 18:13:13 volumiolabo volumio[1150]: warn: [cd-plugin] cdspeedctl: device or media not ready Dec 28 18:13:13 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Dec 28 18:13:13 volumiolabo volumio[1150]: info: Adding inputs REST Endpoints Dec 28 18:13:13 volumiolabo volumio[1150]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Dec 28 18:13:13 volumiolabo volumio[1150]: info: Scanning Audio Inputs Dec 28 18:13:13 volumiolabo volumio[1150]: info: Checking against Known Cards name Dec 28 18:13:13 volumiolabo volumio[1150]: info: Adding Server instance for streaming Dec 28 18:13:13 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Dec 28 18:13:13 volumiolabo volumio[1150]: error: Hi Res Audio Failed Login: Missing Login Data Dec 28 18:13:13 volumiolabo volumio[1150]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 28 18:13:13 volumiolabo volumio[1150]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 28 18:13:13 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Dec 28 18:13:13 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Dec 28 18:13:13 volumiolabo volumio[1150]: info: Refreshing QOBUZ token Dec 28 18:13:13 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Dec 28 18:13:13 volumiolabo volumio[1150]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Dec 28 18:13:13 volumiolabo volumio[1150]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Dec 28 18:13:13 volumiolabo sudo[1870]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 28 18:13:13 volumiolabo sudo[1870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:13 volumiolabo volumio[1150]: info: QobuzConnect: Starting Qobuz Connect socket and service Dec 28 18:13:13 volumiolabo sudo[1870]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:13 volumiolabo volumio[1150]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Dec 28 18:13:13 volumiolabo volumio[1150]: info: Adding TIDAL REST API Endpoints Dec 28 18:13:13 volumiolabo volumio[1150]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 28 18:13:13 volumiolabo volumio[1150]: info: Reading ALSA contributions from plugins. Dec 28 18:13:13 volumiolabo sudo[1877]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 28 18:13:13 volumiolabo sudo[1877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:13 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:13 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:13 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 28 18:13:13 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 28 18:13:13 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 28 18:13:13 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetBrowseSources Dec 28 18:13:13 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 18:13:13 volumiolabo volumio[1150]: info: MRS: Getting audio outputs on start Dec 28 18:13:13 volumiolabo volumio[1150]: info: MRS: Requesting all other devices output Dec 28 18:13:13 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 28 18:13:13 volumiolabo sudo[1877]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:13 volumiolabo volumio[1150]: info: CoreStateMachine::getcurrentVolume Dec 28 18:13:13 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 18:13:13 volumiolabo sudo[1880]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 28 18:13:13 volumiolabo sudo[1880]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:13 volumiolabo volumio[1150]: info: MRS: Adding multiroomSync output Dec 28 18:13:13 volumiolabo volumio[1150]: info: Adding audio output: Dec 28 18:13:13 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output Dec 28 18:13:13 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:13 volumiolabo volumio[1150]: info: CorePlayQueue::getTrack 0 Dec 28 18:13:13 volumiolabo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 28 18:13:13 volumiolabo sudo[1880]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:13 volumiolabo volumio[1150]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Dec 28 18:13:14 volumiolabo volumio[1150]: info: MPD Permissions set Dec 28 18:13:14 volumiolabo sudo[1886]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Dec 28 18:13:14 volumiolabo sudo[1886]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:14 volumiolabo systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Dec 28 18:13:14 volumiolabo volumio[1150]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 28 18:13:14 volumiolabo volumiobt[1890]: INFO [BTSTART] Ensuring Bluetooth directory exists... Dec 28 18:13:14 volumiolabo sudo[1886]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:14 volumiolabo sudo[1891]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Dec 28 18:13:14 volumiolabo sudo[1891]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:14 volumiolabo sudo[1891]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:14 volumiolabo sudo[1894]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Dec 28 18:13:14 volumiolabo sudo[1894]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:14 volumiolabo sudo[1894]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:14 volumiolabo volumiobt[1896]: INFO [BTSTART] Powering on Bluetooth if needed... Dec 28 18:13:14 volumiolabo kernel: usb 1-1.1: USB disconnect, device number 6 Dec 28 18:13:14 volumiolabo bluetoothd[774]: Adv Monitor app :1.23 disconnected from D-Bus Dec 28 18:13:14 volumiolabo volumiobt[1899]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Dec 28 18:13:14 volumiolabo volumiobt[1900]: [176B blob data] Dec 28 18:13:14 volumiolabo volumiobt[1900]: [157B blob data] Dec 28 18:13:14 volumiolabo volumiobt[1900]: [157B blob data] Dec 28 18:13:14 volumiolabo volumiobt[1900]: [157B blob data] Dec 28 18:13:14 volumiolabo volumiobt[1900]: [113B blob data] Dec 28 18:13:14 volumiolabo volumiobt[1900]: [bluetoothctl]> discoverable on Dec 28 18:13:14 volumiolabo volumiobt[1900]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Dec 28 18:13:14 volumiolabo volumiobt[1900]: [bluetoothctl]> pairable on Dec 28 18:13:14 volumiolabo bluetoothd[774]: Path / reserved for Adv Monitor app :1.24 Dec 28 18:13:14 volumiolabo bluetoothd[774]: Adv Monitor app :1.24 disconnected from D-Bus Dec 28 18:13:14 volumiolabo volumiobt[1900]: [bluetoothctl]> Dec 28 18:13:14 volumiolabo volumiobt[1901]: INFO [BTSTART] Registering Bluetooth agent... Dec 28 18:13:14 volumiolabo volumiobt[1902]: [NEW] Media /org/bluez/hci0 Dec 28 18:13:14 volumiolabo volumiobt[1902]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 28 18:13:14 volumiolabo volumiobt[1902]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 28 18:13:14 volumiolabo volumiobt[1902]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Dec 28 18:13:14 volumiolabo bluetoothd[774]: Adv Monitor app :1.25 disconnected from D-Bus Dec 28 18:13:14 volumiolabo volumiobt[1903]: No agent is registered Dec 28 18:13:14 volumiolabo volumiobt[1903]: [NEW] Media /org/bluez/hci0 Dec 28 18:13:14 volumiolabo volumiobt[1903]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 28 18:13:14 volumiolabo volumiobt[1903]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 28 18:13:14 volumiolabo volumiobt[1903]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Dec 28 18:13:14 volumiolabo bluetoothd[774]: Adv Monitor app :1.26 disconnected from D-Bus Dec 28 18:13:14 volumiolabo volumiobt[1904]: INFO [BTSTART] Agent registered successfully. Dec 28 18:13:14 volumiolabo volumiobt[1905]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Dec 28 18:13:14 volumiolabo volumio[1150]: info: VolumeController:: Volume=undefined Mute =false Dec 28 18:13:14 volumiolabo volumio[1150]: info: CoreStateMachine::pushState Dec 28 18:13:14 volumiolabo volumio[1150]: info: CorePlayQueue::getTrack 0 Dec 28 18:13:14 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioPushState Dec 28 18:13:14 volumiolabo kernel: usb 1-1.1: new high-speed USB device number 7 using xhci_hcd Dec 28 18:13:14 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output update for this device Dec 28 18:13:14 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output Dec 28 18:13:14 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:14 volumiolabo volumio[1150]: info: CorePlayQueue::getTrack 0 Dec 28 18:13:14 volumiolabo volumio[1150]: info: CoreStateMachine::updateTrackBlock Dec 28 18:13:14 volumiolabo volumio[1150]: info: CorePlayQueue::getTrackBlock Dec 28 18:13:14 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 18:13:14 volumiolabo kernel: usb 1-1.1: New USB device found, idVendor=2772, idProduct=0221, bcdDevice= 3.43 Dec 28 18:13:14 volumiolabo kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 28 18:13:14 volumiolabo kernel: usb 1-1.1: Product: Project RS USB Audio 2.0 Dec 28 18:13:14 volumiolabo kernel: usb 1-1.1: Manufacturer: Project RS Dec 28 18:13:14 volumiolabo kernel: usb 1-1.1: SerialNumber: 0000 Dec 28 18:13:14 volumiolabo volumio[1150]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Dec 28 18:13:14 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioStop Dec 28 18:13:14 volumiolabo volumio[1150]: info: CoreStateMachine::stop Dec 28 18:13:14 volumiolabo volumio[1150]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 18:13:14 volumiolabo volumio[1150]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Dec 28 18:13:14 volumiolabo volumio[1150]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 18:13:15 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:15 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:15 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:15 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:15 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:15 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:15 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:15 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:15 volumiolabo kernel: usb 1-1.1: USB disconnect, device number 7 Dec 28 18:13:15 volumiolabo volumiobt[1906]: INFO:a2dp-agent:Connecting to system bus. Dec 28 18:13:15 volumiolabo volumiobt[1906]: INFO:a2dp-agent:Found Bluetooth adapter at: /org/bluez/hci0 Dec 28 18:13:15 volumiolabo volumiobt[1906]: INFO:a2dp-agent:Enabling infinite discovery on adapter. Dec 28 18:13:15 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:13:15 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:15 volumiolabo volumiobt[1906]: INFO:a2dp-agent:Registering agent. Dec 28 18:13:15 volumiolabo volumiobt[1906]: INFO:a2dp-agent:Searching for MediaPlayer1 interface... Dec 28 18:13:15 volumiolabo volumiobt[1906]: WARNING:a2dp-agent:No active MediaPlayer1 found. Metadata updates won't work. Dec 28 18:13:15 volumiolabo volumio[1150]: info: Dec 28 18:13:15 volumiolabo volumio[1150]: ---------------------------- USB Audio Device Detached Dec 28 18:13:15 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 28 18:13:15 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 28 18:13:15 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 28 18:13:15 volumiolabo (udev-worker)[1847]: controlC5: /usr/lib/udev/rules.d/78-sound-card.rules:5 Failed to write ATTR{/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1:1.0/sound/card5/controlC5/../uevent}, ignoring: No such file or directory Dec 28 18:13:15 volumiolabo volumio[1150]: info: Access Token successfully retrieved Dec 28 18:13:15 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 18:13:15 volumiolabo volumio[1150]: info: [1766941995489] CoreMusicLibrary::Adding element QOBUZ Dec 28 18:13:15 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 18:13:15 volumiolabo volumio[1150]: Cannot find translation for source QOBUZ Dec 28 18:13:15 volumiolabo kernel: usb 1-1.1: new high-speed USB device number 8 using xhci_hcd Dec 28 18:13:15 volumiolabo volumio[1150]: info: Stopping AccessToken refresher cron for QOBUZ Dec 28 18:13:15 volumiolabo kernel: usb 1-1.1: New USB device found, idVendor=2772, idProduct=0221, bcdDevice= 3.43 Dec 28 18:13:15 volumiolabo kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 28 18:13:15 volumiolabo kernel: usb 1-1.1: Product: Project RS USB Audio 2.0 Dec 28 18:13:15 volumiolabo kernel: usb 1-1.1: Manufacturer: Project RS Dec 28 18:13:15 volumiolabo kernel: usb 1-1.1: SerialNumber: 0000 Dec 28 18:13:15 volumiolabo volumio[1150]: info: AccessToken refresher cron started for QOBUZ Dec 28 18:13:15 volumiolabo volumio[1150]: info: Adding QOBUZ REST API Endpoints Dec 28 18:13:15 volumiolabo volumio[1150]: error: updateQueue error: null Dec 28 18:13:15 volumiolabo volumio[1150]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 28 18:13:15 volumiolabo volumio[1150]: info: VolumeController:: Volume=undefined Mute =false Dec 28 18:13:15 volumiolabo volumio[1150]: info: CoreStateMachine::pushState Dec 28 18:13:15 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 18:13:15 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioPushState Dec 28 18:13:15 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output update for this device Dec 28 18:13:15 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output Dec 28 18:13:15 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:16 volumiolabo kernel: usb 1-1.1: USB disconnect, device number 8 Dec 28 18:13:16 volumiolabo volumio[1150]: info: Executing endpoint qc_getconfig Dec 28 18:13:16 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 28 18:13:16 volumiolabo qobuz-connect[1883]: 20251228 18:13:16.364 [1883.1883] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 28 18:13:16 volumiolabo qobuz-connect[1883]: 20251228 18:13:16.459 [1883.1883] INFO VolumeManager: [0x1f62348]: Setting new playback volume: 75 Dec 28 18:13:16 volumiolabo qobuz-connect[1883]: 20251228 18:13:16.459 [1883.1883] INFO VolumeManager: [0x1f62348]: Setting new mute state: 0 Dec 28 18:13:16 volumiolabo qobuz-connect[1883]: 20251228 18:13:16.459 [1883.1883] INFO QobuzConnect: [0x1f62d18]: Client initialized! Dec 28 18:13:16 volumiolabo qobuz-connect[1883]: 20251228 18:13:16.459 [1883.1883] INFO SampleApp: Starting Avahi advertising, name: Volumio_LaBo, service name: _qobuz-connect._tcp Dec 28 18:13:16 volumiolabo qobuz-connect[1883]: 20251228 18:13:16.482 [1883.1883] INFO LocalConfigManager: [0x1f61c28]: Starting Local Configuration server Dec 28 18:13:16 volumiolabo qobuz-connect[1883]: 20251228 18:13:16.482 [1883.1883] INFO SampleApp: Starting Local configuration server Dec 28 18:13:16 volumiolabo qobuz-connect[1883]: 20251228 18:13:16.483 [1883.1883] INFO SampleApp: Connected to UNIX socket client 0x1f4c818 Dec 28 18:13:16 volumiolabo kernel: usb 1-1.1: new high-speed USB device number 9 using xhci_hcd Dec 28 18:13:16 volumiolabo volumio[1150]: info: Starting Shairport Sync Dec 28 18:13:16 volumiolabo qobuz-connect[1883]: 20251228 18:13:16.514 [1883.1883] INFO SampleApp: Playback volume changed: 75 Dec 28 18:13:16 volumiolabo sudo[1927]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 28 18:13:16 volumiolabo sudo[1927]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:16 volumiolabo volumio[1150]: info: MRS: Found cast device: BRAVIA-4K-UR1-7dc975fca967208b4c3c0d3d3c3704a1 Dec 28 18:13:16 volumiolabo volumio[1150]: info: Adding audio output: Dec 28 18:13:16 volumiolabo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 28 18:13:16 volumiolabo systemd[1]: shairport-sync.service: Deactivated successfully. Dec 28 18:13:16 volumiolabo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 28 18:13:16 volumiolabo systemd[1]: shairport-sync.service: Consumed 1.721s CPU time. Dec 28 18:13:16 volumiolabo volumio[1150]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 28 18:13:16 volumiolabo volumio[1150]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 28 18:13:16 volumiolabo kernel: usb 1-1.1: New USB device found, idVendor=2772, idProduct=0221, bcdDevice= 3.43 Dec 28 18:13:16 volumiolabo kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 28 18:13:16 volumiolabo kernel: usb 1-1.1: Product: Project RS USB Audio 2.0 Dec 28 18:13:16 volumiolabo kernel: usb 1-1.1: Manufacturer: Project RS Dec 28 18:13:16 volumiolabo kernel: usb 1-1.1: SerialNumber: 0000 Dec 28 18:13:16 volumiolabo kernel: usb 1-1.1: parse_audio_format_rates_v2v3(): unable to retrieve number of sample rates (clock 41) Dec 28 18:13:16 volumiolabo kernel: usb 1-1.1: 10:0: failed to get current value for ch 1 (-71) Dec 28 18:13:16 volumiolabo kernel: usb 1-1.1: 10:0: failed to get current value for ch 2 (-71) Dec 28 18:13:16 volumiolabo kernel: usb 1-1.1: 10:0: failed to get current value for ch 0 (-71) Dec 28 18:13:16 volumiolabo kernel: usb 1-1.1: 10:0: cannot get min/max values for control 2 (id 10) Dec 28 18:13:16 volumiolabo kernel: usb 1-1.1: 10:0: cannot get min/max values for control 2 (id 10) Dec 28 18:13:16 volumiolabo kernel: usb 1-1.1: USB disconnect, device number 9 Dec 28 18:13:16 volumiolabo volumio[1150]: info: TidalConnect service stoped! Dec 28 18:13:16 volumiolabo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 28 18:13:16 volumiolabo sudo[1927]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:16 volumiolabo volumio[1150]: info: Asound.conf file unchanged, so no further update is needed Dec 28 18:13:16 volumiolabo volumio[1150]: info: Output device has changed, restarting MPD Dec 28 18:13:16 volumiolabo volumio[1150]: info: Output device has changed, restarting Shairport Sync Dec 28 18:13:16 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:16 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:13:16 volumiolabo sudo[1949]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 18:13:16 volumiolabo sudo[1949]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:16 volumiolabo sudo[1949]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:16 volumiolabo sudo[1951]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 18:13:16 volumiolabo volumio[1150]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 18:13:16 volumiolabo volumio[1150]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 28 18:13:16 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:16 volumiolabo sudo[1951]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:17 volumiolabo volumio[1150]: info: QobuzConnect: setDeactiveState invoked Dec 28 18:13:17 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:17 volumiolabo kernel: usb 1-1.1: new high-speed USB device number 10 using xhci_hcd Dec 28 18:13:17 volumiolabo systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 28 18:13:17 volumiolabo volumio[1150]: info: Dec 28 18:13:17 volumiolabo volumio[1150]: ---------------------------- USB Audio Device Attached Dec 28 18:13:17 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 28 18:13:17 volumiolabo systemd[1]: mpd.service: Deactivated successfully. Dec 28 18:13:17 volumiolabo systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 28 18:13:17 volumiolabo systemd[1]: mpd.service: Consumed 1.388s CPU time. Dec 28 18:13:17 volumiolabo systemd[1]: mpd.socket: Deactivated successfully. Dec 28 18:13:17 volumiolabo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 28 18:13:17 volumiolabo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 28 18:13:17 volumiolabo kernel: usb 1-1.1: New USB device found, idVendor=2772, idProduct=0221, bcdDevice= 3.43 Dec 28 18:13:17 volumiolabo kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 28 18:13:17 volumiolabo kernel: usb 1-1.1: Product: Project RS USB Audio 2.0 Dec 28 18:13:17 volumiolabo kernel: usb 1-1.1: Manufacturer: Project RS Dec 28 18:13:17 volumiolabo kernel: usb 1-1.1: SerialNumber: 0000 Dec 28 18:13:17 volumiolabo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 28 18:13:17 volumiolabo systemd[1]: Starting mpd.service - Music Player Daemon... Dec 28 18:13:17 volumiolabo volumio[1150]: error: API:pushError: TypeError: Cannot read properties of null (reading 'name') Dec 28 18:13:17 volumiolabo sudo[1965]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 28 18:13:17 volumiolabo sudo[1965]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:17 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:17 volumiolabo volumio[1150]: info: Shairport-Sync Started Dec 28 18:13:17 volumiolabo sudo[1970]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 28 18:13:17 volumiolabo sudo[1970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 28 18:13:17 volumiolabo sudo[1970]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:17 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:17 volumiolabo sudo[1978]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 28 18:13:17 volumiolabo sudo[1978]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:17 volumiolabo sudo[1965]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:17 volumiolabo volumio[1150]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Dec 28 18:13:17 volumiolabo volumio[1150]: info: MPD Permissions set Dec 28 18:13:17 volumiolabo systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 28 18:13:17 volumiolabo systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 28 18:13:17 volumiolabo systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 28 18:13:17 volumiolabo sudo[1980]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 28 18:13:17 volumiolabo sudo[1980]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:17 volumiolabo volumio[1150]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2JWNNKFfw7fLE2kTohONBLWrYfI3.78bd435749c1f115edb0909ce93aace5.state.status' Dec 28 18:13:17 volumiolabo volumio[1150]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 28 18:13:17 volumiolabo qobuz-connect[1883]: 20251228 18:13:17.975 [1883.1883] INFO SampleApp: Stopping Local configuration server Dec 28 18:13:17 volumiolabo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 28 18:13:17 volumiolabo volumio[1150]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 28 18:13:17 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:18 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:18 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:18 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:18 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:18 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:18 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:18 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:18 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:13:18 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:18 volumiolabo volumio[1150]: info: Dec 28 18:13:18 volumiolabo volumio[1150]: ---------------------------- USB Audio Device Detached Dec 28 18:13:18 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 28 18:13:18 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 28 18:13:18 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 28 18:13:18 volumiolabo kernel: usb 1-1.1: USB disconnect, device number 10 Dec 28 18:13:18 volumiolabo qobuz-connect[1883]: 20251228 18:13:18.499 [1883.1883] INFO SampleApp: shat down connection on UNIX socket Dec 28 18:13:18 volumiolabo systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 28 18:13:18 volumiolabo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 28 18:13:18 volumiolabo volumio[1150]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 28 18:13:18 volumiolabo volumio[1150]: info: QobuzConnect: setDeactiveState invoked Dec 28 18:13:18 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:18 volumiolabo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 28 18:13:18 volumiolabo sudo[1980]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:18 volumiolabo volumio[1150]: info: Starting Shairport Sync Dec 28 18:13:18 volumiolabo volumio[1150]: info: Preparing to generate the ALSA configuration file Dec 28 18:13:18 volumiolabo sudo[2001]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 28 18:13:18 volumiolabo sudo[2001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:18 volumiolabo sudo[2003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 28 18:13:18 volumiolabo sudo[2003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:18 volumiolabo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 28 18:13:18 volumiolabo sudo[2001]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:18 volumiolabo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 28 18:13:18 volumiolabo systemd[1]: shairport-sync.service: Deactivated successfully. Dec 28 18:13:18 volumiolabo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 28 18:13:18 volumiolabo systemd[1]: shairport-sync.service: Consumed 1.282s CPU time. Dec 28 18:13:18 volumiolabo volumio[1150]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 28 18:13:18 volumiolabo volumio[1150]: info: Reading ALSA contributions from plugins. Dec 28 18:13:18 volumiolabo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 28 18:13:18 volumiolabo sudo[2003]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:19 volumiolabo kernel: usb 1-1.1: new high-speed USB device number 11 using xhci_hcd Dec 28 18:13:19 volumiolabo volumio[1150]: info: Executing endpoint qc_getconfig Dec 28 18:13:19 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 28 18:13:19 volumiolabo mpd[1976]: 2025-12-28T18:13:19 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 28 18:13:19 volumiolabo systemd[1]: Started mpd.service - Music Player Daemon. Dec 28 18:13:19 volumiolabo sudo[1951]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:19 volumiolabo kernel: usb 1-1.1: Device not responding to setup address. Dec 28 18:13:19 volumiolabo systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 28 18:13:19 volumiolabo mpd_monitor.sh[2028]: MPD Monitor Service: Starting MPD Monitor Service Dec 28 18:13:19 volumiolabo sudo[1978]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:19 volumiolabo qobuz-connect[1999]: 20251228 18:13:19.135 [1999.1999] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 28 18:13:19 volumiolabo qobuz-connect[1999]: 20251228 18:13:19.139 [1999.1999] INFO VolumeManager: [0x1888348]: Setting new playback volume: 75 Dec 28 18:13:19 volumiolabo qobuz-connect[1999]: 20251228 18:13:19.139 [1999.1999] INFO VolumeManager: [0x1888348]: Setting new mute state: 0 Dec 28 18:13:19 volumiolabo qobuz-connect[1999]: 20251228 18:13:19.139 [1999.1999] INFO QobuzConnect: [0x1888d18]: Client initialized! Dec 28 18:13:19 volumiolabo qobuz-connect[1999]: 20251228 18:13:19.139 [1999.1999] INFO SampleApp: Starting Avahi advertising, name: Volumio_LaBo, service name: _qobuz-connect._tcp Dec 28 18:13:19 volumiolabo qobuz-connect[1999]: 20251228 18:13:19.164 [1999.1999] INFO LocalConfigManager: [0x1887c28]: Starting Local Configuration server Dec 28 18:13:19 volumiolabo qobuz-connect[1999]: 20251228 18:13:19.164 [1999.1999] INFO SampleApp: Starting Local configuration server Dec 28 18:13:19 volumiolabo qobuz-connect[1999]: 20251228 18:13:19.165 [1999.1999] INFO SampleApp: Connected to UNIX socket client 0x1872818 Dec 28 18:13:19 volumiolabo volumio[1150]: info: Dec 28 18:13:19 volumiolabo volumio[1150]: ---------------------------- USB Audio Device Attached Dec 28 18:13:19 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 28 18:13:19 volumiolabo volumio[1150]: error: API:pushError: TypeError: Cannot read properties of null (reading 'name') Dec 28 18:13:19 volumiolabo qobuz-connect[1999]: 20251228 18:13:19.285 [1999.1999] INFO SampleApp: Playback volume changed: 75 Dec 28 18:13:19 volumiolabo kernel: usb 1-1.1: Device not responding to setup address. Dec 28 18:13:19 volumiolabo volumio[1150]: info: Successfully started MPD Monitor Dec 28 18:13:19 volumiolabo volumio[1150]: info: Shairport-Sync Started Dec 28 18:13:19 volumiolabo volumio[1150]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 28 18:13:19 volumiolabo volumio[1150]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 28 18:13:19 volumiolabo volumio[1150]: info: Executing endpoint tc_getconfig Dec 28 18:13:19 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 28 18:13:19 volumiolabo vtcs[2007]: STARTING TidalConnect services, version: 1.5.2.56 Dec 28 18:13:19 volumiolabo vtcs[2007]: STARTED TidalConnect services. Dec 28 18:13:19 volumiolabo kernel: usb 1-1.1: device not accepting address 11, error -71 Dec 28 18:13:19 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:19 volumiolabo volumio[1150]: info: Dec 28 18:13:19 volumiolabo volumio[1150]: ---------------------------- USB Audio Device Detached Dec 28 18:13:19 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 28 18:13:19 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 28 18:13:19 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 28 18:13:19 volumiolabo (udev-worker)[1847]: controlC5: /usr/lib/udev/rules.d/78-sound-card.rules:5 Failed to write ATTR{/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1:1.0/sound/card5/controlC5/../uevent}, ignoring: No such file or directory Dec 28 18:13:19 volumiolabo volumio[1150]: error: updateQueue error: null Dec 28 18:13:19 volumiolabo volumio[1150]: info: Executing endpoint tc_connect Dec 28 18:13:19 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 28 18:13:19 volumiolabo volumio[1150]: info: Connecting to TidalConnect Dec 28 18:13:19 volumiolabo volumio[1150]: info: CoreCommandRouter::servicePushState Dec 28 18:13:19 volumiolabo volumio[1150]: info: CoreStateMachine::pushState Dec 28 18:13:19 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioPushState Dec 28 18:13:20 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output update for this device Dec 28 18:13:20 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output Dec 28 18:13:20 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:20 volumiolabo kernel: usb 1-1.1: new high-speed USB device number 12 using xhci_hcd Dec 28 18:13:20 volumiolabo volumio[1150]: info: CorePlayQueue::getTrack 0 Dec 28 18:13:20 volumiolabo volumio[1150]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Dec 28 18:13:20 volumiolabo volumio[1150]: info: CoreCommandRouter::servicePushState Dec 28 18:13:20 volumiolabo volumio[1150]: info: CoreStateMachine::pushState Dec 28 18:13:20 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioPushState Dec 28 18:13:20 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output update for this device Dec 28 18:13:20 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output Dec 28 18:13:20 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:20 volumiolabo volumio[1150]: info: CorePlayQueue::getTrack 0 Dec 28 18:13:20 volumiolabo volumio[1150]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Dec 28 18:13:20 volumiolabo kernel: usb 1-1.1: Device not responding to setup address. Dec 28 18:13:20 volumiolabo volumio[1150]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2JWNNKFfw7fLE2kTohONBLWrYfI3.78bd435749c1f115edb0909ce93aace5.state.status' Dec 28 18:13:20 volumiolabo volumio[1150]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2JWNNKFfw7fLE2kTohONBLWrYfI3.78bd435749c1f115edb0909ce93aace5.state.status' Dec 28 18:13:20 volumiolabo kernel: usb 1-1.1: Device not responding to setup address. Dec 28 18:13:20 volumiolabo volumio[1150]: info: Asound.conf file unchanged, so no further update is needed Dec 28 18:13:20 volumiolabo volumio[1150]: info: Output device has changed, restarting MPD Dec 28 18:13:20 volumiolabo volumio[1150]: info: Output device has changed, restarting Shairport Sync Dec 28 18:13:20 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:20 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:13:20 volumiolabo sudo[2061]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 18:13:20 volumiolabo sudo[2061]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:20 volumiolabo volumio[1150]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 18:13:20 volumiolabo volumio[1150]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 28 18:13:20 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:20 volumiolabo sudo[2063]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 18:13:20 volumiolabo kernel: usb 1-1.1: device not accepting address 12, error -71 Dec 28 18:13:20 volumiolabo kernel: usb 1-1-port1: attempt power cycle Dec 28 18:13:20 volumiolabo sudo[2063]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:20 volumiolabo volumio[1150]: info: QobuzConnect: setDeactiveState invoked Dec 28 18:13:20 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:20 volumiolabo vtcs[2007]: [2025-12-28 18:13:20.574] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 28 18:13:20 volumiolabo sudo[2061]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:20 volumiolabo vtcs[2007]: [2025-12-28 18:13:20.592] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Dec 28 18:13:20 volumiolabo vtcs[2007]: [2025-12-28 18:13:20.592] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 28 18:13:20 volumiolabo systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 28 18:13:20 volumiolabo systemd[1]: mpd.service: Deactivated successfully. Dec 28 18:13:20 volumiolabo systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 28 18:13:20 volumiolabo systemd[1]: mpd.service: Consumed 1.483s CPU time. Dec 28 18:13:20 volumiolabo systemd[1]: mpd.socket: Deactivated successfully. Dec 28 18:13:20 volumiolabo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 28 18:13:20 volumiolabo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 28 18:13:20 volumiolabo sudo[2073]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 28 18:13:20 volumiolabo sudo[2077]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 28 18:13:20 volumiolabo sudo[2077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:20 volumiolabo sudo[2073]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:20 volumiolabo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 28 18:13:20 volumiolabo systemd[1]: Starting mpd.service - Music Player Daemon... Dec 28 18:13:20 volumiolabo volumio[1150]: info: Dec 28 18:13:20 volumiolabo volumio[1150]: ---------------------------- USB Audio Device Attached Dec 28 18:13:20 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 28 18:13:20 volumiolabo sudo[2083]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 28 18:13:20 volumiolabo sudo[2083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:20 volumiolabo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 28 18:13:20 volumiolabo systemd[1]: vtcs.service: Deactivated successfully. Dec 28 18:13:20 volumiolabo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 28 18:13:20 volumiolabo sudo[2077]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:20 volumiolabo volumio[1150]: error: API:pushError: TypeError: Cannot read properties of null (reading 'name') Dec 28 18:13:20 volumiolabo sudo[2085]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 28 18:13:20 volumiolabo sudo[2085]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 28 18:13:20 volumiolabo sudo[2085]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:21 volumiolabo volumio[1150]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Dec 28 18:13:21 volumiolabo volumio[1150]: info: MPD Permissions set Dec 28 18:13:21 volumiolabo sudo[2073]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:21 volumiolabo sudo[2083]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:21 volumiolabo sudo[2096]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 28 18:13:21 volumiolabo sudo[2096]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:21 volumiolabo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 28 18:13:21 volumiolabo qobuz-connect[1999]: 20251228 18:13:21.165 [1999.1999] INFO SampleApp: Stopping Local configuration server Dec 28 18:13:21 volumiolabo qobuz-connect[1999]: 20251228 18:13:21.175 [1999.1999] INFO SampleApp: shat down connection on UNIX socket Dec 28 18:13:21 volumiolabo systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 28 18:13:21 volumiolabo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 28 18:13:21 volumiolabo volumio[1150]: info: Dec 28 18:13:21 volumiolabo volumio[1150]: ---------------------------- USB Audio Device Detached Dec 28 18:13:21 volumiolabo kernel: usb 1-1.1: new high-speed USB device number 13 using xhci_hcd Dec 28 18:13:21 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 28 18:13:21 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 28 18:13:21 volumiolabo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 28 18:13:21 volumiolabo sudo[2096]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:21 volumiolabo kernel: usb 1-1.1: New USB device found, idVendor=2772, idProduct=0221, bcdDevice= 3.43 Dec 28 18:13:21 volumiolabo kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 28 18:13:21 volumiolabo kernel: usb 1-1.1: Product: Project RS USB Audio 2.0 Dec 28 18:13:21 volumiolabo kernel: usb 1-1.1: Manufacturer: Project RS Dec 28 18:13:21 volumiolabo kernel: usb 1-1.1: SerialNumber: 0000 Dec 28 18:13:21 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 28 18:13:21 volumiolabo volumio[1150]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 28 18:13:21 volumiolabo volumio[1150]: info: QobuzConnect: setDeactiveState invoked Dec 28 18:13:21 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:21 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:21 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:21 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:21 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:21 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:21 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:21 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:21 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:21 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:13:21 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:21 volumiolabo volumio[1150]: info: Executing endpoint qc_getconfig Dec 28 18:13:21 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 28 18:13:21 volumiolabo qobuz-connect[2098]: 20251228 18:13:21.804 [2098.2098] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 28 18:13:21 volumiolabo qobuz-connect[2098]: 20251228 18:13:21.809 [2098.2098] INFO VolumeManager: [0x1fc7348]: Setting new playback volume: 75 Dec 28 18:13:21 volumiolabo qobuz-connect[2098]: 20251228 18:13:21.809 [2098.2098] INFO VolumeManager: [0x1fc7348]: Setting new mute state: 0 Dec 28 18:13:21 volumiolabo qobuz-connect[2098]: 20251228 18:13:21.809 [2098.2098] INFO QobuzConnect: [0x1fc7d18]: Client initialized! Dec 28 18:13:21 volumiolabo qobuz-connect[2098]: 20251228 18:13:21.809 [2098.2098] INFO SampleApp: Starting Avahi advertising, name: Volumio_LaBo, service name: _qobuz-connect._tcp Dec 28 18:13:21 volumiolabo qobuz-connect[2098]: 20251228 18:13:21.839 [2098.2098] INFO LocalConfigManager: [0x1fc6c28]: Starting Local Configuration server Dec 28 18:13:21 volumiolabo qobuz-connect[2098]: 20251228 18:13:21.839 [2098.2098] INFO SampleApp: Starting Local configuration server Dec 28 18:13:21 volumiolabo qobuz-connect[2098]: 20251228 18:13:21.840 [2098.2098] INFO SampleApp: Connected to UNIX socket client 0x1fb1818 Dec 28 18:13:21 volumiolabo volumio[1150]: info: Dec 28 18:13:21 volumiolabo volumio[1150]: ---------------------------- USB Audio Device Attached Dec 28 18:13:21 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 28 18:13:21 volumiolabo qobuz-connect[2098]: 20251228 18:13:21.954 [2098.2098] INFO SampleApp: Playback volume changed: 75 Dec 28 18:13:21 volumiolabo volumio[1150]: info: CoreCommandRouter::Close All Modals sent Dec 28 18:13:21 volumiolabo volumio[1150]: info: Preparing to save Alsa Options, stopping services first Dec 28 18:13:21 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:21 volumiolabo volumio[1150]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Project RS USB Audio 2.0","alsacard":"P20"},"i2s":false} Dec 28 18:13:21 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 28 18:13:22 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 28 18:13:22 volumiolabo volumio[1150]: info: Setting mixer Project RS Audio 2.0 Output for card Project RS USB Audio 2.0 Dec 28 18:13:22 volumiolabo volumio[1150]: info: QobuzConnect: setDeactiveState invoked Dec 28 18:13:22 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:22 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 28 18:13:22 volumiolabo volumio[1150]: info: Updating Volume Controller Parameters: Device: 5 Name: Project RS USB Audio 2.0 Mixer: Project RS Audio 2.0 Output Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 28 18:13:22 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 28 18:13:22 volumiolabo volumio[1150]: info: Disabling external Volume Control Dec 28 18:13:22 volumiolabo sudo[2129]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 28 18:13:22 volumiolabo volumio[1150]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 18:13:22 volumiolabo sudo[2129]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:22 volumiolabo volumio[1150]: info: Preparing to generate the ALSA configuration file Dec 28 18:13:22 volumiolabo sudo[2129]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:22 volumiolabo mpd[2093]: 2025-12-28T18:13:22 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 28 18:13:22 volumiolabo systemd[1]: Started mpd.service - Music Player Daemon. Dec 28 18:13:22 volumiolabo sudo[2063]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:22 volumiolabo sudo[2141]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 28 18:13:22 volumiolabo sudo[2141]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:22 volumiolabo volumio[1150]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 28 18:13:22 volumiolabo volumio[1150]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 28 18:13:22 volumiolabo sudo[2148]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 28 18:13:22 volumiolabo volumio[1150]: info: Starting Shairport Sync Dec 28 18:13:22 volumiolabo sudo[2141]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:22 volumiolabo sudo[2148]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:22 volumiolabo volumio[1150]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 28 18:13:22 volumiolabo sudo[2150]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 28 18:13:22 volumiolabo sudo[2150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:22 volumiolabo volumio[1150]: info: Reading ALSA contributions from plugins. Dec 28 18:13:22 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:22 volumiolabo sudo[2148]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:22 volumiolabo volumio[1150]: info: TidalConnect service started! Dec 28 18:13:22 volumiolabo sudo[2154]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 28 18:13:22 volumiolabo sudo[2154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:22 volumiolabo systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 28 18:13:22 volumiolabo systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 28 18:13:22 volumiolabo systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 28 18:13:22 volumiolabo volumio[1150]: [Metrics] CommandRouter: 64s 70.72ms Dec 28 18:13:22 volumiolabo volumio[1150]: info: CoreCommandRouter::volumiosetStartupVolume Dec 28 18:13:22 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:22 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:13:22 volumiolabo systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 28 18:13:22 volumiolabo sudo[2157]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 28 18:13:22 volumiolabo sudo[2157]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:22 volumiolabo mpd_monitor.sh[2158]: MPD Monitor Service: Starting MPD Monitor Service Dec 28 18:13:22 volumiolabo sudo[2150]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:22 volumiolabo volumio[1150]: info: CoreCommandRouter::Close All Modals sent Dec 28 18:13:22 volumiolabo volumio[1150]: info: CoreCommandRouter::Close All Modals sent Dec 28 18:13:22 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 28 18:13:22 volumiolabo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 28 18:13:22 volumiolabo systemd[1]: shairport-sync.service: Deactivated successfully. Dec 28 18:13:22 volumiolabo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 28 18:13:22 volumiolabo systemd[1]: shairport-sync.service: Consumed 1.368s CPU time. Dec 28 18:13:22 volumiolabo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 28 18:13:23 volumiolabo sudo[2154]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:23 volumiolabo volumio[1150]: info: VolumeController:: Volume=100 Mute =false Dec 28 18:13:23 volumiolabo volumio[1150]: info: CoreStateMachine::pushState Dec 28 18:13:23 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioPushState Dec 28 18:13:23 volumiolabo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 28 18:13:23 volumiolabo qobuz-connect[2098]: 20251228 18:13:23.108 [2098.2098] INFO SampleApp: Stopping Local configuration server Dec 28 18:13:23 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output update for this device Dec 28 18:13:23 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output Dec 28 18:13:23 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:23 volumiolabo volumio[1150]: info: Successfully started MPD Monitor Dec 28 18:13:23 volumiolabo volumio[1150]: info: Dec 28 18:13:23 volumiolabo volumio[1150]: ---------------------------- USB Audio Device Detached Dec 28 18:13:23 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 28 18:13:23 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 28 18:13:23 volumiolabo volumio[1150]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2JWNNKFfw7fLE2kTohONBLWrYfI3.78bd435749c1f115edb0909ce93aace5.state.status' Dec 28 18:13:23 volumiolabo volumio[1150]: info: Shairport-Sync Started Dec 28 18:13:23 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 28 18:13:23 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:13:23 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 28 18:13:23 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 18:13:23 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 18:13:23 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 18:13:23 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 18:13:23 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 18:13:23 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 18:13:23 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 18:13:23 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 28 18:13:23 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 28 18:13:23 volumiolabo volumio[1150]: error: updateQueue error: null Dec 28 18:13:23 volumiolabo qobuz-connect[2098]: 20251228 18:13:23.853 [2098.2098] INFO SampleApp: shat down connection on UNIX socket Dec 28 18:13:23 volumiolabo systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 28 18:13:23 volumiolabo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 28 18:13:23 volumiolabo volumio[1150]: info: TidalConnect service stoped! Dec 28 18:13:23 volumiolabo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 28 18:13:23 volumiolabo sudo[2157]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:23 volumiolabo volumio[1150]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 28 18:13:23 volumiolabo volumio[1150]: info: QobuzConnect: setDeactiveState invoked Dec 28 18:13:23 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:23 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 28 18:13:23 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 28 18:13:24 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 28 18:13:24 volumiolabo volumio[1150]: info: TidalConnect service stoped! Dec 28 18:13:24 volumiolabo volumio[1150]: info: Dec 28 18:13:24 volumiolabo volumio[1150]: ---------------------------- USB Audio Device Attached Dec 28 18:13:24 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 28 18:13:24 volumiolabo volumio[1150]: info: CoreCommandRouter::Close All Modals sent Dec 28 18:13:24 volumiolabo kernel: usb 1-1.1: USB disconnect, device number 13 Dec 28 18:13:24 volumiolabo kernel: usb 1-1.1: Unable to submit urb #2: -19 at snd_usb_queue_pending_output_urbs Dec 28 18:13:24 volumiolabo volumio[1150]: info: Preparing to save Alsa Options, stopping services first Dec 28 18:13:24 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:24 volumiolabo volumio[1150]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Project RS USB Audio 2.0","alsacard":"P20"},"i2s":false} Dec 28 18:13:24 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 28 18:13:24 volumiolabo volumio[1150]: warn: Unable to locate the device Project RS USB Audio 2.0 a reboot may be required. Dec 28 18:13:24 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 28 18:13:24 volumiolabo volumio[1150]: Invalid card number '5'. Dec 28 18:13:24 volumiolabo kernel: usb 1-1.1: new high-speed USB device number 14 using xhci_hcd Dec 28 18:13:24 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 28 18:13:24 volumiolabo volumio[1150]: info: Updating Volume Controller Parameters: Device: 5 Name: Project RS USB Audio 2.0 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 28 18:13:24 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 28 18:13:24 volumiolabo volumio[1150]: info: Disabling external Volume Control Dec 28 18:13:24 volumiolabo volumio[1150]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 18:13:24 volumiolabo kernel: usb 1-1.1: New USB device found, idVendor=2772, idProduct=0221, bcdDevice= 3.43 Dec 28 18:13:24 volumiolabo kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 28 18:13:24 volumiolabo kernel: usb 1-1.1: Product: Project RS USB Audio 2.0 Dec 28 18:13:24 volumiolabo kernel: usb 1-1.1: Manufacturer: Project RS Dec 28 18:13:24 volumiolabo kernel: usb 1-1.1: SerialNumber: 0000 Dec 28 18:13:24 volumiolabo volumio[1150]: info: Executing endpoint qc_getconfig Dec 28 18:13:24 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 28 18:13:24 volumiolabo qobuz-connect[2208]: 20251228 18:13:24.858 [2208.2208] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 28 18:13:24 volumiolabo qobuz-connect[2208]: 20251228 18:13:24.863 [2208.2208] INFO VolumeManager: [0x1b78348]: Setting new playback volume: 75 Dec 28 18:13:24 volumiolabo qobuz-connect[2208]: 20251228 18:13:24.863 [2208.2208] INFO VolumeManager: [0x1b78348]: Setting new mute state: 0 Dec 28 18:13:24 volumiolabo qobuz-connect[2208]: 20251228 18:13:24.863 [2208.2208] INFO QobuzConnect: [0x1b78d18]: Client initialized! Dec 28 18:13:24 volumiolabo qobuz-connect[2208]: 20251228 18:13:24.863 [2208.2208] INFO SampleApp: Starting Avahi advertising, name: Volumio_LaBo, service name: _qobuz-connect._tcp Dec 28 18:13:24 volumiolabo qobuz-connect[2208]: 20251228 18:13:24.880 [2208.2208] INFO LocalConfigManager: [0x1b77c28]: Starting Local Configuration server Dec 28 18:13:24 volumiolabo qobuz-connect[2208]: 20251228 18:13:24.880 [2208.2208] INFO SampleApp: Starting Local configuration server Dec 28 18:13:24 volumiolabo qobuz-connect[2208]: 20251228 18:13:24.880 [2208.2208] INFO SampleApp: Connected to UNIX socket client 0x1b62818 Dec 28 18:13:24 volumiolabo volumio[1150]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 28 18:13:24 volumiolabo volumio[1150]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Dec 28 18:13:24 volumiolabo volumio[1150]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Dec 28 18:13:24 volumiolabo volumio[1150]: ALSA lib ./src/pcm_volumioswitch.c:912:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot write to target PCM volumioOutput as it has failed its update check. Dec 28 18:13:24 volumiolabo volumio[1150]: underrun!!! (at least 1208.920 ms long) Dec 28 18:13:24 volumiolabo volumio[1150]: aplay: xrun:1672: xrun: prepare error: No such device Dec 28 18:13:24 volumiolabo volumio[1150]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 28 18:13:24 volumiolabo volumio[1150]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 28 18:13:24 volumiolabo volumio[1150]: info: Asound.conf file unchanged, so no further update is needed Dec 28 18:13:24 volumiolabo volumio[1150]: info: Output device has changed, restarting MPD Dec 28 18:13:24 volumiolabo volumio[1150]: info: Output device has changed, restarting Shairport Sync Dec 28 18:13:24 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:24 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:13:24 volumiolabo sudo[2245]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 18:13:24 volumiolabo sudo[2245]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:24 volumiolabo sudo[2247]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 18:13:24 volumiolabo sudo[2247]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:25 volumiolabo qobuz-connect[2208]: 20251228 18:13:25.008 [2208.2208] INFO SampleApp: Playback volume changed: 75 Dec 28 18:13:25 volumiolabo sudo[2245]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:25 volumiolabo volumio[1150]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 18:13:25 volumiolabo volumio[1150]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 28 18:13:25 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:25 volumiolabo volumio[1150]: info: QobuzConnect: setDeactiveState invoked Dec 28 18:13:25 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:25 volumiolabo systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 28 18:13:25 volumiolabo volumio[1150]: info: Preparing to generate the ALSA configuration file Dec 28 18:13:25 volumiolabo systemd[1]: mpd.service: Deactivated successfully. Dec 28 18:13:25 volumiolabo systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 28 18:13:25 volumiolabo systemd[1]: mpd.service: Consumed 1.462s CPU time. Dec 28 18:13:25 volumiolabo systemd[1]: mpd.socket: Deactivated successfully. Dec 28 18:13:25 volumiolabo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 28 18:13:25 volumiolabo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 28 18:13:25 volumiolabo sudo[2257]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 28 18:13:25 volumiolabo sudo[2257]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:25 volumiolabo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 28 18:13:25 volumiolabo systemd[1]: Starting mpd.service - Music Player Daemon... Dec 28 18:13:25 volumiolabo sudo[2257]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:25 volumiolabo volumio[1150]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 28 18:13:25 volumiolabo sudo[2264]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 28 18:13:25 volumiolabo sudo[2264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 28 18:13:25 volumiolabo volumio[1150]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 28 18:13:25 volumiolabo sudo[2264]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:25 volumiolabo volumio[1150]: info: VolumeController:: Volume=undefined Mute =false Dec 28 18:13:25 volumiolabo kernel: usb 1-1.1: USB disconnect, device number 14 Dec 28 18:13:25 volumiolabo volumio[1150]: info: CoreStateMachine::pushState Dec 28 18:13:25 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioPushState Dec 28 18:13:25 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output update for this device Dec 28 18:13:25 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output Dec 28 18:13:25 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:25 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 28 18:13:25 volumiolabo volumio[1150]: info: Dec 28 18:13:25 volumiolabo volumio[1150]: ---------------------------- USB Audio Device Detached Dec 28 18:13:25 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 28 18:13:25 volumiolabo sudo[2261]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 28 18:13:25 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 28 18:13:25 volumiolabo sudo[2261]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:25 volumiolabo sudo[2268]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 28 18:13:25 volumiolabo sudo[2268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:25 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 28 18:13:25 volumiolabo sudo[2261]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:25 volumiolabo volumio[1150]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 28 18:13:25 volumiolabo volumio[1150]: info: Reading ALSA contributions from plugins. Dec 28 18:13:25 volumiolabo kernel: usb 1-1.1: new high-speed USB device number 15 using xhci_hcd Dec 28 18:13:25 volumiolabo volumio[1150]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2JWNNKFfw7fLE2kTohONBLWrYfI3.78bd435749c1f115edb0909ce93aace5.state.status' Dec 28 18:13:25 volumiolabo (udev-worker)[1845]: controlC5: /usr/lib/udev/rules.d/78-sound-card.rules:5 Failed to write ATTR{/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1:1.0/sound/card5/controlC5/../uevent}, ignoring: No such file or directory Dec 28 18:13:25 volumiolabo sudo[2268]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:25 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:25 volumiolabo volumio[1150]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Dec 28 18:13:25 volumiolabo volumio[1150]: info: MPD Permissions set Dec 28 18:13:25 volumiolabo kernel: usb 1-1.1: New USB device found, idVendor=2772, idProduct=0221, bcdDevice= 3.43 Dec 28 18:13:25 volumiolabo kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 28 18:13:25 volumiolabo kernel: usb 1-1.1: Product: Project RS USB Audio 2.0 Dec 28 18:13:25 volumiolabo kernel: usb 1-1.1: Manufacturer: Project RS Dec 28 18:13:25 volumiolabo kernel: usb 1-1.1: SerialNumber: 0000 Dec 28 18:13:25 volumiolabo sudo[2281]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 28 18:13:25 volumiolabo sudo[2281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:25 volumiolabo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 28 18:13:25 volumiolabo qobuz-connect[2208]: 20251228 18:13:25.988 [2208.2208] INFO SampleApp: Stopping Local configuration server Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 28 18:13:26 volumiolabo volumio[1150]: Invalid card number '5'. Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 28 18:13:26 volumiolabo volumio[1150]: info: TidalConnect service stoped! Dec 28 18:13:26 volumiolabo kernel: usb 1-1.1: USB disconnect, device number 15 Dec 28 18:13:26 volumiolabo volumio[1150]: info: TidalConnect service stoped! Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:26 volumiolabo sudo[2295]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:26 volumiolabo sudo[2295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:26 volumiolabo sudo[2297]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 28 18:13:26 volumiolabo sudo[2297]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:26 volumiolabo systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 28 18:13:26 volumiolabo systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 28 18:13:26 volumiolabo systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreStateMachine::getcurrentVolume Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 18:13:26 volumiolabo volumio[1150]: info: Dec 28 18:13:26 volumiolabo volumio[1150]: ---------------------------- USB Audio Device Attached Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 28 18:13:26 volumiolabo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 28 18:13:26 volumiolabo sudo[2297]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:26 volumiolabo volumio[1150]: error: API:pushError: TypeError: Cannot read properties of null (reading 'name') Dec 28 18:13:26 volumiolabo volumio[1150]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 28 18:13:26 volumiolabo volumio[1150]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 28 18:13:26 volumiolabo volumio[1150]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 28 18:13:26 volumiolabo volumio[1150]: info: VolumeController:: Volume=undefined Mute =false Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreStateMachine::pushState Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioPushState Dec 28 18:13:26 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output update for this device Dec 28 18:13:26 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreStateMachine::updateTrackBlock Dec 28 18:13:26 volumiolabo volumio[1150]: info: CorePlayQueue::getTrackBlock Dec 28 18:13:26 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 18:13:26 volumiolabo volumio[1150]: info: Starting Shairport Sync Dec 28 18:13:26 volumiolabo qobuz-connect[2208]: 20251228 18:13:26.893 [2208.2208] INFO SampleApp: shat down connection on UNIX socket Dec 28 18:13:26 volumiolabo volumio[1150]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2JWNNKFfw7fLE2kTohONBLWrYfI3.78bd435749c1f115edb0909ce93aace5.state.status' Dec 28 18:13:26 volumiolabo systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 28 18:13:26 volumiolabo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 28 18:13:26 volumiolabo volumio[1150]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 28 18:13:26 volumiolabo mpd[2271]: 2025-12-28T18:13:26 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 28 18:13:27 volumiolabo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 28 18:13:27 volumiolabo sudo[2319]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 28 18:13:27 volumiolabo systemd[1]: Started mpd.service - Music Player Daemon. Dec 28 18:13:27 volumiolabo sudo[2319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:27 volumiolabo systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 28 18:13:27 volumiolabo mpd_monitor.sh[2325]: MPD Monitor Service: Starting MPD Monitor Service Dec 28 18:13:27 volumiolabo sudo[2295]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:27 volumiolabo sudo[2281]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:27 volumiolabo sudo[2247]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:27 volumiolabo kernel: usb 1-1.1: new high-speed USB device number 16 using xhci_hcd Dec 28 18:13:27 volumiolabo volumio[1150]: info: VolumeController:: Volume=undefined Mute =false Dec 28 18:13:27 volumiolabo volumio[1150]: info: CoreStateMachine::pushState Dec 28 18:13:27 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioPushState Dec 28 18:13:27 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output update for this device Dec 28 18:13:27 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output Dec 28 18:13:27 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:27 volumiolabo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 28 18:13:27 volumiolabo systemd[1]: shairport-sync.service: Deactivated successfully. Dec 28 18:13:27 volumiolabo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 28 18:13:27 volumiolabo systemd[1]: shairport-sync.service: Consumed 1.217s CPU time. Dec 28 18:13:27 volumiolabo sudo[2328]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 28 18:13:27 volumiolabo volumio[1150]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 28 18:13:27 volumiolabo volumio[1150]: info: QobuzConnect: setDeactiveState invoked Dec 28 18:13:27 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:27 volumiolabo kernel: usb 1-1.1: New USB device found, idVendor=2772, idProduct=0221, bcdDevice= 3.43 Dec 28 18:13:27 volumiolabo kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 28 18:13:27 volumiolabo kernel: usb 1-1.1: Product: Project RS USB Audio 2.0 Dec 28 18:13:27 volumiolabo kernel: usb 1-1.1: Manufacturer: Project RS Dec 28 18:13:27 volumiolabo kernel: usb 1-1.1: SerialNumber: 0000 Dec 28 18:13:27 volumiolabo sudo[2328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:27 volumiolabo kernel: usb 1-1.1: 1:1: cannot set freq 384000 (v2/v3): err -71 Dec 28 18:13:27 volumiolabo kernel: usb 1-1.1: 10:0: failed to get current value for ch 1 (-71) Dec 28 18:13:27 volumiolabo kernel: usb 1-1.1: 10:0: failed to get current value for ch 2 (-71) Dec 28 18:13:27 volumiolabo kernel: usb 1-1.1: 10:0: failed to get current value for ch 0 (-71) Dec 28 18:13:27 volumiolabo volumio[1150]: info: Asound.conf file unchanged, so no further update is needed Dec 28 18:13:27 volumiolabo volumio[1150]: info: Output device has changed, restarting MPD Dec 28 18:13:27 volumiolabo kernel: usb 1-1.1: 10:0: cannot get min/max values for control 2 (id 10) Dec 28 18:13:27 volumiolabo kernel: usb 1-1.1: 10:0: cannot get min/max values for control 2 (id 10) Dec 28 18:13:27 volumiolabo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 28 18:13:27 volumiolabo sudo[2319]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:27 volumiolabo sudo[2328]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:27 volumiolabo volumio[1150]: info: Output device has changed, restarting Shairport Sync Dec 28 18:13:27 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:27 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:13:27 volumiolabo sudo[2334]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 18:13:27 volumiolabo sudo[2334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:27 volumiolabo sudo[2334]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:27 volumiolabo kernel: usb 1-1.1: USB disconnect, device number 16 Dec 28 18:13:27 volumiolabo volumio[1150]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 18:13:27 volumiolabo volumio[1150]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 28 18:13:27 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:27 volumiolabo sudo[2348]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 18:13:27 volumiolabo sudo[2348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:27 volumiolabo volumio[1150]: info: QobuzConnect: setDeactiveState invoked Dec 28 18:13:27 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:27 volumiolabo systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 28 18:13:27 volumiolabo systemd[1]: mpd.service: Deactivated successfully. Dec 28 18:13:27 volumiolabo systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 28 18:13:27 volumiolabo systemd[1]: mpd.service: Consumed 1.485s CPU time. Dec 28 18:13:27 volumiolabo systemd[1]: mpd.socket: Deactivated successfully. Dec 28 18:13:27 volumiolabo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 28 18:13:27 volumiolabo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 28 18:13:27 volumiolabo sudo[2363]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 28 18:13:27 volumiolabo sudo[2363]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:27 volumiolabo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 28 18:13:27 volumiolabo sudo[2365]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 28 18:13:27 volumiolabo sudo[2365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:27 volumiolabo systemd[1]: Starting mpd.service - Music Player Daemon... Dec 28 18:13:27 volumiolabo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 28 18:13:27 volumiolabo systemd[1]: vtcs.service: Deactivated successfully. Dec 28 18:13:27 volumiolabo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 28 18:13:27 volumiolabo sudo[2372]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 28 18:13:27 volumiolabo sudo[2372]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 28 18:13:27 volumiolabo sudo[2372]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:27 volumiolabo sudo[2375]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 28 18:13:27 volumiolabo volumio[1150]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2JWNNKFfw7fLE2kTohONBLWrYfI3.78bd435749c1f115edb0909ce93aace5.state.status' Dec 28 18:13:27 volumiolabo volumio[1150]: info: Executing endpoint tc_getconfig Dec 28 18:13:27 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 28 18:13:27 volumiolabo sudo[2375]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:27 volumiolabo volumio[1150]: info: Successfully started MPD Monitor Dec 28 18:13:27 volumiolabo sudo[2363]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:27 volumiolabo sudo[2365]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:27 volumiolabo sudo[2375]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:28 volumiolabo volumio[1150]: info: Executing endpoint qc_getconfig Dec 28 18:13:28 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 28 18:13:28 volumiolabo sudo[2381]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 28 18:13:28 volumiolabo sudo[2381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:28 volumiolabo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 28 18:13:28 volumiolabo systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 28 18:13:28 volumiolabo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 28 18:13:28 volumiolabo volumio[1150]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Dec 28 18:13:28 volumiolabo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 28 18:13:28 volumiolabo sudo[2381]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:28 volumiolabo kernel: usb 1-1.1: new high-speed USB device number 17 using xhci_hcd Dec 28 18:13:28 volumiolabo volumio[1150]: info: MPD Permissions set Dec 28 18:13:28 volumiolabo volumio[1150]: info: Shairport-Sync Started Dec 28 18:13:28 volumiolabo volumio[1150]: info: TidalConnect service stoped! Dec 28 18:13:28 volumiolabo volumio[1150]: info: Executing endpoint qc_getconfig Dec 28 18:13:28 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 28 18:13:28 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 28 18:13:28 volumiolabo volumio[1150]: info: CoreStateMachine::getcurrentVolume Dec 28 18:13:28 volumiolabo qobuz-connect[2387]: 20251228 18:13:28.676 [2387.2387] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 28 18:13:28 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 18:13:28 volumiolabo qobuz-connect[2387]: 20251228 18:13:28.680 [2387.2387] INFO VolumeManager: [0x337348]: Setting new playback volume: 75 Dec 28 18:13:28 volumiolabo qobuz-connect[2387]: 20251228 18:13:28.680 [2387.2387] INFO VolumeManager: [0x337348]: Setting new mute state: 0 Dec 28 18:13:28 volumiolabo qobuz-connect[2387]: 20251228 18:13:28.681 [2387.2387] INFO QobuzConnect: [0x337d18]: Client initialized! Dec 28 18:13:28 volumiolabo qobuz-connect[2387]: 20251228 18:13:28.681 [2387.2387] INFO SampleApp: Starting Avahi advertising, name: Volumio_LaBo, service name: _qobuz-connect._tcp Dec 28 18:13:28 volumiolabo qobuz-connect[2387]: 20251228 18:13:28.716 [2387.2387] INFO LocalConfigManager: [0x336c28]: Starting Local Configuration server Dec 28 18:13:28 volumiolabo qobuz-connect[2387]: 20251228 18:13:28.716 [2387.2387] INFO SampleApp: Starting Local configuration server Dec 28 18:13:28 volumiolabo qobuz-connect[2387]: 20251228 18:13:28.716 [2387.2387] INFO SampleApp: Connected to UNIX socket client 0x321818 Dec 28 18:13:28 volumiolabo volumio[1150]: info: Dec 28 18:13:28 volumiolabo volumio[1150]: ---------------------------- USB Audio Device Detached Dec 28 18:13:28 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 28 18:13:28 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 28 18:13:28 volumiolabo qobuz-connect[2387]: 20251228 18:13:28.827 [2387.2387] INFO SampleApp: Playback volume changed: 75 Dec 28 18:13:28 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 28 18:13:28 volumiolabo kernel: usb 1-1.1: device descriptor read/64, error -71 Dec 28 18:13:28 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:28 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:28 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:28 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:28 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:28 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:28 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:28 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:29 volumiolabo (udev-worker)[1847]: controlC5: /usr/lib/udev/rules.d/78-sound-card.rules:5 Failed to write ATTR{/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1:1.0/sound/card5/controlC5/../uevent}, ignoring: No such file or directory Dec 28 18:13:29 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 18:13:29 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 18:13:29 volumiolabo volumio[1150]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 28 18:13:29 volumiolabo volumio[1150]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 28 18:13:29 volumiolabo kernel: usb 1-1.1: New USB device found, idVendor=2772, idProduct=0221, bcdDevice= 3.43 Dec 28 18:13:29 volumiolabo kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 28 18:13:29 volumiolabo kernel: usb 1-1.1: Product: Project RS USB Audio 2.0 Dec 28 18:13:29 volumiolabo kernel: usb 1-1.1: Manufacturer: Project RS Dec 28 18:13:29 volumiolabo kernel: usb 1-1.1: SerialNumber: 0000 Dec 28 18:13:29 volumiolabo volumio[1150]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 28 18:13:29 volumiolabo volumio[1150]: info: TidalConnect service stoped! Dec 28 18:13:29 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:29 volumiolabo volumio[1150]: info: VolumeController:: Volume=undefined Mute =false Dec 28 18:13:29 volumiolabo volumio[1150]: info: CoreStateMachine::pushState Dec 28 18:13:29 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioPushState Dec 28 18:13:29 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output update for this device Dec 28 18:13:29 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output Dec 28 18:13:29 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:29 volumiolabo mpd[2377]: 2025-12-28T18:13:29 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 28 18:13:29 volumiolabo systemd[1]: Started mpd.service - Music Player Daemon. Dec 28 18:13:29 volumiolabo sudo[2348]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:29 volumiolabo volumio[1150]: info: CoreStateMachine::updateTrackBlock Dec 28 18:13:29 volumiolabo volumio[1150]: info: CorePlayQueue::getTrackBlock Dec 28 18:13:29 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 18:13:29 volumiolabo volumio[1150]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2JWNNKFfw7fLE2kTohONBLWrYfI3.78bd435749c1f115edb0909ce93aace5.state.status' Dec 28 18:13:29 volumiolabo volumio[1150]: info: Starting Shairport Sync Dec 28 18:13:29 volumiolabo volumio[1150]: info: Dec 28 18:13:29 volumiolabo volumio[1150]: ---------------------------- USB Audio Device Attached Dec 28 18:13:29 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 28 18:13:29 volumiolabo sudo[2419]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 28 18:13:29 volumiolabo sudo[2419]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:29 volumiolabo volumio[1150]: error: API:pushError: TypeError: Cannot read properties of null (reading 'name') Dec 28 18:13:29 volumiolabo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 28 18:13:29 volumiolabo systemd[1]: shairport-sync.service: Deactivated successfully. Dec 28 18:13:29 volumiolabo volumio[1150]: error: updateQueue error: null Dec 28 18:13:29 volumiolabo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 28 18:13:29 volumiolabo systemd[1]: shairport-sync.service: Consumed 1.265s CPU time. Dec 28 18:13:29 volumiolabo volumio[1150]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 28 18:13:29 volumiolabo volumio[1150]: info: VolumeController:: Volume=undefined Mute =false Dec 28 18:13:29 volumiolabo volumio[1150]: info: CoreStateMachine::pushState Dec 28 18:13:29 volumiolabo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 28 18:13:29 volumiolabo sudo[2419]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:29 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioPushState Dec 28 18:13:29 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output update for this device Dec 28 18:13:29 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output Dec 28 18:13:29 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:29 volumiolabo volumio[1150]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 28 18:13:29 volumiolabo volumio[1150]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 28 18:13:29 volumiolabo volumio[1150]: error: updateQueue error: null Dec 28 18:13:29 volumiolabo volumio[1150]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2JWNNKFfw7fLE2kTohONBLWrYfI3.78bd435749c1f115edb0909ce93aace5.state.status' Dec 28 18:13:29 volumiolabo volumio[1150]: info: TidalConnect service started! Dec 28 18:13:29 volumiolabo volumio[1150]: info: Shairport-Sync Started Dec 28 18:13:29 volumiolabo volumio[1150]: info: Dec 28 18:13:29 volumiolabo volumio[1150]: ---------------------------- USB Audio Device Detached Dec 28 18:13:29 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 28 18:13:29 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 28 18:13:29 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 28 18:13:29 volumiolabo volumio[1150]: info: BOOT COMPLETED Dec 28 18:13:29 volumiolabo kernel: usb 1-1.1: USB disconnect, device number 17 Dec 28 18:13:30 volumiolabo (udev-worker)[1847]: controlC5: /usr/lib/udev/rules.d/78-sound-card.rules:5 Failed to write ATTR{/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1:1.0/sound/card5/controlC5/../uevent}, ignoring: No such file or directory Dec 28 18:13:30 volumiolabo sudo[2449]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 28 18:13:30 volumiolabo sudo[2449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:30 volumiolabo volumio[1150]: info: Dec 28 18:13:30 volumiolabo volumio[1150]: ---------------------------- USB Audio Device Attached Dec 28 18:13:30 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 28 18:13:30 volumiolabo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 28 18:13:30 volumiolabo sudo[2449]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:30 volumiolabo volumio[1150]: error: API:pushError: TypeError: Cannot read properties of null (reading 'name') Dec 28 18:13:30 volumiolabo volumio[1150]: info: TidalConnect service started! Dec 28 18:13:30 volumiolabo volumio[1150]: info: Dec 28 18:13:30 volumiolabo volumio[1150]: ---------------------------- USB Audio Device Detached Dec 28 18:13:30 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 28 18:13:30 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 28 18:13:30 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 28 18:13:30 volumiolabo (udev-worker)[1845]: controlC5: /usr/lib/udev/rules.d/78-sound-card.rules:5 Failed to write ATTR{/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1:1.0/sound/card5/controlC5/../uevent}, ignoring: No such file or directory Dec 28 18:13:31 volumiolabo volumio[1150]: info: Executing endpoint tc_getconfig Dec 28 18:13:31 volumiolabo sudo[2466]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 28 18:13:31 volumiolabo sudo[2466]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 18:13:31 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 28 18:13:31 volumiolabo vtcs[2455]: STARTING TidalConnect services, version: 1.5.2.56 Dec 28 18:13:31 volumiolabo vtcs[2455]: STARTED TidalConnect services. Dec 28 18:13:31 volumiolabo volumio[1150]: info: TidalConnect service stoped! Dec 28 18:13:31 volumiolabo systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 28 18:13:31 volumiolabo systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 28 18:13:31 volumiolabo systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 28 18:13:31 volumiolabo volumio[1150]: info: TidalConnect service stoped! Dec 28 18:13:31 volumiolabo volumio[1150]: info: Dec 28 18:13:31 volumiolabo volumio[1150]: ---------------------------- USB Audio Device Attached Dec 28 18:13:31 volumiolabo systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 28 18:13:31 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 28 18:13:31 volumiolabo mpd_monitor.sh[2478]: MPD Monitor Service: Starting MPD Monitor Service Dec 28 18:13:31 volumiolabo sudo[2466]: pam_unix(sudo:session): session closed for user root Dec 28 18:13:31 volumiolabo volumio[1150]: error: API:pushError: TypeError: Cannot read properties of null (reading 'name') Dec 28 18:13:31 volumiolabo volumio[1150]: info: Executing endpoint tc_connect Dec 28 18:13:31 volumiolabo volumio[1150]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 28 18:13:31 volumiolabo volumio[1150]: info: Connecting to TidalConnect Dec 28 18:13:31 volumiolabo volumio[1150]: info: Successfully started MPD Monitor Dec 28 18:13:31 volumiolabo volumio[1150]: info: CoreCommandRouter::servicePushState Dec 28 18:13:31 volumiolabo volumio[1150]: info: CoreStateMachine::pushState Dec 28 18:13:31 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioPushState Dec 28 18:13:31 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output update for this device Dec 28 18:13:31 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output Dec 28 18:13:31 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:31 volumiolabo volumio[1150]: info: CorePlayQueue::getTrack 0 Dec 28 18:13:31 volumiolabo volumio[1150]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Dec 28 18:13:31 volumiolabo volumio[1150]: info: CoreCommandRouter::servicePushState Dec 28 18:13:31 volumiolabo volumio[1150]: info: CoreStateMachine::pushState Dec 28 18:13:31 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioPushState Dec 28 18:13:31 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output update for this device Dec 28 18:13:31 volumiolabo volumio[1150]: info: MRS: Pushing multiroomSync output Dec 28 18:13:31 volumiolabo volumio[1150]: info: CoreCommandRouter::volumioGetState Dec 28 18:13:31 volumiolabo volumio[1150]: info: CorePlayQueue::getTrack 0 Dec 28 18:13:31 volumiolabo volumio[1150]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Dec 28 18:13:31 volumiolabo volumio[1150]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 28 18:13:31 volumiolabo volumio[1150]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 28 18:13:31 volumiolabo volumio[1150]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 18:13:31 volumiolabo volumio[1150]: Error: ENOENT: no such file or directory, unlink '/tmp/AMov6fPEWvIh49zJ3tgYp0vxIG9MH3Lour3xGIDwkLKGNIROmR1qaRhRkvQVzX8fXiNtNevxfvPUQj8Wo9NMuTYkOufyITT9lYbY' Dec 28 18:13:31 volumiolabo volumio[1150]: at Object.unlinkSync (node:fs:1899:3) Dec 28 18:13:31 volumiolabo volumio[1150]: at ControllerTidalconnect.deleteCertificate (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:11238) Dec 28 18:13:31 volumiolabo volumio[1150]: at TidalConnectClient. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:2774) Dec 28 18:13:31 volumiolabo volumio[1150]: at TidalConnectClient.emit (node:events:514:28) Dec 28 18:13:31 volumiolabo volumio[1150]: at TidalConnectClient.parseData (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:44661) Dec 28 18:13:31 volumiolabo volumio[1150]: at /myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41631 Dec 28 18:13:31 volumiolabo volumio[1150]: at Array.forEach () Dec 28 18:13:31 volumiolabo volumio[1150]: at Socket. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41509) Dec 28 18:13:31 volumiolabo volumio[1150]: at Socket.emit (node:events:514:28) Dec 28 18:13:31 volumiolabo volumio[1150]: at addChunk (node:internal/streams/readable:343:12) Dec 28 18:13:31 volumiolabo volumio[1150]: at readableAddChunk (node:internal/streams/readable:316:9) Dec 28 18:13:31 volumiolabo volumio[1150]: at Readable.push (node:internal/streams/readable:253:10) Dec 28 18:13:31 volumiolabo volumio[1150]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) { Dec 28 18:13:31 volumiolabo volumio[1150]: errno: -2, Dec 28 18:13:31 volumiolabo volumio[1150]: syscall: 'unlink', Dec 28 18:13:31 volumiolabo volumio[1150]: code: 'ENOENT', Dec 28 18:13:31 volumiolabo volumio[1150]: path: '/tmp/AMov6fPEWvIh49zJ3tgYp0vxIG9MH3Lour3xGIDwkLKGNIROmR1qaRhRkvQVzX8fXiNtNevxfvPUQj8Wo9NMuTYkOufyITT9lYbY' Dec 28 18:13:31 volumiolabo volumio[1150]: } Dec 28 18:13:31 volumiolabo volumio[1150]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 18:13:32 volumiolabo kernel: usb 1-1-port1: Cannot enable. Maybe the USB cable is bad? Dec 28 18:13:32 volumiolabo kernel: usb 1-1.1: new high-speed USB device number 19 using xhci_hcd Dec 28 18:13:34 volumiolabo kernel: usb 1-1.1: new high-speed USB device number 20 using xhci_hcd Dec 28 18:13:34 volumiolabo kernel: usb 1-1.1: device descriptor read/64, error -71 Dec 28 18:13:35 volumiolabo sudo[2527]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-28 18:12' Dec 28 18:13:35 volumiolabo sudo[2527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"