-- Logs begin at Thu 2019-02-14 05:11:59 EST, end at Fri 2025-05-30 06:15:31 EDT. -- May 30 06:14:00 audio-1 qobuz-connect[1287]: 20250530 06:14:00.897 [1287.1287] INFO AudioStreamManager: [0x2003830]: [Stream 88]: Running audio stream May 30 06:14:00 audio-1 qobuz-connect[1287]: 20250530 06:14:00.897 [1287.1287] INFO UrlAudioSource: [0x260d260]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=1478571&eid=106784831&fmt=6&profile=raw&app_id=174516466&cid=1496927&etsp=1748603447&hmac=coocJYqEyQLzuG6ustTPemMOmWM May 30 06:14:00 audio-1 qobuz-connect[1287]: 20250530 06:14:00.899 [1287.1287] INFO ContentFetcher: [0x27a7640]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=1478571&eid=106784831&fmt=6&profile=raw&app_id=174516466&cid=1496927&etsp=1748603447&hmac=coocJYqEyQLzuG6ustTPemMOmWM, offset: 0 May 30 06:14:00 audio-1 qobuz-connect[1287]: 20250530 06:14:00.938 [1287.1287] INFO SampleApp: [Stream 88]: New audio stream (starting from 0ms) May 30 06:14:00 audio-1 qobuz-connect[1287]: 20250530 06:14:00.939 [1287.1287] INFO SampleApp: [Stream 88]: Stream metadata became available: May 30 06:14:00 audio-1 qobuz-connect[1287]: 20250530 06:14:00.939 [1287.1287] INFO SampleApp: Title: Suite in D Minor: III. Courante May 30 06:14:00 audio-1 qobuz-connect[1287]: 20250530 06:14:00.939 [1287.1287] INFO SampleApp: Artist: Pascal Boëls May 30 06:14:00 audio-1 qobuz-connect[1287]: 20250530 06:14:00.939 [1287.1287] INFO SampleApp: Album: The Golden Age of the Guitar in Europe May 30 06:14:00 audio-1 qobuz-connect[1287]: 20250530 06:14:00.939 [1287.1287] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/2b/sm/p26siq501sm2b_600.jpg May 30 06:14:00 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:01 audio-1 qobuz-connect[1287]: 20250530 06:14:01.718 [1287.1287] INFO UrlAudioSource: [0x260d260]: Content info extracted May 30 06:14:01 audio-1 qobuz-connect[1287]: 20250530 06:14:01.718 [1287.1287] INFO UrlAudioSource: [0x260d260]: Size: 6166674 May 30 06:14:01 audio-1 qobuz-connect[1287]: 20250530 06:14:01.719 [1287.13917] INFO AudioDecoder: [0x20e0540]: Stream info: May 30 06:14:01 audio-1 qobuz-connect[1287]: 20250530 06:14:01.719 [1287.13917] INFO AudioDecoder: [0x20e0540]: Total samples: 4578756 May 30 06:14:01 audio-1 qobuz-connect[1287]: 20250530 06:14:01.719 [1287.13917] INFO AudioDecoder: [0x20e0540]: Sample rate: 44100 May 30 06:14:01 audio-1 qobuz-connect[1287]: 20250530 06:14:01.719 [1287.13917] INFO AudioDecoder: [0x20e0540]: Channel count: 2 May 30 06:14:01 audio-1 qobuz-connect[1287]: 20250530 06:14:01.719 [1287.13917] INFO AudioDecoder: [0x20e0540]: Bits per sample: 16 May 30 06:14:01 audio-1 qobuz-connect[1287]: 20250530 06:14:01.719 [1287.13917] INFO AudioDecoder: [0x20e0540]: Duration: 103826 May 30 06:14:01 audio-1 qobuz-connect[1287]: 20250530 06:14:01.719 [1287.1287] INFO UrlAudioSource: [0x260d260]: Audio properties have changed May 30 06:14:04 audio-1 qobuz-connect[1287]: 20250530 06:14:04.958 [1287.1287] INFO AudioStreamManager: [0x2003830]: [Stream 87]: Audio stream has finished May 30 06:14:04 audio-1 qobuz-connect[1287]: 20250530 06:14:04.958 [1287.1287] INFO SampleApp: [Stream 87]: Audio stream finished May 30 06:14:06 audio-1 qobuz-connect[1287]: 20250530 06:14:06.880 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Need to send state update May 30 06:14:06 audio-1 qobuz-connect[1287]: 20250530 06:14:06.880 [1287.1287] INFO QConnectMessageSender: [0x20a1ab8]: Sending StateUpdated message May 30 06:14:06 audio-1 qobuz-connect[1287]: 20250530 06:14:06.880 [1287.1287] INFO QwspMessageSender: [0x20a1aa8]: Sending Payload message May 30 06:14:06 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:06 audio-1 volumio[854]: info: CoreCommandRouter::servicePushState May 30 06:14:06 audio-1 volumio[854]: info: CoreStateMachine::pushState May 30 06:14:06 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 06:14:06 audio-1 volumio[854]: info: CoreCommandRouter::volumioPushState May 30 06:14:06 audio-1 volumio[854]: info: MRS: Pushing multiroomSync output update for this device May 30 06:14:06 audio-1 volumio[854]: info: MRS: Pushing multiroomSync output May 30 06:14:06 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:07 audio-1 qobuz-connect[1287]: 20250530 06:14:07.899 [1287.1287] INFO SampleApp: Playback is nearly finished May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.899 [1287.1287] INFO SampleApp: Playback has finished May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.899 [1287.1287] INFO AudioStreamManager: [0x2003830]: [Stream 87]: Disposing of audio stream May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.899 [1287.1287] INFO ContentFetcher: [0x204b880]: Canceling download May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.899 [1287.1287] INFO SampleApp: [Stream 87]: Audio stream has been disposed of May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.900 [1287.1287] INFO StreamingEventReporter: [0x20042a0]: Reporting streaming ended event May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.900 [1287.1287] INFO HttpDownloader: [0x20042f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.900 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Need to send state update May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.900 [1287.1287] INFO QConnectMessageSender: [0x20a1ab8]: Sending StateUpdated message May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.901 [1287.1287] INFO QwspMessageSender: [0x20a1aa8]: Sending Payload message May 30 06:14:10 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:10 audio-1 volumio[854]: info: CoreCommandRouter::servicePushState May 30 06:14:10 audio-1 volumio[854]: info: CoreStateMachine::pushState May 30 06:14:10 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 06:14:10 audio-1 volumio[854]: info: CoreCommandRouter::volumioPushState May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.903 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Need to send state update May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.904 [1287.1287] INFO QConnectMessageSender: [0x20a1ab8]: Sending StateUpdated message May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.904 [1287.1287] INFO QwspMessageSender: [0x20a1aa8]: Sending Payload message May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.904 [1287.1287] INFO QConnectMessageSender: [0x20a1ab8]: Sending FileAudioQualityChanged message May 30 06:14:10 audio-1 volumio[854]: info: MRS: Pushing multiroomSync output update for this device May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.905 [1287.1287] INFO QwspMessageSender: [0x20a1aa8]: Sending Payload message May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.905 [1287.1287] INFO StreamingEventReporter: [0x20042a0]: Reporting streaming started event May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.906 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Need to send state update May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.906 [1287.1287] INFO QConnectMessageSender: [0x20a1ab8]: Sending StateUpdated message May 30 06:14:10 audio-1 volumio[854]: info: MRS: Pushing multiroomSync output May 30 06:14:10 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.907 [1287.1287] INFO QwspMessageSender: [0x20a1aa8]: Sending Payload message May 30 06:14:10 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:10 audio-1 volumio[854]: info: CoreCommandRouter::servicePushState May 30 06:14:10 audio-1 volumio[854]: info: CoreStateMachine::pushState May 30 06:14:10 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 06:14:10 audio-1 volumio[854]: info: CoreCommandRouter::volumioPushState May 30 06:14:10 audio-1 volumio[854]: info: MRS: Pushing multiroomSync output update for this device May 30 06:14:10 audio-1 volumio[854]: info: MRS: Pushing multiroomSync output May 30 06:14:10 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:10 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:10 audio-1 volumio[854]: info: CoreCommandRouter::servicePushState May 30 06:14:10 audio-1 volumio[854]: info: CoreStateMachine::pushState May 30 06:14:10 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 06:14:10 audio-1 volumio[854]: info: CoreCommandRouter::volumioPushState May 30 06:14:10 audio-1 volumio[854]: info: MRS: Pushing multiroomSync output update for this device May 30 06:14:10 audio-1 volumio[854]: info: MRS: Pushing multiroomSync output May 30 06:14:10 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:10 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:10 audio-1 volumio[854]: info: CoreCommandRouter::servicePushState May 30 06:14:10 audio-1 volumio[854]: info: CoreStateMachine::pushState May 30 06:14:10 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 06:14:10 audio-1 volumio[854]: info: CoreCommandRouter::volumioPushState May 30 06:14:10 audio-1 volumio[854]: info: MRS: Pushing multiroomSync output update for this device May 30 06:14:10 audio-1 volumio[854]: info: MRS: Pushing multiroomSync output May 30 06:14:10 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.923 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Received SetState message: May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.923 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Playing state: May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.923 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Playback position: May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.923 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Queue version: 5.1 May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.923 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Current track: May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.923 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Next track: TID: 106784832, QID: 114, Context UUID: 146fa831-b41e-4c66-a0f7-2586bd612377 May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.923 [1287.1287] INFO MediaEngine: [0x2003748]: Setting next track: 106784832 May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.923 [1287.1287] INFO AudioStreamManager: [0x2003830]: New stream: 89 May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.923 [1287.1287] INFO HttpDownloader: [0x269e038]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=009f663587973335ff6c6b25fd560727&request_ts=1748600050&track_id=106784832 May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.924 [1287.1287] INFO HttpDownloader: [0x2044f18]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=106784832 May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.924 [1287.1287] INFO HttpDownloader: [0x20379e8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=61c7d5d86322cffea3e1b42346aecaae&request_ts=1748600050&track_id=106784832 May 30 06:14:10 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:10 audio-1 volumio[854]: info: CoreCommandRouter::servicePushState May 30 06:14:10 audio-1 volumio[854]: info: CoreStateMachine::pushState May 30 06:14:10 audio-1 volumio[854]: info: CoreCommandRouter::volumioPushState May 30 06:14:10 audio-1 volumio[854]: info: MRS: Pushing multiroomSync output update for this device May 30 06:14:10 audio-1 volumio[854]: info: MRS: Pushing multiroomSync output May 30 06:14:10 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.929 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Received SetState message: May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.929 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Playing state: May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.929 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Playback position: May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.929 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Queue version: 5.1 May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.929 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Current track: May 30 06:14:10 audio-1 qobuz-connect[1287]: 20250530 06:14:10.929 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Next track: TID: 106784832, QID: 114, Context UUID: 146fa831-b41e-4c66-a0f7-2586bd612377 May 30 06:14:11 audio-1 qobuz-connect[1287]: 20250530 06:14:11.189 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Received SetState message: May 30 06:14:11 audio-1 qobuz-connect[1287]: 20250530 06:14:11.189 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Playing state: May 30 06:14:11 audio-1 qobuz-connect[1287]: 20250530 06:14:11.189 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Playback position: May 30 06:14:11 audio-1 qobuz-connect[1287]: 20250530 06:14:11.189 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Queue version: 5.1 May 30 06:14:11 audio-1 qobuz-connect[1287]: 20250530 06:14:11.189 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Current track: May 30 06:14:11 audio-1 qobuz-connect[1287]: 20250530 06:14:11.189 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Next track: TID: 106784832, QID: 114, Context UUID: 146fa831-b41e-4c66-a0f7-2586bd612377 May 30 06:14:15 audio-1 qobuz-connect[1287]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:912:(_snd_pcm_volumioswitch_advance) PCM volumioMultiRoomServer cannot write to target PCM volumioOutput as it has failed its update check. May 30 06:14:15 audio-1 qobuz-connect[1287]: 20250530 06:14:15.967 [1287.1287] WARNING SampleApp: Unable to query available space in ALSA audio sink, trying to recover May 30 06:14:15 audio-1 qobuz-connect[1287]: 20250530 06:14:15.969 [1287.1287] INFO AudioStreamManager: [0x2003830]: [Stream 89]: Metadata became available: May 30 06:14:15 audio-1 qobuz-connect[1287]: 20250530 06:14:15.969 [1287.1287] INFO AudioStreamManager: [0x2003830]: Title: Suite in D Minor: IV. Sarabande I May 30 06:14:15 audio-1 qobuz-connect[1287]: 20250530 06:14:15.969 [1287.1287] INFO AudioStreamManager: [0x2003830]: Artist: Pascal Boëls May 30 06:14:15 audio-1 qobuz-connect[1287]: 20250530 06:14:15.969 [1287.1287] INFO AudioStreamManager: [0x2003830]: Album: The Golden Age of the Guitar in Europe May 30 06:14:15 audio-1 qobuz-connect[1287]: 20250530 06:14:15.969 [1287.1287] INFO AudioStreamManager: [0x2003830]: Album art URL: https://static.qobuz.com/images/covers/2b/sm/p26siq501sm2b_600.jpg May 30 06:14:15 audio-1 qobuz-connect[1287]: 20250530 06:14:15.970 [1287.1287] INFO HttpDownloader: [0x20042f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart May 30 06:14:15 audio-1 qobuz-connect[1287]: 20250530 06:14:15.972 [1287.1287] INFO AudioStreamManager: [0x2003830]: [Stream 89]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1478571&eid=106784832&fmt=6&profile=raw&app_id=174516466&cid=1496927&etsp=1748603651&hmac=YJTEjlUJNsUWSAjpCWJt0tXJG2E May 30 06:14:15 audio-1 qobuz-connect[1287]: 20250530 06:14:15.972 [1287.1287] INFO AudioStreamManager: [0x2003830]: [Stream 89]: stream information have been fetched May 30 06:14:20 audio-1 qobuz-connect[1287]: 20250530 06:14:20.905 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Need to send state update May 30 06:14:20 audio-1 qobuz-connect[1287]: 20250530 06:14:20.905 [1287.1287] INFO QConnectMessageSender: [0x20a1ab8]: Sending StateUpdated message May 30 06:14:20 audio-1 qobuz-connect[1287]: 20250530 06:14:20.905 [1287.1287] INFO QwspMessageSender: [0x20a1aa8]: Sending Payload message May 30 06:14:20 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:20 audio-1 volumio[854]: info: CoreCommandRouter::servicePushState May 30 06:14:20 audio-1 volumio[854]: info: CoreStateMachine::pushState May 30 06:14:20 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 06:14:20 audio-1 volumio[854]: info: CoreCommandRouter::volumioPushState May 30 06:14:20 audio-1 volumio[854]: info: MRS: Pushing multiroomSync output update for this device May 30 06:14:20 audio-1 volumio[854]: info: MRS: Pushing multiroomSync output May 30 06:14:20 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:30 audio-1 qobuz-connect[1287]: 20250530 06:14:30.905 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Need to send state update May 30 06:14:30 audio-1 qobuz-connect[1287]: 20250530 06:14:30.905 [1287.1287] INFO QConnectMessageSender: [0x20a1ab8]: Sending StateUpdated message May 30 06:14:30 audio-1 qobuz-connect[1287]: 20250530 06:14:30.906 [1287.1287] INFO QwspMessageSender: [0x20a1aa8]: Sending Payload message May 30 06:14:30 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:30 audio-1 volumio[854]: info: CoreCommandRouter::servicePushState May 30 06:14:30 audio-1 volumio[854]: info: CoreStateMachine::pushState May 30 06:14:30 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 06:14:30 audio-1 volumio[854]: info: CoreCommandRouter::volumioPushState May 30 06:14:30 audio-1 volumio[854]: info: MRS: Pushing multiroomSync output update for this device May 30 06:14:30 audio-1 volumio[854]: info: MRS: Pushing multiroomSync output May 30 06:14:30 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:37 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 06:14:37 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 06:14:37 audio-1 volumio[854]: info: Discovery: Getting this device information May 30 06:14:37 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:37 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 06:14:37 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 06:14:37 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 06:14:37 audio-1 volumio[854]: info: Discovery: Getting this device information May 30 06:14:37 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:37 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 06:14:37 audio-1 volumiologrotate[570]: ls: cannot access '/var/log/samba/log.wb-AUDIO': No such file or directory May 30 06:14:37 audio-1 volumiologrotate[570]: ls: cannot access '1': No such file or directory May 30 06:14:38 audio-1 volumio[854]: verbose: New Socket.io Connection to 192.168.1.129:3000 from 192.168.1.124 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 May 30 06:14:39 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:40 audio-1 sudo[14029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 30 06:14:40 audio-1 sudo[14029]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 06:14:40 audio-1 sudo[14029]: pam_unix(sudo:session): session closed for user root May 30 06:14:40 audio-1 sudo[14032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 06:14:40 audio-1 sudo[14032]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 06:14:40 audio-1 sudo[14032]: pam_unix(sudo:session): session closed for user root May 30 06:14:40 audio-1 volumio[854]: verbose: New Socket.io Connection to 192.168.1.129 from 192.168.1.124 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 May 30 06:14:40 audio-1 sudo[14035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 30 06:14:40 audio-1 sudo[14035]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 06:14:40 audio-1 sudo[14035]: pam_unix(sudo:session): session closed for user root May 30 06:14:40 audio-1 sudo[14038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 06:14:40 audio-1 sudo[14038]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 06:14:40 audio-1 sudo[14038]: pam_unix(sudo:session): session closed for user root May 30 06:14:40 audio-1 volumio[854]: verbose: New Socket.io Connection to 192.168.1.129 from 192.168.1.124 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 May 30 06:14:40 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 30 06:14:40 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 30 06:14:40 audio-1 qobuz-connect[1287]: 20250530 06:14:40.906 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Need to send state update May 30 06:14:40 audio-1 qobuz-connect[1287]: 20250530 06:14:40.906 [1287.1287] INFO QConnectMessageSender: [0x20a1ab8]: Sending StateUpdated message May 30 06:14:40 audio-1 qobuz-connect[1287]: 20250530 06:14:40.906 [1287.1287] INFO QwspMessageSender: [0x20a1aa8]: Sending Payload message May 30 06:14:40 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:40 audio-1 volumio[854]: info: CoreCommandRouter::servicePushState May 30 06:14:40 audio-1 volumio[854]: info: CoreStateMachine::pushState May 30 06:14:40 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 06:14:40 audio-1 volumio[854]: info: CoreCommandRouter::volumioPushState May 30 06:14:40 audio-1 volumio[854]: info: MRS: Pushing multiroomSync output update for this device May 30 06:14:40 audio-1 volumio[854]: info: MRS: Pushing multiroomSync output May 30 06:14:40 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:40 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetVisibleSources May 30 06:14:40 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 06:14:40 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:40 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 30 06:14:40 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 30 06:14:40 audio-1 volumio[854]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 30 06:14:40 audio-1 volumio[854]: info: Received Get System Info May 30 06:14:40 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 06:14:40 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 06:14:40 audio-1 volumio[854]: info: Discovery: Getting this device information May 30 06:14:40 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:40 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 06:14:40 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:40 audio-1 volumio[854]: info: Listing playlists May 30 06:14:40 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 30 06:14:40 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 30 06:14:42 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 30 06:14:42 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 30 06:14:42 audio-1 volumio[854]: info: Received Get System Info May 30 06:14:42 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 06:14:42 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 06:14:42 audio-1 volumio[854]: info: Discovery: Getting this device information May 30 06:14:42 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:42 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 06:14:42 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 30 06:14:43 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 06:14:43 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 06:14:43 audio-1 volumio[854]: info: Discovery: Getting this device information May 30 06:14:43 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:43 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 06:14:45 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 30 06:14:45 audio-1 volumio[854]: info: Received Get System Info May 30 06:14:45 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 06:14:45 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 06:14:45 audio-1 volumio[854]: info: Discovery: Getting this device information May 30 06:14:45 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:45 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 06:14:45 audio-1 volumio[854]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 06:14:45 audio-1 volumio[854]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 06:14:45 audio-1 volumio[854]: info: Retrieving Cloud Streaming UI May 30 06:14:45 audio-1 volumio[854]: info: Getting Tidal Cloud Configuration May 30 06:14:45 audio-1 volumio[854]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 06:14:45 audio-1 volumio[854]: info: Getting Qobuz Cloud Configuration May 30 06:14:45 audio-1 volumio[854]: info: Asking plugin for UI Config May 30 06:14:45 audio-1 volumio[854]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 06:14:45 audio-1 volumio[854]: info: Getting Spotify Cloud Configuration May 30 06:14:45 audio-1 volumio[854]: info: Asking plugin for UI Config May 30 06:14:45 audio-1 volumio[854]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 06:14:45 audio-1 volumio[854]: info: Saving Spotify Acccount May 30 06:14:45 audio-1 volumio[854]: info: Got it May 30 06:14:45 audio-1 volumio[854]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 30 06:14:45 audio-1 volumio[854]: info: Got Tidal Cloud Configuration May 30 06:14:45 audio-1 volumio[854]: info: Got it May 30 06:14:45 audio-1 volumio[854]: info: CoreCommandRouter::getUIConfigOnPlugin May 30 06:14:45 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 30 06:14:45 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 30 06:14:45 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 30 06:14:45 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 30 06:14:45 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 06:14:45 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 06:14:45 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 30 06:14:45 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetBrowseSources May 30 06:14:45 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetBrowseSources May 30 06:14:45 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetBrowseSources May 30 06:14:45 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 06:14:45 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 30 06:14:49 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 06:14:50 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 30 06:14:50 audio-1 qobuz-connect[1287]: 20250530 06:14:50.905 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Need to send state update May 30 06:14:50 audio-1 qobuz-connect[1287]: 20250530 06:14:50.905 [1287.1287] INFO QConnectMessageSender: [0x20a1ab8]: Sending StateUpdated message May 30 06:14:50 audio-1 qobuz-connect[1287]: 20250530 06:14:50.905 [1287.1287] INFO QwspMessageSender: [0x20a1aa8]: Sending Payload message May 30 06:14:50 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:50 audio-1 volumio[854]: info: CoreCommandRouter::servicePushState May 30 06:14:50 audio-1 volumio[854]: info: CoreStateMachine::pushState May 30 06:14:50 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 06:14:50 audio-1 volumio[854]: info: CoreCommandRouter::volumioPushState May 30 06:14:50 audio-1 volumio[854]: info: MRS: Pushing multiroomSync output update for this device May 30 06:14:50 audio-1 volumio[854]: info: MRS: Pushing multiroomSync output May 30 06:14:50 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:52 audio-1 volumio[854]: info: Disabling MyMusic plugin upnp May 30 06:14:52 audio-1 sudo[14059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service May 30 06:14:52 audio-1 sudo[14059]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 06:14:52 audio-1 systemd[1]: Stopping UPnP Renderer front-end to MPD... May 30 06:14:52 audio-1 volumio[854]: error: Upnp client error: Error: This socket has been ended by the other party May 30 06:14:52 audio-1 volumio[854]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 06:14:53 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 06:14:54 audio-1 volumio[854]: info: Disabling MyMusic plugin airplay_emulation May 30 06:14:54 audio-1 volumio[854]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync May 30 06:14:54 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 06:14:54 audio-1 volumio[854]: info: Disabling plugin airplay_emulation May 30 06:14:54 audio-1 volumio[854]: info: Done. May 30 06:14:54 audio-1 sudo[14062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync May 30 06:14:54 audio-1 sudo[14062]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 06:14:54 audio-1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 30 06:14:54 audio-1 systemd[1]: shairport-sync.service: Succeeded. May 30 06:14:54 audio-1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 30 06:14:54 audio-1 volumio[854]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 06:14:54 audio-1 sudo[14062]: pam_unix(sudo:session): session closed for user root May 30 06:14:54 audio-1 volumio[854]: info: Shairport-Sync Stopped May 30 06:14:55 audio-1 volumio[854]: info: Enabling MyMusic plugin airplay_emulation May 30 06:14:55 audio-1 volumio[854]: info: Enabling plugin airplay_emulation May 30 06:14:55 audio-1 volumio[854]: info: Loading plugin "airplay_emulation"... May 30 06:14:55 audio-1 volumio[854]: info: Starting Shairport Sync May 30 06:14:55 audio-1 volumio[854]: info: PLUGIN START: airplay_emulation May 30 06:14:55 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:14:55 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 06:14:55 audio-1 volumio[854]: xcb_connection_has_error() returned true May 30 06:14:55 audio-1 volumio[854]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 30 06:14:55 audio-1 volumio[854]: xcb_connection_has_error() returned true May 30 06:14:55 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 30 06:14:55 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 06:14:56 audio-1 volumio[854]: xcb_connection_has_error() returned true May 30 06:14:56 audio-1 volumio[854]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 30 06:14:56 audio-1 volumio[854]: xcb_connection_has_error() returned true May 30 06:14:56 audio-1 volumio[854]: info: Done. May 30 06:14:56 audio-1 volumio[854]: info: Starting Shairport Sync May 30 06:14:56 audio-1 volumio[854]: info: Starting Shairport Sync May 30 06:14:56 audio-1 sudo[14086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 30 06:14:56 audio-1 sudo[14086]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 06:14:56 audio-1 sudo[14089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 30 06:14:56 audio-1 sudo[14089]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 06:14:56 audio-1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 30 06:14:56 audio-1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 30 06:14:56 audio-1 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 30 06:14:56 audio-1 sudo[14086]: pam_unix(sudo:session): session closed for user root May 30 06:14:56 audio-1 systemd[1]: shairport-sync.service: Succeeded. May 30 06:14:56 audio-1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 30 06:14:56 audio-1 volumio[854]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 06:14:56 audio-1 volumio[854]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 30 06:14:56 audio-1 volumio[854]: info: Shairport-Sync Started May 30 06:14:56 audio-1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 30 06:14:56 audio-1 sudo[14089]: pam_unix(sudo:session): session closed for user root May 30 06:14:56 audio-1 volumio[854]: info: Shairport-Sync Started May 30 06:14:57 audio-1 volumio[854]: info: Disabling MyMusic plugin upnp May 30 06:14:57 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 06:14:57 audio-1 sudo[14096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service May 30 06:14:57 audio-1 sudo[14096]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 06:14:57 audio-1 volumio[854]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 06:14:59 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 30 06:14:59 audio-1 volumio[854]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 30 06:14:59 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 30 06:14:59 audio-1 volumio[854]: info: Received Get System Version May 30 06:14:59 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 30 06:14:59 audio-1 volumio[854]: info: Received Get System Info May 30 06:14:59 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 06:14:59 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 06:14:59 audio-1 volumio[854]: info: Discovery: Getting this device information May 30 06:14:59 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:14:59 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 06:15:00 audio-1 qobuz-connect[1287]: 20250530 06:15:00.906 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Need to send state update May 30 06:15:00 audio-1 qobuz-connect[1287]: 20250530 06:15:00.906 [1287.1287] INFO QConnectMessageSender: [0x20a1ab8]: Sending StateUpdated message May 30 06:15:00 audio-1 qobuz-connect[1287]: 20250530 06:15:00.906 [1287.1287] INFO QwspMessageSender: [0x20a1aa8]: Sending Payload message May 30 06:15:00 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:15:00 audio-1 volumio[854]: info: CoreCommandRouter::servicePushState May 30 06:15:00 audio-1 volumio[854]: info: CoreStateMachine::pushState May 30 06:15:00 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 06:15:00 audio-1 volumio[854]: info: CoreCommandRouter::volumioPushState May 30 06:15:00 audio-1 volumio[854]: info: MRS: Pushing multiroomSync output update for this device May 30 06:15:00 audio-1 volumio[854]: info: MRS: Pushing multiroomSync output May 30 06:15:00 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:15:01 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 06:15:02 audio-1 volumio[854]: info: Disabling MyMusic plugin upnp_browser May 30 06:15:02 audio-1 volumio[854]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMedia Servers May 30 06:15:02 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 06:15:02 audio-1 volumio[854]: info: Disabling plugin upnp_browser May 30 06:15:02 audio-1 volumio[854]: info: Done. May 30 06:15:05 audio-1 volumio[854]: info: Disabling MyMusic plugin upnp May 30 06:15:05 audio-1 sudo[14117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service May 30 06:15:05 audio-1 sudo[14117]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 06:15:05 audio-1 volumio[854]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 06:15:05 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 06:15:09 audio-1 volumio[854]: info: Disabling MyMusic plugin bluetooth May 30 06:15:09 audio-1 volumio[854]: info: [MyVolumio PluginManager] Disabling and stopping plugin audio_interface bluetooth May 30 06:15:09 audio-1 sudo[14120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumiobt.service May 30 06:15:09 audio-1 sudo[14120]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 06:15:09 audio-1 systemd[1]: Stopping Volumio Bluetooth Module... May 30 06:15:09 audio-1 volumiobt[14122]: Killing pulse May 30 06:15:09 audio-1 volumio[854]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 06:15:09 audio-1 volumiobt[14122]: Killing BT Pair agent May 30 06:15:09 audio-1 bluetoothd[683]: Endpoint unregistered: sender=:1.45 path=/MediaEndpoint/A2DPSource May 30 06:15:09 audio-1 bluetoothd[683]: Endpoint unregistered: sender=:1.45 path=/MediaEndpoint/A2DPSink May 30 06:15:09 audio-1 volumiobt[1317]: Terminated May 30 06:15:09 audio-1 systemd[1]: volumiobt.service: Main process exited, code=killed, status=15/TERM May 30 06:15:09 audio-1 systemd[1]: volumiobt.service: Succeeded. May 30 06:15:09 audio-1 systemd[1]: Stopped Volumio Bluetooth Module. May 30 06:15:09 audio-1 sudo[14120]: pam_unix(sudo:session): session closed for user root May 30 06:15:09 audio-1 volumio[854]: info: Volumio BT Module successfully stopped May 30 06:15:09 audio-1 volumio[854]: info: Disabling plugin bluetooth May 30 06:15:09 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 06:15:10 audio-1 qobuz-connect[1287]: 20250530 06:15:10.906 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Need to send state update May 30 06:15:10 audio-1 qobuz-connect[1287]: 20250530 06:15:10.906 [1287.1287] INFO QConnectMessageSender: [0x20a1ab8]: Sending StateUpdated message May 30 06:15:10 audio-1 qobuz-connect[1287]: 20250530 06:15:10.906 [1287.1287] INFO QwspMessageSender: [0x20a1aa8]: Sending Payload message May 30 06:15:10 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:15:10 audio-1 volumio[854]: info: CoreCommandRouter::servicePushState May 30 06:15:10 audio-1 volumio[854]: info: CoreStateMachine::pushState May 30 06:15:10 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 06:15:10 audio-1 volumio[854]: info: CoreCommandRouter::volumioPushState May 30 06:15:10 audio-1 volumio[854]: info: MRS: Pushing multiroomSync output update for this device May 30 06:15:10 audio-1 volumio[854]: info: MRS: Pushing multiroomSync output May 30 06:15:10 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:15:12 audio-1 volumio[854]: info: Disabling MyMusic plugin metavolumio May 30 06:15:12 audio-1 volumio[854]: info: [MyVolumio PluginManager] Disabling and stopping plugin miscellanea metavolumio May 30 06:15:12 audio-1 volumio[854]: info: Removing METAVOLUMIO REST API Endpoints May 30 06:15:12 audio-1 volumio[854]: info: Removing metavolumio REST Endpoint May 30 06:15:12 audio-1 volumio[854]: info: Removing getSimilarArtists REST Endpoint May 30 06:15:12 audio-1 volumio[854]: info: Removing getSimilarAlbums REST Endpoint May 30 06:15:12 audio-1 volumio[854]: info: Removing getSimilarTracks REST Endpoint May 30 06:15:12 audio-1 volumio[854]: info: Disabling plugin metavolumio May 30 06:15:13 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 06:15:13 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 06:15:13 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 06:15:13 audio-1 volumio[854]: info: Discovery: Getting this device information May 30 06:15:13 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:15:13 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 06:15:14 audio-1 volumio[854]: info: Disabling MyMusic plugin cd_controller May 30 06:15:14 audio-1 volumio[854]: info: [MyVolumio PluginManager] Disabling and stopping plugin music_service cd_controller May 30 06:15:14 audio-1 volumio[854]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD May 30 06:15:14 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 06:15:14 audio-1 volumio[854]: info: Stopping UDEV Watcher for CD May 30 06:15:14 audio-1 volumio[854]: info: Disabling plugin cd_controller May 30 06:15:16 audio-1 volumio[854]: info: Disabling MyMusic plugin qobuzconnect May 30 06:15:16 audio-1 volumio[854]: info: [MyVolumio PluginManager] Disabling and stopping plugin music_service qobuzconnect May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.719 [1287.1287] INFO MediaEngine: [0x2003748]: Stopping playback, keeping tracks May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.720 [1287.1287] INFO AudioRenderer: [0x2003968]: Stopping audio renderer May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.720 [1287.1287] INFO SampleApp: Stopping playback May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.721 [1287.1287] INFO AudioStreamManager: [0x2003830]: Clearing all streams May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.721 [1287.1287] INFO AudioStreamManager: [0x2003830]: [Stream 88]: Disposing of audio stream May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.721 [1287.1287] INFO ContentFetcher: [0x27a7640]: Canceling download May 30 06:15:16 audio-1 volumio[854]: info: QobuzConnect: setDeactiveState invoked May 30 06:15:16 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:15:16 audio-1 volumio[854]: info: QobuzConnect: setDeactiveState(): relinquishing Volumio State May 30 06:15:16 audio-1 volumio[854]: verbose: UNSET VOLATILE: Service: qobuzconnect May 30 06:15:16 audio-1 volumio[854]: info: QobuzConnect: onUnsetVolatile() called, relinquishing Volumio State to another service May 30 06:15:16 audio-1 volumio[854]: info: QobuzConnect: Received stop May 30 06:15:16 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.728 [1287.1287] INFO AudioStreamManager: [0x2003830]: New stream: 90 May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.728 [1287.1287] INFO HttpDownloader: [0x269e038]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=775ceb05049632ceee86fe7e978b6b28&request_ts=1748600116&track_id=106784831 May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.729 [1287.1287] INFO HttpDownloader: [0x21c56a0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=106784831 May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.729 [1287.1287] INFO HttpDownloader: [0x214afe0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=d523045d8d516d3c6a8696ff07433a1b&request_ts=1748600116&track_id=106784831 May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.729 [1287.1287] INFO AudioStreamManager: [0x2003830]: [Stream 90]: Running audio stream May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.729 [1287.1287] INFO AudioStreamManager: [0x2003830]: New stream: 91 May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.729 [1287.1287] INFO HttpDownloader: [0x23737e0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=fe4269063128fe90bb84ec5df34a4b04&request_ts=1748600116&track_id=106784832 May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.729 [1287.1287] INFO HttpDownloader: [0x204ba80]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=106784832 May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.729 [1287.1287] INFO HttpDownloader: [0x2413e08]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=1407bff184587fcaab0c1cd3a4c2120e&request_ts=1748600116&track_id=106784832 May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.729 [1287.1287] INFO MediaEngine: [0x2003748]: Stopping playback, keeping tracks May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.732 [1287.1287] INFO SampleApp: Playback state changed: Stopped May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.732 [1287.1287] INFO StreamingEventReporter: [0x20042a0]: Reporting streaming ended event May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.732 [1287.1287] INFO HttpDownloader: [0x20042f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.732 [1287.1287] INFO ProtocolHandler: [0x2003e88]: Need to send state update May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.732 [1287.1287] INFO QConnectMessageSender: [0x20a1ab8]: Sending StateUpdated message May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.732 [1287.1287] INFO SampleApp: [Stream 88]: Audio stream has been disposed of May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.732 [1287.1287] INFO QwspMessageSender: [0x20a1aa8]: Sending Payload message May 30 06:15:16 audio-1 volumio[854]: info: QobuzConnect: playback_stop received, deferred solved May 30 06:15:16 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:15:16 audio-1 volumio[854]: info: CorePlayQueue::getTrack 0 May 30 06:15:16 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:15:16 audio-1 volumio[854]: info: CorePlayQueue::getTrack 0 May 30 06:15:16 audio-1 sudo[14142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop qobuz-connect May 30 06:15:16 audio-1 sudo[14142]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.780 [1287.1287] INFO HttpDownloader: [0x214afe0]: Canceling download May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.780 [1287.1287] INFO HttpDownloader: [0x21c56a0]: Canceling download May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.780 [1287.1287] INFO HttpDownloader: [0x269e038]: Canceling download May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.780 [1287.1287] INFO HttpDownloader: [0x2413e08]: Canceling download May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.780 [1287.1287] INFO HttpDownloader: [0x204ba80]: Canceling download May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.780 [1287.1287] INFO HttpDownloader: [0x23737e0]: Canceling download May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.781 [1287.1287] INFO CloudClient: [0x2003d20]: Disconnecting from the cloud May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.781 [1287.1287] INFO CloudClient: [0x2003d20]: Connection closed May 30 06:15:16 audio-1 systemd[1]: Stopping Volumio Qobuz Connect Service... May 30 06:15:16 audio-1 volumio[854]: ------------------------------------ BT MESSAGE: BT STATUS: running May 30 06:15:16 audio-1 qobuz-connect[1287]: 20250530 06:15:16.813 [1287.1287] INFO HttpDownloader: [0x20042f8]: Canceling download May 30 06:15:16 audio-1 qobuz-connect[1287]: qobuz-connect-daemon: /home/builder/qobuz-connect-sdk/libqobuz_connect/details/network/network_manager.c:302: qbz_network_manager_on_network_client_curl_handle_changed: Assertion `error == CURLM_OK' failed. May 30 06:15:16 audio-1 systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT May 30 06:15:16 audio-1 volumio[854]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 30 06:15:16 audio-1 systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 30 06:15:16 audio-1 volumio[854]: info: QobuzConnect: setDeactiveState invoked May 30 06:15:16 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:15:16 audio-1 systemd[1]: Stopped Volumio Qobuz Connect Service. May 30 06:15:16 audio-1 volumio[854]: info: CorePlayQueue::getTrack 0 May 30 06:15:16 audio-1 sudo[14142]: pam_unix(sudo:session): session closed for user root May 30 06:15:16 audio-1 volumio[854]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket disconnected and tore down May 30 06:15:16 audio-1 volumio[854]: info: Disabling plugin qobuzconnect May 30 06:15:17 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 06:15:18 audio-1 volumio[854]: info: Enabling MyMusic plugin qobuzconnect May 30 06:15:18 audio-1 volumio[854]: info: [MyVolumio PluginManager] Enabling and starting plugin music_service qobuzconnect May 30 06:15:18 audio-1 volumio[854]: info: Enabling plugin qobuzconnect May 30 06:15:18 audio-1 volumio[854]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect May 30 06:15:18 audio-1 volumio[854]: info: Updating qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect May 30 06:15:18 audio-1 volumio[854]: info: QobuzConnect: Starting Qobuz Connect socket and service May 30 06:15:18 audio-1 volumio[854]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections May 30 06:15:18 audio-1 sudo[14148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 30 06:15:18 audio-1 sudo[14148]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 06:15:18 audio-1 sudo[14148]: pam_unix(sudo:session): session closed for user root May 30 06:15:18 audio-1 sudo[14150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 30 06:15:18 audio-1 sudo[14150]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 06:15:18 audio-1 systemd[1]: Started Volumio Qobuz Connect Service. May 30 06:15:18 audio-1 sudo[14150]: pam_unix(sudo:session): session closed for user root May 30 06:15:18 audio-1 volumio[854]: info: Done. May 30 06:15:18 audio-1 volumio[854]: info: Executing endpoint qc_getconfig May 30 06:15:18 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 30 06:15:18 audio-1 qobuz-connect[14152]: 20250530 06:15:18.793 [14152.14152] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 30 06:15:18 audio-1 volumio[854]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 30 06:15:18 audio-1 volumio[854]: info: QobuzConnect: QOBUZ Connect daemon connected May 30 06:15:18 audio-1 qobuz-connect[14152]: 20250530 06:15:18.799 [14152.14152] INFO VolumeManager: [0x592bb0]: Setting new playback volume: 75 May 30 06:15:18 audio-1 qobuz-connect[14152]: 20250530 06:15:18.799 [14152.14152] INFO VolumeManager: [0x592bb0]: Setting new mute state: 0 May 30 06:15:18 audio-1 qobuz-connect[14152]: 20250530 06:15:18.799 [14152.14152] INFO QobuzConnect: [0x593580]: Client initialized! May 30 06:15:18 audio-1 qobuz-connect[14152]: 20250530 06:15:18.799 [14152.14152] INFO SampleApp: Starting Avahi advertising, name: Audio 1, service name: _qobuz-connect._tcp May 30 06:15:18 audio-1 qobuz-connect[14152]: 20250530 06:15:18.813 [14152.14152] INFO LocalConfigManager: [0x592490]: Starting Local Configuration server May 30 06:15:18 audio-1 qobuz-connect[14152]: 20250530 06:15:18.813 [14152.14152] INFO SampleApp: Starting Local configuration server May 30 06:15:18 audio-1 qobuz-connect[14152]: 20250530 06:15:18.814 [14152.14152] INFO SampleApp: Connected to UNIX socket client 0x587f60 May 30 06:15:18 audio-1 qobuz-connect[14152]: 20250530 06:15:18.943 [14152.14152] INFO SampleApp: Playback volume changed: 75 May 30 06:15:18 audio-1 volumio[854]: info: CoreCommandRouter::volumioGetState May 30 06:15:18 audio-1 volumio[854]: info: CorePlayQueue::getTrack 0 May 30 06:15:21 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 06:15:23 audio-1 volumio[854]: info: Disabling MyMusic plugin smart_inputs May 30 06:15:23 audio-1 volumio[854]: info: [MyVolumio PluginManager] Disabling and stopping plugin music_service smart_inputs May 30 06:15:23 audio-1 volumio[854]: info: Removeing all smart Inputs May 30 06:15:23 audio-1 volumio[854]: info: Disabling plugin smart_inputs May 30 06:15:25 audio-1 volumio[854]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 06:15:30 audio-1 volumio[854]: info: CoreCommandRouter::volumioPrevious May 30 06:15:30 audio-1 volumio[854]: info: CoreStateMachine::previous May 30 06:15:30 audio-1 volumio[854]: info: CorePlayQueue::getTrack 0 May 30 06:15:30 audio-1 volumio[854]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 06:15:30 audio-1 volumio[854]: TypeError: Cannot read property 'service' of undefined May 30 06:15:30 audio-1 volumio[854]: at CoreStateMachine.previous (/volumio/app/statemachine.js:1325:97) May 30 06:15:30 audio-1 volumio[854]: at CoreCommandRouter.volumioPrevious (/volumio/app/index.js:97:28) May 30 06:15:30 audio-1 volumio[854]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:270:33) May 30 06:15:30 audio-1 volumio[854]: at Socket.emit (events.js:315:20) May 30 06:15:30 audio-1 volumio[854]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 30 06:15:30 audio-1 volumio[854]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 30 06:15:30 audio-1 volumio[854]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 06:15:31 audio-1 sudo[14182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-30 06:14 May 30 06:15:31 audio-1 sudo[14182]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"