-- Logs begin at Tue 2025-05-13 12:22:17 -03, end at Thu 2025-06-05 16:09:09 -03. -- Jun 05 16:08:03 volumio qobuz-connect[6965]: 20250605 16:08:03.614 [6965.6965] INFO AudioStreamManager: [0x2487330]: [Stream 51]: Running audio stream Jun 05 16:08:03 volumio qobuz-connect[6965]: 20250605 16:08:03.615 [6965.6965] INFO UrlAudioSource: [0x27c1fc8]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=4884528&eid=25273041&fmt=7&profile=raw&app_id=174516466&cid=2800179&etsp=1749154006&hmac=SfWzN2kJl4QzhvhFj6IS2snWAJo Jun 05 16:08:03 volumio qobuz-connect[6965]: 20250605 16:08:03.615 [6965.6965] INFO ContentFetcher: [0x2740fe0]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=4884528&eid=25273041&fmt=7&profile=raw&app_id=174516466&cid=2800179&etsp=1749154006&hmac=SfWzN2kJl4QzhvhFj6IS2snWAJo, offset: 0 Jun 05 16:08:03 volumio qobuz-connect[6965]: 20250605 16:08:03.617 [6965.6965] INFO SampleApp: [Stream 51]: New audio stream (starting from 0ms) Jun 05 16:08:03 volumio qobuz-connect[6965]: 20250605 16:08:03.618 [6965.6965] INFO SampleApp: [Stream 51]: Stream metadata became available: Jun 05 16:08:03 volumio qobuz-connect[6965]: 20250605 16:08:03.618 [6965.6965] INFO SampleApp: Title: Rehab Jun 05 16:08:03 volumio qobuz-connect[6965]: 20250605 16:08:03.618 [6965.6965] INFO SampleApp: Artist: Amy Winehouse Jun 05 16:08:03 volumio qobuz-connect[6965]: 20250605 16:08:03.618 [6965.6965] INFO SampleApp: Album: Back To Black Jun 05 16:08:03 volumio qobuz-connect[6965]: 20250605 16:08:03.618 [6965.6965] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/22/62/0060254746222_600.jpg Jun 05 16:08:03 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:04 volumio qobuz-connect[6965]: 20250605 16:08:04.359 [6965.6965] INFO ProtocolHandler: [0x2487988]: Need to send state update Jun 05 16:08:04 volumio qobuz-connect[6965]: 20250605 16:08:04.360 [6965.6965] INFO QConnectMessageSender: [0x2585208]: Sending StateUpdated message Jun 05 16:08:04 volumio qobuz-connect[6965]: 20250605 16:08:04.360 [6965.6965] INFO QwspMessageSender: [0x25e4330]: Sending Payload message Jun 05 16:08:04 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:04 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:08:04 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:08:04 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 16:08:04 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:08:04 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:08:04 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:08:04 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:05 volumio qobuz-connect[6965]: 20250605 16:08:05.158 [6965.6965] INFO UrlAudioSource: [0x27c1fc8]: Content info extracted Jun 05 16:08:05 volumio qobuz-connect[6965]: 20250605 16:08:05.159 [6965.6965] INFO UrlAudioSource: [0x27c1fc8]: Size: 82887377 Jun 05 16:08:05 volumio qobuz-connect[6965]: 20250605 16:08:05.166 [6965.7933] INFO AudioDecoder: [0x281bcd0]: Stream info: Jun 05 16:08:05 volumio qobuz-connect[6965]: 20250605 16:08:05.167 [6965.7933] INFO AudioDecoder: [0x281bcd0]: Total samples: 20615680 Jun 05 16:08:05 volumio qobuz-connect[6965]: 20250605 16:08:05.167 [6965.7933] INFO AudioDecoder: [0x281bcd0]: Sample rate: 96000 Jun 05 16:08:05 volumio qobuz-connect[6965]: 20250605 16:08:05.167 [6965.7933] INFO AudioDecoder: [0x281bcd0]: Channel count: 2 Jun 05 16:08:05 volumio qobuz-connect[6965]: 20250605 16:08:05.167 [6965.7933] INFO AudioDecoder: [0x281bcd0]: Bits per sample: 24 Jun 05 16:08:05 volumio qobuz-connect[6965]: 20250605 16:08:05.167 [6965.7933] INFO AudioDecoder: [0x281bcd0]: Duration: 214746 Jun 05 16:08:05 volumio qobuz-connect[6965]: 20250605 16:08:05.172 [6965.6965] INFO UrlAudioSource: [0x27c1fc8]: Audio properties have changed Jun 05 16:08:13 volumio qobuz-connect[6965]: 20250605 16:08:13.010 [6965.6965] INFO AudioStreamManager: [0x2487330]: [Stream 50]: Audio stream has finished Jun 05 16:08:13 volumio qobuz-connect[6965]: 20250605 16:08:13.010 [6965.6965] INFO SampleApp: [Stream 50]: Audio stream finished Jun 05 16:08:13 volumio qobuz-connect[6965]: 20250605 16:08:13.011 [6965.6965] INFO SampleApp: Playback is nearly finished Jun 05 16:08:13 volumio qobuz-connect[6965]: 20250605 16:08:13.765 [6965.6965] INFO SampleApp: Playback has finished Jun 05 16:08:13 volumio qobuz-connect[6965]: 20250605 16:08:13.837 [6965.6965] INFO AudioStreamManager: [0x2487330]: [Stream 50]: Disposing of audio stream Jun 05 16:08:13 volumio qobuz-connect[6965]: 20250605 16:08:13.837 [6965.6965] INFO ContentFetcher: [0x2cdc6a0]: Canceling download Jun 05 16:08:13 volumio qobuz-connect[6965]: 20250605 16:08:13.838 [6965.6965] INFO SampleApp: [Stream 50]: Audio stream has been disposed of Jun 05 16:08:13 volumio qobuz-connect[6965]: 20250605 16:08:13.839 [6965.6965] INFO StreamingEventReporter: [0x2487da0]: Reporting streaming ended event Jun 05 16:08:13 volumio qobuz-connect[6965]: 20250605 16:08:13.839 [6965.6965] INFO HttpDownloader: [0x2487df8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jun 05 16:08:13 volumio qobuz-connect[6965]: 20250605 16:08:13.840 [6965.6965] INFO ProtocolHandler: [0x2487988]: Need to send state update Jun 05 16:08:13 volumio qobuz-connect[6965]: 20250605 16:08:13.840 [6965.6965] INFO QConnectMessageSender: [0x2585208]: Sending StateUpdated message Jun 05 16:08:13 volumio qobuz-connect[6965]: 20250605 16:08:13.840 [6965.6965] INFO QwspMessageSender: [0x25e4330]: Sending Payload message Jun 05 16:08:13 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:13 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:08:13 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:08:13 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 16:08:13 volumio qobuz-connect[6965]: 20250605 16:08:13.845 [6965.6965] INFO QConnectMessageSender: [0x2585208]: Sending FileAudioQualityChanged message Jun 05 16:08:13 volumio qobuz-connect[6965]: 20250605 16:08:13.845 [6965.6965] INFO QwspMessageSender: [0x25e4330]: Sending Payload message Jun 05 16:08:13 volumio qobuz-connect[6965]: 20250605 16:08:13.845 [6965.6965] INFO StreamingEventReporter: [0x2487da0]: Reporting streaming started event Jun 05 16:08:13 volumio qobuz-connect[6965]: 20250605 16:08:13.847 [6965.6965] INFO QConnectMessageSender: [0x2585208]: Sending DeviceAudioQualityChanged message Jun 05 16:08:13 volumio qobuz-connect[6965]: 20250605 16:08:13.847 [6965.6965] INFO QwspMessageSender: [0x25e4330]: Sending Payload message Jun 05 16:08:13 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:08:13 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:08:13 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:08:13 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:13 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:13 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:08:13 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:08:13 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:08:13 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:08:13 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:08:13 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:13 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:13 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:08:13 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:08:13 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:08:13 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:08:13 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:08:13 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:13 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:13 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:08:13 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:08:13 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:08:13 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:08:13 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:08:13 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:13 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:13 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:08:13 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:08:13 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:08:13 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:08:13 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:08:13 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:14 volumio qobuz-connect[6965]: 20250605 16:08:14.013 [6965.6965] INFO ProtocolHandler: [0x2487988]: Received SetState message: Jun 05 16:08:14 volumio qobuz-connect[6965]: 20250605 16:08:14.013 [6965.6965] INFO ProtocolHandler: [0x2487988]: Playing state: Jun 05 16:08:14 volumio qobuz-connect[6965]: 20250605 16:08:14.013 [6965.6965] INFO ProtocolHandler: [0x2487988]: Playback position: Jun 05 16:08:14 volumio qobuz-connect[6965]: 20250605 16:08:14.014 [6965.6965] INFO ProtocolHandler: [0x2487988]: Queue version: 3.4 Jun 05 16:08:14 volumio qobuz-connect[6965]: 20250605 16:08:14.014 [6965.6965] INFO ProtocolHandler: [0x2487988]: Current track: Jun 05 16:08:14 volumio qobuz-connect[6965]: 20250605 16:08:14.014 [6965.6965] INFO ProtocolHandler: [0x2487988]: Next track: TID: 25273044, QID: 26, Context UUID: 6995f71e-27c0-4279-af13-ff1b87013c39 Jun 05 16:08:14 volumio qobuz-connect[6965]: 20250605 16:08:14.014 [6965.6965] INFO MediaEngine: [0x2487248]: Setting next track: 25273044 Jun 05 16:08:14 volumio qobuz-connect[6965]: 20250605 16:08:14.015 [6965.6965] INFO AudioStreamManager: [0x2487330]: New stream: 52 Jun 05 16:08:14 volumio qobuz-connect[6965]: 20250605 16:08:14.015 [6965.6965] INFO HttpDownloader: [0x2d31660]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=661b94d2c4fdfc1ff87f94e3790f4126&request_ts=1749150494&track_id=25273044 Jun 05 16:08:14 volumio qobuz-connect[6965]: 20250605 16:08:14.015 [6965.6965] INFO HttpDownloader: [0x251df38]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=25273044 Jun 05 16:08:14 volumio qobuz-connect[6965]: 20250605 16:08:14.016 [6965.6965] INFO HttpDownloader: [0x2d51310]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=459fc2074be96ca3e7464cd3053e222b&request_ts=1749150494&track_id=25273044 Jun 05 16:08:14 volumio volumio[879]: info: [LastFM] Current track has sufficient metadata: title (Rehab) and artist (Amy Winehouse) passed on explicitly Jun 05 16:08:14 volumio qobuz-connect[6965]: 20250605 16:08:14.163 [6965.6965] INFO HttpDownloader: [0x2487df8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jun 05 16:08:14 volumio qobuz-connect[6965]: 20250605 16:08:14.297 [6965.6965] INFO AudioStreamManager: [0x2487330]: [Stream 52]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=4884528&eid=25273044&fmt=7&profile=raw&app_id=174516466&cid=2800179&etsp=1749154094&hmac=wJVPLzWavg1uhHoQ74lAlDp5OKs Jun 05 16:08:14 volumio volumio[879]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Rehab","mbid":"873eba85-b721-4728-be2e-96ce94cccb8b","url":"https://www.last.fm/music/Amy+Winehouse/_/Rehab","duration":"211000","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"1808049","playcount":"12219160","artist":{"name":"Amy Winehouse","mbid":"dfe9a7c4-8cf2-47f4-9dcb-d233c2b86ec3","url":"https://www.last.fm/music/Amy+Winehouse"},"album":{"@":{"position":"1"},"artist":"Amy Winehouse","title":"Back to Black","mbid":"0b0ca6cc-f793-3dd8-9329-611a9fb08ae2","url":"https://www.last.fm/music/Amy+Winehouse/Back+to+Black","image":[{"#":"https://lastfm.freetls.fastly.net/i/u/34s/5e8b279da10957d060253256c8302f8f.png","@":{"size":"small"}},{"#":"https://lastfm.freetls.fastly.net/i/u/64s/5e8b279da10957d060253256c8302f8f.png","@":{"size":"medium"}},{"#":"https://lastfm.freetls.fastly.net/i/u/174s/5e8b279da10957d060253256c8302f8f.png","@":{"size":"large"}},{"#":"https://lastfm.freetls.fastly.net/i/u/300x300/5e8b279da10957d060253256c8302f8f.png","@":{"size":"extralarge"}}]},"userplaycount":"1","userloved":"0","toptags":{"tag":[{"name":"soul","url":"https://www.last.fm/tag/soul"},{"name":"female vocalists","url":"https://www.last.fm/tag/female+vocalists"},{"name":"jazz","url":"https://www.last.fm/tag/jazz"},{"name":"Amy Winehouse","url":"https://www.last.fm/tag/Amy+Winehouse"},{"name":"british","url":"https://www.last.fm/tag/british"}]},"wiki":{"published":"25 Sep 2008, 08:03","summary":"\"Rehab\" is a song about refusing to go to rehab, despite pressure from family, friends and doctors. The narrator describes her struggles with addiction and her choice to fight it outside of a clinical setting. She expresses her strong will and determination to quit drinking, even if it means she \"will have everyone think [she's] on the mend\". Finally, she is adamant about her choice to take a non-traditional approach to recovery and \"won't go, go, go\" to rehab. Read more on Last.fm.","content":"\"Rehab\" is a song about refusing to go to rehab, despite pressure from family, friends and doctors. The narrator describes her struggles with addiction and her choice to fight it outside of a clinical setting. She expresses her strong will and determination to quit drinking, even if it means she \"will have everyone think [she's] on the mend\". Finally, she is adamant about her choice to take a non-traditional approach to recovery and \"won't go, go, go\" to rehab. Read more on Last.fm. User-contributed text is available under the Creative Commons By-SA License; additional terms may apply."}}} Jun 05 16:08:14 volumio qobuz-connect[6965]: 20250605 16:08:14.348 [6965.6965] INFO AudioStreamManager: [0x2487330]: [Stream 52]: stream information have been fetched Jun 05 16:08:14 volumio qobuz-connect[6965]: 20250605 16:08:14.429 [6965.6965] INFO AudioStreamManager: [0x2487330]: [Stream 52]: Metadata became available: Jun 05 16:08:14 volumio qobuz-connect[6965]: 20250605 16:08:14.429 [6965.6965] INFO AudioStreamManager: [0x2487330]: Title: You Know I'm No Good Jun 05 16:08:14 volumio qobuz-connect[6965]: 20250605 16:08:14.429 [6965.6965] INFO AudioStreamManager: [0x2487330]: Artist: Amy Winehouse Jun 05 16:08:14 volumio qobuz-connect[6965]: 20250605 16:08:14.429 [6965.6965] INFO AudioStreamManager: [0x2487330]: Album: Back To Black Jun 05 16:08:14 volumio qobuz-connect[6965]: 20250605 16:08:14.429 [6965.6965] INFO AudioStreamManager: [0x2487330]: Album art URL: https://static.qobuz.com/images/covers/22/62/0060254746222_600.jpg Jun 05 16:08:23 volumio qobuz-connect[6965]: 20250605 16:08:23.838 [6965.6965] INFO ProtocolHandler: [0x2487988]: Need to send state update Jun 05 16:08:23 volumio qobuz-connect[6965]: 20250605 16:08:23.839 [6965.6965] INFO QConnectMessageSender: [0x2585208]: Sending StateUpdated message Jun 05 16:08:23 volumio qobuz-connect[6965]: 20250605 16:08:23.839 [6965.6965] INFO QwspMessageSender: [0x25e4330]: Sending Payload message Jun 05 16:08:23 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:23 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:08:23 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:08:23 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 16:08:23 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:08:23 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:08:23 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:08:23 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.590 [6965.6965] INFO ProtocolHandler: [0x2487988]: Received SetState message: Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.590 [6965.6965] INFO ProtocolHandler: [0x2487988]: Playing state: Playing Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.590 [6965.6965] INFO ProtocolHandler: [0x2487988]: Playback position: 0 Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.590 [6965.6965] INFO ProtocolHandler: [0x2487988]: Queue version: 4.1 Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.590 [6965.6965] INFO ProtocolHandler: [0x2487988]: Current track: TID: 25273047, QID: 2, Context UUID: 2df51f5b-d08e-4e98-b323-8e9c27a2195f Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.590 [6965.6965] INFO ProtocolHandler: [0x2487988]: Next track: TID: 25273050, QID: 3, Context UUID: 2df51f5b-d08e-4e98-b323-8e9c27a2195f Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.591 [6965.6965] INFO MediaEngine: [0x2487248]: Stopping playback, clearing tracks Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.591 [6965.6965] INFO MediaEngine: [0x2487248]: Initiating playback Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.591 [6965.6965] INFO AudioRenderer: [0x2487468]: Stopping audio renderer Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.591 [6965.6965] INFO SampleApp: Stopping playback Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.593 [6965.6965] INFO AudioStreamManager: [0x2487330]: Clearing all streams Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.594 [6965.6965] INFO AudioStreamManager: [0x2487330]: [Stream 51]: Disposing of audio stream Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.594 [6965.6965] INFO ContentFetcher: [0x2740fe0]: Canceling download Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.610 [6965.6965] INFO SampleApp: Playback state changed: Stopped Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.610 [6965.6965] INFO StreamingEventReporter: [0x2487da0]: Reporting streaming ended event Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.611 [6965.6965] INFO HttpDownloader: [0x2487df8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.612 [6965.6965] INFO ProtocolHandler: [0x2487988]: Need to send state update Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.612 [6965.6965] INFO QConnectMessageSender: [0x2585208]: Sending StateUpdated message Jun 05 16:08:33 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:33 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:08:33 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:08:33 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.613 [6965.6965] INFO SampleApp: [Stream 51]: Audio stream has been disposed of Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.613 [6965.6965] INFO MediaEngine: [0x2487248]: Setting current track: 25273047, initial offset: 0ms Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.613 [6965.6965] INFO AudioStreamManager: [0x2487330]: Clearing all streams Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.613 [6965.6965] INFO AudioStreamManager: [0x2487330]: New stream: 53 Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.613 [6965.6965] INFO HttpDownloader: [0x2e92bc0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=ac49230496e565a1c3550db03ce27ebc&request_ts=1749150513&track_id=25273047 Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.614 [6965.6965] INFO HttpDownloader: [0x25618e0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=25273047 Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.614 [6965.6965] INFO HttpDownloader: [0x2525488]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=3aa5de42c21157bdfcbe54b303c27ddb&request_ts=1749150513&track_id=25273047 Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.615 [6965.6965] INFO AudioStreamManager: [0x2487330]: [Stream 53]: Running audio stream Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.615 [6965.6965] INFO QwspMessageSender: [0x25e4330]: Sending Payload message Jun 05 16:08:33 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:08:33 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.622 [6965.6965] INFO MediaEngine: [0x2487248]: Setting next track: 25273050 Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.622 [6965.6965] INFO AudioStreamManager: [0x2487330]: New stream: 54 Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.622 [6965.6965] INFO HttpDownloader: [0x2510a60]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=98c398aee35044a2a4eb6edc936b4d8c&request_ts=1749150513&track_id=25273050 Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.623 [6965.6965] INFO HttpDownloader: [0x2cf65e8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=25273050 Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.623 [6965.6965] INFO HttpDownloader: [0x2ea5c28]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=8724b4ba728a812fdbe134b3191d5455&request_ts=1749150513&track_id=25273050 Jun 05 16:08:33 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:08:33 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.628 [6965.6965] INFO MediaEngine: [0x2487248]: Waiting for current stream to start before starting audio renderer Jun 05 16:08:33 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:33 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:08:33 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:08:33 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 16:08:33 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:08:33 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:08:33 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:08:33 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.972 [6965.6965] INFO ProtocolHandler: [0x2487988]: Received SetState message: Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.973 [6965.6965] INFO ProtocolHandler: [0x2487988]: Playing state: Paused Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.973 [6965.6965] INFO ProtocolHandler: [0x2487988]: Playback position: Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.973 [6965.6965] INFO ProtocolHandler: [0x2487988]: Queue version: Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.973 [6965.6965] INFO ProtocolHandler: [0x2487988]: Current track: Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.973 [6965.6965] INFO ProtocolHandler: [0x2487988]: Next track: Jun 05 16:08:33 volumio qobuz-connect[6965]: 20250605 16:08:33.973 [6965.6965] INFO MediaEngine: [0x2487248]: Pausing playback Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.045 [6965.6965] INFO AudioStreamManager: [0x2487330]: [Stream 54]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=4884528&eid=25273050&fmt=7&profile=raw&app_id=174516466&cid=2800179&etsp=1749154113&hmac=_Rcex_uw4Ov2yEiSola8o_nxYy0 Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.073 [6965.6965] INFO AudioStreamManager: [0x2487330]: [Stream 53]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=4884528&eid=25273047&fmt=7&profile=raw&app_id=174516466&cid=2800179&etsp=1749154113&hmac=ECqgZlK0WiQ9elXKS5STecXl6mc Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.078 [6965.6965] INFO AudioStreamManager: [0x2487330]: [Stream 54]: stream information have been fetched Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.147 [6965.6965] INFO AudioStreamManager: [0x2487330]: [Stream 53]: Metadata became available: Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.148 [6965.6965] INFO AudioStreamManager: [0x2487330]: Title: Me & Mr Jones Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.148 [6965.6965] INFO AudioStreamManager: [0x2487330]: Artist: Amy Winehouse Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.148 [6965.6965] INFO AudioStreamManager: [0x2487330]: Album: Back To Black Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.148 [6965.6965] INFO AudioStreamManager: [0x2487330]: Album art URL: https://static.qobuz.com/images/covers/22/62/0060254746222_600.jpg Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.158 [6965.6965] INFO AudioStreamManager: [0x2487330]: [Stream 54]: Metadata became available: Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.158 [6965.6965] INFO AudioStreamManager: [0x2487330]: Title: Just Friends Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.158 [6965.6965] INFO AudioStreamManager: [0x2487330]: Artist: Amy Winehouse Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.158 [6965.6965] INFO AudioStreamManager: [0x2487330]: Album: Back To Black Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.159 [6965.6965] INFO AudioStreamManager: [0x2487330]: Album art URL: https://static.qobuz.com/images/covers/22/62/0060254746222_600.jpg Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.160 [6965.6965] INFO AudioStreamManager: [0x2487330]: [Stream 53]: stream information have been fetched Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.161 [6965.6965] INFO UrlAudioSource: [0x2a04478]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=4884528&eid=25273047&fmt=7&profile=raw&app_id=174516466&cid=2800179&etsp=1749154113&hmac=ECqgZlK0WiQ9elXKS5STecXl6mc Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.161 [6965.6965] INFO ContentFetcher: [0x2b6cf68]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=4884528&eid=25273047&fmt=7&profile=raw&app_id=174516466&cid=2800179&etsp=1749154113&hmac=ECqgZlK0WiQ9elXKS5STecXl6mc, offset: 0 Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.162 [6965.6965] INFO AudioRenderer: [0x2487468]: Starting audio renderer, initial playback state: Playing Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.163 [6965.6965] INFO SampleApp: [Stream 53]: New audio stream (starting from 0ms) Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.163 [6965.6965] INFO SampleApp: [Stream 53]: Stream metadata became available: Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.163 [6965.6965] INFO SampleApp: Title: Me & Mr Jones Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.163 [6965.6965] INFO SampleApp: Artist: Amy Winehouse Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.163 [6965.6965] INFO SampleApp: Album: Back To Black Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.163 [6965.6965] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/22/62/0060254746222_600.jpg Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:34 volumio volumio[879]: info: QobuzConnect: alread currrent service, send playback confirmation Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.168 [6965.6965] INFO SampleApp: Initiating playback, initial state: Playing Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.178 [6965.6965] INFO SampleApp: Playback state changed: Playing Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.178 [6965.6965] INFO StreamingEventReporter: [0x2487da0]: Reporting streaming started event Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.179 [6965.6965] INFO HttpDownloader: [0x2487df8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.179 [6965.6965] INFO ProtocolHandler: [0x2487988]: Need to send state update Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:08:34 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.180 [6965.6965] INFO QConnectMessageSender: [0x2585208]: Sending StateUpdated message Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.180 [6965.6965] INFO AudioRenderer: [0x2487468]: Pausing audio renderer Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.180 [6965.6965] INFO QwspMessageSender: [0x25e4330]: Sending Payload message Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.183 [6965.6965] INFO QConnectMessageSender: [0x2585208]: Sending FileAudioQualityChanged message Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.183 [6965.6965] INFO QwspMessageSender: [0x25e4330]: Sending Payload message Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.184 [6965.6965] INFO SampleApp: Pausing playback Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.185 [6965.6965] INFO SampleApp: Playback state changed: Paused Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.185 [6965.6965] INFO StreamingEventReporter: [0x2487da0]: Reporting streaming ended event Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.186 [6965.6965] INFO ProtocolHandler: [0x2487988]: Need to send state update Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.186 [6965.6965] INFO QConnectMessageSender: [0x2585208]: Sending StateUpdated message Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.186 [6965.6965] INFO QwspMessageSender: [0x25e4330]: Sending Payload message Jun 05 16:08:34 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:08:34 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:08:34 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:08:34 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:08:34 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:08:34 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:08:34 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:08:34 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:08:34 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:08:34 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:08:34 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:08:34 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:08:34 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:08:34 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:08:34 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:08:34 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:08:34 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:08:34 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:08:34 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:08:34 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:08:34 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:34 volumio volumio[879]: info: [LastFM] Current track has sufficient metadata: title (Me & Mr Jones) and artist (Amy Winehouse) passed on explicitly Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.391 [6965.6965] INFO HttpDownloader: [0x2487df8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jun 05 16:08:34 volumio volumio[879]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Me & Mr Jones","mbid":"217c4f4a-d6d1-44db-9581-4cc83b738446","url":"https://www.last.fm/music/Amy+Winehouse/_/Me+&+Mr+Jones","duration":"151000","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"808446","playcount":"5161864","artist":{"name":"Amy Winehouse","mbid":"dfe9a7c4-8cf2-47f4-9dcb-d233c2b86ec3","url":"https://www.last.fm/music/Amy+Winehouse"},"album":{"@":{"position":"3"},"artist":"Amy Winehouse","title":"Back to Black","mbid":"0b0ca6cc-f793-3dd8-9329-611a9fb08ae2","url":"https://www.last.fm/music/Amy+Winehouse/Back+to+Black","image":[{"#":"https://lastfm.freetls.fastly.net/i/u/34s/5e8b279da10957d060253256c8302f8f.png","@":{"size":"small"}},{"#":"https://lastfm.freetls.fastly.net/i/u/64s/5e8b279da10957d060253256c8302f8f.png","@":{"size":"medium"}},{"#":"https://lastfm.freetls.fastly.net/i/u/174s/5e8b279da10957d060253256c8302f8f.png","@":{"size":"large"}},{"#":"https://lastfm.freetls.fastly.net/i/u/300x300/5e8b279da10957d060253256c8302f8f.png","@":{"size":"extralarge"}}]},"userplaycount":"2","userloved":"0","toptags":{"tag":[{"name":"soul","url":"https://www.last.fm/tag/soul"},{"name":"jazz","url":"https://www.last.fm/tag/jazz"},{"name":"female vocalists","url":"https://www.last.fm/tag/female+vocalists"},{"name":"Amy Winehouse","url":"https://www.last.fm/tag/Amy+Winehouse"},{"name":"pop","url":"https://www.last.fm/tag/pop"}]},"wiki":{"published":"26 Jun 2022, 16:19","summary":"\"Me & Mr. Jones\" explores themes of love, betrayal and loyalty. The song appears to be about a tumultuous relationship between Winehouse and her lover, referred to as Mr. Jones. In the chorus, Winehouse expresses her frustration with Mr. Jones, who caused her to miss a concert by rapper Slick Rick and played her \"out\" by pretending not to love her when he actually did. In the first verse, Winehouse asserts that Mr. Jones is not worth a guest list spot, implying that he has been unfaithful by kissing other girls. Read more on Last.fm.","content":"\"Me & Mr. Jones\" explores themes of love, betrayal and loyalty. The song appears to be about a tumultuous relationship between Winehouse and her lover, referred to as Mr. Jones. In the chorus, Winehouse expresses her frustration with Mr. Jones, who caused her to miss a concert by rapper Slick Rick and played her \"out\" by pretending not to love her when he actually did. In the first verse, Winehouse asserts that Mr. Jones is not worth a guest list spot, implying that he has been unfaithful by kissing other girls. She also suggests that Mr. Jones is lying to himself about their relationship and has played himself by losing her. The bridge emphasizes Winehouse's loyalty to her man, as nobody stands in between her and Mr. Jones, regardless of where they are. The second chorus shows that Winehouse still cares about Mr. Jones, but he no longer holds the same importance to her. She hints that he has done something wrong and needs to make it up to her. The use of the term \"fuckery\" implies that their relationship is tumultuous and filled with drama. In the third chorus, Winehouse calls Mr. Jones her \"best black Jew\", suggesting that he is important to her despite their issues. She expresses confusion over whether they are still together, suggesting that he has given her reason to doubt the stability of their relationship. The outro references Mr. Destiny, 9 and 14, which are references to Nas, or Nasir Jones, Amy’s favourite rapper. Winehouse is expressing her devotion to Mr. Jones despite their ups and downs. Ultimately, \"Me & Mr. Jones\" is a song about the complicated and often painful nature of romantic relationships. Read more on Last.fm. User-contributed text is available under the Creative Commons By-SA License; additional terms may apply."}}} Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.744 [6965.6965] INFO ProtocolHandler: [0x2487988]: Received SetState message: Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.745 [6965.6965] INFO ProtocolHandler: [0x2487988]: Playing state: Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.745 [6965.6965] INFO ProtocolHandler: [0x2487988]: Playback position: Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.745 [6965.6965] INFO ProtocolHandler: [0x2487988]: Queue version: 4.2 Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.745 [6965.6965] INFO ProtocolHandler: [0x2487988]: Current track: Jun 05 16:08:34 volumio qobuz-connect[6965]: 20250605 16:08:34.745 [6965.6965] INFO ProtocolHandler: [0x2487988]: Next track: TID: 25273050, QID: 3, Context UUID: 2df51f5b-d08e-4e98-b323-8e9c27a2195f Jun 05 16:08:35 volumio qobuz-connect[6965]: 20250605 16:08:35.061 [6965.6965] INFO UrlAudioSource: [0x2a04478]: Content info extracted Jun 05 16:08:35 volumio qobuz-connect[6965]: 20250605 16:08:35.061 [6965.6965] INFO UrlAudioSource: [0x2a04478]: Size: 55858676 Jun 05 16:08:35 volumio qobuz-connect[6965]: 20250605 16:08:35.064 [6965.8006] INFO AudioDecoder: [0x24c83f8]: Stream info: Jun 05 16:08:35 volumio qobuz-connect[6965]: 20250605 16:08:35.064 [6965.8006] INFO AudioDecoder: [0x24c83f8]: Total samples: 14704640 Jun 05 16:08:35 volumio qobuz-connect[6965]: 20250605 16:08:35.064 [6965.8006] INFO AudioDecoder: [0x24c83f8]: Sample rate: 96000 Jun 05 16:08:35 volumio qobuz-connect[6965]: 20250605 16:08:35.064 [6965.8006] INFO AudioDecoder: [0x24c83f8]: Channel count: 2 Jun 05 16:08:35 volumio qobuz-connect[6965]: 20250605 16:08:35.064 [6965.8006] INFO AudioDecoder: [0x24c83f8]: Bits per sample: 24 Jun 05 16:08:35 volumio qobuz-connect[6965]: 20250605 16:08:35.064 [6965.8006] INFO AudioDecoder: [0x24c83f8]: Duration: 153173 Jun 05 16:08:35 volumio qobuz-connect[6965]: 20250605 16:08:35.065 [6965.6965] INFO UrlAudioSource: [0x2a04478]: Audio properties have changed Jun 05 16:08:35 volumio qobuz-connect[6965]: 20250605 16:08:35.566 [6965.6965] INFO ProtocolHandler: [0x2487988]: Received SetState message: Jun 05 16:08:35 volumio qobuz-connect[6965]: 20250605 16:08:35.566 [6965.6965] INFO ProtocolHandler: [0x2487988]: Playing state: Playing Jun 05 16:08:35 volumio qobuz-connect[6965]: 20250605 16:08:35.567 [6965.6965] INFO ProtocolHandler: [0x2487988]: Playback position: Jun 05 16:08:35 volumio qobuz-connect[6965]: 20250605 16:08:35.567 [6965.6965] INFO ProtocolHandler: [0x2487988]: Queue version: Jun 05 16:08:35 volumio qobuz-connect[6965]: 20250605 16:08:35.567 [6965.6965] INFO ProtocolHandler: [0x2487988]: Current track: Jun 05 16:08:35 volumio qobuz-connect[6965]: 20250605 16:08:35.567 [6965.6965] INFO ProtocolHandler: [0x2487988]: Next track: Jun 05 16:08:35 volumio qobuz-connect[6965]: 20250605 16:08:35.567 [6965.6965] INFO MediaEngine: [0x2487248]: Resuming playback Jun 05 16:08:35 volumio qobuz-connect[6965]: 20250605 16:08:35.567 [6965.6965] INFO AudioRenderer: [0x2487468]: Resuming audio renderer Jun 05 16:08:35 volumio qobuz-connect[6965]: 20250605 16:08:35.568 [6965.6965] INFO SampleApp: Resuming playback Jun 05 16:08:35 volumio qobuz-connect[6965]: 20250605 16:08:35.569 [6965.6965] INFO SampleApp: Playback state changed: Playing Jun 05 16:08:35 volumio qobuz-connect[6965]: 20250605 16:08:35.570 [6965.6965] INFO StreamingEventReporter: [0x2487da0]: Reporting streaming started event Jun 05 16:08:35 volumio qobuz-connect[6965]: 20250605 16:08:35.570 [6965.6965] INFO HttpDownloader: [0x2487df8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jun 05 16:08:35 volumio qobuz-connect[6965]: 20250605 16:08:35.571 [6965.6965] INFO ProtocolHandler: [0x2487988]: Need to send state update Jun 05 16:08:35 volumio qobuz-connect[6965]: 20250605 16:08:35.571 [6965.6965] INFO QConnectMessageSender: [0x2585208]: Sending StateUpdated message Jun 05 16:08:35 volumio qobuz-connect[6965]: 20250605 16:08:35.572 [6965.6965] INFO QwspMessageSender: [0x25e4330]: Sending Payload message Jun 05 16:08:35 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:35 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:08:35 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:08:35 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:08:35 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:08:35 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:08:35 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:35 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:08:35 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:08:35 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 16:08:35 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:08:35 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:08:35 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:08:35 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:35 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:35 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:08:35 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:08:35 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:08:35 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:08:35 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:08:35 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:35 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:35 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:08:35 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:08:35 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:08:35 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:08:35 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:08:35 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:45 volumio qobuz-connect[6965]: 20250605 16:08:45.569 [6965.6965] INFO ProtocolHandler: [0x2487988]: Need to send state update Jun 05 16:08:45 volumio qobuz-connect[6965]: 20250605 16:08:45.569 [6965.6965] INFO QConnectMessageSender: [0x2585208]: Sending StateUpdated message Jun 05 16:08:45 volumio qobuz-connect[6965]: 20250605 16:08:45.570 [6965.6965] INFO QwspMessageSender: [0x25e4330]: Sending Payload message Jun 05 16:08:45 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:45 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:08:45 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:08:45 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 16:08:45 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:08:45 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:08:45 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:08:45 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:55 volumio qobuz-connect[6965]: 20250605 16:08:55.569 [6965.6965] INFO ProtocolHandler: [0x2487988]: Need to send state update Jun 05 16:08:55 volumio qobuz-connect[6965]: 20250605 16:08:55.569 [6965.6965] INFO QConnectMessageSender: [0x2585208]: Sending StateUpdated message Jun 05 16:08:55 volumio qobuz-connect[6965]: 20250605 16:08:55.569 [6965.6965] INFO QwspMessageSender: [0x25e4330]: Sending Payload message Jun 05 16:08:55 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:08:55 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:08:55 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:08:55 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 16:08:55 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:08:55 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:08:55 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:08:55 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:09:05 volumio qobuz-connect[6965]: 20250605 16:09:05.571 [6965.6965] INFO ProtocolHandler: [0x2487988]: Need to send state update Jun 05 16:09:05 volumio qobuz-connect[6965]: 20250605 16:09:05.571 [6965.6965] INFO QConnectMessageSender: [0x2585208]: Sending StateUpdated message Jun 05 16:09:05 volumio qobuz-connect[6965]: 20250605 16:09:05.572 [6965.6965] INFO QwspMessageSender: [0x25e4330]: Sending Payload message Jun 05 16:09:05 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:09:05 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:09:05 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:09:05 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 16:09:05 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:09:05 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:09:05 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:09:05 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.626 [6965.6965] INFO ProtocolHandler: [0x2487988]: Received SetState message: Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.626 [6965.6965] INFO ProtocolHandler: [0x2487988]: Playing state: Playing Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.626 [6965.6965] INFO ProtocolHandler: [0x2487988]: Playback position: 0 Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.626 [6965.6965] INFO ProtocolHandler: [0x2487988]: Queue version: 5.1 Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.627 [6965.6965] INFO ProtocolHandler: [0x2487988]: Current track: TID: 192650920, QID: 6, Context UUID: 8cddc250-b545-4986-9fa2-20a3dba57a26 Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.627 [6965.6965] INFO ProtocolHandler: [0x2487988]: Next track: TID: 192650921, QID: 7, Context UUID: 8cddc250-b545-4986-9fa2-20a3dba57a26 Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.627 [6965.6965] INFO MediaEngine: [0x2487248]: Stopping playback, clearing tracks Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.627 [6965.6965] INFO MediaEngine: [0x2487248]: Initiating playback Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.627 [6965.6965] INFO AudioRenderer: [0x2487468]: Stopping audio renderer Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.628 [6965.6965] INFO SampleApp: Stopping playback Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.630 [6965.6965] INFO AudioStreamManager: [0x2487330]: Clearing all streams Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.630 [6965.6965] INFO AudioStreamManager: [0x2487330]: [Stream 53]: Disposing of audio stream Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.630 [6965.6965] INFO ContentFetcher: [0x2b6cf68]: Canceling download Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.649 [6965.6965] INFO SampleApp: Playback state changed: Stopped Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.649 [6965.6965] INFO StreamingEventReporter: [0x2487da0]: Reporting streaming ended event Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.650 [6965.6965] INFO HttpDownloader: [0x2487df8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.651 [6965.6965] INFO ProtocolHandler: [0x2487988]: Need to send state update Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.651 [6965.6965] INFO QConnectMessageSender: [0x2585208]: Sending StateUpdated message Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.651 [6965.6965] INFO SampleApp: [Stream 53]: Audio stream has been disposed of Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.651 [6965.6965] INFO MediaEngine: [0x2487248]: Setting current track: 192650920, initial offset: 0ms Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.651 [6965.6965] INFO AudioStreamManager: [0x2487330]: Clearing all streams Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.652 [6965.6965] INFO AudioStreamManager: [0x2487330]: New stream: 55 Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.652 [6965.6965] INFO HttpDownloader: [0x2912498]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=34ed4b88479e0ae654e22b50ea6db5ba&request_ts=1749150546&track_id=192650920 Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.652 [6965.6965] INFO HttpDownloader: [0x256f6b8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=192650920 Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.652 [6965.6965] INFO HttpDownloader: [0x2e92bc0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=857fed6aeae7111f4a83c9888f64e90f&request_ts=1749150546&track_id=192650920 Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.653 [6965.6965] INFO AudioStreamManager: [0x2487330]: [Stream 55]: Running audio stream Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.653 [6965.6965] INFO QwspMessageSender: [0x25e4330]: Sending Payload message Jun 05 16:09:06 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:09:06 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:09:06 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:09:06 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 16:09:06 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:09:06 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:09:06 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:09:06 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:09:06 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:09:06 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:09:06 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 16:09:06 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:09:06 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:09:06 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:09:06 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.707 [6965.6965] INFO MediaEngine: [0x2487248]: Setting next track: 192650921 Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.707 [6965.6965] INFO AudioStreamManager: [0x2487330]: New stream: 56 Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.707 [6965.6965] INFO HttpDownloader: [0x252b180]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=dd11ceb5aeaaac2a87eac8681b383200&request_ts=1749150546&track_id=192650921 Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.708 [6965.6965] INFO HttpDownloader: [0x2517f38]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=192650921 Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.708 [6965.6965] INFO HttpDownloader: [0x2702e58]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=ba7c084f4be3c77e5c96e8f15d07dac1&request_ts=1749150546&track_id=192650921 Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.712 [6965.6965] INFO MediaEngine: [0x2487248]: Waiting for current stream to start before starting audio renderer Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.964 [6965.6965] INFO AudioStreamManager: [0x2487330]: [Stream 55]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=4884528&eid=192650920&fmt=6&profile=raw&app_id=174516466&cid=2800179&etsp=1749154146&hmac=XumchotjsgD8IVot0Ngfr3sxXNE Jun 05 16:09:06 volumio qobuz-connect[6965]: 20250605 16:09:06.982 [6965.6965] INFO AudioStreamManager: [0x2487330]: [Stream 56]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=4884528&eid=192650921&fmt=6&profile=raw&app_id=174516466&cid=2800179&etsp=1749154146&hmac=Kt4u9KTocBLqWpbZHnV8CFLx3g0 Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.039 [6965.6965] INFO AudioStreamManager: [0x2487330]: [Stream 55]: stream information have been fetched Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.039 [6965.6965] INFO UrlAudioSource: [0x248b278]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=4884528&eid=192650920&fmt=6&profile=raw&app_id=174516466&cid=2800179&etsp=1749154146&hmac=XumchotjsgD8IVot0Ngfr3sxXNE Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.040 [6965.6965] INFO ContentFetcher: [0x2651400]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=4884528&eid=192650920&fmt=6&profile=raw&app_id=174516466&cid=2800179&etsp=1749154146&hmac=XumchotjsgD8IVot0Ngfr3sxXNE, offset: 0 Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.040 [6965.6965] INFO AudioStreamManager: [0x2487330]: [Stream 56]: stream information have been fetched Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.041 [6965.6965] INFO AudioRenderer: [0x2487468]: Starting audio renderer, initial playback state: Playing Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.041 [6965.6965] INFO SampleApp: [Stream 55]: New audio stream (starting from 0ms) Jun 05 16:09:07 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:09:07 volumio volumio[879]: info: QobuzConnect: alread currrent service, send playback confirmation Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.045 [6965.6965] INFO SampleApp: Initiating playback, initial state: Playing Jun 05 16:09:07 volumio qobuz-connect[6965]: 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 88200 Jun 05 16:09:07 volumio qobuz-connect[6965]: 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 88200 Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.081 [6965.6965] INFO SampleApp: Playback state changed: Playing Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.081 [6965.6965] INFO StreamingEventReporter: [0x2487da0]: Reporting streaming started event Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.081 [6965.6965] INFO HttpDownloader: [0x2487df8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.082 [6965.6965] INFO ProtocolHandler: [0x2487988]: Need to send state update Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.082 [6965.6965] INFO QConnectMessageSender: [0x2585208]: Sending StateUpdated message Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.082 [6965.6965] INFO QwspMessageSender: [0x25e4330]: Sending Payload message Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.084 [6965.6965] INFO QConnectMessageSender: [0x2585208]: Sending FileAudioQualityChanged message Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.085 [6965.6965] INFO QwspMessageSender: [0x25e4330]: Sending Payload message Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.085 [6965.6965] INFO QConnectMessageSender: [0x2585208]: Sending DeviceAudioQualityChanged message Jun 05 16:09:07 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:09:07 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:09:07 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:09:07 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.087 [6965.6965] INFO QwspMessageSender: [0x25e4330]: Sending Payload message Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.090 [6965.6965] INFO AudioStreamManager: [0x2487330]: [Stream 55]: Metadata became available: Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.090 [6965.6965] INFO AudioStreamManager: [0x2487330]: Title: Mistério Stereo Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.090 [6965.6965] INFO AudioStreamManager: [0x2487330]: Artist: Curumin Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.090 [6965.6965] INFO AudioStreamManager: [0x2487330]: Album: JapanPopShow Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.090 [6965.6965] INFO AudioStreamManager: [0x2487330]: Album art URL: https://static.qobuz.com/images/covers/ua/nd/arb73x2onndua_600.jpg Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.090 [6965.6965] INFO AudioStreamManager: [0x2487330]: [Stream 56]: Metadata became available: Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.090 [6965.6965] INFO AudioStreamManager: [0x2487330]: Title: Saída Bangu Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.090 [6965.6965] INFO AudioStreamManager: [0x2487330]: Artist: Curumin Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.091 [6965.6965] INFO AudioStreamManager: [0x2487330]: Album: JapanPopShow Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.091 [6965.6965] INFO AudioStreamManager: [0x2487330]: Album art URL: https://static.qobuz.com/images/covers/ua/nd/arb73x2onndua_600.jpg Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.091 [6965.6965] INFO SampleApp: [Stream 55]: Stream metadata became available: Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.091 [6965.6965] INFO SampleApp: Title: Mistério Stereo Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.091 [6965.6965] INFO SampleApp: Artist: Curumin Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.091 [6965.6965] INFO SampleApp: Album: JapanPopShow Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.091 [6965.6965] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/ua/nd/arb73x2onndua_600.jpg Jun 05 16:09:07 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:09:07 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:09:07 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:09:07 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:09:07 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:09:07 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:09:07 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 16:09:07 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:09:07 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:09:07 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:09:07 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:09:07 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:09:07 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:09:07 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:09:07 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:09:07 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:09:07 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:09:07 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:09:07 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:09:07 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:09:07 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:09:07 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:09:07 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:09:07 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:09:07 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:09:07 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:09:07 volumio volumio[879]: info: CoreCommandRouter::servicePushState Jun 05 16:09:07 volumio volumio[879]: info: CoreStateMachine::pushState Jun 05 16:09:07 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 16:09:07 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Jun 05 16:09:07 volumio volumio[879]: info: MRS: Pushing multiroomSync output update for this device Jun 05 16:09:07 volumio volumio[879]: info: MRS: Pushing multiroomSync output Jun 05 16:09:07 volumio volumio[879]: info: CoreCommandRouter::volumioGetState Jun 05 16:09:07 volumio volumio[879]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2fUiR7QiRXgyEbmJgdrOOq4yFmu1.9ccd4fb6672625ff00da074872019019.state.track' Jun 05 16:09:07 volumio volumio[879]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.2fUiR7QiRXgyEbmJgdrOOq4yFmu1.9ccd4fb6672625ff00da074872019019.state.track' Jun 05 16:09:07 volumio volumio[879]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Jun 05 16:09:07 volumio volumio[879]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 05 16:09:07 volumio volumio[879]: TypeError: Cannot read property 'indexOf' of undefined Jun 05 16:09:07 volumio volumio[879]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Jun 05 16:09:07 volumio volumio[879]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Jun 05 16:09:07 volumio volumio[879]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Jun 05 16:09:07 volumio volumio[879]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jun 05 16:09:07 volumio volumio[879]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Jun 05 16:09:07 volumio volumio[879]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Jun 05 16:09:07 volumio volumio[879]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jun 05 16:09:07 volumio volumio[879]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jun 05 16:09:07 volumio volumio[879]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Jun 05 16:09:07 volumio volumio[879]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jun 05 16:09:07 volumio volumio[879]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Jun 05 16:09:07 volumio volumio[879]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Jun 05 16:09:07 volumio volumio[879]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Jun 05 16:09:07 volumio volumio[879]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Jun 05 16:09:07 volumio volumio[879]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Jun 05 16:09:07 volumio volumio[879]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Jun 05 16:09:07 volumio volumio[879]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.407 [6965.6965] INFO UrlAudioSource: [0x248b278]: Content info extracted Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.407 [6965.6965] INFO UrlAudioSource: [0x248b278]: Size: 24444276 Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.418 [6965.8073] INFO AudioDecoder: [0x26de898]: Stream info: Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.419 [6965.8073] INFO AudioDecoder: [0x26de898]: Total samples: 10934448 Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.419 [6965.8073] INFO AudioDecoder: [0x26de898]: Sample rate: 44100 Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.419 [6965.8073] INFO AudioDecoder: [0x26de898]: Channel count: 2 Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.419 [6965.8073] INFO AudioDecoder: [0x26de898]: Bits per sample: 16 Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.419 [6965.8073] INFO AudioDecoder: [0x26de898]: Duration: 247946 Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.419 [6965.6965] INFO UrlAudioSource: [0x248b278]: Audio properties have changed Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.902 [6965.6965] INFO ProtocolHandler: [0x2487988]: Received SetState message: Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.902 [6965.6965] INFO ProtocolHandler: [0x2487988]: Playing state: Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.902 [6965.6965] INFO ProtocolHandler: [0x2487988]: Playback position: Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.902 [6965.6965] INFO ProtocolHandler: [0x2487988]: Queue version: 5.2 Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.903 [6965.6965] INFO ProtocolHandler: [0x2487988]: Current track: Jun 05 16:09:07 volumio qobuz-connect[6965]: 20250605 16:09:07.903 [6965.6965] INFO ProtocolHandler: [0x2487988]: Next track: TID: 192650921, QID: 7, Context UUID: 8cddc250-b545-4986-9fa2-20a3dba57a26 Jun 05 16:09:09 volumio sudo[8082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-05 16:08 Jun 05 16:09:09 volumio sudo[8082]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"