-- Logs begin at Fri 2025-09-05 22:19:03 JST, end at Fri 2025-09-05 22:48:35 JST. -- Sep 05 22:47:01 rivo-toshi qobuz-connect[5680]: 20250905 22:47:01.223 [5680.5680] INFO ProtocolHandler: [0xace18538]: Need to send state update Sep 05 22:47:01 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:47:01 rivo-toshi volumio[3269]: info: CoreCommandRouter::servicePushState Sep 05 22:47:01 rivo-toshi volumio[3269]: info: CoreStateMachine::pushState Sep 05 22:47:01 rivo-toshi volumio[3269]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:47:01 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioPushState Sep 05 22:47:01 rivo-toshi qobuz-connect[5680]: 20250905 22:47:01.229 [5680.5680] INFO QConnectMessageSender: [0xace1b728]: Sending StateUpdated message Sep 05 22:47:01 rivo-toshi qobuz-connect[5680]: 20250905 22:47:01.229 [5680.5680] INFO QwspMessageSender: [0xacf645a0]: Sending Payload message Sep 05 22:47:01 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:47:01 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:47:01 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output Sep 05 22:47:01 rivo-toshi volumio[3269]: info: Signalling Playback active due to playback status change Sep 05 22:47:01 rivo-toshi volumio[3269]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:47:01 rivo-toshi volumio[3269]: info: Updating RAAT Signal Path Sep 05 22:47:11 rivo-toshi qobuz-connect[5680]: 20250905 22:47:11.223 [5680.5680] INFO ProtocolHandler: [0xace18538]: Need to send state update Sep 05 22:47:11 rivo-toshi qobuz-connect[5680]: 20250905 22:47:11.223 [5680.5680] INFO QConnectMessageSender: [0xace1b728]: Sending StateUpdated message Sep 05 22:47:11 rivo-toshi qobuz-connect[5680]: 20250905 22:47:11.223 [5680.5680] INFO QwspMessageSender: [0xacf645a0]: Sending Payload message Sep 05 22:47:11 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:47:11 rivo-toshi volumio[3269]: info: CoreCommandRouter::servicePushState Sep 05 22:47:11 rivo-toshi volumio[3269]: info: CoreStateMachine::pushState Sep 05 22:47:11 rivo-toshi volumio[3269]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:47:11 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioPushState Sep 05 22:47:11 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:47:11 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:47:11 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output Sep 05 22:47:11 rivo-toshi volumio[3269]: info: Signalling Playback active due to playback status change Sep 05 22:47:11 rivo-toshi volumio[3269]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:47:11 rivo-toshi volumio[3269]: info: Updating RAAT Signal Path Sep 05 22:47:12 rivo-toshi volumiologrotate[2840]: ls: cannot access '/var/log/samba/log.wb-RIVO': No such file or directory Sep 05 22:47:12 rivo-toshi volumiologrotate[2840]: ls: cannot access 'TOSHI': No such file or directory Sep 05 22:47:21 rivo-toshi qobuz-connect[5680]: 20250905 22:47:21.223 [5680.5680] INFO ProtocolHandler: [0xace18538]: Need to send state update Sep 05 22:47:21 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:47:21 rivo-toshi volumio[3269]: info: CoreCommandRouter::servicePushState Sep 05 22:47:21 rivo-toshi volumio[3269]: info: CoreStateMachine::pushState Sep 05 22:47:21 rivo-toshi volumio[3269]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:47:21 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioPushState Sep 05 22:47:21 rivo-toshi qobuz-connect[5680]: 20250905 22:47:21.228 [5680.5680] INFO QConnectMessageSender: [0xace1b728]: Sending StateUpdated message Sep 05 22:47:21 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:47:21 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:47:21 rivo-toshi qobuz-connect[5680]: 20250905 22:47:21.229 [5680.5680] INFO QwspMessageSender: [0xacf645a0]: Sending Payload message Sep 05 22:47:21 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output Sep 05 22:47:21 rivo-toshi volumio[3269]: info: Signalling Playback active due to playback status change Sep 05 22:47:21 rivo-toshi volumio[3269]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:47:21 rivo-toshi volumio[3269]: info: Updating RAAT Signal Path Sep 05 22:47:31 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:47:31 rivo-toshi volumio[3269]: info: CoreCommandRouter::servicePushState Sep 05 22:47:31 rivo-toshi volumio[3269]: info: CoreStateMachine::pushState Sep 05 22:47:31 rivo-toshi volumio[3269]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:47:31 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioPushState Sep 05 22:47:31 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:47:31 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:47:31 rivo-toshi qobuz-connect[5680]: 20250905 22:47:31.234 [5680.5680] INFO ProtocolHandler: [0xace18538]: Need to send state update Sep 05 22:47:31 rivo-toshi qobuz-connect[5680]: 20250905 22:47:31.234 [5680.5680] INFO QConnectMessageSender: [0xace1b728]: Sending StateUpdated message Sep 05 22:47:31 rivo-toshi qobuz-connect[5680]: 20250905 22:47:31.234 [5680.5680] INFO QwspMessageSender: [0xacf645a0]: Sending Payload message Sep 05 22:47:31 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output Sep 05 22:47:31 rivo-toshi volumio[3269]: info: Signalling Playback active due to playback status change Sep 05 22:47:31 rivo-toshi volumio[3269]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:47:31 rivo-toshi volumio[3269]: info: Updating RAAT Signal Path Sep 05 22:47:41 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:47:41 rivo-toshi volumio[3269]: info: CoreCommandRouter::servicePushState Sep 05 22:47:41 rivo-toshi volumio[3269]: info: CoreStateMachine::pushState Sep 05 22:47:41 rivo-toshi volumio[3269]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:47:41 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioPushState Sep 05 22:47:41 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:47:41 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:47:41 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output Sep 05 22:47:41 rivo-toshi qobuz-connect[5680]: 20250905 22:47:41.234 [5680.5680] INFO ProtocolHandler: [0xace18538]: Need to send state update Sep 05 22:47:41 rivo-toshi qobuz-connect[5680]: 20250905 22:47:41.235 [5680.5680] INFO QConnectMessageSender: [0xace1b728]: Sending StateUpdated message Sep 05 22:47:41 rivo-toshi qobuz-connect[5680]: 20250905 22:47:41.235 [5680.5680] INFO QwspMessageSender: [0xacf645a0]: Sending Payload message Sep 05 22:47:41 rivo-toshi volumio[3269]: info: Signalling Playback active due to playback status change Sep 05 22:47:41 rivo-toshi volumio[3269]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:47:41 rivo-toshi volumio[3269]: info: Updating RAAT Signal Path Sep 05 22:47:51 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:47:51 rivo-toshi volumio[3269]: info: CoreCommandRouter::servicePushState Sep 05 22:47:51 rivo-toshi volumio[3269]: info: CoreStateMachine::pushState Sep 05 22:47:51 rivo-toshi volumio[3269]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:47:51 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioPushState Sep 05 22:47:51 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:47:51 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:47:51 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output Sep 05 22:47:51 rivo-toshi qobuz-connect[5680]: 20250905 22:47:51.235 [5680.5680] INFO ProtocolHandler: [0xace18538]: Need to send state update Sep 05 22:47:51 rivo-toshi qobuz-connect[5680]: 20250905 22:47:51.235 [5680.5680] INFO QConnectMessageSender: [0xace1b728]: Sending StateUpdated message Sep 05 22:47:51 rivo-toshi qobuz-connect[5680]: 20250905 22:47:51.235 [5680.5680] INFO QwspMessageSender: [0xacf645a0]: Sending Payload message Sep 05 22:47:51 rivo-toshi volumio[3269]: info: Signalling Playback active due to playback status change Sep 05 22:47:51 rivo-toshi volumio[3269]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:47:51 rivo-toshi volumio[3269]: info: Updating RAAT Signal Path Sep 05 22:48:01 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:01 rivo-toshi volumio[3269]: info: CoreCommandRouter::servicePushState Sep 05 22:48:01 rivo-toshi volumio[3269]: info: CoreStateMachine::pushState Sep 05 22:48:01 rivo-toshi volumio[3269]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:48:01 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioPushState Sep 05 22:48:01 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:01 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:48:01 rivo-toshi qobuz-connect[5680]: 20250905 22:48:01.235 [5680.5680] INFO ProtocolHandler: [0xace18538]: Need to send state update Sep 05 22:48:01 rivo-toshi qobuz-connect[5680]: 20250905 22:48:01.235 [5680.5680] INFO QConnectMessageSender: [0xace1b728]: Sending StateUpdated message Sep 05 22:48:01 rivo-toshi qobuz-connect[5680]: 20250905 22:48:01.235 [5680.5680] INFO QwspMessageSender: [0xacf645a0]: Sending Payload message Sep 05 22:48:01 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output Sep 05 22:48:01 rivo-toshi volumio[3269]: info: Signalling Playback active due to playback status change Sep 05 22:48:01 rivo-toshi volumio[3269]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:48:01 rivo-toshi volumio[3269]: info: Updating RAAT Signal Path Sep 05 22:48:11 rivo-toshi qobuz-connect[5680]: 20250905 22:48:11.225 [5680.5680] INFO ProtocolHandler: [0xace18538]: Need to send state update Sep 05 22:48:11 rivo-toshi qobuz-connect[5680]: 20250905 22:48:11.225 [5680.5680] INFO QConnectMessageSender: [0xace1b728]: Sending StateUpdated message Sep 05 22:48:11 rivo-toshi qobuz-connect[5680]: 20250905 22:48:11.225 [5680.5680] INFO QwspMessageSender: [0xacf645a0]: Sending Payload message Sep 05 22:48:11 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:11 rivo-toshi volumio[3269]: info: CoreCommandRouter::servicePushState Sep 05 22:48:11 rivo-toshi volumio[3269]: info: CoreStateMachine::pushState Sep 05 22:48:11 rivo-toshi volumio[3269]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:48:11 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioPushState Sep 05 22:48:11 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:11 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:48:11 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output Sep 05 22:48:11 rivo-toshi volumio[3269]: info: Signalling Playback active due to playback status change Sep 05 22:48:11 rivo-toshi volumio[3269]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:48:11 rivo-toshi volumio[3269]: info: Updating RAAT Signal Path Sep 05 22:48:12 rivo-toshi volumiologrotate[2840]: ls: cannot access '/var/log/samba/log.wb-RIVO': No such file or directory Sep 05 22:48:12 rivo-toshi volumiologrotate[2840]: ls: cannot access 'TOSHI': No such file or directory Sep 05 22:48:21 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:21 rivo-toshi volumio[3269]: info: CoreCommandRouter::servicePushState Sep 05 22:48:21 rivo-toshi volumio[3269]: info: CoreStateMachine::pushState Sep 05 22:48:21 rivo-toshi volumio[3269]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:48:21 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioPushState Sep 05 22:48:21 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:21 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:48:21 rivo-toshi qobuz-connect[5680]: 20250905 22:48:21.235 [5680.5680] INFO ProtocolHandler: [0xace18538]: Need to send state update Sep 05 22:48:21 rivo-toshi qobuz-connect[5680]: 20250905 22:48:21.235 [5680.5680] INFO QConnectMessageSender: [0xace1b728]: Sending StateUpdated message Sep 05 22:48:21 rivo-toshi qobuz-connect[5680]: 20250905 22:48:21.235 [5680.5680] INFO QwspMessageSender: [0xacf645a0]: Sending Payload message Sep 05 22:48:21 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output Sep 05 22:48:21 rivo-toshi volumio[3269]: info: Signalling Playback active due to playback status change Sep 05 22:48:21 rivo-toshi volumio[3269]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:48:21 rivo-toshi volumio[3269]: info: Updating RAAT Signal Path Sep 05 22:48:31 rivo-toshi qobuz-connect[5680]: 20250905 22:48:31.225 [5680.5680] INFO ProtocolHandler: [0xace18538]: Need to send state update Sep 05 22:48:31 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:31 rivo-toshi volumio[3269]: info: CoreCommandRouter::servicePushState Sep 05 22:48:31 rivo-toshi volumio[3269]: info: CoreStateMachine::pushState Sep 05 22:48:31 rivo-toshi volumio[3269]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:48:31 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioPushState Sep 05 22:48:31 rivo-toshi qobuz-connect[5680]: 20250905 22:48:31.231 [5680.5680] INFO QConnectMessageSender: [0xace1b728]: Sending StateUpdated message Sep 05 22:48:31 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:31 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:48:31 rivo-toshi qobuz-connect[5680]: 20250905 22:48:31.232 [5680.5680] INFO QwspMessageSender: [0xacf645a0]: Sending Payload message Sep 05 22:48:31 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output Sep 05 22:48:31 rivo-toshi volumio[3269]: info: Signalling Playback active due to playback status change Sep 05 22:48:31 rivo-toshi volumio[3269]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:48:31 rivo-toshi volumio[3269]: info: Updating RAAT Signal Path Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.666 [5680.5680] INFO ProtocolHandler: [0xace18538]: Received SetState message: Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.668 [5680.5680] INFO ProtocolHandler: [0xace18538]: Playing state: Playing Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.668 [5680.5680] INFO ProtocolHandler: [0xace18538]: Playback position: 0 Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.668 [5680.5680] INFO ProtocolHandler: [0xace18538]: Queue version: 12.1 Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.669 [5680.5680] INFO ProtocolHandler: [0xace18538]: Current track: TID: 303218005, QID: 28, Context UUID: a2abe007-5aad-4857-be32-71f80d28aa87 Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.669 [5680.5680] INFO ProtocolHandler: [0xace18538]: Next track: TID: 303218006, QID: 29, Context UUID: a2abe007-5aad-4857-be32-71f80d28aa87 Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.669 [5680.5680] INFO MediaEngine: [0xace17df8]: Stopping playback, clearing tracks Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.670 [5680.5680] INFO MediaEngine: [0xace17df8]: Initiating playback Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.670 [5680.5680] INFO AudioRenderer: [0xace18018]: Stopping audio renderer Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.671 [5680.5680] INFO SampleApp: Stopping playback Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.692 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: Clearing all streams Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.693 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: [Stream 3]: Disposing of audio stream Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.694 [5680.5680] INFO ContentFetcher: [0xacfe34b8]: Canceling download Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.702 [5680.5680] INFO SampleApp: Playback state changed: Stopped Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.702 [5680.5680] INFO StreamingEventReporter: [0xace18950]: Reporting streaming ended event Sep 05 22:48:32 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:32 rivo-toshi volumio[3269]: info: CoreCommandRouter::servicePushState Sep 05 22:48:32 rivo-toshi volumio[3269]: info: CoreStateMachine::pushState Sep 05 22:48:32 rivo-toshi volumio[3269]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:48:32 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioPushState Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.703 [5680.5680] INFO HttpDownloader: [0xace189a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.706 [5680.5680] INFO ProtocolHandler: [0xace18538]: Need to send state update Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.707 [5680.5680] INFO QConnectMessageSender: [0xace1b728]: Sending StateUpdated message Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.708 [5680.5680] INFO SampleApp: [Stream 3]: Audio stream has been disposed of Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.708 [5680.5680] INFO MediaEngine: [0xace17df8]: Setting current track: 303218005, initial offset: 0ms Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.709 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: Clearing all streams Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.709 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: New stream: 5 Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.709 [5680.5680] INFO HttpDownloader: [0xacf0f4d8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=31c3950b35f3102e7cc04f41fda6f470&request_ts=1757080112&track_id=303218005 Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.709 [5680.5680] INFO HttpDownloader: [0xad09b8e8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=303218005 Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.709 [5680.5680] INFO HttpDownloader: [0xad1fbd78]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=50962a73918cff692311df5cdf2e6573&request_ts=1757080112&track_id=303218005 Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.709 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: [Stream 5]: Running audio stream Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.709 [5680.5680] INFO QwspMessageSender: [0xacf645a0]: Sending Payload message Sep 05 22:48:32 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:32 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:48:32 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output Sep 05 22:48:32 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:32 rivo-toshi volumio[3269]: info: CoreCommandRouter::servicePushState Sep 05 22:48:32 rivo-toshi volumio[3269]: info: CoreStateMachine::pushState Sep 05 22:48:32 rivo-toshi volumio[3269]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:48:32 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioPushState Sep 05 22:48:32 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:32 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:48:32 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.765 [5680.5680] INFO MediaEngine: [0xace17df8]: Setting next track: 303218006 Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.766 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: New stream: 6 Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.766 [5680.5680] INFO HttpDownloader: [0xacf460f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=5535473ec95309bda742e2d592795b38&request_ts=1757080112&track_id=303218006 Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.766 [5680.5680] INFO HttpDownloader: [0xacf16920]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=303218006 Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.766 [5680.5680] INFO HttpDownloader: [0xacf34708]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=82a21581c7154b25650793bdb54877e9&request_ts=1757080112&track_id=303218006 Sep 05 22:48:32 rivo-toshi qobuz-connect[5680]: 20250905 22:48:32.768 [5680.5680] INFO MediaEngine: [0xace17df8]: Waiting for current stream to start before starting audio renderer Sep 05 22:48:32 rivo-toshi volumio[3269]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:48:32 rivo-toshi volumio[3269]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:48:32 rivo-toshi volumio[3269]: info: Updating RAAT Signal Path Sep 05 22:48:32 rivo-toshi volumio[3269]: info: Updating RAAT Signal Path Sep 05 22:48:32 rivo-toshi volumio[3269]: info: MCU Signalled Playback Inactive Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.525 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: [Stream 6]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=303218006&fmt=7&profile=raw&app_id=174516466&cid=2464732&etsp=1757083713&hmac=woGrJzZQ46o7pWSZJTj62YLzYzs Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.534 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: [Stream 5]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=303218005&fmt=7&profile=raw&app_id=174516466&cid=2464732&etsp=1757083713&hmac=-OGLt4OtS016yzuo34rut-4EVtg Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.619 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: [Stream 6]: Metadata became available: Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.619 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: Title: Tarantos - Una Mina En Asturias (En Directo en Oviedo, 13 de Septiembre de 1991) Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.619 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: Artist: カマロン・デ・ラ・イスラ Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.619 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: Album: Oviedo, San Mateo 1991 (En Directo en Oviedo, 13 de Septiembre de 1991) Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.619 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: Album art URL: https://static.qobuz.com/images/covers/ca/lz/ggzw1pyijlzca_600.jpg Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.680 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: [Stream 5]: Metadata became available: Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.680 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: Title: Alegrías - Soy Aquel Contrabandista (En Directo en Oviedo, 13 de Septiembre de 1991) Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.681 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: Artist: カマロン・デ・ラ・イスラ Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.681 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: Album: Oviedo, San Mateo 1991 (En Directo en Oviedo, 13 de Septiembre de 1991) Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.681 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: Album art URL: https://static.qobuz.com/images/covers/ca/lz/ggzw1pyijlzca_600.jpg Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.682 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: [Stream 6]: stream information have been fetched Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.703 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: [Stream 5]: stream information have been fetched Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.703 [5680.5680] INFO UrlAudioSource: [0xace1c5f8]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=303218005&fmt=7&profile=raw&app_id=174516466&cid=2464732&etsp=1757083713&hmac=-OGLt4OtS016yzuo34rut-4EVtg Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.703 [5680.5680] INFO ContentFetcher: [0xacfe34b8]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=303218005&fmt=7&profile=raw&app_id=174516466&cid=2464732&etsp=1757083713&hmac=-OGLt4OtS016yzuo34rut-4EVtg, offset: 0 Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.703 [5680.5680] INFO AudioRenderer: [0xace18018]: Starting audio renderer, initial playback state: Playing Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.703 [5680.5680] INFO SampleApp: [Stream 5]: New audio stream (starting from 0ms) Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.703 [5680.5680] INFO SampleApp: [Stream 5]: Stream metadata became available: Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.703 [5680.5680] INFO SampleApp: Title: Alegrías - Soy Aquel Contrabandista (En Directo en Oviedo, 13 de Septiembre de 1991) Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.703 [5680.5680] INFO SampleApp: Artist: カマロン・デ・ラ・イスラ Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.704 [5680.5680] INFO SampleApp: Album: Oviedo, San Mateo 1991 (En Directo en Oviedo, 13 de Septiembre de 1991) Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.704 [5680.5680] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/ca/lz/ggzw1pyijlzca_600.jpg Sep 05 22:48:33 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:33 rivo-toshi volumio[3269]: info: QobuzConnect: alread currrent service, send playback confirmation Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.706 [5680.5680] INFO SampleApp: Initiating playback, initial state: Playing Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.888 [5680.5680] INFO SampleApp: Playback state changed: Playing Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.888 [5680.5680] INFO StreamingEventReporter: [0xace18950]: Reporting streaming started event Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.889 [5680.5680] INFO ProtocolHandler: [0xace18538]: Need to send state update Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.889 [5680.5680] INFO QConnectMessageSender: [0xace1b728]: Sending StateUpdated message Sep 05 22:48:33 rivo-toshi qobuz-connect[5680]: 20250905 22:48:33.889 [5680.5680] INFO QwspMessageSender: [0xacf645a0]: Sending Payload message Sep 05 22:48:33 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:33 rivo-toshi volumio[3269]: info: CoreCommandRouter::servicePushState Sep 05 22:48:33 rivo-toshi volumio[3269]: info: CoreStateMachine::pushState Sep 05 22:48:33 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioPushState Sep 05 22:48:33 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:33 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:48:33 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output Sep 05 22:48:33 rivo-toshi volumio[3269]: info: CoreCommandRouter::servicePushState Sep 05 22:48:33 rivo-toshi volumio[3269]: info: CoreStateMachine::pushState Sep 05 22:48:33 rivo-toshi volumio[3269]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:48:33 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioPushState Sep 05 22:48:33 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:33 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:48:33 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output Sep 05 22:48:33 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:33 rivo-toshi volumio[3269]: info: CoreCommandRouter::servicePushState Sep 05 22:48:33 rivo-toshi volumio[3269]: info: CoreStateMachine::pushState Sep 05 22:48:33 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioPushState Sep 05 22:48:33 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:33 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:48:33 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output Sep 05 22:48:33 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:33 rivo-toshi volumio[3269]: info: CoreCommandRouter::servicePushState Sep 05 22:48:33 rivo-toshi volumio[3269]: info: CoreStateMachine::pushState Sep 05 22:48:33 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioPushState Sep 05 22:48:33 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:33 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:48:33 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output Sep 05 22:48:33 rivo-toshi volumio[3269]: info: Signalling Playback active due to playback status change Sep 05 22:48:33 rivo-toshi volumio[3269]: info: Signalling Playback active due to playback status change Sep 05 22:48:33 rivo-toshi volumio[3269]: info: Signalling Playback active due to playback status change Sep 05 22:48:33 rivo-toshi volumio[3269]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:48:33 rivo-toshi volumio[3269]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:48:33 rivo-toshi volumio[3269]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:48:33 rivo-toshi volumio[3269]: info: Updating RAAT Signal Path Sep 05 22:48:33 rivo-toshi volumio[3269]: info: Updating RAAT Signal Path Sep 05 22:48:33 rivo-toshi volumio[3269]: info: Updating RAAT Signal Path Sep 05 22:48:33 rivo-toshi volumio[3269]: info: Updating RAAT Signal Path Sep 05 22:48:34 rivo-toshi volumio[3269]: info: MCU Signalled Playback Active Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.057 [5680.5680] INFO ProtocolHandler: [0xace18538]: Received SetState message: Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.057 [5680.5680] INFO ProtocolHandler: [0xace18538]: Playing state: Playing Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.057 [5680.5680] INFO ProtocolHandler: [0xace18538]: Playback position: 0 Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.057 [5680.5680] INFO ProtocolHandler: [0xace18538]: Queue version: 13.1 Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.057 [5680.5680] INFO ProtocolHandler: [0xace18538]: Current track: TID: 303218005, QID: 28, Context UUID: ab72e379-c786-406d-bf82-97ea70b8d80d Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.058 [5680.5680] INFO ProtocolHandler: [0xace18538]: Next track: TID: 303218006, QID: 29, Context UUID: ab72e379-c786-406d-bf82-97ea70b8d80d Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.058 [5680.5680] INFO MediaEngine: [0xace17df8]: Stopping playback, clearing tracks Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.058 [5680.5680] INFO MediaEngine: [0xace17df8]: Initiating playback Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.058 [5680.5680] INFO AudioRenderer: [0xace18018]: Stopping audio renderer Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.058 [5680.5680] INFO SampleApp: Stopping playback Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.068 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: Clearing all streams Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.069 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: [Stream 5]: Disposing of audio stream Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.069 [5680.5680] INFO ContentFetcher: [0xacfe34b8]: Canceling download Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.105 [5680.5680] INFO SampleApp: Playback state changed: Stopped Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.106 [5680.5680] INFO StreamingEventReporter: [0xace18950]: Reporting streaming ended event Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.106 [5680.5680] INFO ProtocolHandler: [0xace18538]: Need to send state update Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.106 [5680.5680] INFO QConnectMessageSender: [0xace1b728]: Sending StateUpdated message Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.106 [5680.5680] INFO SampleApp: [Stream 5]: Audio stream has been disposed of Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.106 [5680.5680] INFO MediaEngine: [0xace17df8]: Setting current track: 303218005, initial offset: 0ms Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.106 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: Clearing all streams Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.106 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: New stream: 7 Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.106 [5680.5680] INFO HttpDownloader: [0xad0aacb0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=4f7c01fb5d825eed41b53ccb85347758&request_ts=1757080114&track_id=303218005 Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.106 [5680.5680] INFO HttpDownloader: [0xad4a0430]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=303218005 Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.107 [5680.5680] INFO HttpDownloader: [0xace4ec40]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=b70f275b2b7fb4e4bf235c1da41ce703&request_ts=1757080114&track_id=303218005 Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.107 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: [Stream 7]: Running audio stream Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.107 [5680.5680] INFO QwspMessageSender: [0xacf645a0]: Sending Payload message Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::servicePushState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreStateMachine::pushState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioPushState Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.109 [5680.5680] INFO MediaEngine: [0xace17df8]: Setting next track: 303218006 Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.109 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: New stream: 8 Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.109 [5680.5680] INFO HttpDownloader: [0xad431c68]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=7c8978390b6381d01f3a03a8361adb19&request_ts=1757080114&track_id=303218006 Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.109 [5680.5680] INFO HttpDownloader: [0xad42e2b0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=303218006 Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.110 [5680.5680] INFO HttpDownloader: [0xad48fbf8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=f431a5599c5bb7740ab07fe0b3893523&request_ts=1757080114&track_id=303218006 Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.111 [5680.5680] INFO MediaEngine: [0xace17df8]: Waiting for current stream to start before starting audio renderer Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:48:34 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::servicePushState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreStateMachine::pushState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioPushState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:48:34 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output Sep 05 22:48:34 rivo-toshi volumio[3269]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:48:34 rivo-toshi volumio[3269]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:48:34 rivo-toshi volumio[3269]: info: Updating RAAT Signal Path Sep 05 22:48:34 rivo-toshi volumio[3269]: info: MCU Signalled Playback Inactive Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.411 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: [Stream 7]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=303218005&fmt=7&profile=raw&app_id=174516466&cid=2464732&etsp=1757083714&hmac=YL6snmhODg9GY1_2_El5W_Xbp_Q Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.436 [5680.5680] INFO HttpDownloader: [0xace189a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.515 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: [Stream 7]: stream information have been fetched Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.515 [5680.5680] INFO UrlAudioSource: [0xad3a4e60]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=303218005&fmt=7&profile=raw&app_id=174516466&cid=2464732&etsp=1757083714&hmac=YL6snmhODg9GY1_2_El5W_Xbp_Q Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.515 [5680.5680] INFO ContentFetcher: [0xad566bc8]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=303218005&fmt=7&profile=raw&app_id=174516466&cid=2464732&etsp=1757083714&hmac=YL6snmhODg9GY1_2_El5W_Xbp_Q, offset: 0 Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.516 [5680.5680] INFO AudioRenderer: [0xace18018]: Starting audio renderer, initial playback state: Playing Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.516 [5680.5680] INFO SampleApp: [Stream 7]: New audio stream (starting from 0ms) Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: QobuzConnect: alread currrent service, send playback confirmation Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.518 [5680.5680] INFO SampleApp: Initiating playback, initial state: Playing Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.722 [5680.5680] INFO SampleApp: Playback state changed: Playing Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.722 [5680.5680] INFO StreamingEventReporter: [0xace18950]: Reporting streaming started event Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.722 [5680.5680] INFO ProtocolHandler: [0xace18538]: Need to send state update Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.722 [5680.5680] INFO QConnectMessageSender: [0xace1b728]: Sending StateUpdated message Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.723 [5680.5680] INFO QwspMessageSender: [0xacf645a0]: Sending Payload message Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.724 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: [Stream 8]: stream information have been fetched Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::servicePushState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreStateMachine::pushState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioPushState Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.724 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: [Stream 7]: Metadata became available: Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.724 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: Title: Alegrías - Soy Aquel Contrabandista (En Directo en Oviedo, 13 de Septiembre de 1991) Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.724 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: Artist: カマロン・デ・ラ・イスラ Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.724 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: Album: Oviedo, San Mateo 1991 (En Directo en Oviedo, 13 de Septiembre de 1991) Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.725 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: Album art URL: https://static.qobuz.com/images/covers/ca/lz/ggzw1pyijlzca_600.jpg Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.725 [5680.5680] INFO SampleApp: [Stream 7]: Stream metadata became available: Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.725 [5680.5680] INFO SampleApp: Title: Alegrías - Soy Aquel Contrabandista (En Directo en Oviedo, 13 de Septiembre de 1991) Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.725 [5680.5680] INFO SampleApp: Artist: カマロン・デ・ラ・イスラ Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.725 [5680.5680] INFO SampleApp: Album: Oviedo, San Mateo 1991 (En Directo en Oviedo, 13 de Septiembre de 1991) Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.725 [5680.5680] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/ca/lz/ggzw1pyijlzca_600.jpg Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:48:34 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.734 [5680.5680] INFO HttpDownloader: [0xace189a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::servicePushState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreStateMachine::pushState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioPushState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:48:34 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::servicePushState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreStateMachine::pushState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioPushState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:48:34 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::servicePushState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreStateMachine::pushState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioPushState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:48:34 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::servicePushState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreStateMachine::pushState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioPushState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: CoreCommandRouter::volumioGetState Sep 05 22:48:34 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:48:34 rivo-toshi volumio[3269]: info: MRS: Pushing multiroomSync output Sep 05 22:48:34 rivo-toshi volumio[3269]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Sep 05 22:48:34 rivo-toshi volumio[3269]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.885 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: [Stream 8]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=303218006&fmt=7&profile=raw&app_id=174516466&cid=2464732&etsp=1757083714&hmac=8zolpfnED5De_aSQgK2b4Wv5Sow Sep 05 22:48:34 rivo-toshi volumio[3269]: TypeError: Cannot read property 'indexOf' of undefined Sep 05 22:48:34 rivo-toshi volumio[3269]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Sep 05 22:48:34 rivo-toshi volumio[3269]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Sep 05 22:48:34 rivo-toshi volumio[3269]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Sep 05 22:48:34 rivo-toshi volumio[3269]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Sep 05 22:48:34 rivo-toshi volumio[3269]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Sep 05 22:48:34 rivo-toshi volumio[3269]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Sep 05 22:48:34 rivo-toshi volumio[3269]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Sep 05 22:48:34 rivo-toshi volumio[3269]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Sep 05 22:48:34 rivo-toshi volumio[3269]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Sep 05 22:48:34 rivo-toshi volumio[3269]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Sep 05 22:48:34 rivo-toshi volumio[3269]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Sep 05 22:48:34 rivo-toshi volumio[3269]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Sep 05 22:48:34 rivo-toshi volumio[3269]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Sep 05 22:48:34 rivo-toshi volumio[3269]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Sep 05 22:48:34 rivo-toshi volumio[3269]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Sep 05 22:48:34 rivo-toshi volumio[3269]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Sep 05 22:48:34 rivo-toshi volumio[3269]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.913 [5680.5680] INFO UrlAudioSource: [0xad3a4e60]: Content info extracted Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.913 [5680.5680] INFO UrlAudioSource: [0xad3a4e60]: Size: 185348806 Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.919 [5680.9566] INFO AudioDecoder: [0xad398d98]: Stream info: Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.920 [5680.9566] INFO AudioDecoder: [0xad398d98]: Total samples: 53115104 Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.920 [5680.9566] INFO AudioDecoder: [0xad398d98]: Sample rate: 96000 Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.920 [5680.9566] INFO AudioDecoder: [0xad398d98]: Channel count: 2 Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.920 [5680.9566] INFO AudioDecoder: [0xad398d98]: Bits per sample: 24 Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.921 [5680.9566] INFO AudioDecoder: [0xad398d98]: Duration: 553282 Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.921 [5680.5680] INFO UrlAudioSource: [0xad3a4e60]: Audio properties have changed Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.945 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: [Stream 8]: Metadata became available: Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.949 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: Title: Tarantos - Una Mina En Asturias (En Directo en Oviedo, 13 de Septiembre de 1991) Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.950 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: Artist: カマロン・デ・ラ・イスラ Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.951 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: Album: Oviedo, San Mateo 1991 (En Directo en Oviedo, 13 de Septiembre de 1991) Sep 05 22:48:34 rivo-toshi qobuz-connect[5680]: 20250905 22:48:34.951 [5680.5680] INFO AudioStreamManager: [0xace17ee0]: Album art URL: https://static.qobuz.com/images/covers/ca/lz/ggzw1pyijlzca_600.jpg Sep 05 22:48:35 rivo-toshi qobuz-connect[5680]: 20250905 22:48:35.105 [5680.5680] INFO HttpDownloader: [0xace189a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Sep 05 22:48:35 rivo-toshi sudo[9576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 22:47 Sep 05 22:48:35 rivo-toshi sudo[9576]: 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="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:43:09 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="fc7d09bc07ffd188bff4808d8f79c746"