-- Logs begin at Thu 2019-02-14 19:11:58 JST, end at Fri 2025-07-04 21:16:20 JST. -- Jul 04 21:15:02 rivo-toshi qobuz-connect[3939]: 20250704 21:15:02.650 [3939.3939] INFO ProtocolHandler: [0xac581538]: Need to send state update Jul 04 21:15:02 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:02 rivo-toshi volumio[3290]: info: CoreCommandRouter::servicePushState Jul 04 21:15:02 rivo-toshi volumio[3290]: info: CoreStateMachine::pushState Jul 04 21:15:02 rivo-toshi volumio[3290]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 21:15:02 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioPushState Jul 04 21:15:02 rivo-toshi qobuz-connect[3939]: 20250704 21:15:02.656 [3939.3939] INFO QConnectMessageSender: [0xac707008]: Sending StateUpdated message Jul 04 21:15:02 rivo-toshi qobuz-connect[3939]: 20250704 21:15:02.657 [3939.3939] INFO QwspMessageSender: [0xac706ff8]: Sending Payload message Jul 04 21:15:02 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:02 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output update for this device Jul 04 21:15:02 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output Jul 04 21:15:02 rivo-toshi volumio[3290]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 21:15:12 rivo-toshi qobuz-connect[3939]: 20250704 21:15:12.650 [3939.3939] INFO ProtocolHandler: [0xac581538]: Need to send state update Jul 04 21:15:12 rivo-toshi qobuz-connect[3939]: 20250704 21:15:12.650 [3939.3939] INFO QConnectMessageSender: [0xac707008]: Sending StateUpdated message Jul 04 21:15:12 rivo-toshi qobuz-connect[3939]: 20250704 21:15:12.651 [3939.3939] INFO QwspMessageSender: [0xac706ff8]: Sending Payload message Jul 04 21:15:12 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:12 rivo-toshi volumio[3290]: info: CoreCommandRouter::servicePushState Jul 04 21:15:12 rivo-toshi volumio[3290]: info: CoreStateMachine::pushState Jul 04 21:15:12 rivo-toshi volumio[3290]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 21:15:12 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioPushState Jul 04 21:15:12 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:12 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output update for this device Jul 04 21:15:12 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output Jul 04 21:15:12 rivo-toshi volumio[3290]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 21:15:22 rivo-toshi qobuz-connect[3939]: 20250704 21:15:22.650 [3939.3939] INFO ProtocolHandler: [0xac581538]: Need to send state update Jul 04 21:15:22 rivo-toshi qobuz-connect[3939]: 20250704 21:15:22.650 [3939.3939] INFO QConnectMessageSender: [0xac707008]: Sending StateUpdated message Jul 04 21:15:22 rivo-toshi qobuz-connect[3939]: 20250704 21:15:22.651 [3939.3939] INFO QwspMessageSender: [0xac706ff8]: Sending Payload message Jul 04 21:15:22 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:22 rivo-toshi volumio[3290]: info: CoreCommandRouter::servicePushState Jul 04 21:15:22 rivo-toshi volumio[3290]: info: CoreStateMachine::pushState Jul 04 21:15:22 rivo-toshi volumio[3290]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 21:15:22 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioPushState Jul 04 21:15:22 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:22 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output update for this device Jul 04 21:15:22 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output Jul 04 21:15:22 rivo-toshi volumio[3290]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 21:15:32 rivo-toshi qobuz-connect[3939]: 20250704 21:15:32.650 [3939.3939] INFO ProtocolHandler: [0xac581538]: Need to send state update Jul 04 21:15:32 rivo-toshi qobuz-connect[3939]: 20250704 21:15:32.651 [3939.3939] INFO QConnectMessageSender: [0xac707008]: Sending StateUpdated message Jul 04 21:15:32 rivo-toshi qobuz-connect[3939]: 20250704 21:15:32.651 [3939.3939] INFO QwspMessageSender: [0xac706ff8]: Sending Payload message Jul 04 21:15:32 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:32 rivo-toshi volumio[3290]: info: CoreCommandRouter::servicePushState Jul 04 21:15:32 rivo-toshi volumio[3290]: info: CoreStateMachine::pushState Jul 04 21:15:32 rivo-toshi volumio[3290]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 21:15:32 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioPushState Jul 04 21:15:32 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:32 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output update for this device Jul 04 21:15:32 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output Jul 04 21:15:32 rivo-toshi volumio[3290]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 21:15:42 rivo-toshi qobuz-connect[3939]: 20250704 21:15:42.650 [3939.3939] INFO ProtocolHandler: [0xac581538]: Need to send state update Jul 04 21:15:42 rivo-toshi qobuz-connect[3939]: 20250704 21:15:42.650 [3939.3939] INFO QConnectMessageSender: [0xac707008]: Sending StateUpdated message Jul 04 21:15:42 rivo-toshi qobuz-connect[3939]: 20250704 21:15:42.651 [3939.3939] INFO QwspMessageSender: [0xac706ff8]: Sending Payload message Jul 04 21:15:42 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:42 rivo-toshi volumio[3290]: info: CoreCommandRouter::servicePushState Jul 04 21:15:42 rivo-toshi volumio[3290]: info: CoreStateMachine::pushState Jul 04 21:15:42 rivo-toshi volumio[3290]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 21:15:42 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioPushState Jul 04 21:15:42 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:42 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output update for this device Jul 04 21:15:42 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output Jul 04 21:15:42 rivo-toshi volumio[3290]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 21:15:44 rivo-toshi volumiologrotate[2836]: ls: cannot access '/var/log/samba/log.wb-RIVO': No such file or directory Jul 04 21:15:44 rivo-toshi volumiologrotate[2836]: ls: cannot access 'TOSHI': No such file or directory Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.503 [3939.3939] INFO ProtocolHandler: [0xac581538]: Received SetState message: Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.503 [3939.3939] INFO ProtocolHandler: [0xac581538]: Playing state: Playing Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.503 [3939.3939] INFO ProtocolHandler: [0xac581538]: Playback position: 0 Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.503 [3939.3939] INFO ProtocolHandler: [0xac581538]: Queue version: 4.1 Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.503 [3939.3939] INFO ProtocolHandler: [0xac581538]: Current track: TID: 14345385, QID: 0, Context UUID: 89bbcf4c-a762-43ed-bdf9-c776059eb415 Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.503 [3939.3939] INFO ProtocolHandler: [0xac581538]: Next track: TID: 14345386, QID: 1, Context UUID: 89bbcf4c-a762-43ed-bdf9-c776059eb415 Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.504 [3939.3939] INFO MediaEngine: [0xac580df8]: Stopping playback, clearing tracks Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.504 [3939.3939] INFO MediaEngine: [0xac580df8]: Initiating playback Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.504 [3939.3939] INFO AudioRenderer: [0xac581018]: Stopping audio renderer Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.504 [3939.3939] INFO SampleApp: Stopping playback Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.531 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: Clearing all streams Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.531 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: [Stream 13]: Disposing of audio stream Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.531 [3939.3939] INFO ContentFetcher: [0xaca6db78]: Canceling download Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.540 [3939.3939] INFO SampleApp: Playback state changed: Stopped Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.541 [3939.3939] INFO StreamingEventReporter: [0xac581950]: Reporting streaming ended event Jul 04 21:15:45 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:45 rivo-toshi volumio[3290]: info: CoreCommandRouter::servicePushState Jul 04 21:15:45 rivo-toshi volumio[3290]: info: CoreStateMachine::pushState Jul 04 21:15:45 rivo-toshi volumio[3290]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 21:15:45 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioPushState Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.541 [3939.3939] INFO HttpDownloader: [0xac5819a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.542 [3939.3939] INFO ProtocolHandler: [0xac581538]: Need to send state update Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.542 [3939.3939] INFO QConnectMessageSender: [0xac707008]: Sending StateUpdated message Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.542 [3939.3939] INFO SampleApp: [Stream 13]: Audio stream has been disposed of Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.542 [3939.3939] INFO MediaEngine: [0xac580df8]: Setting current track: 14345385, initial offset: 0ms Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.542 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: Clearing all streams Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.542 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: New stream: 15 Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.542 [3939.3939] INFO HttpDownloader: [0xac6702b0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=254f05a4582c6e835013477c37a623a6&request_ts=1751631345&track_id=14345385 Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.542 [3939.3939] INFO HttpDownloader: [0xacb8b880]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=14345385 Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.543 [3939.3939] INFO HttpDownloader: [0xacbf8720]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=cf1bd9ab7546255df2be33da905310c7&request_ts=1751631345&track_id=14345385 Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.543 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: [Stream 15]: Running audio stream Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.543 [3939.3939] INFO QwspMessageSender: [0xac706ff8]: Sending Payload message Jul 04 21:15:45 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:45 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output update for this device Jul 04 21:15:45 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output Jul 04 21:15:45 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:45 rivo-toshi volumio[3290]: info: CoreCommandRouter::servicePushState Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.598 [3939.3939] INFO MediaEngine: [0xac580df8]: Setting next track: 14345386 Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.598 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: New stream: 16 Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.598 [3939.3939] INFO HttpDownloader: [0xac681a10]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=84f0ed762b990694bfc333f6726cef6c&request_ts=1751631345&track_id=14345386 Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.598 [3939.3939] INFO HttpDownloader: [0xacbf8440]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=14345386 Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.599 [3939.3939] INFO HttpDownloader: [0xac6bd118]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=79491fa65458451a1ed97ff217a192cb&request_ts=1751631345&track_id=14345386 Jul 04 21:15:45 rivo-toshi volumio[3290]: info: CoreStateMachine::pushState Jul 04 21:15:45 rivo-toshi volumio[3290]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 21:15:45 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioPushState Jul 04 21:15:45 rivo-toshi qobuz-connect[3939]: 20250704 21:15:45.600 [3939.3939] INFO MediaEngine: [0xac580df8]: Waiting for current stream to start before starting audio renderer Jul 04 21:15:45 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:45 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output update for this device Jul 04 21:15:45 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output Jul 04 21:15:45 rivo-toshi volumio[3290]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 21:15:45 rivo-toshi volumio[3290]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 21:15:45 rivo-toshi volumio[3290]: info: MCU Signalled Playback Inactive Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.260 [3939.3939] INFO ProtocolHandler: [0xac581538]: Received SetState message: Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.261 [3939.3939] INFO ProtocolHandler: [0xac581538]: Playing state: Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.261 [3939.3939] INFO ProtocolHandler: [0xac581538]: Playback position: Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.261 [3939.3939] INFO ProtocolHandler: [0xac581538]: Queue version: 4.2 Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.261 [3939.3939] INFO ProtocolHandler: [0xac581538]: Current track: Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.261 [3939.3939] INFO ProtocolHandler: [0xac581538]: Next track: TID: 14345386, QID: 1, Context UUID: 89bbcf4c-a762-43ed-bdf9-c776059eb415 Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.479 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: [Stream 16]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=14345386&fmt=27&profile=raw&app_id=174516466&cid=2464732&etsp=1751634946&hmac=wO4Hv6Te7tnOYWCxrqG8erKQWgM Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.540 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: [Stream 15]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=14345385&fmt=27&profile=raw&app_id=174516466&cid=2464732&etsp=1751634946&hmac=5xk6IQinOCW8FojZPNca6TaR9Ak Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.559 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: [Stream 15]: Metadata became available: Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.559 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: Title: Happy Birthday 1975 Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.559 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: Artist: Joni Mitchell Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.559 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: Album: Mingus Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.559 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: Album art URL: https://static.qobuz.com/images/covers/56/69/0603497906956_600.jpg Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.587 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: [Stream 16]: stream information have been fetched Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.589 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: [Stream 15]: stream information have been fetched Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.590 [3939.3939] INFO UrlAudioSource: [0xaca07d48]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=14345385&fmt=27&profile=raw&app_id=174516466&cid=2464732&etsp=1751634946&hmac=5xk6IQinOCW8FojZPNca6TaR9Ak Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.590 [3939.3939] INFO ContentFetcher: [0xac9cdb88]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=14345385&fmt=27&profile=raw&app_id=174516466&cid=2464732&etsp=1751634946&hmac=5xk6IQinOCW8FojZPNca6TaR9Ak, offset: 0 Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.590 [3939.3939] INFO AudioRenderer: [0xac581018]: Starting audio renderer, initial playback state: Playing Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.591 [3939.3939] INFO SampleApp: [Stream 15]: New audio stream (starting from 0ms) Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.592 [3939.3939] INFO SampleApp: [Stream 15]: Stream metadata became available: Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.592 [3939.3939] INFO SampleApp: Title: Happy Birthday 1975 Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.592 [3939.3939] INFO SampleApp: Artist: Joni Mitchell Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.592 [3939.3939] INFO SampleApp: Album: Mingus Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.592 [3939.3939] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/56/69/0603497906956_600.jpg Jul 04 21:15:46 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:46 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:46 rivo-toshi volumio[3290]: info: QobuzConnect: alread currrent service, send playback confirmation Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.594 [3939.3939] INFO SampleApp: Initiating playback, initial state: Playing Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.794 [3939.3939] INFO SampleApp: Playback state changed: Playing Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.794 [3939.3939] INFO StreamingEventReporter: [0xac581950]: Reporting streaming started event Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.795 [3939.3939] INFO ProtocolHandler: [0xac581538]: Need to send state update Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.795 [3939.3939] INFO QConnectMessageSender: [0xac707008]: Sending StateUpdated message Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.795 [3939.3939] INFO QwspMessageSender: [0xac706ff8]: Sending Payload message Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.795 [3939.3939] INFO QConnectMessageSender: [0xac707008]: Sending FileAudioQualityChanged message Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.796 [3939.3939] INFO QwspMessageSender: [0xac706ff8]: Sending Payload message Jul 04 21:15:46 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:46 rivo-toshi volumio[3290]: info: CoreCommandRouter::servicePushState Jul 04 21:15:46 rivo-toshi volumio[3290]: info: CoreStateMachine::pushState Jul 04 21:15:46 rivo-toshi volumio[3290]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 21:15:46 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioPushState Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.797 [3939.3939] INFO HttpDownloader: [0xac5819a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.798 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: [Stream 16]: Metadata became available: Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.798 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: Title: God Must Be a Boogie Man Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.798 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: Artist: Joni Mitchell Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.799 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: Album: Mingus Jul 04 21:15:46 rivo-toshi qobuz-connect[3939]: 20250704 21:15:46.799 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: Album art URL: https://static.qobuz.com/images/covers/56/69/0603497906956_600.jpg Jul 04 21:15:46 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:46 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output update for this device Jul 04 21:15:46 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output Jul 04 21:15:46 rivo-toshi volumio[3290]: info: CoreCommandRouter::servicePushState Jul 04 21:15:46 rivo-toshi volumio[3290]: info: CoreStateMachine::pushState Jul 04 21:15:46 rivo-toshi volumio[3290]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 21:15:46 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioPushState Jul 04 21:15:46 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:46 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output update for this device Jul 04 21:15:46 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output Jul 04 21:15:46 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:46 rivo-toshi volumio[3290]: info: CoreCommandRouter::servicePushState Jul 04 21:15:46 rivo-toshi volumio[3290]: info: CoreStateMachine::pushState Jul 04 21:15:46 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioPushState Jul 04 21:15:46 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:46 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output update for this device Jul 04 21:15:46 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output Jul 04 21:15:46 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:46 rivo-toshi volumio[3290]: info: CoreCommandRouter::servicePushState Jul 04 21:15:46 rivo-toshi volumio[3290]: info: CoreStateMachine::pushState Jul 04 21:15:46 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioPushState Jul 04 21:15:46 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:46 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output update for this device Jul 04 21:15:46 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output Jul 04 21:15:46 rivo-toshi volumio[3290]: info: [LastFM] Current track has sufficient metadata: title (Happy Birthday 1975) and artist (Joni Mitchell) passed on explicitly Jul 04 21:15:46 rivo-toshi volumio[3290]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Jul 04 21:15:46 rivo-toshi volumio[3290]: info: Signalling Playback active due to playback status change Jul 04 21:15:46 rivo-toshi volumio[3290]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 21:15:46 rivo-toshi volumio[3290]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 21:15:46 rivo-toshi volumio[3290]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 21:15:46 rivo-toshi volumio[3290]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 21:15:46 rivo-toshi volumio[3290]: info: MCU Signalled Playback Active Jul 04 21:15:48 rivo-toshi qobuz-connect[3939]: 20250704 21:15:48.360 [3939.3939] INFO UrlAudioSource: [0xaca07d48]: Content info extracted Jul 04 21:15:48 rivo-toshi qobuz-connect[3939]: 20250704 21:15:48.360 [3939.3939] INFO UrlAudioSource: [0xaca07d48]: Size: 30856292 Jul 04 21:15:48 rivo-toshi qobuz-connect[3939]: 20250704 21:15:48.363 [3939.19664] INFO AudioDecoder: [0xacca0eb8]: Stream info: Jul 04 21:15:48 rivo-toshi qobuz-connect[3939]: 20250704 21:15:48.363 [3939.19664] INFO AudioDecoder: [0xacca0eb8]: Total samples: 10944000 Jul 04 21:15:48 rivo-toshi qobuz-connect[3939]: 20250704 21:15:48.363 [3939.19664] INFO AudioDecoder: [0xacca0eb8]: Sample rate: 192000 Jul 04 21:15:48 rivo-toshi qobuz-connect[3939]: 20250704 21:15:48.363 [3939.19664] INFO AudioDecoder: [0xacca0eb8]: Channel count: 2 Jul 04 21:15:48 rivo-toshi qobuz-connect[3939]: 20250704 21:15:48.364 [3939.19664] INFO AudioDecoder: [0xacca0eb8]: Bits per sample: 24 Jul 04 21:15:48 rivo-toshi qobuz-connect[3939]: 20250704 21:15:48.364 [3939.19664] INFO AudioDecoder: [0xacca0eb8]: Duration: 57000 Jul 04 21:15:48 rivo-toshi qobuz-connect[3939]: 20250704 21:15:48.364 [3939.3939] INFO UrlAudioSource: [0xaca07d48]: Audio properties have changed Jul 04 21:15:48 rivo-toshi qobuz-connect[3939]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:912:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot write to target PCM volumioOutput as it has failed its update check. Jul 04 21:15:48 rivo-toshi qobuz-connect[3939]: 20250704 21:15:48.627 [3939.3939] WARNING SampleApp: Unable to query available space in ALSA audio sink, trying to recover Jul 04 21:15:48 rivo-toshi qobuz-connect[3939]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:912:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot write to target PCM volumioOutput as it has failed its update check. Jul 04 21:15:48 rivo-toshi qobuz-connect[3939]: 20250704 21:15:48.746 [3939.3939] WARNING SampleApp: Unable to query available space in ALSA audio sink, trying to recover Jul 04 21:15:48 rivo-toshi volumio[3290]: info: Executing endpoint metavolumio Jul 04 21:15:48 rivo-toshi volumio[3290]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 04 21:15:48 rivo-toshi volumio[3290]: info: Executing endpoint metavolumio Jul 04 21:15:48 rivo-toshi volumio[3290]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 04 21:15:48 rivo-toshi volumio[3290]: info: Executing endpoint metavolumio Jul 04 21:15:48 rivo-toshi volumio[3290]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 04 21:15:48 rivo-toshi qobuz-connect[3939]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:912:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot write to target PCM volumioOutput as it has failed its update check. Jul 04 21:15:48 rivo-toshi qobuz-connect[3939]: 20250704 21:15:48.983 [3939.3939] WARNING SampleApp: Unable to query available space in ALSA audio sink, trying to recover Jul 04 21:15:49 rivo-toshi qobuz-connect[3939]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:912:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot write to target PCM volumioOutput as it has failed its update check. Jul 04 21:15:49 rivo-toshi qobuz-connect[3939]: 20250704 21:15:49.132 [3939.3939] WARNING SampleApp: Unable to query available space in ALSA audio sink, trying to recover Jul 04 21:15:51 rivo-toshi volumio[3290]: error: Failed request for metavolumio API Jul 04 21:15:56 rivo-toshi qobuz-connect[3939]: 20250704 21:15:56.795 [3939.3939] INFO ProtocolHandler: [0xac581538]: Need to send state update Jul 04 21:15:56 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:56 rivo-toshi volumio[3290]: info: CoreCommandRouter::servicePushState Jul 04 21:15:56 rivo-toshi volumio[3290]: info: CoreStateMachine::pushState Jul 04 21:15:56 rivo-toshi volumio[3290]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 21:15:56 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioPushState Jul 04 21:15:56 rivo-toshi qobuz-connect[3939]: 20250704 21:15:56.801 [3939.3939] INFO QConnectMessageSender: [0xac707008]: Sending StateUpdated message Jul 04 21:15:56 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:56 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output update for this device Jul 04 21:15:56 rivo-toshi qobuz-connect[3939]: 20250704 21:15:56.802 [3939.3939] INFO QwspMessageSender: [0xac706ff8]: Sending Payload message Jul 04 21:15:56 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output Jul 04 21:15:56 rivo-toshi volumio[3290]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 21:16:06 rivo-toshi qobuz-connect[3939]: 20250704 21:16:06.795 [3939.3939] INFO ProtocolHandler: [0xac581538]: Need to send state update Jul 04 21:16:06 rivo-toshi qobuz-connect[3939]: 20250704 21:16:06.795 [3939.3939] INFO QConnectMessageSender: [0xac707008]: Sending StateUpdated message Jul 04 21:16:06 rivo-toshi qobuz-connect[3939]: 20250704 21:16:06.795 [3939.3939] INFO QwspMessageSender: [0xac706ff8]: Sending Payload message Jul 04 21:16:06 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:16:06 rivo-toshi volumio[3290]: info: CoreCommandRouter::servicePushState Jul 04 21:16:06 rivo-toshi volumio[3290]: info: CoreStateMachine::pushState Jul 04 21:16:06 rivo-toshi volumio[3290]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 21:16:06 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioPushState Jul 04 21:16:06 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:16:06 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output update for this device Jul 04 21:16:06 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output Jul 04 21:16:06 rivo-toshi volumio[3290]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 21:16:16 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:16:16 rivo-toshi volumio[3290]: info: CoreCommandRouter::servicePushState Jul 04 21:16:16 rivo-toshi volumio[3290]: info: CoreStateMachine::pushState Jul 04 21:16:16 rivo-toshi volumio[3290]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 21:16:16 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioPushState Jul 04 21:16:16 rivo-toshi qobuz-connect[3939]: 20250704 21:16:16.801 [3939.3939] INFO ProtocolHandler: [0xac581538]: Need to send state update Jul 04 21:16:16 rivo-toshi qobuz-connect[3939]: 20250704 21:16:16.803 [3939.3939] INFO QConnectMessageSender: [0xac707008]: Sending StateUpdated message Jul 04 21:16:16 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:16:16 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output update for this device Jul 04 21:16:16 rivo-toshi qobuz-connect[3939]: 20250704 21:16:16.804 [3939.3939] INFO QwspMessageSender: [0xac706ff8]: Sending Payload message Jul 04 21:16:16 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output Jul 04 21:16:16 rivo-toshi volumio[3290]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.507 [3939.3939] INFO ProtocolHandler: [0xac581538]: Received SetState message: Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.507 [3939.3939] INFO ProtocolHandler: [0xac581538]: Playing state: Playing Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.508 [3939.3939] INFO ProtocolHandler: [0xac581538]: Playback position: 0 Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.508 [3939.3939] INFO ProtocolHandler: [0xac581538]: Queue version: 4.2 Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.508 [3939.3939] INFO ProtocolHandler: [0xac581538]: Current track: TID: 14345386, QID: 1, Context UUID: 89bbcf4c-a762-43ed-bdf9-c776059eb415 Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.508 [3939.3939] INFO ProtocolHandler: [0xac581538]: Next track: TID: 14345387, QID: 2, Context UUID: 89bbcf4c-a762-43ed-bdf9-c776059eb415 Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.508 [3939.3939] INFO MediaEngine: [0xac580df8]: Stopping playback, clearing tracks Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.508 [3939.3939] INFO MediaEngine: [0xac580df8]: Initiating playback Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.508 [3939.3939] INFO AudioRenderer: [0xac581018]: Stopping audio renderer Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.508 [3939.3939] INFO SampleApp: Stopping playback Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.523 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: Clearing all streams Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.523 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: [Stream 15]: Disposing of audio stream Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.523 [3939.3939] INFO ContentFetcher: [0xac9cdb88]: Canceling download Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.531 [3939.3939] INFO SampleApp: Playback state changed: Stopped Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.531 [3939.3939] INFO StreamingEventReporter: [0xac581950]: Reporting streaming ended event Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.532 [3939.3939] INFO HttpDownloader: [0xac5819a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.532 [3939.3939] INFO ProtocolHandler: [0xac581538]: Need to send state update Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.532 [3939.3939] INFO QConnectMessageSender: [0xac707008]: Sending StateUpdated message Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.532 [3939.3939] INFO SampleApp: [Stream 15]: Audio stream has been disposed of Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.532 [3939.3939] INFO MediaEngine: [0xac580df8]: Setting current track: 14345386, initial offset: 0ms Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.532 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: Clearing all streams Jul 04 21:16:18 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:16:18 rivo-toshi volumio[3290]: info: CoreCommandRouter::servicePushState Jul 04 21:16:18 rivo-toshi volumio[3290]: info: CoreStateMachine::pushState Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.532 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: New stream: 17 Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.533 [3939.3939] INFO HttpDownloader: [0xac5ac768]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=f0f55af2709d65374014aa4dc967c425&request_ts=1751631378&track_id=14345386 Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.533 [3939.3939] INFO HttpDownloader: [0xac9c8538]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=14345386 Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.533 [3939.3939] INFO HttpDownloader: [0xac5c9e30]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=bd14be006cd74588117bde031b1328b8&request_ts=1751631378&track_id=14345386 Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.534 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: [Stream 17]: Running audio stream Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.534 [3939.3939] INFO QwspMessageSender: [0xac706ff8]: Sending Payload message Jul 04 21:16:18 rivo-toshi volumio[3290]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 21:16:18 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioPushState Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.537 [3939.3939] INFO MediaEngine: [0xac580df8]: Setting next track: 14345387 Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.538 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: New stream: 18 Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.538 [3939.3939] INFO HttpDownloader: [0xac5ac790]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=31ab97db9bdda4c697513d91a7d62aa5&request_ts=1751631378&track_id=14345387 Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.538 [3939.3939] INFO HttpDownloader: [0xac6728a0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=14345387 Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.538 [3939.3939] INFO HttpDownloader: [0xac8bc1e8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=e7f71f934773421fc65b1fc251e320c2&request_ts=1751631378&track_id=14345387 Jul 04 21:16:18 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:16:18 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output update for this device Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.540 [3939.3939] INFO MediaEngine: [0xac580df8]: Waiting for current stream to start before starting audio renderer Jul 04 21:16:18 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output Jul 04 21:16:18 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:16:18 rivo-toshi volumio[3290]: info: CoreCommandRouter::servicePushState Jul 04 21:16:18 rivo-toshi volumio[3290]: info: CoreStateMachine::pushState Jul 04 21:16:18 rivo-toshi volumio[3290]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 21:16:18 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioPushState Jul 04 21:16:18 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:16:18 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output update for this device Jul 04 21:16:18 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output Jul 04 21:16:18 rivo-toshi volumio[3290]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 04 21:16:18 rivo-toshi volumio[3290]: info: MCU Signalled Playback Inactive Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.883 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: [Stream 17]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=14345386&fmt=27&profile=raw&app_id=174516466&cid=2464732&etsp=1751634978&hmac=kLYYH8uVoVpOLsPlhZYZ3hkaSRw Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.883 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: [Stream 18]: Metadata became available: Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.883 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: Title: Funeral Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.883 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: Artist: Joni Mitchell Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.883 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: Album: Mingus Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.883 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: Album art URL: https://static.qobuz.com/images/covers/56/69/0603497906956_600.jpg Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.883 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: [Stream 18]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=14345387&fmt=27&profile=raw&app_id=174516466&cid=2464732&etsp=1751634978&hmac=i2P90IPrNEKTjTRBPodTsKSIFeo Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.885 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: [Stream 17]: stream information have been fetched Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.885 [3939.3939] INFO UrlAudioSource: [0xacc9e538]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=14345386&fmt=27&profile=raw&app_id=174516466&cid=2464732&etsp=1751634978&hmac=kLYYH8uVoVpOLsPlhZYZ3hkaSRw Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.885 [3939.3939] INFO ContentFetcher: [0xac5eb680]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=14345386&fmt=27&profile=raw&app_id=174516466&cid=2464732&etsp=1751634978&hmac=kLYYH8uVoVpOLsPlhZYZ3hkaSRw, offset: 0 Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.886 [3939.3939] INFO AudioRenderer: [0xac581018]: Starting audio renderer, initial playback state: Playing Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.886 [3939.3939] INFO SampleApp: [Stream 17]: New audio stream (starting from 0ms) Jul 04 21:16:18 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:16:18 rivo-toshi volumio[3290]: info: QobuzConnect: alread currrent service, send playback confirmation Jul 04 21:16:18 rivo-toshi qobuz-connect[3939]: 20250704 21:16:18.888 [3939.3939] INFO SampleApp: Initiating playback, initial state: Playing Jul 04 21:16:19 rivo-toshi qobuz-connect[3939]: 20250704 21:16:19.160 [3939.3939] INFO SampleApp: Playback state changed: Playing Jul 04 21:16:19 rivo-toshi qobuz-connect[3939]: 20250704 21:16:19.160 [3939.3939] INFO StreamingEventReporter: [0xac581950]: Reporting streaming started event Jul 04 21:16:19 rivo-toshi qobuz-connect[3939]: 20250704 21:16:19.161 [3939.3939] INFO ProtocolHandler: [0xac581538]: Need to send state update Jul 04 21:16:19 rivo-toshi qobuz-connect[3939]: 20250704 21:16:19.161 [3939.3939] INFO QConnectMessageSender: [0xac707008]: Sending StateUpdated message Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreCommandRouter::servicePushState Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreStateMachine::pushState Jul 04 21:16:19 rivo-toshi qobuz-connect[3939]: 20250704 21:16:19.161 [3939.3939] INFO QwspMessageSender: [0xac706ff8]: Sending Payload message Jul 04 21:16:19 rivo-toshi qobuz-connect[3939]: 20250704 21:16:19.161 [3939.3939] INFO QConnectMessageSender: [0xac707008]: Sending FileAudioQualityChanged message Jul 04 21:16:19 rivo-toshi qobuz-connect[3939]: 20250704 21:16:19.162 [3939.3939] INFO QwspMessageSender: [0xac706ff8]: Sending Payload message Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioPushState Jul 04 21:16:19 rivo-toshi qobuz-connect[3939]: 20250704 21:16:19.163 [3939.3939] INFO HttpDownloader: [0xac5819a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jul 04 21:16:19 rivo-toshi qobuz-connect[3939]: 20250704 21:16:19.164 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: [Stream 17]: Metadata became available: Jul 04 21:16:19 rivo-toshi qobuz-connect[3939]: 20250704 21:16:19.164 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: Title: God Must Be a Boogie Man Jul 04 21:16:19 rivo-toshi qobuz-connect[3939]: 20250704 21:16:19.164 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: Artist: Joni Mitchell Jul 04 21:16:19 rivo-toshi qobuz-connect[3939]: 20250704 21:16:19.164 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: Album: Mingus Jul 04 21:16:19 rivo-toshi qobuz-connect[3939]: 20250704 21:16:19.164 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: Album art URL: https://static.qobuz.com/images/covers/56/69/0603497906956_600.jpg Jul 04 21:16:19 rivo-toshi qobuz-connect[3939]: 20250704 21:16:19.164 [3939.3939] INFO AudioStreamManager: [0xac580ee0]: [Stream 18]: stream information have been fetched Jul 04 21:16:19 rivo-toshi qobuz-connect[3939]: 20250704 21:16:19.165 [3939.3939] INFO SampleApp: [Stream 17]: Stream metadata became available: Jul 04 21:16:19 rivo-toshi qobuz-connect[3939]: 20250704 21:16:19.165 [3939.3939] INFO SampleApp: Title: God Must Be a Boogie Man Jul 04 21:16:19 rivo-toshi qobuz-connect[3939]: 20250704 21:16:19.165 [3939.3939] INFO SampleApp: Artist: Joni Mitchell Jul 04 21:16:19 rivo-toshi qobuz-connect[3939]: 20250704 21:16:19.165 [3939.3939] INFO SampleApp: Album: Mingus Jul 04 21:16:19 rivo-toshi qobuz-connect[3939]: 20250704 21:16:19.165 [3939.3939] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/56/69/0603497906956_600.jpg Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:16:19 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output update for this device Jul 04 21:16:19 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreCommandRouter::servicePushState Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreStateMachine::pushState Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioPushState Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:16:19 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output update for this device Jul 04 21:16:19 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreCommandRouter::servicePushState Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreStateMachine::pushState Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioPushState Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:16:19 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output update for this device Jul 04 21:16:19 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreCommandRouter::servicePushState Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreStateMachine::pushState Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioPushState Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:16:19 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output update for this device Jul 04 21:16:19 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreCommandRouter::servicePushState Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreStateMachine::pushState Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioPushState Jul 04 21:16:19 rivo-toshi volumio[3290]: info: CoreCommandRouter::volumioGetState Jul 04 21:16:19 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output update for this device Jul 04 21:16:19 rivo-toshi volumio[3290]: info: MRS: Pushing multiroomSync output Jul 04 21:16:19 rivo-toshi volumio[3290]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Jul 04 21:16:19 rivo-toshi volumio[3290]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 21:16:19 rivo-toshi volumio[3290]: TypeError: Cannot read property 'indexOf' of undefined Jul 04 21:16:19 rivo-toshi volumio[3290]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Jul 04 21:16:19 rivo-toshi volumio[3290]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Jul 04 21:16:19 rivo-toshi volumio[3290]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Jul 04 21:16:19 rivo-toshi volumio[3290]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jul 04 21:16:19 rivo-toshi volumio[3290]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Jul 04 21:16:19 rivo-toshi volumio[3290]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Jul 04 21:16:19 rivo-toshi volumio[3290]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jul 04 21:16:19 rivo-toshi volumio[3290]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jul 04 21:16:19 rivo-toshi volumio[3290]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Jul 04 21:16:19 rivo-toshi volumio[3290]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jul 04 21:16:19 rivo-toshi volumio[3290]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Jul 04 21:16:19 rivo-toshi volumio[3290]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Jul 04 21:16:19 rivo-toshi volumio[3290]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Jul 04 21:16:19 rivo-toshi volumio[3290]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jul 04 21:16:19 rivo-toshi volumio[3290]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jul 04 21:16:19 rivo-toshi volumio[3290]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Jul 04 21:16:19 rivo-toshi volumio[3290]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 21:16:20 rivo-toshi sudo[19744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-04 21:15 Jul 04 21:16:20 rivo-toshi sudo[19744]: 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"