-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Thu 2025-07-03 20:46:29 CEST. -- Jul 03 20:45:00 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:00.598 [1538.1538] INFO ProtocolHandler: [0x1f08af8]: Need to send state update Jul 03 20:45:00 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:00.598 [1538.1538] INFO QConnectMessageSender: [0x1f3b728]: Sending StateUpdated message Jul 03 20:45:00 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:00.598 [1538.1538] INFO QwspMessageSender: [0x1f7e9d8]: Sending Payload message Jul 03 20:45:00 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:45:00 volumio-sovrum volumio[988]: info: CoreCommandRouter::servicePushState Jul 03 20:45:00 volumio-sovrum volumio[988]: info: CoreStateMachine::pushState Jul 03 20:45:00 volumio-sovrum volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 20:45:00 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioPushState Jul 03 20:45:00 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output update for this device Jul 03 20:45:00 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output Jul 03 20:45:00 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:45:05 volumio-sovrum volumiologrotate[647]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Jul 03 20:45:05 volumio-sovrum volumiologrotate[647]: ls: cannot access 'SOVRUM': No such file or directory Jul 03 20:45:10 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:10.598 [1538.1538] INFO ProtocolHandler: [0x1f08af8]: Need to send state update Jul 03 20:45:10 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:45:10 volumio-sovrum volumio[988]: info: CoreCommandRouter::servicePushState Jul 03 20:45:10 volumio-sovrum volumio[988]: info: CoreStateMachine::pushState Jul 03 20:45:10 volumio-sovrum volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 20:45:10 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioPushState Jul 03 20:45:10 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:10.601 [1538.1538] INFO QConnectMessageSender: [0x1f3b728]: Sending StateUpdated message Jul 03 20:45:10 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:10.601 [1538.1538] INFO QwspMessageSender: [0x1f7e9d8]: Sending Payload message Jul 03 20:45:10 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output update for this device Jul 03 20:45:10 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output Jul 03 20:45:10 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:45:20 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:20.597 [1538.1538] INFO ProtocolHandler: [0x1f08af8]: Need to send state update Jul 03 20:45:20 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:20.597 [1538.1538] INFO QConnectMessageSender: [0x1f3b728]: Sending StateUpdated message Jul 03 20:45:20 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:20.598 [1538.1538] INFO QwspMessageSender: [0x1f7e9d8]: Sending Payload message Jul 03 20:45:20 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:45:20 volumio-sovrum volumio[988]: info: CoreCommandRouter::servicePushState Jul 03 20:45:20 volumio-sovrum volumio[988]: info: CoreStateMachine::pushState Jul 03 20:45:20 volumio-sovrum volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 20:45:20 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioPushState Jul 03 20:45:20 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output update for this device Jul 03 20:45:20 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output Jul 03 20:45:20 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:45:30 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:30.598 [1538.1538] INFO ProtocolHandler: [0x1f08af8]: Need to send state update Jul 03 20:45:30 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:30.598 [1538.1538] INFO QConnectMessageSender: [0x1f3b728]: Sending StateUpdated message Jul 03 20:45:30 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:30.598 [1538.1538] INFO QwspMessageSender: [0x1f7e9d8]: Sending Payload message Jul 03 20:45:30 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:45:30 volumio-sovrum volumio[988]: info: CoreCommandRouter::servicePushState Jul 03 20:45:30 volumio-sovrum volumio[988]: info: CoreStateMachine::pushState Jul 03 20:45:30 volumio-sovrum volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 20:45:30 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioPushState Jul 03 20:45:30 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output update for this device Jul 03 20:45:30 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output Jul 03 20:45:30 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:45:40 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:40.598 [1538.1538] INFO ProtocolHandler: [0x1f08af8]: Need to send state update Jul 03 20:45:40 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:40.598 [1538.1538] INFO QConnectMessageSender: [0x1f3b728]: Sending StateUpdated message Jul 03 20:45:40 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:40.598 [1538.1538] INFO QwspMessageSender: [0x1f7e9d8]: Sending Payload message Jul 03 20:45:40 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:45:40 volumio-sovrum volumio[988]: info: CoreCommandRouter::servicePushState Jul 03 20:45:40 volumio-sovrum volumio[988]: info: CoreStateMachine::pushState Jul 03 20:45:40 volumio-sovrum volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 20:45:40 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioPushState Jul 03 20:45:40 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output update for this device Jul 03 20:45:40 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output Jul 03 20:45:40 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.073 [1538.1538] INFO ProtocolHandler: [0x1f08af8]: Received SetState message: Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.073 [1538.1538] INFO ProtocolHandler: [0x1f08af8]: Playing state: Playing Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.073 [1538.1538] INFO ProtocolHandler: [0x1f08af8]: Playback position: 0 Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.073 [1538.1538] INFO ProtocolHandler: [0x1f08af8]: Queue version: 7.1 Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.073 [1538.1538] INFO ProtocolHandler: [0x1f08af8]: Current track: TID: 341887416, QID: 0, Context UUID: 2efde306-d34f-40db-abb4-f811766229b6 Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.073 [1538.1538] INFO ProtocolHandler: [0x1f08af8]: Next track: TID: 341887417, QID: 1, Context UUID: 2efde306-d34f-40db-abb4-f811766229b6 Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.073 [1538.1538] INFO MediaEngine: [0x1f083b8]: Stopping playback, clearing tracks Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.073 [1538.1538] INFO MediaEngine: [0x1f083b8]: Initiating playback Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.073 [1538.1538] INFO AudioRenderer: [0x1f085d8]: Stopping audio renderer Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.074 [1538.1538] INFO SampleApp: Stopping playback Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.081 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: Clearing all streams Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.081 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: [Stream 20]: Disposing of audio stream Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.081 [1538.1538] INFO ContentFetcher: [0x2229170]: Canceling download Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.089 [1538.1538] INFO SampleApp: Playback state changed: Stopped Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.089 [1538.1538] INFO StreamingEventReporter: [0x1f08f10]: Reporting streaming ended event Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.089 [1538.1538] INFO HttpDownloader: [0x1f08f68]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.089 [1538.1538] INFO ProtocolHandler: [0x1f08af8]: Need to send state update Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.089 [1538.1538] INFO QConnectMessageSender: [0x1f3b728]: Sending StateUpdated message Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.090 [1538.1538] INFO SampleApp: [Stream 20]: Audio stream has been disposed of Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.090 [1538.1538] INFO MediaEngine: [0x1f083b8]: Setting current track: 341887416, initial offset: 0ms Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.090 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: Clearing all streams Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.090 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: New stream: 22 Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.090 [1538.1538] INFO HttpDownloader: [0x22de260]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=e7e1af58b38dd1891f477b32b0f1b7f2&request_ts=1751568349&track_id=341887416 Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.090 [1538.1538] INFO HttpDownloader: [0x2005bb8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=341887416 Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.090 [1538.1538] INFO HttpDownloader: [0x1efe650]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=c58b590cc34e5c88664ac5178c1a86a2&request_ts=1751568349&track_id=341887416 Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::servicePushState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreStateMachine::pushState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.090 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: [Stream 22]: Running audio stream Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.090 [1538.1538] INFO QwspMessageSender: [0x1f7e9d8]: Sending Payload message Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioPushState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output update for this device Jul 03 20:45:49 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::servicePushState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreStateMachine::pushState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioPushState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output update for this device Jul 03 20:45:49 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.141 [1538.1538] INFO MediaEngine: [0x1f083b8]: Setting next track: 341887417 Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.141 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: New stream: 23 Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.141 [1538.1538] INFO HttpDownloader: [0x1f38088]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=6070ab591273ac995bd23754acccd8df&request_ts=1751568349&track_id=341887417 Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.141 [1538.1538] INFO HttpDownloader: [0x2173ca8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=341887417 Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.142 [1538.1538] INFO HttpDownloader: [0x1f1e368]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=ce4a0801086b26957159e5bf3c386553&request_ts=1751568349&track_id=341887417 Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.143 [1538.1538] INFO MediaEngine: [0x1f083b8]: Waiting for current stream to start before starting audio renderer Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.726 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: [Stream 22]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=341887416&fmt=6&profile=raw&app_id=174516466&cid=2789982&etsp=1751571949&hmac=PKVqv0ygDMWQZklQAFLHezd-S3s Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.760 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: [Stream 22]: Metadata became available: Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.760 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: Title: Ironheart (Riri's Theme) (From "Marvel's Ironheart") Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.760 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: Artist: Dara Taylor Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.760 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: Album: Marvel's Ironheart Vol. 1 (Episodes 1-3) (From "Marvel's Ironheart") Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.760 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: Album art URL: https://static.qobuz.com/images/covers/ab/k2/oeoz9koihk2ab_600.jpg Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.804 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: [Stream 23]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=341887417&fmt=6&profile=raw&app_id=174516466&cid=2789982&etsp=1751571949&hmac=BeKg7t-o7C7ZaYFBsKZ354A7w8g Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.817 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: [Stream 23]: stream information have been fetched Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.833 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: [Stream 23]: Metadata became available: Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.833 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: Title: The Crew (From "Marvel's Ironheart Vol. 1" (Episode 1-3)/Score) Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.833 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: Artist: Dara Taylor Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.833 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: Album: Marvel's Ironheart Vol. 1 (Episodes 1-3) (From "Marvel's Ironheart Vol. 1" (Episode 1-3)/Score) Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.833 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: Album art URL: https://static.qobuz.com/images/covers/ab/k2/oeoz9koihk2ab_600.jpg Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.879 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: [Stream 22]: stream information have been fetched Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.879 [1538.1538] INFO UrlAudioSource: [0x23f6c78]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=341887416&fmt=6&profile=raw&app_id=174516466&cid=2789982&etsp=1751571949&hmac=PKVqv0ygDMWQZklQAFLHezd-S3s Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.879 [1538.1538] INFO ContentFetcher: [0x1fafc88]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=341887416&fmt=6&profile=raw&app_id=174516466&cid=2789982&etsp=1751571949&hmac=PKVqv0ygDMWQZklQAFLHezd-S3s, offset: 0 Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.879 [1538.1538] INFO AudioRenderer: [0x1f085d8]: Starting audio renderer, initial playback state: Playing Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.879 [1538.1538] INFO SampleApp: [Stream 22]: New audio stream (starting from 0ms) Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.880 [1538.1538] INFO SampleApp: [Stream 22]: Stream metadata became available: Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.880 [1538.1538] INFO SampleApp: Title: Ironheart (Riri's Theme) (From "Marvel's Ironheart") Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.880 [1538.1538] INFO SampleApp: Artist: Dara Taylor Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.880 [1538.1538] INFO SampleApp: Album: Marvel's Ironheart Vol. 1 (Episodes 1-3) (From "Marvel's Ironheart") Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.880 [1538.1538] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/ab/k2/oeoz9koihk2ab_600.jpg Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: QobuzConnect: alread currrent service, send playback confirmation Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.882 [1538.1538] INFO SampleApp: Initiating playback, initial state: Playing Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:257:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioOutput has buffer size 32768 Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:268:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioOutput has buffer size 32768 Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.885 [1538.1538] INFO SampleApp: Playback state changed: Playing Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.885 [1538.1538] INFO StreamingEventReporter: [0x1f08f10]: Reporting streaming started event Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.886 [1538.1538] INFO HttpDownloader: [0x1f08f68]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.886 [1538.1538] INFO ProtocolHandler: [0x1f08af8]: Need to send state update Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.886 [1538.1538] INFO QConnectMessageSender: [0x1f3b728]: Sending StateUpdated message Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.886 [1538.1538] INFO QwspMessageSender: [0x1f7e9d8]: Sending Payload message Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::servicePushState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreStateMachine::pushState Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.887 [1538.1538] INFO QConnectMessageSender: [0x1f3b728]: Sending FileAudioQualityChanged message Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.887 [1538.1538] INFO QwspMessageSender: [0x1f7e9d8]: Sending Payload message Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.887 [1538.1538] INFO QConnectMessageSender: [0x1f3b728]: Sending DeviceAudioQualityChanged message Jul 03 20:45:49 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:49.887 [1538.1538] INFO QwspMessageSender: [0x1f7e9d8]: Sending Payload message Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioPushState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output update for this device Jul 03 20:45:49 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::servicePushState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreStateMachine::pushState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioPushState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output update for this device Jul 03 20:45:49 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::servicePushState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreStateMachine::pushState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioPushState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output update for this device Jul 03 20:45:49 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::servicePushState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreStateMachine::pushState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioPushState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output update for this device Jul 03 20:45:49 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output Jul 03 20:45:49 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:45:49 volumio-sovrum volumio[988]: info: [LastFM] Current track has sufficient metadata: title (Ironheart (Riri's Theme) (From "Marvel's Ironheart")) and artist (Dara Taylor) passed on explicitly Jul 03 20:45:50 volumio-sovrum volumio[988]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Ironheart (Riri's Theme) (From \"Marvel's Ironheart\")","url":"https://www.last.fm/music/Dara+Taylor/_/Ironheart+(Riri%27s+Theme)+(From+%22Marvel%27s+Ironheart%22)","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"2","playcount":"2","artist":{"name":"Dara Taylor","url":"https://www.last.fm/music/Dara+Taylor"},"album":{"artist":"Dara Taylor","title":"Ironheart (Riri's Theme) (From \"Marvel's Ironheart\")","url":"https://www.last.fm/music/Dara+Taylor/Ironheart+(Riri%27s+Theme)+(From+%22Marvel%27s+Ironheart%22)","image":[{"@":{"size":"small"}},{"@":{"size":"medium"}},{"@":{"size":"large"}},{"@":{"size":"extralarge"}}]},"userplaycount":"0","userloved":"0","toptags":""}} Jul 03 20:45:50 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:50.329 [1538.1538] INFO UrlAudioSource: [0x23f6c78]: Content info extracted Jul 03 20:45:50 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:50.329 [1538.1538] INFO UrlAudioSource: [0x23f6c78]: Size: 13508608 Jul 03 20:45:50 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:50.339 [1538.27999] INFO AudioDecoder: [0x1f747b8]: Stream info: Jul 03 20:45:50 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:50.339 [1538.27999] INFO AudioDecoder: [0x1f747b8]: Total samples: 6049661 Jul 03 20:45:50 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:50.339 [1538.27999] INFO AudioDecoder: [0x1f747b8]: Sample rate: 44100 Jul 03 20:45:50 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:50.339 [1538.27999] INFO AudioDecoder: [0x1f747b8]: Channel count: 2 Jul 03 20:45:50 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:50.339 [1538.27999] INFO AudioDecoder: [0x1f747b8]: Bits per sample: 16 Jul 03 20:45:50 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:50.339 [1538.27999] INFO AudioDecoder: [0x1f747b8]: Duration: 137180 Jul 03 20:45:50 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:50.339 [1538.1538] INFO UrlAudioSource: [0x23f6c78]: Audio properties have changed Jul 03 20:45:59 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:59.884 [1538.1538] INFO ProtocolHandler: [0x1f08af8]: Need to send state update Jul 03 20:45:59 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:59.885 [1538.1538] INFO QConnectMessageSender: [0x1f3b728]: Sending StateUpdated message Jul 03 20:45:59 volumio-sovrum qobuz-connect[1538]: 20250703 20:45:59.885 [1538.1538] INFO QwspMessageSender: [0x1f7e9d8]: Sending Payload message Jul 03 20:45:59 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:45:59 volumio-sovrum volumio[988]: info: CoreCommandRouter::servicePushState Jul 03 20:45:59 volumio-sovrum volumio[988]: info: CoreStateMachine::pushState Jul 03 20:45:59 volumio-sovrum volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 20:45:59 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioPushState Jul 03 20:45:59 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output update for this device Jul 03 20:45:59 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output Jul 03 20:45:59 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:46:05 volumio-sovrum volumiologrotate[647]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Jul 03 20:46:05 volumio-sovrum volumiologrotate[647]: ls: cannot access 'SOVRUM': No such file or directory Jul 03 20:46:09 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:09.885 [1538.1538] INFO ProtocolHandler: [0x1f08af8]: Need to send state update Jul 03 20:46:09 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:09.885 [1538.1538] INFO QConnectMessageSender: [0x1f3b728]: Sending StateUpdated message Jul 03 20:46:09 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:09.885 [1538.1538] INFO QwspMessageSender: [0x1f7e9d8]: Sending Payload message Jul 03 20:46:09 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:46:09 volumio-sovrum volumio[988]: info: CoreCommandRouter::servicePushState Jul 03 20:46:09 volumio-sovrum volumio[988]: info: CoreStateMachine::pushState Jul 03 20:46:09 volumio-sovrum volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 20:46:09 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioPushState Jul 03 20:46:09 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output update for this device Jul 03 20:46:09 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output Jul 03 20:46:09 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:46:19 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:19.885 [1538.1538] INFO ProtocolHandler: [0x1f08af8]: Need to send state update Jul 03 20:46:19 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:19.885 [1538.1538] INFO QConnectMessageSender: [0x1f3b728]: Sending StateUpdated message Jul 03 20:46:19 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:19.885 [1538.1538] INFO QwspMessageSender: [0x1f7e9d8]: Sending Payload message Jul 03 20:46:19 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:46:19 volumio-sovrum volumio[988]: info: CoreCommandRouter::servicePushState Jul 03 20:46:19 volumio-sovrum volumio[988]: info: CoreStateMachine::pushState Jul 03 20:46:19 volumio-sovrum volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 20:46:19 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioPushState Jul 03 20:46:19 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output update for this device Jul 03 20:46:19 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output Jul 03 20:46:19 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.307 [1538.1538] INFO ProtocolHandler: [0x1f08af8]: Received SetState message: Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.307 [1538.1538] INFO ProtocolHandler: [0x1f08af8]: Playing state: Playing Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.307 [1538.1538] INFO ProtocolHandler: [0x1f08af8]: Playback position: 0 Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.307 [1538.1538] INFO ProtocolHandler: [0x1f08af8]: Queue version: 8.1 Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.307 [1538.1538] INFO ProtocolHandler: [0x1f08af8]: Current track: TID: 186644666, QID: 11, Context UUID: bc0cfa92-5814-4633-8d4e-bd2643c29921 Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.307 [1538.1538] INFO ProtocolHandler: [0x1f08af8]: Next track: TID: 278287855, QID: 12, Context UUID: bc0cfa92-5814-4633-8d4e-bd2643c29921 Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.307 [1538.1538] INFO MediaEngine: [0x1f083b8]: Stopping playback, clearing tracks Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.307 [1538.1538] INFO MediaEngine: [0x1f083b8]: Initiating playback Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.307 [1538.1538] INFO AudioRenderer: [0x1f085d8]: Stopping audio renderer Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.307 [1538.1538] INFO SampleApp: Stopping playback Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.314 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: Clearing all streams Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.314 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: [Stream 22]: Disposing of audio stream Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.315 [1538.1538] INFO ContentFetcher: [0x1fafc88]: Canceling download Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.321 [1538.1538] INFO SampleApp: Playback state changed: Stopped Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.321 [1538.1538] INFO StreamingEventReporter: [0x1f08f10]: Reporting streaming ended event Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.322 [1538.1538] INFO HttpDownloader: [0x1f08f68]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.322 [1538.1538] INFO ProtocolHandler: [0x1f08af8]: Need to send state update Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.322 [1538.1538] INFO QConnectMessageSender: [0x1f3b728]: Sending StateUpdated message Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.322 [1538.1538] INFO SampleApp: [Stream 22]: Audio stream has been disposed of Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.322 [1538.1538] INFO MediaEngine: [0x1f083b8]: Setting current track: 186644666, initial offset: 0ms Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.322 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: Clearing all streams Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.322 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: New stream: 24 Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.322 [1538.1538] INFO HttpDownloader: [0x1f09a98]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=da747823684c49643978f8bb30839105&request_ts=1751568388&track_id=186644666 Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.322 [1538.1538] INFO HttpDownloader: [0x1f8c890]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=186644666 Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.322 [1538.1538] INFO HttpDownloader: [0x25588d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=ad7ee584efe573c4a2da01b2ceff5817&request_ts=1751568388&track_id=186644666 Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.323 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: [Stream 24]: Running audio stream Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.323 [1538.1538] INFO QwspMessageSender: [0x1f7e9d8]: Sending Payload message Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::servicePushState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreStateMachine::pushState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioPushState Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.325 [1538.1538] INFO MediaEngine: [0x1f083b8]: Setting next track: 278287855 Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.325 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: New stream: 25 Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.325 [1538.1538] INFO HttpDownloader: [0x1f09ac0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=18d41576ac02c00cb65575021d114e40&request_ts=1751568388&track_id=278287855 Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.326 [1538.1538] INFO HttpDownloader: [0x21e7a90]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=278287855 Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.326 [1538.1538] INFO HttpDownloader: [0x22b7d20]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=7b4c99c7868884ee511abd18915d73af&request_ts=1751568388&track_id=278287855 Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.327 [1538.1538] INFO MediaEngine: [0x1f083b8]: Waiting for current stream to start before starting audio renderer Jul 03 20:46:28 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output update for this device Jul 03 20:46:28 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::servicePushState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreStateMachine::pushState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioPushState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output update for this device Jul 03 20:46:28 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.465 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: [Stream 24]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=186644666&fmt=6&profile=raw&app_id=174516466&cid=2789982&etsp=1751571988&hmac=V4Mk3-hn7fVtEoYa7qScY6Ep0Aw Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.465 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: [Stream 25]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=278287855&fmt=6&profile=raw&app_id=174516466&cid=2789982&etsp=1751571988&hmac=UYrDYNP7Pmqn2oGksI6Vt0Cjfgc Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.528 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: [Stream 25]: Metadata became available: Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.528 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: Title: Bodhicitta Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.528 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: Artist: Jass Yang Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.528 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: Album: Bodhicitta Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.528 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: Album art URL: https://static.qobuz.com/images/covers/xb/cs/yraeuym7pcsxb_600.jpg Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.611 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: [Stream 25]: stream information have been fetched Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.638 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: [Stream 24]: stream information have been fetched Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.638 [1538.1538] INFO UrlAudioSource: [0x248ed38]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=186644666&fmt=6&profile=raw&app_id=174516466&cid=2789982&etsp=1751571988&hmac=V4Mk3-hn7fVtEoYa7qScY6Ep0Aw Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.638 [1538.1538] INFO ContentFetcher: [0x2582160]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=186644666&fmt=6&profile=raw&app_id=174516466&cid=2789982&etsp=1751571988&hmac=V4Mk3-hn7fVtEoYa7qScY6Ep0Aw, offset: 0 Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.638 [1538.1538] INFO AudioRenderer: [0x1f085d8]: Starting audio renderer, initial playback state: Playing Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.638 [1538.1538] INFO SampleApp: [Stream 24]: New audio stream (starting from 0ms) Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: QobuzConnect: alread currrent service, send playback confirmation Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.640 [1538.1538] INFO SampleApp: Initiating playback, initial state: Playing Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:257:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioOutput has buffer size 32768 Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:268:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioOutput has buffer size 32768 Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.643 [1538.1538] INFO SampleApp: Playback state changed: Playing Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.643 [1538.1538] INFO StreamingEventReporter: [0x1f08f10]: Reporting streaming started event Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.643 [1538.1538] INFO ProtocolHandler: [0x1f08af8]: Need to send state update Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.643 [1538.1538] INFO QConnectMessageSender: [0x1f3b728]: Sending StateUpdated message Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.643 [1538.1538] INFO QwspMessageSender: [0x1f7e9d8]: Sending Payload message Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.643 [1538.1538] INFO QConnectMessageSender: [0x1f3b728]: Sending FileAudioQualityChanged message Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.644 [1538.1538] INFO QwspMessageSender: [0x1f7e9d8]: Sending Payload message Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::servicePushState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreStateMachine::pushState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioPushState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output update for this device Jul 03 20:46:28 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.651 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: [Stream 24]: Metadata became available: Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.651 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: Title: Into My Arms Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.652 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: Artist: Coin Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.652 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: Album: Dreamland Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.652 [1538.1538] INFO AudioStreamManager: [0x1f084a0]: Album art URL: https://static.qobuz.com/images/covers/ha/n3/iyqamc32in3ha_600.jpg Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.652 [1538.1538] INFO SampleApp: [Stream 24]: Stream metadata became available: Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.652 [1538.1538] INFO SampleApp: Title: Into My Arms Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.652 [1538.1538] INFO SampleApp: Artist: Coin Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.652 [1538.1538] INFO SampleApp: Album: Dreamland Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.652 [1538.1538] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/ha/n3/iyqamc32in3ha_600.jpg Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::servicePushState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreStateMachine::pushState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioPushState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output update for this device Jul 03 20:46:28 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::servicePushState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreStateMachine::pushState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioPushState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output update for this device Jul 03 20:46:28 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::servicePushState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreStateMachine::pushState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioPushState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output update for this device Jul 03 20:46:28 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::servicePushState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreStateMachine::pushState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioPushState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output update for this device Jul 03 20:46:28 volumio-sovrum volumio[988]: info: MRS: Pushing multiroomSync output Jul 03 20:46:28 volumio-sovrum volumio[988]: info: CoreCommandRouter::volumioGetState Jul 03 20:46:28 volumio-sovrum volumio[988]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Jul 03 20:46:28 volumio-sovrum volumio[988]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 20:46:28 volumio-sovrum volumio[988]: TypeError: Cannot read property 'indexOf' of undefined Jul 03 20:46:28 volumio-sovrum volumio[988]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Jul 03 20:46:28 volumio-sovrum volumio[988]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Jul 03 20:46:28 volumio-sovrum volumio[988]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Jul 03 20:46:28 volumio-sovrum volumio[988]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jul 03 20:46:28 volumio-sovrum volumio[988]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Jul 03 20:46:28 volumio-sovrum volumio[988]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Jul 03 20:46:28 volumio-sovrum volumio[988]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jul 03 20:46:28 volumio-sovrum volumio[988]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jul 03 20:46:28 volumio-sovrum volumio[988]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Jul 03 20:46:28 volumio-sovrum volumio[988]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jul 03 20:46:28 volumio-sovrum volumio[988]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Jul 03 20:46:28 volumio-sovrum volumio[988]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Jul 03 20:46:28 volumio-sovrum volumio[988]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Jul 03 20:46:28 volumio-sovrum volumio[988]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jul 03 20:46:28 volumio-sovrum volumio[988]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jul 03 20:46:28 volumio-sovrum volumio[988]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Jul 03 20:46:28 volumio-sovrum volumio[988]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 20:46:28 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:28.765 [1538.1538] INFO HttpDownloader: [0x1f08f68]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jul 03 20:46:29 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:29.313 [1538.1538] INFO UrlAudioSource: [0x248ed38]: Content info extracted Jul 03 20:46:29 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:29.313 [1538.1538] INFO UrlAudioSource: [0x248ed38]: Size: 28331937 Jul 03 20:46:29 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:29.318 [1538.28117] INFO AudioDecoder: [0x2114e68]: Stream info: Jul 03 20:46:29 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:29.318 [1538.28117] INFO AudioDecoder: [0x2114e68]: Total samples: 10205916 Jul 03 20:46:29 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:29.318 [1538.28117] INFO AudioDecoder: [0x2114e68]: Sample rate: 44100 Jul 03 20:46:29 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:29.318 [1538.28117] INFO AudioDecoder: [0x2114e68]: Channel count: 2 Jul 03 20:46:29 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:29.318 [1538.28117] INFO AudioDecoder: [0x2114e68]: Bits per sample: 16 Jul 03 20:46:29 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:29.318 [1538.28117] INFO AudioDecoder: [0x2114e68]: Duration: 231426 Jul 03 20:46:29 volumio-sovrum qobuz-connect[1538]: 20250703 20:46:29.318 [1538.1538] INFO UrlAudioSource: [0x248ed38]: Audio properties have changed Jul 03 20:46:29 volumio-sovrum sudo[28118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-03 20:45 Jul 03 20:46:29 volumio-sovrum sudo[28118]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"