Jan 26 18:58:00 volumio go-librespot[1987]: time="2026-01-26T18:58:00+02:00" level=trace msg="sent dealer ping" Jan 26 18:58:00 volumio go-librespot[1987]: time="2026-01-26T18:58:00+02:00" level=trace msg="received dealer pong" Jan 26 18:58:18 volumio volumio[1234]: info: Tunnel connection is inactive, restarting it Jan 26 18:58:18 volumio volumio[1234]: info: Starting Tunnel 1 Jan 26 18:58:18 volumio volumio[1234]: info: Starting Tunnel Connection Checker Jan 26 18:58:18 volumio sudo[6546]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 26 18:58:18 volumio sudo[6546]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 18:58:18 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 26 18:58:18 volumio sudo[6546]: pam_unix(sudo:session): session closed for user root Jan 26 18:58:18 volumio volumio[1234]: info: Remote SSH Started Jan 26 18:58:18 volumio autossh[6549]: port set to 0, monitoring disabled Jan 26 18:58:18 volumio autossh[6549]: starting ssh (count 1) Jan 26 18:58:18 volumio autossh[6549]: ssh child pid is 6552 Jan 26 18:58:18 volumio volumio[1234]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jan 26 18:58:18 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Jan 26 18:58:18 volumio volumiossh-tunnel[6552]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 26 18:58:18 volumio autossh[6549]: ssh exited prematurely with status 255; autossh exiting Jan 26 18:58:18 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 26 18:58:18 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 26 18:58:18 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 1. Jan 26 18:58:18 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 26 18:58:19 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 26 18:58:19 volumio autossh[6554]: port set to 0, monitoring disabled Jan 26 18:58:19 volumio autossh[6554]: starting ssh (count 1) Jan 26 18:58:19 volumio autossh[6554]: ssh child pid is 6557 Jan 26 18:58:19 volumio volumiossh-tunnel[6557]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 26 18:58:19 volumio autossh[6554]: ssh exited prematurely with status 255; autossh exiting Jan 26 18:58:19 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 26 18:58:19 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 26 18:58:19 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 2. Jan 26 18:58:19 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 26 18:58:19 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 26 18:58:19 volumio autossh[6559]: port set to 0, monitoring disabled Jan 26 18:58:19 volumio autossh[6559]: starting ssh (count 1) Jan 26 18:58:19 volumio autossh[6559]: ssh child pid is 6562 Jan 26 18:58:19 volumio volumiossh-tunnel[6562]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 26 18:58:19 volumio autossh[6559]: ssh exited prematurely with status 255; autossh exiting Jan 26 18:58:19 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 26 18:58:19 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 26 18:58:19 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 3. Jan 26 18:58:19 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 26 18:58:20 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 26 18:58:20 volumio autossh[6564]: port set to 0, monitoring disabled Jan 26 18:58:20 volumio autossh[6564]: starting ssh (count 1) Jan 26 18:58:20 volumio autossh[6564]: ssh child pid is 6567 Jan 26 18:58:20 volumio volumiossh-tunnel[6567]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 26 18:58:20 volumio autossh[6564]: ssh exited prematurely with status 255; autossh exiting Jan 26 18:58:20 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 26 18:58:20 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 26 18:58:20 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 4. Jan 26 18:58:20 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 26 18:58:20 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 26 18:58:20 volumio autossh[6569]: port set to 0, monitoring disabled Jan 26 18:58:20 volumio autossh[6569]: starting ssh (count 1) Jan 26 18:58:20 volumio autossh[6569]: ssh child pid is 6572 Jan 26 18:58:20 volumio volumiossh-tunnel[6572]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 26 18:58:20 volumio autossh[6569]: ssh exited prematurely with status 255; autossh exiting Jan 26 18:58:20 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 26 18:58:20 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 26 18:58:20 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 5. Jan 26 18:58:20 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 26 18:58:20 volumio systemd[1]: sshtunnel.service: Start request repeated too quickly. Jan 26 18:58:20 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 26 18:58:20 volumio systemd[1]: Failed to start sshtunnel.service - MyVolumio SSH Tunnel. Jan 26 18:58:30 volumio go-librespot[1987]: time="2026-01-26T18:58:30+02:00" level=trace msg="sent dealer ping" Jan 26 18:58:30 volumio go-librespot[1987]: time="2026-01-26T18:58:30+02:00" level=trace msg="received dealer pong" Jan 26 18:59:00 volumio go-librespot[1987]: time="2026-01-26T18:59:00+02:00" level=trace msg="sent dealer ping" Jan 26 18:59:00 volumio go-librespot[1987]: time="2026-01-26T18:59:00+02:00" level=trace msg="received dealer pong" Jan 26 18:59:23 volumio volumio[1234]: info: Pushing metadata Jan 26 18:59:23 volumio volumio[1234]: info: CoreCommandRouter::servicePushState Jan 26 18:59:23 volumio volumio[1234]: info: CoreStateMachine::pushState Jan 26 18:59:23 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 18:59:23 volumio volumio[1234]: info: CoreCommandRouter::volumioPushState Jan 26 18:59:23 volumio volumio[1234]: info: CoreCommandRouter::servicePushState Jan 26 18:59:23 volumio volumio[1234]: info: CoreStateMachine::pushState Jan 26 18:59:23 volumio volumio[1234]: info: CoreCommandRouter::volumioPushState Jan 26 18:59:23 volumio volumio[1234]: info: CoreCommandRouter::servicePushState Jan 26 18:59:23 volumio volumio[1234]: info: CoreStateMachine::pushState Jan 26 18:59:23 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 18:59:23 volumio volumio[1234]: info: CoreCommandRouter::volumioPushState Jan 26 18:59:23 volumio volumio[1234]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 26 18:59:23 volumio volumio[1234]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 26 18:59:23 volumio volumio[1234]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 26 18:59:29 volumio go-librespot[1987]: time="2026-01-26T18:59:29+02:00" level=trace msg="received accesspoint ping" Jan 26 18:59:29 volumio go-librespot[1987]: time="2026-01-26T18:59:29+02:00" level=trace msg="received accesspoint pong ack" Jan 26 18:59:30 volumio go-librespot[1987]: time="2026-01-26T18:59:30+02:00" level=trace msg="sent dealer ping" Jan 26 18:59:30 volumio go-librespot[1987]: time="2026-01-26T18:59:30+02:00" level=trace msg="received dealer pong" Jan 26 18:59:36 volumio volumio[1234]: verbose: New Socket.io Connection to 192.168.0.113 from 192.168.0.108 UA: Mozilla/5.0 (Linux; Android 15; 2405CRPFDG Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.59 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jan 26 18:59:36 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 26 18:59:36 volumio volumio[1234]: info: CoreCommandRouter::volumioGetVisibleSources Jan 26 18:59:36 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 18:59:36 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Jan 26 18:59:36 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Jan 26 18:59:36 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 26 18:59:36 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 26 18:59:36 volumio volumio[1234]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 26 18:59:36 volumio volumio[1234]: info: Received Get System Info Jan 26 18:59:36 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 18:59:36 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 18:59:36 volumio volumio[1234]: info: Discovery: Getting this device information Jan 26 18:59:36 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Jan 26 18:59:36 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 18:59:36 volumio volumio[1234]: info: CoreCommandRouter::volumioGetState Jan 26 18:59:36 volumio volumio[1234]: info: Listing playlists Jan 26 18:59:36 volumio volumio[1234]: info: CoreCommandRouter::volumioGetQueue Jan 26 18:59:36 volumio volumio[1234]: info: CoreStateMachine::getQueue Jan 26 18:59:36 volumio volumio[1234]: info: CorePlayQueue::getQueue Jan 26 18:59:37 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 26 18:59:37 volumio volumio[1234]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 18:59:37 volumio volumio[1234]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 18:59:39 volumio volumio[1234]: info: Executing endpoint metavolumio Jan 26 18:59:39 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 26 18:59:39 volumio volumio[1234]: info: Executing endpoint metavolumio Jan 26 18:59:39 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 26 18:59:39 volumio volumio[1234]: info: Executing endpoint metavolumio Jan 26 18:59:39 volumio volumio[1234]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 26 18:59:46 volumio volumio[1234]: info: CoreCommandRouter::volumioRemoveQueueItem Jan 26 18:59:46 volumio volumio[1234]: info: CoreStateMachine::removeQueueItem Jan 26 18:59:46 volumio volumio[1234]: info: CoreStateMachine::stop Jan 26 18:59:46 volumio volumio[1234]: info: CoreStateMachine::serviceStop Jan 26 18:59:46 volumio volumio[1234]: info: CoreCommandRouter::serviceStop Jan 26 18:59:46 volumio volumio[1234]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 18:59:46 volumio vtcs[2342]: [close:85] Entering Jan 26 18:59:46 volumio volumio[1234]: TypeError: Cannot read properties of undefined (reading 'then') Jan 26 18:59:46 volumio volumio[1234]: at CoreStateMachine.removeQueueItem (/volumio/app/statemachine.js:1372:7) Jan 26 18:59:46 volumio volumio[1234]: at CoreCommandRouter.volumioRemoveQueueItem (/volumio/app/index.js:121:28) Jan 26 18:59:46 volumio volumio[1234]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:173:33) Jan 26 18:59:46 volumio volumio[1234]: at Socket.emit (node:events:514:28) Jan 26 18:59:46 volumio volumio[1234]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jan 26 18:59:46 volumio volumio[1234]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Jan 26 18:59:46 volumio volumio[1234]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 18:59:46 volumio vtcs[2342]: [2026-01-26 18:59:46] [info] asio async_shutdown error: asio.misc:2 (End of file) Jan 26 18:59:46 volumio vtcs[2342]: [feedThread:276] Exiting Jan 26 18:59:46 volumio vtcs[2342]: [close:100] Exiting Jan 26 18:59:46 volumio vtcs[2342]: [2026-01-26 18:59:46.698] [tisoc] [warning] [PlaybackControllerImpl.cpp:520] Illegal state:0 Jan 26 18:59:47 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 3 ep 9 Jan 26 18:59:47 volumio kernel: xhci_hcd 0000:01:00.0: @0000000428d14740 00000000 00000000 0e000000 030a8000 Jan 26 18:59:47 volumio sudo[6739]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-26 18:58' Jan 26 18:59:47 volumio sudo[6739]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"