-- Logs begin at Thu 2025-12-18 21:50:54 SAST, end at Sat 2025-12-20 18:48:58 SAST. -- Dec 20 18:47:05 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:47:05.113+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=71 Dec 20 18:47:05 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:47:05.605+02:00 level=WARN msg="received acknowledgment for source with no pending writes" component=conn/ble data=4700 addr=71 Dec 20 18:47:10 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:47:10.124+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=71 Dec 20 18:47:10 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:47:10.532+02:00 level=WARN msg="received acknowledgment for source with no pending writes" component=conn/ble data=4700 addr=71 Dec 20 18:47:17 primo-plus volumio[1100]: verbose: New Socket.io Connection to 192.168.68.101 from 192.168.68.112 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Dec 20 18:47:17 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:17 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:17 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:17 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetVisibleSources Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 20 18:47:17 primo-plus volumio[1100]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Dec 20 18:47:17 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:17 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: Listing playlists Dec 20 18:47:17 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:17 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetQueue Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreStateMachine::getQueue Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getQueue Dec 20 18:47:17 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:17 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:17 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetVisibleSources Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 20 18:47:17 primo-plus volumio[1100]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Dec 20 18:47:17 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:17 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: Listing playlists Dec 20 18:47:17 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:17 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetQueue Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreStateMachine::getQueue Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getQueue Dec 20 18:47:17 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:17 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:17 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetVisibleSources Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 20 18:47:17 primo-plus volumio[1100]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Dec 20 18:47:17 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:17 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: Listing playlists Dec 20 18:47:17 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:17 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:17 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:17 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetQueue Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreStateMachine::getQueue Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getQueue Dec 20 18:47:18 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:18 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:18 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetVisibleSources Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 20 18:47:18 primo-plus volumio[1100]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Dec 20 18:47:18 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:18 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: Listing playlists Dec 20 18:47:18 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:18 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetQueue Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreStateMachine::getQueue Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getQueue Dec 20 18:47:18 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:18 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:18 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetVisibleSources Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 20 18:47:18 primo-plus volumio[1100]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Dec 20 18:47:18 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:18 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: Listing playlists Dec 20 18:47:18 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:18 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetQueue Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreStateMachine::getQueue Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getQueue Dec 20 18:47:18 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:18 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:18 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetVisibleSources Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 20 18:47:18 primo-plus volumio[1100]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Dec 20 18:47:18 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:18 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: Listing playlists Dec 20 18:47:18 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:18 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetQueue Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreStateMachine::getQueue Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getQueue Dec 20 18:47:18 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:18 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:18 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetVisibleSources Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 20 18:47:18 primo-plus volumio[1100]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Dec 20 18:47:18 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:18 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: Listing playlists Dec 20 18:47:18 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:18 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetQueue Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreStateMachine::getQueue Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getQueue Dec 20 18:47:18 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:18 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: Received Get System Info Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 18:47:18 primo-plus volumio[1100]: info: Discovery: Getting this device information Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:47:18 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:47:18 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 20 18:47:20 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:47:20.248+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=71 Dec 20 18:47:20 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:47:20.658+02:00 level=WARN msg="received acknowledgment for source with no pending writes" component=conn/ble data=4700 addr=71 Dec 20 18:47:25 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:47:25.270+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=71 Dec 20 18:47:25 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:47:25.821+02:00 level=WARN msg="received acknowledgment for source with no pending writes" component=conn/ble data=4700 addr=71 Dec 20 18:47:27 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 20 18:47:27 primo-plus volumio[1100]: info: CURURI: music-library Dec 20 18:47:27 primo-plus volumio[1100]: info: Preload queue cleared Dec 20 18:47:30 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:47:30.297+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=71 Dec 20 18:47:30 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:47:30.782+02:00 level=WARN msg="received acknowledgment for source with no pending writes" component=conn/ble data=4700 addr=71 Dec 20 18:47:31 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 20 18:47:31 primo-plus volumio[1100]: info: CURURI: music-library/NAS Dec 20 18:47:31 primo-plus volumio[1100]: info: Preload queue cleared Dec 20 18:47:31 primo-plus volumio[1100]: An internal error occurred while serving an albumart. Details: Error: UNKNOWN: unknown error, stat '/mnt/NAS/USB' Dec 20 18:47:31 primo-plus volumio[1100]: at Object.statSync (fs.js:1086:3) Dec 20 18:47:31 primo-plus volumio[1100]: at Object.statSync (/volumio/node_modules/graceful-fs/polyfills.js:308:16) Dec 20 18:47:31 primo-plus volumio[1100]: at processRequest (/volumio/app/plugins/miscellanea/albumart/albumart.js:354:22) Dec 20 18:47:31 primo-plus volumio[1100]: at processExpressRequest (/volumio/app/plugins/miscellanea/albumart/albumart.js:437:17) Dec 20 18:47:31 primo-plus volumio[1100]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 20 18:47:31 primo-plus volumio[1100]: at next (/volumio/node_modules/express/lib/router/route.js:137:13) Dec 20 18:47:31 primo-plus volumio[1100]: at Route.dispatch (/volumio/node_modules/express/lib/router/route.js:112:3) Dec 20 18:47:31 primo-plus volumio[1100]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 20 18:47:31 primo-plus volumio[1100]: at /volumio/node_modules/express/lib/router/index.js:281:22 Dec 20 18:47:31 primo-plus volumio[1100]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Dec 20 18:47:31 primo-plus volumio[1100]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) Dec 20 18:47:31 primo-plus volumio[1100]: at allowCrossDomain (/volumio/http/index.js:34:5) Dec 20 18:47:31 primo-plus volumio[1100]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) Dec 20 18:47:31 primo-plus volumio[1100]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) Dec 20 18:47:31 primo-plus volumio[1100]: at /volumio/node_modules/express/lib/router/index.js:284:7 Dec 20 18:47:31 primo-plus volumio[1100]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) Dec 20 18:47:32 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 20 18:47:32 primo-plus volumio[1100]: info: CURURI: music-library/NAS/USB Dec 20 18:47:32 primo-plus volumio[1100]: info: Preload queue cleared Dec 20 18:47:33 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 20 18:47:33 primo-plus volumio[1100]: info: CURURI: music-library/NAS/USB/WDW Dec 20 18:47:33 primo-plus volumio[1100]: info: Preload queue cleared Dec 20 18:47:35 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:47:35.327+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=71 Dec 20 18:47:35 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:47:35.575+02:00 level=WARN msg="received acknowledgment for source with no pending writes" component=conn/ble data=4700 addr=71 Dec 20 18:47:50 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:47:50.415+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=71 Dec 20 18:47:50 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:47:50.627+02:00 level=WARN msg="received acknowledgment for source with no pending writes" component=conn/ble data=4700 addr=71 Dec 20 18:48:10 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:10.557+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=71 Dec 20 18:48:10 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:10.912+02:00 level=WARN msg="received acknowledgment for source with no pending writes" component=conn/ble data=4700 addr=71 Dec 20 18:48:13 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 20 18:48:13 primo-plus volumio[1100]: info: CURURI: music-library/NAS/USB/WDW/Lana Del Rey Dec 20 18:48:13 primo-plus volumio[1100]: info: Preload queue cleared Dec 20 18:48:15 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:15.060+02:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.68.112:41412 error="websocket: close 1006 (abnormal closure): unexpected EOF" Dec 20 18:48:15 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:15.060+02:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.68.112:41412 Dec 20 18:48:15 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:15.060+02:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.68.112:41412 Dec 20 18:48:16 primo-plus dbus-daemon[647]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.23" (uid=0 pid=1562 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=781 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Dec 20 18:48:29 primo-plus volumio-remote-updater[633]: [2025-12-20 18:48:29] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Dec 20 18:48:31 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 20 18:48:31 primo-plus volumio[1100]: info: CURURI: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA] Dec 20 18:48:31 primo-plus volumio[1100]: info: Discovery: Disconnected from remote: 192.168.68.132 Dec 20 18:48:31 primo-plus volumio[1100]: info: Discovery: Disconnected from remote: 192.168.68.132 Dec 20 18:48:31 primo-plus volumio[1100]: info: Discovery: Disconnected from remote: 192.168.68.132 Dec 20 18:48:31 primo-plus volumio[1100]: info: Discovery: Disconnected from remote: 192.168.68.132 Dec 20 18:48:31 primo-plus volumio[1100]: info: Discovery: Disconnected from remote: 192.168.68.132 Dec 20 18:48:31 primo-plus volumio[1100]: info: Discovery: Disconnected from remote: 192.168.68.132 Dec 20 18:48:31 primo-plus volumio[1100]: info: Preload queue cleared Dec 20 18:48:31 primo-plus volumio[1100]: info: Preloading song: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/01 Text Book.mqa.flac Dec 20 18:48:31 primo-plus volumio[1100]: info: Preloading song: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/02 Blue Banisters.mqa.flac Dec 20 18:48:31 primo-plus volumio[1100]: info: Preloading song: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/03 Arcadia.mqa.flac Dec 20 18:48:31 primo-plus volumio[1100]: info: Preloading song: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/04 Interlude - The Trio.mqa.flac Dec 20 18:48:31 primo-plus volumio[1100]: info: Preloading song: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/05 Black Bathing Suit - Explicit.mqa.flac Dec 20 18:48:31 primo-plus volumio[1100]: info: Preloading song: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/06 If You Lie Down With Me.mqa.flac Dec 20 18:48:31 primo-plus volumio[1100]: info: Preloading song: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/07 Beautiful.mqa.flac Dec 20 18:48:31 primo-plus volumio[1100]: info: Preloading song: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/08 Violets for Roses.mqa.flac Dec 20 18:48:31 primo-plus volumio[1100]: info: Preloading song: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/09 Dealer - Explicit.mqa.flac Dec 20 18:48:31 primo-plus volumio[1100]: info: Preloading song: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/10 Thunder - Explicit.mqa.flac Dec 20 18:48:31 primo-plus volumio[1100]: info: Preloading song: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/11 Wildflower Wildfire.mqa.flac Dec 20 18:48:31 primo-plus volumio[1100]: info: Preloading song: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/12 Nectar Of The Gods - Explicit.mqa.flac Dec 20 18:48:31 primo-plus volumio[1100]: info: Preloading song: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/13 Living Legend.mqa.flac Dec 20 18:48:31 primo-plus volumio[1100]: info: Preloading song: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/14 Cherry Blossom.mqa.flac Dec 20 18:48:31 primo-plus volumio[1100]: info: Preloading song: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/15 Sweet Carolina - Explicit.mqa.flac Dec 20 18:48:31 primo-plus volumio[1100]: info: Exploding uri music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/01 Text Book.mqa.flac in service mpd Dec 20 18:48:31 primo-plus volumio[1100]: info: ALBUMART /albumart?cacheid=820&web=Lana%20Del%20Rey/Blue%20Banisters/extralarge&path=%2Fmnt%2FNAS%2FUSB%2FWDW%2FLana%20Del%20Rey%2FLana%20Del%20Rey%20-%202021%20-%20Blue%20Banisters%20%5BMQA%5D%2F01%20Text%20Book.mqa.flac&metadata=false Dec 20 18:48:31 primo-plus volumio[1100]: info: URI /mnt/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/01 Text Book.mqa.flac Dec 20 18:48:32 primo-plus volumio[1100]: info: Exploding uri music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/02 Blue Banisters.mqa.flac in service mpd Dec 20 18:48:32 primo-plus volumio[1100]: info: ALBUMART /albumart?cacheid=820&web=Lana%20Del%20Rey/Blue%20Banisters/extralarge&path=%2Fmnt%2FNAS%2FUSB%2FWDW%2FLana%20Del%20Rey%2FLana%20Del%20Rey%20-%202021%20-%20Blue%20Banisters%20%5BMQA%5D%2F02%20Blue%20Banisters.mqa.flac&metadata=false Dec 20 18:48:32 primo-plus volumio[1100]: info: URI /mnt/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/02 Blue Banisters.mqa.flac Dec 20 18:48:32 primo-plus volumio[1100]: info: Exploding uri music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/03 Arcadia.mqa.flac in service mpd Dec 20 18:48:32 primo-plus volumio[1100]: info: ALBUMART /albumart?cacheid=820&web=Lana%20Del%20Rey/Blue%20Banisters/extralarge&path=%2Fmnt%2FNAS%2FUSB%2FWDW%2FLana%20Del%20Rey%2FLana%20Del%20Rey%20-%202021%20-%20Blue%20Banisters%20%5BMQA%5D%2F03%20Arcadia.mqa.flac&metadata=false Dec 20 18:48:32 primo-plus volumio[1100]: info: URI /mnt/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/03 Arcadia.mqa.flac Dec 20 18:48:32 primo-plus volumio[1100]: info: Exploding uri music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/04 Interlude - The Trio.mqa.flac in service mpd Dec 20 18:48:32 primo-plus volumio[1100]: info: ALBUMART /albumart?cacheid=820&web=Lana%20Del%20Rey/Blue%20Banisters/extralarge&path=%2Fmnt%2FNAS%2FUSB%2FWDW%2FLana%20Del%20Rey%2FLana%20Del%20Rey%20-%202021%20-%20Blue%20Banisters%20%5BMQA%5D%2F04%20Interlude%20-%20The%20Trio.mqa.flac&metadata=false Dec 20 18:48:32 primo-plus volumio[1100]: info: URI /mnt/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/04 Interlude - The Trio.mqa.flac Dec 20 18:48:32 primo-plus volumio[1100]: info: Exploding uri music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/05 Black Bathing Suit - Explicit.mqa.flac in service mpd Dec 20 18:48:32 primo-plus volumio[1100]: info: ALBUMART /albumart?cacheid=820&web=Lana%20Del%20Rey/Blue%20Banisters/extralarge&path=%2Fmnt%2FNAS%2FUSB%2FWDW%2FLana%20Del%20Rey%2FLana%20Del%20Rey%20-%202021%20-%20Blue%20Banisters%20%5BMQA%5D%2F05%20Black%20Bathing%20Suit%20-%20Explicit.mqa.flac&metadata=false Dec 20 18:48:32 primo-plus volumio[1100]: info: URI /mnt/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/05 Black Bathing Suit - Explicit.mqa.flac Dec 20 18:48:32 primo-plus volumio[1100]: info: Exploding uri music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/06 If You Lie Down With Me.mqa.flac in service mpd Dec 20 18:48:32 primo-plus volumio[1100]: info: Exploding uri music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/07 Beautiful.mqa.flac in service mpd Dec 20 18:48:32 primo-plus volumio[1100]: info: Exploding uri music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/08 Violets for Roses.mqa.flac in service mpd Dec 20 18:48:32 primo-plus volumio[1100]: info: ALBUMART /albumart?cacheid=820&web=Lana%20Del%20Rey/Blue%20Banisters/extralarge&path=%2Fmnt%2FNAS%2FUSB%2FWDW%2FLana%20Del%20Rey%2FLana%20Del%20Rey%20-%202021%20-%20Blue%20Banisters%20%5BMQA%5D%2F06%20If%20You%20Lie%20Down%20With%20Me.mqa.flac&metadata=false Dec 20 18:48:32 primo-plus volumio[1100]: info: URI /mnt/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/06 If You Lie Down With Me.mqa.flac Dec 20 18:48:32 primo-plus volumio[1100]: info: ALBUMART /albumart?cacheid=820&web=Lana%20Del%20Rey/Blue%20Banisters/extralarge&path=%2Fmnt%2FNAS%2FUSB%2FWDW%2FLana%20Del%20Rey%2FLana%20Del%20Rey%20-%202021%20-%20Blue%20Banisters%20%5BMQA%5D%2F07%20Beautiful.mqa.flac&metadata=false Dec 20 18:48:32 primo-plus volumio[1100]: info: URI /mnt/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/07 Beautiful.mqa.flac Dec 20 18:48:32 primo-plus volumio[1100]: info: ALBUMART /albumart?cacheid=820&web=Lana%20Del%20Rey/Blue%20Banisters/extralarge&path=%2Fmnt%2FNAS%2FUSB%2FWDW%2FLana%20Del%20Rey%2FLana%20Del%20Rey%20-%202021%20-%20Blue%20Banisters%20%5BMQA%5D%2F08%20Violets%20for%20Roses.mqa.flac&metadata=false Dec 20 18:48:32 primo-plus volumio[1100]: info: URI /mnt/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/08 Violets for Roses.mqa.flac Dec 20 18:48:32 primo-plus volumio[1100]: info: Exploding uri music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/09 Dealer - Explicit.mqa.flac in service mpd Dec 20 18:48:32 primo-plus volumio[1100]: info: ALBUMART /albumart?cacheid=820&web=Lana%20Del%20Rey/Blue%20Banisters/extralarge&path=%2Fmnt%2FNAS%2FUSB%2FWDW%2FLana%20Del%20Rey%2FLana%20Del%20Rey%20-%202021%20-%20Blue%20Banisters%20%5BMQA%5D%2F09%20Dealer%20-%20Explicit.mqa.flac&metadata=false Dec 20 18:48:32 primo-plus volumio[1100]: info: URI /mnt/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/09 Dealer - Explicit.mqa.flac Dec 20 18:48:32 primo-plus volumio[1100]: info: Exploding uri music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/10 Thunder - Explicit.mqa.flac in service mpd Dec 20 18:48:32 primo-plus volumio[1100]: info: Exploding uri music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/11 Wildflower Wildfire.mqa.flac in service mpd Dec 20 18:48:32 primo-plus volumio[1100]: info: Exploding uri music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/12 Nectar Of The Gods - Explicit.mqa.flac in service mpd Dec 20 18:48:32 primo-plus volumio[1100]: info: ALBUMART /albumart?cacheid=820&web=Lana%20Del%20Rey/Blue%20Banisters/extralarge&path=%2Fmnt%2FNAS%2FUSB%2FWDW%2FLana%20Del%20Rey%2FLana%20Del%20Rey%20-%202021%20-%20Blue%20Banisters%20%5BMQA%5D%2F10%20Thunder%20-%20Explicit.mqa.flac&metadata=false Dec 20 18:48:32 primo-plus volumio[1100]: info: URI /mnt/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/10 Thunder - Explicit.mqa.flac Dec 20 18:48:32 primo-plus volumio[1100]: info: ALBUMART /albumart?cacheid=820&web=Lana%20Del%20Rey/Blue%20Banisters/extralarge&path=%2Fmnt%2FNAS%2FUSB%2FWDW%2FLana%20Del%20Rey%2FLana%20Del%20Rey%20-%202021%20-%20Blue%20Banisters%20%5BMQA%5D%2F11%20Wildflower%20Wildfire.mqa.flac&metadata=false Dec 20 18:48:32 primo-plus volumio[1100]: info: URI /mnt/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/11 Wildflower Wildfire.mqa.flac Dec 20 18:48:32 primo-plus volumio[1100]: info: ALBUMART /albumart?cacheid=820&web=Lana%20Del%20Rey/Blue%20Banisters/extralarge&path=%2Fmnt%2FNAS%2FUSB%2FWDW%2FLana%20Del%20Rey%2FLana%20Del%20Rey%20-%202021%20-%20Blue%20Banisters%20%5BMQA%5D%2F12%20Nectar%20Of%20The%20Gods%20-%20Explicit.mqa.flac&metadata=false Dec 20 18:48:32 primo-plus volumio[1100]: info: URI /mnt/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/12 Nectar Of The Gods - Explicit.mqa.flac Dec 20 18:48:32 primo-plus volumio[1100]: info: Exploding uri music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/13 Living Legend.mqa.flac in service mpd Dec 20 18:48:32 primo-plus volumio[1100]: info: ALBUMART /albumart?cacheid=820&web=Lana%20Del%20Rey/Blue%20Banisters/extralarge&path=%2Fmnt%2FNAS%2FUSB%2FWDW%2FLana%20Del%20Rey%2FLana%20Del%20Rey%20-%202021%20-%20Blue%20Banisters%20%5BMQA%5D%2F13%20Living%20Legend.mqa.flac&metadata=false Dec 20 18:48:32 primo-plus volumio[1100]: info: URI /mnt/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/13 Living Legend.mqa.flac Dec 20 18:48:32 primo-plus volumio[1100]: info: Exploding uri music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/14 Cherry Blossom.mqa.flac in service mpd Dec 20 18:48:32 primo-plus volumio[1100]: info: ALBUMART /albumart?cacheid=820&web=Lana%20Del%20Rey/Blue%20Banisters/extralarge&path=%2Fmnt%2FNAS%2FUSB%2FWDW%2FLana%20Del%20Rey%2FLana%20Del%20Rey%20-%202021%20-%20Blue%20Banisters%20%5BMQA%5D%2F14%20Cherry%20Blossom.mqa.flac&metadata=false Dec 20 18:48:32 primo-plus volumio[1100]: info: URI /mnt/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/14 Cherry Blossom.mqa.flac Dec 20 18:48:32 primo-plus volumio[1100]: info: Exploding uri music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/15 Sweet Carolina - Explicit.mqa.flac in service mpd Dec 20 18:48:32 primo-plus volumio[1100]: info: ALBUMART /albumart?cacheid=820&web=Lana%20Del%20Rey/Blue%20Banisters/extralarge&path=%2Fmnt%2FNAS%2FUSB%2FWDW%2FLana%20Del%20Rey%2FLana%20Del%20Rey%20-%202021%20-%20Blue%20Banisters%20%5BMQA%5D%2F15%20Sweet%20Carolina%20-%20Explicit.mqa.flac&metadata=false Dec 20 18:48:32 primo-plus volumio[1100]: info: URI /mnt/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/15 Sweet Carolina - Explicit.mqa.flac Dec 20 18:48:32 primo-plus volumio[1100]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Dec 20 18:48:32 primo-plus volumio[1100]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Dec 20 18:48:32 primo-plus volumio[1100]: at SendStream.pipe (/volumio/node_modules/send/index.js:584:14) Dec 20 18:48:32 primo-plus volumio[1100]: at sendfile (/volumio/node_modules/express/lib/response.js:1103:8) Dec 20 18:48:32 primo-plus volumio[1100]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:433:3) Dec 20 18:48:32 primo-plus volumio[1100]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) Dec 20 18:48:32 primo-plus volumio[1100]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 20 18:48:32 primo-plus volumio[1100]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Dec 20 18:48:33 primo-plus volumio[1100]: info: MRS: Pushing audio output on request Dec 20 18:48:33 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output Dec 20 18:48:33 primo-plus volumio[1100]: info: Discovery: Connected to remote: 192.168.68.132 Dec 20 18:48:33 primo-plus volumio[1100]: info: MRS: Pushing audio output on request Dec 20 18:48:33 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output Dec 20 18:48:33 primo-plus volumio[1100]: info: Discovery: Connected to remote: 192.168.68.132 Dec 20 18:48:34 primo-plus volumio-remote-updater[633]: [2025-12-20 18:48:34] [connect] Successful connection Dec 20 18:48:34 primo-plus volumio-remote-updater[633]: [2025-12-20 18:48:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766249314 101 Dec 20 18:48:34 primo-plus volumio[1100]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 10 Dec 20 18:48:39 primo-plus volumio[1100]: info: Preload queue cleared Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::ClearQueue Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::stop Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::updateTrackBlock Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrackBlock Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::stPlaybackTimer Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::pushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioPushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output update for this device Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::serviceStop Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::serviceStop Dec 20 18:48:39 primo-plus volumio[1100]: info: ControllerMpd::stop Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::sendMpdCommand stop Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::clearPlayQueue Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::saveQueue Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioPushQueue Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::addQueueItems Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::addQueueItems Dec 20 18:48:39 primo-plus volumio[1100]: info: Preload queue cleared Dec 20 18:48:39 primo-plus volumio[1100]: info: Adding Item to queue: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/01 Text Book.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Using cached record of: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/01 Text Book.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioPushQueue Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::saveQueue Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::updateTrackBlock Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrackBlock Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioPlay Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::play index 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::addQueueItems Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::addQueueItems Dec 20 18:48:39 primo-plus volumio[1100]: info: Preload queue cleared Dec 20 18:48:39 primo-plus volumio[1100]: info: Adding Item to queue: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/02 Blue Banisters.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Using cached record of: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/02 Blue Banisters.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Adding Item to queue: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/03 Arcadia.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Using cached record of: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/03 Arcadia.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Adding Item to queue: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/04 Interlude - The Trio.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Using cached record of: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/04 Interlude - The Trio.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Adding Item to queue: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/05 Black Bathing Suit - Explicit.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Using cached record of: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/05 Black Bathing Suit - Explicit.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Adding Item to queue: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/06 If You Lie Down With Me.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Using cached record of: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/06 If You Lie Down With Me.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Adding Item to queue: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/07 Beautiful.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Using cached record of: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/07 Beautiful.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Adding Item to queue: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/08 Violets for Roses.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Using cached record of: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/08 Violets for Roses.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Adding Item to queue: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/09 Dealer - Explicit.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Using cached record of: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/09 Dealer - Explicit.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Adding Item to queue: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/10 Thunder - Explicit.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Using cached record of: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/10 Thunder - Explicit.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Adding Item to queue: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/11 Wildflower Wildfire.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Using cached record of: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/11 Wildflower Wildfire.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Adding Item to queue: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/12 Nectar Of The Gods - Explicit.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Using cached record of: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/12 Nectar Of The Gods - Explicit.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Adding Item to queue: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/13 Living Legend.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Using cached record of: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/13 Living Legend.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Adding Item to queue: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/14 Cherry Blossom.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Using cached record of: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/14 Cherry Blossom.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Adding Item to queue: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/15 Sweet Carolina - Explicit.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: Using cached record of: music-library/NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/15 Sweet Carolina - Explicit.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::stop Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioPushQueue Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::saveQueue Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::play index undefined Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::updateTrackBlock Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrackBlock Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::startPlaybackTimer Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::clearAddPlayTracks NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/01 Text Book.mqa.flac Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::sendMpdCommand stop Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Dec 20 18:48:39 primo-plus volumio[1100]: ---------------------------- MPD announces state update: player Dec 20 18:48:39 primo-plus volumio[1100]: info: sendMpdCommand stop took 59 milliseconds Dec 20 18:48:39 primo-plus volumio[1100]: info: ControllerMpd::getState Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::sendMpdCommand status Dec 20 18:48:39 primo-plus volumio[1100]: info: sendMpdCommand stop took 30 milliseconds Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::sendMpdCommand clear Dec 20 18:48:39 primo-plus volumio[1100]: info: Dec 20 18:48:39 primo-plus volumio[1100]: ---------------------------- MPD announces state update: player Dec 20 18:48:39 primo-plus volumio[1100]: info: ControllerMpd::getState Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::sendMpdCommand status Dec 20 18:48:39 primo-plus volumio[1100]: info: Dec 20 18:48:39 primo-plus volumio[1100]: ---------------------------- MPD announces state update: player Dec 20 18:48:39 primo-plus volumio[1100]: info: ControllerMpd::getState Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::sendMpdCommand status Dec 20 18:48:39 primo-plus volumio[1100]: info: Dec 20 18:48:39 primo-plus volumio[1100]: ---------------------------- MPD announces system playlist update Dec 20 18:48:39 primo-plus volumio[1100]: info: Ignoring MPD Status Update Dec 20 18:48:39 primo-plus volumio[1100]: info: sendMpdCommand status took 9 milliseconds Dec 20 18:48:39 primo-plus volumio[1100]: info: sendMpdCommand clear took 8 milliseconds Dec 20 18:48:39 primo-plus volumio[1100]: info: sendMpdCommand status took 7 milliseconds Dec 20 18:48:39 primo-plus volumio[1100]: info: sendMpdCommand status took 7 milliseconds Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::parseState Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::sendMpdCommand add "NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/01 Text Book.mqa.flac" Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::parseState Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::parseState Dec 20 18:48:39 primo-plus volumio[1100]: info: ControllerMpd::pushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::servicePushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::pushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioPushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output update for this device Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 18:48:39 primo-plus volumio[1100]: verbose: CURRENT POSITION 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::syncState stateService stop Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::syncState currentStatus stop Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::pushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioPushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output update for this device Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output Dec 20 18:48:39 primo-plus volumio[1100]: info: No code Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::pushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioPushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output update for this device Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output Dec 20 18:48:39 primo-plus volumio[1100]: info: ControllerMpd::pushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::servicePushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::pushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioPushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output update for this device Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 20 18:48:39 primo-plus volumio[1100]: verbose: CURRENT POSITION 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::syncState stateService stop Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::syncState currentStatus stop Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::pushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioPushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output update for this device Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output Dec 20 18:48:39 primo-plus volumio[1100]: info: No code Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::pushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioPushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output update for this device Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output Dec 20 18:48:39 primo-plus volumio[1100]: info: ------------------------------ 59ms Dec 20 18:48:39 primo-plus volumio[1100]: info: ------------------------------ 58ms Dec 20 18:48:39 primo-plus volumio[1100]: info: Dec 20 18:48:39 primo-plus volumio[1100]: ---------------------------- MPD announces system playlist update Dec 20 18:48:39 primo-plus volumio[1100]: info: Ignoring MPD Status Update Dec 20 18:48:39 primo-plus volumio[1100]: info: Dec 20 18:48:39 primo-plus volumio[1100]: ---------------------------- MPD announces system playlist update Dec 20 18:48:39 primo-plus volumio[1100]: info: Ignoring MPD Status Update Dec 20 18:48:39 primo-plus volumio[1100]: error: Upnp client error: Error: This socket has been ended by the other party Dec 20 18:48:39 primo-plus volumio[1100]: error: updateQueue error: null Dec 20 18:48:39 primo-plus volumio[1100]: info: Dec 20 18:48:39 primo-plus volumio[1100]: ---------------------------- MPD announces system playlist update Dec 20 18:48:39 primo-plus volumio[1100]: info: Ignoring MPD Status Update Dec 20 18:48:39 primo-plus volumio[1100]: info: ------------------------------ 63ms Dec 20 18:48:39 primo-plus volumio[1100]: info: sendMpdCommand playlistinfo took 60 milliseconds Dec 20 18:48:39 primo-plus volumio[1100]: info: sendMpdCommand add "NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/01 Text Book.mqa.flac" took 60 milliseconds Dec 20 18:48:39 primo-plus volumio[1100]: info: ------------------------------ 9ms Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::parseTrackInfo Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::sendMpdCommand play Dec 20 18:48:39 primo-plus volumio[1100]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 20 18:48:39 primo-plus volumio[1100]: info: ------------------------------ 80ms Dec 20 18:48:39 primo-plus volumio[1100]: info: Dec 20 18:48:39 primo-plus volumio[1100]: ---------------------------- MPD announces system playlist update Dec 20 18:48:39 primo-plus volumio[1100]: info: Ignoring MPD Status Update Dec 20 18:48:39 primo-plus volumio[1100]: info: Dec 20 18:48:39 primo-plus volumio[1100]: ---------------------------- MPD announces system playlist update Dec 20 18:48:39 primo-plus volumio[1100]: info: Ignoring MPD Status Update Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Dec 20 18:48:39 primo-plus volumio[1100]: ---------------------------- MPD announces state update: player Dec 20 18:48:39 primo-plus volumio[1100]: info: ------------------------------ 51ms Dec 20 18:48:39 primo-plus volumio[1100]: info: sendMpdCommand play took 47 milliseconds Dec 20 18:48:39 primo-plus volumio[1100]: info: ------------------------------ 46ms Dec 20 18:48:39 primo-plus volumio[1100]: info: ------------------------------ 45ms Dec 20 18:48:39 primo-plus volumio[1100]: info: ControllerMpd::getState Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::sendMpdCommand status Dec 20 18:48:39 primo-plus volumio[1100]: info: Dec 20 18:48:39 primo-plus volumio[1100]: ---------------------------- MPD announces state update: player Dec 20 18:48:39 primo-plus volumio[1100]: info: ControllerMpd::getState Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::sendMpdCommand status Dec 20 18:48:39 primo-plus volumio[1100]: info: Dec 20 18:48:39 primo-plus volumio[1100]: ---------------------------- MPD announces state update: player Dec 20 18:48:39 primo-plus volumio[1100]: info: ControllerMpd::getState Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::sendMpdCommand status Dec 20 18:48:39 primo-plus volumio[1100]: info: Dec 20 18:48:39 primo-plus volumio[1100]: ---------------------------- MPD announces state update: player Dec 20 18:48:39 primo-plus volumio[1100]: info: ControllerMpd::getState Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::sendMpdCommand status Dec 20 18:48:39 primo-plus volumio[1100]: info: sendMpdCommand status took 4 milliseconds Dec 20 18:48:39 primo-plus volumio[1100]: info: sendMpdCommand status took 3 milliseconds Dec 20 18:48:39 primo-plus volumio[1100]: info: sendMpdCommand status took 2 milliseconds Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::parseState Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::parseState Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::parseState Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 18:48:39 primo-plus volumio[1100]: info: Dec 20 18:48:39 primo-plus volumio[1100]: ---------------------------- MPD announces state update: player Dec 20 18:48:39 primo-plus volumio[1100]: info: ControllerMpd::getState Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::sendMpdCommand status Dec 20 18:48:39 primo-plus volumio[1100]: info: Dec 20 18:48:39 primo-plus volumio[1100]: ---------------------------- MPD announces state update: player Dec 20 18:48:39 primo-plus volumio[1100]: info: ControllerMpd::getState Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::sendMpdCommand status Dec 20 18:48:39 primo-plus volumio[1100]: info: sendMpdCommand status took 9 milliseconds Dec 20 18:48:39 primo-plus volumio[1100]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 20 18:48:39 primo-plus volumio[1100]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 20 18:48:39 primo-plus volumio[1100]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 20 18:48:39 primo-plus volumio[1100]: info: sendMpdCommand status took 7 milliseconds Dec 20 18:48:39 primo-plus volumio[1100]: info: sendMpdCommand status took 6 milliseconds Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::parseState Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::parseTrackInfo Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::parseState Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::parseState Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 20 18:48:39 primo-plus volumio[1100]: info: ControllerMpd::pushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::servicePushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Text Book","artist":"Lana Del Rey","album":"Blue Banisters","uri":"NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/01 Text Book.mqa.flac","trackType":"flac"} Dec 20 18:48:39 primo-plus volumio[1100]: verbose: CURRENT POSITION 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::syncState stateService play Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::syncState currentStatus stop Dec 20 18:48:39 primo-plus volumio[1100]: info: ControllerMpd::pushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::servicePushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Text Book","artist":"Lana Del Rey","album":"Blue Banisters","uri":"NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/01 Text Book.mqa.flac","trackType":"flac"} Dec 20 18:48:39 primo-plus volumio[1100]: verbose: CURRENT POSITION 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::syncState stateService play Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::syncState currentStatus play Dec 20 18:48:39 primo-plus volumio[1100]: info: Received an update from plugin. extracting info from payload Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::pushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioPushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output update for this device Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::pushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioPushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output update for this device Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output Dec 20 18:48:39 primo-plus volumio[1100]: info: ControllerMpd::pushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::servicePushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Text Book","artist":"Lana Del Rey","album":"Blue Banisters","uri":"NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/01 Text Book.mqa.flac","trackType":"flac"} Dec 20 18:48:39 primo-plus volumio[1100]: verbose: CURRENT POSITION 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::syncState stateService play Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::syncState currentStatus play Dec 20 18:48:39 primo-plus volumio[1100]: info: Received an update from plugin. extracting info from payload Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::pushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioPushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output update for this device Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::pushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioPushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output update for this device Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output Dec 20 18:48:39 primo-plus volumio[1100]: info: ------------------------------ 45ms Dec 20 18:48:39 primo-plus volumio[1100]: info: ------------------------------ 60ms Dec 20 18:48:39 primo-plus volumio[1100]: info: ------------------------------ 60ms Dec 20 18:48:39 primo-plus volumio[1100]: info: sendMpdCommand playlistinfo took 47 milliseconds Dec 20 18:48:39 primo-plus volumio[1100]: info: sendMpdCommand playlistinfo took 46 milliseconds Dec 20 18:48:39 primo-plus volumio[1100]: info: sendMpdCommand playlistinfo took 47 milliseconds Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::parseTrackInfo Dec 20 18:48:39 primo-plus volumio[1100]: verbose: ControllerMpd::parseTrackInfo Dec 20 18:48:39 primo-plus volumio[1100]: info: ControllerMpd::pushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::servicePushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Text Book","artist":"Lana Del Rey","album":"Blue Banisters","uri":"NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/01 Text Book.mqa.flac","trackType":"flac"} Dec 20 18:48:39 primo-plus volumio[1100]: verbose: CURRENT POSITION 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::syncState stateService play Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::syncState currentStatus play Dec 20 18:48:39 primo-plus volumio[1100]: info: Received an update from plugin. extracting info from payload Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::pushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioPushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output update for this device Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::pushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioPushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output update for this device Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output Dec 20 18:48:39 primo-plus volumio[1100]: info: ControllerMpd::pushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::servicePushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Text Book","artist":"Lana Del Rey","album":"Blue Banisters","uri":"NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/01 Text Book.mqa.flac","trackType":"flac"} Dec 20 18:48:39 primo-plus volumio[1100]: verbose: CURRENT POSITION 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::syncState stateService play Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::syncState currentStatus play Dec 20 18:48:39 primo-plus volumio[1100]: info: Received an update from plugin. extracting info from payload Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::pushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioPushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output update for this device Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::pushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioPushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output update for this device Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output Dec 20 18:48:39 primo-plus volumio[1100]: info: ControllerMpd::pushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::servicePushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":303,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Text Book","artist":"Lana Del Rey","album":"Blue Banisters","uri":"NAS/USB/WDW/Lana Del Rey/Lana Del Rey - 2021 - Blue Banisters [MQA]/01 Text Book.mqa.flac","trackType":"flac"} Dec 20 18:48:39 primo-plus volumio[1100]: verbose: CURRENT POSITION 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::syncState stateService play Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::syncState currentStatus play Dec 20 18:48:39 primo-plus volumio[1100]: info: Received an update from plugin. extracting info from payload Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::pushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioPushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output update for this device Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreStateMachine::pushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioPushState Dec 20 18:48:39 primo-plus volumio[1100]: info: CoreCommandRouter::volumioGetState Dec 20 18:48:39 primo-plus volumio[1100]: info: CorePlayQueue::getTrack 0 Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output update for this device Dec 20 18:48:39 primo-plus volumio[1100]: info: MRS: Pushing multiroomSync output Dec 20 18:48:39 primo-plus volumio[1100]: info: ------------------------------ 110ms Dec 20 18:48:39 primo-plus volumio[1100]: info: ------------------------------ 107ms Dec 20 18:48:39 primo-plus volumio[1100]: info: ------------------------------ 107ms Dec 20 18:48:39 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:39.666+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:39 primo-plus volumio[1100]: info: Signalling Playback active due to playback status change Dec 20 18:48:39 primo-plus volumio[1100]: info: Signalling Playback active due to playback status change Dec 20 18:48:39 primo-plus volumio[1100]: info: Signalling Playback active due to playback status change Dec 20 18:48:39 primo-plus volumio[1100]: info: Signalling Playback active due to playback status change Dec 20 18:48:39 primo-plus volumio[1100]: info: Signalling Playback active due to playback status change Dec 20 18:48:39 primo-plus volumio[1100]: info: Signalling Playback active due to playback status change Dec 20 18:48:39 primo-plus volumio[1100]: info: Signalling Playback active due to playback status change Dec 20 18:48:39 primo-plus volumio[1100]: info: Signalling Playback active due to playback status change Dec 20 18:48:39 primo-plus volumio[1100]: info: Signalling Playback active due to playback status change Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Updating RAAT Signal Path Dec 20 18:48:39 primo-plus volumio[1100]: info: Signalling Playback active due to playback status change Dec 20 18:48:39 primo-plus volumio[1100]: info: Signalling Playback active due to playback status change Dec 20 18:48:39 primo-plus volumio[1100]: info: Signalling Playback active due to playback status change Dec 20 18:48:39 primo-plus volumio[1100]: info: Signalling Playback active due to playback status change Dec 20 18:48:39 primo-plus volumio[1100]: info: Signalling Playback active due to playback status change Dec 20 18:48:39 primo-plus volumio[1100]: info: Signalling Playback active due to playback status change Dec 20 18:48:39 primo-plus volumio[1100]: info: Signalling Playback active due to playback status change Dec 20 18:48:39 primo-plus volumio[1100]: info: Signalling Playback active due to playback status change Dec 20 18:48:39 primo-plus volumio[1100]: info: Signalling Playback active due to playback status change Dec 20 18:48:39 primo-plus volumio[1100]: info: Signalling Playback active due to playback status change Dec 20 18:48:39 primo-plus volumio[1100]: info: MCU Signalled Playback Active Dec 20 18:48:39 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:39.966+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:40 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:40.267+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:40 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:40.568+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:40 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:40.869+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:41 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:41.169+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:41 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:41.470+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:41 primo-plus volumio[1100]: info: Executing endpoint metavolumio Dec 20 18:48:41 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 20 18:48:41 primo-plus volumio[1100]: info: Executing endpoint metavolumio Dec 20 18:48:41 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 20 18:48:41 primo-plus volumio[1100]: info: Executing endpoint metavolumio Dec 20 18:48:41 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 20 18:48:41 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:41.771+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:42 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:42.071+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:42 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:42.371+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:42 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:42.672+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:42 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:42.672+02:00 level=ERROR msg="pending write exceeded maximum retries, dropping" component=conn/ble addr=69 chunks=1 index=0 Dec 20 18:48:42 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:42.672+02:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%45 @ 0x303c240" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Dec 20 18:48:42 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:42.973+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:43 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:43.273+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=71 Dec 20 18:48:43 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:43.573+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=71 Dec 20 18:48:43 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:43.874+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=71 Dec 20 18:48:44 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:44.175+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=71 Dec 20 18:48:44 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:44.475+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:44 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:44.776+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=71 Dec 20 18:48:45 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:45.077+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=71 Dec 20 18:48:45 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:45.378+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=71 Dec 20 18:48:45 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:45.679+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=71 Dec 20 18:48:45 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:45.979+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=71 Dec 20 18:48:46 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:46.280+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=71 Dec 20 18:48:46 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:46.580+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=71 Dec 20 18:48:46 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:46.580+02:00 level=ERROR msg="pending write exceeded maximum retries, dropping" component=conn/ble addr=71 chunks=1 index=0 Dec 20 18:48:46 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:46.581+02:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%47 @ 0x307e030" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Dec 20 18:48:46 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:46.881+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:47 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:47.182+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:47 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:47.482+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:47 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:47.784+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:48 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:48.085+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:48 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:48.386+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:48 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:48.686+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:48 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:48.987+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:49 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:49.288+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:49 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:49.289+02:00 level=ERROR msg="pending write exceeded maximum retries, dropping" component=conn/ble addr=69 chunks=1 index=0 Dec 20 18:48:49 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:49.290+02:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%45 @ 0x303c240" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Dec 20 18:48:49 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:49.290+02:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x307e030" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="no addresses to write to" Dec 20 18:48:49 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:49.590+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:49 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:49.891+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:50 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:50.192+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:50 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:50.493+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:50 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:50.793+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:51 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:51.094+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:51 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:51.396+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:51 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:51.696+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:51 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:51.997+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:52 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:52.298+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:52 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:52.598+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:52 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:52.599+02:00 level=ERROR msg="pending write exceeded maximum retries, dropping" component=conn/ble addr=69 chunks=1 index=0 Dec 20 18:48:52 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:52.599+02:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%45 @ 0x303c240" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Dec 20 18:48:52 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:52.599+02:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x307e030" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="no addresses to write to" Dec 20 18:48:52 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:52.901+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:53 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:53.202+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:53 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:53.503+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:53 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:53.804+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:54 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:54.105+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:54 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:54.405+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:54 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:54.706+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:55 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:55.007+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:55 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:55.308+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:55 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:55.609+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:55 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:55.910+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:55 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:55.910+02:00 level=ERROR msg="pending write exceeded maximum retries, dropping" component=conn/ble addr=69 chunks=1 index=0 Dec 20 18:48:55 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:55.910+02:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%45 @ 0x303c240" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Dec 20 18:48:55 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:55.910+02:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x307e030" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="no addresses to write to" Dec 20 18:48:56 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:56.211+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:56 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:56.512+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:56 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:56.814+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:57 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:57.115+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:57 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:57.416+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:57 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:57.716+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:58 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:58.018+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:58 primo-plus volumio[1100]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Dec 20 18:48:58 primo-plus volumio[1100]: info: MRS: Starting cast device: Q990D-820a56b2a7a49ee5e42b07ca242ba083 Dec 20 18:48:58 primo-plus volumio[1100]: info: Enabled audio output: Q990D-820a56b2a7a49ee5e42b07ca242ba083 Dec 20 18:48:58 primo-plus volumio[1100]: info: MRS: Setting this device as Streaming Server Dec 20 18:48:58 primo-plus volumio[1100]: info: Dec 20 18:48:58 primo-plus volumio[1100]: [1766249338128] ---------------------------- MRS: Setting Streaming Server Dec 20 18:48:58 primo-plus volumio[1100]: info: MRS: enable multiroom server output Dec 20 18:48:58 primo-plus volumio[1100]: info: MRS: Set multiroom target PCM to volumioMultiRoom Dec 20 18:48:58 primo-plus volumio[1100]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Dec 20 18:48:58 primo-plus volumio[1100]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Dec 20 18:48:58 primo-plus volumio[1100]: info: MRS: Connected to cast device: Q990D-820a56b2a7a49ee5e42b07ca242ba083 Dec 20 18:48:58 primo-plus volumio[1100]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Dec 20 18:48:58 primo-plus volumio[1100]: info: MRS: STARTING volumioStreaming Dec 20 18:48:58 primo-plus sudo[5208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 20 18:48:58 primo-plus sudo[5208]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 18:48:58 primo-plus sudo[5208]: pam_unix(sudo:session): session closed for user root Dec 20 18:48:58 primo-plus volumio[1100]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 18:48:58 primo-plus volumio[1100]: TypeError: Cannot read property 'on' of undefined Dec 20 18:48:58 primo-plus volumio[1100]: at /volumio/app/plugins/audio_interface/multiroom/multiroom_real:1:29834 Dec 20 18:48:58 primo-plus volumio[1100]: at /volumio/app/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/senders/platform.js:95:20 Dec 20 18:48:58 primo-plus volumio[1100]: at /volumio/app/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/receiver.js:51:14 Dec 20 18:48:58 primo-plus volumio[1100]: at fn.onmessage (/volumio/app/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/request-response.js:27:7) Dec 20 18:48:58 primo-plus volumio[1100]: at fn.emit (events.js:327:22) Dec 20 18:48:58 primo-plus volumio[1100]: at Channel.onmessage (/volumio/app/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/controller.js:16:10) Dec 20 18:48:58 primo-plus volumio[1100]: at Channel.emit (events.js:315:20) Dec 20 18:48:58 primo-plus volumio[1100]: at Client.onmessage (/volumio/app/plugins/audio_interface/multiroom/node_modules/castv2/lib/channel.js:23:10) Dec 20 18:48:58 primo-plus volumio[1100]: at Client.emit (events.js:327:22) Dec 20 18:48:58 primo-plus volumio[1100]: at PacketStreamWrapper.onpacket (/volumio/app/plugins/audio_interface/multiroom/node_modules/castv2/lib/client.js:81:10) Dec 20 18:48:58 primo-plus volumio[1100]: at PacketStreamWrapper.emit (events.js:315:20) Dec 20 18:48:58 primo-plus volumio[1100]: at TLSSocket. (/volumio/app/plugins/audio_interface/multiroom/node_modules/castv2/lib/packet-stream-wrapper.js:28:16) Dec 20 18:48:58 primo-plus volumio[1100]: at TLSSocket.emit (events.js:315:20) Dec 20 18:48:58 primo-plus volumio[1100]: at emitReadable_ (internal/streams/readable.js:569:12) Dec 20 18:48:58 primo-plus volumio[1100]: at processTicksAndRejections (internal/process/task_queues.js:79:21) Dec 20 18:48:58 primo-plus volumio[1100]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 18:48:58 primo-plus sudo[5211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Dec 20 18:48:58 primo-plus sudo[5211]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 18:48:58 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:58.322+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:58 primo-plus systemd[1]: Started VolumioStreamingService. Dec 20 18:48:58 primo-plus sudo[5211]: pam_unix(sudo:session): session closed for user root Dec 20 18:48:58 primo-plus sudo[5221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 20 18:48:58 primo-plus sudo[5221]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 18:48:58 primo-plus sudo[5221]: pam_unix(sudo:session): session closed for user root Dec 20 18:48:58 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:58.623+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 Dec 20 18:48:58 primo-plus sudo[5227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-20 18:47 Dec 20 18:48:58 primo-plus sudo[5227]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 20 18:48:58 primo-plus volumio5-onboarding[1562]: time=2025-12-20T18:48:58.926+02:00 level=WARN msg="pending write acknowledgment timed out, retrying" component=conn/ble addr=69 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="2f25e1a98e53e2dbc4f3878604e250b0379f3ea4" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="primoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 08:48:49 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Primo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo Plus" VOLUMIO_HASH="cbffafee532a1056b7b5bfef76197910"