-- Logs begin at Wed 2026-01-21 16:42:40 +07, end at Sun 2026-01-25 18:15:36 +07. -- Jan 25 18:14:00 rivoplus qobuz-connect[28130]: 20260125 18:14:00.557 [28130.28130] INFO ProtocolHandler: [0xab6035f8]: Need to send state update Jan 25 18:14:00 rivoplus qobuz-connect[28130]: 20260125 18:14:00.557 [28130.28130] INFO QConnectMessageSender: [0xab72d3e8]: Sending StateUpdated message Jan 25 18:14:00 rivoplus qobuz-connect[28130]: 20260125 18:14:00.557 [28130.28130] INFO QwspMessageSender: [0xab72db48]: Sending Payload message Jan 25 18:14:00 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:00 rivoplus volumio[3298]: info: CoreCommandRouter::servicePushState Jan 25 18:14:00 rivoplus volumio[3298]: info: CoreStateMachine::pushState Jan 25 18:14:00 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 18:14:00 rivoplus volumio[3298]: info: CoreCommandRouter::volumioPushState Jan 25 18:14:00 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:00 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:14:00 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output Jan 25 18:14:00 rivoplus volumio[3298]: info: Signalling Playback active due to playback status change Jan 25 18:14:00 rivoplus volumio[3298]: info: Updating RAAT Signal Path Jan 25 18:14:10 rivoplus qobuz-connect[28130]: 20260125 18:14:10.558 [28130.28130] INFO ProtocolHandler: [0xab6035f8]: Need to send state update Jan 25 18:14:10 rivoplus qobuz-connect[28130]: 20260125 18:14:10.558 [28130.28130] INFO QConnectMessageSender: [0xab72d3e8]: Sending StateUpdated message Jan 25 18:14:10 rivoplus qobuz-connect[28130]: 20260125 18:14:10.558 [28130.28130] INFO QwspMessageSender: [0xab72db48]: Sending Payload message Jan 25 18:14:10 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:10 rivoplus volumio[3298]: info: CoreCommandRouter::servicePushState Jan 25 18:14:10 rivoplus volumio[3298]: info: CoreStateMachine::pushState Jan 25 18:14:10 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 18:14:10 rivoplus volumio[3298]: info: CoreCommandRouter::volumioPushState Jan 25 18:14:10 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:10 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:14:10 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output Jan 25 18:14:10 rivoplus volumio[3298]: info: Signalling Playback active due to playback status change Jan 25 18:14:10 rivoplus volumio[3298]: info: Updating RAAT Signal Path Jan 25 18:14:20 rivoplus qobuz-connect[28130]: 20260125 18:14:20.558 [28130.28130] INFO ProtocolHandler: [0xab6035f8]: Need to send state update Jan 25 18:14:20 rivoplus qobuz-connect[28130]: 20260125 18:14:20.558 [28130.28130] INFO QConnectMessageSender: [0xab72d3e8]: Sending StateUpdated message Jan 25 18:14:20 rivoplus qobuz-connect[28130]: 20260125 18:14:20.558 [28130.28130] INFO QwspMessageSender: [0xab72db48]: Sending Payload message Jan 25 18:14:20 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:20 rivoplus volumio[3298]: info: CoreCommandRouter::servicePushState Jan 25 18:14:20 rivoplus volumio[3298]: info: CoreStateMachine::pushState Jan 25 18:14:20 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 18:14:20 rivoplus volumio[3298]: info: CoreCommandRouter::volumioPushState Jan 25 18:14:20 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:20 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:14:20 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output Jan 25 18:14:20 rivoplus volumio[3298]: info: Signalling Playback active due to playback status change Jan 25 18:14:20 rivoplus volumio[3298]: info: Updating RAAT Signal Path Jan 25 18:14:30 rivoplus qobuz-connect[28130]: 20260125 18:14:30.559 [28130.28130] INFO ProtocolHandler: [0xab6035f8]: Need to send state update Jan 25 18:14:30 rivoplus qobuz-connect[28130]: 20260125 18:14:30.559 [28130.28130] INFO QConnectMessageSender: [0xab72d3e8]: Sending StateUpdated message Jan 25 18:14:30 rivoplus qobuz-connect[28130]: 20260125 18:14:30.559 [28130.28130] INFO QwspMessageSender: [0xab72db48]: Sending Payload message Jan 25 18:14:30 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:30 rivoplus volumio[3298]: info: CoreCommandRouter::servicePushState Jan 25 18:14:30 rivoplus volumio[3298]: info: CoreStateMachine::pushState Jan 25 18:14:30 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 18:14:30 rivoplus volumio[3298]: info: CoreCommandRouter::volumioPushState Jan 25 18:14:30 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:30 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:14:30 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output Jan 25 18:14:30 rivoplus volumio[3298]: info: Signalling Playback active due to playback status change Jan 25 18:14:30 rivoplus volumio[3298]: info: Updating RAAT Signal Path Jan 25 18:14:33 rivoplus qobuz-connect[28130]: 20260125 18:14:33.371 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: [Stream 2]: Running audio stream Jan 25 18:14:33 rivoplus qobuz-connect[28130]: 20260125 18:14:33.372 [28130.28130] INFO UrlAudioSource: [0xab820508]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=5071656&eid=201866221&fmt=6&profile=raw&app_id=174516466&cid=3705737&etsp=1769343048&hmac=8vxVL0VWnqPweseV06pRa0zN3Qk Jan 25 18:14:33 rivoplus qobuz-connect[28130]: 20260125 18:14:33.372 [28130.28130] INFO ContentFetcher: [0xab7a5e38]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=5071656&eid=201866221&fmt=6&profile=raw&app_id=174516466&cid=3705737&etsp=1769343048&hmac=8vxVL0VWnqPweseV06pRa0zN3Qk, offset: 0 Jan 25 18:14:33 rivoplus qobuz-connect[28130]: 20260125 18:14:33.379 [28130.28130] INFO SampleApp: [Stream 2]: New audio stream (starting from 0ms) Jan 25 18:14:33 rivoplus qobuz-connect[28130]: 20260125 18:14:33.380 [28130.28130] INFO SampleApp: [Stream 2]: Stream metadata became available: Jan 25 18:14:33 rivoplus qobuz-connect[28130]: 20260125 18:14:33.380 [28130.28130] INFO SampleApp: Title: Concerto In F Minor, BWV 1056: Allegro Jan 25 18:14:33 rivoplus qobuz-connect[28130]: 20260125 18:14:33.380 [28130.28130] INFO SampleApp: Artist: Jacques Loussier Jan 25 18:14:33 rivoplus qobuz-connect[28130]: 20260125 18:14:33.380 [28130.28130] INFO SampleApp: Album: Jacques Loussier Plays Bach: Encore! Jan 25 18:14:33 rivoplus qobuz-connect[28130]: 20260125 18:14:33.380 [28130.28130] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/lc/fc/c6uw14szpfclc_600.jpg Jan 25 18:14:33 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:33 rivoplus qobuz-connect[28130]: 20260125 18:14:33.534 [28130.28130] INFO UrlAudioSource: [0xab820508]: Content info extracted Jan 25 18:14:33 rivoplus qobuz-connect[28130]: 20260125 18:14:33.535 [28130.28130] INFO UrlAudioSource: [0xab820508]: Size: 25626119 Jan 25 18:14:33 rivoplus qobuz-connect[28130]: 20260125 18:14:33.546 [28130.6949] INFO AudioDecoder: [0xab648370]: Stream info: Jan 25 18:14:33 rivoplus qobuz-connect[28130]: 20260125 18:14:33.546 [28130.6949] INFO AudioDecoder: [0xab648370]: Total samples: 12595254 Jan 25 18:14:33 rivoplus qobuz-connect[28130]: 20260125 18:14:33.546 [28130.6949] INFO AudioDecoder: [0xab648370]: Sample rate: 44100 Jan 25 18:14:33 rivoplus qobuz-connect[28130]: 20260125 18:14:33.546 [28130.6949] INFO AudioDecoder: [0xab648370]: Channel count: 2 Jan 25 18:14:33 rivoplus qobuz-connect[28130]: 20260125 18:14:33.546 [28130.6949] INFO AudioDecoder: [0xab648370]: Bits per sample: 16 Jan 25 18:14:33 rivoplus qobuz-connect[28130]: 20260125 18:14:33.546 [28130.6949] INFO AudioDecoder: [0xab648370]: Duration: 285606 Jan 25 18:14:33 rivoplus qobuz-connect[28130]: 20260125 18:14:33.547 [28130.28130] INFO UrlAudioSource: [0xab820508]: Audio properties have changed Jan 25 18:14:39 rivoplus qobuz-connect[28130]: 20260125 18:14:39.224 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: [Stream 1]: Audio stream has finished Jan 25 18:14:39 rivoplus qobuz-connect[28130]: 20260125 18:14:39.224 [28130.28130] INFO SampleApp: [Stream 1]: Audio stream finished Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.561 [28130.28130] INFO ProtocolHandler: [0xab6035f8]: Need to send state update Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.561 [28130.28130] INFO QConnectMessageSender: [0xab72d3e8]: Sending StateUpdated message Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.561 [28130.28130] INFO QwspMessageSender: [0xab72db48]: Sending Payload message Jan 25 18:14:40 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:40 rivoplus volumio[3298]: info: CoreCommandRouter::servicePushState Jan 25 18:14:40 rivoplus volumio[3298]: info: CoreStateMachine::pushState Jan 25 18:14:40 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 18:14:40 rivoplus volumio[3298]: info: CoreCommandRouter::volumioPushState Jan 25 18:14:40 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:40 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:14:40 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output Jan 25 18:14:40 rivoplus volumio[3298]: info: Signalling Playback active due to playback status change Jan 25 18:14:40 rivoplus volumio[3298]: info: Updating RAAT Signal Path Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.707 [28130.28130] INFO ProtocolHandler: [0xab6035f8]: Received SetState message: Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.707 [28130.28130] INFO ProtocolHandler: [0xab6035f8]: Playing state: Playing Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.707 [28130.28130] INFO ProtocolHandler: [0xab6035f8]: Playback position: 0 Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.707 [28130.28130] INFO ProtocolHandler: [0xab6035f8]: Queue version: 4.1 Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.707 [28130.28130] INFO ProtocolHandler: [0xab6035f8]: Current track: TID: 266725028, QID: 4, Context UUID: 744a228a-da41-41be-b1f2-52a09fcdac69 Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.707 [28130.28130] INFO ProtocolHandler: [0xab6035f8]: Next track: TID: 183852513, QID: 5, Context UUID: 744a228a-da41-41be-b1f2-52a09fcdac69 Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.707 [28130.28130] INFO MediaEngine: [0xab602eb8]: Stopping playback, clearing tracks Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.708 [28130.28130] INFO MediaEngine: [0xab602eb8]: Initiating playback Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.708 [28130.28130] INFO AudioRenderer: [0xab6030d8]: Stopping audio renderer Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.708 [28130.28130] INFO SampleApp: Stopping playback Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.735 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: Clearing all streams Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.736 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: [Stream 1]: Disposing of audio stream Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.736 [28130.28130] INFO ContentFetcher: [0xab6f3f00]: Canceling download Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.737 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: [Stream 2]: Disposing of audio stream Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.737 [28130.28130] INFO ContentFetcher: [0xab7a5e38]: Canceling download Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.746 [28130.28130] INFO SampleApp: Playback state changed: Stopped Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.747 [28130.28130] INFO StreamingEventReporter: [0xab603a10]: Reporting streaming ended event Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.747 [28130.28130] INFO HttpDownloader: [0xab603a68]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jan 25 18:14:40 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:40 rivoplus volumio[3298]: info: CoreCommandRouter::servicePushState Jan 25 18:14:40 rivoplus volumio[3298]: info: CoreStateMachine::pushState Jan 25 18:14:40 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 18:14:40 rivoplus volumio[3298]: info: CoreCommandRouter::volumioPushState Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.749 [28130.28130] INFO ProtocolHandler: [0xab6035f8]: Need to send state update Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.750 [28130.28130] INFO QConnectMessageSender: [0xab72d3e8]: Sending StateUpdated message Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.751 [28130.28130] INFO SampleApp: [Stream 1]: Audio stream has been disposed of Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.752 [28130.28130] INFO MediaEngine: [0xab602eb8]: Setting current track: 266725028, initial offset: 0ms Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.753 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: Clearing all streams Jan 25 18:14:40 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:40 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.754 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: New stream: 3 Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.755 [28130.28130] INFO HttpDownloader: [0xab7a2010]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=b7fdd94a002900550bdf141cfec42b32&request_ts=1769339680&track_id=266725028 Jan 25 18:14:40 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.755 [28130.28130] INFO HttpDownloader: [0xab617e10]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=266725028 Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.756 [28130.28130] INFO HttpDownloader: [0xab7a2038]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=39dc205f7e7267e4ce63c1f2607a2ce4&request_ts=1769339680&track_id=266725028 Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.757 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: [Stream 3]: Running audio stream Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.758 [28130.28130] INFO QwspMessageSender: [0xab72db48]: Sending Payload message Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.760 [28130.28130] INFO SampleApp: [Stream 2]: Audio stream has been disposed of Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.761 [28130.28130] INFO MediaEngine: [0xab602eb8]: Setting next track: 183852513 Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.761 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: New stream: 4 Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.761 [28130.28130] INFO HttpDownloader: [0xab5f9298]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=d31f8f89209506c56af527699511f293&request_ts=1769339680&track_id=183852513 Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.761 [28130.28130] INFO HttpDownloader: [0xab65e018]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=183852513 Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.761 [28130.28130] INFO HttpDownloader: [0xab60e6e0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=1d7f1f1ca3b43be897e1d58d9ee13164&request_ts=1769339680&track_id=183852513 Jan 25 18:14:40 rivoplus qobuz-connect[28130]: 20260125 18:14:40.763 [28130.28130] INFO MediaEngine: [0xab602eb8]: Waiting for current stream to start before starting audio renderer Jan 25 18:14:40 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:40 rivoplus volumio[3298]: info: CoreCommandRouter::servicePushState Jan 25 18:14:40 rivoplus volumio[3298]: info: CoreStateMachine::pushState Jan 25 18:14:40 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 18:14:40 rivoplus volumio[3298]: info: CoreCommandRouter::volumioPushState Jan 25 18:14:40 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:40 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:14:40 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output Jan 25 18:14:40 rivoplus volumio[3298]: info: Updating RAAT Signal Path Jan 25 18:14:40 rivoplus volumio[3298]: info: Updating RAAT Signal Path Jan 25 18:14:40 rivoplus volumio[3298]: info: MCU Signalled Playback Inactive Jan 25 18:14:41 rivoplus qobuz-connect[28130]: 20260125 18:14:41.488 [28130.28130] INFO ProtocolHandler: [0xab6035f8]: Received SetState message: Jan 25 18:14:41 rivoplus qobuz-connect[28130]: 20260125 18:14:41.488 [28130.28130] INFO ProtocolHandler: [0xab6035f8]: Playing state: Playing Jan 25 18:14:41 rivoplus qobuz-connect[28130]: 20260125 18:14:41.488 [28130.28130] INFO ProtocolHandler: [0xab6035f8]: Playback position: 0 Jan 25 18:14:41 rivoplus qobuz-connect[28130]: 20260125 18:14:41.488 [28130.28130] INFO ProtocolHandler: [0xab6035f8]: Queue version: 5.1 Jan 25 18:14:41 rivoplus qobuz-connect[28130]: 20260125 18:14:41.489 [28130.28130] INFO ProtocolHandler: [0xab6035f8]: Current track: TID: 183852513, QID: 5, Context UUID: b3b30438-500d-4cdc-bb9e-35f78de0ccb1 Jan 25 18:14:41 rivoplus qobuz-connect[28130]: 20260125 18:14:41.489 [28130.28130] INFO ProtocolHandler: [0xab6035f8]: Next track: TID: 205464807, QID: 6, Context UUID: b3b30438-500d-4cdc-bb9e-35f78de0ccb1 Jan 25 18:14:41 rivoplus qobuz-connect[28130]: 20260125 18:14:41.489 [28130.28130] INFO MediaEngine: [0xab602eb8]: Stopping playback, clearing tracks Jan 25 18:14:41 rivoplus qobuz-connect[28130]: 20260125 18:14:41.489 [28130.28130] INFO MediaEngine: [0xab602eb8]: Initiating playback Jan 25 18:14:41 rivoplus qobuz-connect[28130]: 20260125 18:14:41.640 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: [Stream 4]: Metadata became available: Jan 25 18:14:41 rivoplus qobuz-connect[28130]: 20260125 18:14:41.641 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: Title: Le Chat Noir Jan 25 18:14:41 rivoplus qobuz-connect[28130]: 20260125 18:14:41.641 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: Artist: Philippe Chrétien Jan 25 18:14:41 rivoplus qobuz-connect[28130]: 20260125 18:14:41.641 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: Album: Noir Jan 25 18:14:41 rivoplus qobuz-connect[28130]: 20260125 18:14:41.641 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: Album art URL: https://static.qobuz.com/images/covers/fb/1u/lcwh57wds1ufb_600.jpg Jan 25 18:14:41 rivoplus qobuz-connect[28130]: 20260125 18:14:41.689 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: [Stream 3]: Metadata became available: Jan 25 18:14:41 rivoplus qobuz-connect[28130]: 20260125 18:14:41.689 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: Title: CHIHIRO Jan 25 18:14:41 rivoplus qobuz-connect[28130]: 20260125 18:14:41.689 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: Artist: Billie Eilish Jan 25 18:14:41 rivoplus qobuz-connect[28130]: 20260125 18:14:41.689 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: Album: HIT ME HARD AND SOFT Jan 25 18:14:41 rivoplus qobuz-connect[28130]: 20260125 18:14:41.689 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: Album art URL: https://static.qobuz.com/images/covers/kc/95/gvcirtodd95kc_600.jpg Jan 25 18:14:41 rivoplus qobuz-connect[28130]: 20260125 18:14:41.713 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: [Stream 3]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=5071656&eid=266725028&fmt=7&profile=raw&app_id=174516466&cid=3705737&etsp=1769343281&hmac=4vFzGOLgU1mDM4KpdWEJ4PaRS3c Jan 25 18:14:41 rivoplus qobuz-connect[28130]: 20260125 18:14:41.738 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: [Stream 4]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=5071656&eid=183852513&fmt=6&profile=raw&app_id=174516466&cid=3705737&etsp=1769343281&hmac=vqXTQ_F_OCgl7HVZmXT4UEDyxmg Jan 25 18:14:41 rivoplus qobuz-connect[28130]: 20260125 18:14:41.820 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: [Stream 4]: stream information have been fetched Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.230 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: [Stream 3]: stream information have been fetched Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.230 [28130.28130] INFO UrlAudioSource: [0xab607690]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=5071656&eid=266725028&fmt=7&profile=raw&app_id=174516466&cid=3705737&etsp=1769343281&hmac=4vFzGOLgU1mDM4KpdWEJ4PaRS3c Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.230 [28130.28130] INFO ContentFetcher: [0xab7bdd48]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=5071656&eid=266725028&fmt=7&profile=raw&app_id=174516466&cid=3705737&etsp=1769343281&hmac=4vFzGOLgU1mDM4KpdWEJ4PaRS3c, offset: 0 Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.231 [28130.28130] INFO AudioRenderer: [0xab6030d8]: Starting audio renderer, initial playback state: Playing Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.231 [28130.28130] INFO SampleApp: [Stream 3]: New audio stream (starting from 0ms) Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.231 [28130.28130] INFO SampleApp: [Stream 3]: Stream metadata became available: Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.231 [28130.28130] INFO SampleApp: Title: CHIHIRO Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.231 [28130.28130] INFO SampleApp: Artist: Billie Eilish Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.231 [28130.28130] INFO SampleApp: Album: HIT ME HARD AND SOFT Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.231 [28130.28130] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/kc/95/gvcirtodd95kc_600.jpg Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:42 rivoplus volumio[3298]: info: QobuzConnect: alread currrent service, send playback confirmation Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.234 [28130.28130] INFO SampleApp: Initiating playback, initial state: Playing Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.241 [28130.28130] INFO SampleApp: Playback state changed: Playing Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.241 [28130.28130] INFO StreamingEventReporter: [0xab603a10]: Reporting streaming started event Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.241 [28130.28130] INFO HttpDownloader: [0xab603a68]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.241 [28130.28130] INFO ProtocolHandler: [0xab6035f8]: Need to send state update Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.242 [28130.28130] INFO QConnectMessageSender: [0xab72d3e8]: Sending StateUpdated message Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.242 [28130.28130] INFO AudioRenderer: [0xab6030d8]: Stopping audio renderer Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.242 [28130.28130] INFO QwspMessageSender: [0xab72db48]: Sending Payload message Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::servicePushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreStateMachine::pushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioPushState Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.243 [28130.28130] INFO QConnectMessageSender: [0xab72d3e8]: Sending FileAudioQualityChanged message Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.244 [28130.28130] INFO QwspMessageSender: [0xab72db48]: Sending Payload message Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.244 [28130.28130] INFO QConnectMessageSender: [0xab72d3e8]: Sending DeviceAudioQualityChanged message Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.244 [28130.28130] INFO QwspMessageSender: [0xab72db48]: Sending Payload message Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.245 [28130.28130] INFO SampleApp: Stopping playback Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:42 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:14:42 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::servicePushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreStateMachine::pushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioPushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:42 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:14:42 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::servicePushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreStateMachine::pushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioPushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:42 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:14:42 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.272 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: Clearing all streams Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.272 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: [Stream 3]: Disposing of audio stream Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.272 [28130.28130] INFO ContentFetcher: [0xab7bdd48]: Canceling download Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::servicePushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreStateMachine::pushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioPushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:42 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:14:42 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.292 [28130.28130] INFO SampleApp: Playback state changed: Stopped Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.292 [28130.28130] INFO StreamingEventReporter: [0xab603a10]: Reporting streaming ended event Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.293 [28130.28130] INFO ProtocolHandler: [0xab6035f8]: Need to send state update Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.293 [28130.28130] INFO QConnectMessageSender: [0xab72d3e8]: Sending StateUpdated message Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.293 [28130.28130] INFO SampleApp: [Stream 3]: Audio stream has been disposed of Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.293 [28130.28130] INFO MediaEngine: [0xab602eb8]: Setting current track: 183852513, initial offset: 0ms Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.293 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: Clearing all streams Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.293 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: New stream: 5 Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.293 [28130.28130] INFO HttpDownloader: [0xab613350]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=c2e4d0c4a278537d4666e2f200ac6449&request_ts=1769339682&track_id=183852513 Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.293 [28130.28130] INFO HttpDownloader: [0xaba949f0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=183852513 Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.294 [28130.28130] INFO HttpDownloader: [0xab63f580]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=dde46797a4b7a1d0461e309b8f0ae1e7&request_ts=1769339682&track_id=183852513 Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.294 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: [Stream 5]: Running audio stream Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.294 [28130.28130] INFO QwspMessageSender: [0xab72db48]: Sending Payload message Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.301 [28130.28130] INFO MediaEngine: [0xab602eb8]: Setting next track: 205464807 Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.301 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: New stream: 6 Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.301 [28130.28130] INFO HttpDownloader: [0xab613378]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=588c686daaa8eaf74853076a08da87fd&request_ts=1769339682&track_id=205464807 Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.301 [28130.28130] INFO HttpDownloader: [0xab617e10]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=205464807 Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.301 [28130.28130] INFO HttpDownloader: [0xaba945f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=e000b78727ad5d102bd1220693c1fbc6&request_ts=1769339682&track_id=205464807 Jan 25 18:14:42 rivoplus volumio[3298]: info: Signalling Playback active due to playback status change Jan 25 18:14:42 rivoplus volumio[3298]: info: Signalling Playback active due to playback status change Jan 25 18:14:42 rivoplus volumio[3298]: info: Updating RAAT Signal Path Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.326 [28130.28130] INFO MediaEngine: [0xab602eb8]: Waiting for current stream to start before starting audio renderer Jan 25 18:14:42 rivoplus volumio[3298]: info: Updating RAAT Signal Path Jan 25 18:14:42 rivoplus volumio[3298]: info: Updating RAAT Signal Path Jan 25 18:14:42 rivoplus volumio[3298]: info: Updating RAAT Signal Path Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::servicePushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreStateMachine::pushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioPushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:42 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:14:42 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::servicePushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreStateMachine::pushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioPushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:42 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:14:42 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output Jan 25 18:14:42 rivoplus volumio[3298]: info: Updating RAAT Signal Path Jan 25 18:14:42 rivoplus volumio[3298]: info: Updating RAAT Signal Path Jan 25 18:14:42 rivoplus volumio[3298]: info: MCU Signalled Playback Active Jan 25 18:14:42 rivoplus volumio[3298]: info: MCU Signalled Playback Inactive Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.513 [28130.28130] INFO HttpDownloader: [0xab603a68]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.623 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: [Stream 5]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=5071656&eid=183852513&fmt=6&profile=raw&app_id=174516466&cid=3705737&etsp=1769343282&hmac=vvLXqvSqjWhsS8QRdhXIJ3x0zB4 Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.624 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: [Stream 6]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=5071656&eid=205464807&fmt=6&profile=raw&app_id=174516466&cid=3705737&etsp=1769343282&hmac=CGO5lxirirp_YSZw4_Yi_piyxRU Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.647 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: [Stream 5]: Metadata became available: Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.647 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: Title: Le Chat Noir Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.648 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: Artist: Philippe Chrétien Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.648 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: Album: Noir Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.648 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: Album art URL: https://static.qobuz.com/images/covers/fb/1u/lcwh57wds1ufb_600.jpg Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.686 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: [Stream 5]: stream information have been fetched Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.686 [28130.28130] INFO UrlAudioSource: [0xabbf9f48]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=5071656&eid=183852513&fmt=6&profile=raw&app_id=174516466&cid=3705737&etsp=1769343282&hmac=vvLXqvSqjWhsS8QRdhXIJ3x0zB4 Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.687 [28130.28130] INFO ContentFetcher: [0xab7ab438]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=5071656&eid=183852513&fmt=6&profile=raw&app_id=174516466&cid=3705737&etsp=1769343282&hmac=vvLXqvSqjWhsS8QRdhXIJ3x0zB4, offset: 0 Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.687 [28130.28130] INFO AudioRenderer: [0xab6030d8]: Starting audio renderer, initial playback state: Playing Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.687 [28130.28130] INFO SampleApp: [Stream 5]: New audio stream (starting from 0ms) Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.687 [28130.28130] INFO SampleApp: [Stream 5]: Stream metadata became available: Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.688 [28130.28130] INFO SampleApp: Title: Le Chat Noir Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.688 [28130.28130] INFO SampleApp: Artist: Philippe Chrétien Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.688 [28130.28130] INFO SampleApp: Album: Noir Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.688 [28130.28130] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/fb/1u/lcwh57wds1ufb_600.jpg Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:42 rivoplus volumio[3298]: info: QobuzConnect: alread currrent service, send playback confirmation Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.691 [28130.28130] INFO SampleApp: Initiating playback, initial state: Playing Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.722 [28130.28130] INFO SampleApp: Playback state changed: Playing Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.722 [28130.28130] INFO StreamingEventReporter: [0xab603a10]: Reporting streaming started event Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.723 [28130.28130] INFO ProtocolHandler: [0xab6035f8]: Need to send state update Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.723 [28130.28130] INFO QConnectMessageSender: [0xab72d3e8]: Sending StateUpdated message Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.723 [28130.28130] INFO QwspMessageSender: [0xab72db48]: Sending Payload message Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::servicePushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreStateMachine::pushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.724 [28130.28130] INFO QConnectMessageSender: [0xab72d3e8]: Sending FileAudioQualityChanged message Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.724 [28130.28130] INFO QwspMessageSender: [0xab72db48]: Sending Payload message Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.724 [28130.28130] INFO QConnectMessageSender: [0xab72d3e8]: Sending DeviceAudioQualityChanged message Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioPushState Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.725 [28130.28130] INFO QwspMessageSender: [0xab72db48]: Sending Payload message Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.726 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: [Stream 6]: stream information have been fetched Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:42 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:14:42 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::servicePushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreStateMachine::pushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioPushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:42 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.747 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: [Stream 6]: Metadata became available: Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.747 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: Title: Dreaming Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.747 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: Artist: Bratislava Chamber Ensamble Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.747 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: Album: Orchestral Minatures Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.747 [28130.28130] INFO AudioStreamManager: [0xab602fa0]: Album art URL: https://static.qobuz.com/images/covers/ta/b3/syhfiht21b3ta_600.jpg Jan 25 18:14:42 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::servicePushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreStateMachine::pushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioPushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:42 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:14:42 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::servicePushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreStateMachine::pushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioPushState Jan 25 18:14:42 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:42 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:14:42 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output Jan 25 18:14:42 rivoplus volumio[3298]: info: Signalling Playback active due to playback status change Jan 25 18:14:42 rivoplus volumio[3298]: info: Signalling Playback active due to playback status change Jan 25 18:14:42 rivoplus volumio[3298]: info: Signalling Playback active due to playback status change Jan 25 18:14:42 rivoplus volumio[3298]: info: Updating RAAT Signal Path Jan 25 18:14:42 rivoplus volumio[3298]: info: Updating RAAT Signal Path Jan 25 18:14:42 rivoplus volumio[3298]: info: Updating RAAT Signal Path Jan 25 18:14:42 rivoplus volumio[3298]: info: Updating RAAT Signal Path Jan 25 18:14:42 rivoplus volumio[3298]: info: MCU Signalled Playback Active Jan 25 18:14:42 rivoplus qobuz-connect[28130]: 20260125 18:14:42.929 [28130.28130] INFO HttpDownloader: [0xab603a68]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jan 25 18:14:43 rivoplus qobuz-connect[28130]: 20260125 18:14:43.865 [28130.28130] INFO UrlAudioSource: [0xabbf9f48]: Content info extracted Jan 25 18:14:43 rivoplus qobuz-connect[28130]: 20260125 18:14:43.865 [28130.28130] INFO UrlAudioSource: [0xabbf9f48]: Size: 47553340 Jan 25 18:14:43 rivoplus qobuz-connect[28130]: 20260125 18:14:43.868 [28130.7020] INFO AudioDecoder: [0xab650430]: Stream info: Jan 25 18:14:43 rivoplus qobuz-connect[28130]: 20260125 18:14:43.868 [28130.7020] INFO AudioDecoder: [0xab650430]: Total samples: 20156052 Jan 25 18:14:43 rivoplus qobuz-connect[28130]: 20260125 18:14:43.868 [28130.7020] INFO AudioDecoder: [0xab650430]: Sample rate: 44100 Jan 25 18:14:43 rivoplus qobuz-connect[28130]: 20260125 18:14:43.868 [28130.7020] INFO AudioDecoder: [0xab650430]: Channel count: 2 Jan 25 18:14:43 rivoplus qobuz-connect[28130]: 20260125 18:14:43.868 [28130.7020] INFO AudioDecoder: [0xab650430]: Bits per sample: 16 Jan 25 18:14:43 rivoplus qobuz-connect[28130]: 20260125 18:14:43.868 [28130.7020] INFO AudioDecoder: [0xab650430]: Duration: 457053 Jan 25 18:14:43 rivoplus qobuz-connect[28130]: 20260125 18:14:43.868 [28130.28130] INFO UrlAudioSource: [0xabbf9f48]: Audio properties have changed Jan 25 18:14:52 rivoplus qobuz-connect[28130]: 20260125 18:14:52.723 [28130.28130] INFO ProtocolHandler: [0xab6035f8]: Need to send state update Jan 25 18:14:52 rivoplus qobuz-connect[28130]: 20260125 18:14:52.723 [28130.28130] INFO QConnectMessageSender: [0xab72d3e8]: Sending StateUpdated message Jan 25 18:14:52 rivoplus qobuz-connect[28130]: 20260125 18:14:52.723 [28130.28130] INFO QwspMessageSender: [0xab72db48]: Sending Payload message Jan 25 18:14:52 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:52 rivoplus volumio[3298]: info: CoreCommandRouter::servicePushState Jan 25 18:14:52 rivoplus volumio[3298]: info: CoreStateMachine::pushState Jan 25 18:14:52 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 18:14:52 rivoplus volumio[3298]: info: CoreCommandRouter::volumioPushState Jan 25 18:14:52 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:14:52 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:14:52 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output Jan 25 18:14:52 rivoplus volumio[3298]: info: Signalling Playback active due to playback status change Jan 25 18:14:52 rivoplus volumio[3298]: info: Updating RAAT Signal Path Jan 25 18:14:57 rivoplus volumio5-onboarding[3920]: time=2026-01-25T18:14:57.854+07:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.32:58480 Jan 25 18:14:57 rivoplus volumio5-onboarding[3920]: time=2026-01-25T18:14:57.978+07:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.1.32:58480 Jan 25 18:14:57 rivoplus volumio5-onboarding[3920]: time=2026-01-25T18:14:57.978+07:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.1.32:58480 Jan 25 18:15:02 rivoplus volumio5-onboarding[3920]: time=2026-01-25T18:15:02.540+07:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.32:58508 Jan 25 18:15:02 rivoplus volumio5-onboarding[3920]: time=2026-01-25T18:15:02.707+07:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.32:58508 @ 0x31242a0" latency=620.001771ms platform=PLATFORM_ANDROID version=4.251125.0 Jan 25 18:15:02 rivoplus volumio5-onboarding[3920]: time=2026-01-25T18:15:02.710+07:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.32:58508 @ 0x31242a0" latency=618.953062ms timeout=10s Jan 25 18:15:02 rivoplus qobuz-connect[28130]: 20260125 18:15:02.724 [28130.28130] INFO ProtocolHandler: [0xab6035f8]: Need to send state update Jan 25 18:15:02 rivoplus qobuz-connect[28130]: 20260125 18:15:02.724 [28130.28130] INFO QConnectMessageSender: [0xab72d3e8]: Sending StateUpdated message Jan 25 18:15:02 rivoplus qobuz-connect[28130]: 20260125 18:15:02.724 [28130.28130] INFO QwspMessageSender: [0xab72db48]: Sending Payload message Jan 25 18:15:02 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:15:02 rivoplus volumio[3298]: info: CoreCommandRouter::servicePushState Jan 25 18:15:02 rivoplus volumio[3298]: info: CoreStateMachine::pushState Jan 25 18:15:02 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 18:15:02 rivoplus volumio[3298]: info: CoreCommandRouter::volumioPushState Jan 25 18:15:02 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:15:02 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:15:02 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output Jan 25 18:15:02 rivoplus volumio[3298]: info: Signalling Playback active due to playback status change Jan 25 18:15:02 rivoplus volumio[3298]: info: Updating RAAT Signal Path Jan 25 18:15:02 rivoplus volumio[3298]: info: Received Get System Info Jan 25 18:15:02 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 25 18:15:02 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 25 18:15:02 rivoplus volumio[3298]: info: Discovery: Getting this device information Jan 25 18:15:02 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:15:02 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 25 18:15:02 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Jan 25 18:15:02 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Jan 25 18:15:03 rivoplus volumio[3298]: No protocol specified Jan 25 18:15:03 rivoplus volumio[3298]: xcb_connection_has_error() returned true Jan 25 18:15:03 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 25 18:15:03 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 18:15:03 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 25 18:15:03 rivoplus volumio[3298]: amixer -c 1 info | grep "Comtrue-inc UAC2 Device" Jan 25 18:15:03 rivoplus volumio[3298]: No protocol specified Jan 25 18:15:03 rivoplus volumio[3298]: xcb_connection_has_error() returned true Jan 25 18:15:03 rivoplus volumio[3298]: Card hw:1 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' Jan 25 18:15:03 rivoplus volumio[3298]: No protocol specified Jan 25 18:15:03 rivoplus volumio[3298]: xcb_connection_has_error() returned true Jan 25 18:15:03 rivoplus volumio[3298]: amixer -c 5 info | grep "XMOS USB Audio" Jan 25 18:15:03 rivoplus volumio[3298]: No protocol specified Jan 25 18:15:03 rivoplus volumio[3298]: xcb_connection_has_error() returned true Jan 25 18:15:03 rivoplus volumio[3298]: Card hw:5 'Audio'/'Goldenwave XMOS USB Audio at usb-xhci-hcd.0.auto-1.4.2, high speed' Jan 25 18:15:03 rivoplus volumio[3298]: No protocol specified Jan 25 18:15:03 rivoplus volumio[3298]: xcb_connection_has_error() returned true Jan 25 18:15:03 rivoplus volumio[3298]: amixer -c 5 info | grep "XMOS USB Audio" Jan 25 18:15:03 rivoplus volumio[3298]: No protocol specified Jan 25 18:15:03 rivoplus volumio[3298]: xcb_connection_has_error() returned true Jan 25 18:15:03 rivoplus volumio[3298]: Card hw:5 'Audio'/'Goldenwave XMOS USB Audio at usb-xhci-hcd.0.auto-1.4.2, high speed' Jan 25 18:15:03 rivoplus volumio[3298]: info: Received Get System Info Jan 25 18:15:03 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 25 18:15:03 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 25 18:15:03 rivoplus volumio[3298]: info: Discovery: Getting this device information Jan 25 18:15:03 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:15:03 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 25 18:15:03 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:15:03 rivoplus volumio5-onboarding[3920]: time=2026-01-25T18:15:03.647+07:00 level=INFO msg="continue navigation request" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.32:58508 @ 0x31242a0" latency=593.861097ms timeout=10s from=APP_PAGE_ROOT Jan 25 18:15:05 rivoplus sudo[7102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 25 18:15:05 rivoplus sudo[7100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 25 18:15:05 rivoplus sudo[7100]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:15:05 rivoplus sudo[7102]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:15:06 rivoplus sudo[7100]: pam_unix(sudo:session): session closed for user root Jan 25 18:15:06 rivoplus sudo[7102]: pam_unix(sudo:session): session closed for user root Jan 25 18:15:06 rivoplus volumio[3298]: verbose: New Socket.io Connection to 192.168.1.113 from 192.168.1.32 UA: Mozilla/5.0 (Linux; Android 12; DCO-AL00 Build/HUAWEIDCO-AL00; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/114.0.5735.196 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jan 25 18:15:06 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 25 18:15:06 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 25 18:15:06 rivoplus sudo[7108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 25 18:15:06 rivoplus sudo[7108]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:15:06 rivoplus sudo[7108]: pam_unix(sudo:session): session closed for user root Jan 25 18:15:06 rivoplus sudo[7112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 25 18:15:06 rivoplus sudo[7112]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 25 18:15:06 rivoplus sudo[7112]: pam_unix(sudo:session): session closed for user root Jan 25 18:15:06 rivoplus volumio[3298]: verbose: New Socket.io Connection to 192.168.1.113 from 192.168.1.32 UA: Mozilla/5.0 (Linux; Android 12; DCO-AL00 Build/HUAWEIDCO-AL00; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/114.0.5735.196 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jan 25 18:15:06 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 25 18:15:06 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetVisibleSources Jan 25 18:15:06 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 25 18:15:06 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:15:06 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 25 18:15:06 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 25 18:15:06 rivoplus volumio[3298]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 25 18:15:06 rivoplus volumio[3298]: info: Received Get System Info Jan 25 18:15:06 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 25 18:15:06 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 25 18:15:06 rivoplus volumio[3298]: info: Discovery: Getting this device information Jan 25 18:15:06 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:15:06 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 25 18:15:06 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:15:06 rivoplus volumio[3298]: info: Listing playlists Jan 25 18:15:06 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 25 18:15:06 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 25 18:15:07 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 25 18:15:08 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 25 18:15:08 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:15:08 rivoplus volumio[3298]: info: Received Get System Info Jan 25 18:15:08 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 25 18:15:08 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 25 18:15:08 rivoplus volumio[3298]: info: Discovery: Getting this device information Jan 25 18:15:08 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:15:08 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 25 18:15:08 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 18:15:10 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:15:10 rivoplus volumio[3298]: info: Received Get System Info Jan 25 18:15:10 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 25 18:15:10 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 25 18:15:10 rivoplus volumio[3298]: info: Discovery: Getting this device information Jan 25 18:15:10 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:15:10 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 25 18:15:11 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:15:11 rivoplus volumio[3298]: info: Received Get System Info Jan 25 18:15:11 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 25 18:15:11 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 25 18:15:11 rivoplus volumio[3298]: info: Discovery: Getting this device information Jan 25 18:15:11 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:15:11 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 25 18:15:12 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 18:15:12 rivoplus volumio[3298]: info: Received Get System Info Jan 25 18:15:12 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 25 18:15:12 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 25 18:15:12 rivoplus volumio[3298]: info: Discovery: Getting this device information Jan 25 18:15:12 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:15:12 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 25 18:15:12 rivoplus qobuz-connect[28130]: 20260125 18:15:12.725 [28130.28130] INFO ProtocolHandler: [0xab6035f8]: Need to send state update Jan 25 18:15:12 rivoplus qobuz-connect[28130]: 20260125 18:15:12.725 [28130.28130] INFO QConnectMessageSender: [0xab72d3e8]: Sending StateUpdated message Jan 25 18:15:12 rivoplus qobuz-connect[28130]: 20260125 18:15:12.725 [28130.28130] INFO QwspMessageSender: [0xab72db48]: Sending Payload message Jan 25 18:15:12 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:15:12 rivoplus volumio[3298]: info: CoreCommandRouter::servicePushState Jan 25 18:15:12 rivoplus volumio[3298]: info: CoreStateMachine::pushState Jan 25 18:15:12 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 18:15:12 rivoplus volumio[3298]: info: CoreCommandRouter::volumioPushState Jan 25 18:15:12 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:15:12 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:15:12 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output Jan 25 18:15:12 rivoplus volumio[3298]: info: Signalling Playback active due to playback status change Jan 25 18:15:12 rivoplus volumio[3298]: info: Updating RAAT Signal Path Jan 25 18:15:16 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 25 18:15:22 rivoplus qobuz-connect[28130]: 20260125 18:15:22.725 [28130.28130] INFO ProtocolHandler: [0xab6035f8]: Need to send state update Jan 25 18:15:22 rivoplus qobuz-connect[28130]: 20260125 18:15:22.725 [28130.28130] INFO QConnectMessageSender: [0xab72d3e8]: Sending StateUpdated message Jan 25 18:15:22 rivoplus qobuz-connect[28130]: 20260125 18:15:22.725 [28130.28130] INFO QwspMessageSender: [0xab72db48]: Sending Payload message Jan 25 18:15:22 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:15:22 rivoplus volumio[3298]: info: CoreCommandRouter::servicePushState Jan 25 18:15:22 rivoplus volumio[3298]: info: CoreStateMachine::pushState Jan 25 18:15:22 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 18:15:22 rivoplus volumio[3298]: info: CoreCommandRouter::volumioPushState Jan 25 18:15:22 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:15:22 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:15:22 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output Jan 25 18:15:22 rivoplus volumio[3298]: info: Signalling Playback active due to playback status change Jan 25 18:15:22 rivoplus volumio[3298]: info: Updating RAAT Signal Path Jan 25 18:15:25 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 25 18:15:25 rivoplus volumio[3298]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 25 18:15:25 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 25 18:15:25 rivoplus volumio[3298]: info: Received Get System Version Jan 25 18:15:25 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 25 18:15:25 rivoplus volumio[3298]: info: Received Get System Info Jan 25 18:15:25 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 25 18:15:25 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 25 18:15:25 rivoplus volumio[3298]: info: Discovery: Getting this device information Jan 25 18:15:25 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:15:25 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 25 18:15:30 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetQueue Jan 25 18:15:30 rivoplus volumio[3298]: info: CoreStateMachine::getQueue Jan 25 18:15:30 rivoplus volumio[3298]: info: CorePlayQueue::getQueue Jan 25 18:15:30 rivoplus volumio[3298]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jan 25 18:15:30 rivoplus volumio[3298]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jan 25 18:15:30 rivoplus volumio[3298]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jan 25 18:15:30 rivoplus volumio[3298]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jan 25 18:15:31 rivoplus volumio[3298]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jan 25 18:15:32 rivoplus qobuz-connect[28130]: 20260125 18:15:32.726 [28130.28130] INFO ProtocolHandler: [0xab6035f8]: Need to send state update Jan 25 18:15:32 rivoplus qobuz-connect[28130]: 20260125 18:15:32.726 [28130.28130] INFO QConnectMessageSender: [0xab72d3e8]: Sending StateUpdated message Jan 25 18:15:32 rivoplus qobuz-connect[28130]: 20260125 18:15:32.726 [28130.28130] INFO QwspMessageSender: [0xab72db48]: Sending Payload message Jan 25 18:15:32 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:15:32 rivoplus volumio[3298]: info: CoreCommandRouter::servicePushState Jan 25 18:15:32 rivoplus volumio[3298]: info: CoreStateMachine::pushState Jan 25 18:15:32 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 18:15:32 rivoplus volumio[3298]: info: CoreCommandRouter::volumioPushState Jan 25 18:15:32 rivoplus volumio[3298]: info: CoreCommandRouter::volumioGetState Jan 25 18:15:32 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output update for this device Jan 25 18:15:32 rivoplus volumio[3298]: info: MRS: Pushing multiroomSync output Jan 25 18:15:32 rivoplus volumio[3298]: info: Signalling Playback active due to playback status change Jan 25 18:15:32 rivoplus volumio[3298]: info: Updating RAAT Signal Path Jan 25 18:15:34 rivoplus volumio[3298]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Jan 25 18:15:34 rivoplus volumio[3298]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 18:15:34 rivoplus volumio[3298]: TypeError: Cannot read property 'then' of undefined Jan 25 18:15:34 rivoplus volumio[3298]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20) Jan 25 18:15:34 rivoplus volumio[3298]: at Socket.emit (events.js:400:28) Jan 25 18:15:34 rivoplus volumio[3298]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jan 25 18:15:34 rivoplus volumio[3298]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Jan 25 18:15:34 rivoplus volumio[3298]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 18:15:36 rivoplus sudo[7179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-25 18:14 Jan 25 18:15:36 rivoplus sudo[7179]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="2f25e1a98e53e2dbc4f3878604e250b0379f3ea4" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:47:33 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="9e8aa5d6afb5e5d87a87bafae7aa5647"