-- Logs begin at Sat 2025-06-14 15:07:24 +08, end at Sun 2025-06-15 20:53:09 +08. -- Jun 15 20:52:01 minidsp-shd volumio[684]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 15 20:52:01 minidsp-shd volumio[684]: info: Retrying Login Due to Network Error Jun 15 20:52:09 minidsp-shd volumio[684]: Searching all installed plugins Jun 15 20:52:09 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 15 20:52:09 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: , search Jun 15 20:52:09 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jun 15 20:52:09 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jun 15 20:52:09 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jun 15 20:52:09 minidsp-shd volumio[684]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jun 15 20:52:09 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 15 20:52:09 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: inputs , search Jun 15 20:52:09 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 15 20:52:09 minidsp-shd volumio[684]: info: searchTIDALUri took 621 milliseconds Jun 15 20:52:09 minidsp-shd volumio[684]: info: search took 623 milliseconds Jun 15 20:52:10 minidsp-shd volumio[684]: (node:684) UnhandledPromiseRejectionWarning: Error: Start tag expected, '<' not found Jun 15 20:52:10 minidsp-shd volumio[684]: at Object.module.exports.fromXml (/volumio/node_modules/libxmljs/lib/document.js:168:21) Jun 15 20:52:10 minidsp-shd volumio[684]: at /volumio/app/plugins/music_service/webradio/index.js:971:31 Jun 15 20:52:10 minidsp-shd volumio[684]: at runMicrotasks () Jun 15 20:52:10 minidsp-shd volumio[684]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Jun 15 20:52:10 minidsp-shd volumio[684]: (node:684) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 2) Jun 15 20:52:10 minidsp-shd volumio[684]: (node:684) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Jun 15 20:52:11 minidsp-shd volumio[684]: info: MyVolumio login type: Token Jun 15 20:52:14 minidsp-shd volumio[684]: error: Search in plugin webradio timed out Jun 15 20:52:14 minidsp-shd volumio[684]: error: Search in plugin inputs timed out Jun 15 20:52:14 minidsp-shd volumio[684]: info: All search sources collected, pushing search results Jun 15 20:52:22 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jun 15 20:52:22 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jun 15 20:52:22 minidsp-shd volumio[684]: info: browseTIDALUri took 537 milliseconds Jun 15 20:52:22 minidsp-shd volumio[684]: info: Preload queue cleared Jun 15 20:52:22 minidsp-shd volumio[684]: info: Preload queue cleared Jun 15 20:52:23 minidsp-shd volumio[684]: info: browseTIDALUri took 488 milliseconds Jun 15 20:52:23 minidsp-shd volumio[684]: info: Preload queue cleared Jun 15 20:52:23 minidsp-shd volumio[684]: info: Preload queue cleared Jun 15 20:52:24 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jun 15 20:52:25 minidsp-shd volumio[684]: info: browseTIDALUri took 464 milliseconds Jun 15 20:52:25 minidsp-shd volumio[684]: info: Preload queue cleared Jun 15 20:52:26 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 20:52:26 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 20:52:26 minidsp-shd volumio[684]: info: Discovery: Getting this device information Jun 15 20:52:26 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioGetState Jun 15 20:52:26 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 20:52:28 minidsp-shd volumio[684]: info: Preload queue cleared Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreStateMachine::ClearQueue Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreStateMachine::stop Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreStateMachine::updateTrackBlock Jun 15 20:52:28 minidsp-shd volumio[684]: info: CorePlayQueue::getTrackBlock Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreStateMachine::stPlaybackTimer Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:28 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 0 Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:28 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:28 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreStateMachine::serviceStop Jun 15 20:52:28 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 0 Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreCommandRouter::serviceStop Jun 15 20:52:28 minidsp-shd volumio[684]: info: [1749991948038] ControllerTidal::stop Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 15 20:52:28 minidsp-shd volumio[684]: info: ControllerMpd::stop Jun 15 20:52:28 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand stop Jun 15 20:52:28 minidsp-shd volumio[684]: info: CorePlayQueue::clearPlayQueue Jun 15 20:52:28 minidsp-shd volumio[684]: info: CorePlayQueue::saveQueue Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushQueue Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreStateMachine::addQueueItems Jun 15 20:52:28 minidsp-shd volumio[684]: info: CorePlayQueue::addQueueItems Jun 15 20:52:28 minidsp-shd volumio[684]: info: Preload queue cleared Jun 15 20:52:28 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/198546505 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/198546505 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/198546506 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/198546506 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/198546507 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/198546507 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/198546508 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/198546508 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/198546509 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/198546509 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/198546510 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/198546510 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/198546511 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/198546511 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/198546512 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/198546512 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/198546513 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/198546513 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/198546514 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/198546514 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/198546515 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/198546515 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/198546516 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/198546516 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/198546517 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/198546517 Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushQueue Jun 15 20:52:28 minidsp-shd volumio[684]: info: CorePlayQueue::saveQueue Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreStateMachine::updateTrackBlock Jun 15 20:52:28 minidsp-shd volumio[684]: info: CorePlayQueue::getTrackBlock Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPlay Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreStateMachine::play index 7 Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreStateMachine::stop Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreStateMachine::play index undefined Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 20:52:28 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 7 Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreStateMachine::startPlaybackTimer Jun 15 20:52:28 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 7 Jun 15 20:52:28 minidsp-shd volumio[684]: info: [1749991948103] ControllerTidal::clearAddPlayTrack Jun 15 20:52:28 minidsp-shd volumio[684]: info: Getting stream with soundQuality LOSSLESS Jun 15 20:52:28 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand stop Jun 15 20:52:28 minidsp-shd volumio[684]: info: Jun 15 20:52:28 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:52:28 minidsp-shd volumio[684]: info: sendMpdCommand stop took 93 milliseconds Jun 15 20:52:28 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:52:28 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:52:28 minidsp-shd volumio[684]: info: sendMpdCommand stop took 30 milliseconds Jun 15 20:52:28 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand clear Jun 15 20:52:28 minidsp-shd volumio[684]: info: Jun 15 20:52:28 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:52:28 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:52:28 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:52:28 minidsp-shd volumio[684]: info: Jun 15 20:52:28 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:52:28 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:52:28 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:52:28 minidsp-shd volumio[684]: info: Jun 15 20:52:28 minidsp-shd volumio[684]: ---------------------------- MPD announces system playlist update Jun 15 20:52:28 minidsp-shd volumio[684]: info: Ignoring MPD Status Update Jun 15 20:52:28 minidsp-shd volumio[684]: info: sendMpdCommand status took 32 milliseconds Jun 15 20:52:28 minidsp-shd volumio[684]: info: sendMpdCommand clear took 29 milliseconds Jun 15 20:52:28 minidsp-shd volumio[684]: info: sendMpdCommand status took 25 milliseconds Jun 15 20:52:28 minidsp-shd volumio[684]: info: sendMpdCommand status took 22 milliseconds Jun 15 20:52:28 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:52:28 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:52:28 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/198546512&soundQuality=LOSSLESS" Jun 15 20:52:28 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:52:28 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:52:28 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:28 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 7 Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:28 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:28 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:28 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 7 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jun 15 20:52:28 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:28 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 7 Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:28 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:28 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:28 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 7 Jun 15 20:52:28 minidsp-shd volumio[684]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jun 15 20:52:28 minidsp-shd volumio[684]: info: ------------------------------ 68ms Jun 15 20:52:28 minidsp-shd volumio[684]: info: ------------------------------ 65ms Jun 15 20:52:28 minidsp-shd volumio[684]: info: Jun 15 20:52:28 minidsp-shd volumio[684]: ---------------------------- MPD announces system playlist update Jun 15 20:52:28 minidsp-shd volumio[684]: info: Ignoring MPD Status Update Jun 15 20:52:28 minidsp-shd volumio[684]: info: Jun 15 20:52:28 minidsp-shd volumio[684]: ---------------------------- MPD announces system playlist update Jun 15 20:52:28 minidsp-shd volumio[684]: info: Ignoring MPD Status Update Jun 15 20:52:28 minidsp-shd volumio[684]: error: Upnp client error: Error: This socket has been ended by the other party Jun 15 20:52:28 minidsp-shd volumio[684]: error: updateQueue error: null Jun 15 20:52:28 minidsp-shd volumio[684]: info: Jun 15 20:52:28 minidsp-shd volumio[684]: ---------------------------- MPD announces system playlist update Jun 15 20:52:28 minidsp-shd volumio[684]: info: Ignoring MPD Status Update Jun 15 20:52:28 minidsp-shd volumio[684]: info: ------------------------------ 63ms Jun 15 20:52:28 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 57 milliseconds Jun 15 20:52:28 minidsp-shd volumio[684]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/198546512&soundQuality=LOSSLESS" took 56 milliseconds Jun 15 20:52:28 minidsp-shd volumio[684]: info: ------------------------------ 17ms Jun 15 20:52:28 minidsp-shd volumio[684]: info: ------------------------------ 15ms Jun 15 20:52:28 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 15 20:52:28 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand play Jun 15 20:52:28 minidsp-shd volumio[684]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 15 20:52:28 minidsp-shd volumio[684]: info: ------------------------------ 105ms Jun 15 20:52:28 minidsp-shd volumio[684]: info: Jun 15 20:52:28 minidsp-shd volumio[684]: ---------------------------- MPD announces system playlist update Jun 15 20:52:28 minidsp-shd volumio[684]: info: Ignoring MPD Status Update Jun 15 20:52:28 minidsp-shd volumio[684]: STREAMING PROXY: Handling url /?data=tidal://song/198546512&soundQuality=LOSSLESS Jun 15 20:52:28 minidsp-shd volumio[684]: info: Jun 15 20:52:28 minidsp-shd volumio[684]: ---------------------------- MPD announces system playlist update Jun 15 20:52:28 minidsp-shd volumio[684]: info: Ignoring MPD Status Update Jun 15 20:52:28 minidsp-shd volumio[684]: info: ------------------------------ 46ms Jun 15 20:52:28 minidsp-shd volumio[684]: info: sendMpdCommand play took 38 milliseconds Jun 15 20:52:28 minidsp-shd volumio[684]: info: ------------------------------ 35ms Jun 15 20:52:28 minidsp-shd volumio[684]: info: ------------------------------ 32ms Jun 15 20:52:28 minidsp-shd volumio[684]: info: Executing endpoint getStreamUrltidal Jun 15 20:52:28 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 15 20:52:28 minidsp-shd volumio[684]: info: getStreamUrl took 514 milliseconds Jun 15 20:52:28 minidsp-shd volumio[684]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidlZDAwOGU2MDZmODM5Y2MyYmE1NzRkYzgwMjYxODNhYl82MS5tcDQ/0.flac?token=1749995296~NDNkNGQ5ZWNmZTI5ZDFhNzk1NGY1ZmZhYTIyMWU2ZWU1YjU4ZDMzZA== Jun 15 20:52:29 minidsp-shd volumio[684]: STREAMING PROXY: Response: 200, length: 29228547 Jun 15 20:52:29 minidsp-shd volumio[684]: info: Jun 15 20:52:29 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:52:29 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:52:29 minidsp-shd volumio[684]: info: Jun 15 20:52:29 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:52:29 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:52:29 minidsp-shd volumio[684]: info: Jun 15 20:52:29 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:52:29 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:52:29 minidsp-shd volumio[684]: info: Jun 15 20:52:29 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:52:29 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:52:29 minidsp-shd volumio[684]: info: Jun 15 20:52:29 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:52:29 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:52:29 minidsp-shd volumio[684]: info: Jun 15 20:52:29 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:52:29 minidsp-shd volumio[684]: info: sendMpdCommand status took 26 milliseconds Jun 15 20:52:29 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:52:29 minidsp-shd volumio[684]: info: sendMpdCommand status took 22 milliseconds Jun 15 20:52:29 minidsp-shd volumio[684]: info: sendMpdCommand status took 17 milliseconds Jun 15 20:52:29 minidsp-shd volumio[684]: info: sendMpdCommand status took 12 milliseconds Jun 15 20:52:29 minidsp-shd volumio[684]: info: sendMpdCommand status took 9 milliseconds Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:52:29 minidsp-shd volumio[684]: info: sendMpdCommand status took 17 milliseconds Jun 15 20:52:29 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 15 20:52:29 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 15 20:52:29 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 15 20:52:29 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 15 20:52:29 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:52:29 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 7 Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"198546512&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/198546512&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: CURRENT POSITION 7 Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus stop Jun 15 20:52:29 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 7 Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"198546512&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/198546512&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: CURRENT POSITION 7 Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus play Jun 15 20:52:29 minidsp-shd volumio[684]: info: Received an update from plugin. extracting info from payload Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:29 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:29 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:29 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 7 Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":274,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"217 Kbps","isStreaming":false,"title":"198546512&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/198546512&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: CURRENT POSITION 7 Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus play Jun 15 20:52:29 minidsp-shd volumio[684]: info: Received an update from plugin. extracting info from payload Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:29 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:29 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:29 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 7 Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"465 Kbps","isStreaming":false,"title":"198546512&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/198546512&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: CURRENT POSITION 7 Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus play Jun 15 20:52:29 minidsp-shd volumio[684]: info: Received an update from plugin. extracting info from payload Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:29 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:29 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:29 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 7 Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"465 Kbps","isStreaming":false,"title":"198546512&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/198546512&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: CURRENT POSITION 7 Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus play Jun 15 20:52:29 minidsp-shd volumio[684]: info: Received an update from plugin. extracting info from payload Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:29 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:29 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:29 minidsp-shd volumio[684]: info: ------------------------------ 164ms Jun 15 20:52:29 minidsp-shd volumio[684]: info: ------------------------------ 195ms Jun 15 20:52:29 minidsp-shd volumio[684]: info: ------------------------------ 193ms Jun 15 20:52:29 minidsp-shd volumio[684]: info: ------------------------------ 189ms Jun 15 20:52:29 minidsp-shd volumio[684]: info: ------------------------------ 186ms Jun 15 20:52:29 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 158 milliseconds Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:52:29 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 7 Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"465 Kbps","isStreaming":false,"title":"198546512&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/198546512&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:52:29 minidsp-shd volumio[684]: verbose: CURRENT POSITION 7 Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus play Jun 15 20:52:29 minidsp-shd volumio[684]: info: Received an update from plugin. extracting info from payload Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:29 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:29 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:29 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:29 minidsp-shd volumio[684]: info: ------------------------------ 221ms Jun 15 20:52:33 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioSeek Jun 15 20:52:33 minidsp-shd volumio[684]: info: CoreStateMachine::seek Jun 15 20:52:33 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 7 Jun 15 20:52:33 minidsp-shd volumio[684]: info: TRACKBLOCK {"album":"如风往事","albumUri":"tidal://album/198546504","albumart":"https://resources.tidal.com/images/9bd20926/a912/486b/984c/d53218860502/640x640.jpg","artist":"小娟&山谷里的居民","artistUri":"tidal://artist/28434057","audioQuality":"LOSSLESS","bitdepth":"16 bit","duration":307,"explicit":false,"name":"爱的箴言","samplerate":"44.1 KHz","service":"tidal","title":"爱的箴言","trackType":"tidal","tracknumber":8,"type":"track","uri":"tidal://song/198546512","volumeNumber":1} Jun 15 20:52:33 minidsp-shd volumio[684]: info: CoreStateMachine::startPlaybackTimer Jun 15 20:52:33 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 7 Jun 15 20:52:33 minidsp-shd volumio[684]: info: [1749991953699] ControllerTidal::seek Jun 15 20:52:33 minidsp-shd volumio[684]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 15 20:52:33 minidsp-shd volumio[684]: info: ControllerMpd::seek Jun 15 20:52:33 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:33 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 20:52:33 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:33 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:33 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:33 minidsp-shd volumio[684]: STREAMING PROXY: Client dropped request, destroying Jun 15 20:52:33 minidsp-shd volumio[684]: STREAMING PROXY: Handling url /?data=tidal://song/198546512&soundQuality=LOSSLESS Jun 15 20:52:33 minidsp-shd volumio[684]: info: Executing endpoint getStreamUrltidal Jun 15 20:52:33 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 15 20:52:34 minidsp-shd volumio[684]: info: getStreamUrl took 519 milliseconds Jun 15 20:52:34 minidsp-shd volumio[684]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidlZDAwOGU2MDZmODM5Y2MyYmE1NzRkYzgwMjYxODNhYl82MS5tcDQ/0.flac?token=1749995296~NDNkNGQ5ZWNmZTI5ZDFhNzk1NGY1ZmZhYTIyMWU2ZWU1YjU4ZDMzZA== Jun 15 20:52:34 minidsp-shd volumio[684]: STREAMING PROXY: Got range request: bytes=6057080- Jun 15 20:52:34 minidsp-shd volumio[684]: STREAMING PROXY: Response: 206, length: 23171467 Jun 15 20:52:34 minidsp-shd volumio[684]: error: null Jun 15 20:52:34 minidsp-shd volumio[684]: info: Jun 15 20:52:34 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:52:34 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:52:34 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:52:34 minidsp-shd volumio[684]: info: Jun 15 20:52:34 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:52:34 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:52:34 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:52:34 minidsp-shd volumio[684]: info: Jun 15 20:52:34 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:52:34 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:52:34 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:52:34 minidsp-shd volumio[684]: info: sendMpdCommand status took 13 milliseconds Jun 15 20:52:34 minidsp-shd volumio[684]: info: sendMpdCommand status took 9 milliseconds Jun 15 20:52:34 minidsp-shd volumio[684]: info: sendMpdCommand status took 6 milliseconds Jun 15 20:52:34 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:52:34 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:52:34 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:52:34 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:52:34 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:52:34 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:52:34 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 15 20:52:34 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 15 20:52:34 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 15 20:52:34 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:52:34 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:52:34 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:52:34 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:52:34 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 7 Jun 15 20:52:34 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":67000,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"839 Kbps","isStreaming":false,"title":"198546512&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/198546512&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:52:34 minidsp-shd volumio[684]: verbose: CURRENT POSITION 7 Jun 15 20:52:34 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:52:34 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus play Jun 15 20:52:34 minidsp-shd volumio[684]: info: Received an update from plugin. extracting info from payload Jun 15 20:52:34 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:34 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 20:52:34 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:34 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:34 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:34 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:34 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:34 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:34 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:34 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:52:34 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:52:34 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 7 Jun 15 20:52:34 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":67000,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"839 Kbps","isStreaming":false,"title":"198546512&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/198546512&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:52:34 minidsp-shd volumio[684]: verbose: CURRENT POSITION 7 Jun 15 20:52:34 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:52:34 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus play Jun 15 20:52:34 minidsp-shd volumio[684]: info: Received an update from plugin. extracting info from payload Jun 15 20:52:34 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:34 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:34 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:34 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:34 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:34 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:34 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:34 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:34 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:52:34 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:52:34 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 7 Jun 15 20:52:34 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":67000,"duration":307,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"839 Kbps","isStreaming":false,"title":"198546512&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/198546512&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:52:34 minidsp-shd volumio[684]: verbose: CURRENT POSITION 7 Jun 15 20:52:34 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:52:34 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus play Jun 15 20:52:34 minidsp-shd volumio[684]: info: Received an update from plugin. extracting info from payload Jun 15 20:52:34 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:34 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:34 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:34 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:34 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:34 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:34 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:34 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:34 minidsp-shd volumio[684]: info: ------------------------------ 187ms Jun 15 20:52:34 minidsp-shd volumio[684]: info: ------------------------------ 184ms Jun 15 20:52:34 minidsp-shd volumio[684]: info: ------------------------------ 183ms Jun 15 20:52:35 minidsp-shd volumio[684]: STREAMING PROXY: Client dropped request, destroying Jun 15 20:52:35 minidsp-shd volumio[684]: STREAMING PROXY: Handling url /?data=tidal://song/198546512&soundQuality=LOSSLESS Jun 15 20:52:35 minidsp-shd volumio[684]: info: Executing endpoint getStreamUrltidal Jun 15 20:52:35 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 15 20:52:37 minidsp-shd volumio[684]: info: getStreamUrl took 1480 milliseconds Jun 15 20:52:37 minidsp-shd volumio[684]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidlZDAwOGU2MDZmODM5Y2MyYmE1NzRkYzgwMjYxODNhYl82MS5tcDQ/0.flac?token=1749995296~NDNkNGQ5ZWNmZTI5ZDFhNzk1NGY1ZmZhYTIyMWU2ZWU1YjU4ZDMzZA== Jun 15 20:52:37 minidsp-shd volumio[684]: STREAMING PROXY: Got range request: bytes=2340800- Jun 15 20:52:37 minidsp-shd volumio[684]: STREAMING PROXY: Response: 206, length: 26887747 Jun 15 20:52:41 minidsp-shd volumio[684]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 15 20:52:41 minidsp-shd volumio[684]: info: Retrying Login Due to Network Error Jun 15 20:52:42 minidsp-shd volumio[684]: STREAMING PROXY: Client dropped request, destroying Jun 15 20:52:46 minidsp-shd volumio[684]: Searching plugin music_service/tidal Jun 15 20:52:46 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 15 20:52:47 minidsp-shd volumio[684]: info: searchTIDALUri took 329 milliseconds Jun 15 20:52:47 minidsp-shd volumio[684]: info: search took 331 milliseconds Jun 15 20:52:47 minidsp-shd volumio[684]: info: All search sources collected, pushing search results Jun 15 20:52:51 minidsp-shd volumio[684]: info: MyVolumio login type: Token Jun 15 20:52:52 minidsp-shd volumio[684]: info: Preload queue cleared Jun 15 20:52:52 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 15 20:52:52 minidsp-shd volumio[684]: info: CoreStateMachine::ClearQueue Jun 15 20:52:52 minidsp-shd volumio[684]: info: CoreStateMachine::stop Jun 15 20:52:52 minidsp-shd volumio[684]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 20:52:52 minidsp-shd volumio[684]: info: CoreStateMachine::stPlaybackTimer Jun 15 20:52:52 minidsp-shd volumio[684]: info: CoreStateMachine::updateTrackBlock Jun 15 20:52:52 minidsp-shd volumio[684]: info: CorePlayQueue::getTrackBlock Jun 15 20:52:52 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:52 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 7 Jun 15 20:52:52 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 20:52:52 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:52 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:52 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:52 minidsp-shd volumio[684]: info: CoreStateMachine::serviceStop Jun 15 20:52:52 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 7 Jun 15 20:52:52 minidsp-shd volumio[684]: info: CoreCommandRouter::serviceStop Jun 15 20:52:52 minidsp-shd volumio[684]: info: [1749991972742] ControllerTidal::stop Jun 15 20:52:52 minidsp-shd volumio[684]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 15 20:52:52 minidsp-shd volumio[684]: info: ControllerMpd::stop Jun 15 20:52:52 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand stop Jun 15 20:52:52 minidsp-shd volumio[684]: info: CorePlayQueue::clearPlayQueue Jun 15 20:52:52 minidsp-shd volumio[684]: info: CorePlayQueue::saveQueue Jun 15 20:52:52 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushQueue Jun 15 20:52:52 minidsp-shd volumio[684]: info: CoreStateMachine::addQueueItems Jun 15 20:52:52 minidsp-shd volumio[684]: info: CorePlayQueue::addQueueItems Jun 15 20:52:52 minidsp-shd volumio[684]: info: Preload queue cleared Jun 15 20:52:52 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/3607410 Jun 15 20:52:52 minidsp-shd volumio[684]: info: Exploding uri tidal://song/3607410 in service tidal Jun 15 20:52:52 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/225591868 Jun 15 20:52:52 minidsp-shd volumio[684]: info: Exploding uri tidal://song/225591868 in service tidal Jun 15 20:52:52 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/92525318 Jun 15 20:52:52 minidsp-shd volumio[684]: info: Exploding uri tidal://song/92525318 in service tidal Jun 15 20:52:52 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/105498216 Jun 15 20:52:52 minidsp-shd volumio[684]: info: Exploding uri tidal://song/105498216 in service tidal Jun 15 20:52:52 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/191640932 Jun 15 20:52:52 minidsp-shd volumio[684]: info: Exploding uri tidal://song/191640932 in service tidal Jun 15 20:52:52 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/16431724 Jun 15 20:52:52 minidsp-shd volumio[684]: info: Exploding uri tidal://song/16431724 in service tidal Jun 15 20:52:52 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/16305078 Jun 15 20:52:52 minidsp-shd volumio[684]: info: Exploding uri tidal://song/16305078 in service tidal Jun 15 20:52:52 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/7015309 Jun 15 20:52:52 minidsp-shd volumio[684]: info: Exploding uri tidal://song/7015309 in service tidal Jun 15 20:52:52 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/68522394 Jun 15 20:52:52 minidsp-shd volumio[684]: info: Exploding uri tidal://song/68522394 in service tidal Jun 15 20:52:52 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/335193827 Jun 15 20:52:52 minidsp-shd volumio[684]: info: Exploding uri tidal://song/335193827 in service tidal Jun 15 20:52:52 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/263528414 Jun 15 20:52:52 minidsp-shd volumio[684]: info: Exploding uri tidal://song/263528414 in service tidal Jun 15 20:52:52 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/298889401 Jun 15 20:52:52 minidsp-shd volumio[684]: info: Exploding uri tidal://song/298889401 in service tidal Jun 15 20:52:52 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/13041479 Jun 15 20:52:52 minidsp-shd volumio[684]: info: Exploding uri tidal://song/13041479 in service tidal Jun 15 20:52:52 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/410643468 Jun 15 20:52:52 minidsp-shd volumio[684]: info: Exploding uri tidal://song/410643468 in service tidal Jun 15 20:52:52 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/21024295 Jun 15 20:52:52 minidsp-shd volumio[684]: info: Exploding uri tidal://song/21024295 in service tidal Jun 15 20:52:52 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/411281196 Jun 15 20:52:52 minidsp-shd volumio[684]: info: Exploding uri tidal://song/411281196 in service tidal Jun 15 20:52:52 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/3637912 Jun 15 20:52:52 minidsp-shd volumio[684]: info: Exploding uri tidal://song/3637912 in service tidal Jun 15 20:52:52 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/52587274 Jun 15 20:52:52 minidsp-shd volumio[684]: info: Exploding uri tidal://song/52587274 in service tidal Jun 15 20:52:52 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/59715451 Jun 15 20:52:52 minidsp-shd volumio[684]: info: Exploding uri tidal://song/59715451 in service tidal Jun 15 20:52:52 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/130206620 Jun 15 20:52:52 minidsp-shd volumio[684]: info: Exploding uri tidal://song/130206620 in service tidal Jun 15 20:52:53 minidsp-shd volumio[684]: info: Jun 15 20:52:53 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:52:53 minidsp-shd volumio[684]: info: sendMpdCommand stop took 280 milliseconds Jun 15 20:52:53 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:52:53 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:52:53 minidsp-shd volumio[684]: info: Jun 15 20:52:53 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:52:53 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:52:53 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:52:53 minidsp-shd volumio[684]: info: Jun 15 20:52:53 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:52:53 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:52:53 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:52:53 minidsp-shd volumio[684]: info: sendMpdCommand status took 21 milliseconds Jun 15 20:52:53 minidsp-shd volumio[684]: info: sendMpdCommand status took 17 milliseconds Jun 15 20:52:53 minidsp-shd volumio[684]: info: sendMpdCommand status took 14 milliseconds Jun 15 20:52:53 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:52:53 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:52:53 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:52:53 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:52:53 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:52:53 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:52:53 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 15 20:52:53 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 15 20:52:53 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 15 20:52:53 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:52:53 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:52:53 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:52:53 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:53 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:53 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:53 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 7 Jun 15 20:52:53 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"198546512&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/198546512&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:52:53 minidsp-shd volumio[684]: verbose: CURRENT POSITION 7 Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService stop Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus stop Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:53 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:53 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:53 minidsp-shd volumio[684]: info: No code Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:53 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:53 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:53 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:53 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:53 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:53 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 7 Jun 15 20:52:53 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"198546512&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/198546512&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:52:53 minidsp-shd volumio[684]: verbose: CURRENT POSITION 7 Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService stop Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus stop Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:53 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:53 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:53 minidsp-shd volumio[684]: info: No code Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:53 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:53 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:53 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:53 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:53 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:53 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 7 Jun 15 20:52:53 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"198546512&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/198546512&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:52:53 minidsp-shd volumio[684]: verbose: CURRENT POSITION 7 Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService stop Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus stop Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:53 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:53 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:53 minidsp-shd volumio[684]: info: No code Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:53 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:53 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:53 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:53 minidsp-shd volumio[684]: info: ------------------------------ 185ms Jun 15 20:52:53 minidsp-shd volumio[684]: info: ------------------------------ 182ms Jun 15 20:52:53 minidsp-shd volumio[684]: info: ------------------------------ 181ms Jun 15 20:52:53 minidsp-shd volumio[684]: info: explodeTIDALUri took 1017 milliseconds Jun 15 20:52:53 minidsp-shd volumio[684]: info: explodeTIDALUri took 1009 milliseconds Jun 15 20:52:53 minidsp-shd volumio[684]: info: explodeTIDALUri took 1000 milliseconds Jun 15 20:52:53 minidsp-shd volumio[684]: info: explodeTIDALUri took 1053 milliseconds Jun 15 20:52:53 minidsp-shd volumio[684]: info: explodeTIDALUri took 1079 milliseconds Jun 15 20:52:53 minidsp-shd volumio[684]: info: explodeTIDALUri took 1115 milliseconds Jun 15 20:52:53 minidsp-shd volumio[684]: info: explodeTIDALUri took 1149 milliseconds Jun 15 20:52:53 minidsp-shd volumio[684]: info: explodeTIDALUri took 1116 milliseconds Jun 15 20:52:53 minidsp-shd volumio[684]: info: explodeTIDALUri took 1118 milliseconds Jun 15 20:52:53 minidsp-shd volumio[684]: info: explodeTIDALUri took 1172 milliseconds Jun 15 20:52:53 minidsp-shd volumio[684]: info: explodeTIDALUri took 1179 milliseconds Jun 15 20:52:53 minidsp-shd volumio[684]: info: explodeTIDALUri took 1203 milliseconds Jun 15 20:52:54 minidsp-shd volumio[684]: info: explodeTIDALUri took 1221 milliseconds Jun 15 20:52:54 minidsp-shd volumio[684]: info: explodeTIDALUri took 1191 milliseconds Jun 15 20:52:54 minidsp-shd volumio[684]: info: explodeTIDALUri took 1275 milliseconds Jun 15 20:52:54 minidsp-shd volumio[684]: info: explodeTIDALUri took 1274 milliseconds Jun 15 20:52:54 minidsp-shd volumio[684]: info: explodeTIDALUri took 1315 milliseconds Jun 15 20:52:54 minidsp-shd volumio[684]: info: explodeTIDALUri took 1359 milliseconds Jun 15 20:52:54 minidsp-shd volumio[684]: info: explodeTIDALUri took 1356 milliseconds Jun 15 20:52:54 minidsp-shd volumio[684]: info: explodeTIDALUri took 1747 milliseconds Jun 15 20:52:54 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushQueue Jun 15 20:52:54 minidsp-shd volumio[684]: info: CorePlayQueue::saveQueue Jun 15 20:52:54 minidsp-shd volumio[684]: info: CoreStateMachine::updateTrackBlock Jun 15 20:52:54 minidsp-shd volumio[684]: info: CorePlayQueue::getTrackBlock Jun 15 20:52:54 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPlay Jun 15 20:52:54 minidsp-shd volumio[684]: info: CoreStateMachine::play index 1 Jun 15 20:52:54 minidsp-shd volumio[684]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 20:52:54 minidsp-shd volumio[684]: info: CoreStateMachine::stop Jun 15 20:52:54 minidsp-shd volumio[684]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 20:52:54 minidsp-shd volumio[684]: info: CoreStateMachine::play index undefined Jun 15 20:52:54 minidsp-shd volumio[684]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 20:52:54 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 1 Jun 15 20:52:54 minidsp-shd volumio[684]: info: CoreStateMachine::startPlaybackTimer Jun 15 20:52:54 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 1 Jun 15 20:52:54 minidsp-shd volumio[684]: info: [1749991974520] ControllerTidal::clearAddPlayTrack Jun 15 20:52:54 minidsp-shd volumio[684]: info: Getting stream with soundQuality LOSSLESS Jun 15 20:52:54 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand stop Jun 15 20:52:54 minidsp-shd volumio[684]: info: sendMpdCommand stop took 4 milliseconds Jun 15 20:52:54 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand clear Jun 15 20:52:54 minidsp-shd volumio[684]: info: Jun 15 20:52:54 minidsp-shd volumio[684]: ---------------------------- MPD announces system playlist update Jun 15 20:52:54 minidsp-shd volumio[684]: info: Ignoring MPD Status Update Jun 15 20:52:54 minidsp-shd volumio[684]: info: sendMpdCommand clear took 9 milliseconds Jun 15 20:52:54 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/225591868&soundQuality=LOSSLESS" Jun 15 20:52:54 minidsp-shd volumio[684]: info: Jun 15 20:52:54 minidsp-shd volumio[684]: ---------------------------- MPD announces system playlist update Jun 15 20:52:54 minidsp-shd volumio[684]: info: Ignoring MPD Status Update Jun 15 20:52:54 minidsp-shd volumio[684]: info: Jun 15 20:52:54 minidsp-shd volumio[684]: ---------------------------- MPD announces system playlist update Jun 15 20:52:54 minidsp-shd volumio[684]: info: Ignoring MPD Status Update Jun 15 20:52:54 minidsp-shd volumio[684]: error: updateQueue error: null Jun 15 20:52:54 minidsp-shd volumio[684]: info: Jun 15 20:52:54 minidsp-shd volumio[684]: ---------------------------- MPD announces system playlist update Jun 15 20:52:54 minidsp-shd volumio[684]: info: Ignoring MPD Status Update Jun 15 20:52:54 minidsp-shd volumio[684]: info: ------------------------------ 18ms Jun 15 20:52:54 minidsp-shd volumio[684]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/225591868&soundQuality=LOSSLESS" took 14 milliseconds Jun 15 20:52:54 minidsp-shd volumio[684]: info: ------------------------------ 14ms Jun 15 20:52:54 minidsp-shd volumio[684]: info: ------------------------------ 12ms Jun 15 20:52:54 minidsp-shd volumio[684]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 15 20:52:54 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand play Jun 15 20:52:54 minidsp-shd volumio[684]: info: Jun 15 20:52:54 minidsp-shd volumio[684]: ---------------------------- MPD announces system playlist update Jun 15 20:52:54 minidsp-shd volumio[684]: info: Ignoring MPD Status Update Jun 15 20:52:54 minidsp-shd volumio[684]: STREAMING PROXY: Handling url /?data=tidal://song/225591868&soundQuality=LOSSLESS Jun 15 20:52:54 minidsp-shd volumio[684]: info: Jun 15 20:52:54 minidsp-shd volumio[684]: ---------------------------- MPD announces system playlist update Jun 15 20:52:54 minidsp-shd volumio[684]: info: Ignoring MPD Status Update Jun 15 20:52:54 minidsp-shd volumio[684]: info: ------------------------------ 23ms Jun 15 20:52:54 minidsp-shd volumio[684]: info: sendMpdCommand play took 16 milliseconds Jun 15 20:52:54 minidsp-shd volumio[684]: info: ------------------------------ 15ms Jun 15 20:52:54 minidsp-shd volumio[684]: info: ------------------------------ 12ms Jun 15 20:52:54 minidsp-shd volumio[684]: info: Executing endpoint getStreamUrltidal Jun 15 20:52:54 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 15 20:52:55 minidsp-shd volumio[684]: info: getStreamUrl took 534 milliseconds Jun 15 20:52:55 minidsp-shd volumio[684]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidjNmQ2NzdjNzAxYzc4MDY1ZGI1NzlkZTJlMjMyNWE2Zl82MS5tcDQ/0.flac?token=1749995574~ZTQxMTAwNzRlYjcxZGU1YTFlMDZjMDRiYmE0NWViNGFiZjIzMDI5Mw== Jun 15 20:52:55 minidsp-shd volumio[684]: STREAMING PROXY: Response: 200, length: 13873672 Jun 15 20:52:55 minidsp-shd volumio[684]: info: Jun 15 20:52:55 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:52:55 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:52:55 minidsp-shd volumio[684]: info: Jun 15 20:52:55 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:52:55 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:52:55 minidsp-shd volumio[684]: info: Jun 15 20:52:55 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:52:55 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:52:55 minidsp-shd volumio[684]: info: sendMpdCommand status took 12 milliseconds Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:52:55 minidsp-shd volumio[684]: info: Jun 15 20:52:55 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:52:55 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:52:55 minidsp-shd volumio[684]: info: Jun 15 20:52:55 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:52:55 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:52:55 minidsp-shd volumio[684]: info: sendMpdCommand status took 23 milliseconds Jun 15 20:52:55 minidsp-shd volumio[684]: info: sendMpdCommand status took 18 milliseconds Jun 15 20:52:55 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 15 20:52:55 minidsp-shd volumio[684]: info: sendMpdCommand status took 9 milliseconds Jun 15 20:52:55 minidsp-shd volumio[684]: info: sendMpdCommand status took 6 milliseconds Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:52:55 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 1 Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"225591868&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/225591868&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: CURRENT POSITION 1 Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus stop Jun 15 20:52:55 minidsp-shd volumio[684]: info: ------------------------------ 56ms Jun 15 20:52:55 minidsp-shd volumio[684]: info: Jun 15 20:52:55 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:52:55 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:52:55 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 21 milliseconds Jun 15 20:52:55 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 21 milliseconds Jun 15 20:52:55 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 20 milliseconds Jun 15 20:52:55 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 19 milliseconds Jun 15 20:52:55 minidsp-shd volumio[684]: info: sendMpdCommand status took 8 milliseconds Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:52:55 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 1 Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"225591868&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/225591868&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: CURRENT POSITION 1 Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus play Jun 15 20:52:55 minidsp-shd volumio[684]: info: Received an update from plugin. extracting info from payload Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:55 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:55 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:55 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 1 Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"263 Kbps","isStreaming":false,"title":"225591868&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/225591868&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: CURRENT POSITION 1 Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus play Jun 15 20:52:55 minidsp-shd volumio[684]: info: Received an update from plugin. extracting info from payload Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:55 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:55 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:55 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 1 Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"422 Kbps","isStreaming":false,"title":"225591868&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/225591868&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: CURRENT POSITION 1 Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus play Jun 15 20:52:55 minidsp-shd volumio[684]: info: Received an update from plugin. extracting info from payload Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:55 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:55 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:55 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 1 Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"422 Kbps","isStreaming":false,"title":"225591868&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/225591868&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: CURRENT POSITION 1 Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus play Jun 15 20:52:55 minidsp-shd volumio[684]: info: Received an update from plugin. extracting info from payload Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:55 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:55 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:55 minidsp-shd volumio[684]: info: ------------------------------ 217ms Jun 15 20:52:55 minidsp-shd volumio[684]: info: ------------------------------ 214ms Jun 15 20:52:55 minidsp-shd volumio[684]: info: ------------------------------ 205ms Jun 15 20:52:55 minidsp-shd volumio[684]: info: ------------------------------ 203ms Jun 15 20:52:55 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 157 milliseconds Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:52:55 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 1 Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"419 Kbps","isStreaming":false,"title":"225591868&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/225591868&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:52:55 minidsp-shd volumio[684]: verbose: CURRENT POSITION 1 Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus play Jun 15 20:52:55 minidsp-shd volumio[684]: info: Received an update from plugin. extracting info from payload Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:55 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:55 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:55 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:55 minidsp-shd volumio[684]: info: ------------------------------ 207ms Jun 15 20:52:56 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 20:52:56 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 20:52:56 minidsp-shd volumio[684]: info: Discovery: Getting this device information Jun 15 20:52:56 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioGetState Jun 15 20:52:56 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioSeek Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreStateMachine::seek Jun 15 20:52:58 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 1 Jun 15 20:52:58 minidsp-shd volumio[684]: info: TRACKBLOCK {"album":"又见邓丽君Ⅱ","albumUri":"tidal://album/225591856","albumart":"https://resources.tidal.com/images/ddb8dab1/b1d4/4e4e/b36b/333d6b154ef5/640x640.jpg","artist":"陈佳","artistUri":"tidal://artist/9836252","audioQuality":"LOSSLESS","bitdepth":"16 bit","duration":218,"explicit":false,"name":"南海姑娘","samplerate":"44.1 KHz","service":"tidal","title":"南海姑娘","trackType":"tidal","tracknumber":12,"type":"track","uri":"tidal://song/225591868","volumeNumber":1} Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreStateMachine::startPlaybackTimer Jun 15 20:52:58 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 1 Jun 15 20:52:58 minidsp-shd volumio[684]: info: [1749991978105] ControllerTidal::seek Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 15 20:52:58 minidsp-shd volumio[684]: info: ControllerMpd::seek Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:58 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:58 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:58 minidsp-shd volumio[684]: error: null Jun 15 20:52:58 minidsp-shd volumio[684]: info: Jun 15 20:52:58 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:52:58 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:52:58 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:52:58 minidsp-shd volumio[684]: info: Jun 15 20:52:58 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:52:58 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:52:58 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:52:58 minidsp-shd volumio[684]: info: Jun 15 20:52:58 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:52:58 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:52:58 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:52:58 minidsp-shd volumio[684]: info: sendMpdCommand status took 33 milliseconds Jun 15 20:52:58 minidsp-shd volumio[684]: info: sendMpdCommand status took 16 milliseconds Jun 15 20:52:58 minidsp-shd volumio[684]: info: sendMpdCommand status took 10 milliseconds Jun 15 20:52:58 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:52:58 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:52:58 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:52:58 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:52:58 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:52:58 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:52:58 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 22 milliseconds Jun 15 20:52:58 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 21 milliseconds Jun 15 20:52:58 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 20 milliseconds Jun 15 20:52:58 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:52:58 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:52:58 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:52:58 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:52:58 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 1 Jun 15 20:52:58 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":51052,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"516 Kbps","isStreaming":false,"title":"225591868&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/225591868&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:52:58 minidsp-shd volumio[684]: verbose: CURRENT POSITION 1 Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus play Jun 15 20:52:58 minidsp-shd volumio[684]: info: Received an update from plugin. extracting info from payload Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:58 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:58 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:58 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:58 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:58 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:52:58 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 1 Jun 15 20:52:58 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":51098,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"547 Kbps","isStreaming":false,"title":"225591868&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/225591868&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:52:58 minidsp-shd volumio[684]: verbose: CURRENT POSITION 1 Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus play Jun 15 20:52:58 minidsp-shd volumio[684]: info: Received an update from plugin. extracting info from payload Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:58 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:58 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:58 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:58 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:58 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:52:58 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 1 Jun 15 20:52:58 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":51098,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"452 Kbps","isStreaming":false,"title":"225591868&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/225591868&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:52:58 minidsp-shd volumio[684]: verbose: CURRENT POSITION 1 Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus play Jun 15 20:52:58 minidsp-shd volumio[684]: info: Received an update from plugin. extracting info from payload Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:58 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:58 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:52:58 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:52:58 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:52:58 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:52:58 minidsp-shd volumio[684]: info: ------------------------------ 191ms Jun 15 20:52:58 minidsp-shd volumio[684]: info: ------------------------------ 175ms Jun 15 20:52:58 minidsp-shd volumio[684]: info: ------------------------------ 172ms Jun 15 20:53:00 minidsp-shd volumio[684]: info: Preload queue cleared Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreStateMachine::ClearQueue Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreStateMachine::stop Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreStateMachine::stPlaybackTimer Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreStateMachine::updateTrackBlock Jun 15 20:53:00 minidsp-shd volumio[684]: info: CorePlayQueue::getTrackBlock Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:53:00 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 1 Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:53:00 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:53:00 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreStateMachine::serviceStop Jun 15 20:53:00 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 1 Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreCommandRouter::serviceStop Jun 15 20:53:00 minidsp-shd volumio[684]: info: [1749991980680] ControllerTidal::stop Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 15 20:53:00 minidsp-shd volumio[684]: info: ControllerMpd::stop Jun 15 20:53:00 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand stop Jun 15 20:53:00 minidsp-shd volumio[684]: info: CorePlayQueue::clearPlayQueue Jun 15 20:53:00 minidsp-shd volumio[684]: info: CorePlayQueue::saveQueue Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushQueue Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreStateMachine::addQueueItems Jun 15 20:53:00 minidsp-shd volumio[684]: info: CorePlayQueue::addQueueItems Jun 15 20:53:00 minidsp-shd volumio[684]: info: Preload queue cleared Jun 15 20:53:00 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/3607410 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/3607410 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/225591868 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/225591868 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/92525318 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/92525318 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/105498216 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/105498216 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/191640932 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/191640932 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/16431724 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/16431724 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/16305078 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/16305078 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/7015309 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/7015309 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/68522394 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/68522394 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/335193827 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/335193827 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/263528414 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/263528414 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/298889401 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/298889401 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/13041479 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/13041479 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/410643468 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/410643468 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/21024295 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/21024295 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/411281196 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/411281196 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/3637912 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/3637912 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/52587274 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/52587274 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/59715451 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/59715451 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Adding Item to queue: tidal://song/130206620 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Using cached record of: tidal://song/130206620 Jun 15 20:53:00 minidsp-shd volumio[684]: STREAMING PROXY: Client dropped request, destroying Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushQueue Jun 15 20:53:00 minidsp-shd volumio[684]: info: CorePlayQueue::saveQueue Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreStateMachine::updateTrackBlock Jun 15 20:53:00 minidsp-shd volumio[684]: info: CorePlayQueue::getTrackBlock Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPlay Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreStateMachine::play index 4 Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreStateMachine::stop Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreStateMachine::play index undefined Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 20:53:00 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 4 Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreStateMachine::startPlaybackTimer Jun 15 20:53:00 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 4 Jun 15 20:53:00 minidsp-shd volumio[684]: info: [1749991980756] ControllerTidal::clearAddPlayTrack Jun 15 20:53:00 minidsp-shd volumio[684]: info: Getting stream with soundQuality LOSSLESS Jun 15 20:53:00 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand stop Jun 15 20:53:00 minidsp-shd volumio[684]: info: Jun 15 20:53:00 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:53:00 minidsp-shd volumio[684]: info: sendMpdCommand stop took 97 milliseconds Jun 15 20:53:00 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:53:00 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:53:00 minidsp-shd volumio[684]: info: sendMpdCommand stop took 23 milliseconds Jun 15 20:53:00 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand clear Jun 15 20:53:00 minidsp-shd volumio[684]: info: Jun 15 20:53:00 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:53:00 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:53:00 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:53:00 minidsp-shd volumio[684]: info: Jun 15 20:53:00 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:53:00 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:53:00 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:53:00 minidsp-shd volumio[684]: info: Jun 15 20:53:00 minidsp-shd volumio[684]: ---------------------------- MPD announces system playlist update Jun 15 20:53:00 minidsp-shd volumio[684]: info: Ignoring MPD Status Update Jun 15 20:53:00 minidsp-shd volumio[684]: info: sendMpdCommand status took 30 milliseconds Jun 15 20:53:00 minidsp-shd volumio[684]: info: sendMpdCommand clear took 30 milliseconds Jun 15 20:53:00 minidsp-shd volumio[684]: info: sendMpdCommand status took 26 milliseconds Jun 15 20:53:00 minidsp-shd volumio[684]: info: sendMpdCommand status took 23 milliseconds Jun 15 20:53:00 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:53:00 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:53:00 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/191640932&soundQuality=LOSSLESS" Jun 15 20:53:00 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:53:00 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:53:00 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:53:00 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 4 Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:53:00 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:53:00 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:53:00 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 4 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jun 15 20:53:00 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:53:00 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 4 Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:53:00 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:53:00 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:53:00 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 4 Jun 15 20:53:00 minidsp-shd volumio[684]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jun 15 20:53:00 minidsp-shd volumio[684]: info: ------------------------------ 62ms Jun 15 20:53:00 minidsp-shd volumio[684]: info: ------------------------------ 60ms Jun 15 20:53:00 minidsp-shd volumio[684]: info: Jun 15 20:53:00 minidsp-shd volumio[684]: ---------------------------- MPD announces system playlist update Jun 15 20:53:00 minidsp-shd volumio[684]: info: Ignoring MPD Status Update Jun 15 20:53:00 minidsp-shd volumio[684]: info: Jun 15 20:53:00 minidsp-shd volumio[684]: ---------------------------- MPD announces system playlist update Jun 15 20:53:00 minidsp-shd volumio[684]: info: Ignoring MPD Status Update Jun 15 20:53:00 minidsp-shd volumio[684]: error: updateQueue error: null Jun 15 20:53:00 minidsp-shd volumio[684]: info: Jun 15 20:53:00 minidsp-shd volumio[684]: ---------------------------- MPD announces system playlist update Jun 15 20:53:00 minidsp-shd volumio[684]: info: Ignoring MPD Status Update Jun 15 20:53:00 minidsp-shd volumio[684]: info: ------------------------------ 55ms Jun 15 20:53:00 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 48 milliseconds Jun 15 20:53:00 minidsp-shd volumio[684]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/191640932&soundQuality=LOSSLESS" took 48 milliseconds Jun 15 20:53:00 minidsp-shd volumio[684]: info: ------------------------------ 16ms Jun 15 20:53:00 minidsp-shd volumio[684]: info: ------------------------------ 14ms Jun 15 20:53:00 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 15 20:53:00 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand play Jun 15 20:53:00 minidsp-shd volumio[684]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 15 20:53:00 minidsp-shd volumio[684]: info: ------------------------------ 94ms Jun 15 20:53:00 minidsp-shd volumio[684]: info: Jun 15 20:53:00 minidsp-shd volumio[684]: ---------------------------- MPD announces system playlist update Jun 15 20:53:00 minidsp-shd volumio[684]: info: Ignoring MPD Status Update Jun 15 20:53:00 minidsp-shd volumio[684]: STREAMING PROXY: Handling url /?data=tidal://song/191640932&soundQuality=LOSSLESS Jun 15 20:53:00 minidsp-shd volumio[684]: info: Jun 15 20:53:00 minidsp-shd volumio[684]: ---------------------------- MPD announces system playlist update Jun 15 20:53:00 minidsp-shd volumio[684]: info: Ignoring MPD Status Update Jun 15 20:53:00 minidsp-shd volumio[684]: info: ------------------------------ 47ms Jun 15 20:53:00 minidsp-shd volumio[684]: info: sendMpdCommand play took 39 milliseconds Jun 15 20:53:00 minidsp-shd volumio[684]: info: ------------------------------ 36ms Jun 15 20:53:00 minidsp-shd volumio[684]: info: ------------------------------ 34ms Jun 15 20:53:00 minidsp-shd volumio[684]: info: Executing endpoint getStreamUrltidal Jun 15 20:53:00 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 15 20:53:01 minidsp-shd volumio[684]: info: getStreamUrl took 997 milliseconds Jun 15 20:53:01 minidsp-shd volumio[684]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEic2ZjY2N2IxNDQxOGRiNTA5YzhjZGFlYWIwZTA4OGFmMV82MS5tcDQ/0.flac?token=1749995581~NThjMTdkOWEzN2Q1MTU2N2MxZjNlNzE1NjgxODYwNDRlYjY4YTgyNA== Jun 15 20:53:02 minidsp-shd volumio[684]: STREAMING PROXY: Response: 200, length: 18011418 Jun 15 20:53:02 minidsp-shd volumio[684]: info: Jun 15 20:53:02 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:53:02 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:53:02 minidsp-shd volumio[684]: info: Jun 15 20:53:02 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:53:02 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:53:02 minidsp-shd volumio[684]: info: Jun 15 20:53:02 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:53:02 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:53:02 minidsp-shd volumio[684]: info: Jun 15 20:53:02 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:53:02 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:53:02 minidsp-shd volumio[684]: info: Jun 15 20:53:02 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:53:02 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:53:02 minidsp-shd volumio[684]: info: Jun 15 20:53:02 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:53:02 minidsp-shd volumio[684]: info: sendMpdCommand status took 22 milliseconds Jun 15 20:53:02 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:53:02 minidsp-shd volumio[684]: info: sendMpdCommand status took 18 milliseconds Jun 15 20:53:02 minidsp-shd volumio[684]: info: sendMpdCommand status took 15 milliseconds Jun 15 20:53:02 minidsp-shd volumio[684]: info: sendMpdCommand status took 12 milliseconds Jun 15 20:53:02 minidsp-shd volumio[684]: info: sendMpdCommand status took 9 milliseconds Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:53:02 minidsp-shd volumio[684]: info: sendMpdCommand status took 17 milliseconds Jun 15 20:53:02 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 15 20:53:02 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 15 20:53:02 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 15 20:53:02 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 15 20:53:02 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:53:02 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 4 Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"191640932&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/191640932&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: CURRENT POSITION 4 Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus stop Jun 15 20:53:02 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 4 Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"191640932&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/191640932&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: CURRENT POSITION 4 Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus play Jun 15 20:53:02 minidsp-shd volumio[684]: info: Received an update from plugin. extracting info from payload Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:53:02 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:53:02 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:53:02 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 4 Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"191640932&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/191640932&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: CURRENT POSITION 4 Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus play Jun 15 20:53:02 minidsp-shd volumio[684]: info: Received an update from plugin. extracting info from payload Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:53:02 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:53:02 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:53:02 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 4 Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"191640932&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/191640932&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: CURRENT POSITION 4 Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus play Jun 15 20:53:02 minidsp-shd volumio[684]: info: Received an update from plugin. extracting info from payload Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:53:02 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:53:02 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:53:02 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 4 Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"191640932&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/191640932&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: CURRENT POSITION 4 Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus play Jun 15 20:53:02 minidsp-shd volumio[684]: info: Received an update from plugin. extracting info from payload Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:53:02 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:53:02 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:53:02 minidsp-shd volumio[684]: info: ------------------------------ 156ms Jun 15 20:53:02 minidsp-shd volumio[684]: info: ------------------------------ 180ms Jun 15 20:53:02 minidsp-shd volumio[684]: info: ------------------------------ 179ms Jun 15 20:53:02 minidsp-shd volumio[684]: info: ------------------------------ 178ms Jun 15 20:53:02 minidsp-shd volumio[684]: info: ------------------------------ 176ms Jun 15 20:53:02 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 147 milliseconds Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:53:02 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 4 Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"191640932&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/191640932&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:53:02 minidsp-shd volumio[684]: verbose: CURRENT POSITION 4 Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus play Jun 15 20:53:02 minidsp-shd volumio[684]: info: Received an update from plugin. extracting info from payload Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:53:02 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:53:02 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:53:02 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:53:02 minidsp-shd volumio[684]: info: ------------------------------ 209ms Jun 15 20:53:04 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioSeek Jun 15 20:53:04 minidsp-shd volumio[684]: info: CoreStateMachine::seek Jun 15 20:53:04 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 4 Jun 15 20:53:04 minidsp-shd volumio[684]: info: TRACKBLOCK {"album":"十亿掌声5","albumUri":"tidal://album/191640922","albumart":"https://resources.tidal.com/images/941e2205/3dc0/4569/be30/92a3b134cd0e/640x640.jpg","artist":"徐雯","artistUri":"tidal://artist/18630189","audioQuality":"LOSSLESS","bitdepth":"16 bit","duration":200,"explicit":false,"name":"南海姑娘","samplerate":"44.1 KHz","service":"tidal","title":"南海姑娘","trackType":"tidal","tracknumber":1,"type":"track","uri":"tidal://song/191640932","volumeNumber":1} Jun 15 20:53:04 minidsp-shd volumio[684]: info: CoreStateMachine::startPlaybackTimer Jun 15 20:53:04 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 4 Jun 15 20:53:04 minidsp-shd volumio[684]: info: [1749991984012] ControllerTidal::seek Jun 15 20:53:04 minidsp-shd volumio[684]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 15 20:53:04 minidsp-shd volumio[684]: info: ControllerMpd::seek Jun 15 20:53:04 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:53:04 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 20:53:04 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:53:04 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:53:04 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:53:04 minidsp-shd volumio[684]: STREAMING PROXY: Client dropped request, destroying Jun 15 20:53:04 minidsp-shd volumio[684]: STREAMING PROXY: Handling url /?data=tidal://song/191640932&soundQuality=LOSSLESS Jun 15 20:53:04 minidsp-shd volumio[684]: info: Executing endpoint getStreamUrltidal Jun 15 20:53:04 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 15 20:53:04 minidsp-shd volumio[684]: info: getStreamUrl took 483 milliseconds Jun 15 20:53:04 minidsp-shd volumio[684]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEic2ZjY2N2IxNDQxOGRiNTA5YzhjZGFlYWIwZTA4OGFmMV82MS5tcDQ/0.flac?token=1749995581~NThjMTdkOWEzN2Q1MTU2N2MxZjNlNzE1NjgxODYwNDRlYjY4YTgyNA== Jun 15 20:53:04 minidsp-shd volumio[684]: STREAMING PROXY: Got range request: bytes=3597575- Jun 15 20:53:04 minidsp-shd volumio[684]: STREAMING PROXY: Response: 206, length: 14413843 Jun 15 20:53:04 minidsp-shd volumio[684]: STREAMING PROXY: Client dropped request, destroying Jun 15 20:53:04 minidsp-shd volumio[684]: STREAMING PROXY: Handling url /?data=tidal://song/191640932&soundQuality=LOSSLESS Jun 15 20:53:04 minidsp-shd volumio[684]: info: Executing endpoint getStreamUrltidal Jun 15 20:53:04 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 15 20:53:05 minidsp-shd volumio[684]: info: getStreamUrl took 507 milliseconds Jun 15 20:53:05 minidsp-shd volumio[684]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEic2ZjY2N2IxNDQxOGRiNTA5YzhjZGFlYWIwZTA4OGFmMV82MS5tcDQ/0.flac?token=1749995581~NThjMTdkOWEzN2Q1MTU2N2MxZjNlNzE1NjgxODYwNDRlYjY4YTgyNA== Jun 15 20:53:05 minidsp-shd volumio[684]: STREAMING PROXY: Got range request: bytes=3636904- Jun 15 20:53:05 minidsp-shd volumio[684]: STREAMING PROXY: Response: 206, length: 14374514 Jun 15 20:53:05 minidsp-shd volumio[684]: error: null Jun 15 20:53:05 minidsp-shd volumio[684]: info: Jun 15 20:53:05 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:53:05 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:53:05 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:53:05 minidsp-shd volumio[684]: info: Jun 15 20:53:05 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:53:05 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:53:05 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:53:05 minidsp-shd volumio[684]: info: Jun 15 20:53:05 minidsp-shd volumio[684]: ---------------------------- MPD announces state update: player Jun 15 20:53:05 minidsp-shd volumio[684]: info: ControllerMpd::getState Jun 15 20:53:05 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand status Jun 15 20:53:05 minidsp-shd volumio[684]: info: sendMpdCommand status took 14 milliseconds Jun 15 20:53:05 minidsp-shd volumio[684]: info: sendMpdCommand status took 10 milliseconds Jun 15 20:53:05 minidsp-shd volumio[684]: info: sendMpdCommand status took 7 milliseconds Jun 15 20:53:05 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:53:05 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:53:05 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:53:05 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:53:05 minidsp-shd volumio[684]: verbose: ControllerMpd::parseState Jun 15 20:53:05 minidsp-shd volumio[684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 20:53:05 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 15 20:53:05 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 15 20:53:05 minidsp-shd volumio[684]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 15 20:53:05 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:53:05 minidsp-shd volumio[684]: verbose: ControllerMpd::parseTrackInfo Jun 15 20:53:05 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:53:05 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:53:05 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 4 Jun 15 20:53:05 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":44000,"duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"502 Kbps","isStreaming":false,"title":"191640932&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/191640932&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:53:05 minidsp-shd volumio[684]: verbose: CURRENT POSITION 4 Jun 15 20:53:05 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:53:05 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus play Jun 15 20:53:05 minidsp-shd volumio[684]: info: Received an update from plugin. extracting info from payload Jun 15 20:53:05 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:53:05 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 20:53:05 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:53:05 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:53:05 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:53:05 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:53:05 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:53:05 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:53:05 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:53:05 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:53:05 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:53:05 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 4 Jun 15 20:53:05 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":44000,"duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"502 Kbps","isStreaming":false,"title":"191640932&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/191640932&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:53:05 minidsp-shd volumio[684]: verbose: CURRENT POSITION 4 Jun 15 20:53:05 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:53:05 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus play Jun 15 20:53:05 minidsp-shd volumio[684]: info: Received an update from plugin. extracting info from payload Jun 15 20:53:05 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:53:05 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:53:05 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:53:05 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:53:05 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:53:05 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:53:05 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:53:05 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:53:05 minidsp-shd volumio[684]: info: ControllerMpd::pushState Jun 15 20:53:05 minidsp-shd volumio[684]: info: CoreCommandRouter::servicePushState Jun 15 20:53:05 minidsp-shd volumio[684]: info: CorePlayQueue::getTrack 4 Jun 15 20:53:05 minidsp-shd volumio[684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":44000,"duration":200,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"502 Kbps","isStreaming":false,"title":"191640932&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/191640932&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 20:53:05 minidsp-shd volumio[684]: verbose: CURRENT POSITION 4 Jun 15 20:53:05 minidsp-shd volumio[684]: info: CoreStateMachine::syncState stateService play Jun 15 20:53:05 minidsp-shd volumio[684]: info: CoreStateMachine::syncState currentStatus play Jun 15 20:53:05 minidsp-shd volumio[684]: info: Received an update from plugin. extracting info from payload Jun 15 20:53:05 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:53:05 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:53:05 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:53:05 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:53:05 minidsp-shd volumio[684]: info: CoreStateMachine::pushState Jun 15 20:53:05 minidsp-shd volumio[684]: info: CoreCommandRouter::volumioPushState Jun 15 20:53:05 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output update for this device Jun 15 20:53:05 minidsp-shd volumio[684]: info: MRS: Pushing multiroomSync output Jun 15 20:53:05 minidsp-shd volumio[684]: info: ------------------------------ 146ms Jun 15 20:53:05 minidsp-shd volumio[684]: info: ------------------------------ 143ms Jun 15 20:53:05 minidsp-shd volumio[684]: info: ------------------------------ 141ms Jun 15 20:53:06 minidsp-shd volumio[684]: STREAMING PROXY: Client dropped request, destroying Jun 15 20:53:06 minidsp-shd volumio[684]: STREAMING PROXY: Handling url /?data=tidal://song/191640932&soundQuality=LOSSLESS Jun 15 20:53:06 minidsp-shd volumio[684]: info: Executing endpoint getStreamUrltidal Jun 15 20:53:06 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 15 20:53:07 minidsp-shd volumio[684]: info: getStreamUrl took 511 milliseconds Jun 15 20:53:07 minidsp-shd volumio[684]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEic2ZjY2N2IxNDQxOGRiNTA5YzhjZGFlYWIwZTA4OGFmMV82MS5tcDQ/0.flac?token=1749995581~NThjMTdkOWEzN2Q1MTU2N2MxZjNlNzE1NjgxODYwNDRlYjY4YTgyNA== Jun 15 20:53:07 minidsp-shd volumio[684]: STREAMING PROXY: Got range request: bytes=653263- Jun 15 20:53:07 minidsp-shd volumio[684]: STREAMING PROXY: Response: 206, length: 17358155 Jun 15 20:53:08 minidsp-shd volumio[684]: STREAMING PROXY: Client dropped request, destroying Jun 15 20:53:08 minidsp-shd volumio[684]: STREAMING PROXY: Handling url /?data=tidal://song/191640932&soundQuality=LOSSLESS Jun 15 20:53:08 minidsp-shd volumio[684]: info: Executing endpoint getStreamUrltidal Jun 15 20:53:08 minidsp-shd volumio[684]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 15 20:53:08 minidsp-shd volumio[684]: info: getStreamUrl took 541 milliseconds Jun 15 20:53:08 minidsp-shd volumio[684]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEic2ZjY2N2IxNDQxOGRiNTA5YzhjZGFlYWIwZTA4OGFmMV82MS5tcDQ/0.flac?token=1749995581~NThjMTdkOWEzN2Q1MTU2N2MxZjNlNzE1NjgxODYwNDRlYjY4YTgyNA== Jun 15 20:53:08 minidsp-shd volumio[684]: STREAMING PROXY: Got range request: bytes=3613339- Jun 15 20:53:08 minidsp-shd volumio[684]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 20:53:08 minidsp-shd volumio[684]: Error: connect ETIMEDOUT 162.125.2.6:443 Jun 15 20:53:08 minidsp-shd volumio[684]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 15 20:53:08 minidsp-shd volumio[684]: errno: -110, Jun 15 20:53:08 minidsp-shd volumio[684]: code: 'ETIMEDOUT', Jun 15 20:53:08 minidsp-shd volumio[684]: syscall: 'connect', Jun 15 20:53:08 minidsp-shd volumio[684]: address: '162.125.2.6', Jun 15 20:53:08 minidsp-shd volumio[684]: port: 443 Jun 15 20:53:08 minidsp-shd volumio[684]: } Jun 15 20:53:08 minidsp-shd volumio[684]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 20:53:09 minidsp-shd volumio[684]: STREAMING PROXY: Response: 206, length: 14398079 Jun 15 20:53:09 minidsp-shd volumio[684]: STREAMING PROXY: Client dropped request, destroying Jun 15 20:53:09 minidsp-shd sudo[2282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-15 20:52 Jun 15 20:53:09 minidsp-shd sudo[2282]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Mar 2024 03:13:25 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="18533ba33f8f92d7e7e459bb30f70beb"