Jan 28 10:18:07 volumio volumio[1355]: info: Tunnel connection is inactive, restarting it Jan 28 10:18:07 volumio volumio[1355]: info: Starting Tunnel 1 Jan 28 10:18:07 volumio volumio[1355]: info: Starting Tunnel Connection Checker Jan 28 10:18:07 volumio sudo[9044]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 28 10:18:07 volumio sudo[9044]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:18:07 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 28 10:18:07 volumio sudo[9044]: pam_unix(sudo:session): session closed for user root Jan 28 10:18:07 volumio volumio[1355]: info: Remote SSH Started Jan 28 10:18:07 volumio autossh[9047]: port set to 0, monitoring disabled Jan 28 10:18:07 volumio autossh[9047]: starting ssh (count 1) Jan 28 10:18:07 volumio autossh[9047]: ssh child pid is 9050 Jan 28 10:18:07 volumio volumio[1355]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 28 10:18:07 volumio volumio[1355]: info: CoreCommandRouter::volumioGetState Jan 28 10:18:07 volumio volumiossh-tunnel[9050]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 28 10:18:07 volumio autossh[9047]: ssh exited prematurely with status 255; autossh exiting Jan 28 10:18:07 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 28 10:18:07 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 28 10:18:07 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 1. Jan 28 10:18:07 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 28 10:18:07 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 28 10:18:07 volumio autossh[9066]: port set to 0, monitoring disabled Jan 28 10:18:07 volumio autossh[9066]: starting ssh (count 1) Jan 28 10:18:07 volumio autossh[9066]: ssh child pid is 9069 Jan 28 10:18:07 volumio volumiossh-tunnel[9069]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 28 10:18:07 volumio autossh[9066]: ssh exited prematurely with status 255; autossh exiting Jan 28 10:18:07 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 28 10:18:07 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 28 10:18:08 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 2. Jan 28 10:18:08 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 28 10:18:08 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 28 10:18:08 volumio autossh[9071]: port set to 0, monitoring disabled Jan 28 10:18:08 volumio autossh[9071]: starting ssh (count 1) Jan 28 10:18:08 volumio autossh[9071]: ssh child pid is 9074 Jan 28 10:18:08 volumio volumiossh-tunnel[9074]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 28 10:18:08 volumio autossh[9071]: ssh exited prematurely with status 255; autossh exiting Jan 28 10:18:08 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 28 10:18:08 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 28 10:18:08 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 3. Jan 28 10:18:08 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 28 10:18:08 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 28 10:18:08 volumio autossh[9076]: port set to 0, monitoring disabled Jan 28 10:18:08 volumio autossh[9076]: starting ssh (count 1) Jan 28 10:18:08 volumio autossh[9076]: ssh child pid is 9079 Jan 28 10:18:08 volumio volumiossh-tunnel[9079]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 28 10:18:08 volumio autossh[9076]: ssh exited prematurely with status 255; autossh exiting Jan 28 10:18:08 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 28 10:18:08 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 28 10:18:08 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 4. Jan 28 10:18:08 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 28 10:18:08 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 28 10:18:08 volumio autossh[9081]: port set to 0, monitoring disabled Jan 28 10:18:08 volumio autossh[9081]: starting ssh (count 1) Jan 28 10:18:08 volumio autossh[9081]: ssh child pid is 9084 Jan 28 10:18:09 volumio volumiossh-tunnel[9084]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 28 10:18:09 volumio autossh[9081]: ssh exited prematurely with status 255; autossh exiting Jan 28 10:18:09 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 28 10:18:09 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 28 10:18:09 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 5. Jan 28 10:18:09 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 28 10:18:09 volumio systemd[1]: sshtunnel.service: Start request repeated too quickly. Jan 28 10:18:09 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 28 10:18:09 volumio systemd[1]: Failed to start sshtunnel.service - MyVolumio SSH Tunnel. Jan 28 10:18:09 volumio vtcs[3508]: [start:106] Entering Jan 28 10:18:09 volumio vtcs[3508]: [start:113] Reopening PCM device... Jan 28 10:18:09 volumio volumio[1355]: info: CoreCommandRouter::servicePushState Jan 28 10:18:09 volumio volumio[1355]: info: CoreStateMachine::pushState Jan 28 10:18:09 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 10:18:09 volumio volumio[1355]: info: CoreCommandRouter::volumioPushState Jan 28 10:18:09 volumio volumio[1355]: info: MRS: Pushing multiroomSync output update for this device Jan 28 10:18:09 volumio volumio[1355]: info: MRS: Pushing multiroomSync output Jan 28 10:18:09 volumio volumio[1355]: info: CoreCommandRouter::volumioGetState Jan 28 10:18:09 volumio vtcs[3508]: [start:115] PCM opened successfully. Jan 28 10:18:09 volumio vtcs[3508]: [start:120] Exiting Jan 28 10:18:09 volumio vtcs[3508]: [feedThread:223] Entering Jan 28 10:18:10 volumio volumio[1355]: info: CoreCommandRouter::servicePushState Jan 28 10:18:10 volumio volumio[1355]: info: CoreStateMachine::pushState Jan 28 10:18:10 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 10:18:10 volumio volumio[1355]: info: CoreCommandRouter::volumioPushState Jan 28 10:18:10 volumio volumio[1355]: info: MRS: Pushing multiroomSync output update for this device Jan 28 10:18:10 volumio volumio[1355]: info: MRS: Pushing multiroomSync output Jan 28 10:18:10 volumio volumio[1355]: info: CoreCommandRouter::volumioGetState Jan 28 10:18:13 volumio go-librespot[4069]: time="2026-01-28T10:18:13+02:00" level=trace msg="sent dealer ping" Jan 28 10:18:14 volumio go-librespot[4069]: time="2026-01-28T10:18:14+02:00" level=trace msg="received dealer pong" Jan 28 10:18:31 volumio volumio[1355]: verbose: New Socket.io Connection to 10.248.249.69 from 10.248.249.252 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/29.0 Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jan 28 10:18:31 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 28 10:18:31 volumio volumio[1355]: info: CoreCommandRouter::volumioGetVisibleSources Jan 28 10:18:31 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 10:18:31 volumio volumio[1355]: info: CoreCommandRouter::volumioGetState Jan 28 10:18:31 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 28 10:18:31 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 28 10:18:31 volumio volumio[1355]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 28 10:18:31 volumio volumio[1355]: info: Received Get System Info Jan 28 10:18:31 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 10:18:31 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 10:18:31 volumio volumio[1355]: info: Discovery: Getting this device information Jan 28 10:18:31 volumio volumio[1355]: info: CoreCommandRouter::volumioGetState Jan 28 10:18:31 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 10:18:31 volumio volumio[1355]: info: CoreCommandRouter::volumioGetState Jan 28 10:18:31 volumio volumio[1355]: info: Listing playlists Jan 28 10:18:31 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 28 10:18:31 volumio sudo[9117]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 10:18:31 volumio sudo[9117]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:18:31 volumio sudo[9117]: pam_unix(sudo:session): session closed for user root Jan 28 10:18:31 volumio sudo[9119]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 10:18:31 volumio sudo[9119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:18:31 volumio sudo[9119]: pam_unix(sudo:session): session closed for user root Jan 28 10:18:31 volumio volumio[1355]: verbose: New Socket.io Connection to 10.248.249.69 from 10.248.249.252 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/29.0 Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jan 28 10:18:32 volumio sudo[9123]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 10:18:32 volumio sudo[9123]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:18:32 volumio sudo[9123]: pam_unix(sudo:session): session closed for user root Jan 28 10:18:32 volumio sudo[9125]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 10:18:32 volumio sudo[9125]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 10:18:32 volumio sudo[9125]: pam_unix(sudo:session): session closed for user root Jan 28 10:18:32 volumio volumio[1355]: verbose: New Socket.io Connection to 10.248.249.69 from 10.248.249.252 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/29.0 Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jan 28 10:18:32 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 28 10:18:32 volumio volumio[1355]: info: CoreCommandRouter::volumioGetVisibleSources Jan 28 10:18:32 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 10:18:32 volumio volumio[1355]: info: CoreCommandRouter::volumioGetState Jan 28 10:18:32 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 28 10:18:32 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 28 10:18:32 volumio volumio[1355]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 28 10:18:32 volumio volumio[1355]: info: Received Get System Info Jan 28 10:18:32 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 10:18:32 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 10:18:32 volumio volumio[1355]: info: Discovery: Getting this device information Jan 28 10:18:32 volumio volumio[1355]: info: CoreCommandRouter::volumioGetState Jan 28 10:18:32 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 10:18:32 volumio volumio[1355]: info: CoreCommandRouter::volumioGetState Jan 28 10:18:32 volumio volumio[1355]: info: Listing playlists Jan 28 10:18:32 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 28 10:18:32 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 28 10:18:32 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 28 10:18:33 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 28 10:18:34 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 28 10:18:34 volumio volumio[1355]: info: Received Get System Info Jan 28 10:18:34 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 10:18:34 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 10:18:34 volumio volumio[1355]: info: Discovery: Getting this device information Jan 28 10:18:34 volumio volumio[1355]: info: CoreCommandRouter::volumioGetState Jan 28 10:18:34 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 10:18:35 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 28 10:18:35 volumio volumio[1355]: info: Received Get System Info Jan 28 10:18:35 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 10:18:35 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 10:18:35 volumio volumio[1355]: info: Discovery: Getting this device information Jan 28 10:18:35 volumio volumio[1355]: info: CoreCommandRouter::volumioGetState Jan 28 10:18:35 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 10:18:37 volumio volumio[1355]: info: CoreCommandRouter::volumioGetQueue Jan 28 10:18:37 volumio volumio[1355]: info: CoreStateMachine::getQueue Jan 28 10:18:37 volumio volumio[1355]: info: CorePlayQueue::getQueue Jan 28 10:18:40 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jan 28 10:18:40 volumio volumio[1355]: info: browseTIDALUri took 350 milliseconds Jan 28 10:18:40 volumio volumio[1355]: info: Preload queue cleared Jan 28 10:18:40 volumio volumio[1355]: info: Preload queue cleared Jan 28 10:18:42 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 28 10:18:42 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jan 28 10:18:43 volumio volumio[1355]: info: browseTIDALUri took 253 milliseconds Jan 28 10:18:43 volumio volumio[1355]: info: Preload queue cleared Jan 28 10:18:43 volumio volumio[1355]: info: Preload queue cleared Jan 28 10:18:43 volumio go-librespot[4069]: time="2026-01-28T10:18:43+02:00" level=trace msg="sent dealer ping" Jan 28 10:18:44 volumio go-librespot[4069]: time="2026-01-28T10:18:44+02:00" level=trace msg="received dealer pong" Jan 28 10:18:47 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jan 28 10:18:47 volumio volumio[1355]: info: browseTIDALUri took 410 milliseconds Jan 28 10:18:47 volumio volumio[1355]: info: Preload queue cleared Jan 28 10:18:47 volumio volumio[1355]: info: Preloading song: tidal://song/111171547 Jan 28 10:18:47 volumio volumio[1355]: info: Preloading song: tidal://song/111171548 Jan 28 10:18:47 volumio volumio[1355]: info: Preloading song: tidal://song/111171550 Jan 28 10:18:47 volumio volumio[1355]: info: Preloading song: tidal://song/111171552 Jan 28 10:18:47 volumio volumio[1355]: info: Preloading song: tidal://song/111171553 Jan 28 10:18:47 volumio volumio[1355]: info: Preloading song: tidal://song/111171555 Jan 28 10:18:47 volumio volumio[1355]: info: Preloading song: tidal://song/111171556 Jan 28 10:18:47 volumio volumio[1355]: info: Preloading song: tidal://song/111171558 Jan 28 10:18:47 volumio volumio[1355]: info: Preloading song: tidal://song/111171560 Jan 28 10:18:47 volumio volumio[1355]: info: Preloading song: tidal://song/111171561 Jan 28 10:18:47 volumio volumio[1355]: info: Preloading song: tidal://song/111171563 Jan 28 10:18:47 volumio volumio[1355]: info: Preloading song: tidal://song/111171564 Jan 28 10:18:47 volumio volumio[1355]: info: Preloading song: tidal://song/111171566 Jan 28 10:18:47 volumio volumio[1355]: info: Exploding uri tidal://song/111171547 in service tidal Jan 28 10:18:47 volumio volumio[1355]: info: Exploding uri tidal://song/111171548 in service tidal Jan 28 10:18:47 volumio volumio[1355]: info: Executing endpoint getSimilarAlbums Jan 28 10:18:47 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jan 28 10:18:47 volumio volumio[1355]: info: Exploding uri tidal://song/111171550 in service tidal Jan 28 10:18:47 volumio volumio[1355]: info: Executing endpoint metavolumio Jan 28 10:18:47 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 10:18:47 volumio volumio[1355]: info: Executing endpoint metavolumio Jan 28 10:18:47 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 10:18:47 volumio volumio[1355]: info: Exploding uri tidal://song/111171552 in service tidal Jan 28 10:18:47 volumio volumio[1355]: info: Exploding uri tidal://song/111171553 in service tidal Jan 28 10:18:47 volumio volumio[1355]: info: explodeTIDALUri took 236 milliseconds Jan 28 10:18:47 volumio volumio[1355]: info: Exploding uri tidal://song/111171555 in service tidal Jan 28 10:18:47 volumio volumio[1355]: info: Exploding uri tidal://song/111171556 in service tidal Jan 28 10:18:47 volumio volumio[1355]: info: Exploding uri tidal://song/111171558 in service tidal Jan 28 10:18:48 volumio volumio[1355]: info: Exploding uri tidal://song/111171560 in service tidal Jan 28 10:18:48 volumio volumio[1355]: info: Exploding uri tidal://song/111171561 in service tidal Jan 28 10:18:48 volumio volumio[1355]: info: explodeTIDALUri took 435 milliseconds Jan 28 10:18:48 volumio volumio[1355]: info: explodeTIDALUri took 225 milliseconds Jan 28 10:18:48 volumio volumio[1355]: info: Exploding uri tidal://song/111171563 in service tidal Jan 28 10:18:48 volumio volumio[1355]: info: explodeTIDALUri took 417 milliseconds Jan 28 10:18:48 volumio volumio[1355]: info: Exploding uri tidal://song/111171564 in service tidal Jan 28 10:18:48 volumio volumio[1355]: info: explodeTIDALUri took 409 milliseconds Jan 28 10:18:48 volumio volumio[1355]: info: Exploding uri tidal://song/111171566 in service tidal Jan 28 10:18:48 volumio volumio[1355]: info: explodeTIDALUri took 414 milliseconds Jan 28 10:18:48 volumio volumio[1355]: info: explodeTIDALUri took 217 milliseconds Jan 28 10:18:48 volumio volumio[1355]: info: explodeTIDALUri took 522 milliseconds Jan 28 10:18:48 volumio volumio[1355]: info: explodeTIDALUri took 249 milliseconds Jan 28 10:18:48 volumio volumio[1355]: info: explodeTIDALUri took 419 milliseconds Jan 28 10:18:48 volumio volumio[1355]: info: explodeTIDALUri took 404 milliseconds Jan 28 10:18:48 volumio volumio[1355]: info: explodeTIDALUri took 317 milliseconds Jan 28 10:18:48 volumio volumio[1355]: info: explodeTIDALUri took 568 milliseconds Jan 28 10:18:49 volumio volumio[1355]: info: Preload queue cleared Jan 28 10:18:49 volumio volumio[1355]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 28 10:18:49 volumio volumio[1355]: info: CoreStateMachine::ClearQueue Jan 28 10:18:49 volumio volumio[1355]: info: CoreStateMachine::stop Jan 28 10:18:49 volumio volumio[1355]: info: CoreStateMachine::serviceStop Jan 28 10:18:49 volumio volumio[1355]: info: CoreCommandRouter::serviceStop Jan 28 10:18:49 volumio volumio[1355]: info: CorePlayQueue::clearPlayQueue Jan 28 10:18:49 volumio volumio[1355]: info: CorePlayQueue::saveQueue Jan 28 10:18:49 volumio volumio[1355]: info: CoreCommandRouter::volumioPushQueue Jan 28 10:18:49 volumio volumio[1355]: info: CoreStateMachine::addQueueItems Jan 28 10:18:49 volumio volumio[1355]: info: CorePlayQueue::addQueueItems Jan 28 10:18:49 volumio volumio[1355]: info: Preload queue cleared Jan 28 10:18:49 volumio volumio[1355]: info: Adding Item to queue: tidal://album/111171545 Jan 28 10:18:49 volumio volumio[1355]: info: Exploding uri tidal://album/111171545 in service tidal Jan 28 10:18:49 volumio vtcs[3508]: [close:85] Entering Jan 28 10:18:49 volumio volumio[1355]: info: PlaybackInactive Jan 28 10:18:49 volumio volumio[1355]: info: Device Session is_active: true Jan 28 10:18:49 volumio volumio[1355]: info: DeviceInactive Jan 28 10:18:49 volumio volumio[1355]: info: Relinquishing Volumio State Jan 28 10:18:49 volumio volumio[1355]: verbose: UNSET VOLATILE: Service: tidalconnect Jan 28 10:18:49 volumio volumio[1355]: info: unSetVolatile called Jan 28 10:18:49 volumio volumio[1355]: info: Relinquishing Volumio State to another service Jan 28 10:18:49 volumio vtcs[3508]: [2026-01-28 10:18:49.655] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jan 28 10:18:49 volumio vtcs[3508]: [feedThread:276] Exiting Jan 28 10:18:49 volumio vtcs[3508]: [close:100] Exiting Jan 28 10:18:49 volumio vtcs[3508]: [2026-01-28 10:18:49] [info] asio async_shutdown error: asio.misc:2 (End of file) Jan 28 10:18:49 volumio volumio[1355]: info: explodeTIDALUri took 210 milliseconds Jan 28 10:18:49 volumio volumio[1355]: info: CoreCommandRouter::volumioPushQueue Jan 28 10:18:49 volumio volumio[1355]: info: CorePlayQueue::saveQueue Jan 28 10:18:49 volumio volumio[1355]: info: CoreStateMachine::updateTrackBlock Jan 28 10:18:49 volumio volumio[1355]: info: CorePlayQueue::getTrackBlock Jan 28 10:18:49 volumio volumio[1355]: info: CoreCommandRouter::volumioGetState Jan 28 10:18:49 volumio volumio[1355]: info: CorePlayQueue::getTrack 0 Jan 28 10:18:49 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Jan 28 10:18:49 volumio volumio[1355]: info: CoreCommandRouter::volumioPlay Jan 28 10:18:49 volumio volumio[1355]: info: CoreStateMachine::play index 0 Jan 28 10:18:49 volumio volumio[1355]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 10:18:49 volumio volumio[1355]: info: CoreStateMachine::stop Jan 28 10:18:49 volumio volumio[1355]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 10:18:49 volumio volumio[1355]: info: CoreStateMachine::stPlaybackTimer Jan 28 10:18:49 volumio volumio[1355]: info: CoreStateMachine::updateTrackBlock Jan 28 10:18:49 volumio volumio[1355]: info: CorePlayQueue::getTrackBlock Jan 28 10:18:49 volumio volumio[1355]: info: CoreStateMachine::pushState Jan 28 10:18:49 volumio volumio[1355]: info: CorePlayQueue::getTrack 0 Jan 28 10:18:49 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 10:18:49 volumio volumio[1355]: info: CoreCommandRouter::volumioPushState Jan 28 10:18:49 volumio volumio[1355]: info: MRS: Pushing multiroomSync output update for this device Jan 28 10:18:49 volumio volumio[1355]: info: MRS: Pushing multiroomSync output Jan 28 10:18:49 volumio volumio[1355]: info: CoreCommandRouter::volumioGetState Jan 28 10:18:49 volumio volumio[1355]: info: CorePlayQueue::getTrack 0 Jan 28 10:18:49 volumio volumio[1355]: info: CoreStateMachine::serviceStop Jan 28 10:18:49 volumio volumio[1355]: info: CorePlayQueue::getTrack 0 Jan 28 10:18:49 volumio volumio[1355]: info: CoreCommandRouter::serviceStop Jan 28 10:18:49 volumio volumio[1355]: info: [1769588329864] ControllerTidal::stop Jan 28 10:18:49 volumio volumio[1355]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 28 10:18:49 volumio volumio[1355]: info: ControllerMpd::stop Jan 28 10:18:49 volumio volumio[1355]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 10:18:49 volumio volumio[1355]: info: sendMpdCommand stop took 7 milliseconds Jan 28 10:18:49 volumio volumio[1355]: info: CoreStateMachine::play index undefined Jan 28 10:18:49 volumio volumio[1355]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 10:18:49 volumio volumio[1355]: info: CorePlayQueue::getTrack 0 Jan 28 10:18:49 volumio volumio[1355]: info: CoreStateMachine::startPlaybackTimer Jan 28 10:18:49 volumio volumio[1355]: info: CorePlayQueue::getTrack 0 Jan 28 10:18:49 volumio volumio[1355]: info: [1769588329873] ControllerTidal::clearAddPlayTrack Jan 28 10:18:49 volumio volumio[1355]: info: Getting stream with soundQuality LOSSLESS Jan 28 10:18:50 volumio volumio[1355]: info: getStreamUrl took 276 milliseconds Jan 28 10:18:50 volumio volumio[1355]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 10:18:50 volumio volumio[1355]: info: sendMpdCommand stop took 1 milliseconds Jan 28 10:18:50 volumio volumio[1355]: verbose: ControllerMpd::sendMpdCommand clear Jan 28 10:18:50 volumio volumio[1355]: info: Jan 28 10:18:50 volumio volumio[1355]: ---------------------------- MPD announces system playlist update Jan 28 10:18:50 volumio volumio[1355]: info: Ignoring MPD Status Update Jan 28 10:18:50 volumio volumio[1355]: info: sendMpdCommand clear took 0 milliseconds Jan 28 10:18:50 volumio volumio[1355]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic2ZDIyMjJhNmRhOGM4ZTZhNmQzMWIwYzk0Y2U5NWNmOV82MS5tcDQ/0.flac?token=1769591930~NjhiZjhjNWNhZjg0Y2VmZGM3MjEyZjE1NDBlZmFmMmI3Y2Q4M2E4Mg==" Jan 28 10:18:50 volumio volumio[1355]: info: Jan 28 10:18:50 volumio volumio[1355]: ---------------------------- MPD announces system playlist update Jan 28 10:18:50 volumio volumio[1355]: info: Ignoring MPD Status Update Jan 28 10:18:50 volumio volumio[1355]: error: updateQueue error: null Jan 28 10:18:50 volumio volumio[1355]: info: Jan 28 10:18:50 volumio volumio[1355]: ---------------------------- MPD announces system playlist update Jan 28 10:18:50 volumio volumio[1355]: info: Ignoring MPD Status Update Jan 28 10:18:50 volumio volumio[1355]: info: ------------------------------ 2ms Jan 28 10:18:50 volumio volumio[1355]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic2ZDIyMjJhNmRhOGM4ZTZhNmQzMWIwYzk0Y2U5NWNmOV82MS5tcDQ/0.flac?token=1769591930~NjhiZjhjNWNhZjg0Y2VmZGM3MjEyZjE1NDBlZmFmMmI3Y2Q4M2E4Mg==" took 1 milliseconds Jan 28 10:18:50 volumio volumio[1355]: info: ------------------------------ 1ms Jan 28 10:18:50 volumio volumio[1355]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 28 10:18:50 volumio volumio[1355]: verbose: ControllerMpd::sendMpdCommand play Jan 28 10:18:50 volumio volumio[1355]: info: Jan 28 10:18:50 volumio volumio[1355]: ---------------------------- MPD announces system playlist update Jan 28 10:18:50 volumio volumio[1355]: info: Ignoring MPD Status Update Jan 28 10:18:50 volumio volumio[1355]: info: ------------------------------ 3ms Jan 28 10:18:50 volumio volumio[1355]: info: sendMpdCommand play took 3 milliseconds Jan 28 10:18:50 volumio volumio[1355]: info: ------------------------------ 2ms Jan 28 10:18:50 volumio volumio[1355]: info: Jan 28 10:18:50 volumio volumio[1355]: ---------------------------- MPD announces state update: player Jan 28 10:18:50 volumio volumio[1355]: info: ControllerMpd::getState Jan 28 10:18:50 volumio volumio[1355]: verbose: ControllerMpd::sendMpdCommand status Jan 28 10:18:50 volumio volumio[1355]: info: Jan 28 10:18:50 volumio volumio[1355]: ---------------------------- MPD announces state update: player Jan 28 10:18:50 volumio volumio[1355]: info: ControllerMpd::getState Jan 28 10:18:50 volumio volumio[1355]: verbose: ControllerMpd::sendMpdCommand status Jan 28 10:18:50 volumio volumio[1355]: info: Jan 28 10:18:50 volumio volumio[1355]: ---------------------------- MPD announces state update: player Jan 28 10:18:50 volumio volumio[1355]: info: ControllerMpd::getState Jan 28 10:18:50 volumio volumio[1355]: verbose: ControllerMpd::sendMpdCommand status Jan 28 10:18:50 volumio volumio[1355]: info: Jan 28 10:18:50 volumio volumio[1355]: ---------------------------- MPD announces state update: player Jan 28 10:18:50 volumio volumio[1355]: info: sendMpdCommand status took 1 milliseconds Jan 28 10:18:50 volumio volumio[1355]: info: ControllerMpd::getState Jan 28 10:18:50 volumio volumio[1355]: verbose: ControllerMpd::sendMpdCommand status Jan 28 10:18:50 volumio volumio[1355]: info: sendMpdCommand status took 1 milliseconds Jan 28 10:18:50 volumio volumio[1355]: info: sendMpdCommand status took 1 milliseconds Jan 28 10:18:50 volumio volumio[1355]: verbose: ControllerMpd::parseState Jan 28 10:18:50 volumio volumio[1355]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 10:18:50 volumio volumio[1355]: verbose: ControllerMpd::parseState Jan 28 10:18:50 volumio volumio[1355]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 10:18:50 volumio volumio[1355]: verbose: ControllerMpd::parseState Jan 28 10:18:50 volumio volumio[1355]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 10:18:50 volumio volumio[1355]: info: sendMpdCommand status took 1 milliseconds Jan 28 10:18:50 volumio volumio[1355]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 28 10:18:50 volumio volumio[1355]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 28 10:18:50 volumio volumio[1355]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 28 10:18:50 volumio volumio[1355]: verbose: ControllerMpd::parseState Jan 28 10:18:50 volumio volumio[1355]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 10:18:50 volumio volumio[1355]: verbose: ControllerMpd::parseTrackInfo Jan 28 10:18:50 volumio volumio[1355]: verbose: ControllerMpd::parseTrackInfo Jan 28 10:18:50 volumio volumio[1355]: verbose: ControllerMpd::parseTrackInfo Jan 28 10:18:50 volumio volumio[1355]: info: ControllerMpd::pushState Jan 28 10:18:50 volumio volumio[1355]: info: CoreCommandRouter::servicePushState Jan 28 10:18:50 volumio volumio[1355]: info: CorePlayQueue::getTrack 0 Jan 28 10:18:50 volumio volumio[1355]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1769591930~NjhiZjhjNWNhZjg0Y2VmZGM3MjEyZjE1NDBlZmFmMmI3Y2Q4M2E4Mg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic2ZDIyMjJhNmRhOGM4ZTZhNmQzMWIwYzk0Y2U5NWNmOV82MS5tcDQ/0.flac?token=1769591930~NjhiZjhjNWNhZjg0Y2VmZGM3MjEyZjE1NDBlZmFmMmI3Y2Q4M2E4Mg==","trackType":"tidal"} Jan 28 10:18:50 volumio volumio[1355]: verbose: CURRENT POSITION 0 Jan 28 10:18:50 volumio volumio[1355]: info: CoreStateMachine::syncState stateService play Jan 28 10:18:50 volumio volumio[1355]: info: CoreStateMachine::syncState currentStatus stop Jan 28 10:18:50 volumio volumio[1355]: info: ControllerMpd::pushState Jan 28 10:18:50 volumio volumio[1355]: info: CoreCommandRouter::servicePushState Jan 28 10:18:50 volumio volumio[1355]: info: CorePlayQueue::getTrack 0 Jan 28 10:18:50 volumio volumio[1355]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1769591930~NjhiZjhjNWNhZjg0Y2VmZGM3MjEyZjE1NDBlZmFmMmI3Y2Q4M2E4Mg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic2ZDIyMjJhNmRhOGM4ZTZhNmQzMWIwYzk0Y2U5NWNmOV82MS5tcDQ/0.flac?token=1769591930~NjhiZjhjNWNhZjg0Y2VmZGM3MjEyZjE1NDBlZmFmMmI3Y2Q4M2E4Mg==","trackType":"tidal"} Jan 28 10:18:50 volumio volumio[1355]: verbose: CURRENT POSITION 0 Jan 28 10:18:50 volumio volumio[1355]: info: CoreStateMachine::syncState stateService play Jan 28 10:18:50 volumio volumio[1355]: info: CoreStateMachine::syncState currentStatus play Jan 28 10:18:50 volumio volumio[1355]: info: Received an update from plugin. extracting info from payload Jan 28 10:18:50 volumio volumio[1355]: info: CoreStateMachine::pushState Jan 28 10:18:50 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 10:18:50 volumio volumio[1355]: info: CoreCommandRouter::volumioPushState Jan 28 10:18:50 volumio volumio[1355]: info: MRS: Pushing multiroomSync output update for this device Jan 28 10:18:50 volumio volumio[1355]: info: MRS: Pushing multiroomSync output Jan 28 10:18:50 volumio volumio[1355]: info: CoreCommandRouter::volumioGetState Jan 28 10:18:50 volumio volumio[1355]: info: CoreStateMachine::pushState Jan 28 10:18:50 volumio volumio[1355]: info: CoreCommandRouter::volumioPushState Jan 28 10:18:50 volumio volumio[1355]: info: MRS: Pushing multiroomSync output update for this device Jan 28 10:18:50 volumio volumio[1355]: info: MRS: Pushing multiroomSync output Jan 28 10:18:50 volumio volumio[1355]: info: CoreCommandRouter::volumioGetState Jan 28 10:18:50 volumio volumio[1355]: info: ControllerMpd::pushState Jan 28 10:18:50 volumio volumio[1355]: info: CoreCommandRouter::servicePushState Jan 28 10:18:50 volumio volumio[1355]: info: CorePlayQueue::getTrack 0 Jan 28 10:18:50 volumio volumio[1355]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1769591930~NjhiZjhjNWNhZjg0Y2VmZGM3MjEyZjE1NDBlZmFmMmI3Y2Q4M2E4Mg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic2ZDIyMjJhNmRhOGM4ZTZhNmQzMWIwYzk0Y2U5NWNmOV82MS5tcDQ/0.flac?token=1769591930~NjhiZjhjNWNhZjg0Y2VmZGM3MjEyZjE1NDBlZmFmMmI3Y2Q4M2E4Mg==","trackType":"tidal"} Jan 28 10:18:50 volumio volumio[1355]: verbose: CURRENT POSITION 0 Jan 28 10:18:50 volumio volumio[1355]: info: CoreStateMachine::syncState stateService play Jan 28 10:18:50 volumio volumio[1355]: info: CoreStateMachine::syncState currentStatus play Jan 28 10:18:50 volumio volumio[1355]: info: Received an update from plugin. extracting info from payload Jan 28 10:18:50 volumio volumio[1355]: info: CoreStateMachine::pushState Jan 28 10:18:50 volumio volumio[1355]: info: CoreCommandRouter::volumioPushState Jan 28 10:18:50 volumio volumio[1355]: info: MRS: Pushing multiroomSync output update for this device Jan 28 10:18:50 volumio volumio[1355]: info: MRS: Pushing multiroomSync output Jan 28 10:18:50 volumio volumio[1355]: info: CoreCommandRouter::volumioGetState Jan 28 10:18:50 volumio volumio[1355]: info: CoreStateMachine::pushState Jan 28 10:18:50 volumio volumio[1355]: info: CoreCommandRouter::volumioPushState Jan 28 10:18:50 volumio volumio[1355]: info: MRS: Pushing multiroomSync output update for this device Jan 28 10:18:50 volumio volumio[1355]: info: MRS: Pushing multiroomSync output Jan 28 10:18:50 volumio volumio[1355]: info: CoreCommandRouter::volumioGetState Jan 28 10:18:50 volumio volumio[1355]: info: ------------------------------ 11ms Jan 28 10:18:50 volumio volumio[1355]: info: ------------------------------ 16ms Jan 28 10:18:50 volumio volumio[1355]: info: ------------------------------ 15ms Jan 28 10:18:50 volumio volumio[1355]: info: sendMpdCommand playlistinfo took 13 milliseconds Jan 28 10:18:50 volumio volumio[1355]: verbose: ControllerMpd::parseTrackInfo Jan 28 10:18:50 volumio volumio[1355]: info: ControllerMpd::pushState Jan 28 10:18:50 volumio volumio[1355]: info: CoreCommandRouter::servicePushState Jan 28 10:18:50 volumio volumio[1355]: info: CorePlayQueue::getTrack 0 Jan 28 10:18:50 volumio volumio[1355]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1769591930~NjhiZjhjNWNhZjg0Y2VmZGM3MjEyZjE1NDBlZmFmMmI3Y2Q4M2E4Mg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic2ZDIyMjJhNmRhOGM4ZTZhNmQzMWIwYzk0Y2U5NWNmOV82MS5tcDQ/0.flac?token=1769591930~NjhiZjhjNWNhZjg0Y2VmZGM3MjEyZjE1NDBlZmFmMmI3Y2Q4M2E4Mg==","trackType":"tidal"} Jan 28 10:18:50 volumio volumio[1355]: verbose: CURRENT POSITION 0 Jan 28 10:18:50 volumio volumio[1355]: info: CoreStateMachine::syncState stateService play Jan 28 10:18:50 volumio volumio[1355]: info: CoreStateMachine::syncState currentStatus play Jan 28 10:18:50 volumio volumio[1355]: info: Received an update from plugin. extracting info from payload Jan 28 10:18:50 volumio volumio[1355]: info: CoreStateMachine::pushState Jan 28 10:18:50 volumio volumio[1355]: info: CoreCommandRouter::volumioPushState Jan 28 10:18:50 volumio volumio[1355]: info: MRS: Pushing multiroomSync output update for this device Jan 28 10:18:50 volumio volumio[1355]: info: MRS: Pushing multiroomSync output Jan 28 10:18:50 volumio volumio[1355]: info: CoreCommandRouter::volumioGetState Jan 28 10:18:50 volumio volumio[1355]: info: CoreStateMachine::pushState Jan 28 10:18:50 volumio volumio[1355]: info: CoreCommandRouter::volumioPushState Jan 28 10:18:50 volumio volumio[1355]: info: MRS: Pushing multiroomSync output update for this device Jan 28 10:18:50 volumio volumio[1355]: info: MRS: Pushing multiroomSync output Jan 28 10:18:50 volumio volumio[1355]: info: CoreCommandRouter::volumioGetState Jan 28 10:18:50 volumio volumio[1355]: info: ------------------------------ 22ms Jan 28 10:18:50 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 28 10:18:50 volumio volumio[1355]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 28 10:18:50 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 28 10:18:50 volumio volumio[1355]: info: Received Get System Version Jan 28 10:18:50 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 10:18:50 volumio volumio[1355]: info: Received Get System Info Jan 28 10:18:50 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 10:18:50 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 10:18:50 volumio volumio[1355]: info: Discovery: Getting this device information Jan 28 10:18:50 volumio volumio[1355]: info: CoreCommandRouter::volumioGetState Jan 28 10:18:50 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 10:18:55 volumio volumio[1355]: info: Executing endpoint metavolumio Jan 28 10:18:55 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 10:18:55 volumio volumio[1355]: info: Executing endpoint metavolumio Jan 28 10:18:55 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 10:18:59 volumio go-librespot[4069]: time="2026-01-28T10:18:59+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1430" Jan 28 10:18:59 volumio go-librespot[4069]: time="2026-01-28T10:18:59+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jan 28 10:18:59 volumio go-librespot[4069]: time="2026-01-28T10:18:59+02:00" level=info msg="playback was transferred to " Jan 28 10:18:59 volumio go-librespot[4069]: time="2026-01-28T10:18:59+02:00" level=trace msg="closed output device because of stop command" Jan 28 10:18:59 volumio go-librespot[4069]: time="2026-01-28T10:18:59+02:00" level=debug msg="put connect state inactive" Jan 28 10:18:59 volumio go-librespot[4069]: time="2026-01-28T10:18:59+02:00" level=trace msg="emitting websocket event: inactive" Jan 28 10:18:59 volumio volumio[1355]: SPOTIFY: received: {"type":"inactive","data":null} Jan 28 10:18:59 volumio volumio[1355]: error: Failed to decode event: inactive Jan 28 10:18:59 volumio volumio[1355]: SPOTIFY: received: {"type":"inactive","data":null} Jan 28 10:18:59 volumio volumio[1355]: error: Failed to decode event: inactive Jan 28 10:19:00 volumio go-librespot[4069]: time="2026-01-28T10:19:00+02:00" level=debug msg="dealer connection closed" Jan 28 10:19:00 volumio go-librespot[4069]: time="2026-01-28T10:19:00+02:00" level=debug msg="dealer recv loop stopped" Jan 28 10:19:00 volumio go-librespot[4069]: time="2026-01-28T10:19:00+02:00" level=debug msg="obtained new client token: AADwC+VY+wavGeNaKIVUZmngazlduf3SMTIjjEvVK0y1aS7EaQXbmQyfZ3NSHSlWcdoasFU09NJijKshJ5H+ReirDfqxH6ZRJgwmSWBEBYAxeCWCIO+tzXFs71HMEURx5CU46A3f/Dd7UEimZJOeB+QCk5CGFajGxpruq/00ZhKwLSmzA9s1c8WqLDzWw9Ze/vudkhCALsLitEF1tUgXRU41vPJ7eCZakuIfwrRBACssAQPHrSoYuxk=" Jan 28 10:19:00 volumio go-librespot[4069]: time="2026-01-28T10:19:00+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jan 28 10:19:00 volumio go-librespot[4069]: time="2026-01-28T10:19:00+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 28 10:19:01 volumio go-librespot[4069]: time="2026-01-28T10:19:01+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Jan 28 10:19:01 volumio go-librespot[4069]: time="2026-01-28T10:19:01+02:00" level=debug msg="completed keyexchange" Jan 28 10:19:01 volumio go-librespot[4069]: time="2026-01-28T10:19:01+02:00" level=debug msg="completed challenge" Jan 28 10:19:01 volumio go-librespot[4069]: time="2026-01-28T10:19:01+02:00" level=info msg="authenticated AP" username="vz*********************j5" Jan 28 10:19:02 volumio go-librespot[4069]: time="2026-01-28T10:19:02+02:00" level=info msg="authenticated Login5" username="vz*********************j5" Jan 28 10:19:02 volumio go-librespot[4069]: time="2026-01-28T10:19:02+02:00" level=debug msg="restored session after logout" username="vz*********************j5" Jan 28 10:19:02 volumio go-librespot[4069]: time="2026-01-28T10:19:02+02:00" level=debug msg="dealer connection opened" Jan 28 10:19:02 volumio go-librespot[4069]: time="2026-01-28T10:19:02+02:00" level=trace msg="starting accesspoint recv loop" Jan 28 10:19:02 volumio go-librespot[4069]: time="2026-01-28T10:19:02+02:00" level=trace msg="starting dealer recv loop" Jan 28 10:19:02 volumio go-librespot[4069]: time="2026-01-28T10:19:02+02:00" level=trace msg="received accesspoint ping" Jan 28 10:19:02 volumio go-librespot[4069]: time="2026-01-28T10:19:02+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 28 10:19:02 volumio go-librespot[4069]: time="2026-01-28T10:19:02+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 28 10:19:02 volumio go-librespot[4069]: time="2026-01-28T10:19:02+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 28 10:19:02 volumio go-librespot[4069]: time="2026-01-28T10:19:02+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 28 10:19:02 volumio go-librespot[4069]: time="2026-01-28T10:19:02+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 28 10:19:02 volumio go-librespot[4069]: time="2026-01-28T10:19:02+02:00" level=debug msg="received connection id: ZGUyYTQ2ZDUtOTZm...ODQyMEJDRERCRQ==" Jan 28 10:19:02 volumio go-librespot[4069]: time="2026-01-28T10:19:02+02:00" level=trace msg="received accesspoint pong ack" Jan 28 10:19:02 volumio go-librespot[4069]: time="2026-01-28T10:19:02+02:00" level=debug msg="put connect state because NEW_DEVICE" Jan 28 10:19:02 volumio go-librespot[4069]: time="2026-01-28T10:19:02+02:00" level=debug msg="update volume requested to 65535/65535" Jan 28 10:19:03 volumio go-librespot[4069]: time="2026-01-28T10:19:03+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Jan 28 10:19:03 volumio go-librespot[4069]: time="2026-01-28T10:19:03+02:00" level=trace msg="emitting websocket event: volume" Jan 28 10:19:03 volumio volumio[1355]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Jan 28 10:19:03 volumio volumio[1355]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Jan 28 10:19:03 volumio volumio[1355]: info: Setting Volumio Volume from Spotify: 100 Jan 28 10:19:03 volumio volumio[1355]: info: VolumeController::SetAlsaVolume100 Jan 28 10:19:03 volumio volumio[1355]: info: CoreStateMachine::pushState Jan 28 10:19:03 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 10:19:03 volumio volumio[1355]: info: CoreCommandRouter::volumioPushState Jan 28 10:19:03 volumio volumio[1355]: info: MRS: Pushing multiroomSync output update for this device Jan 28 10:19:03 volumio volumio[1355]: info: MRS: Pushing multiroomSync output Jan 28 10:19:03 volumio volumio[1355]: info: CoreCommandRouter::volumioGetState Jan 28 10:19:03 volumio volumio[1355]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Jan 28 10:19:03 volumio volumio[1355]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Jan 28 10:19:09 volumio volumio[1355]: Searching plugin music_service/tidal Jan 28 10:19:09 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jan 28 10:19:10 volumio volumio[1355]: info: searchTIDALUri took 484 milliseconds Jan 28 10:19:10 volumio volumio[1355]: info: search took 485 milliseconds Jan 28 10:19:10 volumio volumio[1355]: info: All search sources collected, pushing search results Jan 28 10:19:12 volumio volumio[1355]: Searching plugin music_service/tidal Jan 28 10:19:12 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jan 28 10:19:13 volumio volumio[1355]: info: searchTIDALUri took 440 milliseconds Jan 28 10:19:13 volumio volumio[1355]: info: search took 440 milliseconds Jan 28 10:19:13 volumio volumio[1355]: info: All search sources collected, pushing search results Jan 28 10:19:14 volumio volumio[1355]: Searching plugin music_service/tidal Jan 28 10:19:14 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jan 28 10:19:14 volumio volumio[1355]: info: searchTIDALUri took 343 milliseconds Jan 28 10:19:14 volumio volumio[1355]: info: search took 344 milliseconds Jan 28 10:19:14 volumio volumio[1355]: info: All search sources collected, pushing search results Jan 28 10:19:26 volumio volumio[1355]: Searching plugin music_service/tidal Jan 28 10:19:26 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jan 28 10:19:27 volumio volumio[1355]: info: searchTIDALUri took 781 milliseconds Jan 28 10:19:27 volumio volumio[1355]: info: search took 781 milliseconds Jan 28 10:19:27 volumio volumio[1355]: info: All search sources collected, pushing search results Jan 28 10:19:28 volumio volumio[1355]: Searching plugin music_service/tidal Jan 28 10:19:28 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jan 28 10:19:28 volumio volumio[1355]: info: searchTIDALUri took 476 milliseconds Jan 28 10:19:28 volumio volumio[1355]: info: search took 477 milliseconds Jan 28 10:19:28 volumio volumio[1355]: info: All search sources collected, pushing search results Jan 28 10:19:29 volumio volumio[1355]: Searching plugin music_service/tidal Jan 28 10:19:29 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jan 28 10:19:30 volumio volumio[1355]: info: searchTIDALUri took 503 milliseconds Jan 28 10:19:30 volumio volumio[1355]: info: search took 504 milliseconds Jan 28 10:19:30 volumio volumio[1355]: info: All search sources collected, pushing search results Jan 28 10:19:32 volumio go-librespot[4069]: time="2026-01-28T10:19:32+02:00" level=trace msg="sent dealer ping" Jan 28 10:19:32 volumio go-librespot[4069]: time="2026-01-28T10:19:32+02:00" level=trace msg="received dealer pong" Jan 28 10:19:47 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jan 28 10:19:48 volumio volumio[1355]: info: browseTIDALUri took 1342 milliseconds Jan 28 10:19:48 volumio volumio[1355]: info: Preload queue cleared Jan 28 10:19:48 volumio volumio[1355]: info: Preload queue cleared Jan 28 10:19:48 volumio volumio[1355]: info: Preload queue cleared Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/52900276 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/146918680 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/52900279 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/146918683 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/146326901 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/52900274 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/156965554 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/52247618 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/146918679 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/194669073 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/172478374 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/106459000 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/242574319 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/52900281 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/78210319 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/52900275 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/103804786 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/52900277 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/144602698 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/64622556 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/242574321 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/398073967 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/384070882 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/50969411 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/298361001 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/124301132 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/242574323 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/106459005 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/242574320 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/419103954 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/106459006 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/52900278 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/122514730 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/420095013 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/52900280 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/146918682 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/242574316 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/316823029 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/242574314 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/106459002 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/337852637 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/420095016 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/106459007 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/362401887 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/420095014 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/242574318 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/82030953 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/242574322 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/242574324 Jan 28 10:19:48 volumio volumio[1355]: info: Preloading song: tidal://song/420095019 Jan 28 10:19:48 volumio volumio[1355]: info: Exploding uri tidal://song/52900276 in service tidal Jan 28 10:19:48 volumio volumio[1355]: info: Exploding uri tidal://song/146918680 in service tidal Jan 28 10:19:48 volumio volumio[1355]: info: Executing endpoint getSimilarArtists Jan 28 10:19:48 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 28 10:19:48 volumio volumio[1355]: info: Executing endpoint getSimilarArtists Jan 28 10:19:48 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 28 10:19:48 volumio volumio[1355]: info: Exploding uri tidal://song/52900279 in service tidal Jan 28 10:19:48 volumio volumio[1355]: info: Exploding uri tidal://song/146918683 in service tidal Jan 28 10:19:48 volumio volumio[1355]: info: Executing endpoint metavolumio Jan 28 10:19:48 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 10:19:48 volumio volumio[1355]: info: Executing endpoint metavolumio Jan 28 10:19:48 volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 10:19:48 volumio volumio[1355]: info: Exploding uri tidal://song/146326901 in service tidal Jan 28 10:19:48 volumio volumio[1355]: info: explodeTIDALUri took 239 milliseconds Jan 28 10:19:48 volumio volumio[1355]: info: Exploding uri tidal://song/52900274 in service tidal Jan 28 10:19:48 volumio volumio[1355]: info: Exploding uri tidal://song/156965554 in service tidal Jan 28 10:19:48 volumio volumio[1355]: info: Exploding uri tidal://song/52247618 in service tidal Jan 28 10:19:48 volumio volumio[1355]: info: Exploding uri tidal://song/146918679 in service tidal Jan 28 10:19:49 volumio volumio[1355]: info: Exploding uri tidal://song/194669073 in service tidal Jan 28 10:19:49 volumio volumio[1355]: info: explodeTIDALUri took 250 milliseconds Jan 28 10:19:49 volumio volumio[1355]: info: Exploding uri tidal://song/172478374 in service tidal Jan 28 10:19:49 volumio volumio[1355]: info: explodeTIDALUri took 469 milliseconds Jan 28 10:19:49 volumio volumio[1355]: info: Exploding uri tidal://song/106459000 in service tidal Jan 28 10:19:49 volumio volumio[1355]: info: Exploding uri tidal://song/242574319 in service tidal Jan 28 10:19:49 volumio volumio[1355]: info: explodeTIDALUri took 477 milliseconds Jan 28 10:19:49 volumio volumio[1355]: info: explodeTIDALUri took 539 milliseconds Jan 28 10:19:49 volumio volumio[1355]: info: Exploding uri tidal://song/52900281 in service tidal Jan 28 10:19:49 volumio volumio[1355]: info: Exploding uri tidal://song/78210319 in service tidal Jan 28 10:19:49 volumio volumio[1355]: info: explodeTIDALUri took 519 milliseconds Jan 28 10:19:49 volumio volumio[1355]: info: explodeTIDALUri took 232 milliseconds Jan 28 10:19:49 volumio volumio[1355]: info: Exploding uri tidal://song/52900275 in service tidal Jan 28 10:19:49 volumio volumio[1355]: info: explodeTIDALUri took 233 milliseconds Jan 28 10:19:49 volumio volumio[1355]: info: explodeTIDALUri took 445 milliseconds Jan 28 10:19:49 volumio volumio[1355]: info: Exploding uri tidal://song/103804786 in service tidal Jan 28 10:19:49 volumio volumio[1355]: info: explodeTIDALUri took 526 milliseconds Jan 28 10:19:49 volumio volumio[1355]: info: explodeTIDALUri took 438 milliseconds Jan 28 10:19:49 volumio volumio[1355]: info: Exploding uri tidal://song/52900277 in service tidal Jan 28 10:19:49 volumio volumio[1355]: info: explodeTIDALUri took 244 milliseconds Jan 28 10:19:49 volumio volumio[1355]: info: Exploding uri tidal://song/144602698 in service tidal Jan 28 10:19:49 volumio volumio[1355]: info: explodeTIDALUri took 240 milliseconds Jan 28 10:19:49 volumio volumio[1355]: info: explodeTIDALUri took 494 milliseconds Jan 28 10:19:49 volumio volumio[1355]: info: Exploding uri tidal://song/64622556 in service tidal Jan 28 10:19:49 volumio volumio[1355]: info: Exploding uri tidal://song/242574321 in service tidal Jan 28 10:19:49 volumio volumio[1355]: info: explodeTIDALUri took 271 milliseconds Jan 28 10:19:49 volumio volumio[1355]: info: explodeTIDALUri took 225 milliseconds Jan 28 10:19:49 volumio volumio[1355]: info: explodeTIDALUri took 448 milliseconds Jan 28 10:19:49 volumio volumio[1355]: info: Exploding uri tidal://song/398073967 in service tidal Jan 28 10:19:49 volumio volumio[1355]: info: Exploding uri tidal://song/384070882 in service tidal Jan 28 10:19:49 volumio volumio[1355]: info: Exploding uri tidal://song/50969411 in service tidal Jan 28 10:19:49 volumio volumio[1355]: info: explodeTIDALUri took 254 milliseconds Jan 28 10:19:49 volumio volumio[1355]: info: explodeTIDALUri took 233 milliseconds Jan 28 10:19:49 volumio volumio[1355]: info: explodeTIDALUri took 334 milliseconds Jan 28 10:19:49 volumio volumio[1355]: info: Exploding uri tidal://song/298361001 in service tidal Jan 28 10:19:49 volumio volumio[1355]: info: explodeTIDALUri took 219 milliseconds Jan 28 10:19:49 volumio volumio[1355]: info: Exploding uri tidal://song/124301132 in service tidal Jan 28 10:19:49 volumio volumio[1355]: info: explodeTIDALUri took 232 milliseconds Jan 28 10:19:49 volumio volumio[1355]: info: Exploding uri tidal://song/242574323 in service tidal Jan 28 10:19:49 volumio volumio[1355]: info: explodeTIDALUri took 249 milliseconds Jan 28 10:19:49 volumio volumio[1355]: info: Exploding uri tidal://song/106459005 in service tidal Jan 28 10:19:49 volumio volumio[1355]: info: explodeTIDALUri took 229 milliseconds Jan 28 10:19:49 volumio volumio[1355]: info: Exploding uri tidal://song/242574320 in service tidal Jan 28 10:19:49 volumio volumio[1355]: info: explodeTIDALUri took 217 milliseconds Jan 28 10:19:50 volumio volumio[1355]: info: Exploding uri tidal://song/419103954 in service tidal Jan 28 10:19:50 volumio volumio[1355]: info: explodeTIDALUri took 228 milliseconds Jan 28 10:19:50 volumio volumio[1355]: info: Exploding uri tidal://song/106459006 in service tidal Jan 28 10:19:50 volumio volumio[1355]: info: explodeTIDALUri took 232 milliseconds Jan 28 10:19:50 volumio volumio[1355]: info: Exploding uri tidal://song/52900278 in service tidal Jan 28 10:19:50 volumio volumio[1355]: info: Exploding uri tidal://song/122514730 in service tidal Jan 28 10:19:50 volumio volumio[1355]: info: Exploding uri tidal://song/420095013 in service tidal Jan 28 10:19:50 volumio volumio[1355]: info: explodeTIDALUri took 338 milliseconds Jan 28 10:19:50 volumio volumio[1355]: info: Exploding uri tidal://song/52900280 in service tidal Jan 28 10:19:50 volumio volumio[1355]: info: explodeTIDALUri took 345 milliseconds Jan 28 10:19:50 volumio volumio[1355]: info: explodeTIDALUri took 245 milliseconds Jan 28 10:19:50 volumio volumio[1355]: info: Exploding uri tidal://song/146918682 in service tidal Jan 28 10:19:50 volumio volumio[1355]: info: explodeTIDALUri took 219 milliseconds Jan 28 10:19:50 volumio volumio[1355]: info: Exploding uri tidal://song/242574316 in service tidal Jan 28 10:19:50 volumio volumio[1355]: info: explodeTIDALUri took 353 milliseconds Jan 28 10:19:50 volumio volumio[1355]: info: Exploding uri tidal://song/316823029 in service tidal Jan 28 10:19:50 volumio volumio[1355]: info: explodeTIDALUri took 253 milliseconds Jan 28 10:19:50 volumio volumio[1355]: info: Exploding uri tidal://song/242574314 in service tidal Jan 28 10:19:50 volumio volumio[1355]: info: explodeTIDALUri took 258 milliseconds Jan 28 10:19:50 volumio volumio[1355]: info: explodeTIDALUri took 216 milliseconds Jan 28 10:19:50 volumio volumio[1355]: info: Exploding uri tidal://song/106459002 in service tidal Jan 28 10:19:50 volumio volumio[1355]: info: explodeTIDALUri took 227 milliseconds Jan 28 10:19:50 volumio volumio[1355]: info: Exploding uri tidal://song/337852637 in service tidal Jan 28 10:19:50 volumio volumio[1355]: info: explodeTIDALUri took 226 milliseconds Jan 28 10:19:50 volumio volumio[1355]: info: Exploding uri tidal://song/420095016 in service tidal Jan 28 10:19:50 volumio volumio[1355]: info: explodeTIDALUri took 220 milliseconds Jan 28 10:19:50 volumio volumio[1355]: info: Exploding uri tidal://song/106459007 in service tidal Jan 28 10:19:50 volumio volumio[1355]: info: explodeTIDALUri took 223 milliseconds Jan 28 10:19:50 volumio volumio[1355]: info: Exploding uri tidal://song/362401887 in service tidal Jan 28 10:19:50 volumio volumio[1355]: info: explodeTIDALUri took 231 milliseconds Jan 28 10:19:50 volumio volumio[1355]: info: Exploding uri tidal://song/420095014 in service tidal Jan 28 10:19:50 volumio volumio[1355]: info: explodeTIDALUri took 224 milliseconds Jan 28 10:19:50 volumio volumio[1355]: info: Exploding uri tidal://song/242574318 in service tidal Jan 28 10:19:50 volumio volumio[1355]: info: explodeTIDALUri took 206 milliseconds Jan 28 10:19:50 volumio volumio[1355]: info: Exploding uri tidal://song/82030953 in service tidal Jan 28 10:19:50 volumio volumio[1355]: info: explodeTIDALUri took 219 milliseconds Jan 28 10:19:50 volumio volumio[1355]: info: Exploding uri tidal://song/242574322 in service tidal Jan 28 10:19:50 volumio volumio[1355]: info: explodeTIDALUri took 210 milliseconds Jan 28 10:19:50 volumio volumio[1355]: info: Exploding uri tidal://song/242574324 in service tidal Jan 28 10:19:50 volumio volumio[1355]: info: explodeTIDALUri took 218 milliseconds Jan 28 10:19:51 volumio volumio[1355]: info: Exploding uri tidal://song/420095019 in service tidal Jan 28 10:19:51 volumio volumio[1355]: info: explodeTIDALUri took 223 milliseconds Jan 28 10:19:51 volumio volumio[1355]: info: explodeTIDALUri took 238 milliseconds Jan 28 10:19:51 volumio volumio[1355]: info: explodeTIDALUri took 233 milliseconds Jan 28 10:19:51 volumio volumio[1355]: info: explodeTIDALUri took 230 milliseconds Jan 28 10:19:51 volumio volumio[1355]: info: explodeTIDALUri took 243 milliseconds Jan 28 10:19:54 volumio volumio[1355]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 10:19:54 volumio volumio[1355]: AggregateError Jan 28 10:19:54 volumio volumio[1355]: at internalConnectMultiple (node:net:1114:18) Jan 28 10:19:54 volumio volumio[1355]: at afterConnectMultiple (node:net:1667:5) { Jan 28 10:19:54 volumio volumio[1355]: code: 'ETIMEDOUT', Jan 28 10:19:54 volumio volumio[1355]: [errors]: [ Jan 28 10:19:54 volumio volumio[1355]: Error: connect ETIMEDOUT 158.69.209.125:443 Jan 28 10:19:54 volumio volumio[1355]: at createConnectionError (node:net:1634:14) Jan 28 10:19:54 volumio volumio[1355]: at Timeout.internalConnectMultipleTimeout (node:net:1685:38) Jan 28 10:19:54 volumio volumio[1355]: at listOnTimeout (node:internal/timers:575:11) Jan 28 10:19:54 volumio volumio[1355]: at process.processTimers (node:internal/timers:514:7) { Jan 28 10:19:54 volumio volumio[1355]: errno: -110, Jan 28 10:19:54 volumio volumio[1355]: code: 'ETIMEDOUT', Jan 28 10:19:54 volumio volumio[1355]: syscall: 'connect', Jan 28 10:19:54 volumio volumio[1355]: address: '158.69.209.125', Jan 28 10:19:54 volumio volumio[1355]: port: 443 Jan 28 10:19:54 volumio volumio[1355]: }, Jan 28 10:19:54 volumio volumio[1355]: Error: connect ECONNREFUSED 158.69.210.98:443 Jan 28 10:19:54 volumio volumio[1355]: at createConnectionError (node:net:1634:14) Jan 28 10:19:54 volumio volumio[1355]: at afterConnectMultiple (node:net:1664:40) { Jan 28 10:19:54 volumio volumio[1355]: errno: -111, Jan 28 10:19:54 volumio volumio[1355]: code: 'ECONNREFUSED', Jan 28 10:19:54 volumio volumio[1355]: syscall: 'connect', Jan 28 10:19:54 volumio volumio[1355]: address: '158.69.210.98', Jan 28 10:19:54 volumio volumio[1355]: port: 443 Jan 28 10:19:54 volumio volumio[1355]: } Jan 28 10:19:54 volumio volumio[1355]: ] Jan 28 10:19:54 volumio volumio[1355]: } Jan 28 10:19:54 volumio volumio[1355]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 10:19:54 volumio sudo[9263]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-28 10:18' Jan 28 10:19:54 volumio sudo[9263]: 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"