-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Mon 2025-05-19 17:58:07 BST. -- May 19 17:57:23 loungeaudio volumio[822]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 19 17:57:23 loungeaudio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 19 17:57:23 loungeaudio volumio[822]: info: Discovery: Getting this device information May 19 17:57:23 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:57:23 loungeaudio volumio[822]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 19 17:57:53 loungeaudio volumio[822]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 19 17:57:53 loungeaudio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 19 17:57:53 loungeaudio volumio[822]: info: Discovery: Getting this device information May 19 17:57:53 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:57:53 loungeaudio volumio[822]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 19 17:57:54 loungeaudio volumio[822]: info: CoreCommandRouter::volumioPause May 19 17:57:54 loungeaudio volumio[822]: info: CoreStateMachine::pause May 19 17:57:54 loungeaudio volumio[822]: info: CoreStateMachine::stPlaybackTimer May 19 17:57:54 loungeaudio volumio[822]: info: CoreStateMachine::servicePause May 19 17:57:54 loungeaudio volumio[822]: info: CorePlayQueue::getTrack 0 May 19 17:57:54 loungeaudio volumio[822]: info: CoreCommandRouter::servicePause May 19 17:57:54 loungeaudio volumio[822]: info: CoreStateMachine::setConsumeUpdateService mpd May 19 17:57:54 loungeaudio volumio[822]: info: ControllerMpd::pause May 19 17:57:54 loungeaudio volumio[822]: verbose: ControllerMpd::sendMpdCommand pause May 19 17:57:54 loungeaudio volumio[822]: info: May 19 17:57:54 loungeaudio volumio[822]: ---------------------------- MPD announces state update: player May 19 17:57:54 loungeaudio volumio[822]: info: sendMpdCommand pause took 2 milliseconds May 19 17:57:54 loungeaudio volumio[822]: info: ControllerMpd::getState May 19 17:57:54 loungeaudio volumio[822]: verbose: ControllerMpd::sendMpdCommand status May 19 17:57:54 loungeaudio volumio[822]: info: May 19 17:57:54 loungeaudio volumio[822]: ---------------------------- MPD announces state update: player May 19 17:57:54 loungeaudio volumio[822]: info: ControllerMpd::getState May 19 17:57:54 loungeaudio volumio[822]: verbose: ControllerMpd::sendMpdCommand status May 19 17:57:54 loungeaudio volumio[822]: info: sendMpdCommand status took 1 milliseconds May 19 17:57:54 loungeaudio volumio[822]: info: sendMpdCommand status took 0 milliseconds May 19 17:57:54 loungeaudio volumio[822]: verbose: ControllerMpd::parseState May 19 17:57:54 loungeaudio volumio[822]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 19 17:57:54 loungeaudio volumio[822]: verbose: ControllerMpd::parseState May 19 17:57:54 loungeaudio volumio[822]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 19 17:57:54 loungeaudio volumio[822]: info: sendMpdCommand playlistinfo took 0 milliseconds May 19 17:57:54 loungeaudio volumio[822]: info: sendMpdCommand playlistinfo took 1 milliseconds May 19 17:57:54 loungeaudio volumio[822]: verbose: ControllerMpd::parseTrackInfo May 19 17:57:54 loungeaudio volumio[822]: info: ControllerMpd::pushState May 19 17:57:54 loungeaudio volumio[822]: info: CoreCommandRouter::servicePushState May 19 17:57:54 loungeaudio volumio[822]: info: CorePlayQueue::getTrack 0 May 19 17:57:54 loungeaudio volumio[822]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":211257,"duration":408,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fight Like Gods","artist":"Chelsea Wolfe","album":"Sing Songs Together","uri":"https://t4.bcbits.com/stream/cbbf95ed506efd92285980203c14f219/mp3-128/2466637061?p=0&ts=1747760060&t=6bff72f37e57c736f366ab14670f7b96394723b7&token=1747760060_7675cdcc09a5989115e957e998ef6343a202cca2&t.mp3","trackType":"mp3"} May 19 17:57:54 loungeaudio volumio[822]: verbose: CURRENT POSITION 0 May 19 17:57:54 loungeaudio volumio[822]: info: CoreStateMachine::syncState stateService pause May 19 17:57:54 loungeaudio volumio[822]: info: CoreStateMachine::syncState currentStatus pause May 19 17:57:54 loungeaudio volumio[822]: info: CoreStateMachine::pushState May 19 17:57:54 loungeaudio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 17:57:54 loungeaudio volumio[822]: info: CoreCommandRouter::volumioPushState May 19 17:57:54 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:57:54 loungeaudio volumio[822]: info: CoreStateMachine::stPlaybackTimer May 19 17:57:54 loungeaudio volumio[822]: info: ControllerMpd::pushState May 19 17:57:54 loungeaudio volumio[822]: info: CoreCommandRouter::servicePushState May 19 17:57:54 loungeaudio volumio[822]: info: CorePlayQueue::getTrack 0 May 19 17:57:54 loungeaudio volumio[822]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":211257,"duration":408,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fight Like Gods","artist":"Chelsea Wolfe","album":"Sing Songs Together","uri":"https://t4.bcbits.com/stream/cbbf95ed506efd92285980203c14f219/mp3-128/2466637061?p=0&ts=1747760060&t=6bff72f37e57c736f366ab14670f7b96394723b7&token=1747760060_7675cdcc09a5989115e957e998ef6343a202cca2&t.mp3","trackType":"mp3"} May 19 17:57:54 loungeaudio volumio[822]: verbose: CURRENT POSITION 0 May 19 17:57:54 loungeaudio volumio[822]: info: CoreStateMachine::syncState stateService pause May 19 17:57:54 loungeaudio volumio[822]: info: CoreStateMachine::syncState currentStatus pause May 19 17:57:54 loungeaudio volumio[822]: info: CoreStateMachine::pushState May 19 17:57:54 loungeaudio volumio[822]: info: CoreCommandRouter::volumioPushState May 19 17:57:54 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:57:54 loungeaudio volumio[822]: info: CoreStateMachine::stPlaybackTimer May 19 17:57:54 loungeaudio volumio[822]: info: ------------------------------ 28ms May 19 17:57:54 loungeaudio volumio[822]: info: ------------------------------ 27ms May 19 17:57:54 loungeaudio volumio[822]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 May 19 17:57:54 loungeaudio volumio[822]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 May 19 17:57:58 loungeaudio qobuz-connect[1477]: 20250519 17:57:58.595 [1477.5603] INFO SampleApp: API endpoint invoked: get-display-info May 19 17:57:58 loungeaudio qobuz-connect[1477]: 20250519 17:57:58.607 [1477.5603] INFO SampleApp: API endpoint invoked: get-connect-info May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.150 [1477.5603] INFO SampleApp: API endpoint invoked: connect-to-qconnect May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.150 [1477.1477] INFO EndpointManager: [0x1be0188]: Updating API endpoint May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.150 [1477.1477] INFO EndpointManager: [0x1be0188]: Updating QConnect endpoint May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.150 [1477.1477] INFO ActiveStateManager: [0x1bdf1a0]: Setting new active state: active May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.150 [1477.1477] INFO PlaybackSessionManager: [0x1be0578]: Starting playback session maintenance May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.150 [1477.1477] INFO HttpDownloader: [0x1be0768]: Downloading content from: https://www.qobuz.com/api.json/0.2/session/start May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.151 [1477.1477] INFO CloudClient: [0x1be0de8]: Connecting to the cloud May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.151 [1477.1477] INFO SampleApp: Renderer is now active May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.276 [1477.1477] INFO CloudClient: [0x1be0de8]: Connection established May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.276 [1477.1477] INFO QwspMessageSender: [0x1ceaee0]: Sending Authenticate message May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.276 [1477.1477] INFO QwspMessageSender: [0x1ceaee0]: Sending Subscribe message May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.276 [1477.1477] INFO QConnectMessageSender: [0x1c20950]: Sending JoinSession message May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.276 [1477.1477] INFO QConnectMessageSender: [0x1c20950]: Sending VolumeChanged message May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.276 [1477.1477] INFO QConnectMessageSender: [0x1c20950]: Sending VolumeMuted message May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.276 [1477.1477] INFO QConnectMessageSender: [0x1c20950]: Sending MaxAudioQualityChanged message May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.276 [1477.1477] INFO QwspMessageSender: [0x1ceaee0]: Sending Payload message May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.310 [1477.1477] INFO PlaybackSessionManager: [0x1be0578]: Playback session has been refreshed May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.330 [1477.1477] INFO ProtocolHandler: [0x1be0f50]: Received SetActive message: active May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.330 [1477.1477] INFO ProtocolHandler: [0x1be0f50]: Received SetState message: May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.330 [1477.1477] INFO ProtocolHandler: [0x1be0f50]: Playing state: Paused May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.330 [1477.1477] INFO ProtocolHandler: [0x1be0f50]: Playback position: 534 May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.330 [1477.1477] INFO ProtocolHandler: [0x1be0f50]: Queue version: 8.1 May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.330 [1477.1477] INFO ProtocolHandler: [0x1be0f50]: Current track: TID: 306620896, QID: 0, Context UUID: 37948bda-4eea-4e86-b6fb-575a53aec761 May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.331 [1477.1477] INFO ProtocolHandler: [0x1be0f50]: Next track: TID: 299131602, QID: 1, Context UUID: 37948bda-4eea-4e86-b6fb-575a53aec761 May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.331 [1477.1477] INFO MediaEngine: [0x1be0810]: Stopping playback, clearing tracks May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.331 [1477.1477] INFO MediaEngine: [0x1be0810]: Initiating playback May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.331 [1477.1477] INFO RendererActionAvailabilityManager: [0x1be0ea0]: Renderer action 'Next' is available May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.331 [1477.1477] INFO ProtocolHandler: [0x1be0f50]: Received SetLoopMode message: Off May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.331 [1477.1477] INFO PlaybackControlsManager: [0x1be0be8]: Setting new loop mode: Off May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.331 [1477.1477] INFO MediaEngine: [0x1be0810]: Setting current track: 306620896, initial offset: 534ms May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.331 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: Clearing all streams May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.331 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: New stream: 1 May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.331 [1477.1477] INFO HttpDownloader: [0x1be43f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=1f7f9a58c4ab3c6825f57e9af3c13460&request_ts=1747673880&track_id=306620896 May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.331 [1477.1477] INFO HttpDownloader: [0x1be6470]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=306620896 May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.331 [1477.1477] INFO HttpDownloader: [0x1bec9a0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=4fd7a1485385ba72c580c1a68bc1bb39&request_ts=1747673880&track_id=306620896 May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.331 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: [Stream 1]: Running audio stream May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.331 [1477.1477] INFO ProtocolHandler: [0x1be0f50]: Received SetShuffleMode message: disabled May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.331 [1477.1477] INFO PlaybackControlsManager: [0x1be0be8]: Setting new shuffle mode: disabled May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.332 [1477.1477] INFO MediaEngine: [0x1be0810]: Setting next track: 299131602 May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.332 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: New stream: 2 May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.332 [1477.1477] INFO HttpDownloader: [0x1cc05a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=07b72961e80eb3d505cc1df512bc45da&request_ts=1747673880&track_id=299131602 May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.332 [1477.1477] INFO HttpDownloader: [0x1cc0908]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=299131602 May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.332 [1477.1477] INFO HttpDownloader: [0x1cc29d8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=182ffc0ebb1341a6404ef57c7efa3d2b&request_ts=1747673880&track_id=299131602 May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.332 [1477.1477] INFO MediaEngine: [0x1be0810]: Waiting for current stream to start before starting audio renderer May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.442 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: [Stream 2]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2358809&eid=299131602&fmt=6&profile=raw&app_id=174516466&cid=1669468&etsp=1747677480&hmac=c4qo5fNiBL1aNHG05JUfmsw7OqY May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.467 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: [Stream 1]: stream information have been fetched May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.477 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: [Stream 2]: stream information have been fetched May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.479 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: [Stream 2]: Metadata became available: May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.480 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: Title: Dusk May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.480 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: Artist: Chelsea Wolfe May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.480 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: Album: mob wife era by The Circle Sessions May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.480 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: Album art URL: https://static.qobuz.com/images/covers/zb/gp/upwe7vc9sgpzb_600.jpg May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.500 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: [Stream 1]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2358809&eid=306620896&fmt=6&profile=raw&app_id=174516466&cid=1669468&etsp=1747677480&hmac=NC0nS_Bot8VBt1mQQj9R07FbRRI May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.500 [1477.1477] INFO UrlAudioSource: [0x1c1c258]: Starting URL audio source, initial position: 534ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2358809&eid=306620896&fmt=6&profile=raw&app_id=174516466&cid=1669468&etsp=1747677480&hmac=NC0nS_Bot8VBt1mQQj9R07FbRRI May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.501 [1477.1477] INFO ContentFetcher: [0x1be6b18]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2358809&eid=306620896&fmt=6&profile=raw&app_id=174516466&cid=1669468&etsp=1747677480&hmac=NC0nS_Bot8VBt1mQQj9R07FbRRI, offset: 0 May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.501 [1477.1477] INFO AudioRenderer: [0x1be0a30]: Starting audio renderer, initial playback state: Paused May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.501 [1477.1477] INFO SampleApp: [Stream 1]: New audio stream (starting from 533ms) May 19 17:58:00 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:58:00 loungeaudio volumio[822]: info: QobuzConnect: setActiveState() May 19 17:58:00 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:58:00 loungeaudio volumio[822]: info: QobuzConnect: Set Qobuz connect default volume to 20 May 19 17:58:00 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.504 [1477.1477] INFO VolumeManager: [0x1be0c78]: Setting new playback volume: 20 May 19 17:58:00 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:58:00 loungeaudio volumio[822]: info: QobuzConnect: Stopping currently active service May 19 17:58:00 loungeaudio volumio[822]: info: CoreCommandRouter::volumioStop May 19 17:58:00 loungeaudio volumio[822]: info: CoreStateMachine::stop May 19 17:58:00 loungeaudio volumio[822]: info: CoreStateMachine::setConsumeUpdateService undefined May 19 17:58:00 loungeaudio volumio[822]: info: CoreStateMachine::updateTrackBlock May 19 17:58:00 loungeaudio volumio[822]: info: CorePlayQueue::getTrackBlock May 19 17:58:00 loungeaudio volumio[822]: info: CoreStateMachine::stPlaybackTimer May 19 17:58:00 loungeaudio volumio[822]: info: CoreStateMachine::pushState May 19 17:58:00 loungeaudio volumio[822]: info: CorePlayQueue::getTrack 0 May 19 17:58:00 loungeaudio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 17:58:00 loungeaudio volumio[822]: info: CoreCommandRouter::volumioPushState May 19 17:58:00 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:58:00 loungeaudio volumio[822]: info: CorePlayQueue::getTrack 0 May 19 17:58:00 loungeaudio volumio[822]: info: CoreStateMachine::serviceStop May 19 17:58:00 loungeaudio volumio[822]: info: CorePlayQueue::getTrack 0 May 19 17:58:00 loungeaudio volumio[822]: info: CoreCommandRouter::serviceStop May 19 17:58:00 loungeaudio volumio[822]: info: CoreStateMachine::setConsumeUpdateService mpd May 19 17:58:00 loungeaudio volumio[822]: info: ControllerMpd::stop May 19 17:58:00 loungeaudio volumio[822]: verbose: ControllerMpd::sendMpdCommand stop May 19 17:58:00 loungeaudio volumio[822]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 May 19 17:58:00 loungeaudio volumio[822]: info: May 19 17:58:00 loungeaudio volumio[822]: ---------------------------- MPD announces state update: player May 19 17:58:00 loungeaudio volumio[822]: info: sendMpdCommand stop took 9 milliseconds May 19 17:58:00 loungeaudio volumio[822]: info: ControllerMpd::getState May 19 17:58:00 loungeaudio volumio[822]: verbose: ControllerMpd::sendMpdCommand status May 19 17:58:00 loungeaudio volumio[822]: info: CoreStateMachine::setConsumeUpdateService undefined May 19 17:58:00 loungeaudio volumio[822]: info: CoreCommandRouter::servicePushState May 19 17:58:00 loungeaudio volumio[822]: info: CoreStateMachine::pushState May 19 17:58:00 loungeaudio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 17:58:00 loungeaudio volumio[822]: info: CoreCommandRouter::volumioPushState May 19 17:58:00 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:58:00 loungeaudio volumio[822]: info: CorePlayQueue::getTrack 0 May 19 17:58:00 loungeaudio volumio[822]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current bandcamp Received qobuzconnect May 19 17:58:00 loungeaudio volumio[822]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect May 19 17:58:00 loungeaudio volumio[822]: info: QobuzConnect: setActiveState() completed, send playback confirmation May 19 17:58:00 loungeaudio volumio[822]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 May 19 17:58:00 loungeaudio volumio[822]: info: sendMpdCommand status took 22 milliseconds May 19 17:58:00 loungeaudio volumio[822]: verbose: ControllerMpd::parseState May 19 17:58:00 loungeaudio volumio[822]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 19 17:58:00 loungeaudio volumio[822]: info: May 19 17:58:00 loungeaudio volumio[822]: ---------------------------- MPD announces state update: player May 19 17:58:00 loungeaudio volumio[822]: info: ControllerMpd::getState May 19 17:58:00 loungeaudio volumio[822]: verbose: ControllerMpd::sendMpdCommand status May 19 17:58:00 loungeaudio volumio[822]: info: sendMpdCommand playlistinfo took 8 milliseconds May 19 17:58:00 loungeaudio volumio[822]: info: sendMpdCommand status took 7 milliseconds May 19 17:58:00 loungeaudio volumio[822]: verbose: ControllerMpd::parseTrackInfo May 19 17:58:00 loungeaudio volumio[822]: verbose: ControllerMpd::parseState May 19 17:58:00 loungeaudio volumio[822]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 19 17:58:00 loungeaudio volumio[822]: info: ControllerMpd::pushState May 19 17:58:00 loungeaudio volumio[822]: info: CoreCommandRouter::servicePushState May 19 17:58:00 loungeaudio volumio[822]: info: CoreStateMachine::pushState May 19 17:58:00 loungeaudio volumio[822]: info: CoreCommandRouter::volumioPushState May 19 17:58:00 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:58:00 loungeaudio volumio[822]: info: CorePlayQueue::getTrack 0 May 19 17:58:00 loungeaudio volumio[822]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current bandcamp Received mpd May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.573 [1477.1477] INFO SampleApp: Initiating playback, initial state: Paused May 19 17:58:00 loungeaudio volumio[822]: info: ------------------------------ 51ms May 19 17:58:00 loungeaudio volumio[822]: info: sendMpdCommand playlistinfo took 20 milliseconds May 19 17:58:00 loungeaudio volumio[822]: verbose: ControllerMpd::parseTrackInfo May 19 17:58:00 loungeaudio volumio[822]: info: ControllerMpd::pushState May 19 17:58:00 loungeaudio volumio[822]: info: CoreCommandRouter::servicePushState May 19 17:58:00 loungeaudio volumio[822]: info: CoreStateMachine::pushState May 19 17:58:00 loungeaudio volumio[822]: info: CoreCommandRouter::volumioPushState May 19 17:58:00 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:58:00 loungeaudio volumio[822]: info: CorePlayQueue::getTrack 0 May 19 17:58:00 loungeaudio volumio[822]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current bandcamp Received mpd May 19 17:58:00 loungeaudio volumio[822]: info: ------------------------------ 44ms May 19 17:58:00 loungeaudio volumio[822]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 May 19 17:58:00 loungeaudio volumio[822]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 May 19 17:58:00 loungeaudio volumio[822]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.DxASekcaG4NsRgMkpegFO0SKvXm1.d2ae6da250336d6f3241b2456d526d1f.state.status' May 19 17:58:00 loungeaudio volumio[822]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.DxASekcaG4NsRgMkpegFO0SKvXm1.d2ae6da250336d6f3241b2456d526d1f.state.status' May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.612 [1477.1477] INFO SampleApp: Playback state changed: Paused May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.612 [1477.1477] INFO ProtocolHandler: [0x1be0f50]: Need to send state update May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.612 [1477.1477] INFO QConnectMessageSender: [0x1c20950]: Sending StateUpdated message May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.613 [1477.1477] INFO QwspMessageSender: [0x1ceaee0]: Sending Payload message May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.613 [1477.1477] INFO QConnectMessageSender: [0x1c20950]: Sending FileAudioQualityChanged message May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.613 [1477.1477] INFO QwspMessageSender: [0x1ceaee0]: Sending Payload message May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.613 [1477.1477] INFO QConnectMessageSender: [0x1c20950]: Sending DeviceAudioQualityChanged message May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.613 [1477.1477] INFO QwspMessageSender: [0x1ceaee0]: Sending Payload message May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.614 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: [Stream 1]: Metadata became available: May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.614 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: Title: Whispers In The Echo Chamber May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.614 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: Artist: Chelsea Wolfe May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.614 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: Album: Gothic & Darkwave May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.614 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: Album art URL: https://static.qobuz.com/images/covers/1b/07/fokm9y20v071b_600.jpg May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.614 [1477.1477] INFO SampleApp: [Stream 1]: Stream metadata became available: May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.614 [1477.1477] INFO SampleApp: Title: Whispers In The Echo Chamber May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.614 [1477.1477] INFO SampleApp: Artist: Chelsea Wolfe May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.614 [1477.1477] INFO SampleApp: Album: Gothic & Darkwave May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.614 [1477.1477] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/1b/07/fokm9y20v071b_600.jpg May 19 17:58:00 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.654 [1477.1477] INFO SampleApp: Playback volume changed: 20 May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.655 [1477.1477] INFO ProtocolHandler: [0x1be0f50]: Volume changed: 20 May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.655 [1477.1477] INFO QConnectMessageSender: [0x1c20950]: Sending VolumeChanged message May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.655 [1477.1477] INFO QwspMessageSender: [0x1ceaee0]: Sending Payload message May 19 17:58:00 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.935 [1477.1477] INFO UrlAudioSource: [0x1c1c258]: Content info extracted May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.935 [1477.1477] INFO UrlAudioSource: [0x1c1c258]: Size: 26634707 May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.935 [1477.5608] INFO AudioDecoder: [0x1e5a670]: Stream info: May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.935 [1477.5608] INFO AudioDecoder: [0x1e5a670]: Total samples: 10573812 May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.935 [1477.5608] INFO AudioDecoder: [0x1e5a670]: Sample rate: 44100 May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.935 [1477.5608] INFO AudioDecoder: [0x1e5a670]: Channel count: 2 May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.935 [1477.5608] INFO AudioDecoder: [0x1e5a670]: Bits per sample: 16 May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.935 [1477.5608] INFO AudioDecoder: [0x1e5a670]: Duration: 239768 May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.935 [1477.1477] INFO UrlAudioSource: [0x1c1c258]: Audio properties have changed May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.935 [1477.1477] INFO UrlAudioSource: [0x1c1c258]: Seeking input stream to byte offset: 42531 May 19 17:58:00 loungeaudio qobuz-connect[1477]: 20250519 17:58:00.935 [1477.1477] INFO ContentFetcher: [0x1be6b18]: Adjusting download offset to: 42531 May 19 17:58:01 loungeaudio qobuz-connect[1477]: 20250519 17:58:01.863 [1477.1477] INFO ProtocolHandler: [0x1be0f50]: Received SetVolume message, volume: 39 May 19 17:58:01 loungeaudio qobuz-connect[1477]: 20250519 17:58:01.863 [1477.1477] INFO VolumeManager: [0x1be0c78]: Setting new playback volume: 39 May 19 17:58:01 loungeaudio qobuz-connect[1477]: 20250519 17:58:01.969 [1477.1477] INFO ProtocolHandler: [0x1be0f50]: Received SetVolume message, volume: 81 May 19 17:58:01 loungeaudio qobuz-connect[1477]: 20250519 17:58:01.969 [1477.1477] INFO VolumeManager: [0x1be0c78]: Setting new playback volume: 81 May 19 17:58:02 loungeaudio qobuz-connect[1477]: 20250519 17:58:02.014 [1477.1477] INFO SampleApp: Playback volume changed: 81 May 19 17:58:02 loungeaudio qobuz-connect[1477]: 20250519 17:58:02.014 [1477.1477] INFO ProtocolHandler: [0x1be0f50]: Volume changed: 81 May 19 17:58:02 loungeaudio qobuz-connect[1477]: 20250519 17:58:02.014 [1477.1477] INFO QConnectMessageSender: [0x1c20950]: Sending VolumeChanged message May 19 17:58:02 loungeaudio qobuz-connect[1477]: 20250519 17:58:02.014 [1477.1477] INFO QwspMessageSender: [0x1ceaee0]: Sending Payload message May 19 17:58:02 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:58:02 loungeaudio qobuz-connect[1477]: 20250519 17:58:02.033 [1477.1477] INFO ProtocolHandler: [0x1be0f50]: Received SetVolume message, volume: 100 May 19 17:58:02 loungeaudio qobuz-connect[1477]: 20250519 17:58:02.033 [1477.1477] INFO VolumeManager: [0x1be0c78]: Setting new playback volume: 100 May 19 17:58:02 loungeaudio qobuz-connect[1477]: 20250519 17:58:02.183 [1477.1477] INFO SampleApp: Playback volume changed: 100 May 19 17:58:02 loungeaudio qobuz-connect[1477]: 20250519 17:58:02.183 [1477.1477] INFO ProtocolHandler: [0x1be0f50]: Volume changed: 100 May 19 17:58:02 loungeaudio qobuz-connect[1477]: 20250519 17:58:02.183 [1477.1477] INFO QConnectMessageSender: [0x1c20950]: Sending VolumeChanged message May 19 17:58:02 loungeaudio qobuz-connect[1477]: 20250519 17:58:02.183 [1477.1477] INFO QwspMessageSender: [0x1ceaee0]: Sending Payload message May 19 17:58:02 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.435 [1477.1477] INFO ProtocolHandler: [0x1be0f50]: Received SetState message: May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.435 [1477.1477] INFO ProtocolHandler: [0x1be0f50]: Playing state: Playing May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.435 [1477.1477] INFO ProtocolHandler: [0x1be0f50]: Playback position: 0 May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.435 [1477.1477] INFO ProtocolHandler: [0x1be0f50]: Queue version: 9.1 May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.435 [1477.1477] INFO ProtocolHandler: [0x1be0f50]: Current track: TID: 306620890, QID: 0, Context UUID: 366428b2-8c37-4153-a12d-cdcfced8b9dc May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.435 [1477.1477] INFO ProtocolHandler: [0x1be0f50]: Next track: TID: 306620891, QID: 1, Context UUID: 366428b2-8c37-4153-a12d-cdcfced8b9dc May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.435 [1477.1477] INFO MediaEngine: [0x1be0810]: Stopping playback, clearing tracks May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.435 [1477.1477] INFO MediaEngine: [0x1be0810]: Initiating playback May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.435 [1477.1477] INFO AudioRenderer: [0x1be0a30]: Stopping audio renderer May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.435 [1477.1477] INFO SampleApp: Stopping playback May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.447 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: Clearing all streams May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.447 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: [Stream 1]: Disposing of audio stream May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.447 [1477.1477] INFO ContentFetcher: [0x1be6b18]: Canceling download May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.450 [1477.1477] INFO SampleApp: Playback state changed: Stopped May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.450 [1477.1477] INFO ProtocolHandler: [0x1be0f50]: Need to send state update May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.450 [1477.1477] INFO QConnectMessageSender: [0x1c20950]: Sending StateUpdated message May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.451 [1477.1477] INFO SampleApp: [Stream 1]: Audio stream has been disposed of May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.451 [1477.1477] INFO MediaEngine: [0x1be0810]: Setting current track: 306620890, initial offset: 0ms May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.451 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: Clearing all streams May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.451 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: New stream: 3 May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.451 [1477.1477] INFO HttpDownloader: [0x1bec1f0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=6997a9b9a77ad96dc913d9427e2f656a&request_ts=1747673886&track_id=306620890 May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.452 [1477.1477] INFO HttpDownloader: [0x1be43f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=306620890 May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.452 [1477.1477] INFO HttpDownloader: [0x1be4140]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=f79e2004f30593602075b0947c2d9d15&request_ts=1747673886&track_id=306620890 May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.452 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: [Stream 3]: Running audio stream May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.452 [1477.1477] INFO QwspMessageSender: [0x1ceaee0]: Sending Payload message May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.453 [1477.1477] INFO MediaEngine: [0x1be0810]: Setting next track: 306620891 May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.453 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: New stream: 4 May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.453 [1477.1477] INFO HttpDownloader: [0x1c1c120]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=7a2cbade2358484c5a29bfe345e8e00f&request_ts=1747673886&track_id=306620891 May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.453 [1477.1477] INFO HttpDownloader: [0x1cc0cf8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=306620891 May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.453 [1477.1477] INFO HttpDownloader: [0x1bec8f0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=451d0a3c39037b3726dc0d29fbc1de6a&request_ts=1747673886&track_id=306620891 May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.454 [1477.1477] INFO MediaEngine: [0x1be0810]: Waiting for current stream to start before starting audio renderer May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.522 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: [Stream 3]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2358809&eid=306620890&fmt=6&profile=raw&app_id=174516466&cid=1669468&etsp=1747677486&hmac=kF-H2SEsmRqZdVItUOwX87QghdQ May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.556 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: [Stream 4]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2358809&eid=306620891&fmt=6&profile=raw&app_id=174516466&cid=1669468&etsp=1747677486&hmac=w9gFDPbtf_TCpJtLzpNeyt_HySw May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.586 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: [Stream 4]: Metadata became available: May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.586 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: Title: Christine May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.586 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: Artist: Siouxsie & The Banshees May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.586 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: Album: Gothic & Darkwave May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.586 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: Album art URL: https://static.qobuz.com/images/covers/1b/07/fokm9y20v071b_600.jpg May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.598 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: [Stream 3]: stream information have been fetched May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.598 [1477.1477] INFO UrlAudioSource: [0x1dffe50]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2358809&eid=306620890&fmt=6&profile=raw&app_id=174516466&cid=1669468&etsp=1747677486&hmac=kF-H2SEsmRqZdVItUOwX87QghdQ May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.598 [1477.1477] INFO ContentFetcher: [0x1e2cff8]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2358809&eid=306620890&fmt=6&profile=raw&app_id=174516466&cid=1669468&etsp=1747677486&hmac=kF-H2SEsmRqZdVItUOwX87QghdQ, offset: 0 May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.598 [1477.1477] INFO AudioRenderer: [0x1be0a30]: Starting audio renderer, initial playback state: Playing May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.598 [1477.1477] INFO SampleApp: [Stream 3]: New audio stream (starting from 0ms) May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:58:06 loungeaudio volumio[822]: info: QobuzConnect: setActiveState() May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:58:06 loungeaudio volumio[822]: info: QobuzConnect: Set Qobuz connect default volume to 20 May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.599 [1477.1477] INFO VolumeManager: [0x1be0c78]: Setting new playback volume: 20 May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:58:06 loungeaudio volumio[822]: info: QobuzConnect: Stopping currently active service May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::volumioStop May 19 17:58:06 loungeaudio volumio[822]: info: CoreStateMachine::stop May 19 17:58:06 loungeaudio volumio[822]: info: CoreStateMachine::serviceStop May 19 17:58:06 loungeaudio volumio[822]: info: Received STOP, but no service to execute it May 19 17:58:06 loungeaudio volumio[822]: info: CoreStateMachine::setConsumeUpdateService undefined May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::servicePushState May 19 17:58:06 loungeaudio volumio[822]: info: CoreStateMachine::pushState May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::volumioPushState May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:58:06 loungeaudio volumio[822]: info: CorePlayQueue::getTrack 0 May 19 17:58:06 loungeaudio volumio[822]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current bandcamp Received qobuzconnect May 19 17:58:06 loungeaudio volumio[822]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect May 19 17:58:06 loungeaudio volumio[822]: info: QobuzConnect: setActiveState() completed, send playback confirmation May 19 17:58:06 loungeaudio volumio[822]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 May 19 17:58:06 loungeaudio volumio[822]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.DxASekcaG4NsRgMkpegFO0SKvXm1.d2ae6da250336d6f3241b2456d526d1f.state.status' May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.640 [1477.1477] INFO SampleApp: Initiating playback, initial state: Playing May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.653 [1477.1477] INFO SampleApp: Playback state changed: Playing May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.653 [1477.1477] INFO StreamingEventReporter: [0x1be1368]: Reporting streaming started event May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.653 [1477.1477] INFO HttpDownloader: [0x1be13c0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.653 [1477.1477] INFO ProtocolHandler: [0x1be0f50]: Need to send state update May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.653 [1477.1477] INFO QConnectMessageSender: [0x1c20950]: Sending StateUpdated message May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.653 [1477.1477] INFO QwspMessageSender: [0x1ceaee0]: Sending Payload message May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.653 [1477.1477] INFO QConnectMessageSender: [0x1c20950]: Sending FileAudioQualityChanged message May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.654 [1477.1477] INFO QwspMessageSender: [0x1ceaee0]: Sending Payload message May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::servicePushState May 19 17:58:06 loungeaudio volumio[822]: info: CoreStateMachine::pushState May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.654 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: [Stream 4]: stream information have been fetched May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.654 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: [Stream 3]: Metadata became available: May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.654 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: Title: These Things May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.654 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: Artist: She Wants Revenge May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.654 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: Album: Gothic & Darkwave May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.654 [1477.1477] INFO AudioStreamManager: [0x1be08f8]: Album art URL: https://static.qobuz.com/images/covers/1b/07/fokm9y20v071b_600.jpg May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.655 [1477.1477] INFO SampleApp: [Stream 3]: Stream metadata became available: May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.655 [1477.1477] INFO SampleApp: Title: These Things May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.655 [1477.1477] INFO SampleApp: Artist: She Wants Revenge May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.655 [1477.1477] INFO SampleApp: Album: Gothic & Darkwave May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.655 [1477.1477] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/1b/07/fokm9y20v071b_600.jpg May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::volumioPushState May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.662 [1477.1477] INFO VolumeManager: [0x1be0c78]: Setting new playback volume: 20 May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.662 [1477.1477] INFO VolumeManager: [0x1be0c78]: Setting new mute state: 0 May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::servicePushState May 19 17:58:06 loungeaudio volumio[822]: info: CoreStateMachine::pushState May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::volumioPushState May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::servicePushState May 19 17:58:06 loungeaudio volumio[822]: info: CoreStateMachine::pushState May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::volumioPushState May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::servicePushState May 19 17:58:06 loungeaudio volumio[822]: info: CoreStateMachine::pushState May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::volumioPushState May 19 17:58:06 loungeaudio volumio[822]: info: CoreCommandRouter::volumioGetState May 19 17:58:06 loungeaudio volumio[822]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! May 19 17:58:06 loungeaudio volumio[822]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 19 17:58:06 loungeaudio volumio[822]: TypeError: Cannot read property 'indexOf' of undefined May 19 17:58:06 loungeaudio volumio[822]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) May 19 17:58:06 loungeaudio volumio[822]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) May 19 17:58:06 loungeaudio volumio[822]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) May 19 17:58:06 loungeaudio volumio[822]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 19 17:58:06 loungeaudio volumio[822]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) May 19 17:58:06 loungeaudio volumio[822]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) May 19 17:58:06 loungeaudio volumio[822]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 19 17:58:06 loungeaudio volumio[822]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 19 17:58:06 loungeaudio volumio[822]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) May 19 17:58:06 loungeaudio volumio[822]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 19 17:58:06 loungeaudio volumio[822]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) May 19 17:58:06 loungeaudio volumio[822]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) May 19 17:58:06 loungeaudio volumio[822]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) May 19 17:58:06 loungeaudio volumio[822]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 19 17:58:06 loungeaudio volumio[822]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 19 17:58:06 loungeaudio volumio[822]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) May 19 17:58:06 loungeaudio volumio[822]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.749 [1477.1477] INFO SampleApp: Playback volume changed: 20 May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.749 [1477.1477] INFO ProtocolHandler: [0x1be0f50]: Volume changed: 20 May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.749 [1477.1477] INFO QConnectMessageSender: [0x1c20950]: Sending VolumeChanged message May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.749 [1477.1477] INFO QwspMessageSender: [0x1ceaee0]: Sending Payload message May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.820 [1477.1477] INFO UrlAudioSource: [0x1dffe50]: Content info extracted May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.820 [1477.1477] INFO UrlAudioSource: [0x1dffe50]: Size: 31294242 May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.820 [1477.5621] INFO AudioDecoder: [0x1e2cd70]: Stream info: May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.820 [1477.5621] INFO AudioDecoder: [0x1e2cd70]: Total samples: 13778016 May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.820 [1477.5621] INFO AudioDecoder: [0x1e2cd70]: Sample rate: 44100 May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.820 [1477.5621] INFO AudioDecoder: [0x1e2cd70]: Channel count: 2 May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.820 [1477.5621] INFO AudioDecoder: [0x1e2cd70]: Bits per sample: 16 May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.820 [1477.5621] INFO AudioDecoder: [0x1e2cd70]: Duration: 312426 May 19 17:58:06 loungeaudio qobuz-connect[1477]: 20250519 17:58:06.821 [1477.1477] INFO UrlAudioSource: [0x1dffe50]: Audio properties have changed May 19 17:58:07 loungeaudio sudo[5623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-19 17:57 May 19 17:58:07 loungeaudio sudo[5623]: 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"