Apr 11 07:22:06 cm5 volumio[1284]: verbose: ControllerMpd::sendMpdCommand status Apr 11 07:22:06 cm5 volumio[1284]: info: sendMpdCommand status took 1 milliseconds Apr 11 07:22:08 cm5 volumio[1284]: info: [HiResProxy] seg 10/21 done Apr 11 07:22:16 cm5 volumio[1284]: verbose: ControllerMpd::sendMpdCommand status Apr 11 07:22:16 cm5 volumio[1284]: info: sendMpdCommand status took 0 milliseconds Apr 11 07:22:26 cm5 volumio[1284]: verbose: ControllerMpd::sendMpdCommand status Apr 11 07:22:26 cm5 volumio[1284]: info: sendMpdCommand status took 1 milliseconds Apr 11 07:22:36 cm5 volumio[1284]: verbose: ControllerMpd::sendMpdCommand status Apr 11 07:22:36 cm5 volumio[1284]: info: sendMpdCommand status took 1 milliseconds Apr 11 07:22:46 cm5 volumio[1284]: verbose: ControllerMpd::sendMpdCommand status Apr 11 07:22:46 cm5 volumio[1284]: info: sendMpdCommand status took 1 milliseconds Apr 11 07:22:47 cm5 volumio[1284]: info: [HiResProxy] seg 20/21 done Apr 11 07:22:51 cm5 volumio[1284]: info: [HiResProxy] seg 21/21 done Apr 11 07:22:53 cm5 volumio[1284]: info: [HiResProxy] stream complete Apr 11 07:22:53 cm5 volumio[1284]: info: [HiResProxy] stream complete — session will expire in 30s: a5dc69087894e403501d055b Apr 11 07:22:53 cm5 volumio[1284]: info: [HiResProxy] client disconnected, stream cancelled Apr 11 07:22:56 cm5 volumio[1284]: verbose: ControllerMpd::sendMpdCommand status Apr 11 07:22:56 cm5 volumio[1284]: info: sendMpdCommand status took 1 milliseconds Apr 11 07:22:57 cm5 volumio[1284]: info: Apr 11 07:22:57 cm5 volumio[1284]: ---------------------------- MPD announces system playlist update Apr 11 07:22:57 cm5 volumio[1284]: info: Ignoring MPD Status Update Apr 11 07:22:57 cm5 volumio[1284]: info: Apr 11 07:22:57 cm5 volumio[1284]: ---------------------------- MPD announces state update: player Apr 11 07:22:57 cm5 volumio[1284]: info: ControllerMpd::getState Apr 11 07:22:57 cm5 volumio[1284]: verbose: ControllerMpd::sendMpdCommand status Apr 11 07:22:57 cm5 volumio[1284]: info: Apr 11 07:22:57 cm5 volumio[1284]: ---------------------------- MPD announces system playlist update Apr 11 07:22:57 cm5 volumio[1284]: info: Ignoring MPD Status Update Apr 11 07:22:57 cm5 volumio[1284]: info: Apr 11 07:22:57 cm5 volumio[1284]: ---------------------------- MPD announces state update: player Apr 11 07:22:57 cm5 volumio[1284]: info: ControllerMpd::getState Apr 11 07:22:57 cm5 volumio[1284]: verbose: ControllerMpd::sendMpdCommand status Apr 11 07:22:57 cm5 volumio[1284]: error: updateQueue error: null Apr 11 07:22:57 cm5 volumio[1284]: error: updateQueue error: null Apr 11 07:22:57 cm5 volumio[1284]: info: ------------------------------ 1ms Apr 11 07:22:57 cm5 volumio[1284]: info: sendMpdCommand status took 1 milliseconds Apr 11 07:22:57 cm5 volumio[1284]: info: ------------------------------ 1ms Apr 11 07:22:57 cm5 volumio[1284]: info: sendMpdCommand status took 0 milliseconds Apr 11 07:22:57 cm5 volumio[1284]: verbose: ControllerMpd::parseState Apr 11 07:22:57 cm5 volumio[1284]: verbose: ControllerMpd::parseState Apr 11 07:22:57 cm5 volumio[1284]: info: ControllerMpd::pushState Apr 11 07:22:57 cm5 volumio[1284]: info: CoreCommandRouter::servicePushState Apr 11 07:22:57 cm5 volumio[1284]: info: CoreStateMachine::pushState Apr 11 07:22:57 cm5 volumio[1284]: info: CorePlayQueue::getTrack 19 Apr 11 07:22:57 cm5 volumio[1284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 07:22:57 cm5 volumio[1284]: info: CoreCommandRouter::volumioPushState Apr 11 07:22:57 cm5 volumio[1284]: info: CorePlayQueue::getTrack 19 Apr 11 07:22:57 cm5 volumio[1284]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received mpd Apr 11 07:22:57 cm5 volumio[1284]: info: ControllerMpd::pushState Apr 11 07:22:57 cm5 volumio[1284]: info: CoreCommandRouter::servicePushState Apr 11 07:22:57 cm5 volumio[1284]: info: CoreStateMachine::pushState Apr 11 07:22:57 cm5 volumio[1284]: info: CorePlayQueue::getTrack 19 Apr 11 07:22:57 cm5 volumio[1284]: info: CoreCommandRouter::volumioPushState Apr 11 07:22:57 cm5 volumio[1284]: info: CorePlayQueue::getTrack 19 Apr 11 07:22:57 cm5 volumio[1284]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received mpd Apr 11 07:22:57 cm5 volumio[1284]: info: ------------------------------ 5ms Apr 11 07:22:57 cm5 volumio[1284]: info: ------------------------------ 5ms Apr 11 07:23:06 cm5 volumio[1284]: verbose: ControllerMpd::sendMpdCommand status Apr 11 07:23:06 cm5 volumio[1284]: info: sendMpdCommand status took 1 milliseconds Apr 11 07:23:06 cm5 volumio[1284]: info: [DarkAscendants] watchdog: MPD state=stop but seekTimer running — attempting resume Apr 11 07:23:06 cm5 volumio[1284]: info: [DarkAscendants] watchdog: Hi-Res — fetching fresh stream URL Apr 11 07:23:06 cm5 volumio[1284]: info: [TidalApi] getStreamUrl[HI_RES_LOSSLESS] trackId=511065284 audioQuality=HI_RES_LOSSLESS mimeType=application/dash+xml encType=NONE api.bitDepth=16 api.sampleRate=48000 Apr 11 07:23:06 cm5 volumio[1284]: info: [TidalApi] getStreamUrl DASH: quality=HI_RES_LOSSLESS codec=flac bitDepth=16 sampleRate=48000 segments=21 initUrl=hYzfBqi3bZQ__&Key-Pair-Id=K14LZCZ9QUI4JL Apr 11 07:23:06 cm5 volumio[1284]: info: [TidalApi] getStreamUrl HiRes proxy registered: http://127.0.0.1:18321/hires/92b32cd6da78d8c64685c1e8 Apr 11 07:23:06 cm5 volumio[1284]: info: [DarkAscendants] watchdog: Hi-Res fresh url=http://127.0.0.1:18321/hires/92b32cd6da78d8c64685c1e8 Apr 11 07:23:06 cm5 volumio[1284]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 07:23:06 cm5 volumio[1284]: info: sendMpdCommand stop took 9 milliseconds Apr 11 07:23:06 cm5 volumio[1284]: verbose: ControllerMpd::sendMpdCommand clear Apr 11 07:23:06 cm5 volumio[1284]: info: Apr 11 07:23:06 cm5 volumio[1284]: ---------------------------- MPD announces system playlist update Apr 11 07:23:06 cm5 volumio[1284]: info: Ignoring MPD Status Update Apr 11 07:23:06 cm5 volumio[1284]: info: sendMpdCommand clear took 0 milliseconds Apr 11 07:23:06 cm5 volumio[1284]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:18321/hires/92b32cd6da78d8c64685c1e8" Apr 11 07:23:06 cm5 volumio[1284]: info: Apr 11 07:23:06 cm5 volumio[1284]: ---------------------------- MPD announces system playlist update Apr 11 07:23:06 cm5 volumio[1284]: info: Ignoring MPD Status Update Apr 11 07:23:06 cm5 volumio[1284]: error: updateQueue error: null Apr 11 07:23:06 cm5 volumio[1284]: info: Apr 11 07:23:06 cm5 volumio[1284]: ---------------------------- MPD announces system playlist update Apr 11 07:23:06 cm5 volumio[1284]: info: Ignoring MPD Status Update Apr 11 07:23:06 cm5 volumio[1284]: info: ------------------------------ 1ms Apr 11 07:23:06 cm5 volumio[1284]: info: sendMpdCommand add "http://127.0.0.1:18321/hires/92b32cd6da78d8c64685c1e8" took 1 milliseconds Apr 11 07:23:06 cm5 volumio[1284]: info: ------------------------------ 0ms Apr 11 07:23:06 cm5 volumio[1284]: verbose: ControllerMpd::sendMpdCommand play Apr 11 07:23:06 cm5 volumio[1284]: info: ------------------------------ 1ms Apr 11 07:23:06 cm5 volumio[1284]: info: Apr 11 07:23:06 cm5 volumio[1284]: ---------------------------- MPD announces system playlist update Apr 11 07:23:06 cm5 volumio[1284]: info: Ignoring MPD Status Update Apr 11 07:23:06 cm5 volumio[1284]: info: Apr 11 07:23:06 cm5 volumio[1284]: ---------------------------- MPD announces state update: player Apr 11 07:23:06 cm5 volumio[1284]: info: ControllerMpd::getState Apr 11 07:23:06 cm5 volumio[1284]: verbose: ControllerMpd::sendMpdCommand status Apr 11 07:23:06 cm5 volumio[1284]: info: ------------------------------ 12ms Apr 11 07:23:06 cm5 volumio[1284]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 07:23:06 cm5 volumio[1284]: Error: [5@0] {play} Failed to decode "http://127.0.0.1:18321/hires/92b32cd6da78d8c64685c1e8"; Received HTTP/0.9 when not allowed: Unsupported protocol Apr 11 07:23:06 cm5 volumio[1284]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:17) Apr 11 07:23:06 cm5 volumio[1284]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 11 07:23:06 cm5 volumio[1284]: at Socket.emit (node:events:514:28) Apr 11 07:23:06 cm5 volumio[1284]: at addChunk (node:internal/streams/readable:343:12) Apr 11 07:23:06 cm5 volumio[1284]: at readableAddChunk (node:internal/streams/readable:312:11) Apr 11 07:23:06 cm5 volumio[1284]: at Readable.push (node:internal/streams/readable:253:10) Apr 11 07:23:06 cm5 volumio[1284]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Apr 11 07:23:06 cm5 volumio[1284]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 07:23:06 cm5 sudo[16233]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-11 07:22' Apr 11 07:23:06 cm5 sudo[16233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"