-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Mon 2025-05-26 17:19:59 CEST. -- May 26 17:18:50 volumio---stello volumiologrotate[631]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory May 26 17:18:50 volumio---stello volumiologrotate[631]: ls: cannot access '-': No such file or directory May 26 17:18:50 volumio---stello volumiologrotate[631]: ls: cannot access 'STELLO': No such file or directory May 26 17:19:48 volumio---stello qobuz-connect[24660]: 20250526 17:19:48.639 [24660.27041] INFO SampleApp: API endpoint invoked: get-display-info May 26 17:19:48 volumio---stello qobuz-connect[24660]: 20250526 17:19:48.668 [24660.27041] INFO SampleApp: API endpoint invoked: get-connect-info May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.197 [24660.27041] INFO SampleApp: API endpoint invoked: connect-to-qconnect May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.197 [24660.24660] INFO EndpointManager: [0xf2ed30]: Updating API endpoint May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.197 [24660.24660] INFO EndpointManager: [0xf2ed30]: Updating QConnect endpoint May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.197 [24660.24660] INFO ActiveStateManager: [0xf2dd48]: Setting new active state: active May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.197 [24660.24660] INFO PlaybackSessionManager: [0xf2f120]: Starting playback session maintenance May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.197 [24660.24660] INFO HttpDownloader: [0xf2f310]: Downloading content from: https://www.qobuz.com/api.json/0.2/session/start May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.197 [24660.24660] INFO CloudClient: [0xf2f990]: Connecting to the cloud May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.198 [24660.24660] INFO SampleApp: Renderer is now active May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.447 [24660.24660] INFO CloudClient: [0xf2f990]: Connection established May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.448 [24660.24660] INFO QwspMessageSender: [0x1039bf8]: Sending Authenticate message May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.448 [24660.24660] INFO QwspMessageSender: [0x1039bf8]: Sending Subscribe message May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.448 [24660.24660] INFO QConnectMessageSender: [0xf6f630]: Sending JoinSession message May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.448 [24660.24660] INFO QConnectMessageSender: [0xf6f630]: Sending VolumeChanged message May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.448 [24660.24660] INFO QConnectMessageSender: [0xf6f630]: Sending VolumeMuted message May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.448 [24660.24660] INFO QConnectMessageSender: [0xf6f630]: Sending MaxAudioQualityChanged message May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.448 [24660.24660] INFO QwspMessageSender: [0x1039bf8]: Sending Payload message May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.492 [24660.24660] INFO PlaybackSessionManager: [0xf2f120]: Playback session has been refreshed May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.500 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Received SetActive message: active May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.500 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Received SetState message: May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.500 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Playing state: Paused May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.500 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Playback position: 293230 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.500 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Queue version: 11.1 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.500 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Current track: TID: 316500722, QID: 0, Context UUID: 20cf770f-cfdc-4164-b465-5cfbac5a5b1c May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.500 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Next track: TID: 316500723, QID: 1, Context UUID: 20cf770f-cfdc-4164-b465-5cfbac5a5b1c May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.500 [24660.24660] INFO MediaEngine: [0xf2f3b8]: Stopping playback, clearing tracks May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.500 [24660.24660] INFO MediaEngine: [0xf2f3b8]: Initiating playback May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.501 [24660.24660] INFO RendererActionAvailabilityManager: [0xf2fa48]: Renderer action 'Next' is available May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.501 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Received SetLoopMode message: Off May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.501 [24660.24660] INFO PlaybackControlsManager: [0xf2f790]: Setting new loop mode: Off May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.501 [24660.24660] INFO MediaEngine: [0xf2f3b8]: Setting current track: 316500722, initial offset: 293230ms May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.501 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Clearing all streams May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.501 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: New stream: 1 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.501 [24660.24660] INFO HttpDownloader: [0xf32d70]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=8abbe338eaceebe75cfbfb4c9161bfb6&request_ts=1748272790&track_id=316500722 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.501 [24660.24660] INFO HttpDownloader: [0xf359e8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=316500722 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.501 [24660.24660] INFO HttpDownloader: [0xf35c88]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=a09fa6d299d6c9e7a355b167d84f4177&request_ts=1748272790&track_id=316500722 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.501 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: [Stream 1]: Running audio stream May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.501 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Received SetShuffleMode message: disabled May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.501 [24660.24660] INFO PlaybackControlsManager: [0xf2f790]: Setting new shuffle mode: disabled May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.502 [24660.24660] INFO MediaEngine: [0xf2f3b8]: Setting next track: 316500723 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.502 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: New stream: 2 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.503 [24660.24660] INFO HttpDownloader: [0xf3a820]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=ce79268b3de5e0f3f8c02bfb48e86041&request_ts=1748272790&track_id=316500723 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.503 [24660.24660] INFO HttpDownloader: [0x100f5b8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=316500723 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.503 [24660.24660] INFO HttpDownloader: [0x1011788]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=04c54854d63aacbc21983710c087cb5f&request_ts=1748272790&track_id=316500723 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.504 [24660.24660] INFO MediaEngine: [0xf2f3b8]: Waiting for current stream to start before starting audio renderer May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.605 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: [Stream 1]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=316500722&fmt=6&profile=raw&app_id=174516466&cid=2789982&etsp=1748276390&hmac=hC958DtR6AiPeWKo8d0r8jzOJDU May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.635 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: [Stream 2]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=316500723&fmt=6&profile=raw&app_id=174516466&cid=2789982&etsp=1748276390&hmac=kPa4tUWR8R2swj-T1qtXb3SIt_0 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.645 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: [Stream 1]: stream information have been fetched May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.645 [24660.24660] INFO UrlAudioSource: [0x10e19d0]: Starting URL audio source, initial position: 293230ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=316500722&fmt=6&profile=raw&app_id=174516466&cid=2789982&etsp=1748276390&hmac=hC958DtR6AiPeWKo8d0r8jzOJDU May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.646 [24660.24660] INFO ContentFetcher: [0xf3b250]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=316500722&fmt=6&profile=raw&app_id=174516466&cid=2789982&etsp=1748276390&hmac=hC958DtR6AiPeWKo8d0r8jzOJDU, offset: 0 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.646 [24660.24660] INFO AudioRenderer: [0xf2f5d8]: Starting audio renderer, initial playback state: Paused May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.646 [24660.24660] INFO SampleApp: [Stream 1]: New audio stream (starting from 293230ms) May 26 17:19:50 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:50 volumio---stello volumio[989]: info: CorePlayQueue::getTrack 0 May 26 17:19:50 volumio---stello volumio[989]: info: QobuzConnect: setActiveState() May 26 17:19:50 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:50 volumio---stello volumio[989]: info: CorePlayQueue::getTrack 0 May 26 17:19:50 volumio---stello volumio[989]: info: QobuzConnect: Set Qobuz connect default volume to 71 May 26 17:19:50 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.654 [24660.24660] INFO VolumeManager: [0xf2f820]: Setting new playback volume: 71 May 26 17:19:50 volumio---stello volumio[989]: info: CorePlayQueue::getTrack 0 May 26 17:19:50 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:50 volumio---stello volumio[989]: info: CorePlayQueue::getTrack 0 May 26 17:19:50 volumio---stello volumio[989]: info: QobuzConnect: Stopping currently active service May 26 17:19:50 volumio---stello volumio[989]: info: CoreCommandRouter::volumioStop May 26 17:19:50 volumio---stello volumio[989]: info: CoreStateMachine::stop May 26 17:19:50 volumio---stello volumio[989]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 17:19:50 volumio---stello volumio[989]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 17:19:50 volumio---stello volumio[989]: info: CoreCommandRouter::servicePushState May 26 17:19:50 volumio---stello volumio[989]: info: CoreStateMachine::pushState May 26 17:19:50 volumio---stello volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 17:19:50 volumio---stello volumio[989]: info: CoreCommandRouter::volumioPushState May 26 17:19:50 volumio---stello volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 26 17:19:50 volumio---stello volumio[989]: info: MRS: Pushing multiroomSync output May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.675 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: [Stream 1]: Metadata became available: May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.676 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Title: Sonate pour piano No. 3, Op. 23: I. Drammatico May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.676 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Artist: John Gade May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.676 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Album: Opium May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.676 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Album art URL: https://static.qobuz.com/images/covers/9a/zy/g9fwdseuqzy9a_600.jpg May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.676 [24660.24660] INFO SampleApp: [Stream 1]: Stream metadata became available: May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.676 [24660.24660] INFO SampleApp: Title: Sonate pour piano No. 3, Op. 23: I. Drammatico May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.676 [24660.24660] INFO SampleApp: Artist: John Gade May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.676 [24660.24660] INFO SampleApp: Album: Opium May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.676 [24660.24660] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/9a/zy/g9fwdseuqzy9a_600.jpg May 26 17:19:50 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:50 volumio---stello volumio[989]: info: CorePlayQueue::getTrack 0 May 26 17:19:50 volumio---stello volumio[989]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received qobuzconnect May 26 17:19:50 volumio---stello volumio[989]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect May 26 17:19:50 volumio---stello volumio[989]: info: QobuzConnect: setActiveState() completed, send playback confirmation May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.690 [24660.24660] INFO SampleApp: Initiating playback, initial state: Paused May 26 17:19:50 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:50 volumio---stello volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.745 [24660.24660] ERROR SampleApp: Unable to pause ALSA audio sink: Function not implemented (-38) May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.747 [24660.24660] ERROR AudioRenderer: [0xf2f5d8]: Playback error occurred May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.748 [24660.24660] INFO QConnectMessageSender: [0xf6f630]: Sending PlaybackError message May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.749 [24660.24660] INFO QwspMessageSender: [0x1039bf8]: Sending Payload message May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.749 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Need to send state update May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.749 [24660.24660] INFO QConnectMessageSender: [0xf6f630]: Sending StateUpdated message May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.749 [24660.24660] INFO QwspMessageSender: [0x1039bf8]: Sending Payload message May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.750 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: [Stream 2]: Metadata became available: May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.751 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Title: Sonate pour piano No. 3, Op. 23: II. Allegretto May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.751 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Artist: John Gade May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.751 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Album: Opium May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.751 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Album art URL: https://static.qobuz.com/images/covers/9a/zy/g9fwdseuqzy9a_600.jpg May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.751 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: [Stream 2]: stream information have been fetched May 26 17:19:50 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.850 [24660.24660] INFO SampleApp: Playback volume changed: 71 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.850 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Volume changed: 71 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.850 [24660.24660] INFO QConnectMessageSender: [0xf6f630]: Sending VolumeChanged message May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.851 [24660.24660] INFO QwspMessageSender: [0x1039bf8]: Sending Payload message May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.851 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Received SetState message: May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.851 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Playing state: Playing May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.851 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Playback position: 0 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.851 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Queue version: 11.1 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.851 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Current track: TID: 316500723, QID: 1, Context UUID: 20cf770f-cfdc-4164-b465-5cfbac5a5b1c May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.851 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Next track: TID: 316500724, QID: 2, Context UUID: 20cf770f-cfdc-4164-b465-5cfbac5a5b1c May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.852 [24660.24660] INFO MediaEngine: [0xf2f3b8]: Reseting May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.852 [24660.24660] INFO MediaEngine: [0xf2f3b8]: Stopping playback, clearing tracks May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.852 [24660.24660] INFO MediaEngine: [0xf2f3b8]: Initiating playback May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.852 [24660.24660] INFO AudioRenderer: [0xf2f5d8]: Reseting audio renderer May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.852 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Need to send state update May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.852 [24660.24660] INFO QConnectMessageSender: [0xf6f630]: Sending StateUpdated message May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.852 [24660.24660] INFO QwspMessageSender: [0x1039bf8]: Sending Payload message May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.852 [24660.24660] INFO MediaEngine: [0xf2f3b8]: Setting current track: 316500723, initial offset: 0ms May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.852 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Clearing all streams May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.852 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: [Stream 1]: Disposing of audio stream May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.852 [24660.24660] INFO ContentFetcher: [0xf3b250]: Canceling download May 26 17:19:50 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.858 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: New stream: 3 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.859 [24660.24660] INFO HttpDownloader: [0xf3a820]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=ce79268b3de5e0f3f8c02bfb48e86041&request_ts=1748272790&track_id=316500723 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.861 [24660.24660] INFO HttpDownloader: [0xf32d70]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=316500723 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.861 [24660.24660] INFO HttpDownloader: [0xf68e00]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=04c54854d63aacbc21983710c087cb5f&request_ts=1748272790&track_id=316500723 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.861 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: [Stream 3]: Running audio stream May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.863 [24660.24660] INFO SampleApp: [Stream 1]: Audio stream has been disposed of May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.863 [24660.24660] INFO MediaEngine: [0xf2f3b8]: Setting next track: 316500724 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.863 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: New stream: 4 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.863 [24660.24660] INFO HttpDownloader: [0x111a1e8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=81596dcbcbdcd643ddfff6e7c176b64a&request_ts=1748272790&track_id=316500724 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.863 [24660.24660] INFO HttpDownloader: [0x100a880]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=316500724 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.863 [24660.24660] INFO HttpDownloader: [0x110a628]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=fcc3cc2fd1bb063156447ef7f2927e53&request_ts=1748272790&track_id=316500724 May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.865 [24660.24660] INFO MediaEngine: [0xf2f3b8]: Waiting for current stream to start before starting audio renderer May 26 17:19:50 volumio---stello volumiologrotate[631]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory May 26 17:19:50 volumio---stello volumiologrotate[631]: ls: cannot access '-': No such file or directory May 26 17:19:50 volumio---stello volumiologrotate[631]: ls: cannot access 'STELLO': No such file or directory May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.957 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: [Stream 3]: Metadata became available: May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.957 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Title: Sonate pour piano No. 3, Op. 23: II. Allegretto May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.957 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Artist: John Gade May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.957 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Album: Opium May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.957 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Album art URL: https://static.qobuz.com/images/covers/9a/zy/g9fwdseuqzy9a_600.jpg May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.981 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: [Stream 4]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=316500724&fmt=6&profile=raw&app_id=174516466&cid=2789982&etsp=1748276390&hmac=LnIhLzE78H7THwszTKLFgwtsM7A May 26 17:19:50 volumio---stello qobuz-connect[24660]: 20250526 17:19:50.988 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: [Stream 3]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=316500723&fmt=6&profile=raw&app_id=174516466&cid=2789982&etsp=1748276390&hmac=kPa4tUWR8R2swj-T1qtXb3SIt_0 May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.010 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: [Stream 4]: stream information have been fetched May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.015 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: [Stream 3]: stream information have been fetched May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.015 [24660.24660] INFO UrlAudioSource: [0xf68950]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=316500723&fmt=6&profile=raw&app_id=174516466&cid=2789982&etsp=1748276390&hmac=kPa4tUWR8R2swj-T1qtXb3SIt_0 May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.015 [24660.24660] INFO ContentFetcher: [0x10a7e00]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=316500723&fmt=6&profile=raw&app_id=174516466&cid=2789982&etsp=1748276390&hmac=kPa4tUWR8R2swj-T1qtXb3SIt_0, offset: 0 May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.015 [24660.24660] INFO AudioRenderer: [0xf2f5d8]: Starting audio renderer, initial playback state: Playing May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.015 [24660.24660] INFO SampleApp: [Stream 3]: New audio stream (starting from 0ms) May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.016 [24660.24660] INFO SampleApp: [Stream 3]: Stream metadata became available: May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.016 [24660.24660] INFO SampleApp: Title: Sonate pour piano No. 3, Op. 23: II. Allegretto May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.016 [24660.24660] INFO SampleApp: Artist: John Gade May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.016 [24660.24660] INFO SampleApp: Album: Opium May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.016 [24660.24660] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/9a/zy/g9fwdseuqzy9a_600.jpg May 26 17:19:51 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:51 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:51 volumio---stello volumio[989]: info: QobuzConnect: setActiveState() May 26 17:19:51 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:51 volumio---stello volumio[989]: info: QobuzConnect: Set Qobuz connect default volume to 71 May 26 17:19:51 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.018 [24660.24660] INFO VolumeManager: [0xf2f820]: Setting new playback volume: 71 May 26 17:19:51 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:51 volumio---stello volumio[989]: info: QobuzConnect: Stopping currently active service May 26 17:19:51 volumio---stello volumio[989]: info: CoreCommandRouter::volumioStop May 26 17:19:51 volumio---stello volumio[989]: info: CoreStateMachine::stop May 26 17:19:51 volumio---stello volumio[989]: info: CoreStateMachine::serviceStop May 26 17:19:51 volumio---stello volumio[989]: info: Received STOP, but no service to execute it May 26 17:19:51 volumio---stello volumio[989]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 17:19:51 volumio---stello volumio[989]: info: CoreCommandRouter::servicePushState May 26 17:19:51 volumio---stello volumio[989]: info: CoreStateMachine::pushState May 26 17:19:51 volumio---stello volumio[989]: info: CoreCommandRouter::volumioPushState May 26 17:19:51 volumio---stello volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 26 17:19:51 volumio---stello volumio[989]: info: MRS: Pushing multiroomSync output May 26 17:19:51 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:51 volumio---stello volumio[989]: info: CorePlayQueue::getTrack 0 May 26 17:19:51 volumio---stello volumio[989]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received qobuzconnect May 26 17:19:51 volumio---stello volumio[989]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect May 26 17:19:51 volumio---stello volumio[989]: info: QobuzConnect: setActiveState() completed, send playback confirmation May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.031 [24660.24660] INFO SampleApp: Initiating playback, initial state: Playing May 26 17:19:51 volumio---stello volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 May 26 17:19:51 volumio---stello volumio[989]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.amD0dZqJW8Saf6qYu6TN0hEzFCH2.37cf884b76e61419755675e6b24f85f9.state.status' May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.108 [24660.24660] INFO SampleApp: Playback state changed: Playing May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.108 [24660.24660] INFO StreamingEventReporter: [0xf2ff10]: Reporting streaming started event May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.108 [24660.24660] INFO HttpDownloader: [0xf2ff68]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.108 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Need to send state update May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.108 [24660.24660] INFO QConnectMessageSender: [0xf6f630]: Sending StateUpdated message May 26 17:19:51 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:51 volumio---stello volumio[989]: info: CoreCommandRouter::servicePushState May 26 17:19:51 volumio---stello volumio[989]: info: CoreStateMachine::pushState May 26 17:19:51 volumio---stello volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 17:19:51 volumio---stello volumio[989]: info: CoreCommandRouter::volumioPushState May 26 17:19:51 volumio---stello volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 26 17:19:51 volumio---stello volumio[989]: info: MRS: Pushing multiroomSync output May 26 17:19:51 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:51 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:51 volumio---stello volumio[989]: info: CoreCommandRouter::servicePushState May 26 17:19:51 volumio---stello volumio[989]: info: CoreStateMachine::pushState May 26 17:19:51 volumio---stello volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 17:19:51 volumio---stello volumio[989]: info: CoreCommandRouter::volumioPushState May 26 17:19:51 volumio---stello volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 26 17:19:51 volumio---stello volumio[989]: info: MRS: Pushing multiroomSync output May 26 17:19:51 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:51 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:51 volumio---stello volumio[989]: info: CoreCommandRouter::servicePushState May 26 17:19:51 volumio---stello volumio[989]: info: CoreStateMachine::pushState May 26 17:19:51 volumio---stello volumio[989]: info: CoreCommandRouter::volumioPushState May 26 17:19:51 volumio---stello volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.133 [24660.24660] INFO QwspMessageSender: [0x1039bf8]: Sending Payload message May 26 17:19:51 volumio---stello volumio[989]: info: MRS: Pushing multiroomSync output May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.133 [24660.24660] INFO QConnectMessageSender: [0xf6f630]: Sending FileAudioQualityChanged message May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.134 [24660.24660] INFO VolumeManager: [0xf2f820]: Setting new playback volume: 71 May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.134 [24660.24660] INFO QwspMessageSender: [0x1039bf8]: Sending Payload message May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.134 [24660.24660] INFO QConnectMessageSender: [0xf6f630]: Sending DeviceAudioQualityChanged message May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.134 [24660.24660] INFO QwspMessageSender: [0x1039bf8]: Sending Payload message May 26 17:19:51 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.135 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: [Stream 4]: Metadata became available: May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.135 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Title: Sonate pour piano No. 3, Op. 23: III. Andante May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.135 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Artist: John Gade May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.135 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Album: Opium May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.136 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Album art URL: https://static.qobuz.com/images/covers/9a/zy/g9fwdseuqzy9a_600.jpg May 26 17:19:51 volumio---stello volumio[989]: info: [LastFM] Current track has sufficient metadata: title (Sonate pour piano No. 3, Op. 23: II. Allegretto) and artist (John Gade) passed on explicitly May 26 17:19:51 volumio---stello volumio[989]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) May 26 17:19:51 volumio---stello volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 May 26 17:19:51 volumio---stello volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 May 26 17:19:51 volumio---stello volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.191 [24660.24660] INFO UrlAudioSource: [0xf68950]: Content info extracted May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.191 [24660.24660] INFO UrlAudioSource: [0xf68950]: Size: 9698904 May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.215 [24660.15093] INFO AudioDecoder: [0x1152668]: Stream info: May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.215 [24660.15093] INFO AudioDecoder: [0x1152668]: Total samples: 7120092 May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.215 [24660.15093] INFO AudioDecoder: [0x1152668]: Sample rate: 44100 May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.215 [24660.15093] INFO AudioDecoder: [0x1152668]: Channel count: 2 May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.215 [24660.15093] INFO AudioDecoder: [0x1152668]: Bits per sample: 16 May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.215 [24660.15093] INFO AudioDecoder: [0x1152668]: Duration: 161453 May 26 17:19:51 volumio---stello qobuz-connect[24660]: 20250526 17:19:51.215 [24660.24660] INFO UrlAudioSource: [0xf68950]: Audio properties have changed May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.147 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Received SetState message: May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.148 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Playing state: Playing May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.148 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Playback position: 0 May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.148 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Queue version: 12.1 May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.148 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Current track: TID: 312359296, QID: 0, Context UUID: 471133ce-a8ee-4069-a1ef-c797f990fe59 May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.148 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Next track: TID: 312359298, QID: 1, Context UUID: 471133ce-a8ee-4069-a1ef-c797f990fe59 May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.148 [24660.24660] INFO MediaEngine: [0xf2f3b8]: Stopping playback, clearing tracks May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.148 [24660.24660] INFO MediaEngine: [0xf2f3b8]: Initiating playback May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.148 [24660.24660] INFO AudioRenderer: [0xf2f5d8]: Stopping audio renderer May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.148 [24660.24660] INFO SampleApp: Stopping playback May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.152 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Clearing all streams May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.152 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: [Stream 3]: Disposing of audio stream May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.152 [24660.24660] INFO ContentFetcher: [0x10a7e00]: Canceling download May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.159 [24660.24660] INFO SampleApp: Playback state changed: Stopped May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.159 [24660.24660] INFO StreamingEventReporter: [0xf2ff10]: Reporting streaming ended event May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.159 [24660.24660] INFO HttpDownloader: [0xf2ff68]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.159 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Need to send state update May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.159 [24660.24660] INFO QConnectMessageSender: [0xf6f630]: Sending StateUpdated message May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.159 [24660.24660] INFO SampleApp: [Stream 3]: Audio stream has been disposed of May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.159 [24660.24660] INFO MediaEngine: [0xf2f3b8]: Setting current track: 312359296, initial offset: 0ms May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.160 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Clearing all streams May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.160 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: New stream: 5 May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.160 [24660.24660] INFO HttpDownloader: [0xf3a088]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=dc704754c74ceba7457e64d298f675ef&request_ts=1748272798&track_id=312359296 May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.160 [24660.24660] INFO HttpDownloader: [0x1090ae0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=312359296 May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.160 [24660.24660] INFO HttpDownloader: [0xf32d70]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=0a4289581e47bdda0b016144616a7110&request_ts=1748272798&track_id=312359296 May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.160 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: [Stream 5]: Running audio stream May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.160 [24660.24660] INFO QwspMessageSender: [0x1039bf8]: Sending Payload message May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::servicePushState May 26 17:19:58 volumio---stello volumio[989]: info: CoreStateMachine::pushState May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.163 [24660.24660] INFO MediaEngine: [0xf2f3b8]: Setting next track: 312359298 May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.163 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: New stream: 6 May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.163 [24660.24660] INFO HttpDownloader: [0x11f0b90]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=0bf4c624df202e0116cb5ad8b854a3c4&request_ts=1748272798&track_id=312359298 May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.163 [24660.24660] INFO HttpDownloader: [0x11a5940]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=312359298 May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.163 [24660.24660] INFO HttpDownloader: [0x11f0e28]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=01804f08f7884647fb0dce6dd34ea8b7&request_ts=1748272798&track_id=312359298 May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::volumioPushState May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.165 [24660.24660] INFO MediaEngine: [0xf2f3b8]: Waiting for current stream to start before starting audio renderer May 26 17:19:58 volumio---stello volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 26 17:19:58 volumio---stello volumio[989]: info: MRS: Pushing multiroomSync output May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::servicePushState May 26 17:19:58 volumio---stello volumio[989]: info: CoreStateMachine::pushState May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::volumioPushState May 26 17:19:58 volumio---stello volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 26 17:19:58 volumio---stello volumio[989]: info: MRS: Pushing multiroomSync output May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:58 volumio---stello volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 May 26 17:19:58 volumio---stello volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.260 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: [Stream 6]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=312359298&fmt=7&profile=raw&app_id=174516466&cid=2789982&etsp=1748276398&hmac=2uLtuD1QxxKZgHmmiP1SouTfdvc May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.310 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: [Stream 5]: stream information have been fetched May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.321 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: [Stream 5]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=312359296&fmt=7&profile=raw&app_id=174516466&cid=2789982&etsp=1748276398&hmac=-Fy23nTPKJOyy3TnDYq0I4pkYAk May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.321 [24660.24660] INFO UrlAudioSource: [0xf6b9d0]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=312359296&fmt=7&profile=raw&app_id=174516466&cid=2789982&etsp=1748276398&hmac=-Fy23nTPKJOyy3TnDYq0I4pkYAk May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.321 [24660.24660] INFO ContentFetcher: [0x10a7e00]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=4790152&eid=312359296&fmt=7&profile=raw&app_id=174516466&cid=2789982&etsp=1748276398&hmac=-Fy23nTPKJOyy3TnDYq0I4pkYAk, offset: 0 May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.322 [24660.24660] INFO AudioRenderer: [0xf2f5d8]: Starting audio renderer, initial playback state: Playing May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.322 [24660.24660] INFO SampleApp: [Stream 5]: New audio stream (starting from 0ms) May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.324 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: [Stream 6]: stream information have been fetched May 26 17:19:58 volumio---stello volumio[989]: info: QobuzConnect: alread currrent service, send playback confirmation May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.325 [24660.24660] INFO SampleApp: Initiating playback, initial state: Playing May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.402 [24660.24660] INFO SampleApp: Playback state changed: Playing May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.403 [24660.24660] INFO StreamingEventReporter: [0xf2ff10]: Reporting streaming started event May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.403 [24660.24660] INFO ProtocolHandler: [0xf2faf8]: Need to send state update May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.403 [24660.24660] INFO QConnectMessageSender: [0xf6f630]: Sending StateUpdated message May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::servicePushState May 26 17:19:58 volumio---stello volumio[989]: info: CoreStateMachine::pushState May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::volumioPushState May 26 17:19:58 volumio---stello volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 26 17:19:58 volumio---stello volumio[989]: info: MRS: Pushing multiroomSync output May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::servicePushState May 26 17:19:58 volumio---stello volumio[989]: info: CoreStateMachine::pushState May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::volumioPushState May 26 17:19:58 volumio---stello volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 26 17:19:58 volumio---stello volumio[989]: info: MRS: Pushing multiroomSync output May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.425 [24660.24660] INFO QwspMessageSender: [0x1039bf8]: Sending Payload message May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.426 [24660.24660] INFO QConnectMessageSender: [0xf6f630]: Sending FileAudioQualityChanged message May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::servicePushState May 26 17:19:58 volumio---stello volumio[989]: info: CoreStateMachine::pushState May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.426 [24660.24660] INFO QwspMessageSender: [0x1039bf8]: Sending Payload message May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.426 [24660.24660] INFO QConnectMessageSender: [0xf6f630]: Sending DeviceAudioQualityChanged message May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.426 [24660.24660] INFO QwspMessageSender: [0x1039bf8]: Sending Payload message May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::volumioPushState May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.427 [24660.24660] INFO HttpDownloader: [0xf2ff68]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.428 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: [Stream 6]: Metadata became available: May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.428 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Title: Spring Ode – Meskerem May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.428 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Artist: Emahoy Tsege Mariam Gebru May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.428 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Album: Church of Kidane Mehret May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.428 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Album art URL: https://static.qobuz.com/images/covers/6a/9l/i1f0qdwr89l6a_600.jpg May 26 17:19:58 volumio---stello volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 26 17:19:58 volumio---stello volumio[989]: info: MRS: Pushing multiroomSync output May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::servicePushState May 26 17:19:58 volumio---stello volumio[989]: info: CoreStateMachine::pushState May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::volumioPushState May 26 17:19:58 volumio---stello volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 26 17:19:58 volumio---stello volumio[989]: info: MRS: Pushing multiroomSync output May 26 17:19:58 volumio---stello volumio[989]: info: CoreCommandRouter::volumioGetState May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.440 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: [Stream 5]: Metadata became available: May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.440 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Title: Ave Maria May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.440 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Artist: Emahoy Tsege Mariam Gebru May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.440 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Album: Church of Kidane Mehret May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.440 [24660.24660] INFO AudioStreamManager: [0xf2f4a0]: Album art URL: https://static.qobuz.com/images/covers/6a/9l/i1f0qdwr89l6a_600.jpg May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.440 [24660.24660] INFO SampleApp: [Stream 5]: Stream metadata became available: May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.440 [24660.24660] INFO SampleApp: Title: Ave Maria May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.440 [24660.24660] INFO SampleApp: Artist: Emahoy Tsege Mariam Gebru May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.440 [24660.24660] INFO SampleApp: Album: Church of Kidane Mehret May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.440 [24660.24660] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/6a/9l/i1f0qdwr89l6a_600.jpg May 26 17:19:58 volumio---stello volumio[989]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.452 [24660.24660] INFO UrlAudioSource: [0xf6b9d0]: Content info extracted May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.452 [24660.24660] INFO UrlAudioSource: [0xf6b9d0]: Size: 49638046 May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.453 [24660.15110] INFO AudioDecoder: [0x119c3a8]: Stream info: May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.453 [24660.15110] INFO AudioDecoder: [0x119c3a8]: Total samples: 15131009 May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.453 [24660.15110] INFO AudioDecoder: [0x119c3a8]: Sample rate: 96000 May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.453 [24660.15110] INFO AudioDecoder: [0x119c3a8]: Channel count: 2 May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.453 [24660.15110] INFO AudioDecoder: [0x119c3a8]: Bits per sample: 24 May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.453 [24660.15110] INFO AudioDecoder: [0x119c3a8]: Duration: 157614 May 26 17:19:58 volumio---stello qobuz-connect[24660]: 20250526 17:19:58.453 [24660.24660] INFO UrlAudioSource: [0xf6b9d0]: Audio properties have changed May 26 17:19:58 volumio---stello volumio[989]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 17:19:58 volumio---stello volumio[989]: TypeError: Cannot read property 'indexOf' of undefined May 26 17:19:58 volumio---stello volumio[989]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) May 26 17:19:58 volumio---stello volumio[989]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) May 26 17:19:58 volumio---stello volumio[989]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) May 26 17:19:58 volumio---stello volumio[989]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 26 17:19:58 volumio---stello volumio[989]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) May 26 17:19:58 volumio---stello volumio[989]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) May 26 17:19:58 volumio---stello volumio[989]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 26 17:19:58 volumio---stello volumio[989]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 26 17:19:58 volumio---stello volumio[989]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) May 26 17:19:58 volumio---stello volumio[989]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 26 17:19:58 volumio---stello volumio[989]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) May 26 17:19:58 volumio---stello volumio[989]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) May 26 17:19:58 volumio---stello volumio[989]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) May 26 17:19:58 volumio---stello volumio[989]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 26 17:19:58 volumio---stello volumio[989]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 26 17:19:58 volumio---stello volumio[989]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) May 26 17:19:58 volumio---stello volumio[989]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 17:19:59 volumio---stello sudo[15121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-26 17:18 May 26 17:19:59 volumio---stello sudo[15121]: 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"