Dec 20 22:52:22 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioGetQueue Dec 20 22:52:22 musicgenie2 volumio[8579]: info: CoreStateMachine::getQueue Dec 20 22:52:22 musicgenie2 volumio[8579]: info: CorePlayQueue::getQueue Dec 20 22:52:22 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioGetState Dec 20 22:52:22 musicgenie2 volumio[8579]: info: CorePlayQueue::getTrack 0 Dec 20 22:52:22 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioGetQueue Dec 20 22:52:22 musicgenie2 volumio[8579]: info: CoreStateMachine::getQueue Dec 20 22:52:22 musicgenie2 volumio[8579]: info: CorePlayQueue::getQueue Dec 20 22:52:22 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioGetState Dec 20 22:52:22 musicgenie2 volumio[8579]: info: CorePlayQueue::getTrack 0 Dec 20 22:52:23 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioGetQueue Dec 20 22:52:23 musicgenie2 volumio[8579]: info: CoreStateMachine::getQueue Dec 20 22:52:23 musicgenie2 volumio[8579]: info: CorePlayQueue::getQueue Dec 20 22:52:23 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioGetQueue Dec 20 22:52:23 musicgenie2 volumio[8579]: info: CoreStateMachine::getQueue Dec 20 22:52:23 musicgenie2 volumio[8579]: info: CorePlayQueue::getQueue Dec 20 22:52:23 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioAddQueueItems Dec 20 22:52:23 musicgenie2 volumio[8579]: info: CoreStateMachine::addQueueItems Dec 20 22:52:23 musicgenie2 volumio[8579]: info: CorePlayQueue::addQueueItems Dec 20 22:52:23 musicgenie2 volumio[8579]: info: Preload queue cleared Dec 20 22:52:23 musicgenie2 volumio[8579]: info: Adding Item to queue: spotify:track:3spdoTYpuCpmq19tuD0bOe Dec 20 22:52:23 musicgenie2 volumio[8579]: info: Exploding uri spotify:track:3spdoTYpuCpmq19tuD0bOe in service spop Dec 20 22:52:23 musicgenie2 volumio[8579]: SPOTIFY: EXPLODING URI:spotify:track:3spdoTYpuCpmq19tuD0bOe Dec 20 22:52:23 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioGetQueue Dec 20 22:52:23 musicgenie2 volumio[8579]: info: CoreStateMachine::getQueue Dec 20 22:52:23 musicgenie2 volumio[8579]: info: CorePlayQueue::getQueue Dec 20 22:52:23 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioGetState Dec 20 22:52:23 musicgenie2 volumio[8579]: info: CorePlayQueue::getTrack 0 Dec 20 22:52:23 musicgenie2 volumio[8579]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3spdoTYpuCpmq19tuD0bOe","service":"spop","name":"My Way","artist":"Frank Sinatra","album":"My Way (Expanded Edition)","type":"song","duration":276,"albumart":"https://i.scdn.co/image/ab67616d0000b27380aa6a82fce614eea8357253","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 20 22:52:23 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioPushQueue Dec 20 22:52:23 musicgenie2 volumio[8579]: info: CorePlayQueue::saveQueue Dec 20 22:52:23 musicgenie2 volumio[8579]: info: CoreStateMachine::updateTrackBlock Dec 20 22:52:23 musicgenie2 volumio[8579]: info: CorePlayQueue::getTrackBlock Dec 20 22:52:24 musicgenie2 go-librespot[8836]: time="2025-12-20T22:52:24Z" level=trace msg="sent dealer ping" Dec 20 22:52:24 musicgenie2 go-librespot[8836]: time="2025-12-20T22:52:24Z" level=trace msg="received dealer pong" Dec 20 22:52:39 musicgenie2 volumio[8579]: verbose: New Socket.io Connection to musicgenie2.local:3000 from 192.168.1.181 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 20 22:52:47 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioRemoveQueueItem Dec 20 22:52:47 musicgenie2 volumio[8579]: info: CoreStateMachine::removeQueueItem Dec 20 22:52:47 musicgenie2 volumio[8579]: info: CoreStateMachine::stop Dec 20 22:52:47 musicgenie2 volumio[8579]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 22:52:47 musicgenie2 volumio[8579]: info: CorePlayQueue::removeQueueItem 0 Dec 20 22:52:47 musicgenie2 volumio[8579]: info: CorePlayQueue::saveQueue Dec 20 22:52:47 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioPushQueue Dec 20 22:52:49 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioGetState Dec 20 22:52:49 musicgenie2 volumio[8579]: info: CorePlayQueue::getTrack 0 Dec 20 22:52:49 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioGetQueue Dec 20 22:52:49 musicgenie2 volumio[8579]: info: CoreStateMachine::getQueue Dec 20 22:52:49 musicgenie2 volumio[8579]: info: CorePlayQueue::getQueue Dec 20 22:52:50 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioRemoveQueueItem Dec 20 22:52:50 musicgenie2 volumio[8579]: info: CoreStateMachine::removeQueueItem Dec 20 22:52:50 musicgenie2 volumio[8579]: info: CoreStateMachine::stop Dec 20 22:52:50 musicgenie2 volumio[8579]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 22:52:50 musicgenie2 volumio[8579]: info: CorePlayQueue::removeQueueItem 0 Dec 20 22:52:50 musicgenie2 volumio[8579]: info: CorePlayQueue::saveQueue Dec 20 22:52:50 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioPushQueue Dec 20 22:52:51 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioRemoveQueueItem Dec 20 22:52:51 musicgenie2 volumio[8579]: info: CoreStateMachine::removeQueueItem Dec 20 22:52:51 musicgenie2 volumio[8579]: info: CoreStateMachine::stop Dec 20 22:52:51 musicgenie2 volumio[8579]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 22:52:51 musicgenie2 volumio[8579]: info: CorePlayQueue::removeQueueItem 0 Dec 20 22:52:51 musicgenie2 volumio[8579]: info: CorePlayQueue::saveQueue Dec 20 22:52:51 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioPushQueue Dec 20 22:52:54 musicgenie2 go-librespot[8836]: time="2025-12-20T22:52:54Z" level=trace msg="sent dealer ping" Dec 20 22:52:54 musicgenie2 go-librespot[8836]: time="2025-12-20T22:52:54Z" level=trace msg="received dealer pong" Dec 20 22:52:54 musicgenie2 go-librespot[8836]: time="2025-12-20T22:52:54Z" level=trace msg="received accesspoint ping" Dec 20 22:52:54 musicgenie2 go-librespot[8836]: time="2025-12-20T22:52:54Z" level=trace msg="received accesspoint pong ack" Dec 20 22:53:24 musicgenie2 go-librespot[8836]: time="2025-12-20T22:53:24Z" level=trace msg="sent dealer ping" Dec 20 22:53:24 musicgenie2 go-librespot[8836]: time="2025-12-20T22:53:24Z" level=trace msg="received dealer pong" Dec 20 22:53:36 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioGetQueue Dec 20 22:53:36 musicgenie2 volumio[8579]: info: CoreStateMachine::getQueue Dec 20 22:53:36 musicgenie2 volumio[8579]: info: CorePlayQueue::getQueue Dec 20 22:53:36 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioGetState Dec 20 22:53:36 musicgenie2 volumio[8579]: info: CorePlayQueue::getTrack 0 Dec 20 22:53:36 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioGetQueue Dec 20 22:53:36 musicgenie2 volumio[8579]: info: CoreStateMachine::getQueue Dec 20 22:53:36 musicgenie2 volumio[8579]: info: CorePlayQueue::getQueue Dec 20 22:53:36 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioGetState Dec 20 22:53:36 musicgenie2 volumio[8579]: info: CorePlayQueue::getTrack 0 Dec 20 22:53:37 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioGetQueue Dec 20 22:53:37 musicgenie2 volumio[8579]: info: CoreStateMachine::getQueue Dec 20 22:53:37 musicgenie2 volumio[8579]: info: CorePlayQueue::getQueue Dec 20 22:53:37 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioGetQueue Dec 20 22:53:37 musicgenie2 volumio[8579]: info: CoreStateMachine::getQueue Dec 20 22:53:37 musicgenie2 volumio[8579]: info: CorePlayQueue::getQueue Dec 20 22:53:37 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioAddQueueItems Dec 20 22:53:37 musicgenie2 volumio[8579]: info: CoreStateMachine::addQueueItems Dec 20 22:53:37 musicgenie2 volumio[8579]: info: CorePlayQueue::addQueueItems Dec 20 22:53:37 musicgenie2 volumio[8579]: info: Preload queue cleared Dec 20 22:53:37 musicgenie2 volumio[8579]: info: Adding Item to queue: spotify:track:3HNTLCpAGnizbyJmk7466r Dec 20 22:53:37 musicgenie2 volumio[8579]: info: Exploding uri spotify:track:3HNTLCpAGnizbyJmk7466r in service spop Dec 20 22:53:37 musicgenie2 volumio[8579]: SPOTIFY: EXPLODING URI:spotify:track:3HNTLCpAGnizbyJmk7466r Dec 20 22:53:37 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioGetQueue Dec 20 22:53:37 musicgenie2 volumio[8579]: info: CoreStateMachine::getQueue Dec 20 22:53:37 musicgenie2 volumio[8579]: info: CorePlayQueue::getQueue Dec 20 22:53:37 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioGetState Dec 20 22:53:37 musicgenie2 volumio[8579]: info: CorePlayQueue::getTrack 0 Dec 20 22:53:37 musicgenie2 volumio[8579]: info: CoreCommandRouter::volumioAddQueueItems Dec 20 22:53:37 musicgenie2 volumio[8579]: info: CoreStateMachine::addQueueItems Dec 20 22:53:37 musicgenie2 volumio[8579]: info: CorePlayQueue::addQueueItems Dec 20 22:53:37 musicgenie2 volumio[8579]: info: Preload queue cleared Dec 20 22:53:37 musicgenie2 volumio[8579]: info: Adding Item to queue: musicgeniedj:06ee078c-7e07-4545-afa8-53a199a3ebbe Dec 20 22:53:37 musicgenie2 volumio[8579]: info: Exploding uri musicgeniedj:06ee078c-7e07-4545-afa8-53a199a3ebbe in service music-genie-dj Dec 20 22:53:37 musicgenie2 volumio[8579]: info: Fetching track from: undefined/api/message?id=06ee078c-7e07-4545-afa8-53a199a3ebbe Dec 20 22:53:37 musicgenie2 volumio[8579]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 22:53:37 musicgenie2 volumio[8579]: TypeError [ERR_INVALID_URL]: Invalid URL Dec 20 22:53:37 musicgenie2 volumio[8579]: at new NodeError (node:internal/errors:405:5) Dec 20 22:53:37 musicgenie2 volumio[8579]: at new URL (node:internal/url:778:13) Dec 20 22:53:37 musicgenie2 volumio[8579]: at new ClientRequest (node:_http_client:135:30) Dec 20 22:53:37 musicgenie2 volumio[8579]: at request (node:http:101:10) Dec 20 22:53:37 musicgenie2 volumio[8579]: at Object.get (node:http:112:15) Dec 20 22:53:37 musicgenie2 volumio[8579]: at ControllerMusicGenieDj.fetchTrackStream (/data/plugins/music_service/music-genie-dj/index.js:204:9) Dec 20 22:53:37 musicgenie2 volumio[8579]: at ControllerMusicGenieDj.explodeUri (/data/plugins/music_service/music-genie-dj/index.js:312:8) Dec 20 22:53:37 musicgenie2 volumio[8579]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:16) Dec 20 22:53:37 musicgenie2 volumio[8579]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Dec 20 22:53:37 musicgenie2 volumio[8579]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Dec 20 22:53:37 musicgenie2 volumio[8579]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Dec 20 22:53:37 musicgenie2 volumio[8579]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:251:25) Dec 20 22:53:37 musicgenie2 volumio[8579]: at CoreCommandRouter.addQueueItems (/volumio/app/index.js:448:28) Dec 20 22:53:37 musicgenie2 volumio[8579]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:78:26) Dec 20 22:53:37 musicgenie2 volumio[8579]: at Socket.emit (node:events:514:28) Dec 20 22:53:37 musicgenie2 volumio[8579]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 { Dec 20 22:53:37 musicgenie2 volumio[8579]: input: 'undefined/api/message?id=06ee078c-7e07-4545-afa8-53a199a3ebbe', Dec 20 22:53:37 musicgenie2 volumio[8579]: code: 'ERR_INVALID_URL' Dec 20 22:53:37 musicgenie2 volumio[8579]: } Dec 20 22:53:37 musicgenie2 volumio[8579]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 22:53:37 musicgenie2 sudo[24796]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-20 22:52' Dec 20 22:53:37 musicgenie2 sudo[24796]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"