-- Logs begin at Sun 2025-09-07 13:54:44 CEST, end at Sun 2025-09-07 14:07:08 CEST. -- Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.210 [3501.3501] INFO ProtocolHandler: [0x559669929b20]: Received SetState message: Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.210 [3501.3501] INFO ProtocolHandler: [0x559669929b20]: Playing state: Playing Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.210 [3501.3501] INFO ProtocolHandler: [0x559669929b20]: Playback position: 0 Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.210 [3501.3501] INFO ProtocolHandler: [0x559669929b20]: Queue version: 1.1 Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.210 [3501.3501] INFO ProtocolHandler: [0x559669929b20]: Current track: TID: 330845560, QID: 0, Context UUID: ddc4c15b-e1f2-4ef0-9559-2126c3b8a72c Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.210 [3501.3501] INFO ProtocolHandler: [0x559669929b20]: Next track: TID: 330845561, QID: 1, Context UUID: ddc4c15b-e1f2-4ef0-9559-2126c3b8a72c Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.210 [3501.3501] INFO MediaEngine: [0x559669928dd0]: Stopping playback, clearing tracks Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.210 [3501.3501] INFO MediaEngine: [0x559669928dd0]: Initiating playback Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.210 [3501.3501] INFO RendererActionAvailabilityManager: [0x5596699299e0]: Renderer action 'Next' is available Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.210 [3501.3501] INFO MediaEngine: [0x559669928dd0]: Setting current track: 330845560, initial offset: 0ms Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.210 [3501.3501] INFO AudioStreamManager: [0x559669928f70]: Clearing all streams Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.210 [3501.3501] INFO AudioStreamManager: [0x559669928f70]: New stream: 1 Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.210 [3501.3501] INFO HttpDownloader: [0x559669ac98b0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=c1baf025b17a21a2440d8775b198e98e&request_ts=1757246768&track_id=330845560 Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.210 [3501.3501] INFO HttpDownloader: [0x559669ac7a20]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=330845560 Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.210 [3501.3501] INFO HttpDownloader: [0x559669ac42c0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=06420e6ec892e4418fdf636b17041368&request_ts=1757246768&track_id=330845560 Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.211 [3501.3501] INFO AudioStreamManager: [0x559669928f70]: [Stream 1]: Running audio stream Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.211 [3501.3501] INFO MediaEngine: [0x559669928dd0]: Setting next track: 330845561 Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.211 [3501.3501] INFO AudioStreamManager: [0x559669928f70]: New stream: 2 Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.211 [3501.3501] INFO HttpDownloader: [0x559669ad8360]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=4eefaf147f087cde102e871e0c2c900c&request_ts=1757246768&track_id=330845561 Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.211 [3501.3501] INFO HttpDownloader: [0x559669ab5340]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=330845561 Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.211 [3501.3501] INFO HttpDownloader: [0x559669aa8fd0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=c65ea8bdff7019035f593678ff35de20&request_ts=1757246768&track_id=330845561 Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.213 [3501.3501] INFO MediaEngine: [0x559669928dd0]: Waiting for current stream to start before starting audio renderer Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.354 [3501.3501] INFO AudioStreamManager: [0x559669928f70]: [Stream 2]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1654027&eid=330845561&fmt=7&profile=raw&app_id=174516466&cid=2607699&etsp=1757250368&hmac=8c8rn_x3oTBxFaWAN1LcGHtFZtY Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.381 [3501.3501] INFO AudioStreamManager: [0x559669928f70]: [Stream 1]: stream information have been fetched Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.382 [3501.3501] INFO AudioStreamManager: [0x559669928f70]: [Stream 2]: Metadata became available: Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.382 [3501.3501] INFO AudioStreamManager: [0x559669928f70]: Title: Hotel TV Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.382 [3501.3501] INFO AudioStreamManager: [0x559669928f70]: Artist: Folk Bitch Trio Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.382 [3501.3501] INFO AudioStreamManager: [0x559669928f70]: Album: Now Would Be A Good Time Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.382 [3501.3501] INFO AudioStreamManager: [0x559669928f70]: Album art URL: https://static.qobuz.com/images/covers/1a/3r/pnvcpf6le3r1a_600.jpg Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.400 [3501.3501] INFO AudioStreamManager: [0x559669928f70]: [Stream 2]: stream information have been fetched Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.552 [3501.3501] INFO AudioStreamManager: [0x559669928f70]: [Stream 1]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1654027&eid=330845560&fmt=7&profile=raw&app_id=174516466&cid=2607699&etsp=1757250368&hmac=PbcdOSylvvIpGsZLnw63Znodp78 Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.552 [3501.3501] INFO UrlAudioSource: [0x559669a783f0]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=1654027&eid=330845560&fmt=7&profile=raw&app_id=174516466&cid=2607699&etsp=1757250368&hmac=PbcdOSylvvIpGsZLnw63Znodp78 Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.552 [3501.3501] INFO ContentFetcher: [0x559669b693a0]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=1654027&eid=330845560&fmt=7&profile=raw&app_id=174516466&cid=2607699&etsp=1757250368&hmac=PbcdOSylvvIpGsZLnw63Znodp78, offset: 0 Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.552 [3501.3501] INFO AudioRenderer: [0x5596699291d0]: Starting audio renderer, initial playback state: Playing Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.552 [3501.3501] INFO SampleApp: [Stream 1]: New audio stream (starting from 0ms) Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioGetState Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: QobuzConnect: setActiveState() Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioGetState Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: QobuzConnect: Set Qobuz connect default volume to 95 Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioGetState Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.558 [3501.3501] INFO VolumeManager: [0x5596699295d0]: Setting new playback volume: 95 Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioGetState Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: QobuzConnect: Stopping currently active service Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioStop Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreStateMachine::stop Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreStateMachine::stPlaybackTimer Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreStateMachine::updateTrackBlock Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CorePlayQueue::getTrackBlock Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreStateMachine::pushState Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CorePlayQueue::getTrack 4 Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioPushState Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: MRS: Pushing multiroomSync output update for this device Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: MRS: Pushing multiroomSync output Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioGetState Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CorePlayQueue::getTrack 4 Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreStateMachine::serviceStop Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CorePlayQueue::getTrack 4 Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreCommandRouter::serviceStop Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: [1757246768584] ControllerQobuz::stop Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: ControllerMpd::stop Sep 07 14:06:08 volumio-soggiorno volumio[876]: verbose: ControllerMpd::sendMpdCommand stop Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.611 [3501.3501] INFO AudioStreamManager: [0x559669928f70]: [Stream 1]: Metadata became available: Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.611 [3501.3501] INFO AudioStreamManager: [0x559669928f70]: Title: God's A Different Sword Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.611 [3501.3501] INFO AudioStreamManager: [0x559669928f70]: Artist: Folk Bitch Trio Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.611 [3501.3501] INFO AudioStreamManager: [0x559669928f70]: Album: Now Would Be A Good Time Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.611 [3501.3501] INFO AudioStreamManager: [0x559669928f70]: Album art URL: https://static.qobuz.com/images/covers/1a/3r/pnvcpf6le3r1a_600.jpg Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.611 [3501.3501] INFO SampleApp: [Stream 1]: Stream metadata became available: Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.611 [3501.3501] INFO SampleApp: Title: God's A Different Sword Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.611 [3501.3501] INFO SampleApp: Artist: Folk Bitch Trio Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.611 [3501.3501] INFO SampleApp: Album: Now Would Be A Good Time Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.611 [3501.3501] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/1a/3r/pnvcpf6le3r1a_600.jpg Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioGetState Sep 07 14:06:08 volumio-soggiorno volumio[876]: STREAMING PROXY: Client dropped request, destroying Sep 07 14:06:08 volumio-soggiorno volumio[876]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.708 [3501.3501] INFO SampleApp: Playback volume changed: 95 Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.708 [3501.3501] INFO ProtocolHandler: [0x559669929b20]: Volume changed: 95 Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.708 [3501.3501] INFO QConnectMessageSender: [0x559669ae1ff0]: Sending VolumeChanged message Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.709 [3501.3501] INFO QwspMessageSender: [0x559669ae3790]: Sending Payload message Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioGetState Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: Sep 07 14:06:08 volumio-soggiorno volumio[876]: ---------------------------- MPD announces state update: player Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: sendMpdCommand stop took 150 milliseconds Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: ControllerMpd::getState Sep 07 14:06:08 volumio-soggiorno volumio[876]: verbose: ControllerMpd::sendMpdCommand status Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreCommandRouter::servicePushState Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreStateMachine::pushState Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioPushState Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: MRS: Pushing multiroomSync output update for this device Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: MRS: Pushing multiroomSync output Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioGetState Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CorePlayQueue::getTrack 4 Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received qobuzconnect Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: QobuzConnect: setActiveState() completed, send playback confirmation Sep 07 14:06:08 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:08.767 [3501.3501] INFO SampleApp: Initiating playback, initial state: Playing Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: sendMpdCommand status took 35 milliseconds Sep 07 14:06:08 volumio-soggiorno volumio[876]: verbose: ControllerMpd::parseState Sep 07 14:06:08 volumio-soggiorno volumio[876]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 07 14:06:08 volumio-soggiorno volumio[876]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: sendMpdCommand playlistinfo took 18 milliseconds Sep 07 14:06:08 volumio-soggiorno volumio[876]: verbose: ControllerMpd::parseTrackInfo Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: ControllerMpd::pushState Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreCommandRouter::servicePushState Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreStateMachine::pushState Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioPushState Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: MRS: Pushing multiroomSync output update for this device Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: MRS: Pushing multiroomSync output Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioGetState Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: CorePlayQueue::getTrack 4 Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Sep 07 14:06:08 volumio-soggiorno volumio[876]: info: ------------------------------ 100ms Sep 07 14:06:08 volumio-soggiorno volumio[876]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.cYXEQ5XSoGNIAhXSTMgA9fVIS6D2.6d78b7105a87d1148ffbc41af559d0f9.state.status' Sep 07 14:06:08 volumio-soggiorno volumio[876]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Sep 07 14:06:09 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:09.222 [3501.3501] INFO SampleApp: Playback state changed: Playing Sep 07 14:06:09 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:09.222 [3501.3501] INFO StreamingEventReporter: [0x55966992a2c0]: Reporting streaming started event Sep 07 14:06:09 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:09.222 [3501.3501] INFO HttpDownloader: [0x55966992a350]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Sep 07 14:06:09 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:09.222 [3501.3501] INFO ProtocolHandler: [0x559669929b20]: Need to send state update Sep 07 14:06:09 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:09.222 [3501.3501] INFO QConnectMessageSender: [0x559669ae1ff0]: Sending StateUpdated message Sep 07 14:06:09 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:09.222 [3501.3501] INFO QwspMessageSender: [0x559669ae3790]: Sending Payload message Sep 07 14:06:09 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:09.223 [3501.3501] INFO QConnectMessageSender: [0x559669ae1ff0]: Sending FileAudioQualityChanged message Sep 07 14:06:09 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:09.223 [3501.3501] INFO UrlAudioSource: [0x559669a783f0]: Content info extracted Sep 07 14:06:09 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:09.223 [3501.3501] INFO UrlAudioSource: [0x559669a783f0]: Size: 75563224 Sep 07 14:06:09 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:09.223 [3501.3501] INFO QwspMessageSender: [0x559669ae3790]: Sending Payload message Sep 07 14:06:09 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:09.224 [3501.3501] INFO QConnectMessageSender: [0x559669ae1ff0]: Sending DeviceAudioQualityChanged message Sep 07 14:06:09 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:09.224 [3501.3501] INFO QwspMessageSender: [0x559669ae3790]: Sending Payload message Sep 07 14:06:09 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:09.225 [3501.4697] INFO AudioDecoder: [0x559669c8fd60]: Stream info: Sep 07 14:06:09 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:09.225 [3501.4697] INFO AudioDecoder: [0x559669c8fd60]: Total samples: 19128830 Sep 07 14:06:09 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:09.225 [3501.4697] INFO AudioDecoder: [0x559669c8fd60]: Sample rate: 96000 Sep 07 14:06:09 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:09.225 [3501.4697] INFO AudioDecoder: [0x559669c8fd60]: Channel count: 2 Sep 07 14:06:09 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:09.225 [3501.4697] INFO AudioDecoder: [0x559669c8fd60]: Bits per sample: 24 Sep 07 14:06:09 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:09.225 [3501.4697] INFO AudioDecoder: [0x559669c8fd60]: Duration: 199258 Sep 07 14:06:09 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:09.225 [3501.3501] INFO UrlAudioSource: [0x559669a783f0]: Audio properties have changed Sep 07 14:06:09 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioGetState Sep 07 14:06:09 volumio-soggiorno volumio[876]: info: CoreCommandRouter::servicePushState Sep 07 14:06:09 volumio-soggiorno volumio[876]: info: CoreStateMachine::pushState Sep 07 14:06:09 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:06:09 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioPushState Sep 07 14:06:09 volumio-soggiorno volumio[876]: info: MRS: Pushing multiroomSync output update for this device Sep 07 14:06:09 volumio-soggiorno volumio[876]: info: MRS: Pushing multiroomSync output Sep 07 14:06:09 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioGetState Sep 07 14:06:09 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:09.245 [3501.3501] INFO VolumeManager: [0x5596699295d0]: Setting new playback volume: 95 Sep 07 14:06:09 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:09.245 [3501.3501] INFO VolumeManager: [0x5596699295d0]: Setting new mute state: 0 Sep 07 14:06:09 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioGetState Sep 07 14:06:09 volumio-soggiorno volumio[876]: info: CoreCommandRouter::servicePushState Sep 07 14:06:09 volumio-soggiorno volumio[876]: info: CoreStateMachine::pushState Sep 07 14:06:09 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:06:09 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioPushState Sep 07 14:06:09 volumio-soggiorno volumio[876]: info: MRS: Pushing multiroomSync output update for this device Sep 07 14:06:09 volumio-soggiorno volumio[876]: info: MRS: Pushing multiroomSync output Sep 07 14:06:09 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioGetState Sep 07 14:06:09 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioGetState Sep 07 14:06:09 volumio-soggiorno volumio[876]: info: CoreCommandRouter::servicePushState Sep 07 14:06:09 volumio-soggiorno volumio[876]: info: CoreStateMachine::pushState Sep 07 14:06:09 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioPushState Sep 07 14:06:09 volumio-soggiorno volumio[876]: info: MRS: Pushing multiroomSync output update for this device Sep 07 14:06:09 volumio-soggiorno volumio[876]: info: MRS: Pushing multiroomSync output Sep 07 14:06:09 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioGetState Sep 07 14:06:09 volumio-soggiorno volumio[876]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Sep 07 14:06:09 volumio-soggiorno volumio[876]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Sep 07 14:06:09 volumio-soggiorno volumio[876]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Sep 07 14:06:11 volumio-soggiorno volumio[876]: info: Executing endpoint metavolumio Sep 07 14:06:11 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 07 14:06:11 volumio-soggiorno volumio[876]: info: Executing endpoint metavolumio Sep 07 14:06:11 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 07 14:06:11 volumio-soggiorno volumio[876]: info: Executing endpoint metavolumio Sep 07 14:06:11 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 07 14:06:14 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 07 14:06:14 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 07 14:06:14 volumio-soggiorno volumio[876]: info: Discovery: Getting this device information Sep 07 14:06:14 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioGetState Sep 07 14:06:14 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 07 14:06:18 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:18.363 [3501.3501] INFO ProtocolHandler: [0x559669929b20]: Received SetState message: Sep 07 14:06:18 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:18.363 [3501.3501] INFO ProtocolHandler: [0x559669929b20]: Playing state: Paused Sep 07 14:06:18 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:18.363 [3501.3501] INFO ProtocolHandler: [0x559669929b20]: Playback position: Sep 07 14:06:18 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:18.363 [3501.3501] INFO ProtocolHandler: [0x559669929b20]: Queue version: Sep 07 14:06:18 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:18.363 [3501.3501] INFO ProtocolHandler: [0x559669929b20]: Current track: Sep 07 14:06:18 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:18.363 [3501.3501] INFO ProtocolHandler: [0x559669929b20]: Next track: Sep 07 14:06:18 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:18.363 [3501.3501] INFO MediaEngine: [0x559669928dd0]: Pausing playback Sep 07 14:06:18 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:18.363 [3501.3501] INFO AudioRenderer: [0x5596699291d0]: Pausing audio renderer Sep 07 14:06:18 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:18.364 [3501.3501] INFO SampleApp: Pausing playback Sep 07 14:06:18 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:18.364 [3501.3501] INFO SampleApp: Playback state changed: Paused Sep 07 14:06:18 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:18.364 [3501.3501] INFO StreamingEventReporter: [0x55966992a2c0]: Reporting streaming ended event Sep 07 14:06:18 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:18.364 [3501.3501] INFO HttpDownloader: [0x55966992a350]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Sep 07 14:06:18 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:18.364 [3501.3501] INFO ProtocolHandler: [0x559669929b20]: Need to send state update Sep 07 14:06:18 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:18.364 [3501.3501] INFO QConnectMessageSender: [0x559669ae1ff0]: Sending StateUpdated message Sep 07 14:06:18 volumio-soggiorno qobuz-connect[3501]: 20250907 14:06:18.364 [3501.3501] INFO QwspMessageSender: [0x559669ae3790]: Sending Payload message Sep 07 14:06:18 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioGetState Sep 07 14:06:18 volumio-soggiorno volumio[876]: info: CoreCommandRouter::servicePushState Sep 07 14:06:18 volumio-soggiorno volumio[876]: info: CoreStateMachine::pushState Sep 07 14:06:18 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:06:18 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioPushState Sep 07 14:06:18 volumio-soggiorno volumio[876]: info: MRS: Pushing multiroomSync output update for this device Sep 07 14:06:18 volumio-soggiorno volumio[876]: info: MRS: Pushing multiroomSync output Sep 07 14:06:18 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioGetState Sep 07 14:06:18 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioGetState Sep 07 14:06:18 volumio-soggiorno volumio[876]: info: CoreCommandRouter::servicePushState Sep 07 14:06:18 volumio-soggiorno volumio[876]: info: CoreStateMachine::pushState Sep 07 14:06:18 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 14:06:18 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioPushState Sep 07 14:06:18 volumio-soggiorno volumio[876]: info: MRS: Pushing multiroomSync output update for this device Sep 07 14:06:18 volumio-soggiorno volumio[876]: info: MRS: Pushing multiroomSync output Sep 07 14:06:18 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioGetState Sep 07 14:06:18 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioGetState Sep 07 14:06:18 volumio-soggiorno volumio[876]: info: CoreCommandRouter::servicePushState Sep 07 14:06:18 volumio-soggiorno volumio[876]: info: CoreStateMachine::pushState Sep 07 14:06:18 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioPushState Sep 07 14:06:18 volumio-soggiorno volumio[876]: info: MRS: Pushing multiroomSync output update for this device Sep 07 14:06:18 volumio-soggiorno volumio[876]: info: MRS: Pushing multiroomSync output Sep 07 14:06:18 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioGetState Sep 07 14:06:18 volumio-soggiorno volumio[876]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Sep 07 14:06:18 volumio-soggiorno volumio[876]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Sep 07 14:06:37 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: alarm-clock , getSleep Sep 07 14:06:44 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 07 14:06:44 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 07 14:06:44 volumio-soggiorno volumio[876]: info: Discovery: Getting this device information Sep 07 14:06:44 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioGetState Sep 07 14:06:44 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 07 14:06:56 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 07 14:06:56 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 07 14:06:56 volumio-soggiorno volumio[876]: info: Discovery: Getting this device information Sep 07 14:06:56 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioGetState Sep 07 14:06:56 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: Retrieving Cloud Streaming UI Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: Getting Tidal Cloud Configuration Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: Getting Qobuz Cloud Configuration Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: Asking plugin for UI Config Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: Getting Spotify Cloud Configuration Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: Asking plugin for UI Config Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: Saving Spotify Acccount Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: Got it Sep 07 14:06:57 volumio-soggiorno volumio[876]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: Got Tidal Cloud Configuration Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: Got it Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioGetBrowseSources Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: CoreCommandRouter::volumioGetBrowseSources Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 07 14:06:57 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 07 14:07:01 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 07 14:07:03 volumio-soggiorno volumio[876]: info: Disabling MyMusic plugin upnp Sep 07 14:07:03 volumio-soggiorno sudo[4840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Sep 07 14:07:03 volumio-soggiorno sudo[4840]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 14:07:03 volumio-soggiorno systemd[1]: Stopping UPnP Renderer front-end to MPD... Sep 07 14:07:03 volumio-soggiorno volumio[876]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 07 14:07:03 volumio-soggiorno volumio[876]: error: Upnp client error: Error: This socket has been ended by the other party Sep 07 14:07:05 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 07 14:07:07 volumio-soggiorno volumio[876]: info: Enabling MyMusic plugin upnp Sep 07 14:07:07 volumio-soggiorno volumio[876]: info: Enabling plugin upnp Sep 07 14:07:07 volumio-soggiorno volumio[876]: info: Loading plugin "upnp"... Sep 07 14:07:07 volumio-soggiorno volumio[876]: info: [1757246827750] Starting Upmpd Daemon Sep 07 14:07:07 volumio-soggiorno volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 07 14:07:07 volumio-soggiorno volumio[876]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 14:07:07 volumio-soggiorno volumio[876]: Error: listen EADDRINUSE: address already in use :::6599 Sep 07 14:07:07 volumio-soggiorno volumio[876]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Sep 07 14:07:07 volumio-soggiorno volumio[876]: at listenInCluster (net.js:1379:12) Sep 07 14:07:07 volumio-soggiorno volumio[876]: at Server.listen (net.js:1465:7) Sep 07 14:07:07 volumio-soggiorno volumio[876]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Sep 07 14:07:07 volumio-soggiorno volumio[876]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Sep 07 14:07:07 volumio-soggiorno volumio[876]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Sep 07 14:07:07 volumio-soggiorno volumio[876]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 07 14:07:07 volumio-soggiorno volumio[876]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Sep 07 14:07:07 volumio-soggiorno volumio[876]: code: 'EADDRINUSE', Sep 07 14:07:07 volumio-soggiorno volumio[876]: errno: -98, Sep 07 14:07:07 volumio-soggiorno volumio[876]: syscall: 'listen', Sep 07 14:07:07 volumio-soggiorno volumio[876]: address: '::', Sep 07 14:07:07 volumio-soggiorno volumio[876]: port: 6599 Sep 07 14:07:07 volumio-soggiorno volumio[876]: } Sep 07 14:07:07 volumio-soggiorno volumio[876]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 14:07:08 volumio-soggiorno sudo[4851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-07 14:06 Sep 07 14:07:08 volumio-soggiorno sudo[4851]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"