May 27 16:15:02 volumio go-librespot[4885]: time="2026-05-27T16:15:02+05:30" level=trace msg="received accesspoint ping" May 27 16:15:02 volumio go-librespot[4885]: time="2026-05-27T16:15:02+05:30" level=trace msg="received accesspoint pong ack" May 27 16:15:02 volumio go-librespot[4885]: time="2026-05-27T16:15:02+05:30" level=trace msg="sent dealer ping" May 27 16:15:02 volumio go-librespot[4885]: time="2026-05-27T16:15:02+05:30" level=trace msg="received dealer pong" May 27 16:15:07 volumio go-librespot[4885]: time="2026-05-27T16:15:07+05:30" level=debug msg="fetched chunk 5/19, size: 524288" uri="spotify:track:6wnCJqUzFMwqEHs05NgVxr" May 27 16:15:20 volumio go-librespot[4885]: time="2026-05-27T16:15:20+05:30" level=debug msg="fetched chunk 6/19, size: 524288" uri="spotify:track:6wnCJqUzFMwqEHs05NgVxr" May 27 16:15:32 volumio go-librespot[4885]: time="2026-05-27T16:15:32+05:30" level=trace msg="sent dealer ping" May 27 16:15:32 volumio go-librespot[4885]: time="2026-05-27T16:15:32+05:30" level=trace msg="received dealer pong" May 27 16:15:33 volumio go-librespot[4885]: time="2026-05-27T16:15:33+05:30" level=debug msg="fetched chunk 7/19, size: 524288" uri="spotify:track:6wnCJqUzFMwqEHs05NgVxr" May 27 16:15:46 volumio go-librespot[4885]: time="2026-05-27T16:15:46+05:30" level=debug msg="fetched chunk 8/19, size: 524288" uri="spotify:track:6wnCJqUzFMwqEHs05NgVxr" May 27 16:15:59 volumio go-librespot[4885]: time="2026-05-27T16:15:59+05:30" level=debug msg="fetched chunk 9/19, size: 524288" uri="spotify:track:6wnCJqUzFMwqEHs05NgVxr" May 27 16:16:02 volumio go-librespot[4885]: time="2026-05-27T16:16:02+05:30" level=trace msg="sent dealer ping" May 27 16:16:02 volumio go-librespot[4885]: time="2026-05-27T16:16:02+05:30" level=trace msg="received dealer pong" May 27 16:16:03 volumio volumio[1308]: info: [jellyfin-poller] Polled http://10.10.1.104:8096: offline (lost) May 27 16:16:11 volumio volumio[1308]: info: Preload queue cleared May 27 16:16:11 volumio volumio[1308]: info: CoreCommandRouter::volumioReplaceandPlayItems May 27 16:16:11 volumio volumio[1308]: info: CoreStateMachine::ClearQueue May 27 16:16:11 volumio volumio[1308]: info: CoreStateMachine::stop May 27 16:16:11 volumio volumio[1308]: info: CoreStateMachine::serviceStop May 27 16:16:11 volumio volumio[1308]: info: CoreCommandRouter::serviceStop May 27 16:16:11 volumio volumio[1308]: info: Spotify Stop May 27 16:16:11 volumio volumio[1308]: SPOTIFY: SPOTIFY STOP May 27 16:16:11 volumio volumio[1308]: SPOTIFY: {"status":"play","title":"The Bug","artist":"Dire Straits","album":"On Every Street","albumart":"https://i.scdn.co/image/ab67616d00001e02ee92e1d463ee63440431179c","uri":"spotify:track:6wnCJqUzFMwqEHs05NgVxr","trackType":"spotify","codec":"ogg","seek":1000,"duration":254,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":89,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} May 27 16:16:11 volumio volumio[1308]: info: Sending Spotify command to local API: /player/pause May 27 16:16:11 volumio volumio[1308]: info: CorePlayQueue::clearPlayQueue May 27 16:16:11 volumio volumio[1308]: info: CorePlayQueue::saveQueue May 27 16:16:11 volumio volumio[1308]: info: CoreCommandRouter::volumioPushQueue May 27 16:16:11 volumio volumio[1308]: info: CoreStateMachine::addQueueItems May 27 16:16:11 volumio volumio[1308]: info: CorePlayQueue::addQueueItems May 27 16:16:11 volumio volumio[1308]: info: Preload queue cleared May 27 16:16:11 volumio volumio[1308]: info: Adding Item to queue: jellyfin/Victor@2f87a92e0b1d49d284f7c9d63bc11e86/songs@albumId=2e7d85e7d8aed9c64df66d9de620e4b9/songs@albumId=ca0e99efd10b0374a99023342f324c8a/songs@albumId=cd7fbda16dc76882ada7f99f88ca3a51 May 27 16:16:11 volumio volumio[1308]: info: Using cached record of: jellyfin/Victor@2f87a92e0b1d49d284f7c9d63bc11e86/songs@albumId=2e7d85e7d8aed9c64df66d9de620e4b9/songs@albumId=ca0e99efd10b0374a99023342f324c8a/songs@albumId=cd7fbda16dc76882ada7f99f88ca3a51 May 27 16:16:11 volumio volumio[1308]: info: CoreCommandRouter::volumioPushQueue May 27 16:16:11 volumio volumio[1308]: info: CorePlayQueue::saveQueue May 27 16:16:11 volumio volumio[1308]: info: CoreStateMachine::updateTrackBlock May 27 16:16:11 volumio volumio[1308]: info: CorePlayQueue::getTrackBlock May 27 16:16:11 volumio volumio[1308]: info: CoreCommandRouter::volumioPlay May 27 16:16:11 volumio volumio[1308]: info: CoreStateMachine::play index 0 May 27 16:16:11 volumio volumio[1308]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 16:16:11 volumio volumio[1308]: info: CoreStateMachine::stop May 27 16:16:11 volumio volumio[1308]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 16:16:11 volumio volumio[1308]: info: CoreStateMachine::stPlaybackTimer May 27 16:16:11 volumio volumio[1308]: info: CoreStateMachine::updateTrackBlock May 27 16:16:11 volumio volumio[1308]: info: CorePlayQueue::getTrackBlock May 27 16:16:11 volumio volumio[1308]: info: CoreStateMachine::pushState May 27 16:16:11 volumio volumio[1308]: info: CorePlayQueue::getTrack 1 May 27 16:16:11 volumio volumio[1308]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 16:16:11 volumio volumio[1308]: info: CoreCommandRouter::volumioPushState May 27 16:16:11 volumio volumio[1308]: info: CoreStateMachine::serviceStop May 27 16:16:11 volumio volumio[1308]: info: CorePlayQueue::getTrack 1 May 27 16:16:11 volumio volumio[1308]: info: CoreCommandRouter::serviceStop May 27 16:16:11 volumio volumio[1308]: info: CoreStateMachine::setConsumeUpdateService mpd May 27 16:16:11 volumio volumio[1308]: info: ControllerMpd::stop May 27 16:16:11 volumio volumio[1308]: verbose: ControllerMpd::sendMpdCommand stop May 27 16:16:11 volumio volumio5-onboarding[1793]: time=2026-05-27T10:46:11.131Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%07,10.10.1.167:51667 @ 0xc00010c0c0" state=STATUS_STOPPED positionMs=0 volume=89 May 27 16:16:11 volumio volumio5-onboarding[1793]: time=2026-05-27T10:46:11.131Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%07,10.10.1.167:51667 @ 0xc00010c0c0" id="jellyfin/Victor@2f87a92e0b1d49d284f7c9d63bc11e86/song@songId=06ff428d50feb796967bdae08c8eb5e0" title="Black Night" May 27 16:16:11 volumio volumio[1308]: SPOTIFY: RECEIVED VOLUMIO VOLUME 89 May 27 16:16:11 volumio volumio[1308]: info: sendMpdCommand stop took 2 milliseconds May 27 16:16:11 volumio volumio[1308]: info: CoreStateMachine::play index undefined May 27 16:16:11 volumio volumio[1308]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 16:16:11 volumio volumio[1308]: info: CorePlayQueue::getTrack 0 May 27 16:16:11 volumio volumio[1308]: info: CoreStateMachine::startPlaybackTimer May 27 16:16:11 volumio volumio[1308]: info: CorePlayQueue::getTrack 0 May 27 16:16:11 volumio volumio[1308]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/Victor@2f87a92e0b1d49d284f7c9d63bc11e86/song@songId=ba17ed074ba535dd7771f4a1f98704fb May 27 16:16:11 volumio volumio[1308]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 16:16:11 volumio volumio[1308]: Error: Server unavailable May 27 16:16:11 volumio volumio[1308]: at PlayController.getSongFromTrack (/data/plugins/music_service/jellyfin/dist/lib/controller/play/PlayController.js:166:19) May 27 16:16:11 volumio volumio[1308]: at PlayController.clearAddPlayTrack (/data/plugins/music_service/jellyfin/dist/lib/controller/play/PlayController.js:77:49) May 27 16:16:11 volumio volumio[1308]: at ControllerJellyfin.clearAddPlayTrack (/data/plugins/music_service/jellyfin/dist/index.js:405:87) May 27 16:16:11 volumio volumio[1308]: at Promise._successFn (/volumio/app/statemachine.js:1028:24) May 27 16:16:11 volumio volumio[1308]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 27 16:16:11 volumio volumio[1308]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) May 27 16:16:11 volumio volumio[1308]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 16:16:11 volumio sudo[11268]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-27 16:15' May 27 16:16:11 volumio sudo[11268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"