Jan 27 19:04:00 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:00.364 [2048.2048] INFO ProtocolHandler: [0x1872450]: Received SetVolume message, volume: 73 Jan 27 19:04:00 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:00.365 [2048.2048] INFO VolumeManager: [0x1872178]: Setting new playback volume: 73 Jan 27 19:04:00 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:00.515 [2048.2048] INFO SampleApp: Playback volume changed: 73 Jan 27 19:04:00 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:00.515 [2048.2048] INFO ProtocolHandler: [0x1872450]: Volume changed: 73 Jan 27 19:04:00 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:00.515 [2048.2048] INFO QConnectMessageSender: [0x18afbc0]: Sending VolumeChanged message Jan 27 19:04:00 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:00.515 [2048.2048] INFO QwspMessageSender: [0x18afbb0]: Sending Payload message Jan 27 19:04:00 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:00 volumio-sovrum volumio[1141]: info: QobuzConnect: Volume received from daemon: 73 Jan 27 19:04:00 volumio-sovrum volumio[1141]: info: VolumeController::SetAlsaVolume73 Jan 27 19:04:00 volumio-sovrum volumio[1141]: info: CoreStateMachine::pushState Jan 27 19:04:00 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 19:04:00 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioPushState Jan 27 19:04:00 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output update for this device Jan 27 19:04:00 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output Jan 27 19:04:00 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:00 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:00.572 [2048.2048] INFO VolumeManager: [0x1872178]: Setting new playback volume: 73 Jan 27 19:04:00 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:00.614 [2048.2048] INFO ProtocolHandler: [0x1872450]: Received SetVolume message, volume: 74 Jan 27 19:04:00 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:00.614 [2048.2048] INFO VolumeManager: [0x1872178]: Setting new playback volume: 74 Jan 27 19:04:00 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Jan 27 19:04:00 volumio-sovrum volumio[1141]: SPOTIFY: SPOTIFY VOLUME 72 Jan 27 19:04:00 volumio-sovrum volumio[1141]: SPOTIFY: VOLUMIO VOLUME 73 Jan 27 19:04:00 volumio-sovrum volumio[1141]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 19:04:00 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:00.764 [2048.2048] INFO SampleApp: Playback volume changed: 74 Jan 27 19:04:00 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:00.764 [2048.2048] INFO ProtocolHandler: [0x1872450]: Volume changed: 74 Jan 27 19:04:00 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:00.764 [2048.2048] INFO QConnectMessageSender: [0x18afbc0]: Sending VolumeChanged message Jan 27 19:04:00 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:00.764 [2048.2048] INFO QwspMessageSender: [0x18afbb0]: Sending Payload message Jan 27 19:04:00 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:00 volumio-sovrum volumio[1141]: info: QobuzConnect: Volume received from daemon: 74 Jan 27 19:04:00 volumio-sovrum volumio[1141]: info: VolumeController::SetAlsaVolume74 Jan 27 19:04:00 volumio-sovrum volumio[1141]: info: CoreStateMachine::pushState Jan 27 19:04:00 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 19:04:00 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioPushState Jan 27 19:04:00 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output update for this device Jan 27 19:04:00 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output Jan 27 19:04:00 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:00 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:00.824 [2048.2048] INFO VolumeManager: [0x1872178]: Setting new playback volume: 74 Jan 27 19:04:00 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Jan 27 19:04:00 volumio-sovrum volumio[1141]: SPOTIFY: SPOTIFY VOLUME 72 Jan 27 19:04:00 volumio-sovrum volumio[1141]: SPOTIFY: VOLUMIO VOLUME 74 Jan 27 19:04:00 volumio-sovrum volumio[1141]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 27 19:04:00 volumio-sovrum volumio[1141]: info: Setting Spotify Volume from Volumio: 74 Jan 27 19:04:02 volumio-sovrum volumio[1141]: SPOTIFY: SETTING SPOTIFY VOLUME 74 Jan 27 19:04:02 volumio-sovrum volumio[1141]: info: Sending Spotify command with payload to local API: /player/volume Jan 27 19:04:02 volumio-sovrum go-librespot[1680]: time="2026-01-27T19:04:02+01:00" level=debug msg="update volume requested to 48495/65535" Jan 27 19:04:02 volumio-sovrum go-librespot[1680]: time="2026-01-27T19:04:02+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Jan 27 19:04:02 volumio-sovrum go-librespot[1680]: time="2026-01-27T19:04:02+01:00" level=trace msg="emitting websocket event: volume" Jan 27 19:04:02 volumio-sovrum volumio[1141]: SPOTIFY: received: {"type":"volume","data":{"value":74,"max":100}} Jan 27 19:04:02 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED SPOTIFY VOLUME 74 Jan 27 19:04:04 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:04.754 [2048.2048] INFO ProtocolHandler: [0x1872450]: Need to send state update Jan 27 19:04:04 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:04.754 [2048.2048] INFO QConnectMessageSender: [0x18afbc0]: Sending StateUpdated message Jan 27 19:04:04 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:04.754 [2048.2048] INFO QwspMessageSender: [0x18afbb0]: Sending Payload message Jan 27 19:04:04 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:04 volumio-sovrum volumio[1141]: info: CoreCommandRouter::servicePushState Jan 27 19:04:04 volumio-sovrum volumio[1141]: info: CoreStateMachine::pushState Jan 27 19:04:04 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 19:04:04 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioPushState Jan 27 19:04:04 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output update for this device Jan 27 19:04:04 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output Jan 27 19:04:04 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:04 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Jan 27 19:04:12 volumio-sovrum volumio[1141]: info: VolumeController::SetAlsaVolume95 Jan 27 19:04:12 volumio-sovrum volumio[1141]: info: CoreStateMachine::pushState Jan 27 19:04:12 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 19:04:12 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioPushState Jan 27 19:04:12 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output update for this device Jan 27 19:04:12 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output Jan 27 19:04:12 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:12 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:12.718 [2048.2048] INFO VolumeManager: [0x1872178]: Setting new playback volume: 95 Jan 27 19:04:12 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Jan 27 19:04:12 volumio-sovrum volumio[1141]: SPOTIFY: SPOTIFY VOLUME 74 Jan 27 19:04:12 volumio-sovrum volumio[1141]: SPOTIFY: VOLUMIO VOLUME 95 Jan 27 19:04:12 volumio-sovrum volumio[1141]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 27 19:04:12 volumio-sovrum volumio[1141]: info: Setting Spotify Volume from Volumio: 95 Jan 27 19:04:12 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:12.868 [2048.2048] INFO SampleApp: Playback volume changed: 95 Jan 27 19:04:12 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:12.868 [2048.2048] INFO ProtocolHandler: [0x1872450]: Volume changed: 95 Jan 27 19:04:12 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:12.868 [2048.2048] INFO QConnectMessageSender: [0x18afbc0]: Sending VolumeChanged message Jan 27 19:04:12 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:12.868 [2048.2048] INFO QwspMessageSender: [0x18afbb0]: Sending Payload message Jan 27 19:04:12 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:12 volumio-sovrum volumio[1141]: info: QobuzConnect: Volume received from daemon: 95 Jan 27 19:04:12 volumio-sovrum volumio[1141]: info: VolumeController::SetAlsaVolume95 Jan 27 19:04:12 volumio-sovrum volumio[1141]: info: CoreStateMachine::pushState Jan 27 19:04:12 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioPushState Jan 27 19:04:12 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output update for this device Jan 27 19:04:12 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output Jan 27 19:04:12 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:12 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Jan 27 19:04:13 volumio-sovrum volumio[1141]: info: VolumeController::SetAlsaVolume69 Jan 27 19:04:13 volumio-sovrum volumio[1141]: info: CoreStateMachine::pushState Jan 27 19:04:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 19:04:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioPushState Jan 27 19:04:13 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output update for this device Jan 27 19:04:13 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output Jan 27 19:04:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:13 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:13.920 [2048.2048] INFO VolumeManager: [0x1872178]: Setting new playback volume: 69 Jan 27 19:04:13 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Jan 27 19:04:13 volumio-sovrum volumio[1141]: SPOTIFY: SPOTIFY VOLUME 95 Jan 27 19:04:13 volumio-sovrum volumio[1141]: SPOTIFY: VOLUMIO VOLUME 69 Jan 27 19:04:13 volumio-sovrum volumio[1141]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 27 19:04:13 volumio-sovrum volumio[1141]: info: Setting Spotify Volume from Volumio: 69 Jan 27 19:04:14 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:14.070 [2048.2048] INFO SampleApp: Playback volume changed: 69 Jan 27 19:04:14 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:14.070 [2048.2048] INFO ProtocolHandler: [0x1872450]: Volume changed: 69 Jan 27 19:04:14 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:14.070 [2048.2048] INFO QConnectMessageSender: [0x18afbc0]: Sending VolumeChanged message Jan 27 19:04:14 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:14.070 [2048.2048] INFO QwspMessageSender: [0x18afbb0]: Sending Payload message Jan 27 19:04:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:14 volumio-sovrum volumio[1141]: info: QobuzConnect: Volume received from daemon: 69 Jan 27 19:04:14 volumio-sovrum volumio[1141]: info: VolumeController::SetAlsaVolume69 Jan 27 19:04:14 volumio-sovrum volumio[1141]: info: CoreStateMachine::pushState Jan 27 19:04:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioPushState Jan 27 19:04:14 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output update for this device Jan 27 19:04:14 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output Jan 27 19:04:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:14 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Jan 27 19:04:14 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:14.754 [2048.2048] INFO ProtocolHandler: [0x1872450]: Need to send state update Jan 27 19:04:14 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:14.755 [2048.2048] INFO QConnectMessageSender: [0x18afbc0]: Sending StateUpdated message Jan 27 19:04:14 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:14.755 [2048.2048] INFO QwspMessageSender: [0x18afbb0]: Sending Payload message Jan 27 19:04:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::servicePushState Jan 27 19:04:14 volumio-sovrum volumio[1141]: info: CoreStateMachine::pushState Jan 27 19:04:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 19:04:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioPushState Jan 27 19:04:14 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output update for this device Jan 27 19:04:14 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output Jan 27 19:04:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:14 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Jan 27 19:04:15 volumio-sovrum volumio[1141]: SPOTIFY: SETTING SPOTIFY VOLUME 69 Jan 27 19:04:15 volumio-sovrum volumio[1141]: info: Sending Spotify command with payload to local API: /player/volume Jan 27 19:04:15 volumio-sovrum go-librespot[1680]: time="2026-01-27T19:04:15+01:00" level=debug msg="update volume requested to 45219/65535" Jan 27 19:04:15 volumio-sovrum go-librespot[1680]: time="2026-01-27T19:04:15+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Jan 27 19:04:15 volumio-sovrum go-librespot[1680]: time="2026-01-27T19:04:15+01:00" level=trace msg="emitting websocket event: volume" Jan 27 19:04:15 volumio-sovrum volumio[1141]: SPOTIFY: received: {"type":"volume","data":{"value":69,"max":100}} Jan 27 19:04:15 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED SPOTIFY VOLUME 69 Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioNext Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreStateMachine::next Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: QobuzConnect: Received next Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.080 [2048.2048] INFO QConnectMessageSender: [0x18afbc0]: Sending RendererAction message Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.080 [2048.2048] INFO QwspMessageSender: [0x18afbb0]: Sending Payload message Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.126 [2048.2048] INFO ProtocolHandler: [0x1872450]: Received SetState message: Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.126 [2048.2048] INFO ProtocolHandler: [0x1872450]: Playing state: Playing Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.126 [2048.2048] INFO ProtocolHandler: [0x1872450]: Playback position: 0 Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.126 [2048.2048] INFO ProtocolHandler: [0x1872450]: Queue version: 9.1 Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.126 [2048.2048] INFO ProtocolHandler: [0x1872450]: Current track: TID: 2364764, QID: 3, Context UUID: 81e3cfdc-522a-44cc-b9b7-f5f2693a2c76 Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.126 [2048.2048] INFO ProtocolHandler: [0x1872450]: Next track: TID: 7551309, QID: 4, Context UUID: 81e3cfdc-522a-44cc-b9b7-f5f2693a2c76 Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.126 [2048.2048] INFO MediaEngine: [0x1871d10]: Stopping playback, clearing tracks Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.127 [2048.2048] INFO MediaEngine: [0x1871d10]: Initiating playback Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.127 [2048.2048] INFO AudioRenderer: [0x1871f30]: Stopping audio renderer Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.127 [2048.2048] INFO SampleApp: Stopping playback Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.130 [2048.2048] INFO AudioStreamManager: [0x1871df8]: Clearing all streams Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.130 [2048.2048] INFO AudioStreamManager: [0x1871df8]: [Stream 3]: Disposing of audio stream Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.130 [2048.2048] INFO ContentFetcher: [0x1afd3e8]: Canceling download Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.144 [2048.2048] INFO SampleApp: Playback state changed: Stopped Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.144 [2048.2048] INFO StreamingEventReporter: [0x1872868]: Reporting streaming ended event Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.144 [2048.2048] INFO HttpDownloader: [0x18728c0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.145 [2048.2048] INFO ProtocolHandler: [0x1872450]: Need to send state update Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.145 [2048.2048] INFO QConnectMessageSender: [0x18afbc0]: Sending StateUpdated message Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.145 [2048.2048] INFO SampleApp: [Stream 3]: Audio stream has been disposed of Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.145 [2048.2048] INFO MediaEngine: [0x1871d10]: Setting current track: 2364764, initial offset: 0ms Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::servicePushState Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreStateMachine::pushState Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.145 [2048.2048] INFO AudioStreamManager: [0x1871df8]: Clearing all streams Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.145 [2048.2048] INFO AudioStreamManager: [0x1871df8]: New stream: 5 Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.145 [2048.2048] INFO HttpDownloader: [0x1a64eb8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=244cf6334734ba638ffc3edc88d2a669&request_ts=1769537059&track_id=2364764 Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.146 [2048.2048] INFO HttpDownloader: [0x1a64e90]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=2364764 Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.146 [2048.2048] INFO HttpDownloader: [0x1886ae0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=e793f7b90beccfddb8001dd4ff481a05&request_ts=1769537059&track_id=2364764 Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.146 [2048.2048] INFO AudioStreamManager: [0x1871df8]: [Stream 5]: Running audio stream Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.146 [2048.2048] INFO QwspMessageSender: [0x18afbb0]: Sending Payload message Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioPushState Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.150 [2048.2048] INFO MediaEngine: [0x1871d10]: Setting next track: 7551309 Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.150 [2048.2048] INFO AudioStreamManager: [0x1871df8]: New stream: 6 Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.150 [2048.2048] INFO HttpDownloader: [0x187b078]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=322b8fb55a7e614e4c4f11c051507dbd&request_ts=1769537059&track_id=7551309 Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.151 [2048.2048] INFO HttpDownloader: [0x1acd690]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=7551309 Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.151 [2048.2048] INFO HttpDownloader: [0x18ac8c8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=83b341c180ab7e48061ab013bff85f61&request_ts=1769537059&track_id=7551309 Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.154 [2048.2048] INFO MediaEngine: [0x1871d10]: Waiting for current stream to start before starting audio renderer Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output update for this device Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::servicePushState Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreStateMachine::pushState Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioPushState Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.246 [2048.2048] INFO AudioStreamManager: [0x1871df8]: [Stream 5]: Metadata became available: Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.246 [2048.2048] INFO AudioStreamManager: [0x1871df8]: Title: Famous Blue Raincoat (Live Nov 13, 2008; O2 Arena, London, England) Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.246 [2048.2048] INFO AudioStreamManager: [0x1871df8]: Artist: Leonard Cohen Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.246 [2048.2048] INFO AudioStreamManager: [0x1871df8]: Album: Songs From The Road (Live Nov 13, 2008; O2 Arena, London, England) Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.246 [2048.2048] INFO AudioStreamManager: [0x1871df8]: Album art URL: https://static.qobuz.com/images/covers/15/84/0884977728415_600.jpg Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output update for this device Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.263 [2048.2048] INFO AudioStreamManager: [0x1871df8]: [Stream 5]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=523329&eid=2364764&fmt=6&profile=raw&app_id=174516466&cid=3758685&etsp=1769540659&hmac=UfK7clpsALCNdghaJhxN-KPx5BQ Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.276 [2048.2048] INFO AudioStreamManager: [0x1871df8]: [Stream 6]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=523329&eid=7551309&fmt=6&profile=raw&app_id=174516466&cid=3758685&etsp=1769540659&hmac=ZendxhcqPsZfTBxKRDl7-CNQnAA Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.318 [2048.2048] INFO AudioStreamManager: [0x1871df8]: [Stream 6]: Metadata became available: Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.318 [2048.2048] INFO AudioStreamManager: [0x1871df8]: Title: The Chain (2004 Remaster) Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.318 [2048.2048] INFO AudioStreamManager: [0x1871df8]: Artist: Fleetwood Mac Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.318 [2048.2048] INFO AudioStreamManager: [0x1871df8]: Album: Rumours (2004 Remaster) Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.318 [2048.2048] INFO AudioStreamManager: [0x1871df8]: Album art URL: https://static.qobuz.com/images/covers/97/57/0603497925797_600.jpg Jan 27 19:04:19 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Jan 27 19:04:19 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.333 [2048.2048] INFO AudioStreamManager: [0x1871df8]: [Stream 6]: stream information have been fetched Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.385 [2048.2048] INFO AudioStreamManager: [0x1871df8]: [Stream 5]: stream information have been fetched Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.385 [2048.2048] INFO UrlAudioSource: [0x1a4e758]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=523329&eid=2364764&fmt=6&profile=raw&app_id=174516466&cid=3758685&etsp=1769540659&hmac=UfK7clpsALCNdghaJhxN-KPx5BQ Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.385 [2048.2048] INFO ContentFetcher: [0x1afda30]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=523329&eid=2364764&fmt=6&profile=raw&app_id=174516466&cid=3758685&etsp=1769540659&hmac=UfK7clpsALCNdghaJhxN-KPx5BQ, offset: 0 Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.389 [2048.2048] INFO AudioRenderer: [0x1871f30]: Starting audio renderer, initial playback state: Playing Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.389 [2048.2048] INFO SampleApp: [Stream 5]: New audio stream (starting from 0ms) Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.390 [2048.2048] INFO SampleApp: [Stream 5]: Stream metadata became available: Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.390 [2048.2048] INFO SampleApp: Title: Famous Blue Raincoat (Live Nov 13, 2008; O2 Arena, London, England) Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.390 [2048.2048] INFO SampleApp: Artist: Leonard Cohen Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.390 [2048.2048] INFO SampleApp: Album: Songs From The Road (Live Nov 13, 2008; O2 Arena, London, England) Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.390 [2048.2048] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/15/84/0884977728415_600.jpg Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: QobuzConnect: alread currrent service, send playback confirmation Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.524 [2048.2048] INFO SampleApp: Initiating playback, initial state: Playing Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: ALSA lib ./src/pcm_volumioswitch.c:257:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm softvolume has buffer size 88200 Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: ALSA lib ./src/pcm_volumioswitch.c:268:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm softvolume has buffer size 88200 Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.597 [2048.2048] INFO SampleApp: Playback state changed: Playing Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.597 [2048.2048] INFO StreamingEventReporter: [0x1872868]: Reporting streaming started event Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.597 [2048.2048] INFO ProtocolHandler: [0x1872450]: Need to send state update Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.597 [2048.2048] INFO QConnectMessageSender: [0x18afbc0]: Sending StateUpdated message Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.598 [2048.2048] INFO QwspMessageSender: [0x18afbb0]: Sending Payload message Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::servicePushState Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreStateMachine::pushState Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.598 [2048.2048] INFO QConnectMessageSender: [0x18afbc0]: Sending FileAudioQualityChanged message Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.599 [2048.2048] INFO QwspMessageSender: [0x18afbb0]: Sending Payload message Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.599 [2048.2048] INFO HttpDownloader: [0x18728c0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioPushState Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output update for this device Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::servicePushState Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreStateMachine::pushState Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioPushState Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output update for this device Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::servicePushState Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreStateMachine::pushState Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioPushState Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output update for this device Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::servicePushState Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreStateMachine::pushState Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioPushState Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output update for this device Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output Jan 27 19:04:19 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.864 [2048.2048] INFO UrlAudioSource: [0x1a4e758]: Content info extracted Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.864 [2048.2048] INFO UrlAudioSource: [0x1a4e758]: Size: 32777123 Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.876 [2048.3024] INFO AudioDecoder: [0x1ab0508]: Stream info: Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.876 [2048.3024] INFO AudioDecoder: [0x1ab0508]: Total samples: 14283108 Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.876 [2048.3024] INFO AudioDecoder: [0x1ab0508]: Sample rate: 44100 Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.876 [2048.3024] INFO AudioDecoder: [0x1ab0508]: Channel count: 2 Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.876 [2048.3024] INFO AudioDecoder: [0x1ab0508]: Bits per sample: 16 Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.876 [2048.3024] INFO AudioDecoder: [0x1ab0508]: Duration: 323880 Jan 27 19:04:19 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:19.877 [2048.2048] INFO UrlAudioSource: [0x1a4e758]: Audio properties have changed Jan 27 19:04:19 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Jan 27 19:04:19 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Jan 27 19:04:19 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Jan 27 19:04:19 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Jan 27 19:04:21 volumio-sovrum go-librespot[1680]: time="2026-01-27T19:04:21+01:00" level=trace msg="received accesspoint ping" Jan 27 19:04:21 volumio-sovrum go-librespot[1680]: time="2026-01-27T19:04:21+01:00" level=trace msg="received accesspoint pong ack" Jan 27 19:04:22 volumio-sovrum go-librespot[1680]: time="2026-01-27T19:04:22+01:00" level=trace msg="sent dealer ping" Jan 27 19:04:22 volumio-sovrum go-librespot[1680]: time="2026-01-27T19:04:22+01:00" level=trace msg="received dealer pong" Jan 27 19:04:29 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:29.597 [2048.2048] INFO ProtocolHandler: [0x1872450]: Need to send state update Jan 27 19:04:29 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:29.597 [2048.2048] INFO QConnectMessageSender: [0x18afbc0]: Sending StateUpdated message Jan 27 19:04:29 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:29.597 [2048.2048] INFO QwspMessageSender: [0x18afbb0]: Sending Payload message Jan 27 19:04:29 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:29 volumio-sovrum volumio[1141]: info: CoreCommandRouter::servicePushState Jan 27 19:04:29 volumio-sovrum volumio[1141]: info: CoreStateMachine::pushState Jan 27 19:04:29 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 19:04:29 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioPushState Jan 27 19:04:29 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output update for this device Jan 27 19:04:29 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output Jan 27 19:04:29 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:29 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Jan 27 19:04:39 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:39.597 [2048.2048] INFO ProtocolHandler: [0x1872450]: Need to send state update Jan 27 19:04:39 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:39.597 [2048.2048] INFO QConnectMessageSender: [0x18afbc0]: Sending StateUpdated message Jan 27 19:04:39 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:39.597 [2048.2048] INFO QwspMessageSender: [0x18afbb0]: Sending Payload message Jan 27 19:04:39 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:39 volumio-sovrum volumio[1141]: info: CoreCommandRouter::servicePushState Jan 27 19:04:39 volumio-sovrum volumio[1141]: info: CoreStateMachine::pushState Jan 27 19:04:39 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 19:04:39 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioPushState Jan 27 19:04:39 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output update for this device Jan 27 19:04:39 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output Jan 27 19:04:39 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:39 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Jan 27 19:04:44 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetQueue Jan 27 19:04:44 volumio-sovrum volumio[1141]: info: CoreStateMachine::getQueue Jan 27 19:04:44 volumio-sovrum volumio[1141]: info: CorePlayQueue::getQueue Jan 27 19:04:49 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:49.598 [2048.2048] INFO ProtocolHandler: [0x1872450]: Need to send state update Jan 27 19:04:49 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:49.598 [2048.2048] INFO QConnectMessageSender: [0x18afbc0]: Sending StateUpdated message Jan 27 19:04:49 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:49.598 [2048.2048] INFO QwspMessageSender: [0x18afbb0]: Sending Payload message Jan 27 19:04:49 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:49 volumio-sovrum volumio[1141]: info: CoreCommandRouter::servicePushState Jan 27 19:04:49 volumio-sovrum volumio[1141]: info: CoreStateMachine::pushState Jan 27 19:04:49 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 19:04:49 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioPushState Jan 27 19:04:49 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output update for this device Jan 27 19:04:49 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output Jan 27 19:04:49 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:49 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Jan 27 19:04:49 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 27 19:04:49 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 27 19:04:50 volumio-sovrum sudo[3075]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 27 19:04:50 volumio-sovrum sudo[3075]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:04:50 volumio-sovrum sudo[3075]: pam_unix(sudo:session): session closed for user root Jan 27 19:04:50 volumio-sovrum sudo[3077]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 27 19:04:50 volumio-sovrum sudo[3077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:04:50 volumio-sovrum sudo[3077]: pam_unix(sudo:session): session closed for user root Jan 27 19:04:50 volumio-sovrum volumio[1141]: verbose: New Socket.io Connection to 192.168.3.152 from 192.168.3.120 UA: Mozilla/5.0 (Linux; Android 16; Pixel 9 Pro Build/BP4A.260105.004.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.193 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jan 27 19:04:50 volumio-sovrum sudo[3081]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 27 19:04:50 volumio-sovrum sudo[3081]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:04:50 volumio-sovrum sudo[3083]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 27 19:04:50 volumio-sovrum sudo[3081]: pam_unix(sudo:session): session closed for user root Jan 27 19:04:50 volumio-sovrum sudo[3083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:04:50 volumio-sovrum sudo[3083]: pam_unix(sudo:session): session closed for user root Jan 27 19:04:50 volumio-sovrum volumio[1141]: verbose: New Socket.io Connection to 192.168.3.152 from 192.168.3.120 UA: Mozilla/5.0 (Linux; Android 16; Pixel 9 Pro Build/BP4A.260105.004.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.193 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jan 27 19:04:51 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 27 19:04:51 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetVisibleSources Jan 27 19:04:51 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 19:04:51 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:51 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 27 19:04:51 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 27 19:04:51 volumio-sovrum volumio[1141]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 27 19:04:51 volumio-sovrum volumio[1141]: info: Received Get System Info Jan 27 19:04:51 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 19:04:51 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 19:04:51 volumio-sovrum volumio[1141]: info: Discovery: Getting this device information Jan 27 19:04:51 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:51 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 19:04:51 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:51 volumio-sovrum volumio[1141]: info: Listing playlists Jan 27 19:04:51 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 27 19:04:51 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 27 19:04:51 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 27 19:04:52 volumio-sovrum go-librespot[1680]: time="2026-01-27T19:04:52+01:00" level=trace msg="sent dealer ping" Jan 27 19:04:52 volumio-sovrum go-librespot[1680]: time="2026-01-27T19:04:52+01:00" level=trace msg="received dealer pong" Jan 27 19:04:52 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 27 19:04:52 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 27 19:04:52 volumio-sovrum volumio[1141]: info: Received Get System Info Jan 27 19:04:52 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 19:04:52 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 19:04:52 volumio-sovrum volumio[1141]: info: Discovery: Getting this device information Jan 27 19:04:52 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:52 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 19:04:53 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 27 19:04:53 volumio-sovrum volumio[1141]: info: Received Get System Info Jan 27 19:04:53 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 19:04:53 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 19:04:53 volumio-sovrum volumio[1141]: info: Discovery: Getting this device information Jan 27 19:04:53 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:53 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 19:04:59 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:59.598 [2048.2048] INFO ProtocolHandler: [0x1872450]: Need to send state update Jan 27 19:04:59 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:59.598 [2048.2048] INFO QConnectMessageSender: [0x18afbc0]: Sending StateUpdated message Jan 27 19:04:59 volumio-sovrum qobuz-connect[2048]: 20260127 19:04:59.598 [2048.2048] INFO QwspMessageSender: [0x18afbb0]: Sending Payload message Jan 27 19:04:59 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:59 volumio-sovrum volumio[1141]: info: CoreCommandRouter::servicePushState Jan 27 19:04:59 volumio-sovrum volumio[1141]: info: CoreStateMachine::pushState Jan 27 19:04:59 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 19:04:59 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioPushState Jan 27 19:04:59 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output update for this device Jan 27 19:04:59 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output Jan 27 19:04:59 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:04:59 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Jan 27 19:05:00 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 27 19:05:04 volumio-sovrum volumio[1141]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 27 19:05:04 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 27 19:05:04 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 19:05:04 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 27 19:05:04 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 19:05:04 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 19:05:04 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 19:05:04 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 19:05:04 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 19:05:04 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 19:05:04 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 19:05:04 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 27 19:05:04 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 27 19:05:08 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 27 19:05:08 volumio-sovrum volumio[1141]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 27 19:05:08 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 27 19:05:08 volumio-sovrum volumio[1141]: info: Received Get System Version Jan 27 19:05:08 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 27 19:05:08 volumio-sovrum volumio[1141]: info: Received Get System Info Jan 27 19:05:08 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 19:05:08 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 19:05:08 volumio-sovrum volumio[1141]: info: Discovery: Getting this device information Jan 27 19:05:08 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:08 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 19:05:09 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:09.599 [2048.2048] INFO ProtocolHandler: [0x1872450]: Need to send state update Jan 27 19:05:09 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:09.599 [2048.2048] INFO QConnectMessageSender: [0x18afbc0]: Sending StateUpdated message Jan 27 19:05:09 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:09.599 [2048.2048] INFO QwspMessageSender: [0x18afbb0]: Sending Payload message Jan 27 19:05:09 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:09 volumio-sovrum volumio[1141]: info: CoreCommandRouter::servicePushState Jan 27 19:05:09 volumio-sovrum volumio[1141]: info: CoreStateMachine::pushState Jan 27 19:05:09 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 19:05:09 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioPushState Jan 27 19:05:09 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output update for this device Jan 27 19:05:09 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output Jan 27 19:05:09 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:09 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: Preparing to save Alsa Options, stopping services first Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioPause Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreStateMachine::pause Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreStateMachine::stPlaybackTimer Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreStateMachine::servicePause Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::servicePause Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: QobuzConnect: Received pause Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"Headphones"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.114 [2048.2048] INFO MediaEngine: [0x1871d10]: Pausing playback Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.115 [2048.2048] INFO AudioRenderer: [0x1871f30]: Pausing audio renderer Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.115 [2048.2048] INFO SampleApp: Pausing playback Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.115 [2048.2048] INFO SampleApp: Playback state changed: Paused Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.115 [2048.2048] INFO StreamingEventReporter: [0x1872868]: Reporting streaming ended event Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.115 [2048.2048] INFO HttpDownloader: [0x18728c0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.116 [2048.2048] INFO ProtocolHandler: [0x1872450]: Need to send state update Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.116 [2048.2048] INFO QConnectMessageSender: [0x18afbc0]: Sending StateUpdated message Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.116 [2048.2048] INFO QwspMessageSender: [0x18afbb0]: Sending Payload message Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: Found match in Cards Database: setting mixer PCM for card Headphones Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: QobuzConnect: setDeactiveState invoked Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: QobuzConnect: setDeactiveState(): relinquishing Volumio State Jan 27 19:05:10 volumio-sovrum volumio[1141]: verbose: UNSET VOLATILE: Service: qobuzconnect Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: QobuzConnect: onUnsetVolatile() called, relinquishing Volumio State to another service Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: QobuzConnect: Received stop Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.221 [2048.2048] INFO MediaEngine: [0x1871d10]: Stopping playback, keeping tracks Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.221 [2048.2048] INFO AudioRenderer: [0x1871f30]: Stopping audio renderer Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.221 [2048.2048] INFO SampleApp: Stopping playback Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.225 [2048.2048] INFO AudioStreamManager: [0x1871df8]: Clearing all streams Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.225 [2048.2048] INFO AudioStreamManager: [0x1871df8]: [Stream 5]: Disposing of audio stream Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.225 [2048.2048] INFO ContentFetcher: [0x1afda30]: Canceling download Jan 27 19:05:10 volumio-sovrum vtcs[2139]: [2026-01-27 19:05:10.225] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jan 27 19:05:10 volumio-sovrum vtcs[2139]: [2026-01-27 19:05:10.226] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.245 [2048.2048] INFO AudioStreamManager: [0x1871df8]: New stream: 7 Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.245 [2048.2048] INFO HttpDownloader: [0x187b078]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=b43547841851b43c717d7eb6361b3e82&request_ts=1769537110&track_id=2364764 Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.246 [2048.2048] INFO HttpDownloader: [0x1a800e8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=2364764 Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.246 [2048.2048] INFO HttpDownloader: [0x18a4c20]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=5abf07e91210d9df3d30d4cd074a8363&request_ts=1769537110&track_id=2364764 Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.246 [2048.2048] INFO AudioStreamManager: [0x1871df8]: [Stream 7]: Running audio stream Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.246 [2048.2048] INFO AudioStreamManager: [0x1871df8]: New stream: 8 Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.247 [2048.2048] INFO HttpDownloader: [0x1959750]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=90b4981a2e3113824bf2db657ab785a5&request_ts=1769537110&track_id=7551309 Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.247 [2048.2048] INFO HttpDownloader: [0x19da810]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=7551309 Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.247 [2048.2048] INFO HttpDownloader: [0x19066d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=0864d53c661d1c8377a7bbb2f70acf7b&request_ts=1769537110&track_id=7551309 Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.254 [2048.2048] INFO SampleApp: Playback state changed: Stopped Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.254 [2048.2048] INFO ProtocolHandler: [0x1872450]: Need to send state update Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.254 [2048.2048] INFO QConnectMessageSender: [0x18afbc0]: Sending StateUpdated message Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.255 [2048.2048] INFO SampleApp: [Stream 5]: Audio stream has been disposed of Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.255 [2048.2048] INFO QwspMessageSender: [0x18afbb0]: Sending Payload message Jan 27 19:05:10 volumio-sovrum sudo[3130]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 27 19:05:10 volumio-sovrum sudo[3130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: Updating Volume Controller Parameters: Device: 1 Name: softvolume Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: Disabling external Volume Control Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: Enable softmixer device for audio device undefined Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.370 [2048.2048] INFO AudioStreamManager: [0x1871df8]: [Stream 8]: Metadata became available: Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.370 [2048.2048] INFO AudioStreamManager: [0x1871df8]: Title: The Chain (2004 Remaster) Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.370 [2048.2048] INFO AudioStreamManager: [0x1871df8]: Artist: Fleetwood Mac Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.370 [2048.2048] INFO AudioStreamManager: [0x1871df8]: Album: Rumours (2004 Remaster) Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.370 [2048.2048] INFO AudioStreamManager: [0x1871df8]: Album art URL: https://static.qobuz.com/images/covers/97/57/0603497925797_600.jpg Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.375 [2048.2048] INFO AudioStreamManager: [0x1871df8]: [Stream 7]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=523329&eid=2364764&fmt=6&profile=raw&app_id=174516466&cid=3758685&etsp=1769540710&hmac=5bQSf2Xcedz6gfZYH1AoQ6HiyEE Jan 27 19:05:10 volumio-sovrum systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.380 [2048.2048] INFO AudioStreamManager: [0x1871df8]: [Stream 8]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=523329&eid=7551309&fmt=6&profile=raw&app_id=174516466&cid=3758685&etsp=1769540710&hmac=ONd-U4qv2zasmMiwVZbJVwYtUaQ Jan 27 19:05:10 volumio-sovrum systemd[1]: vtcs.service: Deactivated successfully. Jan 27 19:05:10 volumio-sovrum systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Jan 27 19:05:10 volumio-sovrum systemd[1]: vtcs.service: Consumed 1.112s CPU time. Jan 27 19:05:10 volumio-sovrum sudo[3137]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 27 19:05:10 volumio-sovrum sudo[3137]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.437 [2048.2048] INFO AudioStreamManager: [0x1871df8]: [Stream 7]: Metadata became available: Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.438 [2048.2048] INFO AudioStreamManager: [0x1871df8]: Title: Famous Blue Raincoat (Live Nov 13, 2008; O2 Arena, London, England) Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.438 [2048.2048] INFO AudioStreamManager: [0x1871df8]: Artist: Leonard Cohen Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.438 [2048.2048] INFO AudioStreamManager: [0x1871df8]: Album: Songs From The Road (Live Nov 13, 2008; O2 Arena, London, England) Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.438 [2048.2048] INFO AudioStreamManager: [0x1871df8]: Album art URL: https://static.qobuz.com/images/covers/15/84/0884977728415_600.jpg Jan 27 19:05:10 volumio-sovrum sudo[3130]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.458 [2048.2048] INFO AudioStreamManager: [0x1871df8]: [Stream 8]: stream information have been fetched Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrack 0 Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrack 0 Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrack 0 Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: QobuzConnect: playback_stop received, deferred solved Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrack 0 Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrack 0 Jan 27 19:05:10 volumio-sovrum sudo[3144]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 27 19:05:10 volumio-sovrum sudo[3144]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: Software Volume ALSA configuration written Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: Preparing to generate the ALSA configuration file Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.600 [2048.2048] INFO AudioStreamManager: [0x1871df8]: [Stream 7]: stream information have been fetched Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.600 [2048.2048] INFO UrlAudioSource: [0x1a07b88]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=523329&eid=2364764&fmt=6&profile=raw&app_id=174516466&cid=3758685&etsp=1769540710&hmac=5bQSf2Xcedz6gfZYH1AoQ6HiyEE Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.600 [2048.2048] INFO ContentFetcher: [0x19f1e38]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=523329&eid=2364764&fmt=6&profile=raw&app_id=174516466&cid=3758685&etsp=1769540710&hmac=5bQSf2Xcedz6gfZYH1AoQ6HiyEE, offset: 0 Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.601 [2048.2048] INFO SampleApp: [Stream 7]: New audio stream (starting from 0ms) Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.601 [2048.2048] INFO SampleApp: [Stream 7]: Stream metadata became available: Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.601 [2048.2048] INFO SampleApp: Title: Famous Blue Raincoat (Live Nov 13, 2008; O2 Arena, London, England) Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.601 [2048.2048] INFO SampleApp: Artist: Leonard Cohen Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.601 [2048.2048] INFO SampleApp: Album: Songs From The Road (Live Nov 13, 2008; O2 Arena, London, England) Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.602 [2048.2048] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/15/84/0884977728415_600.jpg Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: Reading ALSA contributions from plugins. Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: VolumeController:: Volume=86 Mute =false Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreStateMachine::pushState Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrack 0 Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioPushState Jan 27 19:05:10 volumio-sovrum sudo[3137]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output update for this device Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrack 0 Jan 27 19:05:10 volumio-sovrum sudo[3144]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:10 volumio-sovrum sudo[3153]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 27 19:05:10 volumio-sovrum sudo[3153]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 27 19:05:10 volumio-sovrum systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.856 [2048.2048] INFO ContentFetcher: [0x19f1e38]: Canceling download Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.871 [2048.2048] INFO CloudClient: [0x18722e8]: Disconnecting from the cloud Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.871 [2048.2048] INFO CloudClient: [0x18722e8]: Connection closed Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrack 0 Jan 27 19:05:10 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 27 19:05:10 volumio-sovrum volumio[1141]: SPOTIFY: SPOTIFY VOLUME 69 Jan 27 19:05:10 volumio-sovrum volumio[1141]: SPOTIFY: VOLUMIO VOLUME 86 Jan 27 19:05:10 volumio-sovrum volumio[1141]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: Setting Spotify Volume from Volumio: 86 Jan 27 19:05:10 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:10.905 [2048.2048] INFO SampleApp: Stopping Local configuration server Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: Preparing to save Alsa Options, stopping services first Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrack 0 Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioPause Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreStateMachine::pause Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"Headphones"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Jan 27 19:05:10 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: Found match in Cards Database: setting mixer PCM for card Headphones Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: QobuzConnect: setDeactiveState invoked Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrack 0 Jan 27 19:05:11 volumio-sovrum sudo[3168]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 27 19:05:11 volumio-sovrum sudo[3168]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: Updating Volume Controller Parameters: Device: 1 Name: softvolume Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: Disabling external Volume Control Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: Enable softmixer device for audio device undefined Jan 27 19:05:11 volumio-sovrum sudo[3175]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 27 19:05:11 volumio-sovrum sudo[3175]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:11 volumio-sovrum sudo[3168]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:11 volumio-sovrum sudo[3182]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 27 19:05:11 volumio-sovrum sudo[3182]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: Software Volume ALSA configuration written Jan 27 19:05:11 volumio-sovrum sudo[3182]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:11 volumio-sovrum sudo[3175]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 27 19:05:11 volumio-sovrum sudo[3187]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 27 19:05:11 volumio-sovrum sudo[3187]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:11 volumio-sovrum qobuz-connect[2048]: 20260127 19:05:11.585 [2048.2048] INFO SampleApp: shat down connection on UNIX socket Jan 27 19:05:11 volumio-sovrum systemd[1]: qobuz-connect.service: Deactivated successfully. Jan 27 19:05:11 volumio-sovrum systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Jan 27 19:05:11 volumio-sovrum systemd[1]: qobuz-connect.service: Consumed 14.268s CPU time. Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 27 19:05:11 volumio-sovrum systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jan 27 19:05:11 volumio-sovrum sudo[3153]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:11 volumio-sovrum systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Jan 27 19:05:11 volumio-sovrum systemd[1]: qobuz-connect.service: Deactivated successfully. Jan 27 19:05:11 volumio-sovrum systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Jan 27 19:05:11 volumio-sovrum systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: QobuzConnect: setDeactiveState invoked Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrack 0 Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: VolumeController:: Volume=86 Mute =false Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: CoreStateMachine::pushState Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrack 0 Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioPushState Jan 27 19:05:11 volumio-sovrum sudo[3187]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output update for this device Jan 27 19:05:11 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output Jan 27 19:05:12 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:12 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrack 0 Jan 27 19:05:12 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 27 19:05:12 volumio-sovrum volumio[1141]: info: Executing endpoint qc_getconfig Jan 27 19:05:12 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 27 19:05:12 volumio-sovrum qobuz-connect[3196]: 20260127 19:05:12.162 [3196.3196] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 27 19:05:12 volumio-sovrum volumio[1141]: info: Asound.conf file written Jan 27 19:05:12 volumio-sovrum qobuz-connect[3196]: 20260127 19:05:12.181 [3196.3196] INFO VolumeManager: [0xb53178]: Setting new playback volume: 75 Jan 27 19:05:12 volumio-sovrum qobuz-connect[3196]: 20260127 19:05:12.182 [3196.3196] INFO VolumeManager: [0xb53178]: Setting new mute state: 0 Jan 27 19:05:12 volumio-sovrum qobuz-connect[3196]: 20260127 19:05:12.182 [3196.3196] INFO QobuzConnect: [0xb53b48]: Client initialized! Jan 27 19:05:12 volumio-sovrum qobuz-connect[3196]: 20260127 19:05:12.182 [3196.3196] INFO SampleApp: Starting Avahi advertising, name: Volumio Sovrum, service name: _qobuz-connect._tcp Jan 27 19:05:12 volumio-sovrum qobuz-connect[3196]: 20260127 19:05:12.220 [3196.3196] INFO LocalConfigManager: [0xb52a58]: Starting Local Configuration server Jan 27 19:05:12 volumio-sovrum qobuz-connect[3196]: 20260127 19:05:12.220 [3196.3196] INFO SampleApp: Starting Local configuration server Jan 27 19:05:12 volumio-sovrum qobuz-connect[3196]: 20260127 19:05:12.221 [3196.3196] INFO SampleApp: Connected to UNIX socket client 0xb3d818 Jan 27 19:05:12 volumio-sovrum sudo[3202]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 27 19:05:12 volumio-sovrum sudo[3202]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:12 volumio-sovrum sudo[3202]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:12 volumio-sovrum volumio[1141]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jan 27 19:05:12 volumio-sovrum volumio[1141]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jan 27 19:05:12 volumio-sovrum volumio[1141]: info: Output device has changed, restarting MPD Jan 27 19:05:12 volumio-sovrum qobuz-connect[3196]: 20260127 19:05:12.312 [3196.3196] INFO SampleApp: Playback volume changed: 75 Jan 27 19:05:12 volumio-sovrum volumio[1141]: info: Output device has changed, restarting Shairport Sync Jan 27 19:05:12 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:12 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 19:05:12 volumio-sovrum sudo[3210]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 27 19:05:12 volumio-sovrum sudo[3210]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:12 volumio-sovrum sudo[3212]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 27 19:05:12 volumio-sovrum sudo[3212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:12 volumio-sovrum sudo[3210]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:12 volumio-sovrum volumio[1141]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 27 19:05:12 volumio-sovrum volumio[1141]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 27 19:05:12 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:12 volumio-sovrum volumio[1141]: info: QobuzConnect: setDeactiveState invoked Jan 27 19:05:12 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:12 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrack 0 Jan 27 19:05:12 volumio-sovrum volumio[1141]: info: Preparing to generate the ALSA configuration file Jan 27 19:05:12 volumio-sovrum systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 27 19:05:12 volumio-sovrum sudo[3222]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 27 19:05:12 volumio-sovrum sudo[3222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:12 volumio-sovrum volumio[1141]: info: Output device has changed, restarting MPD Jan 27 19:05:12 volumio-sovrum sudo[3225]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 27 19:05:12 volumio-sovrum sudo[3225]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:12 volumio-sovrum sudo[3225]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:12 volumio-sovrum volumio[1141]: info: Output device has changed, restarting Shairport Sync Jan 27 19:05:12 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:12 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 19:05:12 volumio-sovrum systemd[1]: mpd.service: Deactivated successfully. Jan 27 19:05:12 volumio-sovrum systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 27 19:05:12 volumio-sovrum systemd[1]: mpd.service: Consumed 5.892s CPU time. Jan 27 19:05:12 volumio-sovrum systemd[1]: mpd.socket: Deactivated successfully. Jan 27 19:05:12 volumio-sovrum systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 27 19:05:12 volumio-sovrum systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 27 19:05:12 volumio-sovrum sudo[3228]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 27 19:05:12 volumio-sovrum sudo[3228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:12 volumio-sovrum volumio[1141]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 27 19:05:12 volumio-sovrum volumio[1141]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 27 19:05:12 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:12 volumio-sovrum volumio[1141]: info: QobuzConnect: setDeactiveState invoked Jan 27 19:05:12 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:12 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrack 0 Jan 27 19:05:12 volumio-sovrum systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 27 19:05:12 volumio-sovrum systemd[1]: Starting mpd.service - Music Player Daemon... Jan 27 19:05:12 volumio-sovrum sudo[3238]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 27 19:05:12 volumio-sovrum sudo[3241]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 27 19:05:12 volumio-sovrum sudo[3241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:12 volumio-sovrum sudo[3238]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:12 volumio-sovrum sudo[3245]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 27 19:05:12 volumio-sovrum sudo[3245]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:12 volumio-sovrum sudo[3222]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:13 volumio-sovrum sudo[3254]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 27 19:05:13 volumio-sovrum sudo[3254]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 27 19:05:13 volumio-sovrum volumio[1141]: SPOTIFY: SETTING SPOTIFY VOLUME 86 Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: Sending Spotify command with payload to local API: /player/volume Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: Reading ALSA contributions from plugins. Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrack 0 Jan 27 19:05:13 volumio-sovrum go-librespot[1680]: time="2026-01-27T19:05:13+01:00" level=debug msg="update volume requested to 56360/65535" Jan 27 19:05:13 volumio-sovrum sudo[3261]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 27 19:05:13 volumio-sovrum systemd[1]: mpd.service: Deactivated successfully. Jan 27 19:05:13 volumio-sovrum sudo[3261]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:13 volumio-sovrum systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: MPD Permissions set Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: MPD Permissions set Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: Starting Shairport Sync Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: Starting Shairport Sync Jan 27 19:05:13 volumio-sovrum go-librespot[1680]: time="2026-01-27T19:05:13+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Jan 27 19:05:13 volumio-sovrum go-librespot[1680]: time="2026-01-27T19:05:13+01:00" level=trace msg="emitting websocket event: volume" Jan 27 19:05:13 volumio-sovrum volumio[1141]: SPOTIFY: received: {"type":"volume","data":{"value":86,"max":100}} Jan 27 19:05:13 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED SPOTIFY VOLUME 86 Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: Asound.conf file unchanged, so no further update is needed Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: Output device has changed, restarting MPD Jan 27 19:05:13 volumio-sovrum sudo[3277]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: Output device has changed, restarting Shairport Sync Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 19:05:13 volumio-sovrum sudo[3277]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:13 volumio-sovrum sudo[3279]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 27 19:05:13 volumio-sovrum sudo[3279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:13 volumio-sovrum sudo[3283]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 27 19:05:13 volumio-sovrum sudo[3283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:13 volumio-sovrum sudo[3281]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 27 19:05:13 volumio-sovrum sudo[3281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:13 volumio-sovrum sudo[3281]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:13 volumio-sovrum sudo[3245]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum sudo[3238]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: QobuzConnect: setDeactiveState invoked Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrack 0 Jan 27 19:05:13 volumio-sovrum sudo[3241]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:13 volumio-sovrum sudo[3254]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: Preparing to generate the ALSA configuration file Jan 27 19:05:13 volumio-sovrum sudo[3299]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 27 19:05:13 volumio-sovrum sudo[3299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:13 volumio-sovrum sudo[3298]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 27 19:05:13 volumio-sovrum sudo[3298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:13 volumio-sovrum sudo[3261]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:13 volumio-sovrum sudo[3303]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 27 19:05:13 volumio-sovrum sudo[3303]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:13 volumio-sovrum sudo[3306]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: Reading ALSA contributions from plugins. Jan 27 19:05:13 volumio-sovrum sudo[3306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: MPD Permissions set Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: TidalConnect service stoped! Jan 27 19:05:13 volumio-sovrum sudo[3310]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 27 19:05:13 volumio-sovrum sudo[3310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: TidalConnect service stoped! Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: Starting Shairport Sync Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 27 19:05:13 volumio-sovrum volumio[1141]: info: Asound.conf file written Jan 27 19:05:13 volumio-sovrum sudo[3328]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 27 19:05:13 volumio-sovrum sudo[3328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:13 volumio-sovrum sudo[3330]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 27 19:05:13 volumio-sovrum sudo[3330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:14 volumio-sovrum sudo[3334]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 27 19:05:14 volumio-sovrum sudo[3334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:14 volumio-sovrum sudo[3334]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:14 volumio-sovrum sudo[3310]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:14 volumio-sovrum sudo[3338]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 27 19:05:14 volumio-sovrum sudo[3338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:14 volumio-sovrum volumio[1141]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jan 27 19:05:14 volumio-sovrum volumio[1141]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: Output device has changed, restarting MPD Jan 27 19:05:14 volumio-sovrum systemd[1]: mpd.socket: Deactivated successfully. Jan 27 19:05:14 volumio-sovrum systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 27 19:05:14 volumio-sovrum systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: Output device has changed, restarting Shairport Sync Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 19:05:14 volumio-sovrum sudo[3343]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 27 19:05:14 volumio-sovrum sudo[3343]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:14 volumio-sovrum sudo[3343]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:14 volumio-sovrum sudo[3346]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 27 19:05:14 volumio-sovrum systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 27 19:05:14 volumio-sovrum sudo[3346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:14 volumio-sovrum systemd[1]: Starting mpd.service - Music Player Daemon... Jan 27 19:05:14 volumio-sovrum qobuz-connect[3196]: 20260127 19:05:14.258 [3196.3196] INFO SampleApp: Stopping Local configuration server Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: QobuzConnect: setDeactiveState invoked Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:14 volumio-sovrum systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrack 0 Jan 27 19:05:14 volumio-sovrum systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: Preparing to generate the ALSA configuration file Jan 27 19:05:14 volumio-sovrum systemd[1]: shairport-sync.service: Deactivated successfully. Jan 27 19:05:14 volumio-sovrum systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 27 19:05:14 volumio-sovrum systemd[1]: shairport-sync.service: Consumed 5.302s CPU time. Jan 27 19:05:14 volumio-sovrum sudo[3357]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 27 19:05:14 volumio-sovrum sudo[3357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: CoreStateMachine::getcurrentVolume Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioRetrievevolume Jan 27 19:05:14 volumio-sovrum sudo[3360]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 27 19:05:14 volumio-sovrum sudo[3360]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: Reading ALSA contributions from plugins. Jan 27 19:05:14 volumio-sovrum sudo[3366]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: MPD Permissions set Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: TidalConnect service stoped! Jan 27 19:05:14 volumio-sovrum sudo[3366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:14 volumio-sovrum sudo[3306]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:14 volumio-sovrum sudo[3299]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: TidalConnect service stoped! Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: VolumeController:: Volume=86 Mute =false Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: CoreStateMachine::pushState Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrack 0 Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioPushState Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output update for this device Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output Jan 27 19:05:14 volumio-sovrum sudo[3353]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:14 volumio-sovrum sudo[3353]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 27 19:05:14 volumio-sovrum sudo[3330]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrack 0 Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: CoreStateMachine::updateTrackBlock Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrackBlock Jan 27 19:05:14 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioRetrievevolume Jan 27 19:05:14 volumio-sovrum sudo[3353]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:14 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 27 19:05:15 volumio-sovrum sudo[3366]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:15 volumio-sovrum volumio[1141]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3 Jan 27 19:05:15 volumio-sovrum volumio[1141]: Job for vtcs.service canceled. Jan 27 19:05:15 volumio-sovrum volumio[1141]: {"cmd":"/usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3\nJob for vtcs.service canceled.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)","stderr":"Job for vtcs.service canceled.\n","stdout":""} Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 27 19:05:15 volumio-sovrum systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: Starting Shairport Sync Jan 27 19:05:15 volumio-sovrum sudo[3277]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:15 volumio-sovrum sudo[3389]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 27 19:05:15 volumio-sovrum sudo[3389]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:15 volumio-sovrum sudo[3328]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:15 volumio-sovrum sudo[3279]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:15 volumio-sovrum systemd[1]: mpd.service: Deactivated successfully. Jan 27 19:05:15 volumio-sovrum systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: CoreStateMachine::getcurrentVolume Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioRetrievevolume Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: Asound.conf file unchanged, so no further update is needed Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: Output device has changed, restarting MPD Jan 27 19:05:15 volumio-sovrum sudo[3394]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 27 19:05:15 volumio-sovrum sudo[3394]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:15 volumio-sovrum sudo[3411]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 27 19:05:15 volumio-sovrum sudo[3411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:15 volumio-sovrum sudo[3411]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: Output device has changed, restarting Shairport Sync Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 19:05:15 volumio-sovrum sudo[3357]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:15 volumio-sovrum sudo[3415]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 27 19:05:15 volumio-sovrum sudo[3415]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:15 volumio-sovrum sudo[3360]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: QobuzConnect: setDeactiveState invoked Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrack 0 Jan 27 19:05:15 volumio-sovrum systemd[1]: mpd.socket: Deactivated successfully. Jan 27 19:05:15 volumio-sovrum sudo[3426]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 27 19:05:15 volumio-sovrum systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 27 19:05:15 volumio-sovrum sudo[3426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:15 volumio-sovrum systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 27 19:05:15 volumio-sovrum sudo[3429]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 27 19:05:15 volumio-sovrum sudo[3429]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: VolumeController:: Volume=86 Mute =false Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: CoreStateMachine::pushState Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrack 0 Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioPushState Jan 27 19:05:15 volumio-sovrum sudo[3437]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 27 19:05:15 volumio-sovrum sudo[3437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output update for this device Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrack 0 Jan 27 19:05:15 volumio-sovrum systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 27 19:05:15 volumio-sovrum systemd[1]: Starting mpd.service - Music Player Daemon... Jan 27 19:05:15 volumio-sovrum systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: Shairport-Sync Started Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: Shairport-Sync Started Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: Shairport-Sync Started Jan 27 19:05:15 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 27 19:05:15 volumio-sovrum systemd[1]: shairport-sync.service: Deactivated successfully. Jan 27 19:05:15 volumio-sovrum systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 27 19:05:15 volumio-sovrum volumio[1141]: info: MPD Permissions set Jan 27 19:05:15 volumio-sovrum sudo[3442]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 27 19:05:15 volumio-sovrum sudo[3442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:16 volumio-sovrum volumio[1141]: info: VolumeController:: Volume=86 Mute =false Jan 27 19:05:16 volumio-sovrum volumio[1141]: info: CoreStateMachine::pushState Jan 27 19:05:16 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrack 0 Jan 27 19:05:16 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioPushState Jan 27 19:05:16 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output update for this device Jan 27 19:05:16 volumio-sovrum volumio[1141]: info: MRS: Pushing multiroomSync output Jan 27 19:05:16 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioGetState Jan 27 19:05:16 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrack 0 Jan 27 19:05:16 volumio-sovrum volumio[1141]: info: CoreStateMachine::updateTrackBlock Jan 27 19:05:16 volumio-sovrum volumio[1141]: info: CorePlayQueue::getTrackBlock Jan 27 19:05:16 volumio-sovrum volumio[1141]: info: CoreCommandRouter::volumioRetrievevolume Jan 27 19:05:16 volumio-sovrum sudo[3437]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:16 volumio-sovrum sudo[3426]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:16 volumio-sovrum sudo[3429]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:16 volumio-sovrum sudo[3445]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 27 19:05:16 volumio-sovrum sudo[3445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 19:05:16 volumio-sovrum systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 27 19:05:16 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:16 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:16 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:16 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:16 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:16 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:16 volumio-sovrum qobuz-connect[3196]: 20260127 19:05:16.227 [3196.3196] INFO SampleApp: shat down connection on UNIX socket Jan 27 19:05:16 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:16 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:16 volumio-sovrum sudo[3394]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:16 volumio-sovrum systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jan 27 19:05:16 volumio-sovrum sudo[3442]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:16 volumio-sovrum systemd[1]: qobuz-connect.service: Deactivated successfully. Jan 27 19:05:16 volumio-sovrum systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Jan 27 19:05:16 volumio-sovrum sudo[3439]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 27 19:05:16 volumio-sovrum sudo[3439]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 27 19:05:16 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 27 19:05:16 volumio-sovrum volumio[1141]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 27 19:05:16 volumio-sovrum sudo[3439]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:16 volumio-sovrum volumio[1141]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 27 19:05:16 volumio-sovrum systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jan 27 19:05:16 volumio-sovrum volumio[1141]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Jan 27 19:05:16 volumio-sovrum volumio[1141]: Job for vtcs.service canceled. Jan 27 19:05:16 volumio-sovrum volumio[1141]: {"cmd":"/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3\nJob for vtcs.service canceled.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)","stderr":"Job for vtcs.service canceled.\n","stdout":""} Jan 27 19:05:16 volumio-sovrum sudo[3389]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:16 volumio-sovrum sudo[3298]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:16 volumio-sovrum sudo[3303]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:16 volumio-sovrum sudo[3338]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:16 volumio-sovrum volumio[1141]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 19:05:16 volumio-sovrum volumio[1141]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Jan 27 19:05:16 volumio-sovrum volumio[1141]: Job for vtcs.service canceled. Jan 27 19:05:16 volumio-sovrum volumio[1141]: at ChildProcess.exithandler (node:child_process:421:12) Jan 27 19:05:16 volumio-sovrum volumio[1141]: at ChildProcess.emit (node:events:514:28) Jan 27 19:05:16 volumio-sovrum volumio[1141]: at maybeClose (node:internal/child_process:1105:16) Jan 27 19:05:16 volumio-sovrum volumio[1141]: at Socket. (node:internal/child_process:457:11) Jan 27 19:05:16 volumio-sovrum volumio[1141]: at Socket.emit (node:events:514:28) Jan 27 19:05:16 volumio-sovrum volumio[1141]: at Pipe. (node:net:337:12) { Jan 27 19:05:16 volumio-sovrum volumio[1141]: code: 1, Jan 27 19:05:16 volumio-sovrum volumio[1141]: killed: false, Jan 27 19:05:16 volumio-sovrum volumio[1141]: signal: null, Jan 27 19:05:16 volumio-sovrum volumio[1141]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Jan 27 19:05:16 volumio-sovrum volumio[1141]: stdout: '', Jan 27 19:05:16 volumio-sovrum volumio[1141]: stderr: 'Job for vtcs.service canceled.\n' Jan 27 19:05:16 volumio-sovrum volumio[1141]: } Jan 27 19:05:16 volumio-sovrum volumio[1141]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 19:05:16 volumio-sovrum systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Jan 27 19:05:16 volumio-sovrum systemd[1]: qobuz-connect.service: Deactivated successfully. Jan 27 19:05:16 volumio-sovrum systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Jan 27 19:05:17 volumio-sovrum systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jan 27 19:05:17 volumio-sovrum sudo[3445]: pam_unix(sudo:session): session closed for user root Jan 27 19:05:18 volumio-sovrum sudo[3503]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-27 19:04' Jan 27 19:05:18 volumio-sovrum sudo[3503]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"