-- Logs begin at Mon 2025-05-05 20:44:40 MSK, end at Mon 2025-05-05 20:54:45 MSK. -- May 05 20:53:02 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare May 05 20:53:02 volumio volumio[3926]: info: Adding a new share May 05 20:53:02 volumio volumio[3926]: info: Share alias is blank May 05 20:53:03 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 20:53:07 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 20:53:07 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares May 05 20:53:07 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService May 05 20:53:09 volumio volumio[3926]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L HomeNas8d.local. May 05 20:53:09 volumio volumio[3926]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L HomeNas5d.local. May 05 20:53:09 volumio volumio[3926]: info: Error browsing device HomeNas8d for shares: Command failed: smbclient --no-pass --debuglevel=0 -L HomeNas8d.local. May 05 20:53:09 volumio volumio[3926]: Unable to initialize messaging context May 05 20:53:09 volumio volumio[3926]: info: Error browsing device HomeNas5d for shares: Command failed: smbclient --no-pass --debuglevel=0 -L HomeNas5d.local. May 05 20:53:09 volumio volumio[3926]: Unable to initialize messaging context May 05 20:53:11 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 20:53:15 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 20:53:19 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 20:53:23 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 20:53:27 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 20:53:28 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare May 05 20:53:28 volumio volumio[3926]: info: Adding a new share May 05 20:53:28 volumio volumio[3926]: info: No correspondence found in configuration for share 60 on IP 192.168.1.60 May 05 20:53:28 volumio volumio[3926]: info: Executing SMB command: smbclient --debuglevel=4 -L 192.168.1.60 -N May 05 20:53:28 volumio volumio[3926]: info: Error browsing device 60 for shares: Command failed: smbclient --debuglevel=4 -L 192.168.1.60 -N May 05 20:53:28 volumio volumio[3926]: lp_load_ex: refreshing parameters May 05 20:53:28 volumio volumio[3926]: Initialising global parameters May 05 20:53:28 volumio volumio[3926]: Processing section "[global]" May 05 20:53:28 volumio volumio[3926]: doing parameter netbios name = Volumio May 05 20:53:28 volumio volumio[3926]: doing parameter server string = Volumio Audiophile Music Player May 05 20:53:28 volumio volumio[3926]: doing parameter workgroup = WORKGROUP May 05 20:53:28 volumio volumio[3926]: doing parameter security = user May 05 20:53:28 volumio volumio[3926]: doing parameter map to guest = Bad User May 05 20:53:28 volumio volumio[3926]: doing parameter encrypt passwords = yes May 05 20:53:28 volumio volumio[3926]: doing parameter wins support = yes May 05 20:53:28 volumio volumio[3926]: doing parameter local master = no May 05 20:53:28 volumio volumio[3926]: doing parameter preferred master = no May 05 20:53:28 volumio volumio[3926]: doing parameter os level = 30 May 05 20:53:28 volumio volumio[3926]: doing parameter fruit:model = AirPort May 05 20:53:28 volumio volumio[3926]: pm_process() returned Yes May 05 20:53:28 volumio volumio[3926]: directory_create_or_exist_strict: invalid ownership on directory /var/run/samba/msg.lock May 05 20:53:28 volumio volumio[3926]: cmdline_messaging_context: Unable to initialize messaging context. May 05 20:53:28 volumio volumio[3926]: Unable to initialize messaging context May 05 20:53:28 volumio volumio[3926]: lp_load_ex: refreshing parameters May 05 20:53:28 volumio volumio[3926]: Initialising global parameters May 05 20:53:28 volumio volumio[3926]: Processing section "[global]" May 05 20:53:28 volumio volumio[3926]: doing parameter netbios name = Volumio May 05 20:53:28 volumio volumio[3926]: doing parameter server string = Volumio Audiophile Music Player May 05 20:53:28 volumio volumio[3926]: doing parameter workgroup = WORKGROUP May 05 20:53:28 volumio volumio[3926]: doing parameter security = user May 05 20:53:28 volumio volumio[3926]: doing parameter map to guest = Bad User May 05 20:53:28 volumio volumio[3926]: doing parameter encrypt passwords = yes May 05 20:53:28 volumio volumio[3926]: doing parameter wins support = yes May 05 20:53:28 volumio volumio[3926]: doing parameter local master = no May 05 20:53:28 volumio volumio[3926]: doing parameter preferred master = no May 05 20:53:28 volumio volumio[3926]: doing parameter os level = 30 May 05 20:53:28 volumio volumio[3926]: doing parameter fruit:model = AirPort May 05 20:53:28 volumio volumio[3926]: pm_process() returned Yes May 05 20:53:28 volumio volumio[3926]: added interface eth0 ip=192.168.1.92 bcast=192.168.1.255 netmask=255.255.255.0 May 05 20:53:28 volumio volumio[3926]: Client started (version 4.9.5-Debian). May 05 20:53:28 volumio volumio[3926]: Connecting to 192.168.1.60 at port 445 May 05 20:53:28 volumio volumio[3926]: session request ok May 05 20:53:28 volumio volumio[3926]: negotiated dialect[SMB3_11] against server[192.168.1.60] May 05 20:53:28 volumio volumio[3926]: got OID=1.3.6.1.4.1.311.2.2.10 May 05 20:53:28 volumio volumio[3926]: GENSEC backend 'gssapi_spnego' registered May 05 20:53:28 volumio volumio[3926]: GENSEC backend 'gssapi_krb5' registered May 05 20:53:28 volumio volumio[3926]: GENSEC backend 'gssapi_krb5_sasl' registered May 05 20:53:28 volumio volumio[3926]: GENSEC backend 'spnego' registered May 05 20:53:28 volumio volumio[3926]: GENSEC backend 'schannel' registered May 05 20:53:28 volumio volumio[3926]: GENSEC backend 'naclrpc_as_system' registered May 05 20:53:28 volumio volumio[3926]: GENSEC backend 'sasl-EXTERNAL' registered May 05 20:53:28 volumio volumio[3926]: GENSEC backend 'ntlmssp' registered May 05 20:53:28 volumio volumio[3926]: GENSEC backend 'ntlmssp_resume_ccache' registered May 05 20:53:28 volumio volumio[3926]: GENSEC backend 'http_basic' registered May 05 20:53:28 volumio volumio[3926]: GENSEC backend 'http_ntlm' registered May 05 20:53:28 volumio volumio[3926]: GENSEC backend 'http_negotiate' registered May 05 20:53:28 volumio volumio[3926]: GENSEC backend 'krb5' registered May 05 20:53:28 volumio volumio[3926]: GENSEC backend 'fake_gssapi_krb5' registered May 05 20:53:28 volumio volumio[3926]: Got challenge flags: May 05 20:53:28 volumio volumio[3926]: Got NTLMSSP neg_flags=0x628a8215 May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_UNICODE May 05 20:53:28 volumio volumio[3926]: NTLMSSP_REQUEST_TARGET May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_SIGN May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_NTLM May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN May 05 20:53:28 volumio volumio[3926]: NTLMSSP_TARGET_TYPE_SERVER May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_TARGET_INFO May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_VERSION May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_128 May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_KEY_EXCH May 05 20:53:28 volumio volumio[3926]: NTLMSSP: Set final flags: May 05 20:53:28 volumio volumio[3926]: Got NTLMSSP neg_flags=0x62008215 May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_UNICODE May 05 20:53:28 volumio volumio[3926]: NTLMSSP_REQUEST_TARGET May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_SIGN May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_NTLM May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_VERSION May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_128 May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_KEY_EXCH May 05 20:53:28 volumio volumio[3926]: NTLMSSP Sign/Seal - Initialising with flags: May 05 20:53:28 volumio volumio[3926]: Got NTLMSSP neg_flags=0x62008215 May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_UNICODE May 05 20:53:28 volumio volumio[3926]: NTLMSSP_REQUEST_TARGET May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_SIGN May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_NTLM May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_VERSION May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_128 May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_KEY_EXCH May 05 20:53:28 volumio volumio[3926]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information. May 05 20:53:28 volumio volumio[3926]: got OID=1.3.6.1.4.1.311.2.2.10 May 05 20:53:28 volumio volumio[3926]: Got challenge flags: May 05 20:53:28 volumio volumio[3926]: Got NTLMSSP neg_flags=0x628a8215 May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_UNICODE May 05 20:53:28 volumio volumio[3926]: NTLMSSP_REQUEST_TARGET May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_SIGN May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_NTLM May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN May 05 20:53:28 volumio volumio[3926]: NTLMSSP_TARGET_TYPE_SERVER May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_TARGET_INFO May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_VERSION May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_128 May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_KEY_EXCH May 05 20:53:28 volumio volumio[3926]: NTLMSSP: Set final flags: May 05 20:53:28 volumio volumio[3926]: Got NTLMSSP neg_flags=0x62008a15 May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_UNICODE May 05 20:53:28 volumio volumio[3926]: NTLMSSP_REQUEST_TARGET May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_SIGN May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_NTLM May 05 20:53:28 volumio volumio[3926]: NTLMSSP_ANONYMOUS May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_VERSION May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_128 May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_KEY_EXCH May 05 20:53:28 volumio volumio[3926]: NTLMSSP Sign/Seal - Initialising with flags: May 05 20:53:28 volumio volumio[3926]: Got NTLMSSP neg_flags=0x62008a15 May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_UNICODE May 05 20:53:28 volumio volumio[3926]: NTLMSSP_REQUEST_TARGET May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_SIGN May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_NTLM May 05 20:53:28 volumio volumio[3926]: NTLMSSP_ANONYMOUS May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_VERSION May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_128 May 05 20:53:28 volumio volumio[3926]: NTLMSSP_NEGOTIATE_KEY_EXCH May 05 20:53:28 volumio volumio[3926]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information. May 05 20:53:28 volumio volumio[3926]: info: Detected device 60 with version null May 05 20:53:28 volumio sudo[4582]: 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.1.60/Music /mnt/NAS/60 May 05 20:53:28 volumio sudo[4582]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:53:28 volumio kernel: FS-Cache: Loaded May 05 20:53:28 volumio kernel: Key type dns_resolver registered May 05 20:53:28 volumio kernel: Key type cifs.spnego registered May 05 20:53:28 volumio kernel: Key type cifs.idmap registered May 05 20:53:28 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 05 20:53:28 volumio kernel: CIFS: Attempting to mount //192.168.1.60/Music May 05 20:53:28 volumio sudo[4582]: pam_unix(sudo:session): session closed for user root May 05 20:53:28 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE May 05 20:53:28 volumio kernel: CIFS: VFS: \\192.168.1.60 Send error in SessSetup = -13 May 05 20:53:28 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 May 05 20:53:28 volumio volumio[3926]: info: Permission denied for 60 on IP 192.168.1.60 May 05 20:53:29 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 05 20:53:31 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 20:53:35 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 20:53:39 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 20:53:41 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare May 05 20:53:41 volumio volumio[3926]: info: Share 60 successfully unmounted May 05 20:53:41 volumio sudo[4685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=bilal,password=Dumbass98!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.60/Music /mnt/NAS/60 May 05 20:53:41 volumio sudo[4685]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:53:41 volumio kernel: CIFS: Attempting to mount //192.168.1.60/Music May 05 20:53:41 volumio sudo[4685]: pam_unix(sudo:session): session closed for user root May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::sendMpdCommand status May 05 20:53:42 volumio volumio[3926]: info: May 05 20:53:42 volumio volumio[3926]: ---------------------------- MPD announces state update: update May 05 20:53:42 volumio volumio[3926]: info: ControllerMpd::getState May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::sendMpdCommand status May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::sendMpdCommand status May 05 20:53:42 volumio volumio[3926]: info: May 05 20:53:42 volumio volumio[3926]: ---------------------------- MPD announces state update: update May 05 20:53:42 volumio volumio[3926]: info: ControllerMpd::getState May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::sendMpdCommand status May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::sendMpdCommand status May 05 20:53:42 volumio volumio[3926]: info: May 05 20:53:42 volumio volumio[3926]: ---------------------------- MPD announces state update: update May 05 20:53:42 volumio volumio[3926]: info: ControllerMpd::getState May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::sendMpdCommand status May 05 20:53:42 volumio volumio[3926]: info: [1746467622021] Database update started May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::sendMpdCommand status May 05 20:53:42 volumio volumio[3926]: info: May 05 20:53:42 volumio volumio[3926]: ---------------------------- MPD announces state update: update May 05 20:53:42 volumio volumio[3926]: info: ControllerMpd::getState May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::sendMpdCommand status May 05 20:53:42 volumio volumio[3926]: info: sendMpdCommand status took 15 milliseconds May 05 20:53:42 volumio volumio[3926]: info: sendMpdCommand status took 13 milliseconds May 05 20:53:42 volumio volumio[3926]: info: sendMpdCommand status took 14 milliseconds May 05 20:53:42 volumio volumio[3926]: info: sendMpdCommand status took 13 milliseconds May 05 20:53:42 volumio volumio[3926]: info: sendMpdCommand status took 12 milliseconds May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::parseState May 05 20:53:42 volumio volumio[3926]: info: Command Router : Notfying DB Updatefalse May 05 20:53:42 volumio volumio[3926]: info: CoreCommandRouter::Close All Modals sent May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::parseState May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::parseState May 05 20:53:42 volumio volumio[3926]: info: Command Router : Notfying DB Updatefalse May 05 20:53:42 volumio volumio[3926]: info: CoreCommandRouter::Close All Modals sent May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::parseState May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::parseState May 05 20:53:42 volumio volumio[3926]: info: Command Router : Notfying DB Updatefalse May 05 20:53:42 volumio volumio[3926]: info: CoreCommandRouter::Close All Modals sent May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::parseState May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::sendMpdCommand status May 05 20:53:42 volumio volumio[3926]: info: May 05 20:53:42 volumio volumio[3926]: ---------------------------- MPD announces state update: update May 05 20:53:42 volumio volumio[3926]: info: ControllerMpd::getState May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::sendMpdCommand status May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::sendMpdCommand status May 05 20:53:42 volumio volumio[3926]: info: May 05 20:53:42 volumio volumio[3926]: ---------------------------- MPD announces state update: update May 05 20:53:42 volumio volumio[3926]: info: ControllerMpd::getState May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::sendMpdCommand status May 05 20:53:42 volumio volumio[3926]: info: sendMpdCommand status took 253 milliseconds May 05 20:53:42 volumio volumio[3926]: info: sendMpdCommand status took 251 milliseconds May 05 20:53:42 volumio volumio[3926]: info: sendMpdCommand playlistinfo took 156 milliseconds May 05 20:53:42 volumio volumio[3926]: info: sendMpdCommand playlistinfo took 80 milliseconds May 05 20:53:42 volumio volumio[3926]: info: sendMpdCommand playlistinfo took 55 milliseconds May 05 20:53:42 volumio volumio[3926]: info: sendMpdCommand status took 34 milliseconds May 05 20:53:42 volumio volumio[3926]: info: sendMpdCommand status took 33 milliseconds May 05 20:53:42 volumio volumio[3926]: info: sendMpdCommand status took 32 milliseconds May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::parseState May 05 20:53:42 volumio volumio[3926]: info: Command Router : Notfying DB Updatefalse May 05 20:53:42 volumio volumio[3926]: info: CoreCommandRouter::Close All Modals sent May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::parseState May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::parseTrackInfo May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::parseState May 05 20:53:42 volumio volumio[3926]: info: Command Router : Notfying DB Updatefalse May 05 20:53:42 volumio volumio[3926]: info: CoreCommandRouter::Close All Modals sent May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::parseState May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::parseState May 05 20:53:42 volumio volumio[3926]: info: Command Router : Notfying DB Updatefalse May 05 20:53:42 volumio volumio[3926]: info: CoreCommandRouter::Close All Modals sent May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::parseState May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 20:53:42 volumio volumio[3926]: info: ControllerMpd::pushState May 05 20:53:42 volumio volumio[3926]: info: CoreCommandRouter::servicePushState May 05 20:53:42 volumio volumio[3926]: info: CorePlayQueue::getTrack 0 May 05 20:53:42 volumio volumio[3926]: verbose: STATE SERVICE {"status":"play","position":0,"seek":109968,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TrancEye - In the Air 2014","artist":"Trance","album":null,"uri":"https://trance.stream.laut.fm/trance?ref=radiodns","trackType":""} May 05 20:53:42 volumio volumio[3926]: verbose: CURRENT POSITION 0 May 05 20:53:42 volumio volumio[3926]: info: CoreStateMachine::syncState stateService play May 05 20:53:42 volumio volumio[3926]: info: CoreStateMachine::syncState currentStatus play May 05 20:53:42 volumio volumio[3926]: info: Received an update from plugin. extracting info from payload May 05 20:53:42 volumio volumio[3926]: info: CoreStateMachine::pushState May 05 20:53:42 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 20:53:42 volumio volumio[3926]: info: CoreCommandRouter::volumioPushState May 05 20:53:42 volumio volumio[3926]: info: CoreStateMachine::pushState May 05 20:53:42 volumio volumio[3926]: info: CoreCommandRouter::volumioPushState May 05 20:53:42 volumio volumio[3926]: info: ControllerMpd::pushState May 05 20:53:42 volumio volumio[3926]: info: CoreCommandRouter::servicePushState May 05 20:53:42 volumio volumio[3926]: info: CorePlayQueue::getTrack 0 May 05 20:53:42 volumio volumio[3926]: verbose: STATE SERVICE {"status":"play","position":0,"seek":109968,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TrancEye - In the Air 2014","artist":"Trance","album":null,"uri":"https://trance.stream.laut.fm/trance?ref=radiodns","trackType":""} May 05 20:53:42 volumio volumio[3926]: verbose: CURRENT POSITION 0 May 05 20:53:42 volumio volumio[3926]: info: CoreStateMachine::syncState stateService play May 05 20:53:42 volumio volumio[3926]: info: CoreStateMachine::syncState currentStatus play May 05 20:53:42 volumio volumio[3926]: info: Received an update from plugin. extracting info from payload May 05 20:53:42 volumio volumio[3926]: info: CoreStateMachine::pushState May 05 20:53:42 volumio volumio[3926]: info: CoreCommandRouter::volumioPushState May 05 20:53:42 volumio volumio[3926]: info: CoreStateMachine::pushState May 05 20:53:42 volumio volumio[3926]: info: CoreCommandRouter::volumioPushState May 05 20:53:42 volumio volumio[3926]: info: ControllerMpd::pushState May 05 20:53:42 volumio volumio[3926]: info: CoreCommandRouter::servicePushState May 05 20:53:42 volumio volumio[3926]: info: CorePlayQueue::getTrack 0 May 05 20:53:42 volumio volumio[3926]: verbose: STATE SERVICE {"status":"play","position":0,"seek":109968,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TrancEye - In the Air 2014","artist":"Trance","album":null,"uri":"https://trance.stream.laut.fm/trance?ref=radiodns","trackType":""} May 05 20:53:42 volumio volumio[3926]: verbose: CURRENT POSITION 0 May 05 20:53:42 volumio volumio[3926]: info: CoreStateMachine::syncState stateService play May 05 20:53:42 volumio volumio[3926]: info: CoreStateMachine::syncState currentStatus play May 05 20:53:42 volumio volumio[3926]: info: Received an update from plugin. extracting info from payload May 05 20:53:42 volumio volumio[3926]: info: CoreStateMachine::pushState May 05 20:53:42 volumio volumio[3926]: info: CoreCommandRouter::volumioPushState May 05 20:53:42 volumio volumio[3926]: info: CoreStateMachine::pushState May 05 20:53:42 volumio volumio[3926]: info: CoreCommandRouter::volumioPushState May 05 20:53:42 volumio volumio[3926]: info: ------------------------------ 434ms May 05 20:53:42 volumio volumio[3926]: info: ------------------------------ 432ms May 05 20:53:42 volumio volumio[3926]: info: ------------------------------ 431ms May 05 20:53:42 volumio volumio[3926]: info: sendMpdCommand playlistinfo took 139 milliseconds May 05 20:53:42 volumio volumio[3926]: info: sendMpdCommand playlistinfo took 111 milliseconds May 05 20:53:42 volumio volumio[3926]: info: sendMpdCommand playlistinfo took 86 milliseconds May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::parseTrackInfo May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::parseTrackInfo May 05 20:53:42 volumio volumio[3926]: verbose: ControllerMpd::parseTrackInfo May 05 20:53:42 volumio volumio[3926]: info: ControllerMpd::pushState May 05 20:53:42 volumio volumio[3926]: info: CoreCommandRouter::servicePushState May 05 20:53:42 volumio volumio[3926]: info: CorePlayQueue::getTrack 0 May 05 20:53:42 volumio volumio[3926]: verbose: STATE SERVICE {"status":"play","position":0,"seek":109968,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TrancEye - In the Air 2014","artist":"Trance","album":null,"uri":"https://trance.stream.laut.fm/trance?ref=radiodns","trackType":""} May 05 20:53:42 volumio volumio[3926]: verbose: CURRENT POSITION 0 May 05 20:53:42 volumio volumio[3926]: info: CoreStateMachine::syncState stateService play May 05 20:53:42 volumio volumio[3926]: info: CoreStateMachine::syncState currentStatus play May 05 20:53:42 volumio volumio[3926]: info: Received an update from plugin. extracting info from payload May 05 20:53:42 volumio volumio[3926]: info: CoreStateMachine::pushState May 05 20:53:42 volumio volumio[3926]: info: CoreCommandRouter::volumioPushState May 05 20:53:42 volumio volumio[3926]: info: CoreStateMachine::pushState May 05 20:53:42 volumio volumio[3926]: info: CoreCommandRouter::volumioPushState May 05 20:53:42 volumio volumio[3926]: info: ControllerMpd::pushState May 05 20:53:42 volumio volumio[3926]: info: CoreCommandRouter::servicePushState May 05 20:53:42 volumio volumio[3926]: info: CorePlayQueue::getTrack 0 May 05 20:53:42 volumio volumio[3926]: verbose: STATE SERVICE {"status":"play","position":0,"seek":110219,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TrancEye - In the Air 2014","artist":"Trance","album":null,"uri":"https://trance.stream.laut.fm/trance?ref=radiodns","trackType":""} May 05 20:53:42 volumio volumio[3926]: verbose: CURRENT POSITION 0 May 05 20:53:42 volumio volumio[3926]: info: CoreStateMachine::syncState stateService play May 05 20:53:42 volumio volumio[3926]: info: CoreStateMachine::syncState currentStatus play May 05 20:53:42 volumio volumio[3926]: info: Received an update from plugin. extracting info from payload May 05 20:53:42 volumio volumio[3926]: info: CoreStateMachine::pushState May 05 20:53:42 volumio volumio[3926]: info: CoreCommandRouter::volumioPushState May 05 20:53:42 volumio volumio[3926]: info: CoreStateMachine::pushState May 05 20:53:42 volumio volumio[3926]: info: CoreCommandRouter::volumioPushState May 05 20:53:42 volumio volumio[3926]: info: ControllerMpd::pushState May 05 20:53:42 volumio volumio[3926]: info: CoreCommandRouter::servicePushState May 05 20:53:42 volumio volumio[3926]: info: CorePlayQueue::getTrack 0 May 05 20:53:42 volumio volumio[3926]: verbose: STATE SERVICE {"status":"play","position":0,"seek":110219,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TrancEye - In the Air 2014","artist":"Trance","album":null,"uri":"https://trance.stream.laut.fm/trance?ref=radiodns","trackType":""} May 05 20:53:42 volumio volumio[3926]: verbose: CURRENT POSITION 0 May 05 20:53:42 volumio volumio[3926]: info: CoreStateMachine::syncState stateService play May 05 20:53:42 volumio volumio[3926]: info: CoreStateMachine::syncState currentStatus play May 05 20:53:42 volumio volumio[3926]: info: Received an update from plugin. extracting info from payload May 05 20:53:42 volumio volumio[3926]: info: CoreStateMachine::pushState May 05 20:53:42 volumio volumio[3926]: info: CoreCommandRouter::volumioPushState May 05 20:53:42 volumio volumio[3926]: info: CoreStateMachine::pushState May 05 20:53:42 volumio volumio[3926]: info: CoreCommandRouter::volumioPushState May 05 20:53:42 volumio volumio[3926]: info: ------------------------------ 498ms May 05 20:53:42 volumio volumio[3926]: info: ------------------------------ 279ms May 05 20:53:42 volumio volumio[3926]: info: ------------------------------ 278ms May 05 20:53:43 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 05 20:53:43 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 20:53:47 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 20:53:51 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 20:53:51 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares May 05 20:53:51 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService May 05 20:53:52 volumio volumio[3926]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L HomeNas8d.local. May 05 20:53:52 volumio volumio[3926]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L HomeNas5d.local. May 05 20:53:53 volumio volumio[3926]: info: Error browsing device HomeNas8d for shares: Command failed: smbclient --no-pass --debuglevel=0 -L HomeNas8d.local. May 05 20:53:53 volumio volumio[3926]: Unable to initialize messaging context May 05 20:53:53 volumio volumio[3926]: info: Error browsing device HomeNas5d for shares: Command failed: smbclient --no-pass --debuglevel=0 -L HomeNas5d.local. May 05 20:53:53 volumio volumio[3926]: Unable to initialize messaging context May 05 20:53:55 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 20:53:59 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 20:54:03 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 20:54:07 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 20:54:08 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare May 05 20:54:08 volumio volumio[3926]: info: Adding a new share May 05 20:54:08 volumio volumio[3926]: info: Checking key NasMounts.457712b0-3a44-48f5-97af-3c55f6659587 May 05 20:54:08 volumio volumio[3926]: info: No correspondence found in configuration for share 61 on IP 192.168.1.61 May 05 20:54:08 volumio volumio[3926]: info: Executing SMB command: smbclient --debuglevel=4 -L 192.168.1.61 -N May 05 20:54:08 volumio volumio[3926]: info: Error browsing device 61 for shares: Command failed: smbclient --debuglevel=4 -L 192.168.1.61 -N May 05 20:54:08 volumio volumio[3926]: lp_load_ex: refreshing parameters May 05 20:54:08 volumio volumio[3926]: Initialising global parameters May 05 20:54:08 volumio volumio[3926]: Processing section "[global]" May 05 20:54:08 volumio volumio[3926]: doing parameter netbios name = Volumio May 05 20:54:08 volumio volumio[3926]: doing parameter server string = Volumio Audiophile Music Player May 05 20:54:08 volumio volumio[3926]: doing parameter workgroup = WORKGROUP May 05 20:54:08 volumio volumio[3926]: doing parameter security = user May 05 20:54:08 volumio volumio[3926]: doing parameter map to guest = Bad User May 05 20:54:08 volumio volumio[3926]: doing parameter encrypt passwords = yes May 05 20:54:08 volumio volumio[3926]: doing parameter wins support = yes May 05 20:54:08 volumio volumio[3926]: doing parameter local master = no May 05 20:54:08 volumio volumio[3926]: doing parameter preferred master = no May 05 20:54:08 volumio volumio[3926]: doing parameter os level = 30 May 05 20:54:08 volumio volumio[3926]: doing parameter fruit:model = AirPort May 05 20:54:08 volumio volumio[3926]: pm_process() returned Yes May 05 20:54:08 volumio volumio[3926]: directory_create_or_exist_strict: invalid ownership on directory /var/run/samba/msg.lock May 05 20:54:08 volumio volumio[3926]: cmdline_messaging_context: Unable to initialize messaging context. May 05 20:54:08 volumio volumio[3926]: Unable to initialize messaging context May 05 20:54:08 volumio volumio[3926]: lp_load_ex: refreshing parameters May 05 20:54:08 volumio volumio[3926]: Initialising global parameters May 05 20:54:08 volumio volumio[3926]: Processing section "[global]" May 05 20:54:08 volumio volumio[3926]: doing parameter netbios name = Volumio May 05 20:54:08 volumio volumio[3926]: doing parameter server string = Volumio Audiophile Music Player May 05 20:54:08 volumio volumio[3926]: doing parameter workgroup = WORKGROUP May 05 20:54:08 volumio volumio[3926]: doing parameter security = user May 05 20:54:08 volumio volumio[3926]: doing parameter map to guest = Bad User May 05 20:54:08 volumio volumio[3926]: doing parameter encrypt passwords = yes May 05 20:54:08 volumio volumio[3926]: doing parameter wins support = yes May 05 20:54:08 volumio volumio[3926]: doing parameter local master = no May 05 20:54:08 volumio volumio[3926]: doing parameter preferred master = no May 05 20:54:08 volumio volumio[3926]: doing parameter os level = 30 May 05 20:54:08 volumio volumio[3926]: doing parameter fruit:model = AirPort May 05 20:54:08 volumio volumio[3926]: pm_process() returned Yes May 05 20:54:08 volumio volumio[3926]: added interface eth0 ip=192.168.1.92 bcast=192.168.1.255 netmask=255.255.255.0 May 05 20:54:08 volumio volumio[3926]: Client started (version 4.9.5-Debian). May 05 20:54:08 volumio volumio[3926]: Connecting to 192.168.1.61 at port 445 May 05 20:54:08 volumio volumio[3926]: session request ok May 05 20:54:08 volumio volumio[3926]: negotiated dialect[SMB3_11] against server[192.168.1.61] May 05 20:54:08 volumio volumio[3926]: got OID=1.3.6.1.4.1.311.2.2.10 May 05 20:54:08 volumio volumio[3926]: GENSEC backend 'gssapi_spnego' registered May 05 20:54:08 volumio volumio[3926]: GENSEC backend 'gssapi_krb5' registered May 05 20:54:08 volumio volumio[3926]: GENSEC backend 'gssapi_krb5_sasl' registered May 05 20:54:08 volumio volumio[3926]: GENSEC backend 'spnego' registered May 05 20:54:08 volumio volumio[3926]: GENSEC backend 'schannel' registered May 05 20:54:08 volumio volumio[3926]: GENSEC backend 'naclrpc_as_system' registered May 05 20:54:08 volumio volumio[3926]: GENSEC backend 'sasl-EXTERNAL' registered May 05 20:54:08 volumio volumio[3926]: GENSEC backend 'ntlmssp' registered May 05 20:54:08 volumio volumio[3926]: GENSEC backend 'ntlmssp_resume_ccache' registered May 05 20:54:08 volumio volumio[3926]: GENSEC backend 'http_basic' registered May 05 20:54:08 volumio volumio[3926]: GENSEC backend 'http_ntlm' registered May 05 20:54:08 volumio volumio[3926]: GENSEC backend 'http_negotiate' registered May 05 20:54:08 volumio volumio[3926]: GENSEC backend 'krb5' registered May 05 20:54:08 volumio volumio[3926]: GENSEC backend 'fake_gssapi_krb5' registered May 05 20:54:08 volumio volumio[3926]: Got challenge flags: May 05 20:54:08 volumio volumio[3926]: Got NTLMSSP neg_flags=0x628a8215 May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_UNICODE May 05 20:54:08 volumio volumio[3926]: NTLMSSP_REQUEST_TARGET May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_SIGN May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_NTLM May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN May 05 20:54:08 volumio volumio[3926]: NTLMSSP_TARGET_TYPE_SERVER May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_TARGET_INFO May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_VERSION May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_128 May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_KEY_EXCH May 05 20:54:08 volumio volumio[3926]: NTLMSSP: Set final flags: May 05 20:54:08 volumio volumio[3926]: Got NTLMSSP neg_flags=0x62008215 May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_UNICODE May 05 20:54:08 volumio volumio[3926]: NTLMSSP_REQUEST_TARGET May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_SIGN May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_NTLM May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_VERSION May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_128 May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_KEY_EXCH May 05 20:54:08 volumio volumio[3926]: NTLMSSP Sign/Seal - Initialising with flags: May 05 20:54:08 volumio volumio[3926]: Got NTLMSSP neg_flags=0x62008215 May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_UNICODE May 05 20:54:08 volumio volumio[3926]: NTLMSSP_REQUEST_TARGET May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_SIGN May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_NTLM May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_VERSION May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_128 May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_KEY_EXCH May 05 20:54:08 volumio volumio[3926]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information. May 05 20:54:08 volumio volumio[3926]: got OID=1.3.6.1.4.1.311.2.2.10 May 05 20:54:08 volumio volumio[3926]: Got challenge flags: May 05 20:54:08 volumio volumio[3926]: Got NTLMSSP neg_flags=0x628a8215 May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_UNICODE May 05 20:54:08 volumio volumio[3926]: NTLMSSP_REQUEST_TARGET May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_SIGN May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_NTLM May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN May 05 20:54:08 volumio volumio[3926]: NTLMSSP_TARGET_TYPE_SERVER May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_TARGET_INFO May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_VERSION May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_128 May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_KEY_EXCH May 05 20:54:08 volumio volumio[3926]: NTLMSSP: Set final flags: May 05 20:54:08 volumio volumio[3926]: Got NTLMSSP neg_flags=0x62008a15 May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_UNICODE May 05 20:54:08 volumio volumio[3926]: NTLMSSP_REQUEST_TARGET May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_SIGN May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_NTLM May 05 20:54:08 volumio volumio[3926]: NTLMSSP_ANONYMOUS May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_VERSION May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_128 May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_KEY_EXCH May 05 20:54:08 volumio volumio[3926]: NTLMSSP Sign/Seal - Initialising with flags: May 05 20:54:08 volumio volumio[3926]: Got NTLMSSP neg_flags=0x62008a15 May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_UNICODE May 05 20:54:08 volumio volumio[3926]: NTLMSSP_REQUEST_TARGET May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_SIGN May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_NTLM May 05 20:54:08 volumio volumio[3926]: NTLMSSP_ANONYMOUS May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_VERSION May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_128 May 05 20:54:08 volumio volumio[3926]: NTLMSSP_NEGOTIATE_KEY_EXCH May 05 20:54:08 volumio volumio[3926]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information. May 05 20:54:08 volumio volumio[3926]: info: Detected device 61 with version null May 05 20:54:09 volumio sudo[4757]: 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.1.61/Music /mnt/NAS/61 May 05 20:54:09 volumio sudo[4757]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:54:09 volumio kernel: CIFS: Attempting to mount //192.168.1.61/Music May 05 20:54:09 volumio sudo[4757]: pam_unix(sudo:session): session closed for user root May 05 20:54:09 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE May 05 20:54:09 volumio kernel: CIFS: VFS: \\192.168.1.61 Send error in SessSetup = -13 May 05 20:54:09 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 May 05 20:54:09 volumio volumio[3926]: info: Permission denied for 61 on IP 192.168.1.61 May 05 20:54:10 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 05 20:54:11 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 20:54:15 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 20:54:19 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 20:54:21 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare May 05 20:54:21 volumio volumio[3926]: info: Share 61 successfully unmounted May 05 20:54:21 volumio sudo[4797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=bilal,password=Dumnass9i!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.61/Music /mnt/NAS/61 May 05 20:54:21 volumio sudo[4797]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 20:54:21 volumio kernel: CIFS: Attempting to mount //192.168.1.61/Music May 05 20:54:21 volumio sudo[4797]: pam_unix(sudo:session): session closed for user root May 05 20:54:21 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE May 05 20:54:21 volumio kernel: CIFS: VFS: \\192.168.1.61 Send error in SessSetup = -13 May 05 20:54:21 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 May 05 20:54:21 volumio volumio[3926]: info: An error occurred mounting the new share. Rolling back configuration May 05 20:54:23 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 20:54:27 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 20:54:31 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 20:54:33 volumio volumio[3926]: verbose: New Socket.io Connection to 192.168.1.92 from 192.168.1.76 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::volumioGetVisibleSources May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::volumioGetState May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::getUIConfigOnPlugin May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::getUIConfigOnPlugin May 05 20:54:33 volumio volumio[3926]: info: Retrieving Cloud Streaming UI May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 05 20:54:33 volumio volumio[3926]: info: Received Get System Info May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 05 20:54:33 volumio volumio[3926]: info: Discovery: Getting this device information May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::volumioGetState May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::volumioGetState May 05 20:54:33 volumio volumio[3926]: info: Listing playlists May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 05 20:54:33 volumio volumio[3926]: info: Getting Tidal Cloud Configuration May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::getUIConfigOnPlugin May 05 20:54:33 volumio volumio[3926]: info: Getting Qobuz Cloud Configuration May 05 20:54:33 volumio volumio[3926]: info: Asking plugin for UI Config May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::getUIConfigOnPlugin May 05 20:54:33 volumio volumio[3926]: info: Getting Spotify Cloud Configuration May 05 20:54:33 volumio volumio[3926]: info: Asking plugin for UI Config May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::getUIConfigOnPlugin May 05 20:54:33 volumio volumio[3926]: info: Saving Spotify Acccount May 05 20:54:33 volumio volumio[3926]: info: Got Tidal Cloud Configuration May 05 20:54:33 volumio volumio[3926]: info: Got it May 05 20:54:33 volumio volumio[3926]: info: Got it May 05 20:54:33 volumio volumio[3926]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::getUIConfigOnPlugin May 05 20:54:33 volumio volumio[3926]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::volumioGetBrowseSources May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::volumioGetBrowseSources May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::volumioGetBrowseSources May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 20:54:33 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 05 20:54:34 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 05 20:54:34 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 05 20:54:34 volumio volumio[3926]: info: Received Get System Info May 05 20:54:34 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 05 20:54:34 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 05 20:54:34 volumio volumio[3926]: info: Discovery: Getting this device information May 05 20:54:34 volumio volumio[3926]: info: CoreCommandRouter::volumioGetState May 05 20:54:34 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 05 20:54:37 volumio volumio[3926]: info: CoreCommandRouter::volumioGetQueue May 05 20:54:37 volumio volumio[3926]: info: CoreStateMachine::getQueue May 05 20:54:37 volumio volumio[3926]: info: CorePlayQueue::getQueue May 05 20:54:38 volumio ntpd[3711]: ntpd exiting on signal 15 (Terminated) May 05 20:54:38 volumio ntpd[3711]: 51.250.35.68 local addr 192.168.1.92 -> May 05 20:54:38 volumio systemd[1]: Stopping Network Time Service... May 05 20:54:38 volumio ntpd[3711]: 89.179.240.219 local addr 192.168.1.92 -> May 05 20:54:38 volumio ntpd[3711]: 45.90.217.6 local addr 192.168.1.92 -> May 05 20:54:38 volumio ntpd[3711]: 217.170.87.229 local addr 192.168.1.92 -> May 05 20:54:38 volumio ntpd[3711]: 91.206.16.3 local addr 192.168.1.92 -> May 05 20:54:38 volumio ntpd[3711]: 91.235.144.128 local addr 192.168.1.92 -> May 05 20:54:38 volumio ntpd[3711]: 162.159.200.123 local addr 192.168.1.92 -> May 05 20:54:38 volumio ntpd[3711]: 212.41.10.19 local addr 192.168.1.92 -> May 05 20:54:38 volumio ntpd[3711]: 89.109.251.24 local addr 192.168.1.92 -> May 05 20:54:38 volumio ntpd[3711]: 45.141.102.99 local addr 192.168.1.92 -> May 05 20:54:38 volumio ntpd[3711]: 46.146.231.187 local addr 192.168.1.92 -> May 05 20:54:38 volumio ntpd[3711]: 188.225.9.167 local addr 192.168.1.92 -> May 05 20:54:38 volumio systemd[1]: ntp.service: Succeeded. May 05 20:54:38 volumio systemd[1]: Stopped Network Time Service. May 05 20:54:38 volumio systemd[1]: Starting Network Time Service... May 05 20:54:38 volumio ntpd[4856]: ntpd 4.2.8p12@1.3728-o (1): Starting May 05 20:54:38 volumio ntpd[4856]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 05 20:54:38 volumio systemd[1]: Started Network Time Service. May 05 20:54:38 volumio ntpd[4862]: proto: precision = 0.162 usec (-22) May 05 20:54:38 volumio ntpd[4862]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 05 20:54:38 volumio ntpd[4862]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 05 20:54:38 volumio ntpd[4862]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 860 days ago May 05 20:54:38 volumio ntpd[4862]: Listen and drop on 0 v6wildcard [::]:123 May 05 20:54:38 volumio ntpd[4862]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 05 20:54:38 volumio ntpd[4862]: Listen normally on 2 lo 127.0.0.1:123 May 05 20:54:38 volumio ntpd[4862]: Listen normally on 3 eth0 192.168.1.92:123 May 05 20:54:38 volumio ntpd[4862]: Listening on routing socket on fd #20 for interface updates May 05 20:54:38 volumio ntpd[4862]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 05 20:54:38 volumio ntpd[4862]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 05 20:54:39 volumio ntpd[4862]: Soliciting pool server 51.250.53.172 May 05 20:54:40 volumio ntpd[4862]: Soliciting pool server 46.138.254.88 May 05 20:54:40 volumio ntpd[4862]: Soliciting pool server 92.255.126.11 May 05 20:54:41 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 05 20:54:41 volumio volumio[3926]: info: Preload queue cleared May 05 20:54:41 volumio ntpd[4862]: Soliciting pool server 45.141.102.99 May 05 20:54:41 volumio ntpd[4862]: Soliciting pool server 95.31.7.160 May 05 20:54:41 volumio ntpd[4862]: Soliciting pool server 217.170.87.229 May 05 20:54:42 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 05 20:54:42 volumio volumio[3926]: info: Preload queue cleared May 05 20:54:42 volumio ntpd[4862]: Soliciting pool server 91.235.144.128 May 05 20:54:42 volumio ntpd[4862]: Soliciting pool server 83.143.51.50 May 05 20:54:42 volumio ntpd[4862]: Soliciting pool server 151.0.2.54 May 05 20:54:42 volumio ntpd[4862]: Soliciting pool server 188.225.9.167 May 05 20:54:42 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 05 20:54:43 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 05 20:54:43 volumio volumio[3926]: info: Preload queue cleared May 05 20:54:43 volumio ntpd[4862]: Soliciting pool server 162.159.200.123 May 05 20:54:43 volumio ntpd[4862]: Soliciting pool server 212.41.10.19 May 05 20:54:43 volumio ntpd[4862]: Soliciting pool server 195.218.227.230 May 05 20:54:44 volumio ntpd[4862]: Soliciting pool server 89.109.251.24 May 05 20:54:44 volumio ntpd[4862]: Soliciting pool server 2a03:aa00::136:50 May 05 20:54:44 volumio ntpd[4862]: Soliciting pool server 45.90.217.6 May 05 20:54:44 volumio volumio[3926]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 05 20:54:44 volumio volumio[3926]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 20:54:44 volumio volumio[3926]: TypeError: Cannot read property 'length' of undefined May 05 20:54:44 volumio volumio[3926]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 05 20:54:44 volumio volumio[3926]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 05 20:54:44 volumio volumio[3926]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 05 20:54:44 volumio volumio[3926]: at Parser.emit (events.js:400:28) May 05 20:54:44 volumio volumio[3926]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 05 20:54:44 volumio volumio[3926]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 05 20:54:44 volumio volumio[3926]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 05 20:54:44 volumio volumio[3926]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 05 20:54:44 volumio volumio[3926]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 05 20:54:44 volumio volumio[3926]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 05 20:54:44 volumio volumio[3926]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 05 20:54:44 volumio volumio[3926]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 05 20:54:44 volumio volumio[3926]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 05 20:54:44 volumio volumio[3926]: at IncomingMessage.emit (events.js:412:35) May 05 20:54:44 volumio volumio[3926]: at endReadableNT (internal/streams/readable.js:1333:12) May 05 20:54:44 volumio volumio[3926]: at processTicksAndRejections (internal/process/task_queues.js:82:21) May 05 20:54:44 volumio volumio[3926]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 20:54:45 volumio sudo[4935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-05 20:53 May 05 20:54:45 volumio sudo[4935]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 10:07:11 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dc77d77f02ef88ea816c3d31de871e8f"