Dec 14 12:16:00 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:00 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:00 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:00 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:00 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:00 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:00 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:00 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:01 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:01 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:01 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:01 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:01 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:01 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:01 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:01 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:02 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:02 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:02 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:02 volumioegm volumio[12023]: info: Bluetoothremote--- Checking for trusted devices to reconnect... Dec 14 12:16:02 volumioegm volumio[12023]: info: Bluetoothremote--- Device list cleared and placeholder written. Dec 14 12:16:02 volumioegm bluetoothd[987]: Adv Monitor app :1.669 disconnected from D-Bus Dec 14 12:16:02 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:02 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:02 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:02 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:02 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:02 volumioegm volumio[12023]: info: Bluetoothremote--- Device found: Press scan to detect BT device - xx Dec 14 12:16:02 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:02 volumioegm bluetoothd[987]: Adv Monitor app :1.675 disconnected from D-Bus Dec 14 12:16:03 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:03 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:03 volumioegm mpd[12625]: 2025-12-14T12:16:03 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 14 12:16:03 volumioegm systemd[1]: Started mpd.service - Music Player Daemon. Dec 14 12:16:03 volumioegm sudo[12577]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:03 volumioegm sudo[12521]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:03 volumioegm sudo[12491]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:03 volumioegm volumio[12023]: error: MPD error: The expression evaluated to a falsy value: Dec 14 12:16:03 volumioegm volumio[12023]: assert.ok(self.idling) Dec 14 12:16:03 volumioegm volumio[12023]: error: The expression evaluated to a falsy value: Dec 14 12:16:03 volumioegm volumio[12023]: assert.ok(self.idling) Dec 14 12:16:03 volumioegm volumio[12023]: error: MPD error: The expression evaluated to a falsy value: Dec 14 12:16:03 volumioegm volumio[12023]: assert.ok(self.idling) Dec 14 12:16:03 volumioegm volumio[12023]: error: The expression evaluated to a falsy value: Dec 14 12:16:03 volumioegm volumio[12023]: assert.ok(self.idling) Dec 14 12:16:03 volumioegm volumio[12023]: error: updateQueue error: null Dec 14 12:16:03 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:03 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:03 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:03 volumioegm systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 4. Dec 14 12:16:03 volumioegm systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 12:16:03 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:03 volumioegm systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 12:16:03 volumioegm volumio[12023]: info: No valid Plugin REST Endpoint Dec 14 12:16:03 volumioegm qobuz-connect[12712]: 20251214 12:16:03.735 [12712.12712] ERROR SampleApp: api request was not successful Dec 14 12:16:03 volumioegm qobuz-connect[12712]: Error: Application ID was not specified! Dec 14 12:16:03 volumioegm qobuz-connect[12712]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Dec 14 12:16:03 volumioegm qobuz-connect[12712]: -m|--manufacturer: Manufacturer of the device Dec 14 12:16:03 volumioegm qobuz-connect[12712]: -n|--deviceName: Device name Dec 14 12:16:03 volumioegm qobuz-connect[12712]: -l|--model: Device's model name Dec 14 12:16:03 volumioegm qobuz-connect[12712]: -s|--serialNumber: Device's serial number Dec 14 12:16:03 volumioegm qobuz-connect[12712]: -o|--outputDevice: ALSA PCM device to play audio to Dec 14 12:16:03 volumioegm qobuz-connect[12712]: -p|--port: Port to run the local configuration HTTP server on Dec 14 12:16:03 volumioegm qobuz-connect[12712]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Dec 14 12:16:03 volumioegm qobuz-connect[12712]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Dec 14 12:16:03 volumioegm qobuz-connect[12712]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Dec 14 12:16:03 volumioegm qobuz-connect[12712]: -h|--help: Print this help Dec 14 12:16:03 volumioegm systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Dec 14 12:16:03 volumioegm systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Dec 14 12:16:03 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:03 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:03 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:04 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:04 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:04 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:04 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:04 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:04 volumioegm volumio[12023]: info: Discovery: adding 50d6a6a7-e9e0-4840-8d94-b84ce0aac36b Dec 14 12:16:04 volumioegm volumio[12023]: info: Discovery: Found device VolumioEgm Dec 14 12:16:04 volumioegm volumio[12023]: info: CoreCommandRouter::volumioGetState Dec 14 12:16:04 volumioegm volumio[12023]: info: CorePlayQueue::getTrack 0 Dec 14 12:16:04 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:04 volumioegm volumio[12023]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 14 12:16:04 volumioegm volumio[12023]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 14 12:16:04 volumioegm volumio[12023]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 14 12:16:04 volumioegm volumio[12023]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 14 12:16:04 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:04 volumioegm volumio[12023]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 14 12:16:04 volumioegm volumio[12023]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Dec 14 12:16:04 volumioegm volumio[12023]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Dec 14 12:16:04 volumioegm volumio[12023]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Dec 14 12:16:05 volumioegm volumio[12023]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Dec 14 12:16:05 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:05 volumioegm volumio[12023]: info: CoreCommandRouter::volumioGetState Dec 14 12:16:05 volumioegm volumio[12023]: info: CorePlayQueue::getTrack 0 Dec 14 12:16:05 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 14 12:16:05 volumioegm volumio[12023]: info: Adding plugin bluetooth to MyMusic Plugins Dec 14 12:16:05 volumioegm volumio[12023]: info: Adding plugin multiroom to MyMusic Plugins Dec 14 12:16:05 volumioegm volumio[12023]: info: Adding plugin metavolumio to MyMusic Plugins Dec 14 12:16:05 volumioegm volumio[12023]: info: Adding plugin cd_controller to MyMusic Plugins Dec 14 12:16:05 volumioegm volumio[12023]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 14 12:16:05 volumioegm volumio[12023]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 14 12:16:05 volumioegm volumio[12023]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 14 12:16:05 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 14 12:16:06 volumioegm volumio[12023]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 14 12:16:06 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 14 12:16:06 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 12:16:06 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 12:16:06 volumioegm volumio[12023]: info: Starting MyVolumio Remote Streaming Endpoints Dec 14 12:16:06 volumioegm volumio[12023]: info: MyVolumio login type: Token Dec 14 12:16:06 volumioegm volumio[12023]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 14 12:16:06 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 14 12:16:07 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 14 12:16:07 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 14 12:16:07 volumioegm volumio[12023]: info: Streaming services startup Dec 14 12:16:07 volumioegm volumio[12023]: info: Starting Streaming Daemon Dec 14 12:16:07 volumioegm sudo[12739]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 14 12:16:07 volumioegm sudo[12739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:16:07 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 14 12:16:07 volumioegm sudo[12739]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:07 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 14 12:16:07 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 12:16:07 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 14 12:16:07 volumioegm sudo[12751]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 12:16:07 volumioegm sudo[12751]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:16:07 volumioegm sudo[12753]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 12:16:07 volumioegm sudo[12753]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:16:07 volumioegm sudo[12755]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 12:16:07 volumioegm sudo[12755]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:16:07 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:07 volumioegm volumio[12023]: error: Cannot start Volumio Streaming Daemon Dec 14 12:16:07 volumioegm volumio[12023]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 14 12:16:07 volumioegm volumio[12023]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 14 12:16:07 volumioegm volumio[12023]: info: CoreCommandRouter::volumioGetState Dec 14 12:16:07 volumioegm volumio[12023]: info: CorePlayQueue::getTrack 0 Dec 14 12:16:07 volumioegm systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 14 12:16:07 volumioegm systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 14 12:16:07 volumioegm systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 14 12:16:07 volumioegm systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 14 12:16:07 volumioegm sudo[12755]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:07 volumioegm mpd_monitor.sh[12759]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 12:16:07 volumioegm sudo[12753]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:07 volumioegm sudo[12751]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:07 volumioegm volumio[12023]: info: Successfully started MPD Monitor Dec 14 12:16:07 volumioegm volumio[12023]: info: Successfully started MPD Monitor Dec 14 12:16:07 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:07 volumioegm volumio[12023]: info: Successfully started MPD Monitor Dec 14 12:16:08 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:08 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:08 volumioegm volumio-remote-updater[720]: Test mode disabled Dec 14 12:16:08 volumioegm volumio-remote-updater[720]: Alpha mode disabled Dec 14 12:16:08 volumioegm volumio-remote-updater[720]: Alpha legacy test mode disabled Dec 14 12:16:08 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 14 12:16:08 volumioegm volumio[12023]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 14 12:16:08 volumioegm volumio[12023]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 14 12:16:08 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 14 12:16:08 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:08 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:08 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:08 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:08 volumioegm systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 5. Dec 14 12:16:08 volumioegm systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 12:16:08 volumioegm volumio[12023]: info: MyVolumio token set successfully Dec 14 12:16:08 volumioegm volumio[12023]: info: MYVOLUMIO: Adding device Dec 14 12:16:08 volumioegm volumio[12023]: info: MYVOLUMIO: Evaluating Server Dec 14 12:16:08 volumioegm systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 12:16:08 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:08 volumioegm volumio[12023]: info: No valid Plugin REST Endpoint Dec 14 12:16:08 volumioegm qobuz-connect[12788]: 20251214 12:16:08.899 [12788.12788] ERROR SampleApp: api request was not successful Dec 14 12:16:08 volumioegm qobuz-connect[12788]: Error: Application ID was not specified! Dec 14 12:16:08 volumioegm qobuz-connect[12788]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Dec 14 12:16:08 volumioegm qobuz-connect[12788]: -m|--manufacturer: Manufacturer of the device Dec 14 12:16:08 volumioegm qobuz-connect[12788]: -n|--deviceName: Device name Dec 14 12:16:08 volumioegm qobuz-connect[12788]: -l|--model: Device's model name Dec 14 12:16:08 volumioegm qobuz-connect[12788]: -s|--serialNumber: Device's serial number Dec 14 12:16:08 volumioegm qobuz-connect[12788]: -o|--outputDevice: ALSA PCM device to play audio to Dec 14 12:16:08 volumioegm qobuz-connect[12788]: -p|--port: Port to run the local configuration HTTP server on Dec 14 12:16:08 volumioegm qobuz-connect[12788]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Dec 14 12:16:08 volumioegm qobuz-connect[12788]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Dec 14 12:16:08 volumioegm qobuz-connect[12788]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Dec 14 12:16:08 volumioegm qobuz-connect[12788]: -h|--help: Print this help Dec 14 12:16:08 volumioegm systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Dec 14 12:16:08 volumioegm systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Dec 14 12:16:08 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:09 volumioegm volumio[12023]: info: MyVolumio Plan changed: premium Dec 14 12:16:09 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Dec 14 12:16:09 volumioegm volumio[12023]: info: Removing browser output: myVolumio user plan is not superstar Dec 14 12:16:09 volumioegm volumio[12023]: info: Removing audio output: Dec 14 12:16:09 volumioegm volumio[12023]: info: MYVOLUMIO: Adding device Dec 14 12:16:09 volumioegm volumio[12023]: info: MYVOLUMIO: Evaluating Server Dec 14 12:16:09 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:09 volumioegm volumio[12023]: info: Remote config written successfully Dec 14 12:16:09 volumioegm volumio[12023]: info: Starting Tunnel 1 Dec 14 12:16:09 volumioegm volumio[12023]: info: Starting Tunnel Connection Checker Dec 14 12:16:09 volumioegm volumio[12023]: info: Completed starting MyVolumio Plugin Dec 14 12:16:09 volumioegm volumio[12023]: info: MYVolumio Device enabled Dec 14 12:16:09 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 14 12:16:09 volumioegm volumio[12023]: info: MyVolumio status changed Dec 14 12:16:09 volumioegm volumio[12023]: info: Streaming services startup Dec 14 12:16:09 volumioegm volumio[12023]: info: Starting Streaming Daemon Dec 14 12:16:09 volumioegm volumio[12023]: info: Setting Geolocation for MyVolumio to eu6 Dec 14 12:16:09 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 12:16:09 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 12:16:09 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 12:16:09 volumioegm sudo[12840]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 14 12:16:09 volumioegm sudo[12840]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:16:09 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:09 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 14 12:16:09 volumioegm sudo[12840]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:09 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Dec 14 12:16:09 volumioegm volumio[12023]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Dec 14 12:16:09 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Dec 14 12:16:11 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Dec 14 12:16:11 volumioegm sudo[12844]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 14 12:16:11 volumioegm sudo[12844]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:16:11 volumioegm sudo[12844]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:11 volumioegm volumio[12023]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Dec 14 12:16:11 volumioegm volumio[12023]: info: MRS: MultiRoom plugin initialized Dec 14 12:16:11 volumioegm volumio[12023]: info: MRS: STOPPING SNAPCLIENT Dec 14 12:16:11 volumioegm volumio[12023]: info: MRS: Snap server stop Dec 14 12:16:11 volumioegm volumio[12023]: info: MRS: STOPPING volumioStreaming Dec 14 12:16:11 volumioegm sudo[12861]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 14 12:16:11 volumioegm sudo[12861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:16:11 volumioegm sudo[12863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Dec 14 12:16:11 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Dec 14 12:16:11 volumioegm sudo[12863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:16:11 volumioegm sudo[12866]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Dec 14 12:16:11 volumioegm sudo[12866]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:16:11 volumioegm sudo[12869]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 14 12:16:11 volumioegm sudo[12869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:16:11 volumioegm sudo[12869]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:11 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Dec 14 12:16:11 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Dec 14 12:16:11 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Dec 14 12:16:11 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Dec 14 12:16:11 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Dec 14 12:16:11 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Dec 14 12:16:11 volumioegm sudo[12866]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:11 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Dec 14 12:16:11 volumioegm sudo[12861]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:11 volumioegm sudo[12863]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:11 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Dec 14 12:16:11 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Dec 14 12:16:11 volumioegm volumio[12023]: info: Preparing to generate the ALSA configuration file Dec 14 12:16:11 volumioegm volumio[12023]: info: Updating MyVolumio device info Dec 14 12:16:11 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 12:16:11 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 12:16:11 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 12:16:11 volumioegm volumio[12023]: info: Setting Geolocation for MyVolumio to eu6 Dec 14 12:16:11 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 12:16:11 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 12:16:11 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 12:16:11 volumioegm volumio[12023]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 14 12:16:11 volumioegm volumio[12023]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 14 12:16:11 volumioegm volumio[12023]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 14 12:16:11 volumioegm volumio[12023]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Dec 14 12:16:11 volumioegm volumio[12023]: info: Reading ALSA contributions from plugins. Dec 14 12:16:11 volumioegm volumio[12023]: info: Successfully Added MyVolumio device Dec 14 12:16:11 volumioegm volumio[12023]: info: MRS: Removed streaming files Dec 14 12:16:11 volumioegm volumio[12023]: info: MRS: volumioStreaming STOPPED Dec 14 12:16:11 volumioegm volumio[12023]: info: MRS: SNAPSERVER STOPPED Dec 14 12:16:11 volumioegm volumio[12023]: info: MRS: SNAPCLIENT STOPPED Dec 14 12:16:11 volumioegm volumio[12023]: error: Cannot start Volumio Streaming Daemon Dec 14 12:16:11 volumioegm volumio[12023]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 14 12:16:11 volumioegm volumio[12023]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 14 12:16:11 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:11 volumioegm volumio[12023]: info: Asound.conf file unchanged, so no further update is needed Dec 14 12:16:11 volumioegm volumio[12023]: info: Output device has changed, restarting MPD Dec 14 12:16:11 volumioegm volumio[12023]: info: Output device has changed, restarting Shairport Sync Dec 14 12:16:11 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 12:16:11 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 12:16:11 volumioegm sudo[12876]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 12:16:11 volumioegm sudo[12876]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:16:11 volumioegm sudo[12876]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:11 volumioegm volumio[12023]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 12:16:11 volumioegm volumio[12023]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 14 12:16:11 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 12:16:11 volumioegm sudo[12879]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 12:16:11 volumioegm sudo[12879]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:16:11 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Dec 14 12:16:11 volumioegm volumio[12023]: ------------------------------------ BT MESSAGE: [FUNC] onStart Dec 14 12:16:11 volumioegm volumio[12023]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Dec 14 12:16:11 volumioegm volumio[12023]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Dec 14 12:16:11 volumioegm volumio[12023]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Dec 14 12:16:11 volumioegm systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 14 12:16:11 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Dec 14 12:16:11 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Dec 14 12:16:11 volumioegm volumio[12023]: info: MRS: Pushing multiroomSync output for this device Dec 14 12:16:11 volumioegm volumio[12023]: info: MRS: Pushing multiroomSync output Dec 14 12:16:11 volumioegm volumio[12023]: info: Adding audio output: Dec 14 12:16:11 volumioegm volumio[12023]: info: Adding audio output: Dec 14 12:16:11 volumioegm systemd[1]: mpd.service: Deactivated successfully. Dec 14 12:16:11 volumioegm systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 14 12:16:11 volumioegm systemd[1]: mpd.service: Consumed 4.113s CPU time. Dec 14 12:16:11 volumioegm systemd[1]: mpd.socket: Deactivated successfully. Dec 14 12:16:11 volumioegm systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 14 12:16:11 volumioegm systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 14 12:16:11 volumioegm bluetoothd[987]: Failed to set mode: Failed (0x03) Dec 14 12:16:11 volumioegm bluetoothd[987]: Path / reserved for Adv Monitor app :1.708 Dec 14 12:16:11 volumioegm volumio[12023]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 14 12:16:11 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 12:16:11 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Dec 14 12:16:11 volumioegm volumio[12023]: info: Adding METAVOLUMIO REST API Endpoints Dec 14 12:16:11 volumioegm volumio[12023]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Dec 14 12:16:11 volumioegm bluetoothd[987]: Adv Monitor app :1.708 disconnected from D-Bus Dec 14 12:16:11 volumioegm volumio[12023]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Dec 14 12:16:11 volumioegm volumio[12023]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Dec 14 12:16:11 volumioegm volumio[12023]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Dec 14 12:16:11 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Dec 14 12:16:11 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Dec 14 12:16:11 volumioegm volumio[12023]: info: Preparing CD Folders Dec 14 12:16:11 volumioegm volumio[12023]: info: Adding CD REST API Endpoints Dec 14 12:16:11 volumioegm volumio[12023]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Dec 14 12:16:11 volumioegm volumio[12023]: info: Starting UDEV Watcher for CD Dec 14 12:16:11 volumioegm volumio[12023]: info: Detecting CD presence with UDEV Dec 14 12:16:11 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Dec 14 12:16:11 volumioegm systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 14 12:16:12 volumioegm systemd[1]: Starting mpd.service - Music Player Daemon... Dec 14 12:16:12 volumioegm sudo[12894]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 14 12:16:12 volumioegm sudo[12894]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 12:16:12 volumioegm sudo[12894]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:13 volumioegm systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 6. Dec 14 12:16:14 volumioegm systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 12:16:14 volumioegm systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 12:16:14 volumioegm mpd[12898]: 2025-12-14T12:16:14 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 14 12:16:14 volumioegm systemd[1]: Started mpd.service - Music Player Daemon. Dec 14 12:16:14 volumioegm sudo[12879]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:17 volumioegm volumio[12023]: warn: [cd-plugin] cdspeedctl: device or media not ready Dec 14 12:16:17 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Dec 14 12:16:17 volumioegm volumio[12023]: info: Adding inputs REST Endpoints Dec 14 12:16:17 volumioegm volumio[12023]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Dec 14 12:16:17 volumioegm volumio[12023]: info: Scanning Audio Inputs Dec 14 12:16:17 volumioegm volumio[12023]: info: Checking against Known Cards name Dec 14 12:16:17 volumioegm volumio[12023]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 14 12:16:17 volumioegm volumio[12023]: info: [1765710977054] CoreMusicLibrary::Adding element Loopback Dec 14 12:16:17 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 12:16:17 volumioegm volumio[12023]: Cannot find translation for source FM/DAB Radio Dec 14 12:16:17 volumioegm volumio[12023]: Cannot find translation for source Loopback Dec 14 12:16:17 volumioegm volumio[12023]: info: Checking against Known Cards name Dec 14 12:16:17 volumioegm volumio[12023]: info: Checking against Known Cards name Dec 14 12:16:17 volumioegm volumio[12023]: info: Checking against Known Cards name Dec 14 12:16:17 volumioegm volumio[12023]: info: Checking against Known Cards name Dec 14 12:16:17 volumioegm volumio[12023]: info: Checking against Known Cards name Dec 14 12:16:17 volumioegm volumio[12023]: info: Checking against Known Cards name Dec 14 12:16:17 volumioegm volumio[12023]: info: Adding Server instance for streaming Dec 14 12:16:17 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Dec 14 12:16:17 volumioegm volumio[12023]: error: Hi Res Audio Failed Login: Missing Login Data Dec 14 12:16:17 volumioegm volumio[12023]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 14 12:16:17 volumioegm volumio[12023]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 14 12:16:17 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Dec 14 12:16:17 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Dec 14 12:16:17 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Dec 14 12:16:17 volumioegm volumio[12023]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Dec 14 12:16:17 volumioegm volumio[12023]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Dec 14 12:16:17 volumioegm sudo[12906]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 14 12:16:17 volumioegm volumio[12023]: info: QobuzConnect: Starting Qobuz Connect socket and service Dec 14 12:16:17 volumioegm sudo[12906]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:16:17 volumioegm sudo[12913]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 14 12:16:17 volumioegm sudo[12913]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:16:17 volumioegm sudo[12906]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:17 volumioegm volumio[12023]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Dec 14 12:16:17 volumioegm volumio[12023]: info: Adding TIDAL REST API Endpoints Dec 14 12:16:17 volumioegm volumio[12023]: info: Stopping AccessToken refresher cron for QOBUZ Dec 14 12:16:17 volumioegm sudo[12913]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:17 volumioegm sudo[12916]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 14 12:16:17 volumioegm sudo[12916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:16:17 volumioegm volumio[12023]: info: AccessToken refresher cron started for QOBUZ Dec 14 12:16:17 volumioegm volumio[12023]: info: Adding QOBUZ REST API Endpoints Dec 14 12:16:17 volumioegm volumio[12023]: info: Updating MyVolumio device info Dec 14 12:16:17 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 12:16:17 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 12:16:17 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 12:16:17 volumioegm systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 14 12:16:17 volumioegm systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 14 12:16:17 volumioegm volumio[12023]: info: MRS: Getting audio outputs on start Dec 14 12:16:17 volumioegm volumio[12023]: info: MRS: Requesting all other devices output Dec 14 12:16:17 volumioegm systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 12:16:17 volumioegm volumio[12023]: info: Successfully Updated MyVolumio device Dec 14 12:16:17 volumioegm systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 14 12:16:17 volumioegm sudo[12916]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:17 volumioegm volumio[12023]: info: Successfully Added MyVolumio device Dec 14 12:16:17 volumioegm volumio[12023]: ------------------------------------ BT MESSAGE: Failed to power on adapter: Dec 14 12:16:17 volumioegm volumio[12023]: info: MPD Permissions set Dec 14 12:16:17 volumioegm sudo[12922]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Dec 14 12:16:17 volumioegm sudo[12922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:16:17 volumioegm volumio[12023]: info: Executing endpoint qc_getconfig Dec 14 12:16:17 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 14 12:16:17 volumioegm sudo[12922]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:17 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:17 volumioegm volumio[12023]: info: Executing endpoint qc_getconfig Dec 14 12:16:17 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 14 12:16:17 volumioegm qobuz-connect[12920]: 20251214 12:16:17.599 [12920.12920] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 14 12:16:17 volumioegm volumio[12023]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Dec 14 12:16:17 volumioegm volumio[12023]: info: CoreCommandRouter::volumioStop Dec 14 12:16:17 volumioegm volumio[12023]: info: CoreStateMachine::stop Dec 14 12:16:17 volumioegm qobuz-connect[12920]: 20251214 12:16:17.607 [12920.12920] INFO VolumeManager: [0x1213348]: Setting new playback volume: 75 Dec 14 12:16:17 volumioegm volumio[12023]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 14 12:16:17 volumioegm qobuz-connect[12920]: 20251214 12:16:17.608 [12920.12920] INFO VolumeManager: [0x1213348]: Setting new mute state: 0 Dec 14 12:16:17 volumioegm qobuz-connect[12920]: 20251214 12:16:17.608 [12920.12920] INFO QobuzConnect: [0x1213d18]: Client initialized! Dec 14 12:16:17 volumioegm qobuz-connect[12920]: 20251214 12:16:17.609 [12920.12920] INFO SampleApp: Starting Avahi advertising, name: VolumioEgm, service name: _qobuz-connect._tcp Dec 14 12:16:17 volumioegm volumio[12023]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Dec 14 12:16:17 volumioegm volumio[12023]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 14 12:16:17 volumioegm qobuz-connect[12920]: 20251214 12:16:17.630 [12920.12920] INFO LocalConfigManager: [0x1212c28]: Starting Local Configuration server Dec 14 12:16:17 volumioegm qobuz-connect[12920]: 20251214 12:16:17.630 [12920.12920] INFO SampleApp: Starting Local configuration server Dec 14 12:16:17 volumioegm qobuz-connect[12920]: 20251214 12:16:17.631 [12920.12920] INFO SampleApp: Connected to UNIX socket client 0x11fd818 Dec 14 12:16:17 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 12:16:17 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 12:16:17 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 12:16:17 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 12:16:17 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 12:16:17 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 12:16:17 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 12:16:17 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 12:16:17 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 12:16:17 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 12:16:17 volumioegm volumio[12023]: error: updateQueue error: null Dec 14 12:16:17 volumioegm qobuz-connect[12920]: 20251214 12:16:17.749 [12920.12920] INFO SampleApp: Playback volume changed: 75 Dec 14 12:16:17 volumioegm volumio[12023]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 14 12:16:17 volumioegm volumio[12023]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 14 12:16:17 volumioegm volumio[12023]: info: Successfully Updated MyVolumio device Dec 14 12:16:17 volumioegm volumio[12023]: info: CoreCommandRouter::volumioGetState Dec 14 12:16:17 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:17 volumioegm volumio[12023]: info: Starting Shairport Sync Dec 14 12:16:17 volumioegm sudo[12945]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 14 12:16:17 volumioegm sudo[12945]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:16:17 volumioegm sudo[12947]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 12:16:17 volumioegm sudo[12947]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:16:17 volumioegm autossh[10201]: received signal to exit (15) Dec 14 12:16:17 volumioegm systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... Dec 14 12:16:17 volumioegm systemd[1]: sshtunnel.service: Deactivated successfully. Dec 14 12:16:17 volumioegm systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Dec 14 12:16:17 volumioegm systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 14 12:16:17 volumioegm sudo[12945]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:17 volumioegm autossh[12953]: port set to 0, monitoring disabled Dec 14 12:16:17 volumioegm autossh[12953]: starting ssh (count 1) Dec 14 12:16:17 volumioegm autossh[12953]: ssh child pid is 12957 Dec 14 12:16:17 volumioegm volumio[12023]: info: Remote SSH Started Dec 14 12:16:18 volumioegm systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 12:16:18 volumioegm systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 12:16:18 volumioegm systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 12:16:18 volumioegm systemd[1]: shairport-sync.service: Consumed 1.859s CPU time. Dec 14 12:16:18 volumioegm systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 12:16:18 volumioegm sudo[12947]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:18 volumioegm volumiossh-tunnel[12957]: Warning: Permanently added '[eu6.myvolumio.org]:2222' (RSA) to the list of known hosts. Dec 14 12:16:18 volumioegm volumio[12023]: info: Shairport-Sync Started Dec 14 12:16:18 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:18 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:18 volumioegm volumio[12023]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Dec 14 12:16:18 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:18 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:18 volumioegm volumio[12023]: info: Discovery: this is already registered, 50d6a6a7-e9e0-4840-8d94-b84ce0aac36b Dec 14 12:16:18 volumioegm volumio[12023]: info: Discovery: Found device VolumioEgm Dec 14 12:16:18 volumioegm volumio[12023]: info: CoreCommandRouter::volumioGetState Dec 14 12:16:18 volumioegm volumio[12023]: info: CoreCommandRouter::volumioGetState Dec 14 12:16:18 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:18 volumioegm volumio[12023]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.NXxQixdpxuVWhEiiRdSOKONaPHK2.4da3c32a1a60dac8fb28cfba0e713188.state.status' Dec 14 12:16:18 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:18 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:19 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:19 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:19 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:19 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:19 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:19 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:19 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:19 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:20 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:20 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:20 volumioegm volumio[12023]: info: TidalConnect service stoped! Dec 14 12:16:20 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:20 volumioegm volumio[12023]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 14 12:16:20 volumioegm volumio[12023]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 14 12:16:20 volumioegm sudo[13029]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 14 12:16:20 volumioegm sudo[13029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:16:20 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:20 volumioegm systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 14 12:16:20 volumioegm sudo[13029]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:20 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:20 volumioegm sudo[13039]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 12:16:20 volumioegm sudo[13039]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:16:20 volumioegm systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 14 12:16:20 volumioegm systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 14 12:16:20 volumioegm systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 14 12:16:20 volumioegm systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 14 12:16:20 volumioegm sudo[13039]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:20 volumioegm mpd_monitor.sh[13042]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 12:16:20 volumioegm volumio[12023]: info: Successfully started MPD Monitor Dec 14 12:16:20 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:20 volumioegm volumio[12023]: info: Executing endpoint tc_getconfig Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 14 12:16:20 volumioegm vtcs[13034]: STARTING TidalConnect services, version: 1.5.2.56 Dec 14 12:16:20 volumioegm vtcs[13034]: STARTED TidalConnect services. Dec 14 12:16:20 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:20 volumioegm volumio[12023]: info: Executing endpoint tc_connect Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 14 12:16:20 volumioegm volumio[12023]: info: Connecting to TidalConnect Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreCommandRouter::servicePushState Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreStateMachine::pushState Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreCommandRouter::volumioPushState Dec 14 12:16:20 volumioegm volumio[12023]: info: MRS: Pushing multiroomSync output update for this device Dec 14 12:16:20 volumioegm volumio[12023]: info: MRS: Pushing multiroomSync output Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreCommandRouter::volumioGetState Dec 14 12:16:20 volumioegm volumio[12023]: info: CorePlayQueue::getTrack 0 Dec 14 12:16:20 volumioegm volumio[12023]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 14 12:16:20 volumioegm volumio[12023]: verbose: CURRENT POSITION 0 Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreStateMachine::syncState stateService stop Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreStateMachine::syncState currentStatus stop Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreStateMachine::pushState Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreCommandRouter::volumioPushState Dec 14 12:16:20 volumioegm volumio[12023]: info: MRS: Pushing multiroomSync output update for this device Dec 14 12:16:20 volumioegm volumio[12023]: info: MRS: Pushing multiroomSync output Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreCommandRouter::volumioGetState Dec 14 12:16:20 volumioegm volumio[12023]: info: No code Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreStateMachine::pushState Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreCommandRouter::volumioPushState Dec 14 12:16:20 volumioegm volumio[12023]: info: MRS: Pushing multiroomSync output update for this device Dec 14 12:16:20 volumioegm volumio[12023]: info: MRS: Pushing multiroomSync output Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreCommandRouter::volumioGetState Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreCommandRouter::servicePushState Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreStateMachine::pushState Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreCommandRouter::volumioPushState Dec 14 12:16:20 volumioegm volumio[12023]: info: MRS: Pushing multiroomSync output update for this device Dec 14 12:16:20 volumioegm volumio[12023]: info: MRS: Pushing multiroomSync output Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreCommandRouter::volumioGetState Dec 14 12:16:20 volumioegm volumio[12023]: info: CorePlayQueue::getTrack 0 Dec 14 12:16:20 volumioegm volumio[12023]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 14 12:16:20 volumioegm volumio[12023]: verbose: CURRENT POSITION 0 Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreStateMachine::syncState stateService stop Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreStateMachine::syncState currentStatus stop Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreStateMachine::pushState Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreCommandRouter::volumioPushState Dec 14 12:16:20 volumioegm volumio[12023]: info: MRS: Pushing multiroomSync output update for this device Dec 14 12:16:20 volumioegm volumio[12023]: info: MRS: Pushing multiroomSync output Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreCommandRouter::volumioGetState Dec 14 12:16:20 volumioegm volumio[12023]: info: No code Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreStateMachine::pushState Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreCommandRouter::volumioPushState Dec 14 12:16:20 volumioegm volumio[12023]: info: MRS: Pushing multiroomSync output update for this device Dec 14 12:16:20 volumioegm volumio[12023]: info: MRS: Pushing multiroomSync output Dec 14 12:16:20 volumioegm volumio[12023]: info: CoreCommandRouter::volumioGetState Dec 14 12:16:20 volumioegm volumio[12023]: info: PeppyMeterBasic ---peppymeterbasic status Dec 14 12:16:20 volumioegm volumio[12023]: info: PeppyMeterBasic ---peppymeterbasic status Dec 14 12:16:20 volumioegm volumio[12023]: info: PeppyMeterBasic ---peppymeterbasic status undefined Dec 14 12:16:20 volumioegm volumio[12023]: info: PeppyMeterBasic ---peppymeterbasic status undefined Dec 14 12:16:20 volumioegm volumio[12023]: info: PeppyMeterBasic ---peppymeterbasic status undefined Dec 14 12:16:20 volumioegm volumio[12023]: info: PeppyMeterBasic ---peppymeterbasic status undefined Dec 14 12:16:20 volumioegm volumio[12023]: info: PeppySpectrum ---peppyspectrum status Dec 14 12:16:20 volumioegm volumio[12023]: info: PeppySpectrum ---peppyspectrum status Dec 14 12:16:20 volumioegm volumio[12023]: info: PeppySpectrum ---peppyspectrum status undefined Dec 14 12:16:20 volumioegm volumio[12023]: info: PeppySpectrum ---peppyspectrum status undefined Dec 14 12:16:20 volumioegm volumio[12023]: info: PeppySpectrum ---peppyspectrum status undefined Dec 14 12:16:20 volumioegm volumio[12023]: info: PeppySpectrum ---peppyspectrum status undefined Dec 14 12:16:21 volumioegm volumio[12023]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.NXxQixdpxuVWhEiiRdSOKONaPHK2.4da3c32a1a60dac8fb28cfba0e713188.state.status' Dec 14 12:16:21 volumioegm volumio[12023]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.NXxQixdpxuVWhEiiRdSOKONaPHK2.4da3c32a1a60dac8fb28cfba0e713188.state.status' Dec 14 12:16:21 volumioegm volumio[12023]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.NXxQixdpxuVWhEiiRdSOKONaPHK2.4da3c32a1a60dac8fb28cfba0e713188.state.status' Dec 14 12:16:21 volumioegm volumio[12023]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.NXxQixdpxuVWhEiiRdSOKONaPHK2.4da3c32a1a60dac8fb28cfba0e713188.state.status' Dec 14 12:16:21 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:21 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:21 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:21 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:21 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 14 12:16:21 volumioegm volumio[12023]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Dec 14 12:16:21 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 14 12:16:21 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 14 12:16:21 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:21 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:21 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:21 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:21 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:21 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:21 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:21 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:21 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:21 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:21 volumioegm volumio[12023]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 14 12:16:21 volumioegm volumio[12023]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 14 12:16:21 volumioegm volumio[12023]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 14 12:16:22 volumioegm volumio[12023]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 14 12:16:22 volumioegm volumio[12023]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 14 12:16:22 volumioegm volumio[12023]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 14 12:16:22 volumioegm volumio[12023]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 14 12:16:22 volumioegm volumio[12023]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 14 12:16:22 volumioegm volumio[12023]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 14 12:16:23 volumioegm volumio[12023]: info: CoreCommandRouter::volumioGetState Dec 14 12:16:23 volumioegm volumio[12023]: info: TidalConnect service started! Dec 14 12:16:23 volumioegm volumio[12023]: [Metrics] CommandRouter: 57s 344.48ms Dec 14 12:16:23 volumioegm volumio[12023]: info: CoreCommandRouter::volumiosetStartupVolume Dec 14 12:16:23 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 12:16:23 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 12:16:23 volumioegm volumio[12023]: info: CoreCommandRouter::Close All Modals sent Dec 14 12:16:23 volumioegm volumio[12023]: info: CoreCommandRouter::Close All Modals sent Dec 14 12:16:23 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:23 volumioegm volumio[12023]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 14 12:16:23 volumioegm volumio[12023]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 14 12:16:23 volumioegm volumio[12023]: info: camilladsp stopping service pid 12337... Dec 14 12:16:23 volumioegm volumio[12023]: grep: /proc/12337/cmdline: binary file matches Dec 14 12:16:23 volumioegm volumio[12023]: grep: /proc/12337/cmdline: binary file matches Dec 14 12:16:23 volumioegm volumio[12023]: info: camilladsp service terminated, instance 1 Dec 14 12:16:23 volumioegm volumio[12023]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 14 12:16:23 volumioegm volumio[12023]: info: camilladsp service started and running in background, instance 1 Dec 14 12:16:23 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:24 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:24 volumioegm volumio[12023]: error: touch_display: Connecting to the Xserver failed. Dec 14 12:16:24 volumioegm volumio[12023]: error: touch_display: Xserver unix domain socket cannot be determined. Dec 14 12:16:24 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 14 12:16:24 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 14 12:16:24 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 14 12:16:26 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 12:16:26 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 12:16:26 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 14 12:16:26 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 14 12:16:26 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 14 12:16:26 volumioegm volumio[12023]: info: CoreCommandRouter::volumioGetBrowseSources Dec 14 12:16:26 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 14 12:16:30 volumioegm volumio[12023]: info: BOOT COMPLETED Dec 14 12:16:33 volumioegm volumio[12023]: info: CoreCommandRouter::volumioVolatilePlay Dec 14 12:16:33 volumioegm volumio[12023]: ------------------------------------ BT MESSAGE: [FUNC] play Dec 14 12:16:33 volumioegm volumio[12023]: ------------------------------------ BT MESSAGE: sendPlay skipped: activePlayer not bound or invalid Dec 14 12:16:37 volumioegm volumio[12023]: info: CoreCommandRouter::volumioVolatilePlay Dec 14 12:16:37 volumioegm volumio[12023]: ------------------------------------ BT MESSAGE: [FUNC] play Dec 14 12:16:37 volumioegm volumio[12023]: ------------------------------------ BT MESSAGE: sendPlay skipped: activePlayer not bound or invalid Dec 14 12:16:41 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 14 12:16:41 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 14 12:16:41 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:41 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:41 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:41 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:41 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:41 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:41 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:41 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:42 volumioegm volumio[12023]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 14 12:16:42 volumioegm volumio[12023]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 14 12:16:42 volumioegm volumio[12023]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 14 12:16:42 volumioegm volumio[12023]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 14 12:16:42 volumioegm volumio[12023]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 14 12:16:42 volumioegm volumio[12023]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 14 12:16:43 volumioegm volumio[12023]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 14 12:16:43 volumioegm volumio[12023]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 14 12:16:46 volumioegm volumio[12023]: info: Enabling plugin spop Dec 14 12:16:46 volumioegm volumio[12023]: info: Loading plugin "spop"... Dec 14 12:16:48 volumioegm volumio[12023]: info: PLUGIN START: spop Dec 14 12:16:48 volumioegm volumio[12023]: info: Creating Spotify config file Dec 14 12:16:48 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 12:16:48 volumioegm volumio[12023]: info: Done. Dec 14 12:16:48 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:48 volumioegm volumio[12023]: info: Spotify config file written Dec 14 12:16:48 volumioegm volumio[12023]: info: No need to fix Spotify hosts Dec 14 12:16:48 volumioegm sudo[13198]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 14 12:16:48 volumioegm sudo[13198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:16:48 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:48 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:48 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:48 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:48 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:48 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:48 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:48 volumioegm systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 14 12:16:48 volumioegm systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 14 12:16:48 volumioegm volumio[12023]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 14 12:16:48 volumioegm systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 14 12:16:48 volumioegm go-librespot[13201]: go-librespot daemon starting... Dec 14 12:16:48 volumioegm sudo[13198]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:48 volumioegm volumio[12023]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 14 12:16:48 volumioegm volumio[12023]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 14 12:16:48 volumioegm volumio[12023]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 14 12:16:48 volumioegm go-librespot[13202]: time="2025-12-14T12:16:48+01:00" level=info msg="running go-librespot 0.4.0" Dec 14 12:16:48 volumioegm go-librespot[13202]: time="2025-12-14T12:16:48+01:00" level=debug msg="no app state found" Dec 14 12:16:48 volumioegm go-librespot[13202]: time="2025-12-14T12:16:48+01:00" level=debug msg="stored credentials not found" Dec 14 12:16:48 volumioegm go-librespot[13202]: time="2025-12-14T12:16:48+01:00" level=info msg="generated new device id: 0b22f914d74eb135d90efdf3562105286d1c2cfe" Dec 14 12:16:48 volumioegm go-librespot[13202]: time="2025-12-14T12:16:48+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 14 12:16:48 volumioegm volumio[12023]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 14 12:16:49 volumioegm volumio[12023]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 14 12:16:49 volumioegm volumio[12023]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 14 12:16:49 volumioegm go-librespot[13202]: time="2025-12-14T12:16:49+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Dec 14 12:16:49 volumioegm go-librespot[13202]: time="2025-12-14T12:16:49+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 14 12:16:49 volumioegm go-librespot[13202]: time="2025-12-14T12:16:49+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 14 12:16:49 volumioegm go-librespot[13202]: time="2025-12-14T12:16:49+01:00" level=info msg="zeroconf server listening on port 36425" Dec 14 12:16:52 volumioegm volumio[12023]: info: go-librespot daemon successfully initialized Dec 14 12:16:53 volumioegm volumio[12023]: info: Received OAUTH Data Dec 14 12:16:53 volumioegm volumio[12023]: info: Executing Spotify Oauth Login Dec 14 12:16:53 volumioegm volumio[12023]: info: Saving Spotify Refresh Token Dec 14 12:16:53 volumioegm volumio[12023]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 14 12:16:53 volumioegm volumio[12023]: SPOTIFY: BQDvkETRDOgsxIkWN6ZoXy5yoGvmGSDzyDaEhnuOZkUcwv7SsOY0iFA4wbdRl7mbRAEi392ZhkCc6r6-H7Csz1of6QPONLD6qt7aJ3b0gHXxaSHovMbpdJdyNGVohi3ahUG3_4ULX9oR6ahj_g6fC7ox9chbAYyCWEEQ96tpyvbsa_UpslkR8Eh9qmQMdTF24Y64FsC2a9J3U0av-xQy0SBbMPzWJxxXYMpUVXD-KpwR18n89cFI06Le5gS2n8UsLbUqZvrxx6IxJ94vXzE7kbEWB-ON8oBSnNIz_A Dec 14 12:16:53 volumioegm volumio[12023]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 14 12:16:53 volumioegm volumio[12023]: info: New Spotify access token = BQDvkETRDOgsxIkWN6ZoXy5yoGvmGSDzyDaEhnuOZkUcwv7SsOY0iFA4wbdRl7mbRAEi392ZhkCc6r6-H7Csz1of6QPONLD6qt7aJ3b0gHXxaSHovMbpdJdyNGVohi3ahUG3_4ULX9oR6ahj_g6fC7ox9chbAYyCWEEQ96tpyvbsa_UpslkR8Eh9qmQMdTF24Y64FsC2a9J3U0av-xQy0SBbMPzWJxxXYMpUVXD-KpwR18n89cFI06Le5gS2n8UsLbUqZvrxx6IxJ94vXzE7kbEWB-ON8oBSnNIz_A Dec 14 12:16:53 volumioegm volumio[12023]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 14 12:16:53 volumioegm sudo[13224]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 12:16:53 volumioegm sudo[13224]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:16:53 volumioegm sudo[13224]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:53 volumioegm sudo[13226]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 12:16:53 volumioegm sudo[13226]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:16:53 volumioegm sudo[13226]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:53 volumioegm volumio[12023]: verbose: New Socket.io Connection to 192.168.0.61 from 192.168.0.27 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 17 Dec 14 12:16:53 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 14 12:16:53 volumioegm volumio[12023]: info: CoreCommandRouter::volumioGetVisibleSources Dec 14 12:16:53 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 12:16:53 volumioegm volumio[12023]: info: CoreCommandRouter::volumioGetState Dec 14 12:16:53 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 14 12:16:53 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:53 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 14 12:16:53 volumioegm volumio[12023]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 14 12:16:53 volumioegm volumio[12023]: info: Received Get System Info Dec 14 12:16:53 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 12:16:53 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 12:16:53 volumioegm volumio[12023]: info: Discovery: Getting this device information Dec 14 12:16:53 volumioegm volumio[12023]: info: CoreCommandRouter::volumioGetState Dec 14 12:16:53 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 12:16:53 volumioegm volumio[12023]: info: CoreCommandRouter::volumioGetState Dec 14 12:16:53 volumioegm volumio[12023]: info: Listing playlists Dec 14 12:16:53 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 14 12:16:53 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 14 12:16:53 volumioegm volumio[12023]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:16:54 volumioegm volumio[12023]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 14 12:16:54 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 14 12:16:54 volumioegm volumio[12023]: SPOTIFY: User informations: {"country":"ES","display_name":"Eduard Graells","email":"egraells@egramedia.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/11158057081"},"followers":{"href":null,"total":2},"href":"https://api.spotify.com/v1/users/11158057081","id":"11158057081","images":[{"height":300,"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=10203629051229167&height=300&width=300&ext=1768286517&hash=AT8TtAnY_xpLOrjnUS8LNm7B","width":300},{"height":64,"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=10203629051229167&height=50&width=50&ext=1768286517&hash=AT-VS74O4POv5bzZQPNCU3vY","width":64}],"product":"premium","type":"user","uri":"spotify:user:11158057081"} Dec 14 12:16:54 volumioegm volumio[12023]: info: Creating Spotify config file Dec 14 12:16:54 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 12:16:54 volumioegm volumio[12023]: info: Spotify config file written Dec 14 12:16:54 volumioegm sudo[13231]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 14 12:16:54 volumioegm sudo[13231]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:16:54 volumioegm systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 14 12:16:54 volumioegm systemd[1]: go-librespot-daemon.service: Killing process 13207 (go-librespot) with signal SIGKILL. Dec 14 12:16:54 volumioegm systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 14 12:16:54 volumioegm systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 14 12:16:54 volumioegm systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 14 12:16:54 volumioegm sudo[13231]: pam_unix(sudo:session): session closed for user root Dec 14 12:16:54 volumioegm go-librespot[13233]: go-librespot daemon starting... Dec 14 12:16:54 volumioegm volumio[12023]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 14 12:16:54 volumioegm volumio[12023]: SPOTIFY: BQAfnM0cwBgeNiypJL6qdbgHVAa7rF4--VyAX5Fn2LT7HsyuWtuft0tyflHPa-9nerZKMGib63NIkpZBXlkaVw9-QpzT4GANR8gRkEDNYRfQfZ9Eb-_BXJHr_9wGIqVaiAggEhdtH2ya8CLJcVkrntnPrQTwvP6zkq2ALcbacgz7kSklLG92j4mpGdpxBY6xuRBnrSGkIOeteuhHlKUAU7LbLkVz_jFnvmLiGcFhT2af27oy-QL7fiV42ZyuIhV41nSa10q-NAS26yRU3YCNvdp-b2xJWwDcR4CmuA Dec 14 12:16:54 volumioegm volumio[12023]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 14 12:16:54 volumioegm volumio[12023]: info: New Spotify access token = BQAfnM0cwBgeNiypJL6qdbgHVAa7rF4--VyAX5Fn2LT7HsyuWtuft0tyflHPa-9nerZKMGib63NIkpZBXlkaVw9-QpzT4GANR8gRkEDNYRfQfZ9Eb-_BXJHr_9wGIqVaiAggEhdtH2ya8CLJcVkrntnPrQTwvP6zkq2ALcbacgz7kSklLG92j4mpGdpxBY6xuRBnrSGkIOeteuhHlKUAU7LbLkVz_jFnvmLiGcFhT2af27oy-QL7fiV42ZyuIhV41nSa10q-NAS26yRU3YCNvdp-b2xJWwDcR4CmuA Dec 14 12:16:54 volumioegm volumio[12023]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 14 12:16:54 volumioegm go-librespot[13234]: time="2025-12-14T12:16:54+01:00" level=info msg="running go-librespot 0.4.0" Dec 14 12:16:54 volumioegm go-librespot[13234]: time="2025-12-14T12:16:54+01:00" level=debug msg="app state loaded" Dec 14 12:16:54 volumioegm go-librespot[13234]: time="2025-12-14T12:16:54+01:00" level=debug msg="stored credentials not found" Dec 14 12:16:54 volumioegm go-librespot[13234]: time="2025-12-14T12:16:54+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 14 12:16:54 volumioegm volumio[12023]: SPOTIFY: User informations: {"country":"ES","display_name":"Eduard Graells","email":"egraells@egramedia.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/11158057081"},"followers":{"href":null,"total":2},"href":"https://api.spotify.com/v1/users/11158057081","id":"11158057081","images":[{"height":300,"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=10203629051229167&height=300&width=300&ext=1768286517&hash=AT8TtAnY_xpLOrjnUS8LNm7B","width":300},{"height":64,"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=10203629051229167&height=50&width=50&ext=1768286517&hash=AT-VS74O4POv5bzZQPNCU3vY","width":64}],"product":"premium","type":"user","uri":"spotify:user:11158057081"} Dec 14 12:16:54 volumioegm volumio[12023]: info: Spotify Successfully logged in Dec 14 12:16:54 volumioegm volumio[12023]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 14 12:16:54 volumioegm volumio[12023]: info: [1765711014597] CoreMusicLibrary::Adding element Spotify Dec 14 12:16:54 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 12:16:54 volumioegm volumio[12023]: Cannot find translation for source FM/DAB Radio Dec 14 12:16:54 volumioegm volumio[12023]: Cannot find translation for source Loopback Dec 14 12:16:54 volumioegm volumio[12023]: Cannot find translation for source Spotify Dec 14 12:16:54 volumioegm go-librespot[13234]: time="2025-12-14T12:16:54+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Dec 14 12:16:54 volumioegm go-librespot[13234]: time="2025-12-14T12:16:54+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 14 12:16:54 volumioegm go-librespot[13234]: time="2025-12-14T12:16:54+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 14 12:16:54 volumioegm go-librespot[13234]: time="2025-12-14T12:16:54+01:00" level=info msg="zeroconf server listening on port 45151" Dec 14 12:16:54 volumioegm go-librespot[13234]: time="2025-12-14T12:16:54+01:00" level=debug msg="obtained new client token: AADVQWqdocDtFY0vdSlJzVZmRIXgLswXfRX836nKxyoBouyVPYWtFo1AIOt/HrFvR2qHzLIOD7wDdUqt4199awEjrXCTPDDSfZY2QEHPrIJ9d7FHMmV76Ul0EIpBTF40pei9wFgGYGRvjkP5lcZJJ74VR0Myw4exebaWQYyKUIUX2vIym2R++iCNrGjfh5Mi2rc9j21aaXFgEQxEjDuw40OiV8ntHQkR1tZRmnUeeerwGk+N9a0ziWl5" Dec 14 12:16:54 volumioegm go-librespot[13234]: time="2025-12-14T12:16:54+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Dec 14 12:16:54 volumioegm go-librespot[13234]: time="2025-12-14T12:16:54+01:00" level=debug msg="completed keyexchange" Dec 14 12:16:54 volumioegm go-librespot[13234]: time="2025-12-14T12:16:54+01:00" level=debug msg="completed challenge" Dec 14 12:16:55 volumioegm go-librespot[13234]: time="2025-12-14T12:16:55+01:00" level=info msg="authenticated AP" username="11*******81" Dec 14 12:16:55 volumioegm go-librespot[13234]: time="2025-12-14T12:16:55+01:00" level=info msg="authenticated Login5" username="11*******81" Dec 14 12:16:55 volumioegm go-librespot[13234]: time="2025-12-14T12:16:55+01:00" level=debug msg="stored credentials" username="11*******81" Dec 14 12:16:55 volumioegm go-librespot[13234]: time="2025-12-14T12:16:55+01:00" level=debug msg="initializing zeroconf session" username="11*******81" Dec 14 12:16:55 volumioegm go-librespot[13234]: time="2025-12-14T12:16:55+01:00" level=debug msg="dealer connection opened" Dec 14 12:16:55 volumioegm go-librespot[13234]: time="2025-12-14T12:16:55+01:00" level=trace msg="starting accesspoint recv loop" Dec 14 12:16:55 volumioegm go-librespot[13234]: time="2025-12-14T12:16:55+01:00" level=trace msg="starting dealer recv loop" Dec 14 12:16:55 volumioegm go-librespot[13234]: time="2025-12-14T12:16:55+01:00" level=trace msg="received accesspoint ping" Dec 14 12:16:55 volumioegm go-librespot[13234]: time="2025-12-14T12:16:55+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 14 12:16:55 volumioegm go-librespot[13234]: time="2025-12-14T12:16:55+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 14 12:16:55 volumioegm go-librespot[13234]: time="2025-12-14T12:16:55+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 14 12:16:55 volumioegm go-librespot[13234]: time="2025-12-14T12:16:55+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 14 12:16:55 volumioegm go-librespot[13234]: time="2025-12-14T12:16:55+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 14 12:16:55 volumioegm go-librespot[13234]: time="2025-12-14T12:16:55+01:00" level=debug msg="received connection id: N2JkNTU5NTMtZjNj...RTEyMzQ5RDI0RA==" Dec 14 12:16:55 volumioegm go-librespot[13234]: time="2025-12-14T12:16:55+01:00" level=trace msg="received accesspoint pong ack" Dec 14 12:16:55 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 14 12:16:55 volumioegm go-librespot[13234]: time="2025-12-14T12:16:55+01:00" level=debug msg="put connect state because NEW_DEVICE" Dec 14 12:16:55 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 14 12:16:55 volumioegm volumio[12023]: info: Received Get System Info Dec 14 12:16:55 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 12:16:55 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 12:16:55 volumioegm volumio[12023]: info: Discovery: Getting this device information Dec 14 12:16:55 volumioegm volumio[12023]: info: CoreCommandRouter::volumioGetState Dec 14 12:16:55 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 12:16:55 volumioegm volumio[12023]: info: Initializing connection to go-librespot Websocket Dec 14 12:16:55 volumioegm go-librespot[13234]: time="2025-12-14T12:16:55+01:00" level=debug msg="new websocket client" Dec 14 12:16:55 volumioegm volumio[12023]: info: Connection to go-librespot Websocket established Dec 14 12:16:56 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 14 12:16:56 volumioegm volumio[12023]: info: Received Get System Info Dec 14 12:16:56 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 12:16:56 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 12:16:56 volumioegm volumio[12023]: info: Discovery: Getting this device information Dec 14 12:16:56 volumioegm volumio[12023]: info: CoreCommandRouter::volumioGetState Dec 14 12:16:56 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 12:16:57 volumioegm volumio[12023]: info: go-librespot daemon successfully initialized Dec 14 12:16:58 volumioegm volumio[12023]: info: Getting Spotify volume Dec 14 12:16:58 volumioegm volumio[12023]: info: Spotify volume: 100 Dec 14 12:16:58 volumioegm volumio[12023]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Dec 14 12:16:59 volumioegm volumio[12023]: info: CoreCommandRouter::volumioGetState Dec 14 12:16:59 volumioegm volumio[12023]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Dec 14 12:16:59 volumioegm volumio[12023]: SPOTIFY: SPOTIFY VOLUME 100 Dec 14 12:16:59 volumioegm volumio[12023]: SPOTIFY: VOLUMIO VOLUME 86 Dec 14 12:16:59 volumioegm volumio[12023]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 14 12:16:59 volumioegm volumio[12023]: info: Setting Spotify Volume from Volumio: 86 Dec 14 12:17:00 volumioegm volumio[12023]: info: Initializing connection to go-librespot Websocket Dec 14 12:17:00 volumioegm go-librespot[13234]: time="2025-12-14T12:17:00+01:00" level=debug msg="new websocket client" Dec 14 12:17:00 volumioegm volumio[12023]: info: Connection to go-librespot Websocket established Dec 14 12:17:00 volumioegm volumio[12023]: SPOTIFY: SETTING SPOTIFY VOLUME 86 Dec 14 12:17:00 volumioegm volumio[12023]: info: Sending Spotify command with payload to local API: /player/volume Dec 14 12:17:00 volumioegm go-librespot[13234]: time="2025-12-14T12:17:00+01:00" level=debug msg="update volume requested to 56360/65535" Dec 14 12:17:00 volumioegm go-librespot[13234]: time="2025-12-14T12:17:00+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 14 12:17:00 volumioegm go-librespot[13234]: time="2025-12-14T12:17:00+01:00" level=trace msg="emitting websocket event: volume" Dec 14 12:17:00 volumioegm volumio[12023]: SPOTIFY: received: {"type":"volume","data":{"value":86,"max":100}} Dec 14 12:17:00 volumioegm volumio[12023]: SPOTIFY: RECEIVED SPOTIFY VOLUME 86 Dec 14 12:17:00 volumioegm volumio[12023]: SPOTIFY: received: {"type":"volume","data":{"value":86,"max":100}} Dec 14 12:17:00 volumioegm volumio[12023]: SPOTIFY: RECEIVED SPOTIFY VOLUME 86 Dec 14 12:17:00 volumioegm volumio[12023]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Dec 14 12:17:00 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Dec 14 12:17:00 volumioegm volumio[12023]: info: Creating Spotify config file Dec 14 12:17:00 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 12:17:00 volumioegm volumio[12023]: info: Spotify config file written Dec 14 12:17:00 volumioegm sudo[13259]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 14 12:17:00 volumioegm sudo[13259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:17:01 volumioegm systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 14 12:17:01 volumioegm systemd[1]: go-librespot-daemon.service: Killing process 13238 (go-librespot) with signal SIGKILL. Dec 14 12:17:01 volumioegm systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 14 12:17:01 volumioegm systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 14 12:17:01 volumioegm volumio[12023]: info: Connection to go-librespot Websocket closed Dec 14 12:17:01 volumioegm volumio[12023]: info: Connection to go-librespot Websocket closed Dec 14 12:17:01 volumioegm systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 14 12:17:01 volumioegm go-librespot[13261]: go-librespot daemon starting... Dec 14 12:17:01 volumioegm sudo[13259]: pam_unix(sudo:session): session closed for user root Dec 14 12:17:01 volumioegm go-librespot[13262]: time="2025-12-14T12:17:01+01:00" level=info msg="running go-librespot 0.4.0" Dec 14 12:17:01 volumioegm go-librespot[13262]: time="2025-12-14T12:17:01+01:00" level=debug msg="app state loaded" Dec 14 12:17:01 volumioegm go-librespot[13262]: time="2025-12-14T12:17:01+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 14 12:17:01 volumioegm go-librespot[13262]: time="2025-12-14T12:17:01+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 14 12:17:01 volumioegm go-librespot[13262]: time="2025-12-14T12:17:01+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 14 12:17:01 volumioegm go-librespot[13262]: time="2025-12-14T12:17:01+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 14 12:17:01 volumioegm go-librespot[13262]: time="2025-12-14T12:17:01+01:00" level=info msg="zeroconf server listening on port 45863" Dec 14 12:17:01 volumioegm go-librespot[13262]: time="2025-12-14T12:17:01+01:00" level=debug msg="obtained new client token: AAAKqVK9A7hLRHEvAjSMquBxiSpPvXKhfJaY2zieMHR+p3ELmIcHmB7Zcj8jY7BdJ4gdp++UFQa/A+M/UdffZIhyMedAYScCSYlPkDAKQ3n66gJne7qMOIBTU1QFafJ5vQlL4xtdqQsrb9jXcDJRsIJHHxYtapy8OSOZThqZ0CGL1zK1D9aU87msXsCPveDyr3obv4fadNHjq0HXey2vipxHdqHj0yX3Zz6LVdCuXir/VQQh0i1iDOLT" Dec 14 12:17:01 volumioegm go-librespot[13262]: time="2025-12-14T12:17:01+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Dec 14 12:17:01 volumioegm go-librespot[13262]: time="2025-12-14T12:17:01+01:00" level=debug msg="completed keyexchange" Dec 14 12:17:01 volumioegm go-librespot[13262]: time="2025-12-14T12:17:01+01:00" level=debug msg="completed challenge" Dec 14 12:17:01 volumioegm go-librespot[13262]: time="2025-12-14T12:17:01+01:00" level=info msg="authenticated AP" username="11*******81" Dec 14 12:17:01 volumioegm go-librespot[13262]: time="2025-12-14T12:17:01+01:00" level=info msg="authenticated Login5" username="11*******81" Dec 14 12:17:01 volumioegm go-librespot[13262]: time="2025-12-14T12:17:01+01:00" level=debug msg="initializing zeroconf session" username="11*******81" Dec 14 12:17:01 volumioegm go-librespot[13262]: time="2025-12-14T12:17:01+01:00" level=debug msg="dealer connection opened" Dec 14 12:17:01 volumioegm go-librespot[13262]: time="2025-12-14T12:17:01+01:00" level=trace msg="starting accesspoint recv loop" Dec 14 12:17:01 volumioegm go-librespot[13262]: time="2025-12-14T12:17:01+01:00" level=trace msg="starting dealer recv loop" Dec 14 12:17:01 volumioegm go-librespot[13262]: time="2025-12-14T12:17:01+01:00" level=trace msg="received accesspoint ping" Dec 14 12:17:01 volumioegm go-librespot[13262]: time="2025-12-14T12:17:01+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 14 12:17:01 volumioegm go-librespot[13262]: time="2025-12-14T12:17:01+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 14 12:17:01 volumioegm go-librespot[13262]: time="2025-12-14T12:17:01+01:00" level=debug msg="received connection id: MzJhOTI2YTQtYjEx...RTgwMUZGMEU4NA==" Dec 14 12:17:02 volumioegm go-librespot[13262]: time="2025-12-14T12:17:02+01:00" level=debug msg="put connect state because NEW_DEVICE" Dec 14 12:17:02 volumioegm go-librespot[13262]: time="2025-12-14T12:17:02+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 14 12:17:02 volumioegm go-librespot[13262]: time="2025-12-14T12:17:02+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 14 12:17:02 volumioegm go-librespot[13262]: time="2025-12-14T12:17:02+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 14 12:17:02 volumioegm go-librespot[13262]: time="2025-12-14T12:17:02+01:00" level=trace msg="received accesspoint pong ack" Dec 14 12:17:03 volumioegm volumio[12023]: info: Getting Spotify volume Dec 14 12:17:03 volumioegm volumio[12023]: info: Spotify volume: 100 Dec 14 12:17:03 volumioegm volumio[12023]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Dec 14 12:17:03 volumioegm volumio[12023]: info: CoreCommandRouter::volumioGetState Dec 14 12:17:03 volumioegm volumio[12023]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Dec 14 12:17:03 volumioegm volumio[12023]: SPOTIFY: SPOTIFY VOLUME 100 Dec 14 12:17:03 volumioegm volumio[12023]: SPOTIFY: VOLUMIO VOLUME 86 Dec 14 12:17:03 volumioegm volumio[12023]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 14 12:17:03 volumioegm volumio[12023]: info: Setting Spotify Volume from Volumio: 86 Dec 14 12:17:03 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 14 12:17:04 volumioegm volumio[12023]: info: Initializing connection to go-librespot Websocket Dec 14 12:17:04 volumioegm volumio[12023]: info: Connection to go-librespot Websocket established Dec 14 12:17:04 volumioegm go-librespot[13262]: time="2025-12-14T12:17:04+01:00" level=debug msg="new websocket client" Dec 14 12:17:04 volumioegm volumio[12023]: info: go-librespot daemon successfully initialized Dec 14 12:17:05 volumioegm volumio[12023]: SPOTIFY: SETTING SPOTIFY VOLUME 86 Dec 14 12:17:05 volumioegm volumio[12023]: info: Sending Spotify command with payload to local API: /player/volume Dec 14 12:17:05 volumioegm go-librespot[13262]: time="2025-12-14T12:17:05+01:00" level=debug msg="update volume requested to 56360/65535" Dec 14 12:17:05 volumioegm go-librespot[13262]: time="2025-12-14T12:17:05+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 14 12:17:05 volumioegm go-librespot[13262]: time="2025-12-14T12:17:05+01:00" level=trace msg="emitting websocket event: volume" Dec 14 12:17:05 volumioegm volumio[12023]: SPOTIFY: received: {"type":"volume","data":{"value":86,"max":100}} Dec 14 12:17:05 volumioegm volumio[12023]: SPOTIFY: RECEIVED SPOTIFY VOLUME 86 Dec 14 12:17:07 volumioegm volumio[12023]: info: Getting Spotify volume Dec 14 12:17:07 volumioegm volumio[12023]: info: Spotify volume: 86 Dec 14 12:17:07 volumioegm volumio[12023]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Dec 14 12:17:07 volumioegm volumio[12023]: info: CoreCommandRouter::volumioGetState Dec 14 12:17:07 volumioegm volumio[12023]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Dec 14 12:17:07 volumioegm volumio[12023]: info: Initializing connection to go-librespot Websocket Dec 14 12:17:07 volumioegm go-librespot[13262]: time="2025-12-14T12:17:07+01:00" level=debug msg="new websocket client" Dec 14 12:17:07 volumioegm volumio[12023]: info: Connection to go-librespot Websocket established Dec 14 12:17:09 volumioegm volumio[12023]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Dec 14 12:17:09 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Dec 14 12:17:09 volumioegm volumio[12023]: info: Creating Spotify config file Dec 14 12:17:09 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 12:17:09 volumioegm volumio[12023]: info: Spotify config file written Dec 14 12:17:09 volumioegm sudo[13285]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 14 12:17:09 volumioegm sudo[13285]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:17:09 volumioegm systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 14 12:17:09 volumioegm systemd[1]: go-librespot-daemon.service: Killing process 13265 (go-librespot) with signal SIGKILL. Dec 14 12:17:09 volumioegm systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 14 12:17:09 volumioegm systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 14 12:17:09 volumioegm volumio[12023]: info: Connection to go-librespot Websocket closed Dec 14 12:17:09 volumioegm volumio[12023]: info: Connection to go-librespot Websocket closed Dec 14 12:17:09 volumioegm systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 14 12:17:09 volumioegm go-librespot[13287]: go-librespot daemon starting... Dec 14 12:17:09 volumioegm sudo[13285]: pam_unix(sudo:session): session closed for user root Dec 14 12:17:09 volumioegm go-librespot[13288]: time="2025-12-14T12:17:09+01:00" level=info msg="running go-librespot 0.4.0" Dec 14 12:17:09 volumioegm go-librespot[13288]: time="2025-12-14T12:17:09+01:00" level=debug msg="app state loaded" Dec 14 12:17:09 volumioegm go-librespot[13288]: time="2025-12-14T12:17:09+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 14 12:17:09 volumioegm go-librespot[13288]: time="2025-12-14T12:17:09+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 14 12:17:09 volumioegm go-librespot[13288]: time="2025-12-14T12:17:09+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 14 12:17:09 volumioegm go-librespot[13288]: time="2025-12-14T12:17:09+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 14 12:17:09 volumioegm go-librespot[13288]: time="2025-12-14T12:17:09+01:00" level=info msg="zeroconf server listening on port 44525" Dec 14 12:17:09 volumioegm go-librespot[13288]: time="2025-12-14T12:17:09+01:00" level=debug msg="obtained new client token: AACqkJSX2aIz6TAnuWltYaXcMNi7np1Jucljvpk19dGt/Amm/KJbacFza8jlUdTpwNZaJwxxPKZpfUitysVnWKqAOic2SYCrAqMUEK5dq7cUD70Pbx6HPCr3MlHD82GDefTrc5TrtEVrsb57F94z5Ivw+UFsfR7FovZNvi1/H9sxFtoZ+WIkDBy3uTWHEoY+8E+2+yXGB1IPd6h+X/fqH4UXJqs4CP0qzL31S45LWPoFG1OvUeM0jTwk" Dec 14 12:17:09 volumioegm go-librespot[13288]: time="2025-12-14T12:17:09+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Dec 14 12:17:09 volumioegm go-librespot[13288]: time="2025-12-14T12:17:09+01:00" level=debug msg="completed keyexchange" Dec 14 12:17:09 volumioegm go-librespot[13288]: time="2025-12-14T12:17:09+01:00" level=debug msg="completed challenge" Dec 14 12:17:09 volumioegm volumio[12023]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Dec 14 12:17:09 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Dec 14 12:17:09 volumioegm volumio[12023]: info: Creating Spotify config file Dec 14 12:17:09 volumioegm volumio[12023]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 12:17:09 volumioegm volumio[12023]: info: Spotify config file written Dec 14 12:17:10 volumioegm sudo[13296]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 14 12:17:10 volumioegm sudo[13296]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:17:10 volumioegm go-librespot[13288]: time="2025-12-14T12:17:10+01:00" level=info msg="authenticated AP" username="11*******81" Dec 14 12:17:10 volumioegm systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 14 12:17:10 volumioegm systemd[1]: go-librespot-daemon.service: Killing process 13294 (go-librespot) with signal SIGKILL. Dec 14 12:17:10 volumioegm systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 14 12:17:10 volumioegm systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 14 12:17:10 volumioegm volumio[12023]: info: Getting Spotify volume Dec 14 12:17:10 volumioegm volumio[12023]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 12:17:10 volumioegm systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 14 12:17:10 volumioegm go-librespot[13298]: go-librespot daemon starting... Dec 14 12:17:10 volumioegm sudo[13296]: pam_unix(sudo:session): session closed for user root Dec 14 12:17:10 volumioegm volumio[12023]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 14 12:17:10 volumioegm volumio[12023]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 14 12:17:10 volumioegm volumio[12023]: errno: -111, Dec 14 12:17:10 volumioegm volumio[12023]: code: 'ECONNREFUSED', Dec 14 12:17:10 volumioegm volumio[12023]: syscall: 'connect', Dec 14 12:17:10 volumioegm volumio[12023]: address: '127.0.0.1', Dec 14 12:17:10 volumioegm volumio[12023]: port: 9879, Dec 14 12:17:10 volumioegm volumio[12023]: response: undefined Dec 14 12:17:10 volumioegm volumio[12023]: } Dec 14 12:17:10 volumioegm volumio[12023]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 12:17:10 volumioegm go-librespot[13299]: time="2025-12-14T12:17:10+01:00" level=info msg="running go-librespot 0.4.0" Dec 14 12:17:10 volumioegm go-librespot[13299]: time="2025-12-14T12:17:10+01:00" level=debug msg="app state loaded" Dec 14 12:17:10 volumioegm go-librespot[13299]: time="2025-12-14T12:17:10+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 14 12:17:10 volumioegm go-librespot[13299]: time="2025-12-14T12:17:10+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 14 12:17:10 volumioegm go-librespot[13299]: time="2025-12-14T12:17:10+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 14 12:17:10 volumioegm go-librespot[13299]: time="2025-12-14T12:17:10+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 14 12:17:10 volumioegm go-librespot[13299]: time="2025-12-14T12:17:10+01:00" level=info msg="zeroconf server listening on port 34687" Dec 14 12:17:10 volumioegm go-librespot[13299]: time="2025-12-14T12:17:10+01:00" level=debug msg="obtained new client token: AADEuNr82FsBDIKJAvhHfsDjuSnQUMJjleICLL05VaEnAuIzWy4XmP/llZhgNoL6xqkPnwboA/z7RyE52jxj/8BBk7Gsig2rwOuuyf3D2dIhM9w5zfZTqJszch7zHXPXPs5pM2gs5roBkXzwsE12/ux8T71ukniKY8pX5kpIbavTDlPkW1/ffiA41/LVDMy//7818MaxhFMvXoEKbxJTDXgqNLHb47XUUZP9GOee7Hh36n8n96R7cjCm" Dec 14 12:17:10 volumioegm go-librespot[13299]: time="2025-12-14T12:17:10+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Dec 14 12:17:10 volumioegm go-librespot[13299]: time="2025-12-14T12:17:10+01:00" level=debug msg="completed keyexchange" Dec 14 12:17:10 volumioegm go-librespot[13299]: time="2025-12-14T12:17:10+01:00" level=debug msg="completed challenge" Dec 14 12:17:10 volumioegm go-librespot[13299]: time="2025-12-14T12:17:10+01:00" level=info msg="authenticated AP" username="11*******81" Dec 14 12:17:10 volumioegm go-librespot[13299]: time="2025-12-14T12:17:10+01:00" level=info msg="authenticated Login5" username="11*******81" Dec 14 12:17:10 volumioegm go-librespot[13299]: time="2025-12-14T12:17:10+01:00" level=debug msg="initializing zeroconf session" username="11*******81" Dec 14 12:17:10 volumioegm go-librespot[13299]: time="2025-12-14T12:17:10+01:00" level=debug msg="dealer connection opened" Dec 14 12:17:10 volumioegm go-librespot[13299]: time="2025-12-14T12:17:10+01:00" level=trace msg="starting accesspoint recv loop" Dec 14 12:17:10 volumioegm go-librespot[13299]: time="2025-12-14T12:17:10+01:00" level=trace msg="starting dealer recv loop" Dec 14 12:17:10 volumioegm go-librespot[13299]: time="2025-12-14T12:17:10+01:00" level=trace msg="received accesspoint ping" Dec 14 12:17:10 volumioegm go-librespot[13299]: time="2025-12-14T12:17:10+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 14 12:17:10 volumioegm go-librespot[13299]: time="2025-12-14T12:17:10+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 14 12:17:10 volumioegm go-librespot[13299]: time="2025-12-14T12:17:10+01:00" level=debug msg="received connection id: NDdhYTUyYTItNWQ4...REQ3NEVDNzY3RA==" Dec 14 12:17:10 volumioegm sudo[13320]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-14 12:16' Dec 14 12:17:10 volumioegm sudo[13320]: 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"