Dec 06 00:20:39 donutvolumio volumio[13933]: info: CorePlayQueue::getTrack 5 Dec 06 00:20:39 donutvolumio volumio[13933]: info: CorePlayQueue::getTrack 6 Dec 06 00:20:39 donutvolumio volumio[13933]: info: Prefetching next song Dec 06 00:20:39 donutvolumio volumio[13933]: info: [1764951639708] ControllerTidal::prefetch Dec 06 00:20:39 donutvolumio volumio[13933]: info: Getting stream with soundQuality HI_RES Dec 06 00:20:40 donutvolumio volumio[13933]: info: getStreamUrl took 347 milliseconds Dec 06 00:20:40 donutvolumio volumio[13933]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic1MThmYzQyYTVjZmUxMTM0ZmMwZmU0NGIzOTY1OTYyOV82MS5tcDQ/0.flac?token=1764955239~NjRjMmRmMzI5MTJlYWFmZGEzYjllOTE0NGE0NjQ1YTM2ZjgwODcyMQ==" Dec 06 00:20:40 donutvolumio volumio[13933]: info: Dec 06 00:20:40 donutvolumio volumio[13933]: ---------------------------- MPD announces system playlist update Dec 06 00:20:40 donutvolumio volumio[13933]: info: Ignoring MPD Status Update Dec 06 00:20:40 donutvolumio volumio[13933]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic1MThmYzQyYTVjZmUxMTM0ZmMwZmU0NGIzOTY1OTYyOV82MS5tcDQ/0.flac?token=1764955239~NjRjMmRmMzI5MTJlYWFmZGEzYjllOTE0NGE0NjQ1YTM2ZjgwODcyMQ==" took 2 milliseconds Dec 06 00:20:40 donutvolumio volumio[13933]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 06 00:20:40 donutvolumio volumio[13933]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 06 00:20:40 donutvolumio volumio[13933]: info: ------------------------------ 1ms Dec 06 00:20:40 donutvolumio volumio[13933]: info: sendMpdCommand consume 1 took 1 milliseconds Dec 06 00:20:44 donutvolumio volumio[13933]: info: CoreStateMachine::startPlaybackTimer Dec 06 00:20:44 donutvolumio volumio[13933]: info: CorePlayQueue::getTrack 6 Dec 06 00:20:44 donutvolumio volumio[13933]: info: CoreStateMachine::pushState Dec 06 00:20:44 donutvolumio volumio[13933]: info: CorePlayQueue::getTrack 6 Dec 06 00:20:44 donutvolumio volumio[13933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 00:20:44 donutvolumio volumio[13933]: info: CoreCommandRouter::volumioPushState Dec 06 00:20:44 donutvolumio volumio[13933]: info: MRS: Pushing multiroomSync output update for this device Dec 06 00:20:44 donutvolumio volumio[13933]: info: MRS: Pushing multiroomSync output Dec 06 00:20:44 donutvolumio volumio[13933]: info: CoreCommandRouter::volumioGetState Dec 06 00:20:44 donutvolumio volumio[13933]: info: CorePlayQueue::getTrack 6 Dec 06 00:20:45 donutvolumio volumio[13933]: info: Dec 06 00:20:45 donutvolumio volumio[13933]: ---------------------------- MPD announces system playlist update Dec 06 00:20:45 donutvolumio volumio[13933]: info: Ignoring MPD Status Update Dec 06 00:20:45 donutvolumio volumio[13933]: info: Dec 06 00:20:45 donutvolumio volumio[13933]: ---------------------------- MPD announces state update: player Dec 06 00:20:45 donutvolumio volumio[13933]: info: ControllerMpd::getState Dec 06 00:20:45 donutvolumio volumio[13933]: verbose: ControllerMpd::sendMpdCommand status Dec 06 00:20:45 donutvolumio volumio[13933]: info: ------------------------------ 3ms Dec 06 00:20:45 donutvolumio volumio[13933]: info: sendMpdCommand status took 2 milliseconds Dec 06 00:20:45 donutvolumio volumio[13933]: verbose: ControllerMpd::parseState Dec 06 00:20:45 donutvolumio volumio[13933]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 00:20:45 donutvolumio volumio[13933]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 06 00:20:45 donutvolumio volumio[13933]: verbose: ControllerMpd::parseTrackInfo Dec 06 00:20:45 donutvolumio volumio[13933]: info: ControllerMpd::pushState Dec 06 00:20:45 donutvolumio volumio[13933]: info: CoreCommandRouter::servicePushState Dec 06 00:20:45 donutvolumio volumio[13933]: info: CorePlayQueue::getTrack 6 Dec 06 00:20:45 donutvolumio volumio[13933]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":157,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"698 Kbps","isStreaming":false,"title":"0.flac?token=1764955239~NjRjMmRmMzI5MTJlYWFmZGEzYjllOTE0NGE0NjQ1YTM2ZjgwODcyMQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic1MThmYzQyYTVjZmUxMTM0ZmMwZmU0NGIzOTY1OTYyOV82MS5tcDQ/0.flac?token=1764955239~NjRjMmRmMzI5MTJlYWFmZGEzYjllOTE0NGE0NjQ1YTM2ZjgwODcyMQ==","trackType":"tidal"} Dec 06 00:20:45 donutvolumio volumio[13933]: verbose: CURRENT POSITION 6 Dec 06 00:20:45 donutvolumio volumio[13933]: info: CoreStateMachine::syncState stateService play Dec 06 00:20:45 donutvolumio volumio[13933]: info: CoreStateMachine::syncState currentStatus play Dec 06 00:20:45 donutvolumio volumio[13933]: info: Received an update from plugin. extracting info from payload Dec 06 00:20:45 donutvolumio volumio[13933]: info: CoreStateMachine::pushState Dec 06 00:20:45 donutvolumio volumio[13933]: info: CorePlayQueue::getTrack 6 Dec 06 00:20:45 donutvolumio volumio[13933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 00:20:45 donutvolumio volumio[13933]: info: CoreCommandRouter::volumioPushState Dec 06 00:20:45 donutvolumio volumio[13933]: info: MRS: Pushing multiroomSync output update for this device Dec 06 00:20:45 donutvolumio volumio[13933]: info: MRS: Pushing multiroomSync output Dec 06 00:20:45 donutvolumio volumio[13933]: info: CoreCommandRouter::volumioGetState Dec 06 00:20:45 donutvolumio volumio[13933]: info: CorePlayQueue::getTrack 6 Dec 06 00:20:45 donutvolumio volumio[13933]: info: CoreStateMachine::pushState Dec 06 00:20:45 donutvolumio volumio[13933]: info: CorePlayQueue::getTrack 6 Dec 06 00:20:45 donutvolumio volumio[13933]: info: CoreCommandRouter::volumioPushState Dec 06 00:20:45 donutvolumio volumio[13933]: info: MRS: Pushing multiroomSync output update for this device Dec 06 00:20:45 donutvolumio volumio[13933]: info: MRS: Pushing multiroomSync output Dec 06 00:20:45 donutvolumio volumio[13933]: info: CoreCommandRouter::volumioGetState Dec 06 00:20:45 donutvolumio volumio[13933]: info: CorePlayQueue::getTrack 6 Dec 06 00:20:45 donutvolumio volumio[13933]: info: ------------------------------ 51ms Dec 06 00:21:06 donutvolumio go-librespot[14407]: time="2025-12-06T00:21:06+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Dec 06 00:21:06 donutvolumio volumio[13933]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 00:21:06 donutvolumio volumio[13933]: Error: socket hang up Dec 06 00:21:06 donutvolumio volumio[13933]: at connResetException (node:internal/errors:720:14) Dec 06 00:21:06 donutvolumio volumio[13933]: at Socket.socketOnEnd (node:_http_client:519:23) Dec 06 00:21:06 donutvolumio volumio[13933]: at Socket.emit (node:events:526:35) Dec 06 00:21:06 donutvolumio volumio[13933]: at endReadableNT (node:internal/streams/readable:1376:12) Dec 06 00:21:06 donutvolumio volumio[13933]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Dec 06 00:21:06 donutvolumio volumio[13933]: code: 'ECONNRESET', Dec 06 00:21:06 donutvolumio volumio[13933]: response: undefined Dec 06 00:21:06 donutvolumio volumio[13933]: } Dec 06 00:21:06 donutvolumio volumio[13933]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 00:21:06 donutvolumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 06 00:21:06 donutvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 00:21:06 donutvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 00:21:06 donutvolumio sudo[15534]: volumio : unable to resolve host donutvolumio: System error Dec 06 00:21:06 donutvolumio sudo[15534]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-06 00:20' Dec 06 00:21:06 donutvolumio sudo[15534]: 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="3a3cfe435f8bb2bc94a920662e91a4ef35bc8e7c" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Dec 2 11:51:19 UTC 2025" VOLUMIO_VERSION="4.072" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e5001581b220b096097cf8f46a54d4f1"