-- Logs begin at Sat 2025-05-10 16:09:28 CEST, end at Tue 2025-05-13 12:50:10 CEST. -- May 13 12:49:06 leporello qobuz-connect[27382]: 20250513 12:49:06.086 [27382.27382] INFO ProtocolHandler: [0xab349b00]: Need to send state update May 13 12:49:06 leporello qobuz-connect[27382]: 20250513 12:49:06.086 [27382.27382] INFO QConnectMessageSender: [0xab446730]: Sending StateUpdated message May 13 12:49:06 leporello qobuz-connect[27382]: 20250513 12:49:06.086 [27382.27382] INFO QwspMessageSender: [0xab446720]: Sending Payload message May 13 12:49:06 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:49:06 leporello volumio[3286]: info: CoreCommandRouter::servicePushState May 13 12:49:06 leporello volumio[3286]: info: CoreStateMachine::pushState May 13 12:49:06 leporello volumio[3286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 12:49:06 leporello volumio[3286]: info: CoreCommandRouter::volumioPushState May 13 12:49:06 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:49:06 leporello volumio[3286]: info: Signalling Playback active due to playback status change May 13 12:49:10 leporello volumio[3286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 12:49:10 leporello volumio[3286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 12:49:10 leporello volumio[3286]: info: Discovery: Getting this device information May 13 12:49:10 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:49:10 leporello volumio[3286]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 12:49:16 leporello qobuz-connect[27382]: 20250513 12:49:16.086 [27382.27382] INFO ProtocolHandler: [0xab349b00]: Need to send state update May 13 12:49:16 leporello qobuz-connect[27382]: 20250513 12:49:16.086 [27382.27382] INFO QConnectMessageSender: [0xab446730]: Sending StateUpdated message May 13 12:49:16 leporello qobuz-connect[27382]: 20250513 12:49:16.086 [27382.27382] INFO QwspMessageSender: [0xab446720]: Sending Payload message May 13 12:49:16 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:49:16 leporello volumio[3286]: info: CoreCommandRouter::servicePushState May 13 12:49:16 leporello volumio[3286]: info: CoreStateMachine::pushState May 13 12:49:16 leporello volumio[3286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 12:49:16 leporello volumio[3286]: info: CoreCommandRouter::volumioPushState May 13 12:49:16 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:49:16 leporello volumio[3286]: info: Signalling Playback active due to playback status change May 13 12:49:18 leporello wpa_supplicant[3186]: wlan0: WPA: Group rekeying completed with dc:39:6f:19:75:ca [GTK=CCMP] May 13 12:49:26 leporello qobuz-connect[27382]: 20250513 12:49:26.086 [27382.27382] INFO ProtocolHandler: [0xab349b00]: Need to send state update May 13 12:49:26 leporello qobuz-connect[27382]: 20250513 12:49:26.087 [27382.27382] INFO QConnectMessageSender: [0xab446730]: Sending StateUpdated message May 13 12:49:26 leporello qobuz-connect[27382]: 20250513 12:49:26.087 [27382.27382] INFO QwspMessageSender: [0xab446720]: Sending Payload message May 13 12:49:26 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:49:26 leporello volumio[3286]: info: CoreCommandRouter::servicePushState May 13 12:49:26 leporello volumio[3286]: info: CoreStateMachine::pushState May 13 12:49:26 leporello volumio[3286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 12:49:26 leporello volumio[3286]: info: CoreCommandRouter::volumioPushState May 13 12:49:26 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:49:26 leporello volumio[3286]: info: Signalling Playback active due to playback status change May 13 12:49:36 leporello qobuz-connect[27382]: 20250513 12:49:36.087 [27382.27382] INFO ProtocolHandler: [0xab349b00]: Need to send state update May 13 12:49:36 leporello qobuz-connect[27382]: 20250513 12:49:36.087 [27382.27382] INFO QConnectMessageSender: [0xab446730]: Sending StateUpdated message May 13 12:49:36 leporello qobuz-connect[27382]: 20250513 12:49:36.087 [27382.27382] INFO QwspMessageSender: [0xab446720]: Sending Payload message May 13 12:49:36 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:49:36 leporello volumio[3286]: info: CoreCommandRouter::servicePushState May 13 12:49:36 leporello volumio[3286]: info: CoreStateMachine::pushState May 13 12:49:36 leporello volumio[3286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 12:49:36 leporello volumio[3286]: info: CoreCommandRouter::volumioPushState May 13 12:49:36 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:49:36 leporello volumio[3286]: info: Signalling Playback active due to playback status change May 13 12:49:40 leporello volumio[3286]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 12:49:40 leporello volumio[3286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 12:49:40 leporello volumio[3286]: info: Discovery: Getting this device information May 13 12:49:40 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:49:40 leporello volumio[3286]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 12:49:46 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:49:46 leporello volumio[3286]: info: CoreCommandRouter::servicePushState May 13 12:49:46 leporello volumio[3286]: info: CoreStateMachine::pushState May 13 12:49:46 leporello volumio[3286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 12:49:46 leporello volumio[3286]: info: CoreCommandRouter::volumioPushState May 13 12:49:46 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:49:46 leporello volumio[3286]: info: Signalling Playback active due to playback status change May 13 12:49:46 leporello qobuz-connect[27382]: 20250513 12:49:46.098 [27382.27382] INFO ProtocolHandler: [0xab349b00]: Need to send state update May 13 12:49:46 leporello qobuz-connect[27382]: 20250513 12:49:46.099 [27382.27382] INFO QConnectMessageSender: [0xab446730]: Sending StateUpdated message May 13 12:49:46 leporello qobuz-connect[27382]: 20250513 12:49:46.099 [27382.27382] INFO QwspMessageSender: [0xab446720]: Sending Payload message May 13 12:49:56 leporello qobuz-connect[27382]: 20250513 12:49:56.087 [27382.27382] INFO ProtocolHandler: [0xab349b00]: Need to send state update May 13 12:49:56 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:49:56 leporello volumio[3286]: info: CoreCommandRouter::servicePushState May 13 12:49:56 leporello volumio[3286]: info: CoreStateMachine::pushState May 13 12:49:56 leporello volumio[3286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 12:49:56 leporello volumio[3286]: info: CoreCommandRouter::volumioPushState May 13 12:49:56 leporello qobuz-connect[27382]: 20250513 12:49:56.091 [27382.27382] INFO QConnectMessageSender: [0xab446730]: Sending StateUpdated message May 13 12:49:56 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:49:56 leporello qobuz-connect[27382]: 20250513 12:49:56.092 [27382.27382] INFO QwspMessageSender: [0xab446720]: Sending Payload message May 13 12:49:56 leporello volumio[3286]: info: Signalling Playback active due to playback status change May 13 12:50:06 leporello qobuz-connect[27382]: 20250513 12:50:06.087 [27382.27382] INFO ProtocolHandler: [0xab349b00]: Need to send state update May 13 12:50:06 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:50:06 leporello volumio[3286]: info: CoreCommandRouter::servicePushState May 13 12:50:06 leporello volumio[3286]: info: CoreStateMachine::pushState May 13 12:50:06 leporello volumio[3286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 12:50:06 leporello volumio[3286]: info: CoreCommandRouter::volumioPushState May 13 12:50:06 leporello qobuz-connect[27382]: 20250513 12:50:06.092 [27382.27382] INFO QConnectMessageSender: [0xab446730]: Sending StateUpdated message May 13 12:50:06 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:50:06 leporello qobuz-connect[27382]: 20250513 12:50:06.093 [27382.27382] INFO QwspMessageSender: [0xab446720]: Sending Payload message May 13 12:50:06 leporello volumio[3286]: info: Signalling Playback active due to playback status change May 13 12:50:08 leporello qobuz-connect[27382]: 20250513 12:50:08.989 [27382.27382] INFO ProtocolHandler: [0xab349b00]: Received SetState message: May 13 12:50:08 leporello qobuz-connect[27382]: 20250513 12:50:08.991 [27382.27382] INFO ProtocolHandler: [0xab349b00]: Playing state: Playing May 13 12:50:08 leporello qobuz-connect[27382]: 20250513 12:50:08.992 [27382.27382] INFO ProtocolHandler: [0xab349b00]: Playback position: 0 May 13 12:50:08 leporello qobuz-connect[27382]: 20250513 12:50:08.992 [27382.27382] INFO ProtocolHandler: [0xab349b00]: Queue version: 4.1 May 13 12:50:08 leporello qobuz-connect[27382]: 20250513 12:50:08.993 [27382.27382] INFO ProtocolHandler: [0xab349b00]: Current track: TID: 4246520, QID: 0, Context UUID: 77ddb353-8a19-4665-a961-022969d9c7e3 May 13 12:50:08 leporello qobuz-connect[27382]: 20250513 12:50:08.993 [27382.27382] INFO ProtocolHandler: [0xab349b00]: Next track: TID: 4246521, QID: 1, Context UUID: 77ddb353-8a19-4665-a961-022969d9c7e3 May 13 12:50:08 leporello qobuz-connect[27382]: 20250513 12:50:08.993 [27382.27382] INFO MediaEngine: [0xab3493c0]: Stopping playback, clearing tracks May 13 12:50:08 leporello qobuz-connect[27382]: 20250513 12:50:08.994 [27382.27382] INFO MediaEngine: [0xab3493c0]: Initiating playback May 13 12:50:08 leporello qobuz-connect[27382]: 20250513 12:50:08.994 [27382.27382] INFO AudioRenderer: [0xab3495e0]: Stopping audio renderer May 13 12:50:08 leporello qobuz-connect[27382]: 20250513 12:50:08.994 [27382.27382] INFO SampleApp: Stopping playback May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.009 [27382.27382] INFO AudioStreamManager: [0xab3494a8]: Clearing all streams May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.010 [27382.27382] INFO AudioStreamManager: [0xab3494a8]: [Stream 37]: Disposing of audio stream May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.011 [27382.27382] INFO ContentFetcher: [0xab6f0f58]: Canceling download May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.019 [27382.27382] INFO SampleApp: Playback state changed: Stopped May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.020 [27382.27382] INFO StreamingEventReporter: [0xab349f18]: Reporting streaming ended event May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::servicePushState May 13 12:50:09 leporello volumio[3286]: info: CoreStateMachine::pushState May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::volumioPushState May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.020 [27382.27382] INFO HttpDownloader: [0xab349f70]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.024 [27382.27382] INFO ProtocolHandler: [0xab349b00]: Need to send state update May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.025 [27382.27382] INFO QConnectMessageSender: [0xab446730]: Sending StateUpdated message May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.025 [27382.27382] INFO SampleApp: [Stream 37]: Audio stream has been disposed of May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.025 [27382.27382] INFO MediaEngine: [0xab3493c0]: Setting current track: 4246520, initial offset: 0ms May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.025 [27382.27382] INFO AudioStreamManager: [0xab3494a8]: Clearing all streams May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.025 [27382.27382] INFO AudioStreamManager: [0xab3494a8]: New stream: 39 May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.025 [27382.27382] INFO HttpDownloader: [0xab531760]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=02e2d090488726d95924e9bd11e7439e&request_ts=1747133409&track_id=4246520 May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.025 [27382.27382] INFO HttpDownloader: [0xab35c908]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=4246520 May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.025 [27382.27382] INFO HttpDownloader: [0xab76b970]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=ebcb88769cc0cfccac2e28299d4343a4&request_ts=1747133409&track_id=4246520 May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.026 [27382.27382] INFO AudioStreamManager: [0xab3494a8]: [Stream 39]: Running audio stream May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.026 [27382.27382] INFO QwspMessageSender: [0xab446720]: Sending Payload message May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::servicePushState May 13 12:50:09 leporello volumio[3286]: info: CoreStateMachine::pushState May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::volumioPushState May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.075 [27382.27382] INFO MediaEngine: [0xab3493c0]: Setting next track: 4246521 May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.075 [27382.27382] INFO AudioStreamManager: [0xab3494a8]: New stream: 40 May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.075 [27382.27382] INFO HttpDownloader: [0xab743250]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=b4eb75d78a61c5ec7e56712faa806b98&request_ts=1747133409&track_id=4246521 May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.075 [27382.27382] INFO HttpDownloader: [0xab4398c8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=4246521 May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.075 [27382.27382] INFO HttpDownloader: [0xab436cd0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=a7965cacafc660e2e42f03cda0ed33f5&request_ts=1747133409&track_id=4246521 May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.076 [27382.27382] INFO MediaEngine: [0xab3493c0]: Waiting for current stream to start before starting audio renderer May 13 12:50:09 leporello volumio[3286]: info: MCU Signalled Playback Inactive May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.623 [27382.27382] INFO AudioStreamManager: [0xab3494a8]: [Stream 39]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=553292&eid=4246520&fmt=6&profile=raw&app_id=174516466&cid=1234969&etsp=1747137009&hmac=I03TDG9Bbm9R_fW2ZgBpSUDXJQw May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.694 [27382.27382] INFO AudioStreamManager: [0xab3494a8]: [Stream 40]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=553292&eid=4246521&fmt=6&profile=raw&app_id=174516466&cid=1234969&etsp=1747137009&hmac=4V-2lchY9MBc4GW4BQJTuaZnYgg May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.734 [27382.27382] INFO AudioStreamManager: [0xab3494a8]: [Stream 39]: stream information have been fetched May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.734 [27382.27382] INFO UrlAudioSource: [0xab7efbc0]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=553292&eid=4246520&fmt=6&profile=raw&app_id=174516466&cid=1234969&etsp=1747137009&hmac=I03TDG9Bbm9R_fW2ZgBpSUDXJQw May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.734 [27382.27382] INFO ContentFetcher: [0xab6d0300]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=553292&eid=4246520&fmt=6&profile=raw&app_id=174516466&cid=1234969&etsp=1747137009&hmac=I03TDG9Bbm9R_fW2ZgBpSUDXJQw, offset: 0 May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.735 [27382.27382] INFO AudioRenderer: [0xab3495e0]: Starting audio renderer, initial playback state: Playing May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.735 [27382.27382] INFO SampleApp: [Stream 39]: New audio stream (starting from 0ms) May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:50:09 leporello volumio[3286]: info: QobuzConnect: alread currrent service, send playback confirmation May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.737 [27382.27382] INFO SampleApp: Initiating playback, initial state: Playing May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.746 [27382.27382] INFO SampleApp: Playback state changed: Playing May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.747 [27382.27382] INFO StreamingEventReporter: [0xab349f18]: Reporting streaming started event May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.747 [27382.27382] INFO HttpDownloader: [0xab349f70]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.747 [27382.27382] INFO ProtocolHandler: [0xab349b00]: Need to send state update May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.747 [27382.27382] INFO QConnectMessageSender: [0xab446730]: Sending StateUpdated message May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.747 [27382.27382] INFO QwspMessageSender: [0xab446720]: Sending Payload message May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.747 [27382.27382] INFO QConnectMessageSender: [0xab446730]: Sending FileAudioQualityChanged message May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::servicePushState May 13 12:50:09 leporello volumio[3286]: info: CoreStateMachine::pushState May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.748 [27382.27382] INFO QwspMessageSender: [0xab446720]: Sending Payload message May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.749 [27382.27382] INFO QConnectMessageSender: [0xab446730]: Sending DeviceAudioQualityChanged message May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.749 [27382.27382] INFO QwspMessageSender: [0xab446720]: Sending Payload message May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::volumioPushState May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.757 [27382.27382] INFO AudioStreamManager: [0xab3494a8]: [Stream 40]: Metadata became available: May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.757 [27382.27382] INFO AudioStreamManager: [0xab3494a8]: Title: Vozes (Saudades) May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.757 [27382.27382] INFO AudioStreamManager: [0xab3494a8]: Artist: Nana Vasconcelos May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.757 [27382.27382] INFO AudioStreamManager: [0xab3494a8]: Album: Saudades May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.757 [27382.27382] INFO AudioStreamManager: [0xab3494a8]: Album art URL: https://static.qobuz.com/images/covers/02/38/0004228293802_600.jpg May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::servicePushState May 13 12:50:09 leporello volumio[3286]: info: CoreStateMachine::pushState May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::volumioPushState May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::servicePushState May 13 12:50:09 leporello volumio[3286]: info: CoreStateMachine::pushState May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::volumioPushState May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::servicePushState May 13 12:50:09 leporello volumio[3286]: info: CoreStateMachine::pushState May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::volumioPushState May 13 12:50:09 leporello volumio[3286]: info: CoreCommandRouter::volumioGetState May 13 12:50:09 leporello volumio[3286]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! May 13 12:50:09 leporello volumio[3286]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 12:50:09 leporello volumio[3286]: TypeError: Cannot read property 'indexOf' of undefined May 13 12:50:09 leporello volumio[3286]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) May 13 12:50:09 leporello volumio[3286]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) May 13 12:50:09 leporello volumio[3286]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) May 13 12:50:09 leporello volumio[3286]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 13 12:50:09 leporello volumio[3286]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) May 13 12:50:09 leporello volumio[3286]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) May 13 12:50:09 leporello volumio[3286]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 13 12:50:09 leporello volumio[3286]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 13 12:50:09 leporello volumio[3286]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) May 13 12:50:09 leporello volumio[3286]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 13 12:50:09 leporello volumio[3286]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) May 13 12:50:09 leporello volumio[3286]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) May 13 12:50:09 leporello volumio[3286]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) May 13 12:50:09 leporello volumio[3286]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 13 12:50:09 leporello volumio[3286]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 13 12:50:09 leporello volumio[3286]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) May 13 12:50:09 leporello volumio[3286]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.791 [27382.27382] INFO AudioStreamManager: [0xab3494a8]: [Stream 39]: Metadata became available: May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.792 [27382.27382] INFO AudioStreamManager: [0xab3494a8]: Title: O Berimbau May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.792 [27382.27382] INFO AudioStreamManager: [0xab3494a8]: Artist: Nana Vasconcelos May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.792 [27382.27382] INFO AudioStreamManager: [0xab3494a8]: Album: Saudades May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.792 [27382.27382] INFO AudioStreamManager: [0xab3494a8]: Album art URL: https://static.qobuz.com/images/covers/02/38/0004228293802_600.jpg May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.792 [27382.27382] INFO SampleApp: [Stream 39]: Stream metadata became available: May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.792 [27382.27382] INFO SampleApp: Title: O Berimbau May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.792 [27382.27382] INFO SampleApp: Artist: Nana Vasconcelos May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.792 [27382.27382] INFO SampleApp: Album: Saudades May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.792 [27382.27382] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/02/38/0004228293802_600.jpg May 13 12:50:09 leporello qobuz-connect[27382]: 20250513 12:50:09.910 [27382.27382] INFO AudioStreamManager: [0xab3494a8]: [Stream 40]: stream information have been fetched May 13 12:50:10 leporello qobuz-connect[27382]: 20250513 12:50:10.322 [27382.27382] INFO UrlAudioSource: [0xab7efbc0]: Content info extracted May 13 12:50:10 leporello qobuz-connect[27382]: 20250513 12:50:10.322 [27382.27382] INFO UrlAudioSource: [0xab7efbc0]: Size: 112096789 May 13 12:50:10 leporello qobuz-connect[27382]: 20250513 12:50:10.323 [27382.21669] INFO AudioDecoder: [0xaba75a40]: Stream info: May 13 12:50:10 leporello qobuz-connect[27382]: 20250513 12:50:10.323 [27382.21669] INFO AudioDecoder: [0xaba75a40]: Total samples: 50174040 May 13 12:50:10 leporello qobuz-connect[27382]: 20250513 12:50:10.323 [27382.21669] INFO AudioDecoder: [0xaba75a40]: Sample rate: 44100 May 13 12:50:10 leporello qobuz-connect[27382]: 20250513 12:50:10.323 [27382.21669] INFO AudioDecoder: [0xaba75a40]: Channel count: 2 May 13 12:50:10 leporello qobuz-connect[27382]: 20250513 12:50:10.323 [27382.21669] INFO AudioDecoder: [0xaba75a40]: Bits per sample: 16 May 13 12:50:10 leporello qobuz-connect[27382]: 20250513 12:50:10.323 [27382.21669] INFO AudioDecoder: [0xaba75a40]: Duration: 1137733 May 13 12:50:10 leporello qobuz-connect[27382]: 20250513 12:50:10.323 [27382.27382] INFO UrlAudioSource: [0xab7efbc0]: Audio properties have changed May 13 12:50:10 leporello sudo[21671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-13 12:49 May 13 12:50:10 leporello sudo[21671]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 12:35:56 PM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="ea561adf1e965df757744ffb8ecc9b8a"