Oct 13 18:54:08 volumio-gross systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5015. Oct 13 18:54:08 volumio-gross systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 13 18:54:08 volumio-gross systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 13 18:54:08 volumio-gross upmpdcli[26048]: Could not open config: /tmp/upmpdcli.conf Oct 13 18:54:08 volumio-gross systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 13 18:54:08 volumio-gross systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 13 18:54:23 volumio-gross systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5016. Oct 13 18:54:23 volumio-gross systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 13 18:54:23 volumio-gross systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 13 18:54:23 volumio-gross upmpdcli[26064]: Could not open config: /tmp/upmpdcli.conf Oct 13 18:54:23 volumio-gross systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 13 18:54:23 volumio-gross systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.206 [2165.2169] INFO SampleApp: API endpoint invoked: get-connect-info Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.403 [2165.2169] INFO SampleApp: API endpoint invoked: connect-to-qconnect Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.403 [2165.2165] INFO EndpointManager: [0x6f87e0]: Updating API endpoint Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.403 [2165.2165] INFO EndpointManager: [0x6f87e0]: Updating QConnect endpoint Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.403 [2165.2165] INFO ActiveStateManager: [0x6f7878]: Setting new active state: active Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.403 [2165.2165] INFO PlaybackSessionManager: [0x6f8af8]: Starting playback session maintenance Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.403 [2165.2165] INFO HttpDownloader: [0x6f8ca0]: Downloading content from: https://www.qobuz.com/api.json/0.2/session/start Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.403 [2165.2165] INFO CloudClient: [0x6f91c8]: Connecting to the cloud Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.403 [2165.2165] INFO SampleApp: Renderer is now active Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.711 [2165.2165] INFO CloudClient: [0x6f91c8]: Connection established Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.711 [2165.2165] INFO QwspMessageSender: [0x734bb8]: Sending Authenticate message Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.712 [2165.2165] INFO QwspMessageSender: [0x734bb8]: Sending Subscribe message Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.712 [2165.2165] INFO QConnectMessageSender: [0x734bc8]: Sending JoinSession message Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.712 [2165.2165] INFO QConnectMessageSender: [0x734bc8]: Sending VolumeChanged message Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.712 [2165.2165] INFO QConnectMessageSender: [0x734bc8]: Sending VolumeMuted message Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.712 [2165.2165] INFO QConnectMessageSender: [0x734bc8]: Sending MaxAudioQualityChanged message Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.712 [2165.2165] INFO QwspMessageSender: [0x734bb8]: Sending Payload message Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.749 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Received SetActive message: active Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.749 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Received SetState message: Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.749 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Playing state: Playing Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.749 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Playback position: 324901 Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.749 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Queue version: 1.1 Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.749 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Current track: TID: 417961783, QID: 4, Context UUID: 403a65aa-a5d0-47b7-ae5e-3dbfae1ef446 Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.749 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Next track: TID: 417961784, QID: 5, Context UUID: 403a65aa-a5d0-47b7-ae5e-3dbfae1ef446 Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.749 [2165.2165] INFO MediaEngine: [0x6f8d00]: Stopping playback, clearing tracks Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.749 [2165.2165] INFO MediaEngine: [0x6f8d00]: Initiating playback Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.749 [2165.2165] INFO RendererActionAvailabilityManager: [0x6f9238]: Renderer action 'Next' is available Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.749 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Received SetLoopMode message: Off Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.749 [2165.2165] INFO PlaybackModeManager: [0x6f8fc8]: Setting new loop mode: Off Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.749 [2165.2165] INFO MediaEngine: [0x6f8d00]: Setting current track: 417961783, initial offset: 324901ms Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.749 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: Clearing all streams Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.749 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: New stream: 1 Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.749 [2165.2165] INFO HttpDownloader: [0x734870]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=a56a69e514c7dbc255449152f72ebaba&request_ts=1791910470&track_id=417961783 Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.749 [2165.2165] INFO HttpDownloader: [0x846420]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=417961783 Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.749 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: [Stream 1]: Running audio stream Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.749 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Received SetShuffleMode message: disabled Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.749 [2165.2165] INFO PlaybackModeManager: [0x6f8fc8]: Setting new shuffle mode: disabled Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.749 [2165.2165] INFO MediaEngine: [0x6f8d00]: Setting next track: 417961784 Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.749 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: New stream: 2 Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.749 [2165.2165] INFO HttpDownloader: [0x7f2fe8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=1168c00815a2f9f3aeb5ac49908805c8&request_ts=1791910470&track_id=417961784 Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.749 [2165.2165] INFO HttpDownloader: [0x7f3f00]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=417961784 Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.750 [2165.2165] INFO MediaEngine: [0x6f8d00]: Waiting for current stream to start before starting audio renderer Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.854 [2165.2165] INFO PlaybackSessionManager: [0x6f8af8]: Playback session has been refreshed Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.855 [2165.2165] INFO HttpDownloader: [0x6fc5b8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=1818783e378f3a60b77c384c9e7f44a0&request_ts=1791910470&track_id=417961783 Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.855 [2165.2165] INFO HttpDownloader: [0x808c18]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=ef1254c883b070234e37e1209130c7b3&request_ts=1791910470&track_id=417961784 Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.855 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: [Stream 1]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=776199&eid=417961783&fmt=7&profile=raw&app_id=174516466&cid=1322134&etsp=1791914070&hmac=ptQJTEDCNlYTGc0_G8oDPTnjWC4 Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.877 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: [Stream 2]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=776199&eid=417961784&fmt=7&profile=raw&app_id=174516466&cid=1322134&etsp=1791914070&hmac=5HAFi2jL4vCSonotg0i5SCd8G9s Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.910 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: [Stream 1]: Metadata became available: Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.910 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: Title: I Want To Be Holding You Next Christmas Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.910 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: Artist: Dexys Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.910 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: Album: LOVE Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.910 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: Album art URL: https://static.qobuz.com/images/covers/pj/p8/f2oswdpdcp8pj_600.jpg Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.930 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: [Stream 2]: Metadata became available: Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.930 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: Title: I'll Always Love You Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.930 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: Artist: Dexys Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.930 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: Album: LOVE Oct 13 18:54:30 volumio-gross qobuz-connect[2165]: 20261013 18:54:30.930 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: Album art URL: https://static.qobuz.com/images/covers/pj/p8/f2oswdpdcp8pj_600.jpg Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.001 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: [Stream 1]: stream information have been fetched Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.001 [2165.2165] INFO UrlAudioSource: [0x7f7750]: Starting URL audio source, initial position: 324901ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=776199&eid=417961783&fmt=7&profile=raw&app_id=174516466&cid=1322134&etsp=1791914070&hmac=ptQJTEDCNlYTGc0_G8oDPTnjWC4 Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.001 [2165.2165] INFO ContentFetcher: [0x7f14c8]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=776199&eid=417961783&fmt=7&profile=raw&app_id=174516466&cid=1322134&etsp=1791914070&hmac=ptQJTEDCNlYTGc0_G8oDPTnjWC4, offset: 0 Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.001 [2165.2165] INFO AudioRenderer: [0x6f8e68]: Starting audio renderer, initial playback state: Playing Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.001 [2165.2165] INFO SampleApp: [Stream 1]: New audio stream (starting from 324900ms) Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.001 [2165.2165] INFO SampleApp: [Stream 1]: Stream metadata became available: Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.001 [2165.2165] INFO SampleApp: Title: I Want To Be Holding You Next Christmas Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.001 [2165.2165] INFO SampleApp: Artist: Dexys Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.001 [2165.2165] INFO SampleApp: Album: LOVE Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.001 [2165.2165] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/pj/p8/f2oswdpdcp8pj_600.jpg Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:31 volumio-gross volumio[1056]: info: QobuzConnect: setActiveState() Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:31 volumio-gross volumio[1056]: info: QobuzConnect: Set Qobuz connect default volume to 100 Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.006 [2165.2165] INFO VolumeManager: [0x6f9058]: Setting new playback volume: 100 Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:31 volumio-gross volumio[1056]: info: QobuzConnect: Stopping currently active service Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioStop Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreStateMachine::stop Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreStateMachine::updateTrackBlock Oct 13 18:54:31 volumio-gross volumio[1056]: info: CorePlayQueue::getTrackBlock Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreStateMachine::stPlaybackTimer Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreStateMachine::pushState Oct 13 18:54:31 volumio-gross volumio[1056]: info: CorePlayQueue::getTrack 2 Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.010 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: [Stream 2]: stream information have been fetched Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:31 volumio-gross volumio[1056]: info: CorePlayQueue::getTrack 2 Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreStateMachine::serviceStop Oct 13 18:54:31 volumio-gross volumio[1056]: info: CorePlayQueue::getTrack 2 Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::serviceStop Oct 13 18:54:31 volumio-gross volumio[1056]: info: [1791910471011] ControllerQobuz::stop Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 13 18:54:31 volumio-gross volumio[1056]: info: ControllerMpd::stop Oct 13 18:54:31 volumio-gross volumio[1056]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.013+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" state=STATUS_STOPPED positionMs=0 volume=100 Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.013+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" state=STATUS_STOPPED positionMs=0 volume=100 Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.015+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" id=qobuz://song/418271046 title=Duvateen Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.015+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" id=qobuz://song/418271046 title=Duvateen Oct 13 18:54:31 volumio-gross volumio[1056]: info: Oct 13 18:54:31 volumio-gross volumio[1056]: ---------------------------- MPD announces state update: player Oct 13 18:54:31 volumio-gross volumio[1056]: info: sendMpdCommand stop took 7 milliseconds Oct 13 18:54:31 volumio-gross volumio[1056]: info: ControllerMpd::getState Oct 13 18:54:31 volumio-gross volumio[1056]: verbose: ControllerMpd::sendMpdCommand status Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreStateMachine::pushState Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:31 volumio-gross volumio[1056]: info: CorePlayQueue::getTrack 2 Oct 13 18:54:31 volumio-gross volumio[1056]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received qobuzconnect Oct 13 18:54:31 volumio-gross volumio[1056]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.025+02:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.026+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" state=STATUS_STOPPED positionMs= volume=100 Oct 13 18:54:31 volumio-gross volumio[1056]: info: QobuzConnect: setActiveState() completed, send playback confirmation Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.026+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" state=STATUS_STOPPED positionMs= volume=100 Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.027 [2165.2165] INFO SampleApp: Initiating playback, initial state: Playing Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.027+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" id= title= Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.027+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" id= title= Oct 13 18:54:31 volumio-gross volumio[1056]: info: sendMpdCommand status took 7 milliseconds Oct 13 18:54:31 volumio-gross volumio[1056]: verbose: ControllerMpd::parseState Oct 13 18:54:31 volumio-gross volumio[1056]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 18:54:31 volumio-gross volumio[1056]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 13 18:54:31 volumio-gross volumio[1056]: verbose: ControllerMpd::parseTrackInfo Oct 13 18:54:31 volumio-gross volumio[1056]: info: ControllerMpd::pushState Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreStateMachine::pushState Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:31 volumio-gross volumio[1056]: info: CorePlayQueue::getTrack 2 Oct 13 18:54:31 volumio-gross volumio[1056]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.041+02:00 level=WARN msg="received unknown player status" component=volumio status="" Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.041+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" state=STATUS_STOPPED positionMs= volume=100 Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.042+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" state=STATUS_STOPPED positionMs= volume=100 Oct 13 18:54:31 volumio-gross volumio[1056]: info: ------------------------------ 23ms Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.043+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" id= title= Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.044+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" id= title= Oct 13 18:54:31 volumio-gross volumio[1056]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Z2LGcrkiK7ScyETTa4BRMXJkEOL2.eccd8b463a3ee0cbf73178faf538b85c.state.status' Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.092 [2165.2165] INFO SampleApp: Playback state changed: Playing Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.092 [2165.2165] INFO StreamingEventReporter: [0x6f9688]: Reporting streaming started event Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.093 [2165.2165] INFO HttpDownloader: [0x6f96e0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.093 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Need to send state update Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.093 [2165.2165] INFO QConnectMessageSender: [0x734bc8]: Sending StateUpdated message Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.093 [2165.2165] INFO QwspMessageSender: [0x734bb8]: Sending Payload message Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.093 [2165.2165] INFO QConnectMessageSender: [0x734bc8]: Sending FileAudioQualityChanged message Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.093 [2165.2165] INFO QwspMessageSender: [0x734bb8]: Sending Payload message Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.093 [2165.2165] INFO QConnectMessageSender: [0x734bc8]: Sending DeviceAudioQualityChanged message Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.094 [2165.2165] INFO QwspMessageSender: [0x734bb8]: Sending Payload message Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreStateMachine::pushState Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.097 [2165.2165] INFO VolumeManager: [0x6f9058]: Setting new playback volume: 100 Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.098 [2165.2165] INFO VolumeManager: [0x6f9058]: Setting new mute state: 0 Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreStateMachine::pushState Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.101+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.101+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" state=STATUS_PLAYING positionMs=0 volume=100 Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.102+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" id= title= Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.102+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" id= title= Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.102+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" state=STATUS_PLAYING positionMs=324900 volume=100 Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.103+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" state=STATUS_PLAYING positionMs=324900 volume=100 Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.103+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" id= title="I Want To Be Holding You Next Christmas" Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.104+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" id= title="I Want To Be Holding You Next Christmas" Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreStateMachine::pushState Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.108+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" state=STATUS_PLAYING positionMs=324900 volume=100 Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.109+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" state=STATUS_PLAYING positionMs=324900 volume=100 Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.109+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" id= title="I Want To Be Holding You Next Christmas" Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.109+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" id= title="I Want To Be Holding You Next Christmas" Oct 13 18:54:31 volumio-gross volumio[1056]: info: [LastFM] Current track has sufficient metadata: title (I Want To Be Holding You Next Christmas) and artist (Dexys) passed on explicitly Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.145 [2165.2165] INFO UrlAudioSource: [0x7f7750]: Content info extracted Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.145 [2165.2165] INFO UrlAudioSource: [0x7f7750]: Size: 69376549 Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.145 [2165.26090] INFO AudioDecoder: [0x8f0318]: Stream info: Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.145 [2165.26090] INFO AudioDecoder: [0x8f0318]: Total samples: 15098664 Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.145 [2165.26090] INFO AudioDecoder: [0x8f0318]: Sample rate: 44100 Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.145 [2165.26090] INFO AudioDecoder: [0x8f0318]: Channel count: 2 Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.145 [2165.26090] INFO AudioDecoder: [0x8f0318]: Bits per sample: 24 Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.145 [2165.26090] INFO AudioDecoder: [0x8f0318]: Duration: 342373 Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.146 [2165.2165] INFO UrlAudioSource: [0x7f7750]: Audio properties have changed Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.146 [2165.2165] INFO UrlAudioSource: [0x7f7750]: Seeking input stream to byte offset: 66454682 Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.146 [2165.2165] INFO ContentFetcher: [0x7f14c8]: Adjusting download offset to: 66454682 Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.156 [2165.2165] INFO SampleApp: Playback volume changed: 100 Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.156 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Volume changed: 100 Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.156 [2165.2165] INFO QConnectMessageSender: [0x734bc8]: Sending VolumeChanged message Oct 13 18:54:31 volumio-gross qobuz-connect[2165]: 20261013 18:54:31.156 [2165.2165] INFO QwspMessageSender: [0x734bb8]: Sending Payload message Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:31 volumio-gross volumio[1056]: info: QobuzConnect: Volume received from daemon: 100 Oct 13 18:54:31 volumio-gross volumio[1056]: info: VolumeController::SetAlsaVolume100 Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreStateMachine::pushState Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 18:54:31 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.167+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" state=STATUS_PLAYING positionMs=324900 volume=100 Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.168+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" state=STATUS_PLAYING positionMs=324900 volume=100 Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.169+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" id= title="I Want To Be Holding You Next Christmas" Oct 13 18:54:31 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:31.169+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" id= title="I Want To Be Holding You Next Christmas" Oct 13 18:54:31 volumio-gross volumio[1056]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 13 18:54:31 volumio-gross volumio[1056]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 13 18:54:31 volumio-gross volumio[1056]: info: camilladsp stopping service pid 26015... Oct 13 18:54:31 volumio-gross volumio[1056]: info: camilladsp service terminated, instance 1 Oct 13 18:54:31 volumio-gross volumio[1056]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 13 18:54:31 volumio-gross volumio[1056]: info: camilladsp service started and running in background, instance 1 Oct 13 18:54:31 volumio-gross volumio[1056]: [LastFM] updated "now playing" failed: { Oct 13 18:54:31 volumio-gross volumio[1056]: success: false, Oct 13 18:54:31 volumio-gross volumio[1056]: error: 'Invalid parameters - Your request is missing a required parameter' Oct 13 18:54:31 volumio-gross volumio[1056]: } Oct 13 18:54:31 volumio-gross volumio[1056]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"I Want To Be Holding You Next Christmas","url":"https://www.last.fm/music/Dexys/_/I+Want+To+Be+Holding+You+Next+Christmas","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"62","playcount":"119","artist":{"name":"Dexys","url":"https://www.last.fm/music/Dexys"},"userplaycount":"0","userloved":"0","toptags":""}} Oct 13 18:54:38 volumio-gross qobuz-connect[2165]: 20261013 18:54:38.565 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: [Stream 2]: Running audio stream Oct 13 18:54:38 volumio-gross qobuz-connect[2165]: 20261013 18:54:38.565 [2165.2165] INFO UrlAudioSource: [0x8f06b0]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=776199&eid=417961784&fmt=7&profile=raw&app_id=174516466&cid=1322134&etsp=1791914070&hmac=5HAFi2jL4vCSonotg0i5SCd8G9s Oct 13 18:54:38 volumio-gross qobuz-connect[2165]: 20261013 18:54:38.566 [2165.2165] INFO ContentFetcher: [0x995840]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=776199&eid=417961784&fmt=7&profile=raw&app_id=174516466&cid=1322134&etsp=1791914070&hmac=5HAFi2jL4vCSonotg0i5SCd8G9s, offset: 0 Oct 13 18:54:38 volumio-gross qobuz-connect[2165]: 20261013 18:54:38.566 [2165.2165] INFO SampleApp: [Stream 2]: New audio stream (starting from 0ms) Oct 13 18:54:38 volumio-gross qobuz-connect[2165]: 20261013 18:54:38.566 [2165.2165] INFO SampleApp: [Stream 2]: Stream metadata became available: Oct 13 18:54:38 volumio-gross qobuz-connect[2165]: 20261013 18:54:38.566 [2165.2165] INFO SampleApp: Title: I'll Always Love You Oct 13 18:54:38 volumio-gross qobuz-connect[2165]: 20261013 18:54:38.566 [2165.2165] INFO SampleApp: Artist: Dexys Oct 13 18:54:38 volumio-gross qobuz-connect[2165]: 20261013 18:54:38.566 [2165.2165] INFO SampleApp: Album: LOVE Oct 13 18:54:38 volumio-gross qobuz-connect[2165]: 20261013 18:54:38.566 [2165.2165] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/pj/p8/f2oswdpdcp8pj_600.jpg Oct 13 18:54:38 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:38 volumio-gross systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5017. Oct 13 18:54:38 volumio-gross systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 13 18:54:38 volumio-gross systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 13 18:54:38 volumio-gross upmpdcli[26116]: Could not open config: /tmp/upmpdcli.conf Oct 13 18:54:38 volumio-gross systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 13 18:54:38 volumio-gross systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 13 18:54:38 volumio-gross qobuz-connect[2165]: 20261013 18:54:38.891 [2165.2165] INFO UrlAudioSource: [0x8f06b0]: Content info extracted Oct 13 18:54:38 volumio-gross qobuz-connect[2165]: 20261013 18:54:38.891 [2165.2165] INFO UrlAudioSource: [0x8f06b0]: Size: 49486959 Oct 13 18:54:38 volumio-gross qobuz-connect[2165]: 20261013 18:54:38.891 [2165.26117] INFO AudioDecoder: [0xa82f48]: Stream info: Oct 13 18:54:38 volumio-gross qobuz-connect[2165]: 20261013 18:54:38.891 [2165.26117] INFO AudioDecoder: [0xa82f48]: Total samples: 10915632 Oct 13 18:54:38 volumio-gross qobuz-connect[2165]: 20261013 18:54:38.891 [2165.26117] INFO AudioDecoder: [0xa82f48]: Sample rate: 44100 Oct 13 18:54:38 volumio-gross qobuz-connect[2165]: 20261013 18:54:38.891 [2165.26117] INFO AudioDecoder: [0xa82f48]: Channel count: 2 Oct 13 18:54:38 volumio-gross qobuz-connect[2165]: 20261013 18:54:38.891 [2165.26117] INFO AudioDecoder: [0xa82f48]: Bits per sample: 24 Oct 13 18:54:38 volumio-gross qobuz-connect[2165]: 20261013 18:54:38.891 [2165.26117] INFO AudioDecoder: [0xa82f48]: Duration: 247520 Oct 13 18:54:38 volumio-gross qobuz-connect[2165]: 20261013 18:54:38.891 [2165.2165] INFO UrlAudioSource: [0x8f06b0]: Audio properties have changed Oct 13 18:54:41 volumio-gross qobuz-connect[2165]: 20261013 18:54:41.093 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Need to send state update Oct 13 18:54:41 volumio-gross qobuz-connect[2165]: 20261013 18:54:41.093 [2165.2165] INFO QConnectMessageSender: [0x734bc8]: Sending StateUpdated message Oct 13 18:54:41 volumio-gross qobuz-connect[2165]: 20261013 18:54:41.093 [2165.2165] INFO QwspMessageSender: [0x734bb8]: Sending Payload message Oct 13 18:54:41 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:41 volumio-gross volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 18:54:41 volumio-gross volumio[1056]: info: CoreStateMachine::pushState Oct 13 18:54:41 volumio-gross volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:54:41 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 18:54:41 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:41 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:41.097+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" state=STATUS_PLAYING positionMs=335314 volume=100 Oct 13 18:54:41 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:41.098+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" state=STATUS_PLAYING positionMs=335314 volume=100 Oct 13 18:54:41 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:41.099+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" id= title="I Want To Be Holding You Next Christmas" Oct 13 18:54:41 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:41.099+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" id= title="I Want To Be Holding You Next Christmas" Oct 13 18:54:46 volumio-gross qobuz-connect[2165]: 20261013 18:54:46.680 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: [Stream 1]: Audio stream has finished Oct 13 18:54:46 volumio-gross qobuz-connect[2165]: 20261013 18:54:46.680 [2165.2165] INFO SampleApp: [Stream 1]: Audio stream finished Oct 13 18:54:46 volumio-gross qobuz-connect[2165]: 20261013 18:54:46.680 [2165.2165] INFO SampleApp: Playback is nearly finished Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.072 [2165.2165] INFO SampleApp: Playback has finished Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.072 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: [Stream 1]: Disposing of audio stream Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.072 [2165.2165] INFO ContentFetcher: [0x7f14c8]: Canceling download Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.073 [2165.2165] INFO SampleApp: [Stream 1]: Audio stream has been disposed of Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.073 [2165.2165] INFO StreamingEventReporter: [0x6f9688]: Reporting streaming ended event Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.074 [2165.2165] INFO HttpDownloader: [0x6f96e0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.074 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Need to send state update Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.074 [2165.2165] INFO QConnectMessageSender: [0x734bc8]: Sending StateUpdated message Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.074 [2165.2165] INFO QwspMessageSender: [0x734bb8]: Sending Payload message Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.075 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Need to send state update Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.075 [2165.2165] INFO QConnectMessageSender: [0x734bc8]: Sending StateUpdated message Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.075 [2165.2165] INFO QwspMessageSender: [0x734bb8]: Sending Payload message Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.075 [2165.2165] INFO StreamingEventReporter: [0x6f9688]: Reporting streaming started event Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreStateMachine::pushState Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:48 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:48.080+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 13 18:54:48 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:48.081+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" state=STATUS_PLAYING positionMs=0 volume=100 Oct 13 18:54:48 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:48.081+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" id= title="I'll Always Love You" Oct 13 18:54:48 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:48.082+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" id= title="I'll Always Love You" Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreStateMachine::pushState Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreStateMachine::pushState Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreStateMachine::pushState Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreStateMachine::pushState Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 18:54:48 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:48 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:48.094+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" state=STATUS_PLAYING positionMs=24 volume=100 Oct 13 18:54:48 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:48.095+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" state=STATUS_PLAYING positionMs=24 volume=100 Oct 13 18:54:48 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:48.095+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" state=STATUS_PLAYING positionMs=24 volume=100 Oct 13 18:54:48 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:48.095+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" state=STATUS_PLAYING positionMs=24 volume=100 Oct 13 18:54:48 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:48.095+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" state=STATUS_PLAYING positionMs=24 volume=100 Oct 13 18:54:48 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:48.095+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" state=STATUS_PLAYING positionMs=24 volume=100 Oct 13 18:54:48 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:48.096+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" state=STATUS_PLAYING positionMs=24 volume=100 Oct 13 18:54:48 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:48.096+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" id= title="I'll Always Love You" Oct 13 18:54:48 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:48.096+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" id= title="I'll Always Love You" Oct 13 18:54:48 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:48.096+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" id= title="I'll Always Love You" Oct 13 18:54:48 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:48.096+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" id= title="I'll Always Love You" Oct 13 18:54:48 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:48.096+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" id= title="I'll Always Love You" Oct 13 18:54:48 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:48.096+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" id= title="I'll Always Love You" Oct 13 18:54:48 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:48.097+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" state=STATUS_PLAYING positionMs=24 volume=100 Oct 13 18:54:48 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:48.097+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" id= title="I'll Always Love You" Oct 13 18:54:48 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:48.098+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" id= title="I'll Always Love You" Oct 13 18:54:48 volumio-gross volumio[1056]: info: [LastFM] Current track has sufficient metadata: title (I'll Always Love You) and artist (Dexys) passed on explicitly Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.117 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Received SetState message: Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.117 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Playing state: Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.117 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Playback position: Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.117 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Queue version: 1.1 Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.117 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Current track: Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.117 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Next track: TID: 417961785, QID: 6, Context UUID: 403a65aa-a5d0-47b7-ae5e-3dbfae1ef446 Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.117 [2165.2165] INFO MediaEngine: [0x6f8d00]: Setting next track: 417961785 Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.117 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: New stream: 3 Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.117 [2165.2165] INFO HttpDownloader: [0x9e14f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=7e1f206c421039d4f5adfe05b531dce4&request_ts=1791910488&track_id=417961785 Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.117 [2165.2165] INFO HttpDownloader: [0xa17118]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=417961785 Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.117 [2165.2165] INFO HttpDownloader: [0x9e1a10]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=4af5c322ddd7e6d217a3c428a850785f&request_ts=1791910488&track_id=417961785 Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.156 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Received SetState message: Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.156 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Playing state: Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.156 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Playback position: Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.156 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Queue version: 1.1 Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.156 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Current track: Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.156 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Next track: TID: 417961785, QID: 6, Context UUID: 403a65aa-a5d0-47b7-ae5e-3dbfae1ef446 Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.263 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: [Stream 3]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=776199&eid=417961785&fmt=7&profile=raw&app_id=174516466&cid=1322134&etsp=1791914088&hmac=fpm7i53HgipAmouA7Ne-_ZdctIE Oct 13 18:54:48 volumio-gross volumio[1056]: [LastFM] updated "now playing" failed: { Oct 13 18:54:48 volumio-gross volumio[1056]: success: false, Oct 13 18:54:48 volumio-gross volumio[1056]: error: 'Invalid parameters - Your request is missing a required parameter' Oct 13 18:54:48 volumio-gross volumio[1056]: } Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.291 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: [Stream 3]: stream information have been fetched Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.301 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: [Stream 3]: Metadata became available: Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.301 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: Title: Strange Feelin' Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.301 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: Artist: Dexys Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.301 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: Album: LOVE Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.301 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: Album art URL: https://static.qobuz.com/images/covers/pj/p8/f2oswdpdcp8pj_600.jpg Oct 13 18:54:48 volumio-gross qobuz-connect[2165]: 20261013 18:54:48.320 [2165.2165] INFO HttpDownloader: [0x6f96e0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Oct 13 18:54:48 volumio-gross volumio[1056]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"I'll Always Love You","url":"https://www.last.fm/music/Dexys/_/I%27ll+Always+Love+You","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"54","playcount":"102","artist":{"name":"Dexys","url":"https://www.last.fm/music/Dexys"},"userplaycount":"0","userloved":"0","toptags":""}} Oct 13 18:54:54 volumio-gross systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5018. Oct 13 18:54:54 volumio-gross systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 13 18:54:54 volumio-gross systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 13 18:54:54 volumio-gross upmpdcli[26148]: Could not open config: /tmp/upmpdcli.conf Oct 13 18:54:54 volumio-gross systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 13 18:54:54 volumio-gross systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 13 18:54:58 volumio-gross qobuz-connect[2165]: 20261013 18:54:58.075 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Need to send state update Oct 13 18:54:58 volumio-gross qobuz-connect[2165]: 20261013 18:54:58.075 [2165.2165] INFO QConnectMessageSender: [0x734bc8]: Sending StateUpdated message Oct 13 18:54:58 volumio-gross qobuz-connect[2165]: 20261013 18:54:58.075 [2165.2165] INFO QwspMessageSender: [0x734bb8]: Sending Payload message Oct 13 18:54:58 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:58 volumio-gross volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 18:54:58 volumio-gross volumio[1056]: info: CoreStateMachine::pushState Oct 13 18:54:58 volumio-gross volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:54:58 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 18:54:58 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:58 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:58.079+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" state=STATUS_PLAYING positionMs=9950 volume=100 Oct 13 18:54:58 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:58.080+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" state=STATUS_PLAYING positionMs=9950 volume=100 Oct 13 18:54:58 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:58.080+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" id= title="I'll Always Love You" Oct 13 18:54:58 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:58.080+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" id= title="I'll Always Love You" Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.491 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Received SetState message: Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.491 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Playing state: Playing Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.491 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Playback position: 0 Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.491 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Queue version: 1.1 Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.491 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Current track: TID: 417961782, QID: 3, Context UUID: 403a65aa-a5d0-47b7-ae5e-3dbfae1ef446 Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.491 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Next track: TID: 417961783, QID: 4, Context UUID: 403a65aa-a5d0-47b7-ae5e-3dbfae1ef446 Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.491 [2165.2165] INFO MediaEngine: [0x6f8d00]: Stopping playback, clearing tracks Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.492 [2165.2165] INFO MediaEngine: [0x6f8d00]: Initiating playback Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.492 [2165.2165] INFO AudioRenderer: [0x6f8e68]: Stopping audio renderer Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.492 [2165.2165] INFO SampleApp: Stopping playback Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.493 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: Clearing all streams Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.493 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: [Stream 2]: Disposing of audio stream Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.493 [2165.2165] INFO ContentFetcher: [0x995840]: Canceling download Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.496 [2165.2165] INFO SampleApp: Playback state changed: Stopped Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.496 [2165.2165] INFO StreamingEventReporter: [0x6f9688]: Reporting streaming ended event Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.497 [2165.2165] INFO HttpDownloader: [0x6f96e0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.497 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Need to send state update Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.497 [2165.2165] INFO QConnectMessageSender: [0x734bc8]: Sending StateUpdated message Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.497 [2165.2165] INFO QwspMessageSender: [0x734bb8]: Sending Payload message Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreStateMachine::pushState Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.498 [2165.2165] INFO SampleApp: [Stream 2]: Audio stream has been disposed of Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.498 [2165.2165] INFO MediaEngine: [0x6f8d00]: Setting current track: 417961782, initial offset: 0ms Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.498 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: Clearing all streams Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.498 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: New stream: 4 Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.498 [2165.2165] INFO HttpDownloader: [0x9919d0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=308e8d665a2635f44c67ba5889eb1390&request_ts=1791910499&track_id=417961782 Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.498 [2165.2165] INFO HttpDownloader: [0x9c3de8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=417961782 Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.498 [2165.2165] INFO HttpDownloader: [0x906e58]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=dc5f35ef582c8cd1e6d6d1b0ae7560c2&request_ts=1791910499&track_id=417961782 Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.499 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: [Stream 4]: Running audio stream Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.499 [2165.2165] INFO MediaEngine: [0x6f8d00]: Setting next track: 417961783 Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.499 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: New stream: 5 Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.499 [2165.2165] INFO HttpDownloader: [0x9033c8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=3c6426a920b0631de2e91a65f48d034d&request_ts=1791910499&track_id=417961783 Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.499 [2165.2165] INFO HttpDownloader: [0x9116c0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=417961783 Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.499 [2165.2165] INFO HttpDownloader: [0x966700]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=e14934c5ef5b3cb3f9e792e1bdb1c83c&request_ts=1791910499&track_id=417961783 Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.499 [2165.2165] INFO MediaEngine: [0x6f8d00]: Waiting for current stream to start before starting audio renderer Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:59 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:59.503+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" state=STATUS_PAUSED positionMs=9950 volume=100 Oct 13 18:54:59 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:59.503+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" state=STATUS_PAUSED positionMs=9950 volume=100 Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreStateMachine::pushState Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 18:54:59 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:59.504+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" id= title="I'll Always Love You" Oct 13 18:54:59 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:59.504+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" id= title="I'll Always Love You" Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:59 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:59.508+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" state=STATUS_PAUSED positionMs=0 volume=100 Oct 13 18:54:59 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:59.508+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" state=STATUS_PAUSED positionMs=0 volume=100 Oct 13 18:54:59 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:59.508+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" id= title="I'll Always Love You" Oct 13 18:54:59 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:59.509+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" id= title="I'll Always Love You" Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.664 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: [Stream 4]: stream information have been fetched Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.667 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: [Stream 4]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=776199&eid=417961782&fmt=7&profile=raw&app_id=174516466&cid=1322134&etsp=1791914099&hmac=UJcsFXmONP2HdG39fTKKhLdvIsU Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.667 [2165.2165] INFO UrlAudioSource: [0x9e03c8]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=776199&eid=417961782&fmt=7&profile=raw&app_id=174516466&cid=1322134&etsp=1791914099&hmac=UJcsFXmONP2HdG39fTKKhLdvIsU Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.667 [2165.2165] INFO ContentFetcher: [0x9e17c8]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=776199&eid=417961782&fmt=7&profile=raw&app_id=174516466&cid=1322134&etsp=1791914099&hmac=UJcsFXmONP2HdG39fTKKhLdvIsU, offset: 0 Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.667 [2165.2165] INFO AudioRenderer: [0x6f8e68]: Starting audio renderer, initial playback state: Playing Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.667 [2165.2165] INFO SampleApp: [Stream 4]: New audio stream (starting from 0ms) Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:59 volumio-gross volumio[1056]: info: QobuzConnect: alread currrent service, send playback confirmation Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.668 [2165.2165] INFO SampleApp: Initiating playback, initial state: Playing Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.672 [2165.2165] INFO SampleApp: Playback state changed: Playing Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.672 [2165.2165] INFO StreamingEventReporter: [0x6f9688]: Reporting streaming started event Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.672 [2165.2165] INFO ProtocolHandler: [0x6f92d8]: Need to send state update Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.672 [2165.2165] INFO QConnectMessageSender: [0x734bc8]: Sending StateUpdated message Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.673 [2165.2165] INFO QwspMessageSender: [0x734bb8]: Sending Payload message Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreStateMachine::pushState Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:59 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:59.676+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" state=STATUS_PAUSED positionMs=0 volume=100 Oct 13 18:54:59 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:59.676+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" state=STATUS_PAUSED positionMs=0 volume=100 Oct 13 18:54:59 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:59.677+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" id= title= Oct 13 18:54:59 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:59.677+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" id= title= Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreStateMachine::pushState Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreStateMachine::pushState Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::servicePushState Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreStateMachine::pushState Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioPushState Oct 13 18:54:59 volumio-gross volumio[1056]: info: CoreCommandRouter::volumioGetState Oct 13 18:54:59 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:59.684+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 13 18:54:59 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:59.684+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" state=STATUS_PLAYING positionMs=0 volume=100 Oct 13 18:54:59 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:59.684+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" id= title= Oct 13 18:54:59 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:59.685+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" id= title= Oct 13 18:54:59 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:59.685+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 13 18:54:59 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:59.685+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" state=STATUS_PLAYING positionMs=0 volume=100 Oct 13 18:54:59 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:59.686+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" id= title= Oct 13 18:54:59 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:59.686+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" id= title= Oct 13 18:54:59 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:59.686+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 13 18:54:59 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:59.687+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" state=STATUS_PLAYING positionMs=0 volume=100 Oct 13 18:54:59 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:59.687+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.226:53108,00:00:00:00:00:00%04 @ 0x36909c0" id= title= Oct 13 18:54:59 volumio-gross volumio5-onboarding[1338]: time=2026-10-13T18:54:59.687+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.198:60483,00:00:00:00:00:00%06 @ 0x31bc030" id= title= Oct 13 18:54:59 volumio-gross volumio[1056]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Oct 13 18:54:59 volumio-gross volumio[1056]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 18:54:59 volumio-gross volumio[1056]: TypeError: Cannot read properties of undefined (reading 'indexOf') Oct 13 18:54:59 volumio-gross volumio[1056]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Oct 13 18:54:59 volumio-gross volumio[1056]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Oct 13 18:54:59 volumio-gross volumio[1056]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Oct 13 18:54:59 volumio-gross volumio[1056]: at Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Oct 13 18:54:59 volumio-gross volumio[1056]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Oct 13 18:54:59 volumio-gross volumio[1056]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Oct 13 18:54:59 volumio-gross volumio[1056]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Oct 13 18:54:59 volumio-gross volumio[1056]: at Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Oct 13 18:54:59 volumio-gross volumio[1056]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Oct 13 18:54:59 volumio-gross volumio[1056]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Oct 13 18:54:59 volumio-gross volumio[1056]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.706 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: [Stream 5]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=776199&eid=417961783&fmt=7&profile=raw&app_id=174516466&cid=1322134&etsp=1791914099&hmac=6VZhvMdXZtbG_0WqptpVBkLtCLo Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.708 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: [Stream 5]: Metadata became available: Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.708 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: Title: I Want To Be Holding You Next Christmas Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.708 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: Artist: Dexys Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.708 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: Album: LOVE Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.708 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: Album art URL: https://static.qobuz.com/images/covers/pj/p8/f2oswdpdcp8pj_600.jpg Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.714 [2165.2165] INFO UrlAudioSource: [0x9e03c8]: Content info extracted Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.714 [2165.2165] INFO UrlAudioSource: [0x9e03c8]: Size: 50151809 Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.715 [2165.26154] INFO AudioDecoder: [0xa82dd8]: Stream info: Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.715 [2165.26154] INFO AudioDecoder: [0xa82dd8]: Total samples: 11410728 Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.715 [2165.26154] INFO AudioDecoder: [0xa82dd8]: Sample rate: 44100 Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.715 [2165.26154] INFO AudioDecoder: [0xa82dd8]: Channel count: 2 Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.715 [2165.26154] INFO AudioDecoder: [0xa82dd8]: Bits per sample: 24 Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.715 [2165.26154] INFO AudioDecoder: [0xa82dd8]: Duration: 258746 Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.715 [2165.2165] INFO UrlAudioSource: [0x9e03c8]: Audio properties have changed Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.756 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: [Stream 5]: stream information have been fetched Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.801 [2165.2165] INFO HttpDownloader: [0x6f96e0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.976 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: [Stream 4]: Metadata became available: Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.976 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: Title: You're Alright Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.976 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: Artist: Dexys Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.976 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: Album: LOVE Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.976 [2165.2165] INFO AudioStreamManager: [0x6f8db0]: Album art URL: https://static.qobuz.com/images/covers/pj/p8/f2oswdpdcp8pj_600.jpg Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.977 [2165.2165] INFO SampleApp: [Stream 4]: Stream metadata became available: Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.977 [2165.2165] INFO SampleApp: Title: You're Alright Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.977 [2165.2165] INFO SampleApp: Artist: Dexys Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.977 [2165.2165] INFO SampleApp: Album: LOVE Oct 13 18:54:59 volumio-gross qobuz-connect[2165]: 20261013 18:54:59.977 [2165.2165] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/pj/p8/f2oswdpdcp8pj_600.jpg Oct 13 18:55:00 volumio-gross sudo[26166]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-13 18:54' Oct 13 18:55:00 volumio-gross sudo[26166]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="ebd772339e193fb4b3a98c68e63a0009e72d6f27" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0a52c1fb90959ba1872a2fb0225e56fe615b19ac" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Sep 14 07:39:23 UTC 2026" VOLUMIO_VERSION="4.204" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="915e71dafc26fd850574d238b4f947d5"