-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-05-11 20:28:53 CEST. -- May 11 20:27:30 volumio-sydcesar volumiologrotate[553]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory May 11 20:27:30 volumio-sydcesar volumiologrotate[553]: ls: cannot access 'SYDCESAR': No such file or directory May 11 20:27:39 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:27:41 volumio-sydcesar volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 11 20:27:41 volumio-sydcesar volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 11 20:27:41 volumio-sydcesar volumio[805]: info: Executing endpoint metavolumio May 11 20:27:41 volumio-sydcesar volumio[805]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 11 20:27:41 volumio-sydcesar volumio[805]: info: Executing endpoint metavolumio May 11 20:27:41 volumio-sydcesar volumio[805]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 11 20:27:41 volumio-sydcesar volumio[805]: info: Executing endpoint metavolumio May 11 20:27:41 volumio-sydcesar volumio[805]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 11 20:27:51 volumio-sydcesar volumio[805]: error: Cannot download Available plugins list: Error: ETIMEDOUT May 11 20:27:54 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:27:56 volumio-sydcesar volumio[805]: info: Executing endpoint metavolumio May 11 20:27:56 volumio-sydcesar volumio[805]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 11 20:27:56 volumio-sydcesar volumio[805]: info: Executing endpoint metavolumio May 11 20:27:56 volumio-sydcesar volumio[805]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 11 20:27:56 volumio-sydcesar volumio[805]: info: Executing endpoint metavolumio May 11 20:27:56 volumio-sydcesar volumio[805]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 11 20:28:05 volumio-sydcesar volumio[805]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 20:28:05 volumio-sydcesar volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 11 20:28:05 volumio-sydcesar volumio[805]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 11 20:28:05 volumio-sydcesar sudo[2763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 11 20:28:05 volumio-sydcesar sudo[2763]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:28:05 volumio-sydcesar sudo[2771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 11 20:28:05 volumio-sydcesar sudo[2763]: pam_unix(sudo:session): session closed for user root May 11 20:28:05 volumio-sydcesar sudo[2771]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:28:05 volumio-sydcesar sudo[2771]: pam_unix(sudo:session): session closed for user root May 11 20:28:05 volumio-sydcesar sudo[2778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 11 20:28:05 volumio-sydcesar sudo[2778]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:28:05 volumio-sydcesar sudo[2778]: pam_unix(sudo:session): session closed for user root May 11 20:28:05 volumio-sydcesar sudo[2785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 11 20:28:05 volumio-sydcesar sudo[2785]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:28:05 volumio-sydcesar sudo[2785]: pam_unix(sudo:session): session closed for user root May 11 20:28:05 volumio-sydcesar sudo[2791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 11 20:28:05 volumio-sydcesar sudo[2791]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:28:05 volumio-sydcesar volumio[805]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 11 20:28:05 volumio-sydcesar volumio[805]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 11 20:28:05 volumio-sydcesar sudo[2791]: pam_unix(sudo:session): session closed for user root May 11 20:28:05 volumio-sydcesar sudo[2794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 11 20:28:05 volumio-sydcesar sudo[2794]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 20:28:05 volumio-sydcesar sudo[2794]: pam_unix(sudo:session): session closed for user root May 11 20:28:15 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:17 volumio-sydcesar volumio[805]: info: Executing endpoint metavolumio May 11 20:28:17 volumio-sydcesar volumio[805]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 11 20:28:17 volumio-sydcesar volumio[805]: info: Executing endpoint metavolumio May 11 20:28:17 volumio-sydcesar volumio[805]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 11 20:28:17 volumio-sydcesar volumio[805]: info: Executing endpoint metavolumio May 11 20:28:17 volumio-sydcesar volumio[805]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 11 20:28:19 volumio-sydcesar volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 11 20:28:19 volumio-sydcesar volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 11 20:28:29 volumio-sydcesar volumio[805]: error: Cannot download Available plugins list: Error: ETIMEDOUT May 11 20:28:30 volumio-sydcesar volumiologrotate[553]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory May 11 20:28:30 volumio-sydcesar volumiologrotate[553]: ls: cannot access 'SYDCESAR': No such file or directory May 11 20:28:33 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:34 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioPause May 11 20:28:34 volumio-sydcesar volumio[805]: info: CoreStateMachine::pause May 11 20:28:34 volumio-sydcesar volumio[805]: info: CoreStateMachine::stPlaybackTimer May 11 20:28:34 volumio-sydcesar volumio[805]: info: CoreStateMachine::servicePause May 11 20:28:34 volumio-sydcesar volumio[805]: info: CorePlayQueue::getTrack 4 May 11 20:28:34 volumio-sydcesar volumio[805]: info: CoreCommandRouter::servicePause May 11 20:28:34 volumio-sydcesar volumio[805]: info: [1746988114434] ControllerQobuz::pause May 11 20:28:34 volumio-sydcesar volumio[805]: info: CoreStateMachine::setConsumeUpdateService mpd May 11 20:28:34 volumio-sydcesar volumio[805]: info: ControllerMpd::pause May 11 20:28:34 volumio-sydcesar volumio[805]: verbose: ControllerMpd::sendMpdCommand pause May 11 20:28:34 volumio-sydcesar volumio[805]: info: sendMpdCommand pause took 1 milliseconds May 11 20:28:34 volumio-sydcesar volumio[805]: info: May 11 20:28:34 volumio-sydcesar volumio[805]: ---------------------------- MPD announces state update: player May 11 20:28:34 volumio-sydcesar volumio[805]: info: ControllerMpd::getState May 11 20:28:34 volumio-sydcesar volumio[805]: verbose: ControllerMpd::sendMpdCommand status May 11 20:28:34 volumio-sydcesar volumio[805]: info: sendMpdCommand status took 1 milliseconds May 11 20:28:34 volumio-sydcesar volumio[805]: verbose: ControllerMpd::parseState May 11 20:28:34 volumio-sydcesar volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 20:28:34 volumio-sydcesar volumio[805]: info: sendMpdCommand playlistinfo took 1 milliseconds May 11 20:28:34 volumio-sydcesar volumio[805]: verbose: ControllerMpd::parseTrackInfo May 11 20:28:34 volumio-sydcesar volumio[805]: info: ControllerMpd::pushState May 11 20:28:34 volumio-sydcesar volumio[805]: info: CoreCommandRouter::servicePushState May 11 20:28:34 volumio-sydcesar volumio[805]: info: CorePlayQueue::getTrack 4 May 11 20:28:34 volumio-sydcesar volumio[805]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":299491,"duration":404,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2165 Kbps","isStreaming":false,"title":"40452678","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/40452678","trackType":"qobuz"} May 11 20:28:34 volumio-sydcesar volumio[805]: verbose: CURRENT POSITION 4 May 11 20:28:34 volumio-sydcesar volumio[805]: info: CoreStateMachine::syncState stateService pause May 11 20:28:34 volumio-sydcesar volumio[805]: info: CoreStateMachine::syncState currentStatus pause May 11 20:28:34 volumio-sydcesar volumio[805]: info: CoreStateMachine::pushState May 11 20:28:34 volumio-sydcesar volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 20:28:34 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioPushState May 11 20:28:34 volumio-sydcesar volumio[805]: info: MRS: Pushing multiroomSync output update for this device May 11 20:28:34 volumio-sydcesar volumio[805]: info: MRS: Pushing multiroomSync output May 11 20:28:34 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:34 volumio-sydcesar volumio[805]: info: CoreStateMachine::stPlaybackTimer May 11 20:28:34 volumio-sydcesar volumio[805]: info: ------------------------------ 19ms May 11 20:28:35 volumio-sydcesar volumio[805]: info: Executing endpoint metavolumio May 11 20:28:35 volumio-sydcesar volumio[805]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 11 20:28:35 volumio-sydcesar volumio[805]: info: Executing endpoint metavolumio May 11 20:28:35 volumio-sydcesar volumio[805]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 11 20:28:35 volumio-sydcesar volumio[805]: info: Executing endpoint metavolumio May 11 20:28:35 volumio-sydcesar volumio[805]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 11 20:28:47 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:47.997 [1264.1281] INFO SampleApp: API endpoint invoked: get-connect-info May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.163 [1264.1281] INFO SampleApp: API endpoint invoked: connect-to-qconnect May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.163 [1264.1264] INFO EndpointManager: [0x19aee90]: Updating API endpoint May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.163 [1264.1264] INFO EndpointManager: [0x19aee90]: Updating QConnect endpoint May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.163 [1264.1264] INFO ActiveStateManager: [0x19adea8]: Setting new active state: active May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.163 [1264.1264] INFO PlaybackSessionManager: [0x19af280]: Starting playback session maintenance May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.163 [1264.1264] INFO HttpDownloader: [0x19af470]: Downloading content from: https://www.qobuz.com/api.json/0.2/session/start May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.163 [1264.1264] INFO CloudClient: [0x19afaf0]: Connecting to the cloud May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.163 [1264.1264] INFO SampleApp: Renderer is now active May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.348 [1264.1264] INFO PlaybackSessionManager: [0x19af280]: Playback session has been refreshed May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.391 [1264.1264] INFO CloudClient: [0x19afaf0]: Connection established May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.391 [1264.1264] INFO QwspMessageSender: [0x1b083a8]: Sending Authenticate message May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.391 [1264.1264] INFO QwspMessageSender: [0x1b083a8]: Sending Subscribe message May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.391 [1264.1264] INFO QConnectMessageSender: [0x1aa4280]: Sending JoinSession message May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.391 [1264.1264] INFO QConnectMessageSender: [0x1aa4280]: Sending VolumeChanged message May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.391 [1264.1264] INFO QConnectMessageSender: [0x1aa4280]: Sending VolumeMuted message May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.391 [1264.1264] INFO QConnectMessageSender: [0x1aa4280]: Sending MaxAudioQualityChanged message May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.391 [1264.1264] INFO QwspMessageSender: [0x1b083a8]: Sending Payload message May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.451 [1264.1264] INFO ProtocolHandler: [0x19afc58]: Received SetActive message: active May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.451 [1264.1264] INFO ProtocolHandler: [0x19afc58]: Received SetState message: May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.451 [1264.1264] INFO ProtocolHandler: [0x19afc58]: Playing state: Paused May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.451 [1264.1264] INFO ProtocolHandler: [0x19afc58]: Playback position: 35136 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.452 [1264.1264] INFO ProtocolHandler: [0x19afc58]: Queue version: 4.1 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.452 [1264.1264] INFO ProtocolHandler: [0x19afc58]: Current track: TID: 40452678, QID: 4, Context UUID: d35758ad-072c-4236-9201-aff5914a499c May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.452 [1264.1264] INFO ProtocolHandler: [0x19afc58]: Next track: TID: 40452679, QID: 5, Context UUID: d35758ad-072c-4236-9201-aff5914a499c May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.452 [1264.1264] INFO MediaEngine: [0x19af518]: Stopping playback, clearing tracks May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.452 [1264.1264] INFO MediaEngine: [0x19af518]: Initiating playback May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.452 [1264.1264] INFO RendererActionAvailabilityManager: [0x19afba8]: Renderer action 'Next' is available May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.452 [1264.1264] INFO ProtocolHandler: [0x19afc58]: Received SetLoopMode message: Off May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.452 [1264.1264] INFO PlaybackControlsManager: [0x19af8f0]: Setting new loop mode: Off May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.452 [1264.1264] INFO MediaEngine: [0x19af518]: Setting current track: 40452678, initial offset: 35136ms May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.452 [1264.1264] INFO AudioStreamManager: [0x19af600]: Clearing all streams May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.452 [1264.1264] INFO AudioStreamManager: [0x19af600]: New stream: 1 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.452 [1264.1264] INFO HttpDownloader: [0x1a8b5d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=cd30fbd126fca37356343d043ded84c9&request_ts=1746988128&track_id=40452678 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.452 [1264.1264] INFO HttpDownloader: [0x19b5fd0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=40452678 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.452 [1264.1264] INFO HttpDownloader: [0x19b6150]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=cbe4089c8cfef517bcab4d7f34be4c1d&request_ts=1746988128&track_id=40452678 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.452 [1264.1264] INFO AudioStreamManager: [0x19af600]: [Stream 1]: Running audio stream May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.452 [1264.1264] INFO ProtocolHandler: [0x19afc58]: Received SetShuffleMode message: disabled May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.452 [1264.1264] INFO PlaybackControlsManager: [0x19af8f0]: Setting new shuffle mode: disabled May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.452 [1264.1264] INFO MediaEngine: [0x19af518]: Setting next track: 40452679 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.452 [1264.1264] INFO AudioStreamManager: [0x19af600]: New stream: 2 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.452 [1264.1264] INFO HttpDownloader: [0x19b9d68]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=e69dd29ee104a6d546f4753b2c4ad025&request_ts=1746988128&track_id=40452679 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.452 [1264.1264] INFO HttpDownloader: [0x19bad68]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=40452679 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.452 [1264.1264] INFO HttpDownloader: [0x19bafa0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=b6387c442ad85f2e7e161fa1ac43fdac&request_ts=1746988128&track_id=40452679 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.452 [1264.1264] INFO MediaEngine: [0x19af518]: Waiting for current stream to start before starting audio renderer May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.599 [1264.1264] INFO AudioStreamManager: [0x19af600]: [Stream 1]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1989252&eid=40452678&fmt=7&profile=raw&app_id=174516466&cid=1503740&etsp=1746991728&hmac=sxnpeDRPGGwoCCZ8SThcZXT64h0 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.671 [1264.1264] INFO AudioStreamManager: [0x19af600]: [Stream 1]: stream information have been fetched May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.672 [1264.1264] INFO UrlAudioSource: [0x1a9bfb0]: Starting URL audio source, initial position: 35136ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=1989252&eid=40452678&fmt=7&profile=raw&app_id=174516466&cid=1503740&etsp=1746991728&hmac=sxnpeDRPGGwoCCZ8SThcZXT64h0 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.672 [1264.1264] INFO ContentFetcher: [0x19b7458]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=1989252&eid=40452678&fmt=7&profile=raw&app_id=174516466&cid=1503740&etsp=1746991728&hmac=sxnpeDRPGGwoCCZ8SThcZXT64h0, offset: 0 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.672 [1264.1264] INFO AudioStreamManager: [0x19af600]: [Stream 2]: Metadata became available: May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.672 [1264.1264] INFO AudioStreamManager: [0x19af600]: Title: Permanating May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.672 [1264.1264] INFO AudioStreamManager: [0x19af600]: Artist: Steven Wilson May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.672 [1264.1264] INFO AudioStreamManager: [0x19af600]: Album: To The Bone May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.672 [1264.1264] INFO AudioStreamManager: [0x19af600]: Album art URL: https://static.qobuz.com/images/covers/83/47/0060255764783_600.jpg May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.672 [1264.1264] INFO AudioRenderer: [0x19af738]: Starting audio renderer, initial playback state: Paused May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.672 [1264.1264] INFO SampleApp: [Stream 1]: New audio stream (starting from 35136ms) May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:48 volumio-sydcesar volumio[805]: info: QobuzConnect: setActiveState() May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:48 volumio-sydcesar volumio[805]: info: QobuzConnect: Set Qobuz connect default volume to 100 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.676 [1264.1264] INFO VolumeManager: [0x19af980]: Setting new playback volume: 100 May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:48 volumio-sydcesar volumio[805]: info: QobuzConnect: Stopping currently active service May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioStop May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreStateMachine::stop May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreStateMachine::updateTrackBlock May 11 20:28:48 volumio-sydcesar volumio[805]: info: CorePlayQueue::getTrackBlock May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreStateMachine::stPlaybackTimer May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreStateMachine::pushState May 11 20:28:48 volumio-sydcesar volumio[805]: info: CorePlayQueue::getTrack 4 May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioPushState May 11 20:28:48 volumio-sydcesar volumio[805]: info: MRS: Pushing multiroomSync output update for this device May 11 20:28:48 volumio-sydcesar volumio[805]: info: MRS: Pushing multiroomSync output May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:48 volumio-sydcesar volumio[805]: info: CorePlayQueue::getTrack 4 May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreStateMachine::serviceStop May 11 20:28:48 volumio-sydcesar volumio[805]: info: CorePlayQueue::getTrack 4 May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreCommandRouter::serviceStop May 11 20:28:48 volumio-sydcesar volumio[805]: info: [1746988128685] ControllerQobuz::stop May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreStateMachine::setConsumeUpdateService mpd May 11 20:28:48 volumio-sydcesar volumio[805]: info: ControllerMpd::stop May 11 20:28:48 volumio-sydcesar volumio[805]: verbose: ControllerMpd::sendMpdCommand stop May 11 20:28:48 volumio-sydcesar volumio[805]: info: May 11 20:28:48 volumio-sydcesar volumio[805]: ---------------------------- MPD announces state update: player May 11 20:28:48 volumio-sydcesar volumio[805]: info: sendMpdCommand stop took 20 milliseconds May 11 20:28:48 volumio-sydcesar volumio[805]: info: ControllerMpd::getState May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.706 [1264.1264] INFO AudioStreamManager: [0x19af600]: [Stream 2]: stream information have been fetched May 11 20:28:48 volumio-sydcesar volumio[805]: verbose: ControllerMpd::sendMpdCommand status May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreCommandRouter::servicePushState May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreStateMachine::pushState May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioPushState May 11 20:28:48 volumio-sydcesar volumio[805]: info: MRS: Pushing multiroomSync output update for this device May 11 20:28:48 volumio-sydcesar volumio[805]: info: MRS: Pushing multiroomSync output May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:48 volumio-sydcesar volumio[805]: info: CorePlayQueue::getTrack 4 May 11 20:28:48 volumio-sydcesar volumio[805]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received qobuzconnect May 11 20:28:48 volumio-sydcesar volumio[805]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect May 11 20:28:48 volumio-sydcesar volumio[805]: info: QobuzConnect: setActiveState() completed, send playback confirmation May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.721 [1264.1264] INFO SampleApp: Initiating playback, initial state: Paused May 11 20:28:48 volumio-sydcesar volumio[805]: info: sendMpdCommand status took 16 milliseconds May 11 20:28:48 volumio-sydcesar volumio[805]: verbose: ControllerMpd::parseState May 11 20:28:48 volumio-sydcesar volumio[805]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 20:28:48 volumio-sydcesar volumio[805]: info: sendMpdCommand playlistinfo took 6 milliseconds May 11 20:28:48 volumio-sydcesar volumio[805]: verbose: ControllerMpd::parseTrackInfo May 11 20:28:48 volumio-sydcesar volumio[805]: info: ControllerMpd::pushState May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreCommandRouter::servicePushState May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreStateMachine::pushState May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioPushState May 11 20:28:48 volumio-sydcesar volumio[805]: info: MRS: Pushing multiroomSync output update for this device May 11 20:28:48 volumio-sydcesar volumio[805]: info: MRS: Pushing multiroomSync output May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:48 volumio-sydcesar volumio[805]: info: CorePlayQueue::getTrack 4 May 11 20:28:48 volumio-sydcesar volumio[805]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd May 11 20:28:48 volumio-sydcesar volumio[805]: info: ------------------------------ 34ms May 11 20:28:48 volumio-sydcesar volumio[805]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.nmcNiEMY2MdpUWmB32F14OLeZyx2.a4bc0786edecf44828de5048866ba4a5.state.status' May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.744 [1264.1264] ERROR SampleApp: Unable to pause ALSA audio sink: Function not implemented (-38) May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.745 [1264.1264] ERROR AudioRenderer: [0x19af738]: Playback error occurred May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.745 [1264.1264] INFO QConnectMessageSender: [0x1aa4280]: Sending PlaybackError message May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.745 [1264.1264] INFO QwspMessageSender: [0x1b083a8]: Sending Payload message May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.746 [1264.1264] INFO ProtocolHandler: [0x19afc58]: Need to send state update May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.746 [1264.1264] INFO QConnectMessageSender: [0x1aa4280]: Sending StateUpdated message May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.746 [1264.1264] INFO QwspMessageSender: [0x1b083a8]: Sending Payload message May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.746 [1264.1264] INFO AudioStreamManager: [0x19af600]: [Stream 2]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1989252&eid=40452679&fmt=7&profile=raw&app_id=174516466&cid=1503740&etsp=1746991728&hmac=RWVU8_TM260OsDIr_8hGE20nfss May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.746 [1264.1264] INFO AudioStreamManager: [0x19af600]: [Stream 1]: Metadata became available: May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.746 [1264.1264] INFO AudioStreamManager: [0x19af600]: Title: Refuge May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.746 [1264.1264] INFO AudioStreamManager: [0x19af600]: Artist: Steven Wilson May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.746 [1264.1264] INFO AudioStreamManager: [0x19af600]: Album: To The Bone May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.746 [1264.1264] INFO AudioStreamManager: [0x19af600]: Album art URL: https://static.qobuz.com/images/covers/83/47/0060255764783_600.jpg May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.746 [1264.1264] INFO SampleApp: [Stream 1]: Stream metadata became available: May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.746 [1264.1264] INFO SampleApp: Title: Refuge May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.746 [1264.1264] INFO SampleApp: Artist: Steven Wilson May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.747 [1264.1264] INFO SampleApp: Album: To The Bone May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.747 [1264.1264] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/83/47/0060255764783_600.jpg May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.831 [1264.1264] INFO SampleApp: Playback volume changed: 100 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.831 [1264.1264] INFO ProtocolHandler: [0x19afc58]: Volume changed: 100 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.831 [1264.1264] INFO QConnectMessageSender: [0x1aa4280]: Sending VolumeChanged message May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.842 [1264.1264] INFO QwspMessageSender: [0x1b083a8]: Sending Payload message May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.842 [1264.1264] INFO ProtocolHandler: [0x19afc58]: Received SetState message: May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.842 [1264.1264] INFO ProtocolHandler: [0x19afc58]: Playing state: Playing May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.842 [1264.1264] INFO ProtocolHandler: [0x19afc58]: Playback position: 0 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.842 [1264.1264] INFO ProtocolHandler: [0x19afc58]: Queue version: 4.1 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.842 [1264.1264] INFO ProtocolHandler: [0x19afc58]: Current track: TID: 40452679, QID: 5, Context UUID: d35758ad-072c-4236-9201-aff5914a499c May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.842 [1264.1264] INFO ProtocolHandler: [0x19afc58]: Next track: TID: 40452680, QID: 6, Context UUID: d35758ad-072c-4236-9201-aff5914a499c May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.842 [1264.1264] INFO MediaEngine: [0x19af518]: Reseting May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.842 [1264.1264] INFO MediaEngine: [0x19af518]: Stopping playback, clearing tracks May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.842 [1264.1264] INFO MediaEngine: [0x19af518]: Initiating playback May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.842 [1264.1264] INFO AudioRenderer: [0x19af738]: Reseting audio renderer May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.842 [1264.1264] INFO ProtocolHandler: [0x19afc58]: Need to send state update May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.842 [1264.1264] INFO QConnectMessageSender: [0x1aa4280]: Sending StateUpdated message May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.842 [1264.1264] INFO QwspMessageSender: [0x1b083a8]: Sending Payload message May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.842 [1264.1264] INFO MediaEngine: [0x19af518]: Setting current track: 40452679, initial offset: 0ms May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.842 [1264.1264] INFO AudioStreamManager: [0x19af600]: Clearing all streams May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.842 [1264.1264] INFO AudioStreamManager: [0x19af600]: [Stream 1]: Disposing of audio stream May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.843 [1264.1264] INFO ContentFetcher: [0x19b7458]: Canceling download May 11 20:28:48 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.845 [1264.1264] INFO AudioStreamManager: [0x19af600]: New stream: 3 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.845 [1264.1264] INFO HttpDownloader: [0x19b9d68]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=e69dd29ee104a6d546f4753b2c4ad025&request_ts=1746988128&track_id=40452679 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.845 [1264.1264] INFO HttpDownloader: [0x1aa19b8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=40452679 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.846 [1264.1264] INFO HttpDownloader: [0x19c5560]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=b6387c442ad85f2e7e161fa1ac43fdac&request_ts=1746988128&track_id=40452679 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.846 [1264.1264] INFO AudioStreamManager: [0x19af600]: [Stream 3]: Running audio stream May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.846 [1264.1264] INFO SampleApp: [Stream 1]: Audio stream has been disposed of May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.846 [1264.1264] INFO MediaEngine: [0x19af518]: Setting next track: 40452680 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.846 [1264.1264] INFO AudioStreamManager: [0x19af600]: New stream: 4 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.846 [1264.1264] INFO HttpDownloader: [0x1ae9628]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=8cbd9efef75e001d8c1da04da6f90d39&request_ts=1746988128&track_id=40452680 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.847 [1264.1264] INFO HttpDownloader: [0x1abe2b0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=40452680 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.847 [1264.1264] INFO HttpDownloader: [0x1ad1b50]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=06cd8ce4510bef62993597c8fe5f01ef&request_ts=1746988128&track_id=40452680 May 11 20:28:48 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:48.847 [1264.1264] INFO MediaEngine: [0x19af518]: Waiting for current stream to start before starting audio renderer May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.022 [1264.1264] INFO AudioStreamManager: [0x19af600]: [Stream 3]: Metadata became available: May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.022 [1264.1264] INFO AudioStreamManager: [0x19af600]: Title: Permanating May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.022 [1264.1264] INFO AudioStreamManager: [0x19af600]: Artist: Steven Wilson May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.022 [1264.1264] INFO AudioStreamManager: [0x19af600]: Album: To The Bone May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.022 [1264.1264] INFO AudioStreamManager: [0x19af600]: Album art URL: https://static.qobuz.com/images/covers/83/47/0060255764783_600.jpg May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.049 [1264.1264] INFO AudioStreamManager: [0x19af600]: [Stream 3]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1989252&eid=40452679&fmt=7&profile=raw&app_id=174516466&cid=1503740&etsp=1746991729&hmac=dgtV4pXr34lGl_cjgtMGHOurtOw May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.072 [1264.1264] INFO AudioStreamManager: [0x19af600]: [Stream 4]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1989252&eid=40452680&fmt=7&profile=raw&app_id=174516466&cid=1503740&etsp=1746991729&hmac=Vg24BmcHC2bKYB-AQkn3xFit4gk May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.112 [1264.1264] INFO AudioStreamManager: [0x19af600]: [Stream 4]: stream information have been fetched May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.121 [1264.1264] INFO AudioStreamManager: [0x19af600]: [Stream 4]: Metadata became available: May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.121 [1264.1264] INFO AudioStreamManager: [0x19af600]: Title: Blank Tapes May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.121 [1264.1264] INFO AudioStreamManager: [0x19af600]: Artist: Steven Wilson May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.121 [1264.1264] INFO AudioStreamManager: [0x19af600]: Album: To The Bone May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.121 [1264.1264] INFO AudioStreamManager: [0x19af600]: Album art URL: https://static.qobuz.com/images/covers/83/47/0060255764783_600.jpg May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.163 [1264.1264] INFO AudioStreamManager: [0x19af600]: [Stream 3]: stream information have been fetched May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.163 [1264.1264] INFO UrlAudioSource: [0x1b62638]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=1989252&eid=40452679&fmt=7&profile=raw&app_id=174516466&cid=1503740&etsp=1746991729&hmac=dgtV4pXr34lGl_cjgtMGHOurtOw May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.163 [1264.1264] INFO ContentFetcher: [0x1b8ad10]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=1989252&eid=40452679&fmt=7&profile=raw&app_id=174516466&cid=1503740&etsp=1746991729&hmac=dgtV4pXr34lGl_cjgtMGHOurtOw, offset: 0 May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.163 [1264.1264] INFO AudioRenderer: [0x19af738]: Starting audio renderer, initial playback state: Playing May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.163 [1264.1264] INFO SampleApp: [Stream 3]: New audio stream (starting from 0ms) May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.163 [1264.1264] INFO SampleApp: [Stream 3]: Stream metadata became available: May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.163 [1264.1264] INFO SampleApp: Title: Permanating May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.163 [1264.1264] INFO SampleApp: Artist: Steven Wilson May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.163 [1264.1264] INFO SampleApp: Album: To The Bone May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.163 [1264.1264] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/83/47/0060255764783_600.jpg May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:49 volumio-sydcesar volumio[805]: info: QobuzConnect: setActiveState() May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:49 volumio-sydcesar volumio[805]: info: QobuzConnect: Set Qobuz connect default volume to 100 May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.165 [1264.1264] INFO VolumeManager: [0x19af980]: Setting new playback volume: 100 May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:49 volumio-sydcesar volumio[805]: info: QobuzConnect: Stopping currently active service May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioStop May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreStateMachine::stop May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreStateMachine::serviceStop May 11 20:28:49 volumio-sydcesar volumio[805]: info: Received STOP, but no service to execute it May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreCommandRouter::servicePushState May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreStateMachine::pushState May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioPushState May 11 20:28:49 volumio-sydcesar volumio[805]: info: MRS: Pushing multiroomSync output update for this device May 11 20:28:49 volumio-sydcesar volumio[805]: info: MRS: Pushing multiroomSync output May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:49 volumio-sydcesar volumio[805]: info: CorePlayQueue::getTrack 4 May 11 20:28:49 volumio-sydcesar volumio[805]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received qobuzconnect May 11 20:28:49 volumio-sydcesar volumio[805]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect May 11 20:28:49 volumio-sydcesar volumio[805]: info: QobuzConnect: setActiveState() completed, send playback confirmation May 11 20:28:49 volumio-sydcesar volumio[805]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.nmcNiEMY2MdpUWmB32F14OLeZyx2.a4bc0786edecf44828de5048866ba4a5.state.status' May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.204 [1264.1264] INFO SampleApp: Initiating playback, initial state: Playing May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.216 [1264.1264] INFO SampleApp: Playback state changed: Playing May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.216 [1264.1264] INFO StreamingEventReporter: [0x19b0070]: Reporting streaming started event May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.216 [1264.1264] INFO HttpDownloader: [0x19b00c8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.216 [1264.1264] INFO ProtocolHandler: [0x19afc58]: Need to send state update May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.216 [1264.1264] INFO QConnectMessageSender: [0x1aa4280]: Sending StateUpdated message May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.216 [1264.1264] INFO QwspMessageSender: [0x1b083a8]: Sending Payload message May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.216 [1264.1264] INFO QConnectMessageSender: [0x1aa4280]: Sending FileAudioQualityChanged message May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.217 [1264.1264] INFO QwspMessageSender: [0x1b083a8]: Sending Payload message May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.217 [1264.1264] INFO QConnectMessageSender: [0x1aa4280]: Sending DeviceAudioQualityChanged message May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.217 [1264.1264] INFO QwspMessageSender: [0x1b083a8]: Sending Payload message May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreCommandRouter::servicePushState May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreStateMachine::pushState May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioPushState May 11 20:28:49 volumio-sydcesar volumio[805]: info: MRS: Pushing multiroomSync output update for this device May 11 20:28:49 volumio-sydcesar volumio[805]: info: MRS: Pushing multiroomSync output May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.223 [1264.1264] INFO VolumeManager: [0x19af980]: Setting new playback volume: 100 May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.224 [1264.1264] INFO VolumeManager: [0x19af980]: Setting new mute state: 1 May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.224 [1264.1264] INFO SampleApp: Playback mute state changed: 1 May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.224 [1264.1264] INFO ProtocolHandler: [0x19afc58]: Mute changed: 1 May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.224 [1264.1264] INFO QConnectMessageSender: [0x1aa4280]: Sending VolumeMuted message May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.224 [1264.1264] INFO QwspMessageSender: [0x1b083a8]: Sending Payload message May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreCommandRouter::servicePushState May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreStateMachine::pushState May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioPushState May 11 20:28:49 volumio-sydcesar volumio[805]: info: MRS: Pushing multiroomSync output update for this device May 11 20:28:49 volumio-sydcesar volumio[805]: info: MRS: Pushing multiroomSync output May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreCommandRouter::servicePushState May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreStateMachine::pushState May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioPushState May 11 20:28:49 volumio-sydcesar volumio[805]: info: MRS: Pushing multiroomSync output update for this device May 11 20:28:49 volumio-sydcesar volumio[805]: info: MRS: Pushing multiroomSync output May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:49 volumio-sydcesar volumio[805]: info: QobuzConnect: Mute state: true May 11 20:28:49 volumio-sydcesar volumio[805]: info: VolumeController::SetAlsaVolumemute May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreStateMachine::pushState May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioPushState May 11 20:28:49 volumio-sydcesar volumio[805]: info: MRS: Pushing multiroomSync output update for this device May 11 20:28:49 volumio-sydcesar volumio[805]: info: MRS: Pushing multiroomSync output May 11 20:28:49 volumio-sydcesar volumio[805]: info: CoreCommandRouter::volumioGetState May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.395 [1264.1264] INFO UrlAudioSource: [0x1b62638]: Content info extracted May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.396 [1264.1264] INFO UrlAudioSource: [0x1b62638]: Size: 78249094 May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.396 [1264.2921] INFO AudioDecoder: [0x1bf2cb0]: Stream info: May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.396 [1264.2921] INFO AudioDecoder: [0x1bf2cb0]: Total samples: 20602880 May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.396 [1264.2921] INFO AudioDecoder: [0x1bf2cb0]: Sample rate: 96000 May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.396 [1264.2921] INFO AudioDecoder: [0x1bf2cb0]: Channel count: 2 May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.396 [1264.2921] INFO AudioDecoder: [0x1bf2cb0]: Bits per sample: 24 May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.396 [1264.2921] INFO AudioDecoder: [0x1bf2cb0]: Duration: 214613 May 11 20:28:49 volumio-sydcesar qobuz-connect[1264]: 20250511 20:28:49.396 [1264.1264] INFO UrlAudioSource: [0x1b62638]: Audio properties have changed May 11 20:28:53 volumio-sydcesar volumio[805]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 20:28:53 volumio-sydcesar volumio[805]: Error: connect ETIMEDOUT 151.101.134.79:443 May 11 20:28:53 volumio-sydcesar volumio[805]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { May 11 20:28:53 volumio-sydcesar volumio[805]: errno: -110, May 11 20:28:53 volumio-sydcesar volumio[805]: code: 'ETIMEDOUT', May 11 20:28:53 volumio-sydcesar volumio[805]: syscall: 'connect', May 11 20:28:53 volumio-sydcesar volumio[805]: address: '151.101.134.79', May 11 20:28:53 volumio-sydcesar volumio[805]: port: 443 May 11 20:28:53 volumio-sydcesar volumio[805]: } May 11 20:28:53 volumio-sydcesar volumio[805]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 20:28:53 volumio-sydcesar sudo[2935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-11 20:27 May 11 20:28:53 volumio-sydcesar sudo[2935]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"