-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Mon 2025-06-30 19:26:46 CEST. -- Jun 30 19:25:01 volumio qobuz-connect[2237]: 20250630 19:25:01.521 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Need to send state update Jun 30 19:25:01 volumio qobuz-connect[2237]: 20250630 19:25:01.521 [2237.2237] INFO QConnectMessageSender: [0x10585a0]: Sending StateUpdated message Jun 30 19:25:01 volumio qobuz-connect[2237]: 20250630 19:25:01.522 [2237.2237] INFO QwspMessageSender: [0x1121470]: Sending Payload message Jun 30 19:25:01 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:01 volumio volumio[1211]: info: CoreCommandRouter::servicePushState Jun 30 19:25:01 volumio volumio[1211]: info: CoreStateMachine::pushState Jun 30 19:25:01 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 19:25:01 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Jun 30 19:25:01 volumio volumio[1211]: info: MRS: Pushing multiroomSync output update for this device Jun 30 19:25:01 volumio volumio[1211]: info: MRS: Pushing multiroomSync output Jun 30 19:25:01 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:11 volumio qobuz-connect[2237]: 20250630 19:25:11.521 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Need to send state update Jun 30 19:25:11 volumio qobuz-connect[2237]: 20250630 19:25:11.521 [2237.2237] INFO QConnectMessageSender: [0x10585a0]: Sending StateUpdated message Jun 30 19:25:11 volumio qobuz-connect[2237]: 20250630 19:25:11.521 [2237.2237] INFO QwspMessageSender: [0x1121470]: Sending Payload message Jun 30 19:25:11 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:11 volumio volumio[1211]: info: CoreCommandRouter::servicePushState Jun 30 19:25:11 volumio volumio[1211]: info: CoreStateMachine::pushState Jun 30 19:25:11 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 19:25:11 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Jun 30 19:25:11 volumio volumio[1211]: info: MRS: Pushing multiroomSync output update for this device Jun 30 19:25:11 volumio volumio[1211]: info: MRS: Pushing multiroomSync output Jun 30 19:25:11 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:21 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:21 volumio volumio[1211]: info: CoreCommandRouter::servicePushState Jun 30 19:25:21 volumio volumio[1211]: info: CoreStateMachine::pushState Jun 30 19:25:21 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 19:25:21 volumio qobuz-connect[2237]: 20250630 19:25:21.523 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Need to send state update Jun 30 19:25:21 volumio qobuz-connect[2237]: 20250630 19:25:21.523 [2237.2237] INFO QConnectMessageSender: [0x10585a0]: Sending StateUpdated message Jun 30 19:25:21 volumio qobuz-connect[2237]: 20250630 19:25:21.523 [2237.2237] INFO QwspMessageSender: [0x1121470]: Sending Payload message Jun 30 19:25:21 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Jun 30 19:25:21 volumio volumio[1211]: info: MRS: Pushing multiroomSync output update for this device Jun 30 19:25:21 volumio volumio[1211]: info: MRS: Pushing multiroomSync output Jun 30 19:25:21 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.522 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Need to send state update Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.522 [2237.2237] INFO QConnectMessageSender: [0x10585a0]: Sending StateUpdated message Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.522 [2237.2237] INFO QwspMessageSender: [0x1121470]: Sending Payload message Jun 30 19:25:31 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:31 volumio volumio[1211]: info: CoreCommandRouter::servicePushState Jun 30 19:25:31 volumio volumio[1211]: info: CoreStateMachine::pushState Jun 30 19:25:31 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 19:25:31 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Jun 30 19:25:31 volumio volumio[1211]: info: MRS: Pushing multiroomSync output update for this device Jun 30 19:25:31 volumio volumio[1211]: info: MRS: Pushing multiroomSync output Jun 30 19:25:31 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.784 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Received SetState message: Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.784 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Playing state: Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.784 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Playback position: Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.784 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Queue version: 5.1 Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.784 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Current track: TID: 82219912, QID: 140, Context UUID: b1ba4e38-27e3-4f54-ab77-72212049bbf8 Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.784 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Next track: TID: 15211731, QID: 141, Context UUID: b1ba4e38-27e3-4f54-ab77-72212049bbf8 Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.784 [2237.2237] INFO MediaEngine: [0x1018480]: Stopping playback, clearing tracks Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.784 [2237.2237] INFO MediaEngine: [0x1018480]: Initiating playback Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.784 [2237.2237] INFO AudioRenderer: [0x10186a0]: Stopping audio renderer Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.784 [2237.2237] INFO SampleApp: Stopping playback Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.785 [2237.2237] INFO AudioStreamManager: [0x1018568]: Clearing all streams Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.785 [2237.2237] INFO AudioStreamManager: [0x1018568]: [Stream 10]: Disposing of audio stream Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.785 [2237.2237] INFO ContentFetcher: [0x11d3548]: Canceling download Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.791 [2237.2237] INFO SampleApp: Playback state changed: Stopped Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.791 [2237.2237] INFO StreamingEventReporter: [0x1018fd8]: Reporting streaming ended event Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.791 [2237.2237] INFO HttpDownloader: [0x1019030]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.791 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Need to send state update Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.792 [2237.2237] INFO QConnectMessageSender: [0x10585a0]: Sending StateUpdated message Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.792 [2237.2237] INFO SampleApp: [Stream 10]: Audio stream has been disposed of Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.792 [2237.2237] INFO MediaEngine: [0x1018480]: Setting current track: 82219912, initial offset: 0ms Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.792 [2237.2237] INFO AudioStreamManager: [0x1018568]: Clearing all streams Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.792 [2237.2237] INFO AudioStreamManager: [0x1018568]: New stream: 12 Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.792 [2237.2237] INFO HttpDownloader: [0x1238bd8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=169958799d89ddcbb2dbe77e25301fd0&request_ts=1751304331&track_id=82219912 Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.792 [2237.2237] INFO HttpDownloader: [0x12cfa38]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=82219912 Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.792 [2237.2237] INFO HttpDownloader: [0x124e368]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=8208b44d8e2c81a708837cabe0e336ca&request_ts=1751304331&track_id=82219912 Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.792 [2237.2237] INFO AudioStreamManager: [0x1018568]: [Stream 12]: Running audio stream Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.792 [2237.2237] INFO QwspMessageSender: [0x1121470]: Sending Payload message Jun 30 19:25:31 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:31 volumio volumio[1211]: info: CoreCommandRouter::servicePushState Jun 30 19:25:31 volumio volumio[1211]: info: CoreStateMachine::pushState Jun 30 19:25:31 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 19:25:31 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.794 [2237.2237] INFO MediaEngine: [0x1018480]: Setting next track: 15211731 Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.794 [2237.2237] INFO AudioStreamManager: [0x1018568]: New stream: 13 Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.794 [2237.2237] INFO HttpDownloader: [0x12bb138]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=306a893522a5650138e89d8cd8b97125&request_ts=1751304331&track_id=15211731 Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.794 [2237.2237] INFO HttpDownloader: [0x12d5fa8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=15211731 Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.794 [2237.2237] INFO HttpDownloader: [0x12d3eb0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=2e23c5e2b533995e246a90e40a47e1db&request_ts=1751304331&track_id=15211731 Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.795 [2237.2237] INFO MediaEngine: [0x1018480]: Waiting for current stream to start before starting audio renderer Jun 30 19:25:31 volumio volumio[1211]: info: MRS: Pushing multiroomSync output update for this device Jun 30 19:25:31 volumio volumio[1211]: info: MRS: Pushing multiroomSync output Jun 30 19:25:31 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:31 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:31 volumio volumio[1211]: info: CoreCommandRouter::servicePushState Jun 30 19:25:31 volumio volumio[1211]: info: CoreStateMachine::pushState Jun 30 19:25:31 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 19:25:31 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.825 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Received SetState message: Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.825 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Playing state: Playing Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.825 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Playback position: Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.825 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Queue version: Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.825 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Current track: Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.826 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Next track: Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.826 [2237.2237] INFO MediaEngine: [0x1018480]: Resuming playback Jun 30 19:25:31 volumio volumio[1211]: info: MRS: Pushing multiroomSync output update for this device Jun 30 19:25:31 volumio volumio[1211]: info: MRS: Pushing multiroomSync output Jun 30 19:25:31 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.885 [2237.2237] INFO AudioStreamManager: [0x1018568]: [Stream 12]: Metadata became available: Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.885 [2237.2237] INFO AudioStreamManager: [0x1018568]: Title: Don't Look Any Further (Original 12” M+M Mix) Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.885 [2237.2237] INFO AudioStreamManager: [0x1018568]: Artist: Dennis Edwards Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.885 [2237.2237] INFO AudioStreamManager: [0x1018568]: Album: Don't Look Any Further (Original 12” M+M Mix) Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.885 [2237.2237] INFO AudioStreamManager: [0x1018568]: Album art URL: https://static.qobuz.com/images/covers/hc/32/bjh9se4z432hc_600.jpg Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.897 [2237.2237] INFO AudioStreamManager: [0x1018568]: [Stream 12]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2149735&eid=82219912&fmt=6&profile=raw&app_id=174516466&cid=1512845&etsp=1751307931&hmac=SESkRwQXphqBuIZpgsSEcxgg-gk Jun 30 19:25:31 volumio volumio[1211]: info: camilladsp spawned new process with pid 4700, instance 1, run: true Jun 30 19:25:31 volumio volumio[1211]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.975 [2237.2237] INFO AudioStreamManager: [0x1018568]: [Stream 13]: Metadata became available: Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.975 [2237.2237] INFO AudioStreamManager: [0x1018568]: Title: Funeral For A Friend / Love Lies Bleeding (Remastered 2014 / Medley) Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.975 [2237.2237] INFO AudioStreamManager: [0x1018568]: Artist: Elton John Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.975 [2237.2237] INFO AudioStreamManager: [0x1018568]: Album: Goodbye Yellow Brick Road (Remastered 2014 / Medley) Jun 30 19:25:31 volumio qobuz-connect[2237]: 20250630 19:25:31.975 [2237.2237] INFO AudioStreamManager: [0x1018568]: Album art URL: https://static.qobuz.com/images/covers/81/95/0060253759581_600.jpg Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.021 [2237.2237] INFO AudioStreamManager: [0x1018568]: [Stream 13]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2149735&eid=15211731&fmt=7&profile=raw&app_id=174516466&cid=1512845&etsp=1751307931&hmac=u3ywZ2diMlQu_eyCFzo9nmqr4Dw Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.036 [2237.2237] INFO AudioStreamManager: [0x1018568]: [Stream 13]: stream information have been fetched Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.044 [2237.2237] INFO AudioStreamManager: [0x1018568]: [Stream 12]: stream information have been fetched Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.044 [2237.2237] INFO UrlAudioSource: [0x12d2888]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2149735&eid=82219912&fmt=6&profile=raw&app_id=174516466&cid=1512845&etsp=1751307931&hmac=SESkRwQXphqBuIZpgsSEcxgg-gk Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.045 [2237.2237] INFO ContentFetcher: [0x125ba10]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2149735&eid=82219912&fmt=6&profile=raw&app_id=174516466&cid=1512845&etsp=1751307931&hmac=SESkRwQXphqBuIZpgsSEcxgg-gk, offset: 0 Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.045 [2237.2237] INFO AudioRenderer: [0x10186a0]: Starting audio renderer, initial playback state: Playing Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.045 [2237.2237] INFO SampleApp: [Stream 12]: New audio stream (starting from 0ms) Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.045 [2237.2237] INFO SampleApp: [Stream 12]: Stream metadata became available: Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.045 [2237.2237] INFO SampleApp: Title: Don't Look Any Further (Original 12” M+M Mix) Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.045 [2237.2237] INFO SampleApp: Artist: Dennis Edwards Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.045 [2237.2237] INFO SampleApp: Album: Don't Look Any Further (Original 12” M+M Mix) Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.045 [2237.2237] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/hc/32/bjh9se4z432hc_600.jpg Jun 30 19:25:32 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:32 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:32 volumio volumio[1211]: info: QobuzConnect: alread currrent service, send playback confirmation Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.047 [2237.2237] INFO SampleApp: Initiating playback, initial state: Playing Jun 30 19:25:32 volumio qobuz-connect[2237]: 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 volumioDsp has buffer size 65536 Jun 30 19:25:32 volumio qobuz-connect[2237]: 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 volumioDsp has buffer size 65536 Jun 30 19:25:32 volumio volumio[1211]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jun 30 19:25:32 volumio volumio[1211]: info: FusionDsp - ---- read samplerate from file: 44100 Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.053 [2237.2237] INFO SampleApp: Playback state changed: Playing Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.053 [2237.2237] INFO StreamingEventReporter: [0x1018fd8]: Reporting streaming started event Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.053 [2237.2237] INFO HttpDownloader: [0x1019030]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.054 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Need to send state update Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.054 [2237.2237] INFO QConnectMessageSender: [0x10585a0]: Sending StateUpdated message Jun 30 19:25:32 volumio volumio[1211]: error: FusionDsp - File size not found in array! Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.054 [2237.2237] INFO QwspMessageSender: [0x1121470]: Sending Payload message Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.055 [2237.2237] INFO QConnectMessageSender: [0x10585a0]: Sending FileAudioQualityChanged message Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.055 [2237.2237] INFO QwspMessageSender: [0x1121470]: Sending Payload message Jun 30 19:25:32 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:32 volumio volumio[1211]: info: CoreCommandRouter::servicePushState Jun 30 19:25:32 volumio volumio[1211]: info: CoreStateMachine::pushState Jun 30 19:25:32 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 19:25:32 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Jun 30 19:25:32 volumio volumio[1211]: info: MRS: Pushing multiroomSync output update for this device Jun 30 19:25:32 volumio volumio[1211]: info: MRS: Pushing multiroomSync output Jun 30 19:25:32 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:32 volumio volumio[1211]: info: CoreCommandRouter::servicePushState Jun 30 19:25:32 volumio volumio[1211]: info: CoreStateMachine::pushState Jun 30 19:25:32 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 19:25:32 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Jun 30 19:25:32 volumio volumio[1211]: info: MRS: Pushing multiroomSync output update for this device Jun 30 19:25:32 volumio volumio[1211]: info: MRS: Pushing multiroomSync output Jun 30 19:25:32 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:32 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:32 volumio volumio[1211]: info: CoreCommandRouter::servicePushState Jun 30 19:25:32 volumio volumio[1211]: info: CoreStateMachine::pushState Jun 30 19:25:32 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Jun 30 19:25:32 volumio volumio[1211]: info: MRS: Pushing multiroomSync output update for this device Jun 30 19:25:32 volumio volumio[1211]: info: MRS: Pushing multiroomSync output Jun 30 19:25:32 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:32 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:32 volumio volumio[1211]: info: CoreCommandRouter::servicePushState Jun 30 19:25:32 volumio volumio[1211]: info: CoreStateMachine::pushState Jun 30 19:25:32 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Jun 30 19:25:32 volumio volumio[1211]: info: MRS: Pushing multiroomSync output update for this device Jun 30 19:25:32 volumio volumio[1211]: info: MRS: Pushing multiroomSync output Jun 30 19:25:32 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:32 volumio volumio[1211]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.284 [2237.2237] INFO UrlAudioSource: [0x12d2888]: Content info extracted Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.284 [2237.2237] INFO UrlAudioSource: [0x12d2888]: Size: 37029005 Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.285 [2237.4710] INFO AudioDecoder: [0x105da28]: Stream info: Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.285 [2237.4710] INFO AudioDecoder: [0x105da28]: Total samples: 14278992 Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.285 [2237.4710] INFO AudioDecoder: [0x105da28]: Sample rate: 44100 Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.286 [2237.4710] INFO AudioDecoder: [0x105da28]: Channel count: 2 Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.286 [2237.4710] INFO AudioDecoder: [0x105da28]: Bits per sample: 16 Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.286 [2237.4710] INFO AudioDecoder: [0x105da28]: Duration: 323786 Jun 30 19:25:32 volumio qobuz-connect[2237]: 20250630 19:25:32.286 [2237.2237] INFO UrlAudioSource: [0x12d2888]: Audio properties have changed Jun 30 19:25:42 volumio qobuz-connect[2237]: 20250630 19:25:42.053 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Need to send state update Jun 30 19:25:42 volumio qobuz-connect[2237]: 20250630 19:25:42.053 [2237.2237] INFO QConnectMessageSender: [0x10585a0]: Sending StateUpdated message Jun 30 19:25:42 volumio qobuz-connect[2237]: 20250630 19:25:42.053 [2237.2237] INFO QwspMessageSender: [0x1121470]: Sending Payload message Jun 30 19:25:42 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:42 volumio volumio[1211]: info: CoreCommandRouter::servicePushState Jun 30 19:25:42 volumio volumio[1211]: info: CoreStateMachine::pushState Jun 30 19:25:42 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 19:25:42 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Jun 30 19:25:42 volumio volumio[1211]: info: MRS: Pushing multiroomSync output update for this device Jun 30 19:25:42 volumio volumio[1211]: info: MRS: Pushing multiroomSync output Jun 30 19:25:42 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:50 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 30 19:25:50 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 30 19:25:50 volumio volumio[1211]: info: Discovery: Getting this device information Jun 30 19:25:50 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:50 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 30 19:25:50 volumio volumio[1211]: verbose: New Socket.io Connection to 192.168.178.142:3000 from 192.168.178.149 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Jun 30 19:25:50 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:50 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 30 19:25:50 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 30 19:25:51 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 30 19:25:51 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 30 19:25:51 volumio volumio[1211]: info: Discovery: Getting this device information Jun 30 19:25:51 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:51 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 30 19:25:51 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 30 19:25:51 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 30 19:25:51 volumio volumio[1211]: info: Discovery: Getting this device information Jun 30 19:25:51 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:51 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 30 19:25:52 volumio qobuz-connect[2237]: 20250630 19:25:52.053 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Need to send state update Jun 30 19:25:52 volumio qobuz-connect[2237]: 20250630 19:25:52.053 [2237.2237] INFO QConnectMessageSender: [0x10585a0]: Sending StateUpdated message Jun 30 19:25:52 volumio qobuz-connect[2237]: 20250630 19:25:52.053 [2237.2237] INFO QwspMessageSender: [0x1121470]: Sending Payload message Jun 30 19:25:52 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:52 volumio volumio[1211]: info: CoreCommandRouter::servicePushState Jun 30 19:25:52 volumio volumio[1211]: info: CoreStateMachine::pushState Jun 30 19:25:52 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 19:25:52 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Jun 30 19:25:52 volumio volumio[1211]: info: MRS: Pushing multiroomSync output update for this device Jun 30 19:25:52 volumio volumio[1211]: info: MRS: Pushing multiroomSync output Jun 30 19:25:52 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:52 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:52 volumio volumio[1211]: verbose: New Socket.io Connection to 192.168.178.142:3000 from 192.168.178.149 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Jun 30 19:25:52 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:52 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 30 19:25:52 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 30 19:25:52 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:52 volumio sudo[4743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 30 19:25:52 volumio sudo[4743]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 19:25:52 volumio sudo[4743]: pam_unix(sudo:session): session closed for user root Jun 30 19:25:52 volumio sudo[4746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 30 19:25:52 volumio sudo[4746]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 19:25:52 volumio sudo[4746]: pam_unix(sudo:session): session closed for user root Jun 30 19:25:52 volumio volumio[1211]: verbose: New Socket.io Connection to 192.168.178.142 from 192.168.178.149 UA: Mozilla/5.0 (Linux; Android 16; Pixel 8 Pro Build/BP2A.250605.031.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jun 30 19:25:53 volumio sudo[4749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 30 19:25:53 volumio sudo[4749]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 19:25:53 volumio sudo[4749]: pam_unix(sudo:session): session closed for user root Jun 30 19:25:53 volumio sudo[4752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 30 19:25:53 volumio sudo[4752]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 19:25:53 volumio sudo[4752]: pam_unix(sudo:session): session closed for user root Jun 30 19:25:53 volumio volumio[1211]: verbose: New Socket.io Connection to 192.168.178.142 from 192.168.178.149 UA: Mozilla/5.0 (Linux; Android 16; Pixel 8 Pro Build/BP2A.250605.031.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Jun 30 19:25:53 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 30 19:25:53 volumio volumio[1211]: info: CoreCommandRouter::volumioGetVisibleSources Jun 30 19:25:53 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 30 19:25:53 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:53 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 30 19:25:53 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 30 19:25:53 volumio volumio[1211]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 30 19:25:53 volumio volumio[1211]: info: Received Get System Info Jun 30 19:25:53 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 30 19:25:53 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 30 19:25:53 volumio volumio[1211]: info: Discovery: Getting this device information Jun 30 19:25:53 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:53 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 30 19:25:53 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:53 volumio volumio[1211]: info: Listing playlists Jun 30 19:25:53 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 30 19:25:53 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 30 19:25:53 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 30 19:25:53 volumio volumio[1211]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 30 19:25:53 volumio volumio[1211]: info: FusionDsp - no preset used Jun 30 19:25:53 volumio volumio[1211]: info: FusionDsp - kef.json,test.json Jun 30 19:25:55 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 30 19:25:55 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 30 19:25:55 volumio volumio[1211]: info: Received Get System Info Jun 30 19:25:55 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 30 19:25:55 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 30 19:25:55 volumio volumio[1211]: info: Discovery: Getting this device information Jun 30 19:25:55 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:55 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 30 19:25:57 volumio volumio[1211]: info: CoreCommandRouter::volumioPause Jun 30 19:25:57 volumio volumio[1211]: info: CoreStateMachine::pause Jun 30 19:25:57 volumio volumio[1211]: info: CoreStateMachine::stPlaybackTimer Jun 30 19:25:57 volumio volumio[1211]: info: CoreStateMachine::servicePause Jun 30 19:25:57 volumio volumio[1211]: info: CoreCommandRouter::servicePause Jun 30 19:25:57 volumio volumio[1211]: info: QobuzConnect: Received pause Jun 30 19:25:57 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:57 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 30 19:25:57 volumio volumio[1211]: info: Received Get System Info Jun 30 19:25:57 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 30 19:25:57 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 30 19:25:57 volumio volumio[1211]: info: Discovery: Getting this device information Jun 30 19:25:57 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:57 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 30 19:25:57 volumio qobuz-connect[2237]: 20250630 19:25:57.111 [2237.2237] INFO MediaEngine: [0x1018480]: Pausing playback Jun 30 19:25:57 volumio qobuz-connect[2237]: 20250630 19:25:57.112 [2237.2237] INFO AudioRenderer: [0x10186a0]: Pausing audio renderer Jun 30 19:25:57 volumio qobuz-connect[2237]: 20250630 19:25:57.112 [2237.2237] INFO SampleApp: Pausing playback Jun 30 19:25:57 volumio qobuz-connect[2237]: 20250630 19:25:57.112 [2237.2237] INFO SampleApp: Playback state changed: Paused Jun 30 19:25:57 volumio qobuz-connect[2237]: 20250630 19:25:57.112 [2237.2237] INFO StreamingEventReporter: [0x1018fd8]: Reporting streaming ended event Jun 30 19:25:57 volumio qobuz-connect[2237]: 20250630 19:25:57.112 [2237.2237] INFO HttpDownloader: [0x1019030]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jun 30 19:25:57 volumio qobuz-connect[2237]: 20250630 19:25:57.112 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Need to send state update Jun 30 19:25:57 volumio qobuz-connect[2237]: 20250630 19:25:57.112 [2237.2237] INFO QConnectMessageSender: [0x10585a0]: Sending StateUpdated message Jun 30 19:25:57 volumio qobuz-connect[2237]: 20250630 19:25:57.112 [2237.2237] INFO QwspMessageSender: [0x1121470]: Sending Payload message Jun 30 19:25:57 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:57 volumio volumio[1211]: info: CoreCommandRouter::servicePushState Jun 30 19:25:57 volumio volumio[1211]: info: CoreStateMachine::pushState Jun 30 19:25:57 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 19:25:57 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Jun 30 19:25:57 volumio volumio[1211]: info: MRS: Pushing multiroomSync output update for this device Jun 30 19:25:57 volumio volumio[1211]: info: MRS: Pushing multiroomSync output Jun 30 19:25:57 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:57 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:57 volumio volumio[1211]: info: CoreCommandRouter::servicePushState Jun 30 19:25:57 volumio volumio[1211]: info: CoreStateMachine::pushState Jun 30 19:25:57 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 19:25:57 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Jun 30 19:25:57 volumio volumio[1211]: info: MRS: Pushing multiroomSync output update for this device Jun 30 19:25:57 volumio volumio[1211]: info: MRS: Pushing multiroomSync output Jun 30 19:25:57 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:57 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:57 volumio volumio[1211]: info: CoreCommandRouter::servicePushState Jun 30 19:25:57 volumio volumio[1211]: info: CoreStateMachine::pushState Jun 30 19:25:57 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Jun 30 19:25:57 volumio volumio[1211]: info: MRS: Pushing multiroomSync output update for this device Jun 30 19:25:57 volumio volumio[1211]: info: MRS: Pushing multiroomSync output Jun 30 19:25:57 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:25:57 volumio volumio[1211]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 30 19:26:01 volumio volumio[1211]: info: CoreCommandRouter::volumioGetQueue Jun 30 19:26:01 volumio volumio[1211]: info: CoreStateMachine::getQueue Jun 30 19:26:01 volumio volumio[1211]: info: CorePlayQueue::getQueue Jun 30 19:26:01 volumio volumio[1211]: info: Executing endpoint metavolumio Jun 30 19:26:01 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 30 19:26:01 volumio volumio[1211]: info: Executing endpoint metavolumio Jun 30 19:26:01 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 30 19:26:03 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 30 19:26:08 volumio qobuz-connect[2237]: 20250630 19:26:08.750 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Received SetState message: Jun 30 19:26:08 volumio qobuz-connect[2237]: 20250630 19:26:08.750 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Playing state: Playing Jun 30 19:26:08 volumio qobuz-connect[2237]: 20250630 19:26:08.750 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Playback position: Jun 30 19:26:08 volumio qobuz-connect[2237]: 20250630 19:26:08.750 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Queue version: Jun 30 19:26:08 volumio qobuz-connect[2237]: 20250630 19:26:08.751 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Current track: Jun 30 19:26:08 volumio qobuz-connect[2237]: 20250630 19:26:08.751 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Next track: Jun 30 19:26:08 volumio qobuz-connect[2237]: 20250630 19:26:08.751 [2237.2237] INFO MediaEngine: [0x1018480]: Resuming playback Jun 30 19:26:08 volumio qobuz-connect[2237]: 20250630 19:26:08.751 [2237.2237] INFO AudioRenderer: [0x10186a0]: Resuming audio renderer Jun 30 19:26:08 volumio qobuz-connect[2237]: 20250630 19:26:08.751 [2237.2237] INFO SampleApp: Resuming playback Jun 30 19:26:08 volumio qobuz-connect[2237]: 20250630 19:26:08.751 [2237.2237] INFO SampleApp: Playback state changed: Playing Jun 30 19:26:08 volumio qobuz-connect[2237]: 20250630 19:26:08.752 [2237.2237] INFO StreamingEventReporter: [0x1018fd8]: Reporting streaming started event Jun 30 19:26:08 volumio qobuz-connect[2237]: 20250630 19:26:08.752 [2237.2237] INFO HttpDownloader: [0x1019030]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jun 30 19:26:08 volumio qobuz-connect[2237]: 20250630 19:26:08.752 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Need to send state update Jun 30 19:26:08 volumio qobuz-connect[2237]: 20250630 19:26:08.752 [2237.2237] INFO QConnectMessageSender: [0x10585a0]: Sending StateUpdated message Jun 30 19:26:08 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:26:08 volumio volumio[1211]: info: CoreCommandRouter::servicePushState Jun 30 19:26:08 volumio volumio[1211]: info: CoreStateMachine::pushState Jun 30 19:26:08 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Jun 30 19:26:08 volumio qobuz-connect[2237]: 20250630 19:26:08.754 [2237.2237] INFO QwspMessageSender: [0x1121470]: Sending Payload message Jun 30 19:26:08 volumio volumio[1211]: info: MRS: Pushing multiroomSync output update for this device Jun 30 19:26:08 volumio volumio[1211]: info: MRS: Pushing multiroomSync output Jun 30 19:26:08 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:26:08 volumio volumio[1211]: info: CoreCommandRouter::servicePushState Jun 30 19:26:08 volumio volumio[1211]: info: CoreStateMachine::pushState Jun 30 19:26:08 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 19:26:08 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Jun 30 19:26:08 volumio volumio[1211]: info: MRS: Pushing multiroomSync output update for this device Jun 30 19:26:08 volumio volumio[1211]: info: MRS: Pushing multiroomSync output Jun 30 19:26:08 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:26:08 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:26:08 volumio volumio[1211]: info: CoreCommandRouter::servicePushState Jun 30 19:26:08 volumio volumio[1211]: info: CoreStateMachine::pushState Jun 30 19:26:08 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Jun 30 19:26:08 volumio volumio[1211]: info: MRS: Pushing multiroomSync output update for this device Jun 30 19:26:08 volumio volumio[1211]: info: MRS: Pushing multiroomSync output Jun 30 19:26:08 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:26:08 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:26:08 volumio volumio[1211]: info: CoreCommandRouter::servicePushState Jun 30 19:26:08 volumio volumio[1211]: info: CoreStateMachine::pushState Jun 30 19:26:08 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Jun 30 19:26:08 volumio volumio[1211]: info: MRS: Pushing multiroomSync output update for this device Jun 30 19:26:08 volumio volumio[1211]: info: MRS: Pushing multiroomSync output Jun 30 19:26:08 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:26:12 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 30 19:26:12 volumio volumio[1211]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 30 19:26:12 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 30 19:26:12 volumio volumio[1211]: info: Received Get System Version Jun 30 19:26:12 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 30 19:26:12 volumio volumio[1211]: info: Received Get System Info Jun 30 19:26:12 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 30 19:26:12 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 30 19:26:12 volumio volumio[1211]: info: Discovery: Getting this device information Jun 30 19:26:12 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:26:12 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 30 19:26:15 volumio qobuz-connect[2237]: 20250630 19:26:15.949 [2237.3699] INFO SampleApp: API endpoint invoked: get-display-info Jun 30 19:26:15 volumio qobuz-connect[2237]: 20250630 19:26:15.980 [2237.3699] INFO SampleApp: API endpoint invoked: get-connect-info Jun 30 19:26:18 volumio qobuz-connect[2237]: 20250630 19:26:18.752 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Need to send state update Jun 30 19:26:18 volumio qobuz-connect[2237]: 20250630 19:26:18.752 [2237.2237] INFO QConnectMessageSender: [0x10585a0]: Sending StateUpdated message Jun 30 19:26:18 volumio qobuz-connect[2237]: 20250630 19:26:18.752 [2237.2237] INFO QwspMessageSender: [0x1121470]: Sending Payload message Jun 30 19:26:18 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:26:18 volumio volumio[1211]: info: CoreCommandRouter::servicePushState Jun 30 19:26:18 volumio volumio[1211]: info: CoreStateMachine::pushState Jun 30 19:26:18 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 19:26:18 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Jun 30 19:26:18 volumio volumio[1211]: info: MRS: Pushing multiroomSync output update for this device Jun 30 19:26:18 volumio volumio[1211]: info: MRS: Pushing multiroomSync output Jun 30 19:26:18 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:26:19 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 30 19:26:19 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 30 19:26:19 volumio volumio[1211]: info: Discovery: Getting this device information Jun 30 19:26:19 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:26:19 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 30 19:26:27 volumio volumio[1211]: info: CoreCommandRouter::volumioPause Jun 30 19:26:27 volumio volumio[1211]: info: CoreStateMachine::pause Jun 30 19:26:27 volumio volumio[1211]: info: CoreStateMachine::stPlaybackTimer Jun 30 19:26:27 volumio volumio[1211]: info: CoreStateMachine::servicePause Jun 30 19:26:27 volumio volumio[1211]: info: CoreCommandRouter::servicePause Jun 30 19:26:27 volumio volumio[1211]: info: QobuzConnect: Received pause Jun 30 19:26:27 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:26:27 volumio qobuz-connect[2237]: 20250630 19:26:27.306 [2237.2237] INFO MediaEngine: [0x1018480]: Pausing playback Jun 30 19:26:27 volumio qobuz-connect[2237]: 20250630 19:26:27.306 [2237.2237] INFO AudioRenderer: [0x10186a0]: Pausing audio renderer Jun 30 19:26:27 volumio qobuz-connect[2237]: 20250630 19:26:27.306 [2237.2237] INFO SampleApp: Pausing playback Jun 30 19:26:27 volumio qobuz-connect[2237]: 20250630 19:26:27.306 [2237.2237] INFO SampleApp: Playback state changed: Paused Jun 30 19:26:27 volumio qobuz-connect[2237]: 20250630 19:26:27.306 [2237.2237] INFO StreamingEventReporter: [0x1018fd8]: Reporting streaming ended event Jun 30 19:26:27 volumio qobuz-connect[2237]: 20250630 19:26:27.307 [2237.2237] INFO HttpDownloader: [0x1019030]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jun 30 19:26:27 volumio qobuz-connect[2237]: 20250630 19:26:27.307 [2237.2237] INFO ProtocolHandler: [0x1018bc0]: Need to send state update Jun 30 19:26:27 volumio qobuz-connect[2237]: 20250630 19:26:27.307 [2237.2237] INFO QConnectMessageSender: [0x10585a0]: Sending StateUpdated message Jun 30 19:26:27 volumio qobuz-connect[2237]: 20250630 19:26:27.307 [2237.2237] INFO QwspMessageSender: [0x1121470]: Sending Payload message Jun 30 19:26:27 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:26:27 volumio volumio[1211]: info: CoreCommandRouter::servicePushState Jun 30 19:26:27 volumio volumio[1211]: info: CoreStateMachine::pushState Jun 30 19:26:27 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 19:26:27 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Jun 30 19:26:27 volumio volumio[1211]: info: MRS: Pushing multiroomSync output update for this device Jun 30 19:26:27 volumio volumio[1211]: info: MRS: Pushing multiroomSync output Jun 30 19:26:27 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:26:27 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:26:27 volumio volumio[1211]: info: CoreCommandRouter::servicePushState Jun 30 19:26:27 volumio volumio[1211]: info: CoreStateMachine::pushState Jun 30 19:26:27 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 30 19:26:27 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Jun 30 19:26:27 volumio volumio[1211]: info: MRS: Pushing multiroomSync output update for this device Jun 30 19:26:27 volumio volumio[1211]: info: MRS: Pushing multiroomSync output Jun 30 19:26:27 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:26:27 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:26:27 volumio volumio[1211]: info: CoreCommandRouter::servicePushState Jun 30 19:26:27 volumio volumio[1211]: info: CoreStateMachine::pushState Jun 30 19:26:27 volumio volumio[1211]: info: CoreCommandRouter::volumioPushState Jun 30 19:26:27 volumio volumio[1211]: info: MRS: Pushing multiroomSync output update for this device Jun 30 19:26:27 volumio volumio[1211]: info: MRS: Pushing multiroomSync output Jun 30 19:26:27 volumio volumio[1211]: info: CoreCommandRouter::volumioGetState Jun 30 19:26:27 volumio volumio[1211]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 30 19:26:33 volumio volumio[1211]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 30 19:26:33 volumio volumio[1211]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 30 19:26:33 volumio volumio[1211]: info: Retrieving Cloud Streaming UI Jun 30 19:26:33 volumio volumio[1211]: info: Getting Tidal Cloud Configuration Jun 30 19:26:33 volumio volumio[1211]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 30 19:26:33 volumio volumio[1211]: info: Getting Qobuz Cloud Configuration Jun 30 19:26:33 volumio volumio[1211]: info: Asking plugin for UI Config Jun 30 19:26:33 volumio volumio[1211]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 30 19:26:33 volumio volumio[1211]: info: Getting Spotify Cloud Configuration Jun 30 19:26:33 volumio volumio[1211]: info: Asking plugin for UI Config Jun 30 19:26:33 volumio volumio[1211]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 30 19:26:33 volumio volumio[1211]: info: Saving Spotify Acccount Jun 30 19:26:33 volumio volumio[1211]: info: Got it Jun 30 19:26:33 volumio volumio[1211]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 30 19:26:33 volumio volumio[1211]: info: Got Tidal Cloud Configuration Jun 30 19:26:33 volumio volumio[1211]: info: Got it Jun 30 19:26:33 volumio volumio[1211]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 30 19:26:33 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 30 19:26:33 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 30 19:26:33 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 30 19:26:33 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 30 19:26:33 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 30 19:26:33 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 30 19:26:33 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 30 19:26:33 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 30 19:26:33 volumio volumio[1211]: info: CoreCommandRouter::volumioGetBrowseSources Jun 30 19:26:33 volumio volumio[1211]: info: CoreCommandRouter::volumioGetBrowseSources Jun 30 19:26:33 volumio volumio[1211]: info: CoreCommandRouter::volumioGetBrowseSources Jun 30 19:26:34 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 30 19:26:34 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 30 19:26:38 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 30 19:26:42 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 30 19:26:42 volumio volumio[1211]: info: Disabling MyMusic plugin upnp Jun 30 19:26:42 volumio sudo[4882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jun 30 19:26:42 volumio sudo[4882]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 30 19:26:42 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Jun 30 19:26:42 volumio volumio[1211]: error: Upnp client error: Error: This socket has been ended by the other party Jun 30 19:26:42 volumio volumio[1211]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 30 19:26:46 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 30 19:26:46 volumio volumio[1211]: info: Enabling MyMusic plugin upnp Jun 30 19:26:46 volumio volumio[1211]: info: Enabling plugin upnp Jun 30 19:26:46 volumio volumio[1211]: info: Loading plugin "upnp"... Jun 30 19:26:46 volumio volumio[1211]: info: [1751304406162] Starting Upmpd Daemon Jun 30 19:26:46 volumio volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 30 19:26:46 volumio volumio[1211]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 30 19:26:46 volumio volumio[1211]: Error: listen EADDRINUSE: address already in use :::6599 Jun 30 19:26:46 volumio volumio[1211]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Jun 30 19:26:46 volumio volumio[1211]: at listenInCluster (net.js:1366:12) Jun 30 19:26:46 volumio volumio[1211]: at Server.listen (net.js:1452:7) Jun 30 19:26:46 volumio volumio[1211]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Jun 30 19:26:46 volumio volumio[1211]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Jun 30 19:26:46 volumio volumio[1211]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jun 30 19:26:46 volumio volumio[1211]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 30 19:26:46 volumio volumio[1211]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Jun 30 19:26:46 volumio volumio[1211]: code: 'EADDRINUSE', Jun 30 19:26:46 volumio volumio[1211]: errno: -98, Jun 30 19:26:46 volumio volumio[1211]: syscall: 'listen', Jun 30 19:26:46 volumio volumio[1211]: address: '::', Jun 30 19:26:46 volumio volumio[1211]: port: 6599 Jun 30 19:26:46 volumio volumio[1211]: } Jun 30 19:26:46 volumio volumio[1211]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 30 19:26:46 volumio sudo[4907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-30 19:25 Jun 30 19:26:46 volumio sudo[4907]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"