-- Logs begin at Thu 2019-02-14 18:11:59 +08, end at Sun 2025-06-08 12:02:12 +08. -- Jun 08 12:01:05 minidsp-shd volumio[2866]: Searching plugin music_service/tidal Jun 08 12:01:05 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 08 12:01:06 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:06 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:06 minidsp-shd volumio[2866]: info: searchTIDALUri took 684 milliseconds Jun 08 12:01:06 minidsp-shd volumio[2866]: info: search took 687 milliseconds Jun 08 12:01:06 minidsp-shd volumio[2866]: info: All search sources collected, pushing search results Jun 08 12:01:06 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:06 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:07 minidsp-shd volumio[2866]: info: MyVolumio login type: Token Jun 08 12:01:12 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:12 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:13 minidsp-shd volumio[2866]: Searching plugin music_service/tidal Jun 08 12:01:13 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 08 12:01:14 minidsp-shd volumio[2866]: info: searchTIDALUri took 897 milliseconds Jun 08 12:01:14 minidsp-shd volumio[2866]: info: search took 899 milliseconds Jun 08 12:01:14 minidsp-shd volumio[2866]: info: All search sources collected, pushing search results Jun 08 12:01:16 minidsp-shd volumio[2866]: Searching plugin music_service/tidal Jun 08 12:01:16 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 08 12:01:17 minidsp-shd volumio[2866]: info: searchTIDALUri took 651 milliseconds Jun 08 12:01:17 minidsp-shd volumio[2866]: info: search took 653 milliseconds Jun 08 12:01:17 minidsp-shd volumio[2866]: info: All search sources collected, pushing search results Jun 08 12:01:17 minidsp-shd volumio[2866]: Searching plugin music_service/tidal Jun 08 12:01:17 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 08 12:01:18 minidsp-shd volumio[2866]: info: searchTIDALUri took 398 milliseconds Jun 08 12:01:18 minidsp-shd volumio[2866]: info: search took 400 milliseconds Jun 08 12:01:18 minidsp-shd volumio[2866]: info: All search sources collected, pushing search results Jun 08 12:01:19 minidsp-shd volumio[2866]: Searching plugin music_service/tidal Jun 08 12:01:19 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 08 12:01:20 minidsp-shd volumio[2866]: info: searchTIDALUri took 661 milliseconds Jun 08 12:01:20 minidsp-shd volumio[2866]: info: search took 663 milliseconds Jun 08 12:01:20 minidsp-shd volumio[2866]: info: All search sources collected, pushing search results Jun 08 12:01:22 minidsp-shd volumio[2866]: Searching plugin music_service/tidal Jun 08 12:01:22 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 08 12:01:23 minidsp-shd volumio[2866]: info: searchTIDALUri took 553 milliseconds Jun 08 12:01:23 minidsp-shd volumio[2866]: info: search took 556 milliseconds Jun 08 12:01:23 minidsp-shd volumio[2866]: info: All search sources collected, pushing search results Jun 08 12:01:30 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jun 08 12:01:30 minidsp-shd volumio[2866]: info: browseTIDALUri took 809 milliseconds Jun 08 12:01:30 minidsp-shd volumio[2866]: info: Preload queue cleared Jun 08 12:01:30 minidsp-shd volumio[2866]: info: Preloading song: tidal://song/10614313 Jun 08 12:01:30 minidsp-shd volumio[2866]: info: Preloading song: tidal://song/10614314 Jun 08 12:01:30 minidsp-shd volumio[2866]: info: Preloading song: tidal://song/10614315 Jun 08 12:01:30 minidsp-shd volumio[2866]: info: Preloading song: tidal://song/10614316 Jun 08 12:01:30 minidsp-shd volumio[2866]: info: Preloading song: tidal://song/10614317 Jun 08 12:01:30 minidsp-shd volumio[2866]: info: Preloading song: tidal://song/10614318 Jun 08 12:01:30 minidsp-shd volumio[2866]: info: Preloading song: tidal://song/10614319 Jun 08 12:01:30 minidsp-shd volumio[2866]: info: Exploding uri tidal://song/10614313 in service tidal Jun 08 12:01:30 minidsp-shd volumio[2866]: info: Exploding uri tidal://song/10614314 in service tidal Jun 08 12:01:31 minidsp-shd volumio[2866]: info: Exploding uri tidal://song/10614315 in service tidal Jun 08 12:01:31 minidsp-shd volumio[2866]: info: Executing endpoint getSimilarAlbums Jun 08 12:01:31 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 08 12:01:31 minidsp-shd volumio[2866]: info: Exploding uri tidal://song/10614316 in service tidal Jun 08 12:01:31 minidsp-shd volumio[2866]: info: Executing endpoint getSimilarAlbums Jun 08 12:01:31 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 08 12:01:31 minidsp-shd volumio[2866]: info: Exploding uri tidal://song/10614317 in service tidal Jun 08 12:01:31 minidsp-shd volumio[2866]: info: Exploding uri tidal://song/10614318 in service tidal Jun 08 12:01:31 minidsp-shd volumio[2866]: info: Exploding uri tidal://song/10614319 in service tidal Jun 08 12:01:31 minidsp-shd volumio[2866]: info: explodeTIDALUri took 563 milliseconds Jun 08 12:01:31 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:31 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:31 minidsp-shd volumio[2866]: info: explodeTIDALUri took 615 milliseconds Jun 08 12:01:31 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:31 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:31 minidsp-shd volumio[2866]: info: explodeTIDALUri took 687 milliseconds Jun 08 12:01:31 minidsp-shd volumio[2866]: info: explodeTIDALUri took 616 milliseconds Jun 08 12:01:31 minidsp-shd volumio[2866]: info: explodeTIDALUri took 608 milliseconds Jun 08 12:01:31 minidsp-shd volumio[2866]: info: explodeTIDALUri took 637 milliseconds Jun 08 12:01:31 minidsp-shd volumio[2866]: info: explodeTIDALUri took 625 milliseconds Jun 08 12:01:31 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:31 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Preload queue cleared Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CoreStateMachine::ClearQueue Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CoreStateMachine::stop Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CorePlayQueue::clearPlayQueue Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CorePlayQueue::saveQueue Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushQueue Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CoreStateMachine::addQueueItems Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CorePlayQueue::addQueueItems Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Preload queue cleared Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Adding Item to queue: tidal://song/10614313 Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Using cached record of: tidal://song/10614313 Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Adding Item to queue: tidal://song/10614314 Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Using cached record of: tidal://song/10614314 Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Adding Item to queue: tidal://song/10614315 Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Using cached record of: tidal://song/10614315 Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Adding Item to queue: tidal://song/10614316 Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Using cached record of: tidal://song/10614316 Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Adding Item to queue: tidal://song/10614317 Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Using cached record of: tidal://song/10614317 Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Adding Item to queue: tidal://song/10614318 Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Using cached record of: tidal://song/10614318 Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Adding Item to queue: tidal://song/10614319 Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Using cached record of: tidal://song/10614319 Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushQueue Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CorePlayQueue::saveQueue Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CoreStateMachine::updateTrackBlock Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrackBlock Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPlay Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CoreStateMachine::play index 0 Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CoreStateMachine::stop Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CoreStateMachine::play index undefined Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrack 0 Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CoreStateMachine::startPlaybackTimer Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrack 0 Jun 08 12:01:33 minidsp-shd volumio[2866]: info: [1749355293104] ControllerTidal::clearAddPlayTrack Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Getting stream with soundQuality LOSSLESS Jun 08 12:01:33 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand stop Jun 08 12:01:33 minidsp-shd volumio[2866]: info: sendMpdCommand stop took 22 milliseconds Jun 08 12:01:33 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand clear Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Jun 08 12:01:33 minidsp-shd volumio[2866]: ---------------------------- MPD announces system playlist update Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Ignoring MPD Status Update Jun 08 12:01:33 minidsp-shd volumio[2866]: info: sendMpdCommand clear took 17 milliseconds Jun 08 12:01:33 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/10614313&soundQuality=LOSSLESS" Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Jun 08 12:01:33 minidsp-shd volumio[2866]: ---------------------------- MPD announces system playlist update Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Ignoring MPD Status Update Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Jun 08 12:01:33 minidsp-shd volumio[2866]: ---------------------------- MPD announces system playlist update Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Ignoring MPD Status Update Jun 08 12:01:33 minidsp-shd volumio[2866]: error: updateQueue error: null Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Jun 08 12:01:33 minidsp-shd volumio[2866]: ---------------------------- MPD announces system playlist update Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Ignoring MPD Status Update Jun 08 12:01:33 minidsp-shd volumio[2866]: info: ------------------------------ 28ms Jun 08 12:01:33 minidsp-shd volumio[2866]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/10614313&soundQuality=LOSSLESS" took 21 milliseconds Jun 08 12:01:33 minidsp-shd volumio[2866]: info: ------------------------------ 20ms Jun 08 12:01:33 minidsp-shd volumio[2866]: info: ------------------------------ 16ms Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 08 12:01:33 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand play Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Jun 08 12:01:33 minidsp-shd volumio[2866]: ---------------------------- MPD announces system playlist update Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Ignoring MPD Status Update Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Jun 08 12:01:33 minidsp-shd volumio[2866]: ---------------------------- MPD announces system playlist update Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Ignoring MPD Status Update Jun 08 12:01:33 minidsp-shd volumio[2866]: info: ------------------------------ 45ms Jun 08 12:01:33 minidsp-shd volumio[2866]: info: sendMpdCommand play took 35 milliseconds Jun 08 12:01:33 minidsp-shd volumio[2866]: info: ------------------------------ 34ms Jun 08 12:01:33 minidsp-shd volumio[2866]: info: ------------------------------ 28ms Jun 08 12:01:33 minidsp-shd volumio[2866]: STREAMING PROXY: Handling url /?data=tidal://song/10614313&soundQuality=LOSSLESS Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Executing endpoint getStreamUrltidal Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 08 12:01:33 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:33 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Executing endpoint getSimilarAlbums Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Executing endpoint getSimilarAlbums Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Executing endpoint getSimilarAlbums Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Executing endpoint getSimilarAlbums Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Executing endpoint getSimilarAlbums Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Executing endpoint getSimilarAlbums Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 08 12:01:33 minidsp-shd volumio[2866]: info: Executing endpoint metavolumio Jun 08 12:01:33 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 08 12:01:33 minidsp-shd volumio[2866]: info: getStreamUrl took 587 milliseconds Jun 08 12:01:33 minidsp-shd volumio[2866]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidjNGFiNzliM2JhN2MxY2QyOWQxNzYwZWE0ZjJiN2IzZl82MS5tcDQ/0.flac?token=1749358893~MTZkYjY3YWM4YjI3YjE0MTFlM2YxZjUyYjdmZjRiNWM3Y2ZhOWY2OQ== Jun 08 12:01:34 minidsp-shd volumio[2866]: STREAMING PROXY: Response: 200, length: 74123207 Jun 08 12:01:34 minidsp-shd volumio[2866]: info: Jun 08 12:01:34 minidsp-shd volumio[2866]: ---------------------------- MPD announces state update: player Jun 08 12:01:34 minidsp-shd volumio[2866]: info: ControllerMpd::getState Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand status Jun 08 12:01:34 minidsp-shd volumio[2866]: info: Jun 08 12:01:34 minidsp-shd volumio[2866]: ---------------------------- MPD announces state update: player Jun 08 12:01:34 minidsp-shd volumio[2866]: info: ControllerMpd::getState Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand status Jun 08 12:01:34 minidsp-shd volumio[2866]: info: Jun 08 12:01:34 minidsp-shd volumio[2866]: ---------------------------- MPD announces state update: player Jun 08 12:01:34 minidsp-shd volumio[2866]: info: ControllerMpd::getState Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand status Jun 08 12:01:34 minidsp-shd volumio[2866]: info: Jun 08 12:01:34 minidsp-shd volumio[2866]: ---------------------------- MPD announces state update: player Jun 08 12:01:34 minidsp-shd volumio[2866]: info: sendMpdCommand status took 46 milliseconds Jun 08 12:01:34 minidsp-shd volumio[2866]: info: sendMpdCommand status took 37 milliseconds Jun 08 12:01:34 minidsp-shd volumio[2866]: info: sendMpdCommand status took 35 milliseconds Jun 08 12:01:34 minidsp-shd volumio[2866]: info: ControllerMpd::getState Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand status Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseState Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseState Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseState Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 12:01:34 minidsp-shd volumio[2866]: info: Jun 08 12:01:34 minidsp-shd volumio[2866]: ---------------------------- MPD announces state update: player Jun 08 12:01:34 minidsp-shd volumio[2866]: info: ControllerMpd::getState Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand status Jun 08 12:01:34 minidsp-shd volumio[2866]: info: Jun 08 12:01:34 minidsp-shd volumio[2866]: ---------------------------- MPD announces state update: player Jun 08 12:01:34 minidsp-shd volumio[2866]: info: ControllerMpd::getState Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand status Jun 08 12:01:34 minidsp-shd volumio[2866]: info: sendMpdCommand status took 42 milliseconds Jun 08 12:01:34 minidsp-shd volumio[2866]: info: sendMpdCommand playlistinfo took 38 milliseconds Jun 08 12:01:34 minidsp-shd volumio[2866]: info: sendMpdCommand playlistinfo took 36 milliseconds Jun 08 12:01:34 minidsp-shd volumio[2866]: info: sendMpdCommand playlistinfo took 31 milliseconds Jun 08 12:01:34 minidsp-shd volumio[2866]: info: sendMpdCommand status took 15 milliseconds Jun 08 12:01:34 minidsp-shd volumio[2866]: info: sendMpdCommand status took 11 milliseconds Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseState Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseTrackInfo Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseTrackInfo Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseTrackInfo Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseState Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseState Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 12:01:34 minidsp-shd volumio[2866]: info: ControllerMpd::pushState Jun 08 12:01:34 minidsp-shd volumio[2866]: info: CoreCommandRouter::servicePushState Jun 08 12:01:34 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrack 0 Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":976,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"10614313&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/10614313&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: CURRENT POSITION 0 Jun 08 12:01:34 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState stateService play Jun 08 12:01:34 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState currentStatus stop Jun 08 12:01:34 minidsp-shd volumio[2866]: info: ControllerMpd::pushState Jun 08 12:01:34 minidsp-shd volumio[2866]: info: CoreCommandRouter::servicePushState Jun 08 12:01:34 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrack 0 Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: STATE SERVICE {"status":"play","position":0,"seek":457,"duration":976,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"384 Kbps","isStreaming":false,"title":"10614313&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/10614313&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: CURRENT POSITION 0 Jun 08 12:01:34 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState stateService play Jun 08 12:01:34 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState currentStatus play Jun 08 12:01:34 minidsp-shd volumio[2866]: info: Received an update from plugin. extracting info from payload Jun 08 12:01:34 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:34 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 12:01:34 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:34 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:34 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:34 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:34 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:34 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:34 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:34 minidsp-shd volumio[2866]: info: ControllerMpd::pushState Jun 08 12:01:34 minidsp-shd volumio[2866]: info: CoreCommandRouter::servicePushState Jun 08 12:01:34 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrack 0 Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: STATE SERVICE {"status":"play","position":0,"seek":457,"duration":976,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"384 Kbps","isStreaming":false,"title":"10614313&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/10614313&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 12:01:34 minidsp-shd volumio[2866]: verbose: CURRENT POSITION 0 Jun 08 12:01:34 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState stateService play Jun 08 12:01:34 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState currentStatus play Jun 08 12:01:34 minidsp-shd volumio[2866]: info: Received an update from plugin. extracting info from payload Jun 08 12:01:34 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:34 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:34 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:34 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:34 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:34 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:34 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:34 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:34 minidsp-shd volumio[2866]: info: ------------------------------ 381ms Jun 08 12:01:35 minidsp-shd volumio[2866]: info: ------------------------------ 427ms Jun 08 12:01:35 minidsp-shd volumio[2866]: info: ------------------------------ 422ms Jun 08 12:01:35 minidsp-shd volumio[2866]: info: sendMpdCommand playlistinfo took 336 milliseconds Jun 08 12:01:35 minidsp-shd volumio[2866]: info: sendMpdCommand playlistinfo took 328 milliseconds Jun 08 12:01:35 minidsp-shd volumio[2866]: info: sendMpdCommand playlistinfo took 327 milliseconds Jun 08 12:01:35 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseTrackInfo Jun 08 12:01:35 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseTrackInfo Jun 08 12:01:35 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseTrackInfo Jun 08 12:01:35 minidsp-shd volumio[2866]: info: ControllerMpd::pushState Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CoreCommandRouter::servicePushState Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrack 0 Jun 08 12:01:35 minidsp-shd volumio[2866]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":976,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"397 Kbps","isStreaming":false,"title":"10614313&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/10614313&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 12:01:35 minidsp-shd volumio[2866]: verbose: CURRENT POSITION 0 Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState stateService play Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState currentStatus play Jun 08 12:01:35 minidsp-shd volumio[2866]: info: Received an update from plugin. extracting info from payload Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:35 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:35 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:35 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:35 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:35 minidsp-shd volumio[2866]: info: ControllerMpd::pushState Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CoreCommandRouter::servicePushState Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrack 0 Jun 08 12:01:35 minidsp-shd volumio[2866]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":976,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"476 Kbps","isStreaming":false,"title":"10614313&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/10614313&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 12:01:35 minidsp-shd volumio[2866]: verbose: CURRENT POSITION 0 Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState stateService play Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState currentStatus play Jun 08 12:01:35 minidsp-shd volumio[2866]: info: Received an update from plugin. extracting info from payload Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:35 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:35 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:35 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:35 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:35 minidsp-shd volumio[2866]: info: ControllerMpd::pushState Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CoreCommandRouter::servicePushState Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrack 0 Jun 08 12:01:35 minidsp-shd volumio[2866]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":976,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"476 Kbps","isStreaming":false,"title":"10614313&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/10614313&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 12:01:35 minidsp-shd volumio[2866]: verbose: CURRENT POSITION 0 Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState stateService play Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState currentStatus play Jun 08 12:01:35 minidsp-shd volumio[2866]: info: Received an update from plugin. extracting info from payload Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:35 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:35 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:35 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:35 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:35 minidsp-shd volumio[2866]: info: ------------------------------ 571ms Jun 08 12:01:35 minidsp-shd volumio[2866]: info: ------------------------------ 534ms Jun 08 12:01:35 minidsp-shd volumio[2866]: info: ------------------------------ 532ms Jun 08 12:01:35 minidsp-shd volumio[2866]: info: Executing endpoint metavolumio Jun 08 12:01:35 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 08 12:01:37 minidsp-shd volumio[2866]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 08 12:01:37 minidsp-shd volumio[2866]: info: Retrying Login Due to Network Error Jun 08 12:01:39 minidsp-shd volumio[2866]: STREAMING PROXY: Client dropped request, destroying Jun 08 12:01:39 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:39 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:39 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:39 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:39 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:39 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:39 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:39 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:39 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:39 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:39 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:39 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:40 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:40 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Preload queue cleared Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::ClearQueue Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::stop Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::stPlaybackTimer Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::updateTrackBlock Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrackBlock Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrack 0 Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:43 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:43 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::serviceStop Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrack 0 Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreCommandRouter::serviceStop Jun 08 12:01:43 minidsp-shd volumio[2866]: info: [1749355303123] ControllerTidal::stop Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 08 12:01:43 minidsp-shd volumio[2866]: info: ControllerMpd::stop Jun 08 12:01:43 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand stop Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CorePlayQueue::clearPlayQueue Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CorePlayQueue::saveQueue Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushQueue Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::addQueueItems Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CorePlayQueue::addQueueItems Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Preload queue cleared Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Adding Item to queue: tidal://song/10614313 Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Using cached record of: tidal://song/10614313 Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Adding Item to queue: tidal://song/10614314 Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Using cached record of: tidal://song/10614314 Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Adding Item to queue: tidal://song/10614315 Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Using cached record of: tidal://song/10614315 Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Adding Item to queue: tidal://song/10614316 Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Using cached record of: tidal://song/10614316 Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Adding Item to queue: tidal://song/10614317 Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Using cached record of: tidal://song/10614317 Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Adding Item to queue: tidal://song/10614318 Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Using cached record of: tidal://song/10614318 Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Adding Item to queue: tidal://song/10614319 Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Using cached record of: tidal://song/10614319 Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushQueue Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CorePlayQueue::saveQueue Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::updateTrackBlock Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrackBlock Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPlay Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::play index 5 Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::stop Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::play index undefined Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrack 5 Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::startPlaybackTimer Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrack 5 Jun 08 12:01:43 minidsp-shd volumio[2866]: info: [1749355303193] ControllerTidal::clearAddPlayTrack Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Getting stream with soundQuality LOSSLESS Jun 08 12:01:43 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand stop Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Jun 08 12:01:43 minidsp-shd volumio[2866]: ---------------------------- MPD announces state update: player Jun 08 12:01:43 minidsp-shd volumio[2866]: info: sendMpdCommand stop took 91 milliseconds Jun 08 12:01:43 minidsp-shd volumio[2866]: info: ControllerMpd::getState Jun 08 12:01:43 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand status Jun 08 12:01:43 minidsp-shd volumio[2866]: info: sendMpdCommand stop took 25 milliseconds Jun 08 12:01:43 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand clear Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Jun 08 12:01:43 minidsp-shd volumio[2866]: ---------------------------- MPD announces system playlist update Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Ignoring MPD Status Update Jun 08 12:01:43 minidsp-shd volumio[2866]: info: sendMpdCommand status took 31 milliseconds Jun 08 12:01:43 minidsp-shd volumio[2866]: info: sendMpdCommand clear took 22 milliseconds Jun 08 12:01:43 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseState Jun 08 12:01:43 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 12:01:43 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/10614318&soundQuality=LOSSLESS" Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Jun 08 12:01:43 minidsp-shd volumio[2866]: ---------------------------- MPD announces state update: player Jun 08 12:01:43 minidsp-shd volumio[2866]: info: ControllerMpd::getState Jun 08 12:01:43 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand status Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Jun 08 12:01:43 minidsp-shd volumio[2866]: ---------------------------- MPD announces state update: player Jun 08 12:01:43 minidsp-shd volumio[2866]: info: ControllerMpd::getState Jun 08 12:01:43 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand status Jun 08 12:01:43 minidsp-shd volumio[2866]: error: updateQueue error: null Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Jun 08 12:01:43 minidsp-shd volumio[2866]: ---------------------------- MPD announces system playlist update Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Ignoring MPD Status Update Jun 08 12:01:43 minidsp-shd volumio[2866]: info: ------------------------------ 60ms Jun 08 12:01:43 minidsp-shd volumio[2866]: info: sendMpdCommand playlistinfo took 53 milliseconds Jun 08 12:01:43 minidsp-shd volumio[2866]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/10614318&soundQuality=LOSSLESS" took 54 milliseconds Jun 08 12:01:43 minidsp-shd volumio[2866]: info: sendMpdCommand status took 35 milliseconds Jun 08 12:01:43 minidsp-shd volumio[2866]: info: sendMpdCommand status took 30 milliseconds Jun 08 12:01:43 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseTrackInfo Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 08 12:01:43 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand play Jun 08 12:01:43 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseState Jun 08 12:01:43 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseState Jun 08 12:01:43 minidsp-shd volumio[2866]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 08 12:01:43 minidsp-shd volumio[2866]: info: ControllerMpd::pushState Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreCommandRouter::servicePushState Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 12:01:43 minidsp-shd volumio[2866]: STREAMING PROXY: Handling url /?data=tidal://song/10614318&soundQuality=LOSSLESS Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:43 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:43 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrack 5 Jun 08 12:01:43 minidsp-shd volumio[2866]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 08 12:01:43 minidsp-shd volumio[2866]: verbose: CURRENT POSITION 5 Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState stateService stop Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState currentStatus stop Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:43 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:43 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:43 minidsp-shd volumio[2866]: info: No code Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:43 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:43 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:43 minidsp-shd volumio[2866]: info: ControllerMpd::pushState Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreCommandRouter::servicePushState Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:43 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:43 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrack 5 Jun 08 12:01:43 minidsp-shd volumio[2866]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 08 12:01:43 minidsp-shd volumio[2866]: verbose: CURRENT POSITION 5 Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState stateService stop Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState currentStatus stop Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:43 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:43 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:43 minidsp-shd volumio[2866]: info: No code Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:43 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:43 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:43 minidsp-shd volumio[2866]: info: ------------------------------ 196ms Jun 08 12:01:43 minidsp-shd volumio[2866]: info: ------------------------------ 171ms Jun 08 12:01:43 minidsp-shd volumio[2866]: info: ------------------------------ 154ms Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Jun 08 12:01:43 minidsp-shd volumio[2866]: ---------------------------- MPD announces system playlist update Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Ignoring MPD Status Update Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Jun 08 12:01:43 minidsp-shd volumio[2866]: ---------------------------- MPD announces system playlist update Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Ignoring MPD Status Update Jun 08 12:01:43 minidsp-shd volumio[2866]: info: ------------------------------ 171ms Jun 08 12:01:43 minidsp-shd volumio[2866]: info: sendMpdCommand play took 156 milliseconds Jun 08 12:01:43 minidsp-shd volumio[2866]: info: ------------------------------ 40ms Jun 08 12:01:43 minidsp-shd volumio[2866]: info: ------------------------------ 38ms Jun 08 12:01:43 minidsp-shd volumio[2866]: info: Executing endpoint getStreamUrltidal Jun 08 12:01:43 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 08 12:01:43 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:43 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:43 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:43 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:44 minidsp-shd volumio[2866]: info: getStreamUrl took 738 milliseconds Jun 08 12:01:44 minidsp-shd volumio[2866]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidmOTM0NTM3ZDc4ZjhkYzE2OWM2MWM5NzY4ZGE1NmM4Y182MS5tcDQ/0.flac?token=1749358904~YTIyMmE0N2I5NDA0MzU0MzAzNWUyNDk3NTBlZmFkNmUyOGRlYzU1NA== Jun 08 12:01:44 minidsp-shd volumio[2866]: STREAMING PROXY: Response: 200, length: 17849729 Jun 08 12:01:44 minidsp-shd volumio[2866]: info: Jun 08 12:01:44 minidsp-shd volumio[2866]: ---------------------------- MPD announces state update: player Jun 08 12:01:44 minidsp-shd volumio[2866]: info: ControllerMpd::getState Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand status Jun 08 12:01:44 minidsp-shd volumio[2866]: info: Jun 08 12:01:44 minidsp-shd volumio[2866]: ---------------------------- MPD announces state update: player Jun 08 12:01:44 minidsp-shd volumio[2866]: info: ControllerMpd::getState Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand status Jun 08 12:01:44 minidsp-shd volumio[2866]: info: Jun 08 12:01:44 minidsp-shd volumio[2866]: ---------------------------- MPD announces state update: player Jun 08 12:01:44 minidsp-shd volumio[2866]: info: ControllerMpd::getState Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand status Jun 08 12:01:44 minidsp-shd volumio[2866]: info: Jun 08 12:01:44 minidsp-shd volumio[2866]: ---------------------------- MPD announces state update: player Jun 08 12:01:44 minidsp-shd volumio[2866]: info: ControllerMpd::getState Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand status Jun 08 12:01:44 minidsp-shd volumio[2866]: info: sendMpdCommand status took 19 milliseconds Jun 08 12:01:44 minidsp-shd volumio[2866]: info: sendMpdCommand status took 14 milliseconds Jun 08 12:01:44 minidsp-shd volumio[2866]: info: sendMpdCommand status took 10 milliseconds Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseState Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseState Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseState Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 12:01:44 minidsp-shd volumio[2866]: info: Jun 08 12:01:44 minidsp-shd volumio[2866]: ---------------------------- MPD announces state update: player Jun 08 12:01:44 minidsp-shd volumio[2866]: info: ControllerMpd::getState Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand status Jun 08 12:01:44 minidsp-shd volumio[2866]: info: Jun 08 12:01:44 minidsp-shd volumio[2866]: ---------------------------- MPD announces state update: player Jun 08 12:01:44 minidsp-shd volumio[2866]: info: ControllerMpd::getState Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand status Jun 08 12:01:44 minidsp-shd volumio[2866]: info: sendMpdCommand status took 32 milliseconds Jun 08 12:01:44 minidsp-shd volumio[2866]: info: sendMpdCommand playlistinfo took 26 milliseconds Jun 08 12:01:44 minidsp-shd volumio[2866]: info: sendMpdCommand playlistinfo took 25 milliseconds Jun 08 12:01:44 minidsp-shd volumio[2866]: info: sendMpdCommand playlistinfo took 23 milliseconds Jun 08 12:01:44 minidsp-shd volumio[2866]: info: sendMpdCommand status took 15 milliseconds Jun 08 12:01:44 minidsp-shd volumio[2866]: info: sendMpdCommand status took 9 milliseconds Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseState Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseTrackInfo Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseTrackInfo Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseTrackInfo Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseState Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseState Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 12:01:44 minidsp-shd volumio[2866]: info: ControllerMpd::pushState Jun 08 12:01:44 minidsp-shd volumio[2866]: info: CoreCommandRouter::servicePushState Jun 08 12:01:44 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrack 5 Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":277,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"10614318&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/10614318&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: CURRENT POSITION 5 Jun 08 12:01:44 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState stateService play Jun 08 12:01:44 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState currentStatus stop Jun 08 12:01:44 minidsp-shd volumio[2866]: info: ControllerMpd::pushState Jun 08 12:01:44 minidsp-shd volumio[2866]: info: CoreCommandRouter::servicePushState Jun 08 12:01:44 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrack 5 Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":277,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"10614318&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/10614318&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: CURRENT POSITION 5 Jun 08 12:01:44 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState stateService play Jun 08 12:01:44 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState currentStatus play Jun 08 12:01:44 minidsp-shd volumio[2866]: info: Received an update from plugin. extracting info from payload Jun 08 12:01:44 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:44 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 12:01:44 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:44 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:44 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:44 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:44 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:44 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:44 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:44 minidsp-shd volumio[2866]: info: ControllerMpd::pushState Jun 08 12:01:44 minidsp-shd volumio[2866]: info: CoreCommandRouter::servicePushState Jun 08 12:01:44 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrack 5 Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":277,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"10614318&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/10614318&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 12:01:44 minidsp-shd volumio[2866]: verbose: CURRENT POSITION 5 Jun 08 12:01:44 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState stateService play Jun 08 12:01:44 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState currentStatus play Jun 08 12:01:44 minidsp-shd volumio[2866]: info: Received an update from plugin. extracting info from payload Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:45 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:45 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:45 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:45 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:45 minidsp-shd volumio[2866]: info: ------------------------------ 167ms Jun 08 12:01:45 minidsp-shd volumio[2866]: info: ------------------------------ 198ms Jun 08 12:01:45 minidsp-shd volumio[2866]: info: ------------------------------ 196ms Jun 08 12:01:45 minidsp-shd volumio[2866]: info: sendMpdCommand playlistinfo took 152 milliseconds Jun 08 12:01:45 minidsp-shd volumio[2866]: info: sendMpdCommand playlistinfo took 147 milliseconds Jun 08 12:01:45 minidsp-shd volumio[2866]: info: sendMpdCommand playlistinfo took 146 milliseconds Jun 08 12:01:45 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseTrackInfo Jun 08 12:01:45 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseTrackInfo Jun 08 12:01:45 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseTrackInfo Jun 08 12:01:45 minidsp-shd volumio[2866]: info: ControllerMpd::pushState Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreCommandRouter::servicePushState Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrack 5 Jun 08 12:01:45 minidsp-shd volumio[2866]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":277,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"10614318&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/10614318&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 12:01:45 minidsp-shd volumio[2866]: verbose: CURRENT POSITION 5 Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState stateService play Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState currentStatus play Jun 08 12:01:45 minidsp-shd volumio[2866]: info: Received an update from plugin. extracting info from payload Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:45 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:45 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:45 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:45 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:45 minidsp-shd volumio[2866]: info: ControllerMpd::pushState Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreCommandRouter::servicePushState Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrack 5 Jun 08 12:01:45 minidsp-shd volumio[2866]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":277,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"395 Kbps","isStreaming":false,"title":"10614318&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/10614318&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 12:01:45 minidsp-shd volumio[2866]: verbose: CURRENT POSITION 5 Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState stateService play Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState currentStatus play Jun 08 12:01:45 minidsp-shd volumio[2866]: info: Received an update from plugin. extracting info from payload Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:45 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:45 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:45 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:45 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:45 minidsp-shd volumio[2866]: info: ControllerMpd::pushState Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreCommandRouter::servicePushState Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrack 5 Jun 08 12:01:45 minidsp-shd volumio[2866]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":277,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"395 Kbps","isStreaming":false,"title":"10614318&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/10614318&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 12:01:45 minidsp-shd volumio[2866]: verbose: CURRENT POSITION 5 Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState stateService play Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState currentStatus play Jun 08 12:01:45 minidsp-shd volumio[2866]: info: Received an update from plugin. extracting info from payload Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:45 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:45 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:45 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:45 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:45 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:45 minidsp-shd volumio[2866]: info: ------------------------------ 390ms Jun 08 12:01:45 minidsp-shd volumio[2866]: info: ------------------------------ 374ms Jun 08 12:01:45 minidsp-shd volumio[2866]: info: ------------------------------ 370ms Jun 08 12:01:46 minidsp-shd volumio[2866]: STREAMING PROXY: Client dropped request, destroying Jun 08 12:01:46 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:46 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:46 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:46 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:46 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:46 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:46 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:46 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:47 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:47 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:47 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:47 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:47 minidsp-shd volumio[2866]: info: MyVolumio login type: Token Jun 08 12:01:50 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioSeek Jun 08 12:01:50 minidsp-shd volumio[2866]: info: CoreStateMachine::seek Jun 08 12:01:50 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrack 5 Jun 08 12:01:50 minidsp-shd volumio[2866]: info: TRACKBLOCK {"album":"Tchaikovsky: Violin Concerto, Op. 35, in D, Sinding: Suite, Op. 10, in A Minor, Ravel:Tzigane","albumUri":"tidal://album/10614310","albumart":"https://resources.tidal.com/images/5fef9b54/12de/4803/b738/567ef00aeeb2/640x640.jpg","artist":"Jascha Heifetz","artistUri":"tidal://artist/8623","audioQuality":"LOSSLESS","bitdepth":"16 bit","duration":277,"explicit":false,"name":"Suite, Op. 10, in A Minor: Tempo giusto","samplerate":"44.1 KHz","service":"tidal","title":"Suite, Op. 10, in A Minor: Tempo giusto","trackType":"tidal","tracknumber":6,"type":"track","uri":"tidal://song/10614318","volumeNumber":1} Jun 08 12:01:50 minidsp-shd volumio[2866]: info: CoreStateMachine::startPlaybackTimer Jun 08 12:01:50 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrack 5 Jun 08 12:01:50 minidsp-shd volumio[2866]: info: [1749355310923] ControllerTidal::seek Jun 08 12:01:50 minidsp-shd volumio[2866]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 08 12:01:50 minidsp-shd volumio[2866]: info: ControllerMpd::seek Jun 08 12:01:50 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:50 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 12:01:50 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:50 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:50 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:50 minidsp-shd volumio[2866]: error: null Jun 08 12:01:50 minidsp-shd volumio[2866]: info: Jun 08 12:01:50 minidsp-shd volumio[2866]: ---------------------------- MPD announces state update: player Jun 08 12:01:50 minidsp-shd volumio[2866]: info: ControllerMpd::getState Jun 08 12:01:50 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand status Jun 08 12:01:50 minidsp-shd volumio[2866]: info: Jun 08 12:01:50 minidsp-shd volumio[2866]: ---------------------------- MPD announces state update: player Jun 08 12:01:50 minidsp-shd volumio[2866]: info: ControllerMpd::getState Jun 08 12:01:50 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand status Jun 08 12:01:50 minidsp-shd volumio[2866]: info: Jun 08 12:01:50 minidsp-shd volumio[2866]: ---------------------------- MPD announces state update: player Jun 08 12:01:50 minidsp-shd volumio[2866]: info: ControllerMpd::getState Jun 08 12:01:50 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand status Jun 08 12:01:51 minidsp-shd volumio[2866]: info: sendMpdCommand status took 36 milliseconds Jun 08 12:01:51 minidsp-shd volumio[2866]: info: sendMpdCommand status took 28 milliseconds Jun 08 12:01:51 minidsp-shd volumio[2866]: info: sendMpdCommand status took 22 milliseconds Jun 08 12:01:51 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseState Jun 08 12:01:51 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 12:01:51 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseState Jun 08 12:01:51 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 12:01:51 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseState Jun 08 12:01:51 minidsp-shd volumio[2866]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 12:01:51 minidsp-shd volumio[2866]: info: sendMpdCommand playlistinfo took 30 milliseconds Jun 08 12:01:51 minidsp-shd volumio[2866]: info: sendMpdCommand playlistinfo took 29 milliseconds Jun 08 12:01:51 minidsp-shd volumio[2866]: info: sendMpdCommand playlistinfo took 22 milliseconds Jun 08 12:01:51 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseTrackInfo Jun 08 12:01:51 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseTrackInfo Jun 08 12:01:51 minidsp-shd volumio[2866]: verbose: ControllerMpd::parseTrackInfo Jun 08 12:01:51 minidsp-shd volumio[2866]: info: ControllerMpd::pushState Jun 08 12:01:51 minidsp-shd volumio[2866]: info: CoreCommandRouter::servicePushState Jun 08 12:01:51 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrack 5 Jun 08 12:01:51 minidsp-shd volumio[2866]: verbose: STATE SERVICE {"status":"play","position":0,"seek":124098,"duration":277,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"527 Kbps","isStreaming":false,"title":"10614318&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/10614318&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 12:01:51 minidsp-shd volumio[2866]: verbose: CURRENT POSITION 5 Jun 08 12:01:51 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState stateService play Jun 08 12:01:51 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState currentStatus play Jun 08 12:01:51 minidsp-shd volumio[2866]: info: Received an update from plugin. extracting info from payload Jun 08 12:01:51 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:51 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 12:01:51 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:51 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:51 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:51 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:51 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:51 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:51 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:51 minidsp-shd volumio[2866]: info: ControllerMpd::pushState Jun 08 12:01:51 minidsp-shd volumio[2866]: info: CoreCommandRouter::servicePushState Jun 08 12:01:51 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrack 5 Jun 08 12:01:51 minidsp-shd volumio[2866]: verbose: STATE SERVICE {"status":"play","position":0,"seek":124098,"duration":277,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"527 Kbps","isStreaming":false,"title":"10614318&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/10614318&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 12:01:51 minidsp-shd volumio[2866]: verbose: CURRENT POSITION 5 Jun 08 12:01:51 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState stateService play Jun 08 12:01:51 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState currentStatus play Jun 08 12:01:51 minidsp-shd volumio[2866]: info: Received an update from plugin. extracting info from payload Jun 08 12:01:51 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:51 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:51 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:51 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:51 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:51 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:51 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:51 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:51 minidsp-shd volumio[2866]: info: ControllerMpd::pushState Jun 08 12:01:51 minidsp-shd volumio[2866]: info: CoreCommandRouter::servicePushState Jun 08 12:01:51 minidsp-shd volumio[2866]: info: CorePlayQueue::getTrack 5 Jun 08 12:01:51 minidsp-shd volumio[2866]: verbose: STATE SERVICE {"status":"play","position":0,"seek":124098,"duration":277,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"527 Kbps","isStreaming":false,"title":"10614318&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/10614318&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 12:01:51 minidsp-shd volumio[2866]: verbose: CURRENT POSITION 5 Jun 08 12:01:51 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState stateService play Jun 08 12:01:51 minidsp-shd volumio[2866]: info: CoreStateMachine::syncState currentStatus play Jun 08 12:01:51 minidsp-shd volumio[2866]: info: Received an update from plugin. extracting info from payload Jun 08 12:01:51 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:51 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:51 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:51 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:51 minidsp-shd volumio[2866]: info: CoreStateMachine::pushState Jun 08 12:01:51 minidsp-shd volumio[2866]: info: CoreCommandRouter::volumioPushState Jun 08 12:01:51 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output update for this device Jun 08 12:01:51 minidsp-shd volumio[2866]: info: MRS: Pushing multiroomSync output Jun 08 12:01:51 minidsp-shd volumio[2866]: info: ------------------------------ 242ms Jun 08 12:01:51 minidsp-shd volumio[2866]: info: ------------------------------ 234ms Jun 08 12:01:51 minidsp-shd volumio[2866]: info: ------------------------------ 230ms Jun 08 12:01:51 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:51 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:51 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:51 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:52 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:01:52 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:02:00 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:02:00 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:02:00 minidsp-shd volumio[2866]: info: Executing endpoint metavolumio Jun 08 12:02:00 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 08 12:02:00 minidsp-shd volumio[2866]: info: Executing endpoint metavolumio Jun 08 12:02:00 minidsp-shd volumio[2866]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 08 12:02:02 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:02:02 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:02:06 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:02:06 minidsp-shd kernel: vdd_arm: ramp_delay not set Jun 08 12:02:11 minidsp-shd volumio[2866]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 12:02:11 minidsp-shd volumio[2866]: Error: connect ETIMEDOUT 31.13.112.4:443 Jun 08 12:02:11 minidsp-shd volumio[2866]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 08 12:02:11 minidsp-shd volumio[2866]: errno: -110, Jun 08 12:02:11 minidsp-shd volumio[2866]: code: 'ETIMEDOUT', Jun 08 12:02:11 minidsp-shd volumio[2866]: syscall: 'connect', Jun 08 12:02:11 minidsp-shd volumio[2866]: address: '31.13.112.4', Jun 08 12:02:11 minidsp-shd volumio[2866]: port: 443 Jun 08 12:02:11 minidsp-shd volumio[2866]: } Jun 08 12:02:11 minidsp-shd volumio[2866]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 12:02:12 minidsp-shd sudo[3401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-08 12:01 Jun 08 12:02:12 minidsp-shd sudo[3401]: 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"