-- Logs begin at Sat 2025-05-17 09:27:53 JST, end at Thu 2025-05-29 22:08:29 JST. -- May 29 22:07:03 rivo-toshi qobuz-connect[3961]: 20250529 22:07:03.333 [3961.3961] INFO ProtocolHandler: [0xad0be538]: Need to send state update May 29 22:07:03 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:07:03 rivo-toshi volumio[3298]: info: CoreCommandRouter::servicePushState May 29 22:07:03 rivo-toshi volumio[3298]: info: CoreStateMachine::pushState May 29 22:07:03 rivo-toshi volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 22:07:03 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioPushState May 29 22:07:03 rivo-toshi qobuz-connect[3961]: 20250529 22:07:03.338 [3961.3961] INFO QConnectMessageSender: [0xad243f50]: Sending StateUpdated message May 29 22:07:03 rivo-toshi qobuz-connect[3961]: 20250529 22:07:03.338 [3961.3961] INFO QwspMessageSender: [0xad243f40]: Sending Payload message May 29 22:07:03 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:07:03 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output update for this device May 29 22:07:03 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output May 29 22:07:03 rivo-toshi volumio[3298]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 29 22:07:13 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:07:13 rivo-toshi volumio[3298]: info: CoreCommandRouter::servicePushState May 29 22:07:13 rivo-toshi volumio[3298]: info: CoreStateMachine::pushState May 29 22:07:13 rivo-toshi volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 22:07:13 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioPushState May 29 22:07:13 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:07:13 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output update for this device May 29 22:07:13 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output May 29 22:07:13 rivo-toshi qobuz-connect[3961]: 20250529 22:07:13.348 [3961.3961] INFO ProtocolHandler: [0xad0be538]: Need to send state update May 29 22:07:13 rivo-toshi qobuz-connect[3961]: 20250529 22:07:13.348 [3961.3961] INFO QConnectMessageSender: [0xad243f50]: Sending StateUpdated message May 29 22:07:13 rivo-toshi qobuz-connect[3961]: 20250529 22:07:13.348 [3961.3961] INFO QwspMessageSender: [0xad243f40]: Sending Payload message May 29 22:07:13 rivo-toshi volumio[3298]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 29 22:07:23 rivo-toshi qobuz-connect[3961]: 20250529 22:07:23.333 [3961.3961] INFO ProtocolHandler: [0xad0be538]: Need to send state update May 29 22:07:23 rivo-toshi qobuz-connect[3961]: 20250529 22:07:23.333 [3961.3961] INFO QConnectMessageSender: [0xad243f50]: Sending StateUpdated message May 29 22:07:23 rivo-toshi qobuz-connect[3961]: 20250529 22:07:23.333 [3961.3961] INFO QwspMessageSender: [0xad243f40]: Sending Payload message May 29 22:07:23 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:07:23 rivo-toshi volumio[3298]: info: CoreCommandRouter::servicePushState May 29 22:07:23 rivo-toshi volumio[3298]: info: CoreStateMachine::pushState May 29 22:07:23 rivo-toshi volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 22:07:23 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioPushState May 29 22:07:23 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:07:23 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output update for this device May 29 22:07:23 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output May 29 22:07:23 rivo-toshi volumio[3298]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 29 22:07:24 rivo-toshi volumiologrotate[2826]: ls: cannot access '/var/log/samba/log.wb-RIVO': No such file or directory May 29 22:07:24 rivo-toshi volumiologrotate[2826]: ls: cannot access 'TOSHI': No such file or directory May 29 22:07:33 rivo-toshi qobuz-connect[3961]: 20250529 22:07:33.333 [3961.3961] INFO ProtocolHandler: [0xad0be538]: Need to send state update May 29 22:07:33 rivo-toshi qobuz-connect[3961]: 20250529 22:07:33.333 [3961.3961] INFO QConnectMessageSender: [0xad243f50]: Sending StateUpdated message May 29 22:07:33 rivo-toshi qobuz-connect[3961]: 20250529 22:07:33.333 [3961.3961] INFO QwspMessageSender: [0xad243f40]: Sending Payload message May 29 22:07:33 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:07:33 rivo-toshi volumio[3298]: info: CoreCommandRouter::servicePushState May 29 22:07:33 rivo-toshi volumio[3298]: info: CoreStateMachine::pushState May 29 22:07:33 rivo-toshi volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 22:07:33 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioPushState May 29 22:07:33 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:07:33 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output update for this device May 29 22:07:33 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output May 29 22:07:33 rivo-toshi volumio[3298]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 29 22:07:43 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:07:43 rivo-toshi volumio[3298]: info: CoreCommandRouter::servicePushState May 29 22:07:43 rivo-toshi volumio[3298]: info: CoreStateMachine::pushState May 29 22:07:43 rivo-toshi volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 22:07:43 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioPushState May 29 22:07:43 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:07:43 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output update for this device May 29 22:07:43 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output May 29 22:07:43 rivo-toshi qobuz-connect[3961]: 20250529 22:07:43.344 [3961.3961] INFO ProtocolHandler: [0xad0be538]: Need to send state update May 29 22:07:43 rivo-toshi qobuz-connect[3961]: 20250529 22:07:43.344 [3961.3961] INFO QConnectMessageSender: [0xad243f50]: Sending StateUpdated message May 29 22:07:43 rivo-toshi qobuz-connect[3961]: 20250529 22:07:43.344 [3961.3961] INFO QwspMessageSender: [0xad243f40]: Sending Payload message May 29 22:07:43 rivo-toshi volumio[3298]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 29 22:07:53 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:07:53 rivo-toshi volumio[3298]: info: CoreCommandRouter::servicePushState May 29 22:07:53 rivo-toshi volumio[3298]: info: CoreStateMachine::pushState May 29 22:07:53 rivo-toshi volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 22:07:53 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioPushState May 29 22:07:53 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:07:53 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output update for this device May 29 22:07:53 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output May 29 22:07:53 rivo-toshi qobuz-connect[3961]: 20250529 22:07:53.359 [3961.3961] INFO ProtocolHandler: [0xad0be538]: Need to send state update May 29 22:07:53 rivo-toshi qobuz-connect[3961]: 20250529 22:07:53.359 [3961.3961] INFO QConnectMessageSender: [0xad243f50]: Sending StateUpdated message May 29 22:07:53 rivo-toshi qobuz-connect[3961]: 20250529 22:07:53.359 [3961.3961] INFO QwspMessageSender: [0xad243f40]: Sending Payload message May 29 22:07:53 rivo-toshi volumio[3298]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 29 22:08:03 rivo-toshi qobuz-connect[3961]: 20250529 22:08:03.338 [3961.3961] INFO ProtocolHandler: [0xad0be538]: Need to send state update May 29 22:08:03 rivo-toshi qobuz-connect[3961]: 20250529 22:08:03.338 [3961.3961] INFO QConnectMessageSender: [0xad243f50]: Sending StateUpdated message May 29 22:08:03 rivo-toshi qobuz-connect[3961]: 20250529 22:08:03.338 [3961.3961] INFO QwspMessageSender: [0xad243f40]: Sending Payload message May 29 22:08:03 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:08:03 rivo-toshi volumio[3298]: info: CoreCommandRouter::servicePushState May 29 22:08:03 rivo-toshi volumio[3298]: info: CoreStateMachine::pushState May 29 22:08:03 rivo-toshi volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 22:08:03 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioPushState May 29 22:08:03 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:08:03 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output update for this device May 29 22:08:03 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output May 29 22:08:03 rivo-toshi volumio[3298]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 29 22:08:13 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:08:13 rivo-toshi volumio[3298]: info: CoreCommandRouter::servicePushState May 29 22:08:13 rivo-toshi volumio[3298]: info: CoreStateMachine::pushState May 29 22:08:13 rivo-toshi volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 22:08:13 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioPushState May 29 22:08:13 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:08:13 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output update for this device May 29 22:08:13 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output May 29 22:08:13 rivo-toshi qobuz-connect[3961]: 20250529 22:08:13.347 [3961.3961] INFO ProtocolHandler: [0xad0be538]: Need to send state update May 29 22:08:13 rivo-toshi qobuz-connect[3961]: 20250529 22:08:13.348 [3961.3961] INFO QConnectMessageSender: [0xad243f50]: Sending StateUpdated message May 29 22:08:13 rivo-toshi qobuz-connect[3961]: 20250529 22:08:13.348 [3961.3961] INFO QwspMessageSender: [0xad243f40]: Sending Payload message May 29 22:08:13 rivo-toshi volumio[3298]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 29 22:08:23 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:08:23 rivo-toshi volumio[3298]: info: CoreCommandRouter::servicePushState May 29 22:08:23 rivo-toshi volumio[3298]: info: CoreStateMachine::pushState May 29 22:08:23 rivo-toshi volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 22:08:23 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioPushState May 29 22:08:23 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:08:23 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output update for this device May 29 22:08:23 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output May 29 22:08:23 rivo-toshi qobuz-connect[3961]: 20250529 22:08:23.359 [3961.3961] INFO ProtocolHandler: [0xad0be538]: Need to send state update May 29 22:08:23 rivo-toshi qobuz-connect[3961]: 20250529 22:08:23.359 [3961.3961] INFO QConnectMessageSender: [0xad243f50]: Sending StateUpdated message May 29 22:08:23 rivo-toshi qobuz-connect[3961]: 20250529 22:08:23.359 [3961.3961] INFO QwspMessageSender: [0xad243f40]: Sending Payload message May 29 22:08:23 rivo-toshi volumio[3298]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 29 22:08:24 rivo-toshi volumiologrotate[2826]: ls: cannot access '/var/log/samba/log.wb-RIVO': No such file or directory May 29 22:08:24 rivo-toshi volumiologrotate[2826]: ls: cannot access 'TOSHI': No such file or directory May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.083 [3961.3961] INFO ProtocolHandler: [0xad0be538]: Received SetState message: May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.083 [3961.3961] INFO ProtocolHandler: [0xad0be538]: Playing state: Playing May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.083 [3961.3961] INFO ProtocolHandler: [0xad0be538]: Playback position: 0 May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.083 [3961.3961] INFO ProtocolHandler: [0xad0be538]: Queue version: 3.1 May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.083 [3961.3961] INFO ProtocolHandler: [0xad0be538]: Current track: TID: 774423, QID: 0, Context UUID: d0b5f6dd-9e89-4bd7-af28-63c2a72ef489 May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.083 [3961.3961] INFO ProtocolHandler: [0xad0be538]: Next track: TID: 774424, QID: 1, Context UUID: d0b5f6dd-9e89-4bd7-af28-63c2a72ef489 May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.083 [3961.3961] INFO MediaEngine: [0xad0bddf8]: Stopping playback, clearing tracks May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.083 [3961.3961] INFO MediaEngine: [0xad0bddf8]: Initiating playback May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.084 [3961.3961] INFO AudioRenderer: [0xad0be018]: Stopping audio renderer May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.084 [3961.3961] INFO SampleApp: Stopping playback May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.086 [3961.3961] INFO AudioStreamManager: [0xad0bdee0]: Clearing all streams May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.086 [3961.3961] INFO AudioStreamManager: [0xad0bdee0]: [Stream 6]: Disposing of audio stream May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.086 [3961.3961] INFO ContentFetcher: [0xad5b62c8]: Canceling download May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.094 [3961.3961] INFO SampleApp: Playback state changed: Stopped May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.095 [3961.3961] INFO StreamingEventReporter: [0xad0be950]: Reporting streaming ended event May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.095 [3961.3961] INFO HttpDownloader: [0xad0be9a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.095 [3961.3961] INFO ProtocolHandler: [0xad0be538]: Need to send state update May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.095 [3961.3961] INFO QConnectMessageSender: [0xad243f50]: Sending StateUpdated message May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.095 [3961.3961] INFO SampleApp: [Stream 6]: Audio stream has been disposed of May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.096 [3961.3961] INFO MediaEngine: [0xad0bddf8]: Setting current track: 774423, initial offset: 0ms May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.096 [3961.3961] INFO AudioStreamManager: [0xad0bdee0]: Clearing all streams May 29 22:08:27 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:08:27 rivo-toshi volumio[3298]: info: CoreCommandRouter::servicePushState May 29 22:08:27 rivo-toshi volumio[3298]: info: CoreStateMachine::pushState May 29 22:08:27 rivo-toshi volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 22:08:27 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioPushState May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.096 [3961.3961] INFO AudioStreamManager: [0xad0bdee0]: New stream: 8 May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.096 [3961.3961] INFO HttpDownloader: [0xad4b4140]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=1964f5ee21739a80bf0c959432b3fbce&request_ts=1748524107&track_id=774423 May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.096 [3961.3961] INFO HttpDownloader: [0xad47b630]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=774423 May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.096 [3961.3961] INFO HttpDownloader: [0xad47c020]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=cbbeb73cb015708683f485cf9c920c56&request_ts=1748524107&track_id=774423 May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.096 [3961.3961] INFO AudioStreamManager: [0xad0bdee0]: [Stream 8]: Running audio stream May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.096 [3961.3961] INFO QwspMessageSender: [0xad243f40]: Sending Payload message May 29 22:08:27 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:08:27 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output update for this device May 29 22:08:27 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output May 29 22:08:27 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:08:27 rivo-toshi volumio[3298]: info: CoreCommandRouter::servicePushState May 29 22:08:27 rivo-toshi volumio[3298]: info: CoreStateMachine::pushState May 29 22:08:27 rivo-toshi volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 22:08:27 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioPushState May 29 22:08:27 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:08:27 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output update for this device May 29 22:08:27 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.137 [3961.3961] INFO MediaEngine: [0xad0bddf8]: Setting next track: 774424 May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.137 [3961.3961] INFO AudioStreamManager: [0xad0bdee0]: New stream: 9 May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.137 [3961.3961] INFO HttpDownloader: [0xad1c7500]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=4912a0d8348150addf1f8648bf44fc36&request_ts=1748524107&track_id=774424 May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.137 [3961.3961] INFO HttpDownloader: [0xad1b9ec8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=774424 May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.137 [3961.3961] INFO HttpDownloader: [0xad115a08]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=2b143f58f74155581c0178a15a53b1f7&request_ts=1748524107&track_id=774424 May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.139 [3961.3961] INFO MediaEngine: [0xad0bddf8]: Waiting for current stream to start before starting audio renderer May 29 22:08:27 rivo-toshi volumio[3298]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 29 22:08:27 rivo-toshi volumio[3298]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 29 22:08:27 rivo-toshi volumio[3298]: info: MCU Signalled Playback Inactive May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.801 [3961.3961] INFO ProtocolHandler: [0xad0be538]: Received SetState message: May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.801 [3961.3961] INFO ProtocolHandler: [0xad0be538]: Playing state: May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.801 [3961.3961] INFO ProtocolHandler: [0xad0be538]: Playback position: May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.801 [3961.3961] INFO ProtocolHandler: [0xad0be538]: Queue version: 3.2 May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.801 [3961.3961] INFO ProtocolHandler: [0xad0be538]: Current track: May 29 22:08:27 rivo-toshi qobuz-connect[3961]: 20250529 22:08:27.801 [3961.3961] INFO ProtocolHandler: [0xad0be538]: Next track: TID: 774424, QID: 1, Context UUID: d0b5f6dd-9e89-4bd7-af28-63c2a72ef489 May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.008 [3961.3961] INFO AudioStreamManager: [0xad0bdee0]: [Stream 8]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=774423&fmt=6&profile=raw&app_id=174516466&cid=2464732&etsp=1748527707&hmac=gKsTsbzHb6_XIrcZIpJjqrIWrVw May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.102 [3961.3961] INFO AudioStreamManager: [0xad0bdee0]: [Stream 8]: stream information have been fetched May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.102 [3961.3961] INFO UrlAudioSource: [0xad1b0a68]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=774423&fmt=6&profile=raw&app_id=174516466&cid=2464732&etsp=1748527707&hmac=gKsTsbzHb6_XIrcZIpJjqrIWrVw May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.103 [3961.3961] INFO ContentFetcher: [0xad48df00]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=774423&fmt=6&profile=raw&app_id=174516466&cid=2464732&etsp=1748527707&hmac=gKsTsbzHb6_XIrcZIpJjqrIWrVw, offset: 0 May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.103 [3961.3961] INFO AudioRenderer: [0xad0be018]: Starting audio renderer, initial playback state: Playing May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.103 [3961.3961] INFO SampleApp: [Stream 8]: New audio stream (starting from 0ms) May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:08:28 rivo-toshi volumio[3298]: info: QobuzConnect: alread currrent service, send playback confirmation May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.105 [3961.3961] INFO SampleApp: Initiating playback, initial state: Playing May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.285 [3961.3961] INFO SampleApp: Playback state changed: Playing May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.285 [3961.3961] INFO StreamingEventReporter: [0xad0be950]: Reporting streaming started event May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.286 [3961.3961] INFO ProtocolHandler: [0xad0be538]: Need to send state update May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.286 [3961.3961] INFO QConnectMessageSender: [0xad243f50]: Sending StateUpdated message May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.286 [3961.3961] INFO QwspMessageSender: [0xad243f40]: Sending Payload message May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreCommandRouter::servicePushState May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreStateMachine::pushState May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.286 [3961.3961] INFO QConnectMessageSender: [0xad243f50]: Sending FileAudioQualityChanged message May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.287 [3961.3961] INFO QwspMessageSender: [0xad243f40]: Sending Payload message May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioPushState May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.289 [3961.3961] INFO HttpDownloader: [0xad0be9a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.290 [3961.3961] INFO AudioStreamManager: [0xad0bdee0]: [Stream 9]: stream information have been fetched May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.291 [3961.3961] INFO AudioStreamManager: [0xad0bdee0]: [Stream 9]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=774424&fmt=6&profile=raw&app_id=174516466&cid=2464732&etsp=1748527707&hmac=-gW_jm2PBo4XG1UtqCp2e3czRUQ May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.291 [3961.3961] INFO AudioStreamManager: [0xad0bdee0]: [Stream 8]: Metadata became available: May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.291 [3961.3961] INFO AudioStreamManager: [0xad0bdee0]: Title: テンパス・フュージット May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.291 [3961.3961] INFO AudioStreamManager: [0xad0bdee0]: Artist: Bud Powell May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.291 [3961.3961] INFO AudioStreamManager: [0xad0bdee0]: Album: ジャズ・ジャイアント May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.291 [3961.3961] INFO AudioStreamManager: [0xad0bdee0]: Album art URL: https://static.qobuz.com/images/covers/22/83/0073145438322_600.jpg May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.291 [3961.3961] INFO AudioStreamManager: [0xad0bdee0]: [Stream 9]: Metadata became available: May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.291 [3961.3961] INFO AudioStreamManager: [0xad0bdee0]: Title: シリア May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.291 [3961.3961] INFO AudioStreamManager: [0xad0bdee0]: Artist: Bud Powell May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.291 [3961.3961] INFO AudioStreamManager: [0xad0bdee0]: Album: ジャズ・ジャイアント May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.291 [3961.3961] INFO AudioStreamManager: [0xad0bdee0]: Album art URL: https://static.qobuz.com/images/covers/22/83/0073145438322_600.jpg May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.292 [3961.3961] INFO SampleApp: [Stream 8]: Stream metadata became available: May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.292 [3961.3961] INFO SampleApp: Title: テンパス・フュージット May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.292 [3961.3961] INFO SampleApp: Artist: Bud Powell May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.292 [3961.3961] INFO SampleApp: Album: ジャズ・ジャイアント May 29 22:08:28 rivo-toshi qobuz-connect[3961]: 20250529 22:08:28.292 [3961.3961] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/22/83/0073145438322_600.jpg May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:08:28 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output update for this device May 29 22:08:28 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreCommandRouter::servicePushState May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreStateMachine::pushState May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioPushState May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:08:28 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output update for this device May 29 22:08:28 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreCommandRouter::servicePushState May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreStateMachine::pushState May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioPushState May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:08:28 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output update for this device May 29 22:08:28 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreCommandRouter::servicePushState May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreStateMachine::pushState May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioPushState May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:08:28 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output update for this device May 29 22:08:28 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreCommandRouter::servicePushState May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreStateMachine::pushState May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioPushState May 29 22:08:28 rivo-toshi volumio[3298]: info: CoreCommandRouter::volumioGetState May 29 22:08:28 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output update for this device May 29 22:08:28 rivo-toshi volumio[3298]: info: MRS: Pushing multiroomSync output May 29 22:08:28 rivo-toshi volumio[3298]: info: Signalling Playback active due to playback status change May 29 22:08:28 rivo-toshi volumio[3298]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! May 29 22:08:28 rivo-toshi volumio[3298]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 22:08:28 rivo-toshi volumio[3298]: TypeError: Cannot read property 'indexOf' of undefined May 29 22:08:28 rivo-toshi volumio[3298]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) May 29 22:08:28 rivo-toshi volumio[3298]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) May 29 22:08:28 rivo-toshi volumio[3298]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) May 29 22:08:28 rivo-toshi volumio[3298]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 29 22:08:28 rivo-toshi volumio[3298]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) May 29 22:08:28 rivo-toshi volumio[3298]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) May 29 22:08:28 rivo-toshi volumio[3298]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 29 22:08:28 rivo-toshi volumio[3298]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 29 22:08:28 rivo-toshi volumio[3298]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) May 29 22:08:28 rivo-toshi volumio[3298]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 29 22:08:28 rivo-toshi volumio[3298]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) May 29 22:08:28 rivo-toshi volumio[3298]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) May 29 22:08:28 rivo-toshi volumio[3298]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) May 29 22:08:28 rivo-toshi volumio[3298]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 29 22:08:28 rivo-toshi volumio[3298]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 29 22:08:28 rivo-toshi volumio[3298]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) May 29 22:08:28 rivo-toshi volumio[3298]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 22:08:29 rivo-toshi sudo[8072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-29 22:07 May 29 22:08:29 rivo-toshi sudo[8072]: 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"