Apr 12 13:43:01 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: ---------------------------- MPD announces system playlist update Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: Ignoring MPD Status Update Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: ---------------------------- MPD announces state update: player Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: ControllerMpd::getState Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: ---------------------------- MPD announces system playlist update Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: Ignoring MPD Status Update Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: ---------------------------- MPD announces state update: player Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: ControllerMpd::getState Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: ---------------------------- MPD announces system playlist update Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: Ignoring MPD Status Update Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: ---------------------------- MPD announces state update: player Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: ControllerMpd::getState Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: verbose: ControllerMpd::sendMpdCommand status Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: ------------------------------ 2ms Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: sendMpdCommand status took 1 milliseconds Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: ------------------------------ 1ms Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: verbose: ControllerMpd::parseState Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: sendMpdCommand status took 2 milliseconds Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: ------------------------------ 1ms Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: sendMpdCommand status took 1 milliseconds Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: verbose: ControllerMpd::parseState Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: verbose: ControllerMpd::parseState Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: ControllerMpd::pushState Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::servicePushState Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: CorePlayQueue::getTrack 0 Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6630086,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Leony - Simple life","artist":"WDR 2 Rheinland aktuell, Westdeutscher Rundfunk Koeln","album":null,"uri":"https://wdr-wdr2-rheinland.icecastssl.wdr.de/wdr/wdr2/rheinland/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: verbose: CURRENT POSITION 0 Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: CoreStateMachine::syncState stateService play Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: CoreStateMachine::syncState currentStatus play Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: Received an update from plugin. extracting info from payload Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: CoreStateMachine::pushState Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::volumioPushState Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: CoreStateMachine::pushState Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::volumioPushState Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: ------------------------------ 10ms Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: verbose: ControllerMpd::parseTrackInfo Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: ControllerMpd::pushState Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::servicePushState Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: CorePlayQueue::getTrack 0 Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6630086,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Leony - Simple life","artist":"WDR 2 Rheinland aktuell, Westdeutscher Rundfunk Koeln","album":null,"uri":"https://wdr-wdr2-rheinland.icecastssl.wdr.de/wdr/wdr2/rheinland/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: verbose: CURRENT POSITION 0 Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: CoreStateMachine::syncState stateService play Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: CoreStateMachine::syncState currentStatus play Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: Received an update from plugin. extracting info from payload Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: CoreStateMachine::pushState Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::volumioPushState Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: CoreStateMachine::pushState Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::volumioPushState Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: ControllerMpd::pushState Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::servicePushState Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: CorePlayQueue::getTrack 0 Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6630086,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Leony - Simple life","artist":"WDR 2 Rheinland aktuell, Westdeutscher Rundfunk Koeln","album":null,"uri":"https://wdr-wdr2-rheinland.icecastssl.wdr.de/wdr/wdr2/rheinland/mp3/128/stream.mp3?aggregator=tunein","trackType":""} Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: verbose: CURRENT POSITION 0 Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: CoreStateMachine::syncState stateService play Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: CoreStateMachine::syncState currentStatus play Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: Received an update from plugin. extracting info from payload Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: CoreStateMachine::pushState Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::volumioPushState Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: CoreStateMachine::pushState Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::volumioPushState Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: ------------------------------ 25ms Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: info: ------------------------------ 25ms Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Apr 12 13:43:02 volumio-rbpi4 volumio[1236]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Apr 12 13:43:05 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 13:43:06 volumio-rbpi4 go-librespot[1762]: time="2026-04-12T13:43:06+02:00" level=trace msg="sent dealer ping" Apr 12 13:43:06 volumio-rbpi4 go-librespot[1762]: time="2026-04-12T13:43:06+02:00" level=trace msg="received dealer pong" Apr 12 13:43:09 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 13:43:13 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: info: Adding a new share Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: info: Checking key NasMounts.0590a70b-980f-43b7-abd0-276eda1ccea0 Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: info: Checking key NasMounts.3e75121b-d61c-491d-89e6-c6bb74ae2d74 Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: info: No correspondence found in configuration for share DS213plus on IP 192.168.63.10 Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: info: Executing SMB command: smbclient --debuglevel=4 -L 192.168.63.10 -N -m SMB3_11 Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: info: Error browsing device DS213plus for shares: Command failed: smbclient --debuglevel=4 -L 192.168.63.10 -N -m SMB3_11 Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: lp_load_ex: refreshing parameters Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: Initialising global parameters Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: Processing section "[global]" Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: doing parameter netbios name = Volumio RbPi4 Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: doing parameter server string = Audiophile Music Player Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: doing parameter workgroup = WORKGROUP Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: doing parameter security = user Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: doing parameter map to guest = Bad User Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: doing parameter encrypt passwords = yes Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: lpcfg_do_global_parameter: WARNING: The "encrypt passwords" option is deprecated Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: doing parameter wins support = yes Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: doing parameter local master = no Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: doing parameter preferred master = no Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: doing parameter os level = 30 Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: pm_process() returned Yes Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: added interface eth0 ip=192.168.63.88 bcast=192.168.63.255 netmask=255.255.255.0 Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: Client started (version 4.17.8-Raspbian). Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: Connecting to 192.168.63.10 at port 445 Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: session request ok Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: negotiated dialect[SMB3_11] against server[192.168.63.10] Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: GENSEC backend 'gssapi_spnego' registered Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: GENSEC backend 'gssapi_krb5' registered Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: GENSEC backend 'gssapi_krb5_sasl' registered Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: GENSEC backend 'spnego' registered Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: GENSEC backend 'schannel' registered Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: GENSEC backend 'ncalrpc_as_system' registered Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: GENSEC backend 'sasl-EXTERNAL' registered Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: GENSEC backend 'ntlmssp' registered Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: GENSEC backend 'ntlmssp_resume_ccache' registered Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: GENSEC backend 'http_basic' registered Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: GENSEC backend 'http_ntlm' registered Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: GENSEC backend 'http_negotiate' registered Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: GENSEC backend 'krb5' registered Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: GENSEC backend 'fake_gssapi_krb5' registered Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: Cannot do GSE to an IP address Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: Failed to start GENSEC client mech gse_krb5: NT_STATUS_INVALID_PARAMETER Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: Got challenge flags: Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: Got NTLMSSP neg_flags=0x628a8215 Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_UNICODE Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_REQUEST_TARGET Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_SIGN Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_NTLM Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_TARGET_TYPE_SERVER Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_TARGET_INFO Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_VERSION Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_128 Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_KEY_EXCH Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP: Set final flags: Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: Got NTLMSSP neg_flags=0x62008215 Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_UNICODE Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_REQUEST_TARGET Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_SIGN Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_NTLM Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_VERSION Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_128 Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_KEY_EXCH Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP Sign/Seal - Initialising with flags: Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: Got NTLMSSP neg_flags=0x62008215 Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_UNICODE Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_REQUEST_TARGET Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_SIGN Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_NTLM Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_VERSION Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_128 Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_KEY_EXCH Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information. Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: Got challenge flags: Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: Got NTLMSSP neg_flags=0x628a8215 Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_UNICODE Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_REQUEST_TARGET Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_SIGN Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_NTLM Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_TARGET_TYPE_SERVER Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_TARGET_INFO Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_VERSION Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_128 Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_KEY_EXCH Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP: Set final flags: Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: Got NTLMSSP neg_flags=0x62008a15 Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_UNICODE Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_REQUEST_TARGET Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_SIGN Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_NTLM Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_ANONYMOUS Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_VERSION Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_128 Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_KEY_EXCH Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP Sign/Seal - Initialising with flags: Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: Got NTLMSSP neg_flags=0x62008a15 Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_UNICODE Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_REQUEST_TARGET Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_SIGN Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_NTLM Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_ANONYMOUS Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_VERSION Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_128 Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: NTLMSSP_NEGOTIATE_KEY_EXCH Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information. Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: info: Detected device DS213plus with version null Apr 12 13:43:17 volumio-rbpi4 volumio[1236]: info: Error mounting DS213plus on IP 192.168.63.10 : Something is already mounted on /mnt/NAS/DS213plus Apr 12 13:43:18 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 12 13:43:21 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 13:43:25 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 13:43:29 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 13:43:33 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 13:43:35 volumio-rbpi4 go-librespot[1762]: time="2026-04-12T13:43:35+02:00" level=trace msg="received accesspoint ping" Apr 12 13:43:35 volumio-rbpi4 go-librespot[1762]: time="2026-04-12T13:43:35+02:00" level=trace msg="received accesspoint pong ack" Apr 12 13:43:36 volumio-rbpi4 go-librespot[1762]: time="2026-04-12T13:43:36+02:00" level=trace msg="sent dealer ping" Apr 12 13:43:36 volumio-rbpi4 go-librespot[1762]: time="2026-04-12T13:43:36+02:00" level=trace msg="received dealer pong" Apr 12 13:43:37 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 13:43:41 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 13:43:45 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 13:43:49 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 13:43:53 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 13:43:57 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 13:44:01 volumio-rbpi4 volumio[1236]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Apr 12 13:44:01 volumio-rbpi4 sudo[5697]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/DS213plus Apr 12 13:44:01 volumio-rbpi4 sudo[5697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 13:44:01 volumio-rbpi4 sudo[5697]: pam_unix(sudo:session): session closed for user root Apr 12 13:44:01 volumio-rbpi4 volumio[1236]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 13:44:01 volumio-rbpi4 volumio[1236]: Error: EBUSY: resource busy or locked, rmdir '/mnt/NAS/DS213plus' Apr 12 13:44:01 volumio-rbpi4 volumio[1236]: at Object.rmdirSync (node:fs:1245:10) Apr 12 13:44:01 volumio-rbpi4 volumio[1236]: at /volumio/node_modules/linux-mountutils/mountutils.js:178:12 Apr 12 13:44:01 volumio-rbpi4 volumio[1236]: at ChildProcess.exithandler (node:child_process:413:7) Apr 12 13:44:01 volumio-rbpi4 volumio[1236]: at ChildProcess.emit (node:events:514:28) Apr 12 13:44:01 volumio-rbpi4 volumio[1236]: at maybeClose (node:internal/child_process:1105:16) Apr 12 13:44:01 volumio-rbpi4 volumio[1236]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) { Apr 12 13:44:01 volumio-rbpi4 volumio[1236]: errno: -16, Apr 12 13:44:01 volumio-rbpi4 volumio[1236]: syscall: 'rmdir', Apr 12 13:44:01 volumio-rbpi4 volumio[1236]: code: 'EBUSY', Apr 12 13:44:01 volumio-rbpi4 volumio[1236]: path: '/mnt/NAS/DS213plus' Apr 12 13:44:01 volumio-rbpi4 volumio[1236]: } Apr 12 13:44:01 volumio-rbpi4 volumio[1236]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 13:44:01 volumio-rbpi4 sudo[5715]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-12 13:43' Apr 12 13:44:01 volumio-rbpi4 sudo[5715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"