-- Logs begin at Thu 2019-02-14 19:11:58 JST, end at Sun 2025-09-14 21:34:37 JST. -- Sep 14 21:33:06 rivo-toshi qobuz-connect[3943]: 20250914 21:33:06.250 [3943.3943] INFO ProtocolHandler: [0xac938538]: Need to send state update Sep 14 21:33:06 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:33:06 rivo-toshi volumio[3288]: info: CoreCommandRouter::servicePushState Sep 14 21:33:06 rivo-toshi volumio[3288]: info: CoreStateMachine::pushState Sep 14 21:33:06 rivo-toshi volumio[3288]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 21:33:06 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioPushState Sep 14 21:33:06 rivo-toshi qobuz-connect[3943]: 20250914 21:33:06.255 [3943.3943] INFO QConnectMessageSender: [0xacabdf10]: Sending StateUpdated message Sep 14 21:33:06 rivo-toshi qobuz-connect[3943]: 20250914 21:33:06.256 [3943.3943] INFO QwspMessageSender: [0xaca28880]: Sending Payload message Sep 14 21:33:06 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:33:06 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output update for this device Sep 14 21:33:06 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output Sep 14 21:33:06 rivo-toshi volumio[3288]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 21:33:16 rivo-toshi qobuz-connect[3943]: 20250914 21:33:16.250 [3943.3943] INFO ProtocolHandler: [0xac938538]: Need to send state update Sep 14 21:33:16 rivo-toshi qobuz-connect[3943]: 20250914 21:33:16.250 [3943.3943] INFO QConnectMessageSender: [0xacabdf10]: Sending StateUpdated message Sep 14 21:33:16 rivo-toshi qobuz-connect[3943]: 20250914 21:33:16.250 [3943.3943] INFO QwspMessageSender: [0xaca28880]: Sending Payload message Sep 14 21:33:16 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:33:16 rivo-toshi volumio[3288]: info: CoreCommandRouter::servicePushState Sep 14 21:33:16 rivo-toshi volumio[3288]: info: CoreStateMachine::pushState Sep 14 21:33:16 rivo-toshi volumio[3288]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 21:33:16 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioPushState Sep 14 21:33:16 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:33:16 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output update for this device Sep 14 21:33:16 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output Sep 14 21:33:16 rivo-toshi volumio[3288]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 21:33:26 rivo-toshi qobuz-connect[3943]: 20250914 21:33:26.251 [3943.3943] INFO ProtocolHandler: [0xac938538]: Need to send state update Sep 14 21:33:26 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:33:26 rivo-toshi volumio[3288]: info: CoreCommandRouter::servicePushState Sep 14 21:33:26 rivo-toshi volumio[3288]: info: CoreStateMachine::pushState Sep 14 21:33:26 rivo-toshi volumio[3288]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 21:33:26 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioPushState Sep 14 21:33:26 rivo-toshi qobuz-connect[3943]: 20250914 21:33:26.255 [3943.3943] INFO QConnectMessageSender: [0xacabdf10]: Sending StateUpdated message Sep 14 21:33:26 rivo-toshi qobuz-connect[3943]: 20250914 21:33:26.256 [3943.3943] INFO QwspMessageSender: [0xaca28880]: Sending Payload message Sep 14 21:33:26 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:33:26 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output update for this device Sep 14 21:33:26 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output Sep 14 21:33:26 rivo-toshi volumio[3288]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 21:33:36 rivo-toshi qobuz-connect[3943]: 20250914 21:33:36.251 [3943.3943] INFO ProtocolHandler: [0xac938538]: Need to send state update Sep 14 21:33:36 rivo-toshi qobuz-connect[3943]: 20250914 21:33:36.251 [3943.3943] INFO QConnectMessageSender: [0xacabdf10]: Sending StateUpdated message Sep 14 21:33:36 rivo-toshi qobuz-connect[3943]: 20250914 21:33:36.251 [3943.3943] INFO QwspMessageSender: [0xaca28880]: Sending Payload message Sep 14 21:33:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:33:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::servicePushState Sep 14 21:33:36 rivo-toshi volumio[3288]: info: CoreStateMachine::pushState Sep 14 21:33:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 21:33:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioPushState Sep 14 21:33:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:33:36 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output update for this device Sep 14 21:33:36 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output Sep 14 21:33:36 rivo-toshi volumio[3288]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 21:33:46 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:33:46 rivo-toshi volumio[3288]: info: CoreCommandRouter::servicePushState Sep 14 21:33:46 rivo-toshi volumio[3288]: info: CoreStateMachine::pushState Sep 14 21:33:46 rivo-toshi volumio[3288]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 21:33:46 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioPushState Sep 14 21:33:46 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:33:46 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output update for this device Sep 14 21:33:46 rivo-toshi qobuz-connect[3943]: 20250914 21:33:46.264 [3943.3943] INFO ProtocolHandler: [0xac938538]: Need to send state update Sep 14 21:33:46 rivo-toshi qobuz-connect[3943]: 20250914 21:33:46.264 [3943.3943] INFO QConnectMessageSender: [0xacabdf10]: Sending StateUpdated message Sep 14 21:33:46 rivo-toshi qobuz-connect[3943]: 20250914 21:33:46.265 [3943.3943] INFO QwspMessageSender: [0xaca28880]: Sending Payload message Sep 14 21:33:46 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output Sep 14 21:33:46 rivo-toshi volumio[3288]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 21:33:53 rivo-toshi qobuz-connect[3943]: 20250914 21:33:53.235 [3943.3943] INFO ProtocolHandler: [0xac938538]: Received SetState message: Sep 14 21:33:53 rivo-toshi qobuz-connect[3943]: 20250914 21:33:53.235 [3943.3943] INFO ProtocolHandler: [0xac938538]: Playing state: Paused Sep 14 21:33:53 rivo-toshi qobuz-connect[3943]: 20250914 21:33:53.235 [3943.3943] INFO ProtocolHandler: [0xac938538]: Playback position: Sep 14 21:33:53 rivo-toshi qobuz-connect[3943]: 20250914 21:33:53.236 [3943.3943] INFO ProtocolHandler: [0xac938538]: Queue version: Sep 14 21:33:53 rivo-toshi qobuz-connect[3943]: 20250914 21:33:53.236 [3943.3943] INFO ProtocolHandler: [0xac938538]: Current track: Sep 14 21:33:53 rivo-toshi qobuz-connect[3943]: 20250914 21:33:53.236 [3943.3943] INFO ProtocolHandler: [0xac938538]: Next track: Sep 14 21:33:53 rivo-toshi qobuz-connect[3943]: 20250914 21:33:53.236 [3943.3943] INFO MediaEngine: [0xac937df8]: Pausing playback Sep 14 21:33:53 rivo-toshi qobuz-connect[3943]: 20250914 21:33:53.236 [3943.3943] INFO AudioRenderer: [0xac938018]: Pausing audio renderer Sep 14 21:33:53 rivo-toshi qobuz-connect[3943]: 20250914 21:33:53.236 [3943.3943] INFO SampleApp: Pausing playback Sep 14 21:33:53 rivo-toshi qobuz-connect[3943]: 20250914 21:33:53.236 [3943.3943] INFO SampleApp: Playback state changed: Paused Sep 14 21:33:53 rivo-toshi qobuz-connect[3943]: 20250914 21:33:53.236 [3943.3943] INFO StreamingEventReporter: [0xac938950]: Reporting streaming ended event Sep 14 21:33:53 rivo-toshi qobuz-connect[3943]: 20250914 21:33:53.236 [3943.3943] INFO HttpDownloader: [0xac9389a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Sep 14 21:33:53 rivo-toshi qobuz-connect[3943]: 20250914 21:33:53.237 [3943.3943] INFO ProtocolHandler: [0xac938538]: Need to send state update Sep 14 21:33:53 rivo-toshi qobuz-connect[3943]: 20250914 21:33:53.237 [3943.3943] INFO QConnectMessageSender: [0xacabdf10]: Sending StateUpdated message Sep 14 21:33:53 rivo-toshi qobuz-connect[3943]: 20250914 21:33:53.237 [3943.3943] INFO QwspMessageSender: [0xaca28880]: Sending Payload message Sep 14 21:33:53 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:33:53 rivo-toshi volumio[3288]: info: CoreCommandRouter::servicePushState Sep 14 21:33:53 rivo-toshi volumio[3288]: info: CoreStateMachine::pushState Sep 14 21:33:53 rivo-toshi volumio[3288]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 21:33:53 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioPushState Sep 14 21:33:53 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:33:53 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output update for this device Sep 14 21:33:53 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output Sep 14 21:33:53 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:33:53 rivo-toshi volumio[3288]: info: CoreCommandRouter::servicePushState Sep 14 21:33:53 rivo-toshi volumio[3288]: info: CoreStateMachine::pushState Sep 14 21:33:53 rivo-toshi volumio[3288]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 21:33:53 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioPushState Sep 14 21:33:53 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:33:53 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output update for this device Sep 14 21:33:53 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output Sep 14 21:33:53 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:33:53 rivo-toshi volumio[3288]: info: CoreCommandRouter::servicePushState Sep 14 21:33:53 rivo-toshi volumio[3288]: info: CoreStateMachine::pushState Sep 14 21:33:53 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioPushState Sep 14 21:33:53 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:33:53 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output update for this device Sep 14 21:33:53 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output Sep 14 21:33:53 rivo-toshi volumio[3288]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 21:33:53 rivo-toshi volumio[3288]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 21:33:53 rivo-toshi volumio[3288]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 21:33:53 rivo-toshi volumio[3288]: info: MCU Signalled Playback Inactive Sep 14 21:33:58 rivo-toshi volumiologrotate[2830]: ls: cannot access '/var/log/samba/log.wb-RIVO': No such file or directory Sep 14 21:33:58 rivo-toshi volumiologrotate[2830]: ls: cannot access 'TOSHI': No such file or directory Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.461 [3943.3943] INFO ProtocolHandler: [0xac938538]: Received SetState message: Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.461 [3943.3943] INFO ProtocolHandler: [0xac938538]: Playing state: Playing Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.461 [3943.3943] INFO ProtocolHandler: [0xac938538]: Playback position: 0 Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.462 [3943.3943] INFO ProtocolHandler: [0xac938538]: Queue version: 21.1 Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.462 [3943.3943] INFO ProtocolHandler: [0xac938538]: Current track: TID: 513278, QID: 0, Context UUID: ac3520ba-8b4a-4f4a-9567-352117476d40 Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.462 [3943.3943] INFO ProtocolHandler: [0xac938538]: Next track: TID: 513279, QID: 1, Context UUID: ac3520ba-8b4a-4f4a-9567-352117476d40 Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.462 [3943.3943] INFO MediaEngine: [0xac937df8]: Stopping playback, clearing tracks Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.462 [3943.3943] INFO MediaEngine: [0xac937df8]: Initiating playback Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.462 [3943.3943] INFO AudioRenderer: [0xac938018]: Stopping audio renderer Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.462 [3943.3943] INFO SampleApp: Stopping playback Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.486 [3943.3943] INFO AudioStreamManager: [0xac937ee0]: Clearing all streams Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.487 [3943.3943] INFO AudioStreamManager: [0xac937ee0]: [Stream 5]: Disposing of audio stream Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.487 [3943.3943] INFO ContentFetcher: [0xacf64048]: Canceling download Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.494 [3943.3943] INFO SampleApp: Playback state changed: Stopped Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.495 [3943.3943] INFO ProtocolHandler: [0xac938538]: Need to send state update Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.495 [3943.3943] INFO QConnectMessageSender: [0xacabdf10]: Sending StateUpdated message Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.495 [3943.3943] INFO SampleApp: [Stream 5]: Audio stream has been disposed of Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.495 [3943.3943] INFO MediaEngine: [0xac937df8]: Setting current track: 513278, initial offset: 0ms Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.495 [3943.3943] INFO AudioStreamManager: [0xac937ee0]: Clearing all streams Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.495 [3943.3943] INFO AudioStreamManager: [0xac937ee0]: New stream: 7 Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.495 [3943.3943] INFO HttpDownloader: [0xac977248]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=791a0a39a78bdf33748e285c73fa7be6&request_ts=1757853275&track_id=513278 Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.496 [3943.3943] INFO HttpDownloader: [0xacbaed50]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=513278 Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.496 [3943.3943] INFO HttpDownloader: [0xac94cd70]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=10fe9b475e1a79f3e6ef30f34a13ae22&request_ts=1757853275&track_id=513278 Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.496 [3943.3943] INFO AudioStreamManager: [0xac937ee0]: [Stream 7]: Running audio stream Sep 14 21:34:35 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:34:35 rivo-toshi volumio[3288]: info: CoreCommandRouter::servicePushState Sep 14 21:34:35 rivo-toshi volumio[3288]: info: CoreStateMachine::pushState Sep 14 21:34:35 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioPushState Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.496 [3943.3943] INFO QwspMessageSender: [0xaca28880]: Sending Payload message Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.498 [3943.3943] INFO MediaEngine: [0xac937df8]: Setting next track: 513279 Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.498 [3943.3943] INFO AudioStreamManager: [0xac937ee0]: New stream: 8 Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.498 [3943.3943] INFO HttpDownloader: [0xacad4e90]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=bcec3993e9ef4a79562b840a96e886c2&request_ts=1757853275&track_id=513279 Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.499 [3943.3943] INFO HttpDownloader: [0xacdf5f48]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=513279 Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.499 [3943.3943] INFO HttpDownloader: [0xac9859c8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=c26c440ff6aeaeae7b95b7313ad04c76&request_ts=1757853275&track_id=513279 Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.501 [3943.3943] INFO MediaEngine: [0xac937df8]: Waiting for current stream to start before starting audio renderer Sep 14 21:34:35 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:34:35 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output update for this device Sep 14 21:34:35 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output Sep 14 21:34:35 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:34:35 rivo-toshi volumio[3288]: info: CoreCommandRouter::servicePushState Sep 14 21:34:35 rivo-toshi volumio[3288]: info: CoreStateMachine::pushState Sep 14 21:34:35 rivo-toshi volumio[3288]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 21:34:35 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioPushState Sep 14 21:34:35 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:34:35 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output update for this device Sep 14 21:34:35 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output Sep 14 21:34:35 rivo-toshi volumio[3288]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 21:34:35 rivo-toshi volumio[3288]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.876 [3943.3943] INFO AudioStreamManager: [0xac937ee0]: [Stream 8]: Metadata became available: Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.876 [3943.3943] INFO AudioStreamManager: [0xac937ee0]: Title: Hi Lili Hi Lo (Take 6) Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.876 [3943.3943] INFO AudioStreamManager: [0xac937ee0]: Artist: ビル・エヴァンス Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.876 [3943.3943] INFO AudioStreamManager: [0xac937ee0]: Album: From The 70's : Previously unissued studio and club recordings, 1973-1977 (Take 6) Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.876 [3943.3943] INFO AudioStreamManager: [0xac937ee0]: Album art URL: https://static.qobuz.com/images/covers/92/06/0002521870692_600.jpg Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.876 [3943.3943] INFO AudioStreamManager: [0xac937ee0]: [Stream 7]: stream information have been fetched Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.876 [3943.3943] INFO UrlAudioSource: [0xaca27590]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=513278&fmt=6&profile=raw&app_id=174516466&cid=2464732&etsp=1757856875&hmac=GwDTENaX403RXmjxrHkva13b61I Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.876 [3943.3943] INFO ContentFetcher: [0xad01a5b8]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=513278&fmt=6&profile=raw&app_id=174516466&cid=2464732&etsp=1757856875&hmac=GwDTENaX403RXmjxrHkva13b61I, offset: 0 Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.876 [3943.3943] INFO AudioStreamManager: [0xac937ee0]: [Stream 8]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=513279&fmt=6&profile=raw&app_id=174516466&cid=2464732&etsp=1757856875&hmac=vOzhXLySYv54qjrEOcFo-BIcpfQ Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.876 [3943.3943] INFO AudioStreamManager: [0xac937ee0]: [Stream 7]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=513278&fmt=6&profile=raw&app_id=174516466&cid=2464732&etsp=1757856875&hmac=GwDTENaX403RXmjxrHkva13b61I Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.877 [3943.3943] INFO AudioRenderer: [0xac938018]: Starting audio renderer, initial playback state: Playing Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.877 [3943.3943] INFO SampleApp: [Stream 7]: New audio stream (starting from 0ms) Sep 14 21:34:35 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:34:35 rivo-toshi volumio[3288]: info: QobuzConnect: alread currrent service, send playback confirmation Sep 14 21:34:35 rivo-toshi qobuz-connect[3943]: 20250914 21:34:35.881 [3943.3943] INFO SampleApp: Initiating playback, initial state: Playing Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.239 [3943.3943] INFO SampleApp: Playback state changed: Playing Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.239 [3943.3943] INFO StreamingEventReporter: [0xac938950]: Reporting streaming started event Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.239 [3943.3943] INFO HttpDownloader: [0xac9389a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.240 [3943.3943] INFO ProtocolHandler: [0xac938538]: Need to send state update Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.240 [3943.3943] INFO QConnectMessageSender: [0xacabdf10]: Sending StateUpdated message Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.240 [3943.3943] INFO QwspMessageSender: [0xaca28880]: Sending Payload message Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::servicePushState Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreStateMachine::pushState Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.241 [3943.3943] INFO QConnectMessageSender: [0xacabdf10]: Sending FileAudioQualityChanged message Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.242 [3943.3943] INFO ProtocolHandler: [0xac938538]: Received SetState message: Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.242 [3943.3943] INFO ProtocolHandler: [0xac938538]: Playing state: Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.242 [3943.3943] INFO ProtocolHandler: [0xac938538]: Playback position: Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.242 [3943.3943] INFO ProtocolHandler: [0xac938538]: Queue version: 21.2 Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.242 [3943.3943] INFO ProtocolHandler: [0xac938538]: Current track: Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.242 [3943.3943] INFO ProtocolHandler: [0xac938538]: Next track: TID: 513279, QID: 1, Context UUID: ac3520ba-8b4a-4f4a-9567-352117476d40 Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.242 [3943.3943] INFO QwspMessageSender: [0xaca28880]: Sending Payload message Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.243 [3943.3943] INFO QConnectMessageSender: [0xacabdf10]: Sending DeviceAudioQualityChanged message Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioPushState Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.243 [3943.3943] INFO QwspMessageSender: [0xaca28880]: Sending Payload message Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.244 [3943.3943] INFO AudioStreamManager: [0xac937ee0]: [Stream 7]: Metadata became available: Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.244 [3943.3943] INFO AudioStreamManager: [0xac937ee0]: Title: Gone With The Wind (Take 3) Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.245 [3943.3943] INFO AudioStreamManager: [0xac937ee0]: Artist: ビル・エヴァンス Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.245 [3943.3943] INFO AudioStreamManager: [0xac937ee0]: Album: From The 70's : Previously unissued studio and club recordings, 1973-1977 (Take 3) Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.245 [3943.3943] INFO AudioStreamManager: [0xac937ee0]: Album art URL: https://static.qobuz.com/images/covers/92/06/0002521870692_600.jpg Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.245 [3943.3943] INFO SampleApp: [Stream 7]: Stream metadata became available: Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.245 [3943.3943] INFO SampleApp: Title: Gone With The Wind (Take 3) Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.245 [3943.3943] INFO SampleApp: Artist: ビル・エヴァンス Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.245 [3943.3943] INFO SampleApp: Album: From The 70's : Previously unissued studio and club recordings, 1973-1977 (Take 3) Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.245 [3943.3943] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/92/06/0002521870692_600.jpg Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:34:36 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output update for this device Sep 14 21:34:36 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::servicePushState Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreStateMachine::pushState Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioPushState Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:34:36 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output update for this device Sep 14 21:34:36 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::servicePushState Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreStateMachine::pushState Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioPushState Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:34:36 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output update for this device Sep 14 21:34:36 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::servicePushState Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreStateMachine::pushState Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioPushState Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:34:36 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output update for this device Sep 14 21:34:36 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::servicePushState Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreStateMachine::pushState Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioPushState Sep 14 21:34:36 rivo-toshi volumio[3288]: info: CoreCommandRouter::volumioGetState Sep 14 21:34:36 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output update for this device Sep 14 21:34:36 rivo-toshi qobuz-connect[3943]: 20250914 21:34:36.296 [3943.3943] INFO AudioStreamManager: [0xac937ee0]: [Stream 8]: stream information have been fetched Sep 14 21:34:36 rivo-toshi volumio[3288]: info: MRS: Pushing multiroomSync output Sep 14 21:34:36 rivo-toshi volumio[3288]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Sep 14 21:34:36 rivo-toshi volumio[3288]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 21:34:36 rivo-toshi volumio[3288]: TypeError: Cannot read property 'indexOf' of undefined Sep 14 21:34:36 rivo-toshi volumio[3288]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Sep 14 21:34:36 rivo-toshi volumio[3288]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Sep 14 21:34:36 rivo-toshi volumio[3288]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Sep 14 21:34:36 rivo-toshi volumio[3288]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Sep 14 21:34:36 rivo-toshi volumio[3288]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Sep 14 21:34:36 rivo-toshi volumio[3288]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Sep 14 21:34:36 rivo-toshi volumio[3288]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Sep 14 21:34:36 rivo-toshi volumio[3288]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Sep 14 21:34:36 rivo-toshi volumio[3288]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Sep 14 21:34:36 rivo-toshi volumio[3288]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Sep 14 21:34:36 rivo-toshi volumio[3288]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Sep 14 21:34:36 rivo-toshi volumio[3288]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Sep 14 21:34:36 rivo-toshi volumio[3288]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Sep 14 21:34:36 rivo-toshi volumio[3288]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Sep 14 21:34:36 rivo-toshi volumio[3288]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Sep 14 21:34:36 rivo-toshi volumio[3288]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Sep 14 21:34:36 rivo-toshi volumio[3288]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 21:34:37 rivo-toshi sudo[6349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-14 21:33 Sep 14 21:34:37 rivo-toshi sudo[6349]: 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"