-- Logs begin at Thu 2019-02-14 13:11:59 MSK, end at Wed 2025-09-17 20:56:35 MSK. -- Sep 17 20:55:00 volumio qobuz-connect[22854]: 20250917 20:55:00.222 [22854.22854] INFO ProtocolHandler: [0xc52a00]: Need to send state update Sep 17 20:55:00 volumio qobuz-connect[22854]: 20250917 20:55:00.222 [22854.22854] INFO QConnectMessageSender: [0xd73a80]: Sending StateUpdated message Sep 17 20:55:00 volumio qobuz-connect[22854]: 20250917 20:55:00.222 [22854.22854] INFO QwspMessageSender: [0xd73f70]: Sending Payload message Sep 17 20:55:00 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:55:00 volumio volumio[981]: info: CoreCommandRouter::servicePushState Sep 17 20:55:00 volumio volumio[981]: info: CoreStateMachine::pushState Sep 17 20:55:00 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 20:55:00 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Sep 17 20:55:00 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Sep 17 20:55:00 volumio volumio[981]: info: MRS: Pushing multiroomSync output Sep 17 20:55:00 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:55:10 volumio qobuz-connect[22854]: 20250917 20:55:10.222 [22854.22854] INFO ProtocolHandler: [0xc52a00]: Need to send state update Sep 17 20:55:10 volumio qobuz-connect[22854]: 20250917 20:55:10.222 [22854.22854] INFO QConnectMessageSender: [0xd73a80]: Sending StateUpdated message Sep 17 20:55:10 volumio qobuz-connect[22854]: 20250917 20:55:10.222 [22854.22854] INFO QwspMessageSender: [0xd73f70]: Sending Payload message Sep 17 20:55:10 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:55:10 volumio volumio[981]: info: CoreCommandRouter::servicePushState Sep 17 20:55:10 volumio volumio[981]: info: CoreStateMachine::pushState Sep 17 20:55:10 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 20:55:10 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Sep 17 20:55:10 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Sep 17 20:55:10 volumio volumio[981]: info: MRS: Pushing multiroomSync output Sep 17 20:55:10 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:55:20 volumio qobuz-connect[22854]: 20250917 20:55:20.223 [22854.22854] INFO ProtocolHandler: [0xc52a00]: Need to send state update Sep 17 20:55:20 volumio qobuz-connect[22854]: 20250917 20:55:20.223 [22854.22854] INFO QConnectMessageSender: [0xd73a80]: Sending StateUpdated message Sep 17 20:55:20 volumio qobuz-connect[22854]: 20250917 20:55:20.223 [22854.22854] INFO QwspMessageSender: [0xd73f70]: Sending Payload message Sep 17 20:55:20 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:55:20 volumio volumio[981]: info: CoreCommandRouter::servicePushState Sep 17 20:55:20 volumio volumio[981]: info: CoreStateMachine::pushState Sep 17 20:55:20 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 20:55:20 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Sep 17 20:55:20 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Sep 17 20:55:20 volumio volumio[981]: info: MRS: Pushing multiroomSync output Sep 17 20:55:20 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:55:30 volumio qobuz-connect[22854]: 20250917 20:55:30.223 [22854.22854] INFO ProtocolHandler: [0xc52a00]: Need to send state update Sep 17 20:55:30 volumio qobuz-connect[22854]: 20250917 20:55:30.224 [22854.22854] INFO QConnectMessageSender: [0xd73a80]: Sending StateUpdated message Sep 17 20:55:30 volumio qobuz-connect[22854]: 20250917 20:55:30.224 [22854.22854] INFO QwspMessageSender: [0xd73f70]: Sending Payload message Sep 17 20:55:30 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:55:30 volumio volumio[981]: info: CoreCommandRouter::servicePushState Sep 17 20:55:30 volumio volumio[981]: info: CoreStateMachine::pushState Sep 17 20:55:30 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 20:55:30 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Sep 17 20:55:30 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Sep 17 20:55:30 volumio volumio[981]: info: MRS: Pushing multiroomSync output Sep 17 20:55:30 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:55:40 volumio qobuz-connect[22854]: 20250917 20:55:40.224 [22854.22854] INFO ProtocolHandler: [0xc52a00]: Need to send state update Sep 17 20:55:40 volumio qobuz-connect[22854]: 20250917 20:55:40.224 [22854.22854] INFO QConnectMessageSender: [0xd73a80]: Sending StateUpdated message Sep 17 20:55:40 volumio qobuz-connect[22854]: 20250917 20:55:40.224 [22854.22854] INFO QwspMessageSender: [0xd73f70]: Sending Payload message Sep 17 20:55:40 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:55:40 volumio volumio[981]: info: CoreCommandRouter::servicePushState Sep 17 20:55:40 volumio volumio[981]: info: CoreStateMachine::pushState Sep 17 20:55:40 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 20:55:40 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Sep 17 20:55:40 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Sep 17 20:55:40 volumio volumio[981]: info: MRS: Pushing multiroomSync output Sep 17 20:55:40 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:55:50 volumio qobuz-connect[22854]: 20250917 20:55:50.225 [22854.22854] INFO ProtocolHandler: [0xc52a00]: Need to send state update Sep 17 20:55:50 volumio qobuz-connect[22854]: 20250917 20:55:50.225 [22854.22854] INFO QConnectMessageSender: [0xd73a80]: Sending StateUpdated message Sep 17 20:55:50 volumio qobuz-connect[22854]: 20250917 20:55:50.225 [22854.22854] INFO QwspMessageSender: [0xd73f70]: Sending Payload message Sep 17 20:55:50 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:55:50 volumio volumio[981]: info: CoreCommandRouter::servicePushState Sep 17 20:55:50 volumio volumio[981]: info: CoreStateMachine::pushState Sep 17 20:55:50 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 20:55:50 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Sep 17 20:55:50 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Sep 17 20:55:50 volumio volumio[981]: info: MRS: Pushing multiroomSync output Sep 17 20:55:50 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:55:54 volumio volumio[981]: error: [LastFM] plugin is not authenticated, please retry Sep 17 20:56:00 volumio qobuz-connect[22854]: 20250917 20:56:00.226 [22854.22854] INFO ProtocolHandler: [0xc52a00]: Need to send state update Sep 17 20:56:00 volumio qobuz-connect[22854]: 20250917 20:56:00.226 [22854.22854] INFO QConnectMessageSender: [0xd73a80]: Sending StateUpdated message Sep 17 20:56:00 volumio qobuz-connect[22854]: 20250917 20:56:00.226 [22854.22854] INFO QwspMessageSender: [0xd73f70]: Sending Payload message Sep 17 20:56:00 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:56:00 volumio volumio[981]: info: CoreCommandRouter::servicePushState Sep 17 20:56:00 volumio volumio[981]: info: CoreStateMachine::pushState Sep 17 20:56:00 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 20:56:00 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Sep 17 20:56:00 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Sep 17 20:56:00 volumio volumio[981]: info: MRS: Pushing multiroomSync output Sep 17 20:56:00 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:56:10 volumio qobuz-connect[22854]: 20250917 20:56:10.226 [22854.22854] INFO ProtocolHandler: [0xc52a00]: Need to send state update Sep 17 20:56:10 volumio qobuz-connect[22854]: 20250917 20:56:10.226 [22854.22854] INFO QConnectMessageSender: [0xd73a80]: Sending StateUpdated message Sep 17 20:56:10 volumio qobuz-connect[22854]: 20250917 20:56:10.226 [22854.22854] INFO QwspMessageSender: [0xd73f70]: Sending Payload message Sep 17 20:56:10 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:56:10 volumio volumio[981]: info: CoreCommandRouter::servicePushState Sep 17 20:56:10 volumio volumio[981]: info: CoreStateMachine::pushState Sep 17 20:56:10 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 20:56:10 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Sep 17 20:56:10 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Sep 17 20:56:10 volumio volumio[981]: info: MRS: Pushing multiroomSync output Sep 17 20:56:10 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:56:20 volumio qobuz-connect[22854]: 20250917 20:56:20.227 [22854.22854] INFO ProtocolHandler: [0xc52a00]: Need to send state update Sep 17 20:56:20 volumio qobuz-connect[22854]: 20250917 20:56:20.227 [22854.22854] INFO QConnectMessageSender: [0xd73a80]: Sending StateUpdated message Sep 17 20:56:20 volumio qobuz-connect[22854]: 20250917 20:56:20.227 [22854.22854] INFO QwspMessageSender: [0xd73f70]: Sending Payload message Sep 17 20:56:20 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:56:20 volumio volumio[981]: info: CoreCommandRouter::servicePushState Sep 17 20:56:20 volumio volumio[981]: info: CoreStateMachine::pushState Sep 17 20:56:20 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 20:56:20 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Sep 17 20:56:20 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Sep 17 20:56:20 volumio volumio[981]: info: MRS: Pushing multiroomSync output Sep 17 20:56:20 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:56:30 volumio qobuz-connect[22854]: 20250917 20:56:30.228 [22854.22854] INFO ProtocolHandler: [0xc52a00]: Need to send state update Sep 17 20:56:30 volumio qobuz-connect[22854]: 20250917 20:56:30.228 [22854.22854] INFO QConnectMessageSender: [0xd73a80]: Sending StateUpdated message Sep 17 20:56:30 volumio qobuz-connect[22854]: 20250917 20:56:30.228 [22854.22854] INFO QwspMessageSender: [0xd73f70]: Sending Payload message Sep 17 20:56:30 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:56:30 volumio volumio[981]: info: CoreCommandRouter::servicePushState Sep 17 20:56:30 volumio volumio[981]: info: CoreStateMachine::pushState Sep 17 20:56:30 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 20:56:30 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Sep 17 20:56:30 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Sep 17 20:56:30 volumio volumio[981]: info: MRS: Pushing multiroomSync output Sep 17 20:56:30 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.796 [22854.22854] INFO ProtocolHandler: [0xc52a00]: Received SetState message: Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.797 [22854.22854] INFO ProtocolHandler: [0xc52a00]: Playing state: Playing Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.797 [22854.22854] INFO ProtocolHandler: [0xc52a00]: Playback position: 0 Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.797 [22854.22854] INFO ProtocolHandler: [0xc52a00]: Queue version: 4.1 Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.797 [22854.22854] INFO ProtocolHandler: [0xc52a00]: Current track: TID: 270168433, QID: 0, Context UUID: 6fdca5cf-a348-46e6-ad67-14ce5ea1569c Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.797 [22854.22854] INFO ProtocolHandler: [0xc52a00]: Next track: TID: 270168434, QID: 1, Context UUID: 6fdca5cf-a348-46e6-ad67-14ce5ea1569c Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.797 [22854.22854] INFO MediaEngine: [0xc522c0]: Stopping playback, clearing tracks Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.797 [22854.22854] INFO MediaEngine: [0xc522c0]: Initiating playback Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.797 [22854.22854] INFO AudioRenderer: [0xc524e0]: Stopping audio renderer Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.797 [22854.22854] INFO SampleApp: Stopping playback Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.798 [22854.22854] INFO AudioStreamManager: [0xc523a8]: Clearing all streams Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.798 [22854.22854] INFO AudioStreamManager: [0xc523a8]: [Stream 4]: Disposing of audio stream Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.798 [22854.22854] INFO ContentFetcher: [0xcf2e48]: Canceling download Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.805 [22854.22854] INFO SampleApp: Playback state changed: Stopped Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.805 [22854.22854] INFO StreamingEventReporter: [0xc52e18]: Reporting streaming ended event Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.805 [22854.22854] INFO HttpDownloader: [0xc52e70]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.806 [22854.22854] INFO ProtocolHandler: [0xc52a00]: Need to send state update Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.806 [22854.22854] INFO QConnectMessageSender: [0xd73a80]: Sending StateUpdated message Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.806 [22854.22854] INFO SampleApp: [Stream 4]: Audio stream has been disposed of Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.806 [22854.22854] INFO MediaEngine: [0xc522c0]: Setting current track: 270168433, initial offset: 0ms Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.806 [22854.22854] INFO AudioStreamManager: [0xc523a8]: Clearing all streams Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.806 [22854.22854] INFO AudioStreamManager: [0xc523a8]: New stream: 6 Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.806 [22854.22854] INFO HttpDownloader: [0xc8b3b8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=0d93aeaa8932cabbcb43ee14906714b0&request_ts=1758131793&track_id=270168433 Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.806 [22854.22854] INFO HttpDownloader: [0xfa4e88]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=270168433 Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.806 [22854.22854] INFO HttpDownloader: [0xc86ba8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=c53f9157f03196fa396c8387d64b9cf4&request_ts=1758131793&track_id=270168433 Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.806 [22854.22854] INFO AudioStreamManager: [0xc523a8]: [Stream 6]: Running audio stream Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.807 [22854.22854] INFO QwspMessageSender: [0xd73f70]: Sending Payload message Sep 17 20:56:33 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:56:33 volumio volumio[981]: info: CoreCommandRouter::servicePushState Sep 17 20:56:33 volumio volumio[981]: info: CoreStateMachine::pushState Sep 17 20:56:33 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 20:56:33 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Sep 17 20:56:33 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Sep 17 20:56:33 volumio volumio[981]: info: MRS: Pushing multiroomSync output Sep 17 20:56:33 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.817 [22854.22854] INFO MediaEngine: [0xc522c0]: Setting next track: 270168434 Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.817 [22854.22854] INFO AudioStreamManager: [0xc523a8]: New stream: 7 Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.817 [22854.22854] INFO HttpDownloader: [0xd5c840]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=21c99db026ace87bd79dbc10d27e6cfe&request_ts=1758131793&track_id=270168434 Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.817 [22854.22854] INFO HttpDownloader: [0xd6bdf0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=270168434 Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.817 [22854.22854] INFO HttpDownloader: [0xd646a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=8e221b54199deadb785fa81066c65789&request_ts=1758131793&track_id=270168434 Sep 17 20:56:33 volumio qobuz-connect[22854]: 20250917 20:56:33.819 [22854.22854] INFO MediaEngine: [0xc522c0]: Waiting for current stream to start before starting audio renderer Sep 17 20:56:33 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:56:33 volumio volumio[981]: info: CoreCommandRouter::servicePushState Sep 17 20:56:33 volumio volumio[981]: info: CoreStateMachine::pushState Sep 17 20:56:33 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 20:56:33 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Sep 17 20:56:33 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Sep 17 20:56:33 volumio volumio[981]: info: MRS: Pushing multiroomSync output Sep 17 20:56:33 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.471 [22854.22854] INFO AudioStreamManager: [0xc523a8]: [Stream 6]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2405276&eid=270168433&fmt=6&profile=raw&app_id=174516466&cid=2332197&etsp=1758135394&hmac=ZDl093_2mx3c-VEGdwnsea7CrGs Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.533 [22854.22854] INFO AudioStreamManager: [0xc523a8]: [Stream 6]: stream information have been fetched Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.533 [22854.22854] INFO UrlAudioSource: [0x1182168]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2405276&eid=270168433&fmt=6&profile=raw&app_id=174516466&cid=2332197&etsp=1758135394&hmac=ZDl093_2mx3c-VEGdwnsea7CrGs Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.533 [22854.22854] INFO ContentFetcher: [0xc9be18]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2405276&eid=270168433&fmt=6&profile=raw&app_id=174516466&cid=2332197&etsp=1758135394&hmac=ZDl093_2mx3c-VEGdwnsea7CrGs, offset: 0 Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.533 [22854.22854] INFO AudioRenderer: [0xc524e0]: Starting audio renderer, initial playback state: Playing Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.533 [22854.22854] INFO SampleApp: [Stream 6]: New audio stream (starting from 0ms) Sep 17 20:56:34 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:56:34 volumio volumio[981]: info: QobuzConnect: alread currrent service, send playback confirmation Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.535 [22854.22854] INFO SampleApp: Initiating playback, initial state: Playing Sep 17 20:56:34 volumio qobuz-connect[22854]: 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 Sep 17 20:56:34 volumio qobuz-connect[22854]: 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 Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.539 [22854.22854] INFO SampleApp: Playback state changed: Playing Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.539 [22854.22854] INFO StreamingEventReporter: [0xc52e18]: Reporting streaming started event Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.539 [22854.22854] INFO HttpDownloader: [0xc52e70]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.539 [22854.22854] INFO ProtocolHandler: [0xc52a00]: Need to send state update Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.539 [22854.22854] INFO QConnectMessageSender: [0xd73a80]: Sending StateUpdated message Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.539 [22854.22854] INFO QwspMessageSender: [0xd73f70]: Sending Payload message Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.540 [22854.22854] INFO QConnectMessageSender: [0xd73a80]: Sending FileAudioQualityChanged message Sep 17 20:56:34 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:56:34 volumio volumio[981]: info: CoreCommandRouter::servicePushState Sep 17 20:56:34 volumio volumio[981]: info: CoreStateMachine::pushState Sep 17 20:56:34 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.541 [22854.22854] INFO QwspMessageSender: [0xd73f70]: Sending Payload message Sep 17 20:56:34 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Sep 17 20:56:34 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Sep 17 20:56:34 volumio volumio[981]: info: MRS: Pushing multiroomSync output Sep 17 20:56:34 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:56:34 volumio volumio[981]: info: CoreCommandRouter::servicePushState Sep 17 20:56:34 volumio volumio[981]: info: CoreStateMachine::pushState Sep 17 20:56:34 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 20:56:34 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Sep 17 20:56:34 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Sep 17 20:56:34 volumio volumio[981]: info: MRS: Pushing multiroomSync output Sep 17 20:56:34 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:56:34 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.556 [22854.22854] INFO AudioStreamManager: [0xc523a8]: [Stream 7]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2405276&eid=270168434&fmt=6&profile=raw&app_id=174516466&cid=2332197&etsp=1758135394&hmac=KFcnCs9Mh5bAWyLPIVO3-jwdI64 Sep 17 20:56:34 volumio volumio[981]: info: CoreCommandRouter::servicePushState Sep 17 20:56:34 volumio volumio[981]: info: CoreStateMachine::pushState Sep 17 20:56:34 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Sep 17 20:56:34 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Sep 17 20:56:34 volumio volumio[981]: info: MRS: Pushing multiroomSync output Sep 17 20:56:34 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:56:34 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:56:34 volumio volumio[981]: info: CoreCommandRouter::servicePushState Sep 17 20:56:34 volumio volumio[981]: info: CoreStateMachine::pushState Sep 17 20:56:34 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Sep 17 20:56:34 volumio volumio[981]: info: MRS: Pushing multiroomSync output update for this device Sep 17 20:56:34 volumio volumio[981]: info: MRS: Pushing multiroomSync output Sep 17 20:56:34 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 17 20:56:34 volumio volumio[981]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.WTzRp2fyNoaiHrjoyWvmFA3v4aZ2.a8230d76e31702b8bfdc37a530c541f9.state.track' Sep 17 20:56:34 volumio volumio[981]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.WTzRp2fyNoaiHrjoyWvmFA3v4aZ2.a8230d76e31702b8bfdc37a530c541f9.state.track' Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.586 [22854.22854] INFO AudioStreamManager: [0xc523a8]: [Stream 7]: stream information have been fetched Sep 17 20:56:34 volumio volumio[981]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Sep 17 20:56:34 volumio volumio[981]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 20:56:34 volumio volumio[981]: TypeError: Cannot read property 'indexOf' of undefined Sep 17 20:56:34 volumio volumio[981]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Sep 17 20:56:34 volumio volumio[981]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Sep 17 20:56:34 volumio volumio[981]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Sep 17 20:56:34 volumio volumio[981]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Sep 17 20:56:34 volumio volumio[981]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Sep 17 20:56:34 volumio volumio[981]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Sep 17 20:56:34 volumio volumio[981]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Sep 17 20:56:34 volumio volumio[981]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Sep 17 20:56:34 volumio volumio[981]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Sep 17 20:56:34 volumio volumio[981]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Sep 17 20:56:34 volumio volumio[981]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Sep 17 20:56:34 volumio volumio[981]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Sep 17 20:56:34 volumio volumio[981]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Sep 17 20:56:34 volumio volumio[981]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Sep 17 20:56:34 volumio volumio[981]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Sep 17 20:56:34 volumio volumio[981]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Sep 17 20:56:34 volumio volumio[981]: at WS. (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:268:10) Sep 17 20:56:34 volumio volumio[981]: at WS.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Sep 17 20:56:34 volumio volumio[981]: at WS.Transport.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/transport.js:145:8) Sep 17 20:56:34 volumio volumio[981]: at WS.Transport.onData (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/transport.js:137:8) Sep 17 20:56:34 volumio volumio[981]: at WebSocket.ws.onmessage (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/transports/websocket.js:146:10) Sep 17 20:56:34 volumio volumio[981]: at WebSocket.onMessage (/data/plugins/user_interface/lastfm/node_modules/ws/lib/WebSocket.js:442:14) Sep 17 20:56:34 volumio volumio[981]: at WebSocket.emit (events.js:315:20) Sep 17 20:56:34 volumio volumio[981]: at Receiver.ontext (/data/plugins/user_interface/lastfm/node_modules/ws/lib/WebSocket.js:841:10) Sep 17 20:56:34 volumio volumio[981]: at /data/plugins/user_interface/lastfm/node_modules/ws/lib/Receiver.js:536:18 Sep 17 20:56:34 volumio volumio[981]: at Receiver.applyExtensions (/data/plugins/user_interface/lastfm/node_modules/ws/lib/Receiver.js:371:5) Sep 17 20:56:34 volumio volumio[981]: at /data/plugins/user_interface/lastfm/node_modules/ws/lib/Receiver.js:508:14 Sep 17 20:56:34 volumio volumio[981]: at Receiver.flush (/data/plugins/user_interface/lastfm/node_modules/ws/lib/Receiver.js:347:3) Sep 17 20:56:34 volumio volumio[981]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.602 [22854.22854] INFO AudioStreamManager: [0xc523a8]: [Stream 6]: Metadata became available: Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.602 [22854.22854] INFO AudioStreamManager: [0xc523a8]: Title: SHINOBI VS DRAGON NINJA Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.603 [22854.22854] INFO AudioStreamManager: [0xc523a8]: Artist: lostprophets Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.603 [22854.22854] INFO AudioStreamManager: [0xc523a8]: Album: THE FAKE SOUND OF PROGRESS Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.603 [22854.22854] INFO AudioStreamManager: [0xc523a8]: Album art URL: https://static.qobuz.com/images/covers/jb/6r/mkntrdp5g6rjb_600.jpg Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.603 [22854.22854] INFO SampleApp: [Stream 6]: Stream metadata became available: Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.603 [22854.22854] INFO SampleApp: Title: SHINOBI VS DRAGON NINJA Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.603 [22854.22854] INFO SampleApp: Artist: lostprophets Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.603 [22854.22854] INFO SampleApp: Album: THE FAKE SOUND OF PROGRESS Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.603 [22854.22854] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/jb/6r/mkntrdp5g6rjb_600.jpg Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.610 [22854.22854] INFO AudioStreamManager: [0xc523a8]: [Stream 7]: Metadata became available: Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.610 [22854.22854] INFO AudioStreamManager: [0xc523a8]: Title: THE FAKE SOUND OF PROGRESS Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.610 [22854.22854] INFO AudioStreamManager: [0xc523a8]: Artist: lostprophets Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.610 [22854.22854] INFO AudioStreamManager: [0xc523a8]: Album: THE FAKE SOUND OF PROGRESS Sep 17 20:56:34 volumio qobuz-connect[22854]: 20250917 20:56:34.610 [22854.22854] INFO AudioStreamManager: [0xc523a8]: Album art URL: https://static.qobuz.com/images/covers/jb/6r/mkntrdp5g6rjb_600.jpg Sep 17 20:56:35 volumio sudo[24750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-17 20:55 Sep 17 20:56:35 volumio sudo[24750]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"