-- Logs begin at Thu 2026-05-28 23:51:34 EEST, end at Mon 2026-06-01 21:44:31 EEST. -- Jun 01 21:43:12 primo-plus-soli volumio5-onboarding[1508]: time=2026-06-01T21:43:12.117+03:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.2.5:55556 Jun 01 21:43:13 primo-plus-soli volumio[1020]: verbose: New Socket.io Connection to 192.168.2.7 from 192.168.2.5 UA: Mozilla/5.0 (Linux; Android 16; MBH-N49 Build/HONORMBH-N49; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jun 01 21:43:13 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 01 21:43:13 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioGetVisibleSources Jun 01 21:43:13 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 21:43:13 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioGetState Jun 01 21:43:13 primo-plus-soli volumio[1020]: info: CorePlayQueue::getTrack 95 Jun 01 21:43:13 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 01 21:43:13 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 01 21:43:13 primo-plus-soli volumio[1020]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 01 21:43:13 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Jun 01 21:43:13 primo-plus-soli volumio[1020]: info: Received Get System Info Jun 01 21:43:13 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 21:43:13 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 21:43:13 primo-plus-soli volumio[1020]: info: Discovery: Getting this device information Jun 01 21:43:13 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioGetState Jun 01 21:43:13 primo-plus-soli volumio[1020]: info: CorePlayQueue::getTrack 95 Jun 01 21:43:13 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 21:43:13 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioGetState Jun 01 21:43:13 primo-plus-soli volumio[1020]: info: CorePlayQueue::getTrack 95 Jun 01 21:43:13 primo-plus-soli volumio[1020]: info: Listing playlists Jun 01 21:43:13 primo-plus-soli volumio[1020]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 21:43:13 primo-plus-soli volumio[1020]: info: FusionDsp - Jun 01 21:43:13 primo-plus-soli volumio[1020]: info: FusionDsp - undefined Jun 01 21:43:13 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 01 21:43:14 primo-plus-soli volumio5-onboarding[1508]: time=2026-06-01T21:43:14.976+03:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.2.5:55556 @ 0x28c8240" latency=434.469392ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: Received Get System Info Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: Discovery: Getting this device information Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioGetState Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: CorePlayQueue::getTrack 95 Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 21:43:15 primo-plus-soli sudo[15866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 01 21:43:15 primo-plus-soli sudo[15866]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 21:43:15 primo-plus-soli sudo[15866]: pam_unix(sudo:session): session closed for user root Jun 01 21:43:15 primo-plus-soli sudo[15869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 01 21:43:15 primo-plus-soli sudo[15869]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 21:43:15 primo-plus-soli sudo[15869]: pam_unix(sudo:session): session closed for user root Jun 01 21:43:15 primo-plus-soli volumio[1020]: verbose: New Socket.io Connection to 192.168.2.7 from 192.168.2.5 UA: Mozilla/5.0 (Linux; Android 16; MBH-N49 Build/HONORMBH-N49; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jun 01 21:43:15 primo-plus-soli sudo[15872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 01 21:43:15 primo-plus-soli sudo[15872]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 21:43:15 primo-plus-soli sudo[15872]: pam_unix(sudo:session): session closed for user root Jun 01 21:43:15 primo-plus-soli sudo[15875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 01 21:43:15 primo-plus-soli sudo[15875]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 21:43:15 primo-plus-soli sudo[15875]: pam_unix(sudo:session): session closed for user root Jun 01 21:43:15 primo-plus-soli volumio[1020]: verbose: New Socket.io Connection to 192.168.2.7 from 192.168.2.5 UA: Mozilla/5.0 (Linux; Android 16; MBH-N49 Build/HONORMBH-N49; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioGetVisibleSources Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioGetState Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: CorePlayQueue::getTrack 95 Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: Received Get System Info Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: Discovery: Getting this device information Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioGetState Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: CorePlayQueue::getTrack 95 Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioGetState Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: CorePlayQueue::getTrack 95 Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: Listing playlists Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 21:43:15 primo-plus-soli volumio[1020]: info: FusionDsp - Jun 01 21:43:16 primo-plus-soli volumio[1020]: info: FusionDsp - undefined Jun 01 21:43:16 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 01 21:43:17 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 01 21:43:17 primo-plus-soli volumio5-onboarding[1508]: time=2026-06-01T21:43:17.576+03:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.2.5:55556 Jun 01 21:43:17 primo-plus-soli volumio5-onboarding[1508]: time=2026-06-01T21:43:17.577+03:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.2.5:55556 Jun 01 21:43:17 primo-plus-soli volumio5-onboarding[1508]: time=2026-06-01T21:43:17.587+03:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.2.5:41422 Jun 01 21:43:17 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 01 21:43:17 primo-plus-soli volumio[1020]: info: Received Get System Info Jun 01 21:43:17 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 21:43:17 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 21:43:17 primo-plus-soli volumio[1020]: info: Discovery: Getting this device information Jun 01 21:43:17 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioGetState Jun 01 21:43:17 primo-plus-soli volumio[1020]: info: CorePlayQueue::getTrack 95 Jun 01 21:43:17 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 21:43:18 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 01 21:43:18 primo-plus-soli volumio[1020]: info: Received Get System Info Jun 01 21:43:18 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 21:43:18 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 21:43:18 primo-plus-soli volumio[1020]: info: Discovery: Getting this device information Jun 01 21:43:18 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioGetState Jun 01 21:43:18 primo-plus-soli volumio[1020]: info: CorePlayQueue::getTrack 95 Jun 01 21:43:18 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 21:43:18 primo-plus-soli volumio[1020]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 21:43:18 primo-plus-soli volumio[1020]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 21:43:18 primo-plus-soli volumio[1020]: info: Retrieving Cloud Streaming UI Jun 01 21:43:18 primo-plus-soli volumio[1020]: info: Getting Tidal Cloud Configuration Jun 01 21:43:18 primo-plus-soli volumio[1020]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 21:43:18 primo-plus-soli volumio[1020]: info: Getting Qobuz Cloud Configuration Jun 01 21:43:18 primo-plus-soli volumio[1020]: info: Asking plugin for UI Config Jun 01 21:43:18 primo-plus-soli volumio[1020]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 21:43:18 primo-plus-soli volumio[1020]: info: Getting Spotify Cloud Configuration Jun 01 21:43:18 primo-plus-soli volumio[1020]: info: Asking plugin for UI Config Jun 01 21:43:18 primo-plus-soli volumio[1020]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 21:43:18 primo-plus-soli volumio[1020]: info: Saving Spotify Acccount Jun 01 21:43:18 primo-plus-soli volumio[1020]: info: Got it Jun 01 21:43:18 primo-plus-soli volumio[1020]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 01 21:43:19 primo-plus-soli volumio[1020]: info: Got Tidal Cloud Configuration Jun 01 21:43:19 primo-plus-soli volumio[1020]: info: Got it Jun 01 21:43:19 primo-plus-soli volumio[1020]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 21:43:19 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 01 21:43:19 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 01 21:43:19 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 01 21:43:19 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 01 21:43:19 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 21:43:19 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 21:43:19 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 21:43:19 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 21:43:19 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioGetBrowseSources Jun 01 21:43:19 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioGetBrowseSources Jun 01 21:43:19 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioGetBrowseSources Jun 01 21:43:19 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 21:43:19 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 01 21:43:23 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 21:43:25 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 01 21:43:27 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 21:43:28 primo-plus-soli volumio[1020]: info: Disabling MyMusic plugin tidalconnect Jun 01 21:43:28 primo-plus-soli vtcs[1722]: [2026-06-01 21:43:28.347] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jun 01 21:43:28 primo-plus-soli vtcs[1722]: [2026-06-01 21:43:28.348] [tisoc] [error] [SpkconServer.cpp:383] recv error. client fd=8 errorno=104 error=Connection reset by peer Jun 01 21:43:28 primo-plus-soli vtcs[1722]: [2026-06-01 21:43:28.348] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Jun 01 21:43:28 primo-plus-soli volumio[1020]: info: Disabling plugin tidalconnect Jun 01 21:43:28 primo-plus-soli volumio[1020]: info: Done. Jun 01 21:43:28 primo-plus-soli sudo[15895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 01 21:43:28 primo-plus-soli sudo[15895]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 21:43:28 primo-plus-soli systemd[1]: Stopping Volumio Tidal Connect Service... Jun 01 21:43:28 primo-plus-soli systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jun 01 21:43:28 primo-plus-soli systemd[1]: vtcs.service: Succeeded. Jun 01 21:43:28 primo-plus-soli systemd[1]: Stopped Volumio Tidal Connect Service. Jun 01 21:43:28 primo-plus-soli volumio[1020]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 01 21:43:28 primo-plus-soli sudo[15895]: pam_unix(sudo:session): session closed for user root Jun 01 21:43:30 primo-plus-soli volumio[1020]: info: Enabling MyMusic plugin tidalconnect Jun 01 21:43:30 primo-plus-soli volumio[1020]: info: Enabling plugin tidalconnect Jun 01 21:43:30 primo-plus-soli volumio[1020]: info: Loading plugin "tidalconnect"... Jun 01 21:43:30 primo-plus-soli volumio[1020]: info: PLUGIN START: tidalconnect Jun 01 21:43:30 primo-plus-soli sudo[15913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 01 21:43:30 primo-plus-soli sudo[15913]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 21:43:30 primo-plus-soli sudo[15913]: pam_unix(sudo:session): session closed for user root Jun 01 21:43:31 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 21:43:31 primo-plus-soli volumio[1020]: info: TidalConnect service stoped! Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: TidalConnect service stoped! Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 01 21:43:33 primo-plus-soli sudo[15927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 01 21:43:33 primo-plus-soli sudo[15927]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 21:43:33 primo-plus-soli systemd[1]: Started Volumio Tidal Connect Service. Jun 01 21:43:33 primo-plus-soli sudo[15927]: pam_unix(sudo:session): session closed for user root Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: Executing endpoint tc_getconfig Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 01 21:43:33 primo-plus-soli vtcs[15929]: STARTING TidalConnect services, version: 1.6.1 Jun 01 21:43:33 primo-plus-soli vtcs[15929]: STARTED TidalConnect services. Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: Executing endpoint tc_connect Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: Connecting to TidalConnect Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: CoreCommandRouter::servicePushState Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: CoreStateMachine::pushState Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: CorePlayQueue::getTrack 95 Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioPushState Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioGetState Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: CorePlayQueue::getTrack 95 Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: MRS: Pushing multiroomSync output update for this device Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: MRS: Pushing multiroomSync output Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: CorePlayQueue::getTrack 95 Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: CoreCommandRouter::servicePushState Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: CoreStateMachine::pushState Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: CorePlayQueue::getTrack 95 Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioPushState Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioGetState Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: CorePlayQueue::getTrack 95 Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: MRS: Pushing multiroomSync output update for this device Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: MRS: Pushing multiroomSync output Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: CorePlayQueue::getTrack 95 Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Jun 01 21:43:33 primo-plus-soli volumio5-onboarding[1508]: time=2026-06-01T21:43:33.761+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.5:41422 @ 0x28c8ab0" state=STATUS_STOPPED positionMs=312525 volume=67 Jun 01 21:43:33 primo-plus-soli volumio5-onboarding[1508]: time=2026-06-01T21:43:33.762+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.5:41422 @ 0x28c8ab0" id=tidal://song/1547149 title="Apollo XI" Jun 01 21:43:33 primo-plus-soli volumio5-onboarding[1508]: time=2026-06-01T21:43:33.762+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.5:41422 @ 0x28c8ab0" state=STATUS_STOPPED positionMs=312525 volume=67 Jun 01 21:43:33 primo-plus-soli volumio5-onboarding[1508]: time=2026-06-01T21:43:33.762+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.5:41422 @ 0x28c8ab0" id=tidal://song/1547149 title="Apollo XI" Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: Updating RAAT Signal Path Jun 01 21:43:33 primo-plus-soli volumio[1020]: info: Updating RAAT Signal Path Jun 01 21:43:34 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 01 21:43:34 primo-plus-soli volumio[1020]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 01 21:43:34 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 01 21:43:34 primo-plus-soli volumio[1020]: info: Received Get System Version Jun 01 21:43:34 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 01 21:43:34 primo-plus-soli volumio[1020]: info: Received Get System Info Jun 01 21:43:34 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 21:43:34 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 21:43:34 primo-plus-soli volumio[1020]: info: Discovery: Getting this device information Jun 01 21:43:34 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioGetState Jun 01 21:43:34 primo-plus-soli volumio[1020]: info: CorePlayQueue::getTrack 95 Jun 01 21:43:34 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 21:43:35 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 21:43:36 primo-plus-soli volumio[1020]: info: TidalConnect service started! Jun 01 21:43:36 primo-plus-soli volumio[1020]: info: Done. Jun 01 21:43:39 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 21:43:43 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 21:43:47 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 21:43:50 primo-plus-soli volumiologrotate[657]: ls: cannot access '/var/log/samba/log.wb-PRIMO': No such file or directory Jun 01 21:43:50 primo-plus-soli volumiologrotate[657]: ls: cannot access 'PLUS': No such file or directory Jun 01 21:43:50 primo-plus-soli volumiologrotate[657]: ls: cannot access 'SOLI': No such file or directory Jun 01 21:43:51 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 21:43:55 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 21:43:59 primo-plus-soli volumio5-onboarding[1508]: time=2026-06-01T21:43:59.162+03:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.2.5:41422 error="read tcp 192.168.2.7:7331->192.168.2.5:41422: read: connection reset by peer" Jun 01 21:43:59 primo-plus-soli volumio5-onboarding[1508]: time=2026-06-01T21:43:59.162+03:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.2.5:41422 Jun 01 21:43:59 primo-plus-soli volumio5-onboarding[1508]: time=2026-06-01T21:43:59.163+03:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.2.5:41422 Jun 01 21:44:09 primo-plus-soli volumio5-onboarding[1508]: time=2026-06-01T21:44:09.292+03:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.2.5:51866 Jun 01 21:44:10 primo-plus-soli volumio[1020]: verbose: New Socket.io Connection to 192.168.2.7 from 192.168.2.5 UA: Mozilla/5.0 (Linux; Android 16; MBH-N49 Build/HONORMBH-N49; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioGetVisibleSources Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioGetState Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CorePlayQueue::getTrack 95 Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: Received Get System Info Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: Discovery: Getting this device information Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioGetState Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CorePlayQueue::getTrack 95 Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioGetState Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CorePlayQueue::getTrack 95 Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: Listing playlists Jun 01 21:44:10 primo-plus-soli volumio[1020]: verbose: New Socket.io Connection to 192.168.2.7 from 192.168.2.5 UA: Mozilla/5.0 (Linux; Android 16; MBH-N49 Build/HONORMBH-N49; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: FusionDsp - Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: FusionDsp - undefined Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: FusionDsp - Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: FusionDsp - undefined Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioGetVisibleSources Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioGetState Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CorePlayQueue::getTrack 95 Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: Received Get System Info Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: Discovery: Getting this device information Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioGetState Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CorePlayQueue::getTrack 95 Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::volumioGetState Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CorePlayQueue::getTrack 95 Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: Listing playlists Jun 01 21:44:10 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 01 21:44:11 primo-plus-soli volumio[1020]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 21:44:11 primo-plus-soli volumio[1020]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 21:44:11 primo-plus-soli volumio[1020]: info: FusionDsp - Jun 01 21:44:11 primo-plus-soli volumio[1020]: info: FusionDsp - Jun 01 21:44:11 primo-plus-soli volumio[1020]: info: FusionDsp - undefined Jun 01 21:44:11 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 21:44:11 primo-plus-soli volumio[1020]: info: FusionDsp - undefined Jun 01 21:44:14 primo-plus-soli volumio5-onboarding[1508]: time=2026-06-01T21:44:14.774+03:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.2.5:51866 Jun 01 21:44:14 primo-plus-soli volumio5-onboarding[1508]: time=2026-06-01T21:44:14.776+03:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.2.5:51866 Jun 01 21:44:14 primo-plus-soli volumio5-onboarding[1508]: time=2026-06-01T21:44:14.799+03:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.2.5:51888 Jun 01 21:44:15 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 21:44:19 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 21:44:21 primo-plus-soli volumio[1020]: info: Disabling MyMusic plugin tidalconnect Jun 01 21:44:21 primo-plus-soli vtcs[15929]: [2026-06-01 21:44:21.117] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Jun 01 21:44:21 primo-plus-soli vtcs[15929]: [2026-06-01 21:44:21.120] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jun 01 21:44:21 primo-plus-soli volumio[1020]: info: Disabling plugin tidalconnect Jun 01 21:44:21 primo-plus-soli volumio[1020]: info: Done. Jun 01 21:44:21 primo-plus-soli sudo[16070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 01 21:44:21 primo-plus-soli sudo[16070]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 21:44:21 primo-plus-soli systemd[1]: Stopping Volumio Tidal Connect Service... Jun 01 21:44:21 primo-plus-soli systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jun 01 21:44:21 primo-plus-soli systemd[1]: vtcs.service: Succeeded. Jun 01 21:44:21 primo-plus-soli systemd[1]: Stopped Volumio Tidal Connect Service. Jun 01 21:44:21 primo-plus-soli volumio[1020]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 01 21:44:21 primo-plus-soli sudo[16070]: pam_unix(sudo:session): session closed for user root Jun 01 21:44:23 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 21:44:24 primo-plus-soli volumio[1020]: info: TidalConnect service stoped! Jun 01 21:44:27 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 21:44:31 primo-plus-soli volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 21:44:31 primo-plus-soli volumio[1020]: info: CorePlayQueue::getTrack 95 Jun 01 21:44:31 primo-plus-soli volumio[1020]: info: CorePlayQueue::getTrack 11 Jun 01 21:44:31 primo-plus-soli volumio[1020]: info: Prefetching next song Jun 01 21:44:31 primo-plus-soli volumio[1020]: info: [1780339471180] ControllerTidal::prefetch Jun 01 21:44:31 primo-plus-soli volumio[1020]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 21:44:31 primo-plus-soli volumio[1020]: TypeError: Cannot read property 'highestSoundQuality' of undefined Jun 01 21:44:31 primo-plus-soli volumio[1020]: at ControllerTidal.getProperSoundQuality (/volumio/app/plugins/music_service/tidal/tidal_real:1:19583) Jun 01 21:44:31 primo-plus-soli volumio[1020]: at ControllerTidal.prefetch (/volumio/app/plugins/music_service/tidal/tidal_real:1:20287) Jun 01 21:44:31 primo-plus-soli volumio[1020]: at CoreStateMachine.increasePlaybackTimer (/volumio/app/statemachine.js:464:18) Jun 01 21:44:31 primo-plus-soli volumio[1020]: at listOnTimeout (internal/timers.js:554:17) Jun 01 21:44:31 primo-plus-soli volumio[1020]: at processTimers (internal/timers.js:497:7) Jun 01 21:44:31 primo-plus-soli volumio[1020]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 21:44:31 primo-plus-soli sudo[16097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-06-01 21:43 Jun 01 21:44:31 primo-plus-soli sudo[16097]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="5c0e72e4b5817fe66c91c9d15d75ff13b8e3f4ba" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="7f1bd9e83b67dc80eb7e0d85089b6e1994df1be5" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="primoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 27 Apr 2026 05:07:51 PM CEST" VOLUMIO_VERSION="3.915" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Primo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo Plus" VOLUMIO_HASH="2456140bb15fd64b723a123b83bcf589"