-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2025-05-25 15:42:14 CEST. -- May 25 15:41:07 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:07.576 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Need to send state update May 25 15:41:07 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:07.576 [11929.11929] INFO QConnectMessageSender: [0x218f520]: Sending StateUpdated message May 25 15:41:07 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:07.576 [11929.11929] INFO QwspMessageSender: [0x2259a88]: Sending Payload message May 25 15:41:07 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:07 volumio-sovrum volumio[969]: info: CoreCommandRouter::servicePushState May 25 15:41:07 volumio-sovrum volumio[969]: info: CoreStateMachine::pushState May 25 15:41:07 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 15:41:07 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioPushState May 25 15:41:07 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output update for this device May 25 15:41:07 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output May 25 15:41:07 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:15 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 15:41:15 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 15:41:15 volumio-sovrum volumio[969]: info: Discovery: Getting this device information May 25 15:41:15 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:15 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 15:41:17 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:17.576 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Need to send state update May 25 15:41:17 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:17.576 [11929.11929] INFO QConnectMessageSender: [0x218f520]: Sending StateUpdated message May 25 15:41:17 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:17.576 [11929.11929] INFO QwspMessageSender: [0x2259a88]: Sending Payload message May 25 15:41:17 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:17 volumio-sovrum volumio[969]: info: CoreCommandRouter::servicePushState May 25 15:41:17 volumio-sovrum volumio[969]: info: CoreStateMachine::pushState May 25 15:41:17 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 15:41:17 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioPushState May 25 15:41:17 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output update for this device May 25 15:41:17 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output May 25 15:41:17 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:17 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:17.615 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: [Stream 29]: Running audio stream May 25 15:41:17 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:17.615 [11929.11929] INFO UrlAudioSource: [0x2488be0]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=334473184&fmt=7&profile=raw&app_id=174516466&cid=2789982&etsp=1748183887&hmac=U6qnVme1uEqcFKib2GUAwRsyCCM May 25 15:41:17 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:17.615 [11929.11929] INFO ContentFetcher: [0x2415070]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=334473184&fmt=7&profile=raw&app_id=174516466&cid=2789982&etsp=1748183887&hmac=U6qnVme1uEqcFKib2GUAwRsyCCM, offset: 0 May 25 15:41:17 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:17.615 [11929.11929] INFO SampleApp: [Stream 29]: New audio stream (starting from 0ms) May 25 15:41:17 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:17.616 [11929.11929] INFO SampleApp: [Stream 29]: Stream metadata became available: May 25 15:41:17 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:17.616 [11929.11929] INFO SampleApp: Title: Luqa Airport May 25 15:41:17 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:17.616 [11929.11929] INFO SampleApp: Artist: Mogwai May 25 15:41:17 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:17.616 [11929.11929] INFO SampleApp: Album: The Bombing of Pan Am 103 May 25 15:41:17 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:17.616 [11929.11929] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/ub/ax/qlw2bki5xaxub_600.jpg May 25 15:41:17 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:17 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:17.852 [11929.11929] INFO UrlAudioSource: [0x2488be0]: Content info extracted May 25 15:41:17 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:17.852 [11929.11929] INFO UrlAudioSource: [0x2488be0]: Size: 32373136 May 25 15:41:17 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:17.854 [11929.14231] INFO AudioDecoder: [0x21cc8f8]: Stream info: May 25 15:41:17 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:17.854 [11929.14231] INFO AudioDecoder: [0x21cc8f8]: Total samples: 10067676 May 25 15:41:17 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:17.854 [11929.14231] INFO AudioDecoder: [0x21cc8f8]: Sample rate: 48000 May 25 15:41:17 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:17.854 [11929.14231] INFO AudioDecoder: [0x21cc8f8]: Channel count: 2 May 25 15:41:17 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:17.854 [11929.14231] INFO AudioDecoder: [0x21cc8f8]: Bits per sample: 24 May 25 15:41:17 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:17.854 [11929.14231] INFO AudioDecoder: [0x21cc8f8]: Duration: 209743 May 25 15:41:17 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:17.855 [11929.11929] INFO UrlAudioSource: [0x2488be0]: Audio properties have changed May 25 15:41:26 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:26.948 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: [Stream 28]: Audio stream has finished May 25 15:41:26 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:26.948 [11929.11929] INFO SampleApp: [Stream 28]: Audio stream finished May 25 15:41:26 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:26.948 [11929.11929] INFO SampleApp: Playback is nearly finished May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.576 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Need to send state update May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.576 [11929.11929] INFO QConnectMessageSender: [0x218f520]: Sending StateUpdated message May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.576 [11929.11929] INFO QwspMessageSender: [0x2259a88]: Sending Payload message May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::servicePushState May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreStateMachine::pushState May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioPushState May 25 15:41:27 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output update for this device May 25 15:41:27 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.615 [11929.11929] INFO SampleApp: Playback has finished May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.616 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: [Stream 28]: Disposing of audio stream May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.616 [11929.11929] INFO ContentFetcher: [0x238f100]: Canceling download May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.616 [11929.11929] INFO SampleApp: [Stream 28]: Audio stream has been disposed of May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.616 [11929.11929] INFO StreamingEventReporter: [0x214ff10]: Reporting streaming ended event May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.616 [11929.11929] INFO HttpDownloader: [0x214ff68]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.616 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Need to send state update May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.617 [11929.11929] INFO QConnectMessageSender: [0x218f520]: Sending StateUpdated message May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.617 [11929.11929] INFO QwspMessageSender: [0x2259a88]: Sending Payload message May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.618 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Need to send state update May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.618 [11929.11929] INFO QConnectMessageSender: [0x218f520]: Sending StateUpdated message May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.618 [11929.11929] INFO QwspMessageSender: [0x2259a88]: Sending Payload message May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::servicePushState May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreStateMachine::pushState May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.618 [11929.11929] INFO QConnectMessageSender: [0x218f520]: Sending FileAudioQualityChanged message May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.619 [11929.11929] INFO QwspMessageSender: [0x2259a88]: Sending Payload message May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.619 [11929.11929] INFO StreamingEventReporter: [0x214ff10]: Reporting streaming started event May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioPushState May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.619 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Need to send state update May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.619 [11929.11929] INFO QConnectMessageSender: [0x218f520]: Sending StateUpdated message May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.619 [11929.11929] INFO QwspMessageSender: [0x2259a88]: Sending Payload message May 25 15:41:27 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output update for this device May 25 15:41:27 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::servicePushState May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreStateMachine::pushState May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioPushState May 25 15:41:27 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output update for this device May 25 15:41:27 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::servicePushState May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreStateMachine::pushState May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioPushState May 25 15:41:27 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output update for this device May 25 15:41:27 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::servicePushState May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreStateMachine::pushState May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioPushState May 25 15:41:27 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output update for this device May 25 15:41:27 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::servicePushState May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreStateMachine::pushState May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioPushState May 25 15:41:27 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output update for this device May 25 15:41:27 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output May 25 15:41:27 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:27 volumio-sovrum volumio[969]: info: [LastFM] Current track has sufficient metadata: title (Luqa Airport) and artist (Mogwai) passed on explicitly May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.736 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Received SetState message: May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.737 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Playing state: May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.737 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Playback position: May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.737 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Queue version: 1.5 May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.737 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Current track: May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.737 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Next track: TID: 334473185, QID: 4, Context UUID: 7412b905-3be4-4f5c-8905-4fd779a99150 May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.738 [11929.11929] INFO MediaEngine: [0x214f3b8]: Setting next track: 334473185 May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.738 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: New stream: 30 May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.738 [11929.11929] INFO HttpDownloader: [0x21e9390]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=dda837d8832ac9346e397a7a64f4d5aa&request_ts=1748180487&track_id=334473185 May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.738 [11929.11929] INFO HttpDownloader: [0x23bc020]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=334473185 May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.738 [11929.11929] INFO HttpDownloader: [0x23bc2c0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=94038861cfcf9d0b813fbae0eacbc283&request_ts=1748180487&track_id=334473185 May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.738 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Received SetState message: May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.738 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Playing state: May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.738 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Playback position: May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.738 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Queue version: 1.5 May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.739 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Current track: May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.739 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Next track: TID: 334473185, QID: 4, Context UUID: 7412b905-3be4-4f5c-8905-4fd779a99150 May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.739 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Received SetState message: May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.739 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Playing state: May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.739 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Playback position: May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.739 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Queue version: 1.5 May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.739 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Current track: May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.739 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Next track: TID: 334473185, QID: 4, Context UUID: 7412b905-3be4-4f5c-8905-4fd779a99150 May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.845 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: [Stream 30]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=334473185&fmt=7&profile=raw&app_id=174516466&cid=2789982&etsp=1748184087&hmac=-_J_0dmJx-WYTz0xMfxpQy2pANQ May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.859 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: [Stream 30]: Metadata became available: May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.859 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: Title: Swiss Timers May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.859 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: Artist: Mogwai May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.859 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: Album: The Bombing of Pan Am 103 May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.859 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: Album art URL: https://static.qobuz.com/images/covers/ub/ax/qlw2bki5xaxub_600.jpg May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.893 [11929.11929] INFO HttpDownloader: [0x214ff68]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart May 25 15:41:27 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:27.950 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: [Stream 30]: stream information have been fetched May 25 15:41:28 volumio-sovrum volumio[969]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Luqa Airport","url":"https://www.last.fm/music/Mogwai/_/Luqa+Airport","duration":"209000","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"1082","playcount":"1574","artist":{"name":"Mogwai","mbid":"d700b3f5-45af-4d02-95ed-57d301bda93e","url":"https://www.last.fm/music/Mogwai"},"album":{"artist":"Mogwai","title":"The Bombing of Pan Am 103","url":"https://www.last.fm/music/Mogwai/The+Bombing+of+Pan+Am+103","image":[{"#":"https://lastfm.freetls.fastly.net/i/u/34s/1fa1a8c81ca335fa4581f7ad6edd5151.png","@":{"size":"small"}},{"#":"https://lastfm.freetls.fastly.net/i/u/64s/1fa1a8c81ca335fa4581f7ad6edd5151.png","@":{"size":"medium"}},{"#":"https://lastfm.freetls.fastly.net/i/u/174s/1fa1a8c81ca335fa4581f7ad6edd5151.png","@":{"size":"large"}},{"#":"https://lastfm.freetls.fastly.net/i/u/300x300/1fa1a8c81ca335fa4581f7ad6edd5151.png","@":{"size":"extralarge"}}]},"userplaycount":"0","userloved":"0","toptags":""}} May 25 15:41:37 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:37.618 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Need to send state update May 25 15:41:37 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:37.618 [11929.11929] INFO QConnectMessageSender: [0x218f520]: Sending StateUpdated message May 25 15:41:37 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:37.618 [11929.11929] INFO QwspMessageSender: [0x2259a88]: Sending Payload message May 25 15:41:37 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:37 volumio-sovrum volumio[969]: info: CoreCommandRouter::servicePushState May 25 15:41:37 volumio-sovrum volumio[969]: info: CoreStateMachine::pushState May 25 15:41:37 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 15:41:37 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioPushState May 25 15:41:37 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output update for this device May 25 15:41:37 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output May 25 15:41:37 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:37 volumio-sovrum volumio[969]: info: MRS: Adding multiroomSync output May 25 15:41:37 volumio-sovrum volumio[969]: info: Adding audio output: May 25 15:41:37 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output May 25 15:41:41 volumio-sovrum volumio[969]: info: MRS: Updating multiroomSync output May 25 15:41:42 volumio-sovrum volumio[969]: info: MRS: Pushing audio output on request May 25 15:41:42 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output May 25 15:41:42 volumio-sovrum volumio[969]: info: MRS: Pushing audio output on request May 25 15:41:42 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output May 25 15:41:44 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 15:41:44 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 15:41:44 volumio-sovrum volumio[969]: info: Discovery: Getting this device information May 25 15:41:44 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:44 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 15:41:46 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:46 volumio-sovrum sudo[14280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 15:41:46 volumio-sovrum sudo[14280]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 15:41:46 volumio-sovrum sudo[14280]: pam_unix(sudo:session): session closed for user root May 25 15:41:46 volumio-sovrum sudo[14283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 15:41:46 volumio-sovrum sudo[14283]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 15:41:46 volumio-sovrum sudo[14283]: pam_unix(sudo:session): session closed for user root May 25 15:41:47 volumio-sovrum volumio[969]: verbose: New Socket.io Connection to 192.168.68.129 from 192.168.68.100 UA: Mozilla/5.0 (Linux; Android 13; FP4 Build/TKQ1.230127.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 25 15:41:47 volumio-sovrum sudo[14286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 15:41:47 volumio-sovrum sudo[14286]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 15:41:47 volumio-sovrum sudo[14286]: pam_unix(sudo:session): session closed for user root May 25 15:41:47 volumio-sovrum sudo[14289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 15:41:47 volumio-sovrum sudo[14289]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 15:41:47 volumio-sovrum sudo[14289]: pam_unix(sudo:session): session closed for user root May 25 15:41:47 volumio-sovrum volumio[969]: verbose: New Socket.io Connection to 192.168.68.129 from 192.168.68.100 UA: Mozilla/5.0 (Linux; Android 13; FP4 Build/TKQ1.230127.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 May 25 15:41:47 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:47.618 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Need to send state update May 25 15:41:47 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:47.618 [11929.11929] INFO QConnectMessageSender: [0x218f520]: Sending StateUpdated message May 25 15:41:47 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:47.618 [11929.11929] INFO QwspMessageSender: [0x2259a88]: Sending Payload message May 25 15:41:47 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:47 volumio-sovrum volumio[969]: info: CoreCommandRouter::servicePushState May 25 15:41:47 volumio-sovrum volumio[969]: info: CoreStateMachine::pushState May 25 15:41:47 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 15:41:47 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioPushState May 25 15:41:47 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output update for this device May 25 15:41:47 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output May 25 15:41:47 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:47 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 25 15:41:47 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetVisibleSources May 25 15:41:47 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 15:41:47 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:47 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 25 15:41:47 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 25 15:41:47 volumio-sovrum volumio[969]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 25 15:41:47 volumio-sovrum volumio[969]: info: Received Get System Info May 25 15:41:47 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 15:41:47 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 15:41:47 volumio-sovrum volumio[969]: info: Discovery: Getting this device information May 25 15:41:47 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:47 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 15:41:47 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:47 volumio-sovrum volumio[969]: info: Listing playlists May 25 15:41:47 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 25 15:41:47 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 15:41:47 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 25 15:41:49 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 25 15:41:49 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 15:41:49 volumio-sovrum volumio[969]: info: Received Get System Info May 25 15:41:49 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 15:41:49 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 15:41:49 volumio-sovrum volumio[969]: info: Discovery: Getting this device information May 25 15:41:49 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:49 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 15:41:49 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetQueue May 25 15:41:49 volumio-sovrum volumio[969]: info: CoreStateMachine::getQueue May 25 15:41:49 volumio-sovrum volumio[969]: info: CorePlayQueue::getQueue May 25 15:41:50 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 15:41:50 volumio-sovrum volumio[969]: info: Received Get System Info May 25 15:41:50 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 15:41:50 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 15:41:50 volumio-sovrum volumio[969]: info: Discovery: Getting this device information May 25 15:41:50 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:50 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 15:41:57 volumio-sovrum volumiologrotate[602]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory May 25 15:41:57 volumio-sovrum volumiologrotate[602]: ls: cannot access 'SOVRUM': No such file or directory May 25 15:41:57 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:57.619 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Need to send state update May 25 15:41:57 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:57.619 [11929.11929] INFO QConnectMessageSender: [0x218f520]: Sending StateUpdated message May 25 15:41:57 volumio-sovrum qobuz-connect[11929]: 20250525 15:41:57.619 [11929.11929] INFO QwspMessageSender: [0x2259a88]: Sending Payload message May 25 15:41:57 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:41:57 volumio-sovrum volumio[969]: info: CoreCommandRouter::servicePushState May 25 15:41:57 volumio-sovrum volumio[969]: info: CoreStateMachine::pushState May 25 15:41:57 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 15:41:57 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioPushState May 25 15:41:57 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output update for this device May 25 15:41:57 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output May 25 15:41:57 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:42:07 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:07.619 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Need to send state update May 25 15:42:07 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:07.619 [11929.11929] INFO QConnectMessageSender: [0x218f520]: Sending StateUpdated message May 25 15:42:07 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:07.619 [11929.11929] INFO QwspMessageSender: [0x2259a88]: Sending Payload message May 25 15:42:07 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:42:07 volumio-sovrum volumio[969]: info: CoreCommandRouter::servicePushState May 25 15:42:07 volumio-sovrum volumio[969]: info: CoreStateMachine::pushState May 25 15:42:07 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 15:42:07 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioPushState May 25 15:42:07 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output update for this device May 25 15:42:07 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output May 25 15:42:07 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.913 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Received SetState message: May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.914 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Playing state: Playing May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.914 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Playback position: 0 May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.914 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Queue version: 2.1 May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.914 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Current track: TID: 35927725, QID: 0, Context UUID: 20f95061-12d4-4810-b2e2-6f2fa3f4ec42 May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.914 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Next track: TID: 248825114, QID: 1, Context UUID: 20f95061-12d4-4810-b2e2-6f2fa3f4ec42 May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.914 [11929.11929] INFO MediaEngine: [0x214f3b8]: Stopping playback, clearing tracks May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.914 [11929.11929] INFO MediaEngine: [0x214f3b8]: Initiating playback May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.914 [11929.11929] INFO AudioRenderer: [0x214f5d8]: Stopping audio renderer May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.914 [11929.11929] INFO SampleApp: Stopping playback May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.925 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: Clearing all streams May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.925 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: [Stream 29]: Disposing of audio stream May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.925 [11929.11929] INFO ContentFetcher: [0x2415070]: Canceling download May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.932 [11929.11929] INFO SampleApp: Playback state changed: Stopped May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.932 [11929.11929] INFO StreamingEventReporter: [0x214ff10]: Reporting streaming ended event May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.933 [11929.11929] INFO HttpDownloader: [0x214ff68]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.933 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Need to send state update May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.933 [11929.11929] INFO QConnectMessageSender: [0x218f520]: Sending StateUpdated message May 25 15:42:13 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:42:13 volumio-sovrum volumio[969]: info: CoreCommandRouter::servicePushState May 25 15:42:13 volumio-sovrum volumio[969]: info: CoreStateMachine::pushState May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.933 [11929.11929] INFO SampleApp: [Stream 29]: Audio stream has been disposed of May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.933 [11929.11929] INFO MediaEngine: [0x214f3b8]: Setting current track: 35927725, initial offset: 0ms May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.933 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: Clearing all streams May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.933 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: New stream: 31 May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.934 [11929.11929] INFO HttpDownloader: [0x21e9420]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=ef578cbd4405f275b3be485b6271a82e&request_ts=1748180533&track_id=35927725 May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.934 [11929.11929] INFO HttpDownloader: [0x21ea810]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=35927725 May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.934 [11929.11929] INFO HttpDownloader: [0x23d2fe8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=88d98899c68e25c20d2f0cedba6f446a&request_ts=1748180533&track_id=35927725 May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.934 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: [Stream 31]: Running audio stream May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.934 [11929.11929] INFO QwspMessageSender: [0x2259a88]: Sending Payload message May 25 15:42:13 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 15:42:13 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioPushState May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.936 [11929.11929] INFO MediaEngine: [0x214f3b8]: Setting next track: 248825114 May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.936 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: New stream: 32 May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.936 [11929.11929] INFO HttpDownloader: [0x21819f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=ee97335aa5a823a9d063920dcf76d5e4&request_ts=1748180533&track_id=248825114 May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.936 [11929.11929] INFO HttpDownloader: [0x2212e90]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=248825114 May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.937 [11929.11929] INFO HttpDownloader: [0x22037e0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=83c0e594acc52c5f0de21fc65f43c4df&request_ts=1748180533&track_id=248825114 May 25 15:42:13 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:13.938 [11929.11929] INFO MediaEngine: [0x214f3b8]: Waiting for current stream to start before starting audio renderer May 25 15:42:13 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output update for this device May 25 15:42:13 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output May 25 15:42:13 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:42:13 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:42:13 volumio-sovrum volumio[969]: info: CoreCommandRouter::servicePushState May 25 15:42:13 volumio-sovrum volumio[969]: info: CoreStateMachine::pushState May 25 15:42:13 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 15:42:13 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioPushState May 25 15:42:13 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output update for this device May 25 15:42:13 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output May 25 15:42:13 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.057 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: [Stream 32]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=248825114&fmt=7&profile=raw&app_id=174516466&cid=2789982&etsp=1748184134&hmac=rI1xJmDP1WnzF5h4FOkm7tVHgW4 May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.061 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: [Stream 31]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=35927725&fmt=6&profile=raw&app_id=174516466&cid=2789982&etsp=1748184134&hmac=pvtbR5qfD2gmvH0AhsAcbnf8eds May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.081 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: [Stream 31]: stream information have been fetched May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.081 [11929.11929] INFO UrlAudioSource: [0x23ef188]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=35927725&fmt=6&profile=raw&app_id=174516466&cid=2789982&etsp=1748184134&hmac=pvtbR5qfD2gmvH0AhsAcbnf8eds May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.081 [11929.11929] INFO ContentFetcher: [0x248ebe8]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=35927725&fmt=6&profile=raw&app_id=174516466&cid=2789982&etsp=1748184134&hmac=pvtbR5qfD2gmvH0AhsAcbnf8eds, offset: 0 May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.081 [11929.11929] INFO AudioRenderer: [0x214f5d8]: Starting audio renderer, initial playback state: Playing May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.081 [11929.11929] INFO SampleApp: [Stream 31]: New audio stream (starting from 0ms) May 25 15:42:14 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:42:14 volumio-sovrum volumio[969]: info: QobuzConnect: alread currrent service, send playback confirmation May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.083 [11929.11929] INFO SampleApp: Initiating playback, initial state: Playing May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.086 [11929.11929] INFO SampleApp: Playback state changed: Playing May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.087 [11929.11929] INFO StreamingEventReporter: [0x214ff10]: Reporting streaming started event May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.087 [11929.11929] INFO ProtocolHandler: [0x214faf8]: Need to send state update May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.087 [11929.11929] INFO QConnectMessageSender: [0x218f520]: Sending StateUpdated message May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.087 [11929.11929] INFO QwspMessageSender: [0x2259a88]: Sending Payload message May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.087 [11929.11929] INFO QConnectMessageSender: [0x218f520]: Sending FileAudioQualityChanged message May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.087 [11929.11929] INFO QwspMessageSender: [0x2259a88]: Sending Payload message May 25 15:42:14 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:42:14 volumio-sovrum volumio[969]: info: CoreCommandRouter::servicePushState May 25 15:42:14 volumio-sovrum volumio[969]: info: CoreStateMachine::pushState May 25 15:42:14 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.087 [11929.11929] INFO QConnectMessageSender: [0x218f520]: Sending DeviceAudioQualityChanged message May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.088 [11929.11929] INFO QwspMessageSender: [0x2259a88]: Sending Payload message May 25 15:42:14 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioPushState May 25 15:42:14 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output update for this device May 25 15:42:14 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output May 25 15:42:14 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:42:14 volumio-sovrum volumio[969]: info: CoreCommandRouter::servicePushState May 25 15:42:14 volumio-sovrum volumio[969]: info: CoreStateMachine::pushState May 25 15:42:14 volumio-sovrum volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 15:42:14 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioPushState May 25 15:42:14 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output update for this device May 25 15:42:14 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output May 25 15:42:14 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:42:14 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:42:14 volumio-sovrum volumio[969]: info: CoreCommandRouter::servicePushState May 25 15:42:14 volumio-sovrum volumio[969]: info: CoreStateMachine::pushState May 25 15:42:14 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioPushState May 25 15:42:14 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output update for this device May 25 15:42:14 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output May 25 15:42:14 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:42:14 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:42:14 volumio-sovrum volumio[969]: info: CoreCommandRouter::servicePushState May 25 15:42:14 volumio-sovrum volumio[969]: info: CoreStateMachine::pushState May 25 15:42:14 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioPushState May 25 15:42:14 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output update for this device May 25 15:42:14 volumio-sovrum volumio[969]: info: MRS: Pushing multiroomSync output May 25 15:42:14 volumio-sovrum volumio[969]: info: CoreCommandRouter::volumioGetState May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.124 [11929.11929] INFO HttpDownloader: [0x214ff68]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.131 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: [Stream 31]: Metadata became available: May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.131 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: Title: Lost on You May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.131 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: Artist: LP May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.131 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: Album: Lost On You May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.131 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: Album art URL: https://static.qobuz.com/images/covers/47/61/4050538256147_600.jpg May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.131 [11929.11929] INFO SampleApp: [Stream 31]: Stream metadata became available: May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.131 [11929.11929] INFO SampleApp: Title: Lost on You May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.131 [11929.11929] INFO SampleApp: Artist: LP May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.131 [11929.11929] INFO SampleApp: Album: Lost On You May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.131 [11929.11929] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/47/61/4050538256147_600.jpg May 25 15:42:14 volumio-sovrum volumio[969]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! May 25 15:42:14 volumio-sovrum volumio[969]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 15:42:14 volumio-sovrum volumio[969]: TypeError: Cannot read property 'indexOf' of undefined May 25 15:42:14 volumio-sovrum volumio[969]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) May 25 15:42:14 volumio-sovrum volumio[969]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) May 25 15:42:14 volumio-sovrum volumio[969]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) May 25 15:42:14 volumio-sovrum volumio[969]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 25 15:42:14 volumio-sovrum volumio[969]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) May 25 15:42:14 volumio-sovrum volumio[969]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) May 25 15:42:14 volumio-sovrum volumio[969]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 25 15:42:14 volumio-sovrum volumio[969]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 25 15:42:14 volumio-sovrum volumio[969]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) May 25 15:42:14 volumio-sovrum volumio[969]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 25 15:42:14 volumio-sovrum volumio[969]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) May 25 15:42:14 volumio-sovrum volumio[969]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) May 25 15:42:14 volumio-sovrum volumio[969]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) May 25 15:42:14 volumio-sovrum volumio[969]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 25 15:42:14 volumio-sovrum volumio[969]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 25 15:42:14 volumio-sovrum volumio[969]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) May 25 15:42:14 volumio-sovrum volumio[969]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.158 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: [Stream 32]: stream information have been fetched May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.166 [11929.11929] INFO UrlAudioSource: [0x23ef188]: Content info extracted May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.166 [11929.11929] INFO UrlAudioSource: [0x23ef188]: Size: 30313436 May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.170 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: [Stream 32]: Metadata became available: May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.170 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: Title: Armor May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.170 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: Artist: Iniko May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.170 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: Album: Armor May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.170 [11929.11929] INFO AudioStreamManager: [0x214f4a0]: Album art URL: https://static.qobuz.com/images/covers/kb/ta/dd6urka16takb_600.jpg May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.177 [11929.14370] INFO AudioDecoder: [0x222ad80]: Stream info: May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.177 [11929.14370] INFO AudioDecoder: [0x222ad80]: Total samples: 11823441 May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.177 [11929.14370] INFO AudioDecoder: [0x222ad80]: Sample rate: 44100 May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.177 [11929.14370] INFO AudioDecoder: [0x222ad80]: Channel count: 2 May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.177 [11929.14370] INFO AudioDecoder: [0x222ad80]: Bits per sample: 16 May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.177 [11929.14370] INFO AudioDecoder: [0x222ad80]: Duration: 268105 May 25 15:42:14 volumio-sovrum qobuz-connect[11929]: 20250525 15:42:14.178 [11929.11929] INFO UrlAudioSource: [0x23ef188]: Audio properties have changed May 25 15:42:14 volumio-sovrum sudo[14379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-25 15:41 May 25 15:42:14 volumio-sovrum sudo[14379]: 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"