-- Logs begin at Wed 2025-05-21 19:30:44 CEST, end at Wed 2025-05-21 20:06:02 CEST. -- May 21 20:05:01 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 20:05:05 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 20:05:09 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 20:05:13 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 20:05:17 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 20:05:21 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 20:05:25 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 20:05:25 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 20:05:25 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 20:05:25 volumio volumio[1230]: info: Discovery: Getting this device information May 21 20:05:25 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState May 21 20:05:25 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 May 21 20:05:25 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 20:05:29 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 20:05:33 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 20:05:37 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 20:05:38 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare May 21 20:05:38 volumio volumio[1230]: info: Adding a new share May 21 20:05:38 volumio volumio[1230]: info: No correspondence found in configuration for share Diskstation on IP 192.168.178.30 May 21 20:05:38 volumio volumio[1230]: info: Executing SMB command: smbclient --debuglevel=4 -L 192.168.178.30 -N May 21 20:05:38 volumio volumio[1230]: info: Error browsing device Diskstation for shares: Command failed: smbclient --debuglevel=4 -L 192.168.178.30 -N May 21 20:05:38 volumio volumio[1230]: lp_load_ex: refreshing parameters May 21 20:05:38 volumio volumio[1230]: Initialising global parameters May 21 20:05:38 volumio volumio[1230]: Processing section "[global]" May 21 20:05:38 volumio volumio[1230]: doing parameter netbios name = Volumio May 21 20:05:38 volumio volumio[1230]: doing parameter server string = Volumio Audiophile Music Player May 21 20:05:38 volumio volumio[1230]: doing parameter workgroup = WORKGROUP May 21 20:05:38 volumio volumio[1230]: doing parameter security = user May 21 20:05:38 volumio volumio[1230]: doing parameter map to guest = Bad User May 21 20:05:38 volumio volumio[1230]: doing parameter encrypt passwords = yes May 21 20:05:38 volumio volumio[1230]: doing parameter wins support = yes May 21 20:05:38 volumio volumio[1230]: doing parameter local master = no May 21 20:05:38 volumio volumio[1230]: doing parameter preferred master = no May 21 20:05:38 volumio volumio[1230]: doing parameter os level = 30 May 21 20:05:38 volumio volumio[1230]: doing parameter fruit:model = AirPort May 21 20:05:38 volumio volumio[1230]: pm_process() returned Yes May 21 20:05:38 volumio volumio[1230]: directory_create_or_exist_strict: invalid ownership on directory /var/run/samba/msg.lock May 21 20:05:38 volumio volumio[1230]: cmdline_messaging_context: Unable to initialize messaging context. May 21 20:05:38 volumio volumio[1230]: Unable to initialize messaging context May 21 20:05:38 volumio volumio[1230]: lp_load_ex: refreshing parameters May 21 20:05:38 volumio volumio[1230]: Initialising global parameters May 21 20:05:38 volumio volumio[1230]: Processing section "[global]" May 21 20:05:38 volumio volumio[1230]: doing parameter netbios name = Volumio May 21 20:05:38 volumio volumio[1230]: doing parameter server string = Volumio Audiophile Music Player May 21 20:05:38 volumio volumio[1230]: doing parameter workgroup = WORKGROUP May 21 20:05:38 volumio volumio[1230]: doing parameter security = user May 21 20:05:38 volumio volumio[1230]: doing parameter map to guest = Bad User May 21 20:05:38 volumio volumio[1230]: doing parameter encrypt passwords = yes May 21 20:05:38 volumio volumio[1230]: doing parameter wins support = yes May 21 20:05:38 volumio volumio[1230]: doing parameter local master = no May 21 20:05:38 volumio volumio[1230]: doing parameter preferred master = no May 21 20:05:38 volumio volumio[1230]: doing parameter os level = 30 May 21 20:05:38 volumio volumio[1230]: doing parameter fruit:model = AirPort May 21 20:05:38 volumio volumio[1230]: pm_process() returned Yes May 21 20:05:38 volumio volumio[1230]: added interface wlan0 ip=192.168.178.76 bcast=192.168.178.255 netmask=255.255.255.0 May 21 20:05:38 volumio volumio[1230]: Client started (version 4.9.5-Debian). May 21 20:05:38 volumio volumio[1230]: Connecting to 192.168.178.30 at port 445 May 21 20:05:38 volumio volumio[1230]: session request ok May 21 20:05:38 volumio volumio[1230]: negotiated dialect[SMB3_11] against server[192.168.178.30] May 21 20:05:38 volumio volumio[1230]: got OID=1.3.6.1.4.1.311.2.2.10 May 21 20:05:38 volumio volumio[1230]: GENSEC backend 'gssapi_spnego' registered May 21 20:05:38 volumio volumio[1230]: GENSEC backend 'gssapi_krb5' registered May 21 20:05:38 volumio volumio[1230]: GENSEC backend 'gssapi_krb5_sasl' registered May 21 20:05:38 volumio volumio[1230]: GENSEC backend 'spnego' registered May 21 20:05:38 volumio volumio[1230]: GENSEC backend 'schannel' registered May 21 20:05:38 volumio volumio[1230]: GENSEC backend 'naclrpc_as_system' registered May 21 20:05:38 volumio volumio[1230]: GENSEC backend 'sasl-EXTERNAL' registered May 21 20:05:38 volumio volumio[1230]: GENSEC backend 'ntlmssp' registered May 21 20:05:38 volumio volumio[1230]: GENSEC backend 'ntlmssp_resume_ccache' registered May 21 20:05:38 volumio volumio[1230]: GENSEC backend 'http_basic' registered May 21 20:05:38 volumio volumio[1230]: GENSEC backend 'http_ntlm' registered May 21 20:05:38 volumio volumio[1230]: GENSEC backend 'http_negotiate' registered May 21 20:05:38 volumio volumio[1230]: GENSEC backend 'krb5' registered May 21 20:05:38 volumio volumio[1230]: GENSEC backend 'fake_gssapi_krb5' registered May 21 20:05:38 volumio volumio[1230]: Got challenge flags: May 21 20:05:38 volumio volumio[1230]: Got NTLMSSP neg_flags=0x628a8215 May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_UNICODE May 21 20:05:38 volumio volumio[1230]: NTLMSSP_REQUEST_TARGET May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_SIGN May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_NTLM May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN May 21 20:05:38 volumio volumio[1230]: NTLMSSP_TARGET_TYPE_SERVER May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_TARGET_INFO May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_VERSION May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_128 May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_KEY_EXCH May 21 20:05:38 volumio volumio[1230]: NTLMSSP: Set final flags: May 21 20:05:38 volumio volumio[1230]: Got NTLMSSP neg_flags=0x62008215 May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_UNICODE May 21 20:05:38 volumio volumio[1230]: NTLMSSP_REQUEST_TARGET May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_SIGN May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_NTLM May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_VERSION May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_128 May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_KEY_EXCH May 21 20:05:38 volumio volumio[1230]: NTLMSSP Sign/Seal - Initialising with flags: May 21 20:05:38 volumio volumio[1230]: Got NTLMSSP neg_flags=0x62008215 May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_UNICODE May 21 20:05:38 volumio volumio[1230]: NTLMSSP_REQUEST_TARGET May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_SIGN May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_NTLM May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_VERSION May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_128 May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_KEY_EXCH May 21 20:05:38 volumio volumio[1230]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information. May 21 20:05:38 volumio volumio[1230]: got OID=1.3.6.1.4.1.311.2.2.10 May 21 20:05:38 volumio volumio[1230]: Got challenge flags: May 21 20:05:38 volumio volumio[1230]: Got NTLMSSP neg_flags=0x628a8215 May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_UNICODE May 21 20:05:38 volumio volumio[1230]: NTLMSSP_REQUEST_TARGET May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_SIGN May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_NTLM May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN May 21 20:05:38 volumio volumio[1230]: NTLMSSP_TARGET_TYPE_SERVER May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_TARGET_INFO May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_VERSION May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_128 May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_KEY_EXCH May 21 20:05:38 volumio volumio[1230]: NTLMSSP: Set final flags: May 21 20:05:38 volumio volumio[1230]: Got NTLMSSP neg_flags=0x62008a15 May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_UNICODE May 21 20:05:38 volumio volumio[1230]: NTLMSSP_REQUEST_TARGET May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_SIGN May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_NTLM May 21 20:05:38 volumio volumio[1230]: NTLMSSP_ANONYMOUS May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_VERSION May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_128 May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_KEY_EXCH May 21 20:05:38 volumio volumio[1230]: NTLMSSP Sign/Seal - Initialising with flags: May 21 20:05:38 volumio volumio[1230]: Got NTLMSSP neg_flags=0x62008a15 May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_UNICODE May 21 20:05:38 volumio volumio[1230]: NTLMSSP_REQUEST_TARGET May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_SIGN May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_NTLM May 21 20:05:38 volumio volumio[1230]: NTLMSSP_ANONYMOUS May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_VERSION May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_128 May 21 20:05:38 volumio volumio[1230]: NTLMSSP_NEGOTIATE_KEY_EXCH May 21 20:05:38 volumio volumio[1230]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information. May 21 20:05:38 volumio volumio[1230]: info: Detected device Diskstation with version null May 21 20:05:38 volumio sudo[11526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.30/diskstation/music /mnt/NAS/Diskstation May 21 20:05:38 volumio sudo[11526]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 20:05:38 volumio kernel: FS-Cache: Loaded May 21 20:05:38 volumio kernel: Key type dns_resolver registered May 21 20:05:38 volumio kernel: Key type cifs.spnego registered May 21 20:05:38 volumio kernel: Key type cifs.idmap registered May 21 20:05:38 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. May 21 20:05:38 volumio kernel: CIFS: Attempting to mount //192.168.178.30/diskstation/music May 21 20:05:38 volumio sudo[11526]: pam_unix(sudo:session): session closed for user root May 21 20:05:38 volumio volumio[1230]: info: Permission denied for Diskstation on IP 192.168.178.30 May 21 20:05:38 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE May 21 20:05:38 volumio kernel: CIFS: VFS: \\192.168.178.30 Send error in SessSetup = -13 May 21 20:05:38 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 May 21 20:05:39 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 21 20:05:41 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 20:05:45 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 20:05:49 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 20:05:52 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare May 21 20:05:53 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 21 20:05:53 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 20:05:55 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 21 20:05:55 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand update May 21 20:05:55 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand status May 21 20:05:55 volumio volumio[1230]: info: May 21 20:05:55 volumio volumio[1230]: ---------------------------- MPD announces state update: update May 21 20:05:55 volumio volumio[1230]: info: sendMpdCommand update took 1 milliseconds May 21 20:05:55 volumio volumio[1230]: info: ControllerMpd::getState May 21 20:05:55 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand status May 21 20:05:55 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand status May 21 20:05:55 volumio volumio[1230]: info: May 21 20:05:55 volumio volumio[1230]: ---------------------------- MPD announces state update: update May 21 20:05:55 volumio volumio[1230]: info: ControllerMpd::getState May 21 20:05:55 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand status May 21 20:05:55 volumio volumio[1230]: info: sendMpdCommand status took 1 milliseconds May 21 20:05:55 volumio volumio[1230]: info: sendMpdCommand status took 0 milliseconds May 21 20:05:55 volumio volumio[1230]: verbose: ControllerMpd::parseState May 21 20:05:55 volumio volumio[1230]: info: Command Router : Notfying DB Updatefalse May 21 20:05:55 volumio volumio[1230]: info: CoreCommandRouter::Close All Modals sent May 21 20:05:55 volumio volumio[1230]: verbose: ControllerMpd::parseState May 21 20:05:55 volumio volumio[1230]: info: ControllerMpd::pushState May 21 20:05:55 volumio volumio[1230]: info: CoreCommandRouter::servicePushState May 21 20:05:55 volumio volumio[1230]: info: CoreStateMachine::pushState May 21 20:05:55 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 May 21 20:05:55 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState May 21 20:05:55 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 May 21 20:05:55 volumio volumio[1230]: 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} May 21 20:05:55 volumio volumio[1230]: verbose: CURRENT POSITION 0 May 21 20:05:55 volumio volumio[1230]: info: CoreStateMachine::syncState stateService stop May 21 20:05:55 volumio volumio[1230]: info: CoreStateMachine::syncState currentStatus stop May 21 20:05:55 volumio volumio[1230]: info: CoreStateMachine::pushState May 21 20:05:55 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 May 21 20:05:55 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState May 21 20:05:55 volumio volumio[1230]: info: No code May 21 20:05:55 volumio volumio[1230]: info: CoreStateMachine::pushState May 21 20:05:55 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 May 21 20:05:55 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState May 21 20:05:55 volumio volumio[1230]: info: ------------------------------ 64ms May 21 20:05:55 volumio volumio[1230]: info: sendMpdCommand status took 64 milliseconds May 21 20:05:55 volumio volumio[1230]: verbose: ControllerMpd::parseState May 21 20:05:55 volumio volumio[1230]: info: Command Router : Notfying DB Updatefalse May 21 20:05:55 volumio volumio[1230]: info: CoreCommandRouter::Close All Modals sent May 21 20:05:55 volumio volumio[1230]: verbose: ControllerMpd::parseState May 21 20:05:55 volumio volumio[1230]: info: ControllerMpd::pushState May 21 20:05:55 volumio volumio[1230]: info: CoreCommandRouter::servicePushState May 21 20:05:55 volumio volumio[1230]: info: CoreStateMachine::pushState May 21 20:05:55 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 May 21 20:05:55 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState May 21 20:05:55 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 May 21 20:05:55 volumio volumio[1230]: 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} May 21 20:05:55 volumio volumio[1230]: verbose: CURRENT POSITION 0 May 21 20:05:55 volumio volumio[1230]: info: CoreStateMachine::syncState stateService stop May 21 20:05:55 volumio volumio[1230]: info: CoreStateMachine::syncState currentStatus stop May 21 20:05:55 volumio volumio[1230]: info: CoreStateMachine::pushState May 21 20:05:55 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 May 21 20:05:55 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState May 21 20:05:55 volumio volumio[1230]: info: No code May 21 20:05:55 volumio volumio[1230]: info: CoreStateMachine::pushState May 21 20:05:55 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 May 21 20:05:55 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState May 21 20:05:55 volumio volumio[1230]: info: ------------------------------ 76ms May 21 20:05:55 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 20:05:55 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 20:05:55 volumio volumio[1230]: info: Discovery: Getting this device information May 21 20:05:55 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState May 21 20:05:55 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 May 21 20:05:55 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 20:05:56 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 21 20:05:56 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand update May 21 20:05:56 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand status May 21 20:05:56 volumio volumio[1230]: info: May 21 20:05:56 volumio volumio[1230]: ---------------------------- MPD announces state update: update May 21 20:05:56 volumio volumio[1230]: info: sendMpdCommand update took 0 milliseconds May 21 20:05:56 volumio volumio[1230]: info: ControllerMpd::getState May 21 20:05:56 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand status May 21 20:05:56 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand status May 21 20:05:56 volumio volumio[1230]: info: May 21 20:05:56 volumio volumio[1230]: ---------------------------- MPD announces state update: update May 21 20:05:56 volumio volumio[1230]: info: ControllerMpd::getState May 21 20:05:56 volumio volumio[1230]: verbose: ControllerMpd::sendMpdCommand status May 21 20:05:56 volumio volumio[1230]: info: sendMpdCommand status took 1 milliseconds May 21 20:05:56 volumio volumio[1230]: info: sendMpdCommand status took 0 milliseconds May 21 20:05:56 volumio volumio[1230]: verbose: ControllerMpd::parseState May 21 20:05:56 volumio volumio[1230]: info: Command Router : Notfying DB Updatefalse May 21 20:05:56 volumio volumio[1230]: info: CoreCommandRouter::Close All Modals sent May 21 20:05:56 volumio volumio[1230]: verbose: ControllerMpd::parseState May 21 20:05:56 volumio volumio[1230]: info: ControllerMpd::pushState May 21 20:05:56 volumio volumio[1230]: info: CoreCommandRouter::servicePushState May 21 20:05:56 volumio volumio[1230]: info: CoreStateMachine::pushState May 21 20:05:56 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 May 21 20:05:56 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState May 21 20:05:56 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 May 21 20:05:56 volumio volumio[1230]: 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} May 21 20:05:56 volumio volumio[1230]: verbose: CURRENT POSITION 0 May 21 20:05:56 volumio volumio[1230]: info: CoreStateMachine::syncState stateService stop May 21 20:05:56 volumio volumio[1230]: info: CoreStateMachine::syncState currentStatus stop May 21 20:05:56 volumio volumio[1230]: info: CoreStateMachine::pushState May 21 20:05:56 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 May 21 20:05:56 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState May 21 20:05:56 volumio volumio[1230]: info: No code May 21 20:05:56 volumio volumio[1230]: info: CoreStateMachine::pushState May 21 20:05:56 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 May 21 20:05:56 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState May 21 20:05:56 volumio volumio[1230]: info: ------------------------------ 15ms May 21 20:05:56 volumio volumio[1230]: info: sendMpdCommand status took 14 milliseconds May 21 20:05:56 volumio volumio[1230]: info: sendMpdCommand status took 15 milliseconds May 21 20:05:56 volumio volumio[1230]: verbose: ControllerMpd::parseState May 21 20:05:56 volumio volumio[1230]: info: Command Router : Notfying DB Updatefalse May 21 20:05:56 volumio volumio[1230]: info: CoreCommandRouter::Close All Modals sent May 21 20:05:56 volumio volumio[1230]: verbose: ControllerMpd::parseState May 21 20:05:56 volumio volumio[1230]: info: ControllerMpd::pushState May 21 20:05:56 volumio volumio[1230]: info: CoreCommandRouter::servicePushState May 21 20:05:56 volumio volumio[1230]: info: CoreStateMachine::pushState May 21 20:05:56 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 May 21 20:05:56 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState May 21 20:05:56 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 May 21 20:05:56 volumio volumio[1230]: 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} May 21 20:05:56 volumio volumio[1230]: verbose: CURRENT POSITION 0 May 21 20:05:56 volumio volumio[1230]: info: CoreStateMachine::syncState stateService stop May 21 20:05:56 volumio volumio[1230]: info: CoreStateMachine::syncState currentStatus stop May 21 20:05:56 volumio volumio[1230]: info: CoreStateMachine::pushState May 21 20:05:56 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 May 21 20:05:56 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState May 21 20:05:56 volumio volumio[1230]: info: No code May 21 20:05:56 volumio volumio[1230]: info: CoreStateMachine::pushState May 21 20:05:56 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 May 21 20:05:56 volumio volumio[1230]: info: CoreCommandRouter::volumioPushState May 21 20:05:56 volumio volumio[1230]: info: ------------------------------ 26ms May 21 20:05:57 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 20:06:01 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 20:06:01 volumio volumio[1230]: info: Disabling MyMusic plugin upnp May 21 20:06:01 volumio sudo[11650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service May 21 20:06:01 volumio sudo[11650]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 20:06:01 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... May 21 20:06:01 volumio volumio[1230]: error: Upnp client error: Error: This socket has been ended by the other party May 21 20:06:02 volumio volumio[1230]: info: Enabling MyMusic plugin upnp May 21 20:06:02 volumio volumio[1230]: info: Enabling plugin upnp May 21 20:06:02 volumio volumio[1230]: info: Loading plugin "upnp"... May 21 20:06:02 volumio volumio[1230]: info: [1747850762246] Starting Upmpd Daemon May 21 20:06:02 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 21 20:06:02 volumio volumio[1230]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 20:06:02 volumio volumio[1230]: Error: listen EADDRINUSE: address already in use :::6599 May 21 20:06:02 volumio volumio[1230]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) May 21 20:06:02 volumio volumio[1230]: at listenInCluster (net.js:1379:12) May 21 20:06:02 volumio volumio[1230]: at Server.listen (net.js:1465:7) May 21 20:06:02 volumio volumio[1230]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) May 21 20:06:02 volumio volumio[1230]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) May 21 20:06:02 volumio volumio[1230]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) May 21 20:06:02 volumio volumio[1230]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 21 20:06:02 volumio volumio[1230]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { May 21 20:06:02 volumio volumio[1230]: code: 'EADDRINUSE', May 21 20:06:02 volumio volumio[1230]: errno: -98, May 21 20:06:02 volumio volumio[1230]: syscall: 'listen', May 21 20:06:02 volumio volumio[1230]: address: '::', May 21 20:06:02 volumio volumio[1230]: port: 6599 May 21 20:06:02 volumio volumio[1230]: } May 21 20:06:02 volumio volumio[1230]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 20:06:02 volumio sudo[11661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-21 20:05 May 21 20:06:02 volumio sudo[11661]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"