-- Logs begin at Thu 2019-02-14 04:11:59 CST, end at Sun 2025-10-26 13:57:14 CDT. -- Oct 26 13:56:09 volumio-suptronic volumiologrotate[610]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Oct 26 13:56:09 volumio-suptronic volumiologrotate[610]: ls: cannot access 'SUPTRONIC': No such file or directory Oct 26 13:56:20 volumio-suptronic volumio[923]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 13:56:20 volumio-suptronic volumio[923]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 13:56:20 volumio-suptronic volumio[923]: info: Retrieving Cloud Streaming UI Oct 26 13:56:20 volumio-suptronic volumio[923]: info: Getting Tidal Cloud Configuration Oct 26 13:56:20 volumio-suptronic volumio[923]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 13:56:20 volumio-suptronic volumio[923]: info: Getting Qobuz Cloud Configuration Oct 26 13:56:20 volumio-suptronic volumio[923]: info: Asking plugin for UI Config Oct 26 13:56:20 volumio-suptronic volumio[923]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 13:56:20 volumio-suptronic volumio[923]: info: Getting Spotify Cloud Configuration Oct 26 13:56:20 volumio-suptronic volumio[923]: info: Asking plugin for UI Config Oct 26 13:56:20 volumio-suptronic volumio[923]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 13:56:20 volumio-suptronic volumio[923]: info: Saving Spotify Acccount Oct 26 13:56:20 volumio-suptronic volumio[923]: info: Got it Oct 26 13:56:20 volumio-suptronic volumio[923]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 26 13:56:20 volumio-suptronic volumio[923]: info: Got Tidal Cloud Configuration Oct 26 13:56:20 volumio-suptronic volumio[923]: info: Got it Oct 26 13:56:20 volumio-suptronic volumio[923]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 13:56:20 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 26 13:56:20 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 26 13:56:20 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 13:56:20 volumio-suptronic volumio[923]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 13:56:21 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 13:56:21 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 13:56:21 volumio-suptronic volumio[923]: info: Discovery: Getting this device information Oct 26 13:56:21 volumio-suptronic volumio[923]: info: CoreCommandRouter::volumioGetState Oct 26 13:56:21 volumio-suptronic volumio[923]: info: CorePlayQueue::getTrack 3 Oct 26 13:56:21 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 13:56:21 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 13:56:21 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 26 13:56:25 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 13:56:29 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 13:56:33 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 13:56:37 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 13:56:41 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 13:56:43 volumio-suptronic volumio[923]: info: Disabling MyMusic plugin airplay_emulation Oct 26 13:56:43 volumio-suptronic volumio[923]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync Oct 26 13:56:43 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 13:56:43 volumio-suptronic volumio[923]: Cannot find translation for source TIDAL Oct 26 13:56:43 volumio-suptronic volumio[923]: info: Disabling plugin airplay_emulation Oct 26 13:56:43 volumio-suptronic volumio[923]: info: Done. Oct 26 13:56:43 volumio-suptronic sudo[4871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Oct 26 13:56:43 volumio-suptronic sudo[4871]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:56:43 volumio-suptronic systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 26 13:56:43 volumio-suptronic systemd[1]: shairport-sync.service: Succeeded. Oct 26 13:56:43 volumio-suptronic systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 26 13:56:43 volumio-suptronic sudo[4871]: pam_unix(sudo:session): session closed for user root Oct 26 13:56:43 volumio-suptronic volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 26 13:56:43 volumio-suptronic volumio[923]: info: Shairport-Sync Stopped Oct 26 13:56:44 volumio-suptronic volumio[923]: info: Disabling MyMusic plugin upnp_browser Oct 26 13:56:44 volumio-suptronic volumio[923]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMedia Servers Oct 26 13:56:44 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 13:56:44 volumio-suptronic volumio[923]: Cannot find translation for source TIDAL Oct 26 13:56:44 volumio-suptronic volumio[923]: info: Disabling plugin upnp_browser Oct 26 13:56:44 volumio-suptronic volumio[923]: info: Done. Oct 26 13:56:45 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 13:56:46 volumio-suptronic volumio[923]: info: Disabling MyMusic plugin upnp Oct 26 13:56:46 volumio-suptronic sudo[4875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 26 13:56:46 volumio-suptronic sudo[4875]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:56:46 volumio-suptronic systemd[1]: Stopping UPnP Renderer front-end to MPD... Oct 26 13:56:46 volumio-suptronic volumio[923]: error: Upnp client error: Error: This socket has been ended by the other party Oct 26 13:56:46 volumio-suptronic volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 26 13:56:48 volumio-suptronic volumio[923]: info: Disabling MyMusic plugin bluetooth Oct 26 13:56:48 volumio-suptronic volumio[923]: info: [MyVolumio PluginManager] Disabling and stopping plugin audio_interface bluetooth Oct 26 13:56:48 volumio-suptronic sudo[4880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumiobt.service Oct 26 13:56:48 volumio-suptronic sudo[4880]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:56:48 volumio-suptronic systemd[1]: Stopping Volumio Bluetooth Module... Oct 26 13:56:48 volumio-suptronic volumiobt[4882]: Killing pulse Oct 26 13:56:48 volumio-suptronic volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 26 13:56:48 volumio-suptronic volumiobt[4882]: Killing BT Pair agent Oct 26 13:56:48 volumio-suptronic systemd[1]: volumiobt.service: Main process exited, code=killed, status=15/TERM Oct 26 13:56:48 volumio-suptronic systemd[1]: volumiobt.service: Succeeded. Oct 26 13:56:48 volumio-suptronic systemd[1]: Stopped Volumio Bluetooth Module. Oct 26 13:56:48 volumio-suptronic sudo[4880]: pam_unix(sudo:session): session closed for user root Oct 26 13:56:48 volumio-suptronic volumio[923]: info: Volumio BT Module successfully stopped Oct 26 13:56:48 volumio-suptronic volumio[923]: info: Disabling plugin bluetooth Oct 26 13:56:49 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 13:56:50 volumio-suptronic volumio[923]: info: Disabling MyMusic plugin multiroom Oct 26 13:56:50 volumio-suptronic volumio[923]: info: [MyVolumio PluginManager] Disabling and stopping plugin audio_interface multiroom Oct 26 13:56:50 volumio-suptronic volumio[923]: info: Disabling plugin multiroom Oct 26 13:56:50 volumio-suptronic volumio[923]: info: Preparing to generate the ALSA configuration file Oct 26 13:56:50 volumio-suptronic volumio[923]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 26 13:56:50 volumio-suptronic volumio[923]: info: Reading ALSA contributions from plugins. Oct 26 13:56:50 volumio-suptronic volumio[923]: info: Asound.conf file written Oct 26 13:56:50 volumio-suptronic sudo[4886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 26 13:56:50 volumio-suptronic sudo[4886]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:56:50 volumio-suptronic sudo[4886]: pam_unix(sudo:session): session closed for user root Oct 26 13:56:50 volumio-suptronic volumio[923]: xcb_connection_has_error() returned true Oct 26 13:56:50 volumio-suptronic volumio[923]: info: Output device has changed, restarting MPD Oct 26 13:56:50 volumio-suptronic sudo[4891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 26 13:56:50 volumio-suptronic sudo[4891]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:56:50 volumio-suptronic sudo[4891]: pam_unix(sudo:session): session closed for user root Oct 26 13:56:50 volumio-suptronic volumio[923]: info: Output device has changed, restarting Shairport Sync Oct 26 13:56:50 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 13:56:50 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 13:56:50 volumio-suptronic sudo[4894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 26 13:56:50 volumio-suptronic sudo[4894]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:56:50 volumio-suptronic systemd[1]: Stopping Music Player Daemon... Oct 26 13:56:50 volumio-suptronic volumio[923]: xcb_connection_has_error() returned true Oct 26 13:56:50 volumio-suptronic volumio[923]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 26 13:56:50 volumio-suptronic volumio[923]: xcb_connection_has_error() returned true Oct 26 13:56:50 volumio-suptronic volumio[923]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 26 13:56:50 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 13:56:50 volumio-suptronic systemd[1]: mpd.service: Succeeded. Oct 26 13:56:50 volumio-suptronic systemd[1]: Stopped Music Player Daemon. Oct 26 13:56:50 volumio-suptronic volumio[923]: info: QobuzConnect: setDeactiveState invoked Oct 26 13:56:50 volumio-suptronic volumio[923]: info: CoreCommandRouter::volumioGetState Oct 26 13:56:50 volumio-suptronic volumio[923]: info: CorePlayQueue::getTrack 3 Oct 26 13:56:50 volumio-suptronic vtcs[1470]: [2025-10-26 13:56:50.651] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Oct 26 13:56:50 volumio-suptronic vtcs[1470]: [2025-10-26 13:56:50.652] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 26 13:56:50 volumio-suptronic systemd[1]: Starting Music Player Daemon... Oct 26 13:56:50 volumio-suptronic sudo[4903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 26 13:56:50 volumio-suptronic sudo[4903]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:56:50 volumio-suptronic systemd[1]: Stopping Volumio Tidal Connect Service... Oct 26 13:56:50 volumio-suptronic systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 26 13:56:50 volumio-suptronic systemd[1]: vtcs.service: Succeeded. Oct 26 13:56:50 volumio-suptronic systemd[1]: Stopped Volumio Tidal Connect Service. Oct 26 13:56:50 volumio-suptronic sudo[4903]: pam_unix(sudo:session): session closed for user root Oct 26 13:56:50 volumio-suptronic sudo[4906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 26 13:56:50 volumio-suptronic sudo[4906]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:56:50 volumio-suptronic sudo[4900]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 26 13:56:50 volumio-suptronic sudo[4906]: pam_unix(sudo:session): session closed for user root Oct 26 13:56:50 volumio-suptronic sudo[4900]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:56:50 volumio-suptronic sudo[4900]: pam_unix(sudo:session): session closed for user root Oct 26 13:56:50 volumio-suptronic volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 26 13:56:50 volumio-suptronic volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 26 13:56:50 volumio-suptronic sudo[4915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 26 13:56:50 volumio-suptronic sudo[4915]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:56:50 volumio-suptronic volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 26 13:56:50 volumio-suptronic volumio[923]: info: MPD Permissions set Oct 26 13:56:50 volumio-suptronic sudo[4915]: pam_unix(sudo:session): session closed for user root Oct 26 13:56:50 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 13:56:50 volumio-suptronic sudo[4919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 26 13:56:50 volumio-suptronic sudo[4919]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:56:50 volumio-suptronic systemd[1]: Stopping Volumio Qobuz Connect Service... Oct 26 13:56:50 volumio-suptronic qobuz-connect[1425]: 20251026 13:56:50.970 [1425.1425] INFO SampleApp: Stopping Local configuration server Oct 26 13:56:50 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 13:56:50 volumio-suptronic qobuz-connect[1425]: 20251026 13:56:50.981 [1425.1425] INFO SampleApp: shat down connection on UNIX socket Oct 26 13:56:50 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 13:56:50 volumio-suptronic systemd[1]: qobuz-connect.service: Succeeded. Oct 26 13:56:50 volumio-suptronic systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 26 13:56:50 volumio-suptronic systemd[1]: Started Volumio Qobuz Connect Service. Oct 26 13:56:51 volumio-suptronic sudo[4919]: pam_unix(sudo:session): session closed for user root Oct 26 13:56:51 volumio-suptronic volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 26 13:56:51 volumio-suptronic volumio[923]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 26 13:56:51 volumio-suptronic volumio[923]: info: QobuzConnect: setDeactiveState invoked Oct 26 13:56:51 volumio-suptronic volumio[923]: info: CoreCommandRouter::volumioGetState Oct 26 13:56:51 volumio-suptronic volumio[923]: info: CorePlayQueue::getTrack 3 Oct 26 13:56:51 volumio-suptronic volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 26 13:56:51 volumio-suptronic volumio[923]: info: Starting Shairport Sync Oct 26 13:56:51 volumio-suptronic volumio[923]: info: Executing endpoint qc_getconfig Oct 26 13:56:51 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 26 13:56:51 volumio-suptronic sudo[4928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 26 13:56:51 volumio-suptronic sudo[4928]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:56:51 volumio-suptronic qobuz-connect[4925]: 20251026 13:56:51.088 [4925.4925] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 26 13:56:51 volumio-suptronic volumio[923]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 26 13:56:51 volumio-suptronic volumio[923]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 26 13:56:51 volumio-suptronic qobuz-connect[4925]: 20251026 13:56:51.097 [4925.4925] INFO VolumeManager: [0x18a7c58]: Setting new playback volume: 75 Oct 26 13:56:51 volumio-suptronic qobuz-connect[4925]: 20251026 13:56:51.099 [4925.4925] INFO VolumeManager: [0x18a7c58]: Setting new mute state: 0 Oct 26 13:56:51 volumio-suptronic qobuz-connect[4925]: 20251026 13:56:51.100 [4925.4925] INFO QobuzConnect: [0x18a8628]: Client initialized! Oct 26 13:56:51 volumio-suptronic qobuz-connect[4925]: 20251026 13:56:51.101 [4925.4925] INFO SampleApp: Starting Avahi advertising, name: Volumio Suptronic, service name: _qobuz-connect._tcp Oct 26 13:56:51 volumio-suptronic qobuz-connect[4925]: 20251026 13:56:51.125 [4925.4925] INFO LocalConfigManager: [0x18a7538]: Starting Local Configuration server Oct 26 13:56:51 volumio-suptronic qobuz-connect[4925]: 20251026 13:56:51.125 [4925.4925] INFO SampleApp: Starting Local configuration server Oct 26 13:56:51 volumio-suptronic qobuz-connect[4925]: 20251026 13:56:51.126 [4925.4925] INFO SampleApp: Connected to UNIX socket client 0x189cf60 Oct 26 13:56:51 volumio-suptronic systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 26 13:56:51 volumio-suptronic sudo[4928]: pam_unix(sudo:session): session closed for user root Oct 26 13:56:51 volumio-suptronic volumio[923]: info: Shairport-Sync Started Oct 26 13:56:51 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 13:56:51 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 13:56:51 volumio-suptronic volumio[923]: info: Discovery: Getting this device information Oct 26 13:56:51 volumio-suptronic volumio[923]: info: CoreCommandRouter::volumioGetState Oct 26 13:56:51 volumio-suptronic volumio[923]: info: CorePlayQueue::getTrack 3 Oct 26 13:56:51 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 13:56:51 volumio-suptronic qobuz-connect[4925]: 20251026 13:56:51.237 [4925.4925] INFO SampleApp: Playback volume changed: 75 Oct 26 13:56:51 volumio-suptronic volumio[923]: info: CoreCommandRouter::volumioGetState Oct 26 13:56:51 volumio-suptronic volumio[923]: info: CorePlayQueue::getTrack 3 Oct 26 13:56:52 volumio-suptronic mpd[4917]: Oct 26 13:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 26 13:56:52 volumio-suptronic systemd[1]: Started Music Player Daemon. Oct 26 13:56:52 volumio-suptronic sudo[4894]: pam_unix(sudo:session): session closed for user root Oct 26 13:56:52 volumio-suptronic volumio[923]: error: updateQueue error: null Oct 26 13:56:53 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 13:56:53 volumio-suptronic volumio[923]: info: TidalConnect service stoped! Oct 26 13:56:53 volumio-suptronic volumio[923]: info: TidalConnect service stoped! Oct 26 13:56:53 volumio-suptronic volumio[923]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 26 13:56:53 volumio-suptronic volumio[923]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 26 13:56:53 volumio-suptronic sudo[4962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 26 13:56:53 volumio-suptronic sudo[4962]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:56:54 volumio-suptronic systemd[1]: Started Volumio Tidal Connect Service. Oct 26 13:56:54 volumio-suptronic sudo[4962]: pam_unix(sudo:session): session closed for user root Oct 26 13:56:54 volumio-suptronic volumio[923]: info: Executing endpoint tc_getconfig Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 26 13:56:54 volumio-suptronic vtcs[4964]: STARTING TidalConnect services, version: 1.5.1-beta1 Oct 26 13:56:54 volumio-suptronic volumio[923]: info: Enabling MyMusic plugin multiroom Oct 26 13:56:54 volumio-suptronic vtcs[4964]: STARTED TidalConnect services. Oct 26 13:56:54 volumio-suptronic volumio[923]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface multiroom Oct 26 13:56:54 volumio-suptronic volumio[923]: info: Enabling plugin multiroom Oct 26 13:56:54 volumio-suptronic volumio[923]: info: Preparing to generate the ALSA configuration file Oct 26 13:56:54 volumio-suptronic volumio[923]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 26 13:56:54 volumio-suptronic volumio[923]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 26 13:56:54 volumio-suptronic volumio[923]: info: Reading ALSA contributions from plugins. Oct 26 13:56:54 volumio-suptronic volumio[923]: info: Executing endpoint tc_connect Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 26 13:56:54 volumio-suptronic volumio[923]: info: Connecting to TidalConnect Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CoreCommandRouter::servicePushState Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CoreStateMachine::pushState Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CorePlayQueue::getTrack 3 Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CoreCommandRouter::volumioPushState Oct 26 13:56:54 volumio-suptronic volumio[923]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:56:54 volumio-suptronic volumio[923]: info: MRS: Pushing multiroomSync output Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CoreCommandRouter::volumioGetState Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CorePlayQueue::getTrack 3 Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CorePlayQueue::getTrack 3 Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CoreCommandRouter::servicePushState Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CoreStateMachine::pushState Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CorePlayQueue::getTrack 3 Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CoreCommandRouter::volumioPushState Oct 26 13:56:54 volumio-suptronic volumio[923]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:56:54 volumio-suptronic volumio[923]: info: MRS: Pushing multiroomSync output Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CoreCommandRouter::volumioGetState Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CorePlayQueue::getTrack 3 Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CorePlayQueue::getTrack 3 Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Oct 26 13:56:54 volumio-suptronic volumio[923]: info: Asound.conf file written Oct 26 13:56:54 volumio-suptronic sudo[4984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 26 13:56:54 volumio-suptronic sudo[4984]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:56:54 volumio-suptronic sudo[4984]: pam_unix(sudo:session): session closed for user root Oct 26 13:56:54 volumio-suptronic volumio[923]: xcb_connection_has_error() returned true Oct 26 13:56:54 volumio-suptronic volumio[923]: info: Output device has changed, restarting MPD Oct 26 13:56:54 volumio-suptronic sudo[4989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 26 13:56:54 volumio-suptronic sudo[4989]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:56:54 volumio-suptronic sudo[4989]: pam_unix(sudo:session): session closed for user root Oct 26 13:56:54 volumio-suptronic volumio[923]: info: Output device has changed, restarting Shairport Sync Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 13:56:54 volumio-suptronic sudo[4992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 26 13:56:54 volumio-suptronic sudo[4992]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:56:54 volumio-suptronic systemd[1]: Stopping Music Player Daemon... Oct 26 13:56:54 volumio-suptronic volumio[923]: xcb_connection_has_error() returned true Oct 26 13:56:54 volumio-suptronic volumio[923]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 26 13:56:54 volumio-suptronic volumio[923]: xcb_connection_has_error() returned true Oct 26 13:56:54 volumio-suptronic volumio[923]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 13:56:54 volumio-suptronic systemd[1]: mpd.service: Succeeded. Oct 26 13:56:54 volumio-suptronic systemd[1]: Stopped Music Player Daemon. Oct 26 13:56:54 volumio-suptronic volumio[923]: info: QobuzConnect: setDeactiveState invoked Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CoreCommandRouter::volumioGetState Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CorePlayQueue::getTrack 3 Oct 26 13:56:54 volumio-suptronic vtcs[4964]: [2025-10-26 13:56:54.680] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 26 13:56:54 volumio-suptronic vtcs[4964]: [2025-10-26 13:56:54.680] [tisoc] [error] [SpkconServer.cpp:472] socket write error. fd=3 errorno=32 error=Broken pipe Oct 26 13:56:54 volumio-suptronic vtcs[4964]: [2025-10-26 13:56:54.681] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Oct 26 13:56:54 volumio-suptronic volumio[923]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 26 13:56:54 volumio-suptronic sudo[5001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 26 13:56:54 volumio-suptronic sudo[5001]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:56:54 volumio-suptronic volumio[923]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 13:56:54 volumio-suptronic systemd[1]: Starting Music Player Daemon... Oct 26 13:56:54 volumio-suptronic systemd[1]: Stopping Volumio Tidal Connect Service... Oct 26 13:56:54 volumio-suptronic systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 26 13:56:54 volumio-suptronic systemd[1]: vtcs.service: Succeeded. Oct 26 13:56:54 volumio-suptronic systemd[1]: Stopped Volumio Tidal Connect Service. Oct 26 13:56:54 volumio-suptronic sudo[5001]: pam_unix(sudo:session): session closed for user root Oct 26 13:56:54 volumio-suptronic sudo[5008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 26 13:56:54 volumio-suptronic sudo[5008]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:56:54 volumio-suptronic sudo[4997]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 26 13:56:54 volumio-suptronic sudo[4997]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:56:54 volumio-suptronic sudo[4997]: pam_unix(sudo:session): session closed for user root Oct 26 13:56:54 volumio-suptronic sudo[5008]: pam_unix(sudo:session): session closed for user root Oct 26 13:56:54 volumio-suptronic volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 26 13:56:54 volumio-suptronic volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 26 13:56:54 volumio-suptronic volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 26 13:56:54 volumio-suptronic sudo[5016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 26 13:56:54 volumio-suptronic sudo[5016]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:56:54 volumio-suptronic volumio[923]: info: MPD Permissions set Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 13:56:54 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 13:56:54 volumio-suptronic sudo[5016]: pam_unix(sudo:session): session closed for user root Oct 26 13:56:54 volumio-suptronic sudo[5020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 26 13:56:54 volumio-suptronic sudo[5020]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:56:55 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 13:56:55 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 13:56:55 volumio-suptronic volumio[923]: info: Done. Oct 26 13:56:55 volumio-suptronic systemd[1]: Stopping Volumio Qobuz Connect Service... Oct 26 13:56:55 volumio-suptronic qobuz-connect[4925]: 20251026 13:56:55.019 [4925.4925] INFO SampleApp: Stopping Local configuration server Oct 26 13:56:55 volumio-suptronic qobuz-connect[4925]: 20251026 13:56:55.030 [4925.4925] INFO SampleApp: shat down connection on UNIX socket Oct 26 13:56:55 volumio-suptronic volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 26 13:56:55 volumio-suptronic systemd[1]: qobuz-connect.service: Succeeded. Oct 26 13:56:55 volumio-suptronic systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 26 13:56:55 volumio-suptronic volumio[923]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 26 13:56:55 volumio-suptronic volumio[923]: info: QobuzConnect: setDeactiveState invoked Oct 26 13:56:55 volumio-suptronic volumio[923]: info: CoreCommandRouter::volumioGetState Oct 26 13:56:55 volumio-suptronic volumio[923]: info: CorePlayQueue::getTrack 3 Oct 26 13:56:55 volumio-suptronic volumio[923]: info: Starting Shairport Sync Oct 26 13:56:55 volumio-suptronic systemd[1]: Started Volumio Qobuz Connect Service. Oct 26 13:56:55 volumio-suptronic sudo[5020]: pam_unix(sudo:session): session closed for user root Oct 26 13:56:55 volumio-suptronic volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 26 13:56:55 volumio-suptronic sudo[5028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 26 13:56:55 volumio-suptronic volumio[923]: info: Executing endpoint qc_getconfig Oct 26 13:56:55 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 26 13:56:55 volumio-suptronic sudo[5028]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:56:55 volumio-suptronic qobuz-connect[5026]: 20251026 13:56:55.109 [5026.5026] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 26 13:56:55 volumio-suptronic qobuz-connect[5026]: 20251026 13:56:55.121 [5026.5026] INFO VolumeManager: [0x1d26c58]: Setting new playback volume: 75 Oct 26 13:56:55 volumio-suptronic qobuz-connect[5026]: 20251026 13:56:55.121 [5026.5026] INFO VolumeManager: [0x1d26c58]: Setting new mute state: 0 Oct 26 13:56:55 volumio-suptronic qobuz-connect[5026]: 20251026 13:56:55.121 [5026.5026] INFO QobuzConnect: [0x1d27628]: Client initialized! Oct 26 13:56:55 volumio-suptronic qobuz-connect[5026]: 20251026 13:56:55.121 [5026.5026] INFO SampleApp: Starting Avahi advertising, name: Volumio Suptronic, service name: _qobuz-connect._tcp Oct 26 13:56:55 volumio-suptronic volumio[923]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 26 13:56:55 volumio-suptronic volumio[923]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 26 13:56:55 volumio-suptronic qobuz-connect[5026]: 20251026 13:56:55.137 [5026.5026] INFO LocalConfigManager: [0x1d26538]: Starting Local Configuration server Oct 26 13:56:55 volumio-suptronic qobuz-connect[5026]: 20251026 13:56:55.137 [5026.5026] INFO SampleApp: Starting Local configuration server Oct 26 13:56:55 volumio-suptronic qobuz-connect[5026]: 20251026 13:56:55.138 [5026.5026] INFO SampleApp: Connected to UNIX socket client 0x1d1bf60 Oct 26 13:56:55 volumio-suptronic systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 26 13:56:55 volumio-suptronic volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 26 13:56:55 volumio-suptronic systemd[1]: shairport-sync.service: Succeeded. Oct 26 13:56:55 volumio-suptronic systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 26 13:56:55 volumio-suptronic volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 26 13:56:55 volumio-suptronic systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 26 13:56:55 volumio-suptronic sudo[5028]: pam_unix(sudo:session): session closed for user root Oct 26 13:56:55 volumio-suptronic volumio[923]: info: Shairport-Sync Started Oct 26 13:56:55 volumio-suptronic qobuz-connect[5026]: 20251026 13:56:55.258 [5026.5026] INFO SampleApp: Playback volume changed: 75 Oct 26 13:56:55 volumio-suptronic volumio[923]: info: CoreCommandRouter::volumioGetState Oct 26 13:56:55 volumio-suptronic volumio[923]: info: CorePlayQueue::getTrack 3 Oct 26 13:56:56 volumio-suptronic mpd[5018]: Oct 26 13:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 26 13:56:56 volumio-suptronic systemd[1]: Started Music Player Daemon. Oct 26 13:56:56 volumio-suptronic sudo[4992]: pam_unix(sudo:session): session closed for user root Oct 26 13:56:56 volumio-suptronic volumio[923]: error: updateQueue error: null Oct 26 13:56:57 volumio-suptronic volumio[923]: info: TidalConnect service started! Oct 26 13:56:57 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 13:56:57 volumio-suptronic volumio[923]: info: TidalConnect service stoped! Oct 26 13:56:57 volumio-suptronic volumio[923]: info: TidalConnect service stoped! Oct 26 13:56:57 volumio-suptronic volumio[923]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 26 13:56:57 volumio-suptronic volumio[923]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 26 13:56:58 volumio-suptronic sudo[5046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 26 13:56:58 volumio-suptronic sudo[5046]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:56:58 volumio-suptronic systemd[1]: Started Volumio Tidal Connect Service. Oct 26 13:56:58 volumio-suptronic sudo[5046]: pam_unix(sudo:session): session closed for user root Oct 26 13:56:58 volumio-suptronic volumio[923]: info: Executing endpoint tc_getconfig Oct 26 13:56:58 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 26 13:56:58 volumio-suptronic vtcs[5048]: STARTING TidalConnect services, version: 1.5.1-beta1 Oct 26 13:56:58 volumio-suptronic vtcs[5048]: STARTED TidalConnect services. Oct 26 13:56:58 volumio-suptronic volumio[923]: info: Executing endpoint tc_connect Oct 26 13:56:58 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 26 13:56:58 volumio-suptronic volumio[923]: info: Connecting to TidalConnect Oct 26 13:56:58 volumio-suptronic volumio[923]: info: CoreCommandRouter::servicePushState Oct 26 13:56:58 volumio-suptronic volumio[923]: info: CoreStateMachine::pushState Oct 26 13:56:58 volumio-suptronic volumio[923]: info: CorePlayQueue::getTrack 3 Oct 26 13:56:58 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 13:56:58 volumio-suptronic volumio[923]: info: CoreCommandRouter::volumioPushState Oct 26 13:56:58 volumio-suptronic volumio[923]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:56:58 volumio-suptronic volumio[923]: info: MRS: Pushing multiroomSync output Oct 26 13:56:58 volumio-suptronic volumio[923]: info: CoreCommandRouter::volumioGetState Oct 26 13:56:58 volumio-suptronic volumio[923]: info: CorePlayQueue::getTrack 3 Oct 26 13:56:58 volumio-suptronic volumio[923]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:56:58 volumio-suptronic volumio[923]: info: MRS: Pushing multiroomSync output Oct 26 13:56:58 volumio-suptronic volumio[923]: info: CorePlayQueue::getTrack 3 Oct 26 13:56:58 volumio-suptronic volumio[923]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Oct 26 13:56:58 volumio-suptronic volumio[923]: info: CoreCommandRouter::servicePushState Oct 26 13:56:58 volumio-suptronic volumio[923]: info: CoreStateMachine::pushState Oct 26 13:56:58 volumio-suptronic volumio[923]: info: CorePlayQueue::getTrack 3 Oct 26 13:56:58 volumio-suptronic volumio[923]: info: CoreCommandRouter::volumioPushState Oct 26 13:56:58 volumio-suptronic volumio[923]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:56:58 volumio-suptronic volumio[923]: info: MRS: Pushing multiroomSync output Oct 26 13:56:58 volumio-suptronic volumio[923]: info: CoreCommandRouter::volumioGetState Oct 26 13:56:58 volumio-suptronic volumio[923]: info: CorePlayQueue::getTrack 3 Oct 26 13:56:58 volumio-suptronic volumio[923]: info: MRS: Pushing multiroomSync output update for this device Oct 26 13:56:58 volumio-suptronic volumio[923]: info: MRS: Pushing multiroomSync output Oct 26 13:56:58 volumio-suptronic volumio[923]: info: CorePlayQueue::getTrack 3 Oct 26 13:56:58 volumio-suptronic volumio[923]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Oct 26 13:56:59 volumio-suptronic volumio[923]: info: MRS: Getting audio outputs on start Oct 26 13:56:59 volumio-suptronic volumio[923]: info: MRS: Requesting all other devices output Oct 26 13:57:00 volumio-suptronic volumio[923]: info: CoreCommandRouter::volumioPause Oct 26 13:57:00 volumio-suptronic volumio[923]: info: CoreStateMachine::pause Oct 26 13:57:00 volumio-suptronic volumio[923]: info: CoreStateMachine::stPlaybackTimer Oct 26 13:57:00 volumio-suptronic volumio[923]: info: CoreStateMachine::servicePause Oct 26 13:57:00 volumio-suptronic volumio[923]: info: CorePlayQueue::getTrack 3 Oct 26 13:57:00 volumio-suptronic volumio[923]: info: CoreCommandRouter::servicePause Oct 26 13:57:00 volumio-suptronic volumio[923]: info: [1761505020832] ControllerTidal::pause Oct 26 13:57:00 volumio-suptronic volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 26 13:57:00 volumio-suptronic volumio[923]: info: ControllerMpd::pause Oct 26 13:57:00 volumio-suptronic volumio[923]: verbose: ControllerMpd::sendMpdCommand pause Oct 26 13:57:00 volumio-suptronic volumio[923]: info: sendMpdCommand pause took 1 milliseconds Oct 26 13:57:01 volumio-suptronic volumio[923]: info: TidalConnect service started! Oct 26 13:57:01 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 13:57:04 volumio-suptronic volumio[923]: info: Enabling MyMusic plugin bluetooth Oct 26 13:57:04 volumio-suptronic volumio[923]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth Oct 26 13:57:04 volumio-suptronic volumio[923]: info: Enabling plugin bluetooth Oct 26 13:57:04 volumio-suptronic volumio[923]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Oct 26 13:57:04 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 26 13:57:04 volumio-suptronic volumio[923]: info: Done. Oct 26 13:57:04 volumio-suptronic sudo[5082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Suptronic Oct 26 13:57:04 volumio-suptronic sudo[5082]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:57:04 volumio-suptronic sudo[5082]: pam_unix(sudo:session): session closed for user root Oct 26 13:57:04 volumio-suptronic volumio[923]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'Volumio Suptronic' Oct 26 13:57:04 volumio-suptronic volumio[923]: Can't change local name on hci0: Network is down (100) Oct 26 13:57:05 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 13:57:05 volumio-suptronic volumio[923]: info: Enabling MyMusic plugin upnp_browser Oct 26 13:57:05 volumio-suptronic volumio[923]: info: Enabling plugin upnp_browser Oct 26 13:57:05 volumio-suptronic volumio[923]: info: Loading plugin "upnp_browser"... Oct 26 13:57:05 volumio-suptronic volumio[923]: info: PLUGIN START: upnp_browser Oct 26 13:57:05 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 13:57:05 volumio-suptronic volumio[923]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 26 13:57:05 volumio-suptronic volumio[923]: info: [1761505025412] CoreMusicLibrary::Adding element Media Servers Oct 26 13:57:05 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 13:57:05 volumio-suptronic volumio[923]: Cannot find translation for source TIDAL Oct 26 13:57:05 volumio-suptronic volumio[923]: info: Done. Oct 26 13:57:07 volumio-suptronic sudo[5085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 26 13:57:07 volumio-suptronic sudo[5085]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:57:07 volumio-suptronic systemd[1]: Started Volumio Bluetooth Module. Oct 26 13:57:07 volumio-suptronic sudo[5085]: pam_unix(sudo:session): session closed for user root Oct 26 13:57:07 volumio-suptronic sudo[5088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Oct 26 13:57:07 volumio-suptronic sudo[5088]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:57:07 volumio-suptronic volumiobt[5087]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Oct 26 13:57:07 volumio-suptronic sudo[5088]: pam_unix(sudo:session): session closed for user root Oct 26 13:57:07 volumio-suptronic volumio[923]: info: Volumio BT Module successfully started Oct 26 13:57:08 volumio-suptronic sudo[5091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Oct 26 13:57:08 volumio-suptronic sudo[5091]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:57:08 volumio-suptronic volumiobt[5087]: pulseaudio: no process found Oct 26 13:57:08 volumio-suptronic sudo[5091]: pam_unix(sudo:session): session closed for user root Oct 26 13:57:08 volumio-suptronic pulseaudio[5095]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 26 13:57:08 volumio-suptronic pulseaudio[5095]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 26 13:57:08 volumio-suptronic volumiobt[5087]: Applying permissions Oct 26 13:57:08 volumio-suptronic volumiobt[5087]: Setting BT discoverable and pairable Oct 26 13:57:08 volumio-suptronic pulseaudio[5095]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Oct 26 13:57:08 volumio-suptronic volumiobt[5087]: [bluetooth]# -e power on Oct 26 13:57:08 volumio-suptronic volumiobt[5087]: Invalid command in menu main: -e Oct 26 13:57:08 volumio-suptronic volumiobt[5087]: Oct 26 13:57:08 volumio-suptronic volumiobt[5087]: Use "help" for a list of available commands in a menu. Oct 26 13:57:08 volumio-suptronic volumiobt[5087]: Use "menu " if you want to enter any submenu. Oct 26 13:57:08 volumio-suptronic volumiobt[5087]: Use "back" if you want to return to menu main. Oct 26 13:57:08 volumio-suptronic volumiobt[5087]: [bluetooth]# agent on Oct 26 13:57:08 volumio-suptronic volumiobt[5087]: Failed to register agent object Oct 26 13:57:08 volumio-suptronic volumiobt[5087]: [bluetooth]# discoverable on Oct 26 13:57:08 volumio-suptronic volumiobt[5087]: No default controller available Oct 26 13:57:08 volumio-suptronic volumiobt[5087]: [bluetooth]# pairable on Oct 26 13:57:08 volumio-suptronic volumiobt[5087]: No default controller available Oct 26 13:57:08 volumio-suptronic volumiobt[5087]: [bluetooth]# agent NoInputNoOutput Oct 26 13:57:08 volumio-suptronic volumiobt[5087]: Failed to register agent object Oct 26 13:57:08 volumio-suptronic volumiobt[5087]: [bluetooth]# default-agent Oct 26 13:57:08 volumio-suptronic volumiobt[5087]: No agent is registered Oct 26 13:57:08 volumio-suptronic volumiobt[5087]: [bluetooth]# quit Oct 26 13:57:08 volumio-suptronic volumiobt[5087]: [59B blob data] Oct 26 13:57:09 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 13:57:09 volumio-suptronic volumiologrotate[610]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Oct 26 13:57:09 volumio-suptronic volumiologrotate[610]: ls: cannot access 'SUPTRONIC': No such file or directory Oct 26 13:57:09 volumio-suptronic volumio[923]: info: Enabling MyMusic plugin airplay_emulation Oct 26 13:57:09 volumio-suptronic volumio[923]: info: Enabling plugin airplay_emulation Oct 26 13:57:09 volumio-suptronic volumio[923]: info: Loading plugin "airplay_emulation"... Oct 26 13:57:09 volumio-suptronic volumio[923]: info: Starting Shairport Sync Oct 26 13:57:09 volumio-suptronic volumio[923]: info: PLUGIN START: airplay_emulation Oct 26 13:57:09 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 13:57:09 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 13:57:10 volumio-suptronic volumio[923]: xcb_connection_has_error() returned true Oct 26 13:57:10 volumio-suptronic volumio[923]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 26 13:57:10 volumio-suptronic volumio[923]: xcb_connection_has_error() returned true Oct 26 13:57:10 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 13:57:10 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 13:57:10 volumio-suptronic volumio[923]: xcb_connection_has_error() returned true Oct 26 13:57:10 volumio-suptronic volumio[923]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 26 13:57:10 volumio-suptronic volumio[923]: xcb_connection_has_error() returned true Oct 26 13:57:10 volumio-suptronic volumio[923]: info: Done. Oct 26 13:57:10 volumio-suptronic volumio[923]: info: Starting Shairport Sync Oct 26 13:57:10 volumio-suptronic volumio[923]: info: Starting Shairport Sync Oct 26 13:57:10 volumio-suptronic sudo[5149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 26 13:57:10 volumio-suptronic sudo[5149]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:57:10 volumio-suptronic systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 26 13:57:10 volumio-suptronic sudo[5152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 26 13:57:10 volumio-suptronic systemd[1]: shairport-sync.service: Succeeded. Oct 26 13:57:10 volumio-suptronic sudo[5152]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:57:10 volumio-suptronic systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 26 13:57:10 volumio-suptronic volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 26 13:57:10 volumio-suptronic volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 26 13:57:10 volumio-suptronic volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 26 13:57:10 volumio-suptronic volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 26 13:57:10 volumio-suptronic systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 26 13:57:10 volumio-suptronic sudo[5149]: pam_unix(sudo:session): session closed for user root Oct 26 13:57:10 volumio-suptronic volumio[923]: info: Shairport-Sync Started Oct 26 13:57:10 volumio-suptronic systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 26 13:57:10 volumio-suptronic systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 26 13:57:10 volumio-suptronic systemd[1]: shairport-sync.service: Succeeded. Oct 26 13:57:10 volumio-suptronic systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 26 13:57:10 volumio-suptronic systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 26 13:57:10 volumio-suptronic sudo[5152]: pam_unix(sudo:session): session closed for user root Oct 26 13:57:10 volumio-suptronic volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 26 13:57:10 volumio-suptronic volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 26 13:57:10 volumio-suptronic volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 26 13:57:10 volumio-suptronic volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 26 13:57:10 volumio-suptronic volumio[923]: info: Shairport-Sync Started Oct 26 13:57:11 volumio-suptronic volumio[923]: info: Disabling MyMusic plugin upnp Oct 26 13:57:11 volumio-suptronic sudo[5159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 26 13:57:11 volumio-suptronic sudo[5159]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 13:57:11 volumio-suptronic volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 26 13:57:11 volumio-suptronic volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 26 13:57:13 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 13:57:13 volumio-suptronic volumio[923]: info: Enabling MyMusic plugin upnp Oct 26 13:57:13 volumio-suptronic volumio[923]: info: Enabling plugin upnp Oct 26 13:57:13 volumio-suptronic volumio[923]: info: Loading plugin "upnp"... Oct 26 13:57:13 volumio-suptronic volumio[923]: info: [1761505033668] Starting Upmpd Daemon Oct 26 13:57:13 volumio-suptronic volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 26 13:57:13 volumio-suptronic volumio[923]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 13:57:13 volumio-suptronic volumio[923]: Error: listen EADDRINUSE: address already in use :::6599 Oct 26 13:57:13 volumio-suptronic volumio[923]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Oct 26 13:57:13 volumio-suptronic volumio[923]: at listenInCluster (net.js:1366:12) Oct 26 13:57:13 volumio-suptronic volumio[923]: at Server.listen (net.js:1452:7) Oct 26 13:57:13 volumio-suptronic volumio[923]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 26 13:57:13 volumio-suptronic volumio[923]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 26 13:57:13 volumio-suptronic volumio[923]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 26 13:57:13 volumio-suptronic volumio[923]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 26 13:57:13 volumio-suptronic volumio[923]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Oct 26 13:57:13 volumio-suptronic volumio[923]: code: 'EADDRINUSE', Oct 26 13:57:13 volumio-suptronic volumio[923]: errno: -98, Oct 26 13:57:13 volumio-suptronic volumio[923]: syscall: 'listen', Oct 26 13:57:13 volumio-suptronic volumio[923]: address: '::', Oct 26 13:57:13 volumio-suptronic volumio[923]: port: 6599 Oct 26 13:57:13 volumio-suptronic volumio[923]: } Oct 26 13:57:13 volumio-suptronic volumio[923]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 13:57:14 volumio-suptronic sudo[5187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-26 13:56 Oct 26 13:57:14 volumio-suptronic sudo[5187]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"