-- Logs begin at Mon 2025-09-08 21:13:58 BST, end at Wed 2025-09-10 13:22:35 BST. -- Sep 10 13:21:01 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:21:01 volumio volumio[1123]: info: Listing playlists Sep 10 13:21:01 volumio volumio[1123]: info: Listing playlists Sep 10 13:21:11 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:21:21 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:21:21 volumio volumio[1123]: info: Listing playlists Sep 10 13:21:21 volumio volumio[1123]: info: Listing playlists Sep 10 13:21:31 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:21:34 volumio kernel: drm-rp1-dsi 1f00130000.dsi: [drm] rp1dsi: Command time (outvact): 33 Sep 10 13:21:34 volumio kernel: drm-rp1-dsi 1f00130000.dsi: [drm] rp1dsi: Nominal Byte clock 90000000 DPI clock 30000000 (parent rate 90000000) Sep 10 13:21:36 volumio volumio[1123]: info: CoreCommandRouter::volumioPlay Sep 10 13:21:36 volumio volumio[1123]: info: CoreStateMachine::play index undefined Sep 10 13:21:36 volumio volumio[1123]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 13:21:36 volumio volumio[1123]: info: CorePlayQueue::getTrack 8 Sep 10 13:21:36 volumio volumio[1123]: info: CoreStateMachine::startPlaybackTimer Sep 10 13:21:36 volumio volumio[1123]: info: CorePlayQueue::getTrack 8 Sep 10 13:21:36 volumio volumio[1123]: info: [1757506896098] ControllerQobuz::resume Sep 10 13:21:36 volumio volumio[1123]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 10 13:21:36 volumio volumio[1123]: info: ControllerMpd::resume Sep 10 13:21:36 volumio volumio[1123]: verbose: ControllerMpd::sendMpdCommand play Sep 10 13:21:36 volumio volumio[1123]: info: Sep 10 13:21:36 volumio volumio[1123]: ---------------------------- MPD announces state update: player Sep 10 13:21:36 volumio volumio[1123]: info: sendMpdCommand play took 4 milliseconds Sep 10 13:21:36 volumio volumio[1123]: info: ControllerMpd::getState Sep 10 13:21:36 volumio volumio[1123]: verbose: ControllerMpd::sendMpdCommand status Sep 10 13:21:36 volumio volumio[1123]: info: sendMpdCommand status took 1 milliseconds Sep 10 13:21:36 volumio volumio[1123]: verbose: ControllerMpd::parseState Sep 10 13:21:36 volumio volumio[1123]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 13:21:36 volumio volumio[1123]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 10 13:21:36 volumio volumio[1123]: verbose: ControllerMpd::parseTrackInfo Sep 10 13:21:36 volumio volumio[1123]: info: ControllerMpd::pushState Sep 10 13:21:36 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Sep 10 13:21:36 volumio volumio[1123]: info: CorePlayQueue::getTrack 8 Sep 10 13:21:36 volumio volumio[1123]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5885,"duration":306,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1294 Kbps","isStreaming":false,"title":"341813939","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/341813939","trackType":"qobuz"} Sep 10 13:21:36 volumio volumio[1123]: verbose: CURRENT POSITION 8 Sep 10 13:21:36 volumio volumio[1123]: info: CoreStateMachine::syncState stateService play Sep 10 13:21:36 volumio volumio[1123]: info: CoreStateMachine::syncState currentStatus pause Sep 10 13:21:36 volumio volumio[1123]: info: CoreStateMachine::pushState Sep 10 13:21:36 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 13:21:36 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Sep 10 13:21:36 volumio volumio[1123]: info: MRS: Pushing multiroomSync output update for this device Sep 10 13:21:36 volumio volumio[1123]: info: MRS: Pushing multiroomSync output Sep 10 13:21:36 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:21:36 volumio volumio[1123]: info: ------------------------------ 7ms Sep 10 13:21:36 volumio volumio[1123]: info: [LastFM] Current track has sufficient metadata: title (Feel Something) and artist (Peverelist) passed on explicitly Sep 10 13:21:36 volumio volumio[1123]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Sep 10 13:21:36 volumio volumio[1123]: info: touch_display: Setting screensaver timeout to 0 seconds. Sep 10 13:21:41 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:21:41 volumio volumio[1123]: info: Listing playlists Sep 10 13:21:41 volumio volumio[1123]: info: Listing playlists Sep 10 13:21:51 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:01 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:01 volumio volumio[1123]: info: Listing playlists Sep 10 13:22:01 volumio volumio[1123]: info: Listing playlists Sep 10 13:22:11 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:21 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:21 volumio volumio[1123]: info: Listing playlists Sep 10 13:22:21 volumio volumio[1123]: info: Listing playlists Sep 10 13:22:31 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.308 [2113.13618] INFO SampleApp: API endpoint invoked: get-connect-info Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.425 [2113.13618] INFO SampleApp: API endpoint invoked: connect-to-qconnect Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.425 [2113.2113] INFO EndpointManager: [0x22a6160]: Updating API endpoint Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.425 [2113.2113] INFO EndpointManager: [0x22a6160]: Updating QConnect endpoint Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.425 [2113.2113] INFO ActiveStateManager: [0x22a5178]: Setting new active state: active Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.425 [2113.2113] INFO PlaybackSessionManager: [0x22a6550]: Starting playback session maintenance Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.425 [2113.2113] INFO HttpDownloader: [0x22a6740]: Downloading content from: https://www.qobuz.com/api.json/0.2/session/start Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.425 [2113.2113] INFO CloudClient: [0x22a6dc0]: Connecting to the cloud Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.425 [2113.2113] INFO SampleApp: Renderer is now active Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.571 [2113.2113] INFO CloudClient: [0x22a6dc0]: Connection established Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.571 [2113.2113] INFO QwspMessageSender: [0x2396968]: Sending Authenticate message Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.571 [2113.2113] INFO QwspMessageSender: [0x2396968]: Sending Subscribe message Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.571 [2113.2113] INFO QConnectMessageSender: [0x23860c0]: Sending JoinSession message Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.571 [2113.2113] INFO QConnectMessageSender: [0x23860c0]: Sending VolumeChanged message Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.571 [2113.2113] INFO QConnectMessageSender: [0x23860c0]: Sending VolumeMuted message Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.571 [2113.2113] INFO QConnectMessageSender: [0x23860c0]: Sending MaxAudioQualityChanged message Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.571 [2113.2113] INFO QwspMessageSender: [0x2396968]: Sending Payload message Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.610 [2113.2113] INFO ProtocolHandler: [0x22a6f28]: Received SetActive message: active Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.610 [2113.2113] INFO ProtocolHandler: [0x22a6f28]: Received SetState message: Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.610 [2113.2113] INFO ProtocolHandler: [0x22a6f28]: Playing state: Paused Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.610 [2113.2113] INFO ProtocolHandler: [0x22a6f28]: Playback position: 20000 Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.610 [2113.2113] INFO ProtocolHandler: [0x22a6f28]: Queue version: 5.1 Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.610 [2113.2113] INFO ProtocolHandler: [0x22a6f28]: Current track: TID: 345452650, QID: 7, Context UUID: b7fac592-30d2-46b0-adc4-dde498e964b3 Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.610 [2113.2113] INFO ProtocolHandler: [0x22a6f28]: Next track: TID: 345452651, QID: 8, Context UUID: b7fac592-30d2-46b0-adc4-dde498e964b3 Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.610 [2113.2113] INFO MediaEngine: [0x22a67e8]: Stopping playback, clearing tracks Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.610 [2113.2113] INFO MediaEngine: [0x22a67e8]: Initiating playback Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.610 [2113.2113] INFO RendererActionAvailabilityManager: [0x22a6e78]: Renderer action 'Next' is available Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.610 [2113.2113] INFO ProtocolHandler: [0x22a6f28]: Received SetLoopMode message: Off Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.610 [2113.2113] INFO PlaybackControlsManager: [0x22a6bc0]: Setting new loop mode: Off Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.610 [2113.2113] INFO MediaEngine: [0x22a67e8]: Setting current track: 345452650, initial offset: 20000ms Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.610 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: Clearing all streams Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.610 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: New stream: 1 Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.610 [2113.2113] INFO HttpDownloader: [0x2387090]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=ea8ac67e5057a92501fa34c05d7f3c72&request_ts=1757506953&track_id=345452650 Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.610 [2113.2113] INFO HttpDownloader: [0x2387f98]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=345452650 Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.610 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: [Stream 1]: Running audio stream Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.610 [2113.2113] INFO ProtocolHandler: [0x22a6f28]: Received SetShuffleMode message: disabled Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.610 [2113.2113] INFO PlaybackControlsManager: [0x22a6bc0]: Setting new shuffle mode: disabled Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.610 [2113.2113] INFO MediaEngine: [0x22a67e8]: Setting next track: 345452651 Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.610 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: New stream: 2 Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.610 [2113.2113] INFO HttpDownloader: [0x2388ed8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=d0cbe6d96159ee92a12d7df42dc215cf&request_ts=1757506953&track_id=345452651 Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.610 [2113.2113] INFO HttpDownloader: [0x22e0590]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=345452651 Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.610 [2113.2113] INFO MediaEngine: [0x22a67e8]: Waiting for current stream to start before starting audio renderer Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.665 [2113.2113] INFO PlaybackSessionManager: [0x22a6550]: Playback session has been refreshed Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.666 [2113.2113] INFO HttpDownloader: [0x22ac410]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=7b6783844a6ac0029344ed1443230c51&request_ts=1757506953&track_id=345452650 Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.666 [2113.2113] INFO HttpDownloader: [0x2380448]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=a096ac17b5326b56ce01320fff2377a4&request_ts=1757506953&track_id=345452651 Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.768 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: [Stream 2]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3704599&eid=345452651&fmt=7&profile=raw&app_id=174516466&cid=2686343&etsp=1757510553&hmac=9Hyr7dUnmOFmfTFLdYuZe7fitaE Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.770 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: [Stream 1]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3704599&eid=345452650&fmt=7&profile=raw&app_id=174516466&cid=2686343&etsp=1757510553&hmac=5fRhsXFhlrX0tz9X_58ZrDM6IFg Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.778 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: [Stream 1]: Metadata became available: Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.778 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: Title: milk of the madonna Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.778 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: Artist: Deftones Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.778 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: Album: private music Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.778 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: Album art URL: https://static.qobuz.com/images/covers/mb/5y/jr41c1qhl5ymb_600.jpg Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.809 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: [Stream 2]: Metadata became available: Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.809 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: Title: cut hands Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.809 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: Artist: Deftones Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.809 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: Album: private music Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.810 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: Album art URL: https://static.qobuz.com/images/covers/mb/5y/jr41c1qhl5ymb_600.jpg Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.833 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: [Stream 2]: stream information have been fetched Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.913 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: [Stream 1]: stream information have been fetched Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.913 [2113.2113] INFO UrlAudioSource: [0x22b03b8]: Starting URL audio source, initial position: 20000ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=3704599&eid=345452650&fmt=7&profile=raw&app_id=174516466&cid=2686343&etsp=1757510553&hmac=5fRhsXFhlrX0tz9X_58ZrDM6IFg Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.913 [2113.2113] INFO ContentFetcher: [0x22e25f8]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=3704599&eid=345452650&fmt=7&profile=raw&app_id=174516466&cid=2686343&etsp=1757510553&hmac=5fRhsXFhlrX0tz9X_58ZrDM6IFg, offset: 0 Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.913 [2113.2113] INFO AudioRenderer: [0x22a6a08]: Starting audio renderer, initial playback state: Paused Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.913 [2113.2113] INFO SampleApp: [Stream 1]: New audio stream (starting from 20000ms) Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.913 [2113.2113] INFO SampleApp: [Stream 1]: Stream metadata became available: Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.913 [2113.2113] INFO SampleApp: Title: milk of the madonna Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.913 [2113.2113] INFO SampleApp: Artist: Deftones Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.913 [2113.2113] INFO SampleApp: Album: private music Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.913 [2113.2113] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/mb/5y/jr41c1qhl5ymb_600.jpg Sep 10 13:22:33 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:33 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:33 volumio volumio[1123]: info: QobuzConnect: setActiveState() Sep 10 13:22:33 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:33 volumio volumio[1123]: info: QobuzConnect: Set Qobuz connect default volume to 80 Sep 10 13:22:33 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.916 [2113.2113] INFO VolumeManager: [0x22a6c50]: Setting new playback volume: 80 Sep 10 13:22:33 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:33 volumio volumio[1123]: info: QobuzConnect: Stopping currently active service Sep 10 13:22:33 volumio volumio[1123]: info: CoreCommandRouter::volumioStop Sep 10 13:22:33 volumio volumio[1123]: info: CoreStateMachine::stop Sep 10 13:22:33 volumio volumio[1123]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 13:22:33 volumio volumio[1123]: info: CoreStateMachine::stPlaybackTimer Sep 10 13:22:33 volumio volumio[1123]: info: CoreStateMachine::updateTrackBlock Sep 10 13:22:33 volumio volumio[1123]: info: CorePlayQueue::getTrackBlock Sep 10 13:22:33 volumio volumio[1123]: info: CoreStateMachine::pushState Sep 10 13:22:33 volumio volumio[1123]: info: CorePlayQueue::getTrack 8 Sep 10 13:22:33 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 13:22:33 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Sep 10 13:22:33 volumio volumio[1123]: info: MRS: Pushing multiroomSync output update for this device Sep 10 13:22:33 volumio volumio[1123]: info: MRS: Pushing multiroomSync output Sep 10 13:22:33 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:33 volumio volumio[1123]: info: CorePlayQueue::getTrack 8 Sep 10 13:22:33 volumio volumio[1123]: info: CoreStateMachine::serviceStop Sep 10 13:22:33 volumio volumio[1123]: info: CorePlayQueue::getTrack 8 Sep 10 13:22:33 volumio volumio[1123]: info: CoreCommandRouter::serviceStop Sep 10 13:22:33 volumio volumio[1123]: info: [1757506953919] ControllerQobuz::stop Sep 10 13:22:33 volumio volumio[1123]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 10 13:22:33 volumio volumio[1123]: info: ControllerMpd::stop Sep 10 13:22:33 volumio volumio[1123]: verbose: ControllerMpd::sendMpdCommand stop Sep 10 13:22:33 volumio volumio[1123]: info: Sep 10 13:22:33 volumio volumio[1123]: ---------------------------- MPD announces state update: player Sep 10 13:22:33 volumio volumio[1123]: info: sendMpdCommand stop took 19 milliseconds Sep 10 13:22:33 volumio volumio[1123]: info: ControllerMpd::getState Sep 10 13:22:33 volumio volumio[1123]: verbose: ControllerMpd::sendMpdCommand status Sep 10 13:22:33 volumio volumio[1123]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 13:22:33 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Sep 10 13:22:33 volumio volumio[1123]: info: CoreStateMachine::pushState Sep 10 13:22:33 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 13:22:33 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Sep 10 13:22:33 volumio volumio[1123]: info: MRS: Pushing multiroomSync output update for this device Sep 10 13:22:33 volumio volumio[1123]: info: MRS: Pushing multiroomSync output Sep 10 13:22:33 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:33 volumio volumio[1123]: info: CorePlayQueue::getTrack 8 Sep 10 13:22:33 volumio volumio[1123]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received qobuzconnect Sep 10 13:22:33 volumio volumio[1123]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect Sep 10 13:22:33 volumio volumio[1123]: info: QobuzConnect: setActiveState() completed, send playback confirmation Sep 10 13:22:33 volumio qobuz-connect[2113]: 20250910 13:22:33.951 [2113.2113] INFO SampleApp: Initiating playback, initial state: Paused Sep 10 13:22:33 volumio volumio[1123]: info: sendMpdCommand status took 13 milliseconds Sep 10 13:22:33 volumio volumio[1123]: verbose: ControllerMpd::parseState Sep 10 13:22:33 volumio volumio[1123]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 13:22:33 volumio volumio[1123]: info: touch_display: Setting screensaver timeout to 120 seconds. Sep 10 13:22:33 volumio volumio[1123]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 10 13:22:33 volumio volumio[1123]: verbose: ControllerMpd::parseTrackInfo Sep 10 13:22:33 volumio volumio[1123]: info: ControllerMpd::pushState Sep 10 13:22:33 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Sep 10 13:22:33 volumio volumio[1123]: info: CoreStateMachine::pushState Sep 10 13:22:33 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Sep 10 13:22:33 volumio volumio[1123]: info: MRS: Pushing multiroomSync output update for this device Sep 10 13:22:33 volumio volumio[1123]: info: MRS: Pushing multiroomSync output Sep 10 13:22:33 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:33 volumio volumio[1123]: info: CorePlayQueue::getTrack 8 Sep 10 13:22:33 volumio volumio[1123]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Sep 10 13:22:33 volumio volumio[1123]: info: ------------------------------ 24ms Sep 10 13:22:33 volumio volumio[1123]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M30Bd4IINqWtqFpRzNZivRReveP2.89f9fed82359916726c4148695ce0598.state.status' Sep 10 13:22:34 volumio qobuz-connect[2113]: 20250910 13:22:34.010 [2113.2113] INFO SampleApp: Playback state changed: Paused Sep 10 13:22:34 volumio qobuz-connect[2113]: 20250910 13:22:34.010 [2113.2113] INFO ProtocolHandler: [0x22a6f28]: Need to send state update Sep 10 13:22:34 volumio qobuz-connect[2113]: 20250910 13:22:34.010 [2113.2113] INFO QConnectMessageSender: [0x23860c0]: Sending StateUpdated message Sep 10 13:22:34 volumio qobuz-connect[2113]: 20250910 13:22:34.010 [2113.2113] INFO QwspMessageSender: [0x2396968]: Sending Payload message Sep 10 13:22:34 volumio qobuz-connect[2113]: 20250910 13:22:34.010 [2113.2113] INFO QConnectMessageSender: [0x23860c0]: Sending FileAudioQualityChanged message Sep 10 13:22:34 volumio qobuz-connect[2113]: 20250910 13:22:34.010 [2113.2113] INFO QwspMessageSender: [0x2396968]: Sending Payload message Sep 10 13:22:34 volumio qobuz-connect[2113]: 20250910 13:22:34.010 [2113.2113] INFO QConnectMessageSender: [0x23860c0]: Sending DeviceAudioQualityChanged message Sep 10 13:22:34 volumio qobuz-connect[2113]: 20250910 13:22:34.010 [2113.2113] INFO QwspMessageSender: [0x2396968]: Sending Payload message Sep 10 13:22:34 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:34 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:34 volumio qobuz-connect[2113]: 20250910 13:22:34.067 [2113.2113] INFO SampleApp: Playback volume changed: 80 Sep 10 13:22:34 volumio qobuz-connect[2113]: 20250910 13:22:34.067 [2113.2113] INFO ProtocolHandler: [0x22a6f28]: Volume changed: 80 Sep 10 13:22:34 volumio qobuz-connect[2113]: 20250910 13:22:34.067 [2113.2113] INFO QConnectMessageSender: [0x23860c0]: Sending VolumeChanged message Sep 10 13:22:34 volumio qobuz-connect[2113]: 20250910 13:22:34.067 [2113.2113] INFO QwspMessageSender: [0x2396968]: Sending Payload message Sep 10 13:22:34 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:34 volumio qobuz-connect[2113]: 20250910 13:22:34.266 [2113.2113] INFO UrlAudioSource: [0x22b03b8]: Content info extracted Sep 10 13:22:34 volumio qobuz-connect[2113]: 20250910 13:22:34.266 [2113.2113] INFO UrlAudioSource: [0x22b03b8]: Size: 102513855 Sep 10 13:22:34 volumio qobuz-connect[2113]: 20250910 13:22:34.266 [2113.13628] INFO AudioDecoder: [0x248a900]: Stream info: Sep 10 13:22:34 volumio qobuz-connect[2113]: 20250910 13:22:34.266 [2113.13628] INFO AudioDecoder: [0x248a900]: Total samples: 23888704 Sep 10 13:22:34 volumio qobuz-connect[2113]: 20250910 13:22:34.266 [2113.13628] INFO AudioDecoder: [0x248a900]: Sample rate: 96000 Sep 10 13:22:34 volumio qobuz-connect[2113]: 20250910 13:22:34.266 [2113.13628] INFO AudioDecoder: [0x248a900]: Channel count: 2 Sep 10 13:22:34 volumio qobuz-connect[2113]: 20250910 13:22:34.266 [2113.13628] INFO AudioDecoder: [0x248a900]: Bits per sample: 24 Sep 10 13:22:34 volumio qobuz-connect[2113]: 20250910 13:22:34.266 [2113.13628] INFO AudioDecoder: [0x248a900]: Duration: 248840 Sep 10 13:22:34 volumio qobuz-connect[2113]: 20250910 13:22:34.266 [2113.2113] INFO UrlAudioSource: [0x22b03b8]: Audio properties have changed Sep 10 13:22:34 volumio qobuz-connect[2113]: 20250910 13:22:34.266 [2113.2113] INFO UrlAudioSource: [0x22b03b8]: Seeking input stream to byte offset: 7790127 Sep 10 13:22:34 volumio qobuz-connect[2113]: 20250910 13:22:34.266 [2113.2113] INFO ContentFetcher: [0x22e25f8]: Adjusting download offset to: 7790127 Sep 10 13:22:34 volumio qobuz-connect[2113]: 20250910 13:22:34.413 [2113.2113] INFO UrlAudioSource: [0x22b03b8]: Seeking input stream to byte offset: 7776511 Sep 10 13:22:34 volumio qobuz-connect[2113]: 20250910 13:22:34.413 [2113.2113] INFO ContentFetcher: [0x22e25f8]: Adjusting download offset to: 7776511 Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.361 [2113.2113] INFO ProtocolHandler: [0x22a6f28]: Received SetState message: Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.361 [2113.2113] INFO ProtocolHandler: [0x22a6f28]: Playing state: Playing Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.361 [2113.2113] INFO ProtocolHandler: [0x22a6f28]: Playback position: 0 Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.361 [2113.2113] INFO ProtocolHandler: [0x22a6f28]: Queue version: 6.1 Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.361 [2113.2113] INFO ProtocolHandler: [0x22a6f28]: Current track: TID: 345452646, QID: 0, Context UUID: 039306b5-b057-4726-ba68-ea4cf2b9b748 Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.361 [2113.2113] INFO ProtocolHandler: [0x22a6f28]: Next track: TID: 328458075, QID: 1, Context UUID: 039306b5-b057-4726-ba68-ea4cf2b9b748 Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.361 [2113.2113] INFO MediaEngine: [0x22a67e8]: Stopping playback, clearing tracks Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.361 [2113.2113] INFO MediaEngine: [0x22a67e8]: Initiating playback Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.361 [2113.2113] INFO AudioRenderer: [0x22a6a08]: Stopping audio renderer Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.361 [2113.2113] INFO SampleApp: Stopping playback Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.361 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: Clearing all streams Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.361 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: [Stream 1]: Disposing of audio stream Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.361 [2113.2113] INFO ContentFetcher: [0x22e25f8]: Canceling download Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.363 [2113.2113] INFO SampleApp: Playback state changed: Stopped Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.363 [2113.2113] INFO ProtocolHandler: [0x22a6f28]: Need to send state update Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.363 [2113.2113] INFO QConnectMessageSender: [0x23860c0]: Sending StateUpdated message Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.363 [2113.2113] INFO SampleApp: [Stream 1]: Audio stream has been disposed of Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.364 [2113.2113] INFO MediaEngine: [0x22a67e8]: Setting current track: 345452646, initial offset: 0ms Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.364 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: Clearing all streams Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.364 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: New stream: 3 Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.364 [2113.2113] INFO HttpDownloader: [0x247e6e8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=63459f9b5dc5300f5f60e40ccb7d7a8e&request_ts=1757506955&track_id=345452646 Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.364 [2113.2113] INFO HttpDownloader: [0x2380448]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=345452646 Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.364 [2113.2113] INFO HttpDownloader: [0x247a870]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=4b22d4118f54cb49128101a6ea7f38b8&request_ts=1757506955&track_id=345452646 Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.364 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: [Stream 3]: Running audio stream Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.364 [2113.2113] INFO QwspMessageSender: [0x2396968]: Sending Payload message Sep 10 13:22:35 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.364 [2113.2113] INFO MediaEngine: [0x22a67e8]: Setting next track: 328458075 Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.364 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: New stream: 4 Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.364 [2113.2113] INFO HttpDownloader: [0x24777a0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=ac04e5c9ef0ae3f2bd365cfb274920ec&request_ts=1757506955&track_id=328458075 Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.364 [2113.2113] INFO HttpDownloader: [0x23a7d60]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=328458075 Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.364 [2113.2113] INFO HttpDownloader: [0x22d9db0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=b3b52c89ceffeb724806b63408c9995d&request_ts=1757506955&track_id=328458075 Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.364 [2113.2113] INFO MediaEngine: [0x22a67e8]: Waiting for current stream to start before starting audio renderer Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.500 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: [Stream 3]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3704599&eid=345452646&fmt=7&profile=raw&app_id=174516466&cid=2686343&etsp=1757510555&hmac=XrI-HFgJ6W59O2LYfvN1XYU0du0 Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.512 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: [Stream 3]: stream information have been fetched Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.512 [2113.2113] INFO UrlAudioSource: [0x24529b0]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=3704599&eid=345452646&fmt=7&profile=raw&app_id=174516466&cid=2686343&etsp=1757510555&hmac=XrI-HFgJ6W59O2LYfvN1XYU0du0 Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.513 [2113.2113] INFO ContentFetcher: [0x24a7d48]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=3704599&eid=345452646&fmt=7&profile=raw&app_id=174516466&cid=2686343&etsp=1757510555&hmac=XrI-HFgJ6W59O2LYfvN1XYU0du0, offset: 0 Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.513 [2113.2113] INFO AudioRenderer: [0x22a6a08]: Starting audio renderer, initial playback state: Playing Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.513 [2113.2113] INFO SampleApp: [Stream 3]: New audio stream (starting from 0ms) Sep 10 13:22:35 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:35 volumio volumio[1123]: info: QobuzConnect: setActiveState() Sep 10 13:22:35 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:35 volumio volumio[1123]: info: QobuzConnect: Set Qobuz connect default volume to 80 Sep 10 13:22:35 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.513 [2113.2113] INFO VolumeManager: [0x22a6c50]: Setting new playback volume: 80 Sep 10 13:22:35 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:35 volumio volumio[1123]: info: QobuzConnect: Stopping currently active service Sep 10 13:22:35 volumio volumio[1123]: info: CoreCommandRouter::volumioStop Sep 10 13:22:35 volumio volumio[1123]: info: CoreStateMachine::stop Sep 10 13:22:35 volumio volumio[1123]: info: CoreStateMachine::serviceStop Sep 10 13:22:35 volumio volumio[1123]: info: Received STOP, but no service to execute it Sep 10 13:22:35 volumio volumio[1123]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 13:22:35 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Sep 10 13:22:35 volumio volumio[1123]: info: CoreStateMachine::pushState Sep 10 13:22:35 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Sep 10 13:22:35 volumio volumio[1123]: info: MRS: Pushing multiroomSync output update for this device Sep 10 13:22:35 volumio volumio[1123]: info: MRS: Pushing multiroomSync output Sep 10 13:22:35 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:35 volumio volumio[1123]: info: CorePlayQueue::getTrack 8 Sep 10 13:22:35 volumio volumio[1123]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received qobuzconnect Sep 10 13:22:35 volumio volumio[1123]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect Sep 10 13:22:35 volumio volumio[1123]: info: QobuzConnect: setActiveState() completed, send playback confirmation Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.517 [2113.2113] INFO SampleApp: Initiating playback, initial state: Playing Sep 10 13:22:35 volumio volumio[1123]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M30Bd4IINqWtqFpRzNZivRReveP2.89f9fed82359916726c4148695ce0598.state.status' Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.520 [2113.2113] INFO SampleApp: Playback state changed: Playing Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.520 [2113.2113] INFO StreamingEventReporter: [0x22a7340]: Reporting streaming started event Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.520 [2113.2113] INFO HttpDownloader: [0x22a7398]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Sep 10 13:22:35 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.520 [2113.2113] INFO ProtocolHandler: [0x22a6f28]: Need to send state update Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.520 [2113.2113] INFO QConnectMessageSender: [0x23860c0]: Sending StateUpdated message Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.520 [2113.2113] INFO QwspMessageSender: [0x2396968]: Sending Payload message Sep 10 13:22:35 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Sep 10 13:22:35 volumio volumio[1123]: info: CoreStateMachine::pushState Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.520 [2113.2113] INFO QConnectMessageSender: [0x23860c0]: Sending FileAudioQualityChanged message Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.520 [2113.2113] INFO QwspMessageSender: [0x2396968]: Sending Payload message Sep 10 13:22:35 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 13:22:35 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Sep 10 13:22:35 volumio volumio[1123]: info: MRS: Pushing multiroomSync output update for this device Sep 10 13:22:35 volumio volumio[1123]: info: MRS: Pushing multiroomSync output Sep 10 13:22:35 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.522 [2113.2113] INFO VolumeManager: [0x22a6c50]: Setting new playback volume: 80 Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.522 [2113.2113] INFO VolumeManager: [0x22a6c50]: Setting new mute state: 0 Sep 10 13:22:35 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:35 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Sep 10 13:22:35 volumio volumio[1123]: info: CoreStateMachine::pushState Sep 10 13:22:35 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 13:22:35 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Sep 10 13:22:35 volumio volumio[1123]: info: MRS: Pushing multiroomSync output update for this device Sep 10 13:22:35 volumio volumio[1123]: info: MRS: Pushing multiroomSync output Sep 10 13:22:35 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:35 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:35 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Sep 10 13:22:35 volumio volumio[1123]: info: CoreStateMachine::pushState Sep 10 13:22:35 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Sep 10 13:22:35 volumio volumio[1123]: info: MRS: Pushing multiroomSync output update for this device Sep 10 13:22:35 volumio volumio[1123]: info: MRS: Pushing multiroomSync output Sep 10 13:22:35 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Sep 10 13:22:35 volumio volumio[1123]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Sep 10 13:22:35 volumio volumio[1123]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 10 13:22:35 volumio volumio[1123]: TypeError: Cannot read property 'indexOf' of undefined Sep 10 13:22:35 volumio volumio[1123]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Sep 10 13:22:35 volumio volumio[1123]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Sep 10 13:22:35 volumio volumio[1123]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Sep 10 13:22:35 volumio volumio[1123]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Sep 10 13:22:35 volumio volumio[1123]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Sep 10 13:22:35 volumio volumio[1123]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Sep 10 13:22:35 volumio volumio[1123]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Sep 10 13:22:35 volumio volumio[1123]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Sep 10 13:22:35 volumio volumio[1123]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Sep 10 13:22:35 volumio volumio[1123]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Sep 10 13:22:35 volumio volumio[1123]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Sep 10 13:22:35 volumio volumio[1123]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Sep 10 13:22:35 volumio volumio[1123]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Sep 10 13:22:35 volumio volumio[1123]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Sep 10 13:22:35 volumio volumio[1123]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Sep 10 13:22:35 volumio volumio[1123]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Sep 10 13:22:35 volumio volumio[1123]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.569 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: [Stream 4]: Metadata became available: Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.569 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: Title: Illegal Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.569 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: Artist: PinkPantheress Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.569 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: Album: Fancy That Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.569 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: Album art URL: https://static.qobuz.com/images/covers/tc/76/hpreil4ot76tc_600.jpg Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.579 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: [Stream 4]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3704599&eid=328458075&fmt=7&profile=raw&app_id=174516466&cid=2686343&etsp=1757510555&hmac=D-rhxHZ9Z2xRxwv3ByT4z0Qmrx4 Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.599 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: [Stream 4]: stream information have been fetched Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.600 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: [Stream 3]: Metadata became available: Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.600 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: Title: infinite source Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.600 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: Artist: Deftones Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.600 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: Album: private music Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.600 [2113.2113] INFO AudioStreamManager: [0x22a68d0]: Album art URL: https://static.qobuz.com/images/covers/mb/5y/jr41c1qhl5ymb_600.jpg Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.600 [2113.2113] INFO SampleApp: [Stream 3]: Stream metadata became available: Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.600 [2113.2113] INFO SampleApp: Title: infinite source Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.600 [2113.2113] INFO SampleApp: Artist: Deftones Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.600 [2113.2113] INFO SampleApp: Album: private music Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.600 [2113.2113] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/mb/5y/jr41c1qhl5ymb_600.jpg Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.707 [2113.2113] INFO UrlAudioSource: [0x24529b0]: Content info extracted Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.707 [2113.2113] INFO UrlAudioSource: [0x24529b0]: Size: 87777494 Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.707 [2113.13640] INFO AudioDecoder: [0x241d180]: Stream info: Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.707 [2113.13640] INFO AudioDecoder: [0x241d180]: Total samples: 20407432 Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.707 [2113.13640] INFO AudioDecoder: [0x241d180]: Sample rate: 96000 Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.707 [2113.13640] INFO AudioDecoder: [0x241d180]: Channel count: 2 Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.707 [2113.13640] INFO AudioDecoder: [0x241d180]: Bits per sample: 24 Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.707 [2113.13640] INFO AudioDecoder: [0x241d180]: Duration: 212577 Sep 10 13:22:35 volumio qobuz-connect[2113]: 20250910 13:22:35.707 [2113.2113] INFO UrlAudioSource: [0x24529b0]: Audio properties have changed Sep 10 13:22:35 volumio sudo[13639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-10 13:21 Sep 10 13:22:35 volumio sudo[13639]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"