-- Logs begin at Sat 2025-05-17 09:27:53 JST, end at Fri 2025-09-05 22:04:20 JST. -- Sep 05 22:03:02 rivo-toshi qobuz-connect[3921]: 20250905 22:03:02.329 [3921.3921] INFO ProtocolHandler: [0xacdd0538]: Need to send state update Sep 05 22:03:02 rivo-toshi qobuz-connect[3921]: 20250905 22:03:02.329 [3921.3921] INFO QConnectMessageSender: [0xacf55ef0]: Sending StateUpdated message Sep 05 22:03:02 rivo-toshi qobuz-connect[3921]: 20250905 22:03:02.329 [3921.3921] INFO QwspMessageSender: [0xacec0890]: Sending Payload message Sep 05 22:03:02 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:03:02 rivo-toshi volumio[3260]: info: CoreCommandRouter::servicePushState Sep 05 22:03:02 rivo-toshi volumio[3260]: info: CoreStateMachine::pushState Sep 05 22:03:02 rivo-toshi volumio[3260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:03:02 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioPushState Sep 05 22:03:02 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:03:02 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:03:02 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output Sep 05 22:03:02 rivo-toshi volumio[3260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:03:12 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:03:12 rivo-toshi volumio[3260]: info: CoreCommandRouter::servicePushState Sep 05 22:03:12 rivo-toshi volumio[3260]: info: CoreStateMachine::pushState Sep 05 22:03:12 rivo-toshi volumio[3260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:03:12 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioPushState Sep 05 22:03:12 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:03:12 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:03:12 rivo-toshi qobuz-connect[3921]: 20250905 22:03:12.339 [3921.3921] INFO ProtocolHandler: [0xacdd0538]: Need to send state update Sep 05 22:03:12 rivo-toshi qobuz-connect[3921]: 20250905 22:03:12.339 [3921.3921] INFO QConnectMessageSender: [0xacf55ef0]: Sending StateUpdated message Sep 05 22:03:12 rivo-toshi qobuz-connect[3921]: 20250905 22:03:12.339 [3921.3921] INFO QwspMessageSender: [0xacec0890]: Sending Payload message Sep 05 22:03:12 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output Sep 05 22:03:12 rivo-toshi volumio[3260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:03:22 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:03:22 rivo-toshi volumio[3260]: info: CoreCommandRouter::servicePushState Sep 05 22:03:22 rivo-toshi volumio[3260]: info: CoreStateMachine::pushState Sep 05 22:03:22 rivo-toshi volumio[3260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:03:22 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioPushState Sep 05 22:03:22 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:03:22 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:03:22 rivo-toshi qobuz-connect[3921]: 20250905 22:03:22.339 [3921.3921] INFO ProtocolHandler: [0xacdd0538]: Need to send state update Sep 05 22:03:22 rivo-toshi qobuz-connect[3921]: 20250905 22:03:22.339 [3921.3921] INFO QConnectMessageSender: [0xacf55ef0]: Sending StateUpdated message Sep 05 22:03:22 rivo-toshi qobuz-connect[3921]: 20250905 22:03:22.340 [3921.3921] INFO QwspMessageSender: [0xacec0890]: Sending Payload message Sep 05 22:03:22 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output Sep 05 22:03:22 rivo-toshi volumio[3260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:03:32 rivo-toshi qobuz-connect[3921]: 20250905 22:03:32.329 [3921.3921] INFO ProtocolHandler: [0xacdd0538]: Need to send state update Sep 05 22:03:32 rivo-toshi qobuz-connect[3921]: 20250905 22:03:32.329 [3921.3921] INFO QConnectMessageSender: [0xacf55ef0]: Sending StateUpdated message Sep 05 22:03:32 rivo-toshi qobuz-connect[3921]: 20250905 22:03:32.329 [3921.3921] INFO QwspMessageSender: [0xacec0890]: Sending Payload message Sep 05 22:03:32 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:03:32 rivo-toshi volumio[3260]: info: CoreCommandRouter::servicePushState Sep 05 22:03:32 rivo-toshi volumio[3260]: info: CoreStateMachine::pushState Sep 05 22:03:32 rivo-toshi volumio[3260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:03:32 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioPushState Sep 05 22:03:32 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:03:32 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:03:32 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output Sep 05 22:03:32 rivo-toshi volumio[3260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:03:42 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:03:42 rivo-toshi volumio[3260]: info: CoreCommandRouter::servicePushState Sep 05 22:03:42 rivo-toshi volumio[3260]: info: CoreStateMachine::pushState Sep 05 22:03:42 rivo-toshi volumio[3260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:03:42 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioPushState Sep 05 22:03:42 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:03:42 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:03:42 rivo-toshi qobuz-connect[3921]: 20250905 22:03:42.340 [3921.3921] INFO ProtocolHandler: [0xacdd0538]: Need to send state update Sep 05 22:03:42 rivo-toshi qobuz-connect[3921]: 20250905 22:03:42.340 [3921.3921] INFO QConnectMessageSender: [0xacf55ef0]: Sending StateUpdated message Sep 05 22:03:42 rivo-toshi qobuz-connect[3921]: 20250905 22:03:42.340 [3921.3921] INFO QwspMessageSender: [0xacec0890]: Sending Payload message Sep 05 22:03:42 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output Sep 05 22:03:42 rivo-toshi volumio[3260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:03:50 rivo-toshi volumiologrotate[2843]: ls: cannot access '/var/log/samba/log.wb-RIVO': No such file or directory Sep 05 22:03:51 rivo-toshi volumiologrotate[2843]: ls: cannot access 'TOSHI': No such file or directory Sep 05 22:03:52 rivo-toshi qobuz-connect[3921]: 20250905 22:03:52.328 [3921.3921] INFO ProtocolHandler: [0xacdd0538]: Need to send state update Sep 05 22:03:52 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:03:52 rivo-toshi volumio[3260]: info: CoreCommandRouter::servicePushState Sep 05 22:03:52 rivo-toshi volumio[3260]: info: CoreStateMachine::pushState Sep 05 22:03:52 rivo-toshi volumio[3260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:03:52 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioPushState Sep 05 22:03:52 rivo-toshi qobuz-connect[3921]: 20250905 22:03:52.333 [3921.3921] INFO QConnectMessageSender: [0xacf55ef0]: Sending StateUpdated message Sep 05 22:03:52 rivo-toshi qobuz-connect[3921]: 20250905 22:03:52.334 [3921.3921] INFO QwspMessageSender: [0xacec0890]: Sending Payload message Sep 05 22:03:52 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:03:52 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:03:52 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output Sep 05 22:03:52 rivo-toshi volumio[3260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:03:55 rivo-toshi volumio[3260]: error: [LastFM] plugin is not authenticated, please retry Sep 05 22:04:02 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:04:02 rivo-toshi volumio[3260]: info: CoreCommandRouter::servicePushState Sep 05 22:04:02 rivo-toshi volumio[3260]: info: CoreStateMachine::pushState Sep 05 22:04:02 rivo-toshi volumio[3260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:04:02 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioPushState Sep 05 22:04:02 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:04:02 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:04:02 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output Sep 05 22:04:02 rivo-toshi qobuz-connect[3921]: 20250905 22:04:02.342 [3921.3921] INFO ProtocolHandler: [0xacdd0538]: Need to send state update Sep 05 22:04:02 rivo-toshi qobuz-connect[3921]: 20250905 22:04:02.342 [3921.3921] INFO QConnectMessageSender: [0xacf55ef0]: Sending StateUpdated message Sep 05 22:04:02 rivo-toshi qobuz-connect[3921]: 20250905 22:04:02.342 [3921.3921] INFO QwspMessageSender: [0xacec0890]: Sending Payload message Sep 05 22:04:02 rivo-toshi volumio[3260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:04:12 rivo-toshi qobuz-connect[3921]: 20250905 22:04:12.328 [3921.3921] INFO ProtocolHandler: [0xacdd0538]: Need to send state update Sep 05 22:04:12 rivo-toshi qobuz-connect[3921]: 20250905 22:04:12.329 [3921.3921] INFO QConnectMessageSender: [0xacf55ef0]: Sending StateUpdated message Sep 05 22:04:12 rivo-toshi qobuz-connect[3921]: 20250905 22:04:12.329 [3921.3921] INFO QwspMessageSender: [0xacec0890]: Sending Payload message Sep 05 22:04:12 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:04:12 rivo-toshi volumio[3260]: info: CoreCommandRouter::servicePushState Sep 05 22:04:12 rivo-toshi volumio[3260]: info: CoreStateMachine::pushState Sep 05 22:04:12 rivo-toshi volumio[3260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:04:12 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioPushState Sep 05 22:04:12 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:04:12 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:04:12 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output Sep 05 22:04:12 rivo-toshi volumio[3260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.369 [3921.3921] INFO ProtocolHandler: [0xacdd0538]: Received SetState message: Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.370 [3921.3921] INFO ProtocolHandler: [0xacdd0538]: Playing state: Playing Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.370 [3921.3921] INFO ProtocolHandler: [0xacdd0538]: Playback position: 0 Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.370 [3921.3921] INFO ProtocolHandler: [0xacdd0538]: Queue version: 2.1 Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.370 [3921.3921] INFO ProtocolHandler: [0xacdd0538]: Current track: TID: 96193447, QID: 0, Context UUID: 42cf81d9-55f1-43bd-babe-067ccf1c39d5 Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.370 [3921.3921] INFO ProtocolHandler: [0xacdd0538]: Next track: TID: 96193448, QID: 1, Context UUID: 42cf81d9-55f1-43bd-babe-067ccf1c39d5 Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.370 [3921.3921] INFO MediaEngine: [0xacdcfdf8]: Stopping playback, clearing tracks Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.370 [3921.3921] INFO MediaEngine: [0xacdcfdf8]: Initiating playback Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.378 [3921.3921] INFO AudioRenderer: [0xacdd0018]: Stopping audio renderer Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.378 [3921.3921] INFO SampleApp: Stopping playback Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.398 [3921.3921] INFO AudioStreamManager: [0xacdcfee0]: Clearing all streams Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.398 [3921.3921] INFO AudioStreamManager: [0xacdcfee0]: [Stream 4]: Disposing of audio stream Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.398 [3921.3921] INFO ContentFetcher: [0xacdbb3b8]: Canceling download Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.406 [3921.3921] INFO SampleApp: Playback state changed: Stopped Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.406 [3921.3921] INFO StreamingEventReporter: [0xacdd0950]: Reporting streaming ended event Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.406 [3921.3921] INFO HttpDownloader: [0xacdd09a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.407 [3921.3921] INFO ProtocolHandler: [0xacdd0538]: Need to send state update Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.407 [3921.3921] INFO QConnectMessageSender: [0xacf55ef0]: Sending StateUpdated message Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.407 [3921.3921] INFO SampleApp: [Stream 4]: Audio stream has been disposed of Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.407 [3921.3921] INFO MediaEngine: [0xacdcfdf8]: Setting current track: 96193447, initial offset: 0ms Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.407 [3921.3921] INFO AudioStreamManager: [0xacdcfee0]: Clearing all streams Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.407 [3921.3921] INFO AudioStreamManager: [0xacdcfee0]: New stream: 6 Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.407 [3921.3921] INFO HttpDownloader: [0xaceb9d30]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=e5a7c257ae4aec19dabad3c42bf29056&request_ts=1757077458&track_id=96193447 Sep 05 22:04:18 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:04:18 rivo-toshi volumio[3260]: info: CoreCommandRouter::servicePushState Sep 05 22:04:18 rivo-toshi volumio[3260]: info: CoreStateMachine::pushState Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.408 [3921.3921] INFO HttpDownloader: [0xace0f1e8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=96193447 Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.408 [3921.3921] INFO HttpDownloader: [0xacec17f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=b9e2be48a629a9287b5100acdf7f1edb&request_ts=1757077458&track_id=96193447 Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.408 [3921.3921] INFO AudioStreamManager: [0xacdcfee0]: [Stream 6]: Running audio stream Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.408 [3921.3921] INFO QwspMessageSender: [0xacec0890]: Sending Payload message Sep 05 22:04:18 rivo-toshi volumio[3260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:04:18 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioPushState Sep 05 22:04:18 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:04:18 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:04:18 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output Sep 05 22:04:18 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:04:18 rivo-toshi volumio[3260]: info: CoreCommandRouter::servicePushState Sep 05 22:04:18 rivo-toshi volumio[3260]: info: CoreStateMachine::pushState Sep 05 22:04:18 rivo-toshi volumio[3260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:04:18 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioPushState Sep 05 22:04:18 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:04:18 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:04:18 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.469 [3921.3921] INFO MediaEngine: [0xacdcfdf8]: Setting next track: 96193448 Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.469 [3921.3921] INFO AudioStreamManager: [0xacdcfee0]: New stream: 7 Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.469 [3921.3921] INFO HttpDownloader: [0xad487338]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=39cec4a6680ec333c42fb177b0c463b8&request_ts=1757077458&track_id=96193448 Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.469 [3921.3921] INFO HttpDownloader: [0xacf0f5b8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=96193448 Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.470 [3921.3921] INFO HttpDownloader: [0xace32d10]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=50dca03fac0c9796f05928e4326cf1b0&request_ts=1757077458&track_id=96193448 Sep 05 22:04:18 rivo-toshi qobuz-connect[3921]: 20250905 22:04:18.471 [3921.3921] INFO MediaEngine: [0xacdcfdf8]: Waiting for current stream to start before starting audio renderer Sep 05 22:04:18 rivo-toshi volumio[3260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:04:18 rivo-toshi volumio[3260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 22:04:18 rivo-toshi volumio[3260]: info: MCU Signalled Playback Inactive Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.329 [3921.3921] INFO AudioStreamManager: [0xacdcfee0]: [Stream 6]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=96193447&fmt=6&profile=raw&app_id=174516466&cid=2464732&etsp=1757081059&hmac=uvl0tanecbu0XMdxrFkrjHc21UI Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.329 [3921.3921] INFO AudioStreamManager: [0xacdcfee0]: [Stream 7]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=96193448&fmt=6&profile=raw&app_id=174516466&cid=2464732&etsp=1757081059&hmac=bieAYGR17OyPXa8BzJ0k2OkAN6g Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.365 [3921.3921] INFO AudioStreamManager: [0xacdcfee0]: [Stream 6]: stream information have been fetched Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.365 [3921.3921] INFO UrlAudioSource: [0xad01ce70]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=96193447&fmt=6&profile=raw&app_id=174516466&cid=2464732&etsp=1757081059&hmac=uvl0tanecbu0XMdxrFkrjHc21UI Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.365 [3921.3921] INFO ContentFetcher: [0xace2f3d0]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=96193447&fmt=6&profile=raw&app_id=174516466&cid=2464732&etsp=1757081059&hmac=uvl0tanecbu0XMdxrFkrjHc21UI, offset: 0 Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.365 [3921.3921] INFO AudioRenderer: [0xacdd0018]: Starting audio renderer, initial playback state: Playing Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.365 [3921.3921] INFO SampleApp: [Stream 6]: New audio stream (starting from 0ms) Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:04:19 rivo-toshi volumio[3260]: info: QobuzConnect: alread currrent service, send playback confirmation Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.369 [3921.3921] INFO SampleApp: Initiating playback, initial state: Playing Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.594 [3921.3921] INFO SampleApp: Playback state changed: Playing Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.595 [3921.3921] INFO StreamingEventReporter: [0xacdd0950]: Reporting streaming started event Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.595 [3921.3921] INFO HttpDownloader: [0xacdd09a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.595 [3921.3921] INFO ProtocolHandler: [0xacdd0538]: Need to send state update Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.595 [3921.3921] INFO QConnectMessageSender: [0xacf55ef0]: Sending StateUpdated message Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.595 [3921.3921] INFO QwspMessageSender: [0xacec0890]: Sending Payload message Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreCommandRouter::servicePushState Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreStateMachine::pushState Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioPushState Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.597 [3921.3921] INFO QConnectMessageSender: [0xacf55ef0]: Sending FileAudioQualityChanged message Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.597 [3921.3921] INFO QwspMessageSender: [0xacec0890]: Sending Payload message Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.599 [3921.3921] INFO AudioStreamManager: [0xacdcfee0]: [Stream 7]: stream information have been fetched Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.599 [3921.3921] INFO AudioStreamManager: [0xacdcfee0]: [Stream 6]: Metadata became available: Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.599 [3921.3921] INFO AudioStreamManager: [0xacdcfee0]: Title: Pap's Blues Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.599 [3921.3921] INFO AudioStreamManager: [0xacdcfee0]: Artist: Herb Ellis Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.599 [3921.3921] INFO AudioStreamManager: [0xacdcfee0]: Album: Nothing but the Blues Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.600 [3921.3921] INFO AudioStreamManager: [0xacdcfee0]: Album art URL: https://static.qobuz.com/images/covers/4a/2v/yco78yg3d2v4a_600.jpg Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.600 [3921.3921] INFO SampleApp: [Stream 6]: Stream metadata became available: Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.600 [3921.3921] INFO SampleApp: Title: Pap's Blues Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.600 [3921.3921] INFO SampleApp: Artist: Herb Ellis Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.600 [3921.3921] INFO SampleApp: Album: Nothing but the Blues Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.600 [3921.3921] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/4a/2v/yco78yg3d2v4a_600.jpg Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:04:19 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:04:19 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreCommandRouter::servicePushState Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreStateMachine::pushState Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioPushState Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:04:19 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:04:19 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreCommandRouter::servicePushState Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreStateMachine::pushState Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioPushState Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:04:19 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:04:19 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreCommandRouter::servicePushState Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreStateMachine::pushState Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioPushState Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:04:19 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:04:19 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreCommandRouter::servicePushState Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreStateMachine::pushState Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioPushState Sep 05 22:04:19 rivo-toshi volumio[3260]: info: CoreCommandRouter::volumioGetState Sep 05 22:04:19 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output update for this device Sep 05 22:04:19 rivo-toshi volumio[3260]: info: MRS: Pushing multiroomSync output Sep 05 22:04:19 rivo-toshi volumio[3260]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Sep 05 22:04:19 rivo-toshi volumio[3260]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 22:04:19 rivo-toshi volumio[3260]: TypeError: Cannot read property 'indexOf' of undefined Sep 05 22:04:19 rivo-toshi volumio[3260]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Sep 05 22:04:19 rivo-toshi volumio[3260]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Sep 05 22:04:19 rivo-toshi volumio[3260]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Sep 05 22:04:19 rivo-toshi volumio[3260]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Sep 05 22:04:19 rivo-toshi volumio[3260]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Sep 05 22:04:19 rivo-toshi volumio[3260]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Sep 05 22:04:19 rivo-toshi volumio[3260]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Sep 05 22:04:19 rivo-toshi volumio[3260]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Sep 05 22:04:19 rivo-toshi volumio[3260]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Sep 05 22:04:19 rivo-toshi volumio[3260]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Sep 05 22:04:19 rivo-toshi volumio[3260]: 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:04:19 rivo-toshi volumio[3260]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Sep 05 22:04:19 rivo-toshi volumio[3260]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Sep 05 22:04:19 rivo-toshi volumio[3260]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Sep 05 22:04:19 rivo-toshi volumio[3260]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Sep 05 22:04:19 rivo-toshi volumio[3260]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Sep 05 22:04:19 rivo-toshi volumio[3260]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.877 [3921.3921] INFO AudioStreamManager: [0xacdcfee0]: [Stream 7]: Metadata became available: Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.877 [3921.3921] INFO AudioStreamManager: [0xacdcfee0]: Title: Big Red's Boogie Woogie Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.877 [3921.3921] INFO AudioStreamManager: [0xacdcfee0]: Artist: Herb Ellis Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.877 [3921.3921] INFO AudioStreamManager: [0xacdcfee0]: Album: Nothing but the Blues Sep 05 22:04:19 rivo-toshi qobuz-connect[3921]: 20250905 22:04:19.877 [3921.3921] INFO AudioStreamManager: [0xacdcfee0]: Album art URL: https://static.qobuz.com/images/covers/4a/2v/yco78yg3d2v4a_600.jpg Sep 05 22:04:20 rivo-toshi qobuz-connect[3921]: 20250905 22:04:20.333 [3921.3921] INFO ProtocolHandler: [0xacdd0538]: Received SetState message: Sep 05 22:04:20 rivo-toshi qobuz-connect[3921]: 20250905 22:04:20.333 [3921.3921] INFO ProtocolHandler: [0xacdd0538]: Playing state: Sep 05 22:04:20 rivo-toshi qobuz-connect[3921]: 20250905 22:04:20.333 [3921.3921] INFO ProtocolHandler: [0xacdd0538]: Playback position: Sep 05 22:04:20 rivo-toshi qobuz-connect[3921]: 20250905 22:04:20.333 [3921.3921] INFO ProtocolHandler: [0xacdd0538]: Queue version: 2.2 Sep 05 22:04:20 rivo-toshi qobuz-connect[3921]: 20250905 22:04:20.333 [3921.3921] INFO ProtocolHandler: [0xacdd0538]: Current track: Sep 05 22:04:20 rivo-toshi qobuz-connect[3921]: 20250905 22:04:20.334 [3921.3921] INFO ProtocolHandler: [0xacdd0538]: Next track: TID: 96193448, QID: 1, Context UUID: 42cf81d9-55f1-43bd-babe-067ccf1c39d5 Sep 05 22:04:20 rivo-toshi sudo[5858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 22:03 Sep 05 22:04:20 rivo-toshi sudo[5858]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 06:24:39 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="c8a59422b1bfedffc72f72cef58ad20b"