-- Logs begin at Thu 2019-02-14 19:11:59 JST, end at Tue 2026-08-25 23:24:21 JST. -- Aug 25 23:23:00 volumio33 qobuz-connect[1595]: 20260825 23:23:00.297 [1595.1595] INFO ProtocolHandler: [0x163a268]: Need to send state update Aug 25 23:23:00 volumio33 qobuz-connect[1595]: 20260825 23:23:00.297 [1595.1595] INFO QConnectMessageSender: [0x1677a28]: Sending StateUpdated message Aug 25 23:23:00 volumio33 qobuz-connect[1595]: 20260825 23:23:00.297 [1595.1595] INFO QwspMessageSender: [0x1677a18]: Sending Payload message Aug 25 23:23:00 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:23:00 volumio33 volumio[1171]: info: CoreCommandRouter::servicePushState Aug 25 23:23:00 volumio33 volumio[1171]: info: CoreStateMachine::pushState Aug 25 23:23:00 volumio33 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:23:00 volumio33 volumio[1171]: info: CoreCommandRouter::volumioPushState Aug 25 23:23:00 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output update for this device Aug 25 23:23:00 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output Aug 25 23:23:00 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:23:10 volumio33 qobuz-connect[1595]: 20260825 23:23:10.297 [1595.1595] INFO ProtocolHandler: [0x163a268]: Need to send state update Aug 25 23:23:10 volumio33 qobuz-connect[1595]: 20260825 23:23:10.297 [1595.1595] INFO QConnectMessageSender: [0x1677a28]: Sending StateUpdated message Aug 25 23:23:10 volumio33 qobuz-connect[1595]: 20260825 23:23:10.297 [1595.1595] INFO QwspMessageSender: [0x1677a18]: Sending Payload message Aug 25 23:23:10 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:23:10 volumio33 volumio[1171]: info: CoreCommandRouter::servicePushState Aug 25 23:23:10 volumio33 volumio[1171]: info: CoreStateMachine::pushState Aug 25 23:23:10 volumio33 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:23:10 volumio33 volumio[1171]: info: CoreCommandRouter::volumioPushState Aug 25 23:23:10 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output update for this device Aug 25 23:23:10 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output Aug 25 23:23:10 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:23:20 volumio33 qobuz-connect[1595]: 20260825 23:23:20.297 [1595.1595] INFO ProtocolHandler: [0x163a268]: Need to send state update Aug 25 23:23:20 volumio33 qobuz-connect[1595]: 20260825 23:23:20.297 [1595.1595] INFO QConnectMessageSender: [0x1677a28]: Sending StateUpdated message Aug 25 23:23:20 volumio33 qobuz-connect[1595]: 20260825 23:23:20.298 [1595.1595] INFO QwspMessageSender: [0x1677a18]: Sending Payload message Aug 25 23:23:20 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:23:20 volumio33 volumio[1171]: info: CoreCommandRouter::servicePushState Aug 25 23:23:20 volumio33 volumio[1171]: info: CoreStateMachine::pushState Aug 25 23:23:20 volumio33 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:23:20 volumio33 volumio[1171]: info: CoreCommandRouter::volumioPushState Aug 25 23:23:20 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output update for this device Aug 25 23:23:20 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output Aug 25 23:23:20 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:23:30 volumio33 qobuz-connect[1595]: 20260825 23:23:30.297 [1595.1595] INFO ProtocolHandler: [0x163a268]: Need to send state update Aug 25 23:23:30 volumio33 qobuz-connect[1595]: 20260825 23:23:30.297 [1595.1595] INFO QConnectMessageSender: [0x1677a28]: Sending StateUpdated message Aug 25 23:23:30 volumio33 qobuz-connect[1595]: 20260825 23:23:30.297 [1595.1595] INFO QwspMessageSender: [0x1677a18]: Sending Payload message Aug 25 23:23:30 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:23:30 volumio33 volumio[1171]: info: CoreCommandRouter::servicePushState Aug 25 23:23:30 volumio33 volumio[1171]: info: CoreStateMachine::pushState Aug 25 23:23:30 volumio33 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:23:30 volumio33 volumio[1171]: info: CoreCommandRouter::volumioPushState Aug 25 23:23:30 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output update for this device Aug 25 23:23:30 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output Aug 25 23:23:30 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:23:40 volumio33 qobuz-connect[1595]: 20260825 23:23:40.297 [1595.1595] INFO ProtocolHandler: [0x163a268]: Need to send state update Aug 25 23:23:40 volumio33 qobuz-connect[1595]: 20260825 23:23:40.297 [1595.1595] INFO QConnectMessageSender: [0x1677a28]: Sending StateUpdated message Aug 25 23:23:40 volumio33 qobuz-connect[1595]: 20260825 23:23:40.298 [1595.1595] INFO QwspMessageSender: [0x1677a18]: Sending Payload message Aug 25 23:23:40 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:23:40 volumio33 volumio[1171]: info: CoreCommandRouter::servicePushState Aug 25 23:23:40 volumio33 volumio[1171]: info: CoreStateMachine::pushState Aug 25 23:23:40 volumio33 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:23:40 volumio33 volumio[1171]: info: CoreCommandRouter::volumioPushState Aug 25 23:23:40 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output update for this device Aug 25 23:23:40 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output Aug 25 23:23:40 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:23:50 volumio33 qobuz-connect[1595]: 20260825 23:23:50.297 [1595.1595] INFO ProtocolHandler: [0x163a268]: Need to send state update Aug 25 23:23:50 volumio33 qobuz-connect[1595]: 20260825 23:23:50.297 [1595.1595] INFO QConnectMessageSender: [0x1677a28]: Sending StateUpdated message Aug 25 23:23:50 volumio33 qobuz-connect[1595]: 20260825 23:23:50.297 [1595.1595] INFO QwspMessageSender: [0x1677a18]: Sending Payload message Aug 25 23:23:50 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:23:50 volumio33 volumio[1171]: info: CoreCommandRouter::servicePushState Aug 25 23:23:50 volumio33 volumio[1171]: info: CoreStateMachine::pushState Aug 25 23:23:50 volumio33 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:23:50 volumio33 volumio[1171]: info: CoreCommandRouter::volumioPushState Aug 25 23:23:50 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output update for this device Aug 25 23:23:50 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output Aug 25 23:23:50 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:24:00 volumio33 qobuz-connect[1595]: 20260825 23:24:00.297 [1595.1595] INFO ProtocolHandler: [0x163a268]: Need to send state update Aug 25 23:24:00 volumio33 qobuz-connect[1595]: 20260825 23:24:00.297 [1595.1595] INFO QConnectMessageSender: [0x1677a28]: Sending StateUpdated message Aug 25 23:24:00 volumio33 qobuz-connect[1595]: 20260825 23:24:00.297 [1595.1595] INFO QwspMessageSender: [0x1677a18]: Sending Payload message Aug 25 23:24:00 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:24:00 volumio33 volumio[1171]: info: CoreCommandRouter::servicePushState Aug 25 23:24:00 volumio33 volumio[1171]: info: CoreStateMachine::pushState Aug 25 23:24:00 volumio33 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:24:00 volumio33 volumio[1171]: info: CoreCommandRouter::volumioPushState Aug 25 23:24:00 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output update for this device Aug 25 23:24:00 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output Aug 25 23:24:00 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:24:09 volumio33 qobuz-connect[1595]: 20260825 23:24:09.363 [1595.1595] INFO AudioStreamManager: [0x1639d40]: [Stream 70]: Running audio stream Aug 25 23:24:09 volumio33 qobuz-connect[1595]: 20260825 23:24:09.363 [1595.1595] INFO UrlAudioSource: [0x18bdf40]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=12792238&eid=429133729&fmt=7&profile=raw&app_id=174516466&cid=4387967&etsp=1787671230&hmac=i9wEIiw3K3Fr-hAwtcjzJ1egDG4 Aug 25 23:24:09 volumio33 qobuz-connect[1595]: 20260825 23:24:09.363 [1595.1595] INFO ContentFetcher: [0x1a0ae98]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=12792238&eid=429133729&fmt=7&profile=raw&app_id=174516466&cid=4387967&etsp=1787671230&hmac=i9wEIiw3K3Fr-hAwtcjzJ1egDG4, offset: 0 Aug 25 23:24:09 volumio33 qobuz-connect[1595]: 20260825 23:24:09.363 [1595.1595] INFO SampleApp: [Stream 70]: New audio stream (starting from 0ms) Aug 25 23:24:09 volumio33 qobuz-connect[1595]: 20260825 23:24:09.363 [1595.1595] INFO SampleApp: [Stream 70]: Stream metadata became available: Aug 25 23:24:09 volumio33 qobuz-connect[1595]: 20260825 23:24:09.363 [1595.1595] INFO SampleApp: Title: 老人と海 Aug 25 23:24:09 volumio33 qobuz-connect[1595]: 20260825 23:24:09.363 [1595.1595] INFO SampleApp: Artist: ヨルシカ Aug 25 23:24:09 volumio33 qobuz-connect[1595]: 20260825 23:24:09.363 [1595.1595] INFO SampleApp: Album: 幻燈 Aug 25 23:24:09 volumio33 qobuz-connect[1595]: 20260825 23:24:09.363 [1595.1595] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/h2/3g/i5wzyxwz33gh2_600.jpg Aug 25 23:24:09 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:24:09 volumio33 qobuz-connect[1595]: 20260825 23:24:09.566 [1595.1595] INFO UrlAudioSource: [0x18bdf40]: Content info extracted Aug 25 23:24:09 volumio33 qobuz-connect[1595]: 20260825 23:24:09.567 [1595.1595] INFO UrlAudioSource: [0x18bdf40]: Size: 92562000 Aug 25 23:24:10 volumio33 qobuz-connect[1595]: 20260825 23:24:10.297 [1595.1595] INFO ProtocolHandler: [0x163a268]: Need to send state update Aug 25 23:24:10 volumio33 qobuz-connect[1595]: 20260825 23:24:10.297 [1595.1595] INFO QConnectMessageSender: [0x1677a28]: Sending StateUpdated message Aug 25 23:24:10 volumio33 qobuz-connect[1595]: 20260825 23:24:10.297 [1595.1595] INFO QwspMessageSender: [0x1677a18]: Sending Payload message Aug 25 23:24:10 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:24:10 volumio33 volumio[1171]: info: CoreCommandRouter::servicePushState Aug 25 23:24:10 volumio33 volumio[1171]: info: CoreStateMachine::pushState Aug 25 23:24:10 volumio33 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:24:10 volumio33 volumio[1171]: info: CoreCommandRouter::volumioPushState Aug 25 23:24:10 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output update for this device Aug 25 23:24:10 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output Aug 25 23:24:10 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:24:12 volumio33 qobuz-connect[1595]: 20260825 23:24:12.865 [1595.1595] ERROR HttpClient: [0x19f2670]: Client finished: Transferred a partial file (18) Aug 25 23:24:12 volumio33 qobuz-connect[1595]: 20260825 23:24:12.866 [1595.1595] ERROR UrlAudioSource: [0x18bdf40]: Content fetcher has encountered an error Aug 25 23:24:12 volumio33 qobuz-connect[1595]: 20260825 23:24:12.866 [1595.1595] INFO ContentFetcher: [0x1a0ae98]: Canceling download Aug 25 23:24:12 volumio33 qobuz-connect[1595]: 20260825 23:24:12.866 [1595.1595] ERROR AudioStreamManager: [0x1639d40]: [Stream 70]: Audio stream has encountered an error Aug 25 23:24:12 volumio33 qobuz-connect[1595]: 20260825 23:24:12.866 [1595.1595] ERROR MediaEngine: [0x1639c90]: Next stream has encountered an error Aug 25 23:24:18 volumio33 qobuz-connect[1595]: 20260825 23:24:18.477 [1595.1595] INFO AudioStreamManager: [0x1639d40]: [Stream 69]: Audio stream has finished Aug 25 23:24:18 volumio33 qobuz-connect[1595]: 20260825 23:24:18.477 [1595.1595] INFO SampleApp: [Stream 69]: Audio stream finished Aug 25 23:24:18 volumio33 qobuz-connect[1595]: 20260825 23:24:18.477 [1595.1595] INFO SampleApp: Playback is nearly finished Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.618 [1595.1595] INFO SampleApp: Playback has finished Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.618 [1595.1595] INFO AudioStreamManager: [0x1639d40]: [Stream 69]: Disposing of audio stream Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.618 [1595.1595] INFO ContentFetcher: [0x1871928]: Canceling download Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.620 [1595.1595] INFO QConnectMessageSender: [0x1677a28]: Sending PlaybackError message Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.620 [1595.1595] INFO MediaEngine: [0x1639c90]: Stopping playback, clearing tracks Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.621 [1595.1595] INFO QwspMessageSender: [0x1677a18]: Sending Payload message Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.621 [1595.1595] INFO SampleApp: [Stream 69]: Audio stream has been disposed of Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.621 [1595.1595] INFO StreamingEventReporter: [0x163a618]: Reporting streaming ended event Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.621 [1595.1595] INFO HttpDownloader: [0x163a670]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.621 [1595.1595] WARNING SampleApp: Unable to get buffered frame count from ALSA audio sink, trying to recover Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.633 [1595.1595] INFO ProtocolHandler: [0x163a268]: Need to send state update Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.633 [1595.1595] INFO QConnectMessageSender: [0x1677a28]: Sending StateUpdated message Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.633 [1595.1595] INFO QwspMessageSender: [0x1677a18]: Sending Payload message Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.634 [1595.1595] INFO SampleApp: Playback state changed: Stopped Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreCommandRouter::servicePushState Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreStateMachine::pushState Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreCommandRouter::volumioPushState Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.634 [1595.1595] INFO AudioRenderer: [0x1639df8]: Stopping audio renderer Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.634 [1595.1595] INFO SampleApp: Stopping playback Aug 25 23:24:19 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output update for this device Aug 25 23:24:19 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreCommandRouter::servicePushState Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreStateMachine::pushState Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreCommandRouter::volumioPushState Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.644 [1595.1595] INFO ProtocolHandler: [0x163a268]: Received SetState message: Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.644 [1595.1595] INFO ProtocolHandler: [0x163a268]: Playing state: Playing Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.644 [1595.1595] INFO ProtocolHandler: [0x163a268]: Playback position: 0 Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.644 [1595.1595] INFO ProtocolHandler: [0x163a268]: Queue version: 23.1 Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.644 [1595.1595] INFO ProtocolHandler: [0x163a268]: Current track: TID: 429133729, QID: 80, Context UUID: 0d70638c-4e33-442d-ac42-9f822d79948b Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.644 [1595.1595] INFO ProtocolHandler: [0x163a268]: Next track: TID: 4294967295, QID: -1, Context UUID: Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.644 [1595.1595] INFO MediaEngine: [0x1639c90]: Reseting Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.644 [1595.1595] INFO MediaEngine: [0x1639c90]: Stopping playback, clearing tracks Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.644 [1595.1595] INFO MediaEngine: [0x1639c90]: Initiating playback Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.644 [1595.1595] INFO RendererActionAvailabilityManager: [0x163a1c8]: Renderer action 'Next' is not available Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.645 [1595.1595] INFO ProtocolHandler: [0x163a268]: Need to send state update Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.645 [1595.1595] INFO QConnectMessageSender: [0x1677a28]: Sending StateUpdated message Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.645 [1595.1595] INFO QwspMessageSender: [0x1677a18]: Sending Payload message Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.645 [1595.1595] INFO MediaEngine: [0x1639c90]: Setting current track: 429133729, initial offset: 0ms Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.645 [1595.1595] INFO AudioStreamManager: [0x1639d40]: Clearing all streams Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.645 [1595.1595] INFO AudioStreamManager: [0x1639d40]: [Stream 70]: Disposing of audio stream Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.645 [1595.1595] INFO AudioStreamManager: [0x1639d40]: New stream: 71 Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.645 [1595.1595] INFO HttpDownloader: [0x1aee908]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=25370a5deccebc50a3eded117b3c9179&request_ts=1787667859&track_id=429133729 Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.646 [1595.1595] INFO HttpDownloader: [0x1913820]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=429133729 Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.646 [1595.1595] INFO HttpDownloader: [0x1708cb8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=128e9022d3d77e0c84f8df62fd66edcb&request_ts=1787667859&track_id=429133729 Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.646 [1595.1595] INFO AudioStreamManager: [0x1639d40]: [Stream 71]: Running audio stream Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.647 [1595.1595] INFO SampleApp: [Stream 70]: Audio stream has been disposed of Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.647 [1595.1595] INFO MediaEngine: [0x1639c90]: Setting next track: NONE Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.647 [1595.1595] INFO MediaEngine: [0x1639c90]: Waiting for current stream to start before starting audio renderer Aug 25 23:24:19 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output update for this device Aug 25 23:24:19 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreCommandRouter::servicePushState Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreStateMachine::pushState Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreCommandRouter::volumioPushState Aug 25 23:24:19 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output update for this device Aug 25 23:24:19 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreCommandRouter::servicePushState Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreStateMachine::pushState Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreCommandRouter::volumioPushState Aug 25 23:24:19 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output update for this device Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.655 [1595.1595] INFO ProtocolHandler: [0x163a268]: Received SetState message: Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.655 [1595.1595] INFO ProtocolHandler: [0x163a268]: Playing state: Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.655 [1595.1595] INFO ProtocolHandler: [0x163a268]: Playback position: Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.655 [1595.1595] INFO ProtocolHandler: [0x163a268]: Queue version: 23.1 Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.655 [1595.1595] INFO ProtocolHandler: [0x163a268]: Current track: Aug 25 23:24:19 volumio33 qobuz-connect[1595]: 20260825 23:24:19.655 [1595.1595] INFO ProtocolHandler: [0x163a268]: Next track: TID: 4294967295, QID: -1, Context UUID: Aug 25 23:24:19 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:24:19 volumio33 volumio[1171]: info: [LastFM] Current track has sufficient metadata: title (老人と海) and artist (ヨルシカ) passed on explicitly Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreCommandRouter::servicePushState Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreStateMachine::pushState Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreCommandRouter::volumioPushState Aug 25 23:24:19 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output update for this device Aug 25 23:24:19 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output Aug 25 23:24:19 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:24:20 volumio33 volumio[1171]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"老人と海","mbid":"464d33d3-492b-4c9f-a8bc-38925fb8ea3e","url":"https://www.last.fm/music/%E3%83%A8%E3%83%AB%E3%82%B7%E3%82%AB/_/%E8%80%81%E4%BA%BA%E3%81%A8%E6%B5%B7","duration":"255000","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"13837","playcount":"127304","artist":{"name":"ヨルシカ","mbid":"dfc6a151-3792-4695-8fda-f64723eaa788","url":"https://www.last.fm/music/%E3%83%A8%E3%83%AB%E3%82%B7%E3%82%AB"},"album":{"artist":"ヨルシカ","title":"老人と海","url":"https://www.last.fm/music/%E3%83%A8%E3%83%AB%E3%82%B7%E3%82%AB/%E8%80%81%E4%BA%BA%E3%81%A8%E6%B5%B7","image":[{"#":"https://lastfm-img.freetls.fastly.net/i/u/34s/b7f3dba3443b9e16263e7f80c3f96af5.png","@":{"size":"small"}},{"#":"https://lastfm-img.freetls.fastly.net/i/u/64s/b7f3dba3443b9e16263e7f80c3f96af5.png","@":{"size":"medium"}},{"#":"https://lastfm-img.freetls.fastly.net/i/u/174s/b7f3dba3443b9e16263e7f80c3f96af5.png","@":{"size":"large"}},{"#":"https://lastfm-img.freetls.fastly.net/i/u/300x300/b7f3dba3443b9e16263e7f80c3f96af5.png","@":{"size":"extralarge"}}]},"userplaycount":"12","userloved":"0","toptags":""}} Aug 25 23:24:20 volumio33 qobuz-connect[1595]: 20260825 23:24:20.295 [1595.1595] INFO AudioStreamManager: [0x1639d40]: [Stream 71]: stream information have been fetched Aug 25 23:24:20 volumio33 qobuz-connect[1595]: 20260825 23:24:20.332 [1595.1595] INFO AudioStreamManager: [0x1639d40]: [Stream 71]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=12792238&eid=429133729&fmt=7&profile=raw&app_id=174516466&cid=4387967&etsp=1787671460&hmac=c_HXrlVBLDqN_AQxaynZBOCuBZM Aug 25 23:24:20 volumio33 qobuz-connect[1595]: 20260825 23:24:20.332 [1595.1595] INFO UrlAudioSource: [0x1ae9100]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=12792238&eid=429133729&fmt=7&profile=raw&app_id=174516466&cid=4387967&etsp=1787671460&hmac=c_HXrlVBLDqN_AQxaynZBOCuBZM Aug 25 23:24:20 volumio33 qobuz-connect[1595]: 20260825 23:24:20.332 [1595.1595] INFO ContentFetcher: [0x1864470]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=12792238&eid=429133729&fmt=7&profile=raw&app_id=174516466&cid=4387967&etsp=1787671460&hmac=c_HXrlVBLDqN_AQxaynZBOCuBZM, offset: 0 Aug 25 23:24:20 volumio33 qobuz-connect[1595]: 20260825 23:24:20.333 [1595.1595] INFO AudioRenderer: [0x1639df8]: Starting audio renderer, initial playback state: Playing Aug 25 23:24:20 volumio33 qobuz-connect[1595]: 20260825 23:24:20.333 [1595.1595] INFO SampleApp: [Stream 71]: New audio stream (starting from 0ms) Aug 25 23:24:20 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:24:20 volumio33 volumio[1171]: info: QobuzConnect: alread currrent service, send playback confirmation Aug 25 23:24:20 volumio33 qobuz-connect[1595]: 20260825 23:24:20.337 [1595.1595] INFO SampleApp: Initiating playback, initial state: Playing Aug 25 23:24:20 volumio33 qobuz-connect[1595]: 20260825 23:24:20.372 [1595.1595] INFO SampleApp: Playback state changed: Playing Aug 25 23:24:20 volumio33 qobuz-connect[1595]: 20260825 23:24:20.372 [1595.1595] INFO StreamingEventReporter: [0x163a618]: Reporting streaming started event Aug 25 23:24:20 volumio33 qobuz-connect[1595]: 20260825 23:24:20.372 [1595.1595] INFO HttpDownloader: [0x163a670]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Aug 25 23:24:20 volumio33 qobuz-connect[1595]: 20260825 23:24:20.372 [1595.1595] INFO ProtocolHandler: [0x163a268]: Need to send state update Aug 25 23:24:20 volumio33 qobuz-connect[1595]: 20260825 23:24:20.372 [1595.1595] INFO QConnectMessageSender: [0x1677a28]: Sending StateUpdated message Aug 25 23:24:20 volumio33 qobuz-connect[1595]: 20260825 23:24:20.372 [1595.1595] INFO QwspMessageSender: [0x1677a18]: Sending Payload message Aug 25 23:24:20 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:24:20 volumio33 volumio[1171]: info: CoreCommandRouter::servicePushState Aug 25 23:24:20 volumio33 volumio[1171]: info: CoreStateMachine::pushState Aug 25 23:24:20 volumio33 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:24:20 volumio33 qobuz-connect[1595]: 20260825 23:24:20.373 [1595.1595] INFO UrlAudioSource: [0x1ae9100]: Content info extracted Aug 25 23:24:20 volumio33 qobuz-connect[1595]: 20260825 23:24:20.373 [1595.1595] INFO UrlAudioSource: [0x1ae9100]: Size: 92562000 Aug 25 23:24:20 volumio33 volumio[1171]: info: CoreCommandRouter::volumioPushState Aug 25 23:24:20 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output update for this device Aug 25 23:24:20 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output Aug 25 23:24:20 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:24:20 volumio33 volumio[1171]: info: CoreCommandRouter::servicePushState Aug 25 23:24:20 volumio33 volumio[1171]: info: CoreStateMachine::pushState Aug 25 23:24:20 volumio33 volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 23:24:20 volumio33 volumio[1171]: info: CoreCommandRouter::volumioPushState Aug 25 23:24:20 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output update for this device Aug 25 23:24:20 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output Aug 25 23:24:20 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:24:20 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:24:20 volumio33 volumio[1171]: info: CoreCommandRouter::servicePushState Aug 25 23:24:20 volumio33 volumio[1171]: info: CoreStateMachine::pushState Aug 25 23:24:20 volumio33 volumio[1171]: info: CoreCommandRouter::volumioPushState Aug 25 23:24:20 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output update for this device Aug 25 23:24:20 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output Aug 25 23:24:20 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:24:20 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:24:20 volumio33 volumio[1171]: info: CoreCommandRouter::servicePushState Aug 25 23:24:20 volumio33 volumio[1171]: info: CoreStateMachine::pushState Aug 25 23:24:20 volumio33 volumio[1171]: info: CoreCommandRouter::volumioPushState Aug 25 23:24:20 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output update for this device Aug 25 23:24:20 volumio33 volumio[1171]: info: MRS: Pushing multiroomSync output Aug 25 23:24:20 volumio33 volumio[1171]: info: CoreCommandRouter::volumioGetState Aug 25 23:24:20 volumio33 qobuz-connect[1595]: 20260825 23:24:20.398 [1595.1595] INFO AudioStreamManager: [0x1639d40]: [Stream 71]: Metadata became available: Aug 25 23:24:20 volumio33 qobuz-connect[1595]: 20260825 23:24:20.398 [1595.1595] INFO AudioStreamManager: [0x1639d40]: Title: 老人と海 Aug 25 23:24:20 volumio33 qobuz-connect[1595]: 20260825 23:24:20.398 [1595.1595] INFO AudioStreamManager: [0x1639d40]: Artist: ヨルシカ Aug 25 23:24:20 volumio33 qobuz-connect[1595]: 20260825 23:24:20.398 [1595.1595] INFO AudioStreamManager: [0x1639d40]: Album: 幻燈 Aug 25 23:24:20 volumio33 qobuz-connect[1595]: 20260825 23:24:20.398 [1595.1595] INFO AudioStreamManager: [0x1639d40]: Album art URL: https://static.qobuz.com/images/covers/h2/3g/i5wzyxwz33gh2_600.jpg Aug 25 23:24:20 volumio33 qobuz-connect[1595]: 20260825 23:24:20.398 [1595.1595] INFO SampleApp: [Stream 71]: Stream metadata became available: Aug 25 23:24:20 volumio33 qobuz-connect[1595]: 20260825 23:24:20.398 [1595.1595] INFO SampleApp: Title: 老人と海 Aug 25 23:24:20 volumio33 qobuz-connect[1595]: 20260825 23:24:20.398 [1595.1595] INFO SampleApp: Artist: ヨルシカ Aug 25 23:24:20 volumio33 qobuz-connect[1595]: 20260825 23:24:20.398 [1595.1595] INFO SampleApp: Album: 幻燈 Aug 25 23:24:20 volumio33 qobuz-connect[1595]: 20260825 23:24:20.398 [1595.1595] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/h2/3g/i5wzyxwz33gh2_600.jpg Aug 25 23:24:20 volumio33 volumio[1171]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Aug 25 23:24:20 volumio33 volumio[1171]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 23:24:20 volumio33 volumio[1171]: TypeError: Cannot read property 'indexOf' of undefined Aug 25 23:24:20 volumio33 volumio[1171]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Aug 25 23:24:20 volumio33 volumio[1171]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Aug 25 23:24:20 volumio33 volumio[1171]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Aug 25 23:24:20 volumio33 volumio[1171]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Aug 25 23:24:20 volumio33 volumio[1171]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Aug 25 23:24:20 volumio33 volumio[1171]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Aug 25 23:24:20 volumio33 volumio[1171]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Aug 25 23:24:20 volumio33 volumio[1171]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Aug 25 23:24:20 volumio33 volumio[1171]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Aug 25 23:24:20 volumio33 volumio[1171]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Aug 25 23:24:20 volumio33 volumio[1171]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Aug 25 23:24:20 volumio33 volumio[1171]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Aug 25 23:24:20 volumio33 volumio[1171]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Aug 25 23:24:20 volumio33 volumio[1171]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Aug 25 23:24:20 volumio33 volumio[1171]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Aug 25 23:24:20 volumio33 volumio[1171]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Aug 25 23:24:20 volumio33 volumio[1171]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 23:24:21 volumio33 sudo[14558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-25 23:23 Aug 25 23:24:21 volumio33 sudo[14558]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"