-- Logs begin at Sun 2025-05-18 03:59:22 AEST, end at Wed 2025-05-21 17:44:21 AEST. -- May 21 17:43:00 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare May 21 17:43:00 volumio volumio[897]: info: Adding a new share May 21 17:43:00 volumio volumio[897]: info: No correspondence found in configuration for share NAS FLAC on IP 192.168.9.65 May 21 17:43:00 volumio volumio[897]: info: Executing SMB command: smbclient --debuglevel=4 -L 192.168.9.65 -N May 21 17:43:01 volumio volumio[897]: info: Error browsing device NAS FLAC for shares: Command failed: smbclient --debuglevel=4 -L 192.168.9.65 -N May 21 17:43:01 volumio volumio[897]: lp_load_ex: refreshing parameters May 21 17:43:01 volumio volumio[897]: Initialising global parameters May 21 17:43:01 volumio volumio[897]: Processing section "[global]" May 21 17:43:01 volumio volumio[897]: doing parameter netbios name = Volumio May 21 17:43:01 volumio volumio[897]: doing parameter server string = Volumio Audiophile Music Player May 21 17:43:01 volumio volumio[897]: doing parameter workgroup = WORKGROUP May 21 17:43:01 volumio volumio[897]: doing parameter security = user May 21 17:43:01 volumio volumio[897]: doing parameter map to guest = Bad User May 21 17:43:01 volumio volumio[897]: doing parameter encrypt passwords = yes May 21 17:43:01 volumio volumio[897]: doing parameter wins support = yes May 21 17:43:01 volumio volumio[897]: doing parameter local master = no May 21 17:43:01 volumio volumio[897]: doing parameter preferred master = no May 21 17:43:01 volumio volumio[897]: doing parameter os level = 30 May 21 17:43:01 volumio volumio[897]: doing parameter fruit:model = AirPort May 21 17:43:01 volumio volumio[897]: pm_process() returned Yes May 21 17:43:01 volumio volumio[897]: directory_create_or_exist_strict: invalid ownership on directory /var/run/samba/msg.lock May 21 17:43:01 volumio volumio[897]: cmdline_messaging_context: Unable to initialize messaging context. May 21 17:43:01 volumio volumio[897]: Unable to initialize messaging context May 21 17:43:01 volumio volumio[897]: lp_load_ex: refreshing parameters May 21 17:43:01 volumio volumio[897]: Initialising global parameters May 21 17:43:01 volumio volumio[897]: Processing section "[global]" May 21 17:43:01 volumio volumio[897]: doing parameter netbios name = Volumio May 21 17:43:01 volumio volumio[897]: doing parameter server string = Volumio Audiophile Music Player May 21 17:43:01 volumio volumio[897]: doing parameter workgroup = WORKGROUP May 21 17:43:01 volumio volumio[897]: doing parameter security = user May 21 17:43:01 volumio volumio[897]: doing parameter map to guest = Bad User May 21 17:43:01 volumio volumio[897]: doing parameter encrypt passwords = yes May 21 17:43:01 volumio volumio[897]: doing parameter wins support = yes May 21 17:43:01 volumio volumio[897]: doing parameter local master = no May 21 17:43:01 volumio volumio[897]: doing parameter preferred master = no May 21 17:43:01 volumio volumio[897]: doing parameter os level = 30 May 21 17:43:01 volumio volumio[897]: doing parameter fruit:model = AirPort May 21 17:43:01 volumio volumio[897]: pm_process() returned Yes May 21 17:43:01 volumio volumio[897]: added interface eth0 ip=192.168.9.202 bcast=192.168.9.255 netmask=255.255.255.0 May 21 17:43:01 volumio volumio[897]: Client started (version 4.9.5-Debian). May 21 17:43:01 volumio volumio[897]: Connecting to 192.168.9.65 at port 445 May 21 17:43:01 volumio volumio[897]: session request ok May 21 17:43:01 volumio volumio[897]: negotiated dialect[SMB3_11] against server[192.168.9.65] May 21 17:43:01 volumio volumio[897]: got OID=1.3.6.1.4.1.311.2.2.10 May 21 17:43:01 volumio volumio[897]: GENSEC backend 'gssapi_spnego' registered May 21 17:43:01 volumio volumio[897]: GENSEC backend 'gssapi_krb5' registered May 21 17:43:01 volumio volumio[897]: GENSEC backend 'gssapi_krb5_sasl' registered May 21 17:43:01 volumio volumio[897]: GENSEC backend 'spnego' registered May 21 17:43:01 volumio volumio[897]: GENSEC backend 'schannel' registered May 21 17:43:01 volumio volumio[897]: GENSEC backend 'naclrpc_as_system' registered May 21 17:43:01 volumio volumio[897]: GENSEC backend 'sasl-EXTERNAL' registered May 21 17:43:01 volumio volumio[897]: GENSEC backend 'ntlmssp' registered May 21 17:43:01 volumio volumio[897]: GENSEC backend 'ntlmssp_resume_ccache' registered May 21 17:43:01 volumio volumio[897]: GENSEC backend 'http_basic' registered May 21 17:43:01 volumio volumio[897]: GENSEC backend 'http_ntlm' registered May 21 17:43:01 volumio volumio[897]: GENSEC backend 'http_negotiate' registered May 21 17:43:01 volumio volumio[897]: GENSEC backend 'krb5' registered May 21 17:43:01 volumio volumio[897]: GENSEC backend 'fake_gssapi_krb5' registered May 21 17:43:01 volumio volumio[897]: Got challenge flags: May 21 17:43:01 volumio volumio[897]: Got NTLMSSP neg_flags=0x628a8215 May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_UNICODE May 21 17:43:01 volumio volumio[897]: NTLMSSP_REQUEST_TARGET May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_SIGN May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_NTLM May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN May 21 17:43:01 volumio volumio[897]: NTLMSSP_TARGET_TYPE_SERVER May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_TARGET_INFO May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_VERSION May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_128 May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_KEY_EXCH May 21 17:43:01 volumio volumio[897]: NTLMSSP: Set final flags: May 21 17:43:01 volumio volumio[897]: Got NTLMSSP neg_flags=0x62008215 May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_UNICODE May 21 17:43:01 volumio volumio[897]: NTLMSSP_REQUEST_TARGET May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_SIGN May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_NTLM May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_VERSION May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_128 May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_KEY_EXCH May 21 17:43:01 volumio volumio[897]: NTLMSSP Sign/Seal - Initialising with flags: May 21 17:43:01 volumio volumio[897]: Got NTLMSSP neg_flags=0x62008215 May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_UNICODE May 21 17:43:01 volumio volumio[897]: NTLMSSP_REQUEST_TARGET May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_SIGN May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_NTLM May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_VERSION May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_128 May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_KEY_EXCH May 21 17:43:01 volumio volumio[897]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information. May 21 17:43:01 volumio volumio[897]: got OID=1.3.6.1.4.1.311.2.2.10 May 21 17:43:01 volumio volumio[897]: Got challenge flags: May 21 17:43:01 volumio volumio[897]: Got NTLMSSP neg_flags=0x628a8215 May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_UNICODE May 21 17:43:01 volumio volumio[897]: NTLMSSP_REQUEST_TARGET May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_SIGN May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_NTLM May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN May 21 17:43:01 volumio volumio[897]: NTLMSSP_TARGET_TYPE_SERVER May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_TARGET_INFO May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_VERSION May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_128 May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_KEY_EXCH May 21 17:43:01 volumio volumio[897]: NTLMSSP: Set final flags: May 21 17:43:01 volumio volumio[897]: Got NTLMSSP neg_flags=0x62008a15 May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_UNICODE May 21 17:43:01 volumio volumio[897]: NTLMSSP_REQUEST_TARGET May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_SIGN May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_NTLM May 21 17:43:01 volumio volumio[897]: NTLMSSP_ANONYMOUS May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_VERSION May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_128 May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_KEY_EXCH May 21 17:43:01 volumio volumio[897]: NTLMSSP Sign/Seal - Initialising with flags: May 21 17:43:01 volumio volumio[897]: Got NTLMSSP neg_flags=0x62008a15 May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_UNICODE May 21 17:43:01 volumio volumio[897]: NTLMSSP_REQUEST_TARGET May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_SIGN May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_NTLM May 21 17:43:01 volumio volumio[897]: NTLMSSP_ANONYMOUS May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_VERSION May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_128 May 21 17:43:01 volumio volumio[897]: NTLMSSP_NEGOTIATE_KEY_EXCH May 21 17:43:01 volumio volumio[897]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information. May 21 17:43:01 volumio volumio[897]: info: Detected device NAS FLAC with version null May 21 17:43:01 volumio sudo[2552]: 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.9.65/music /mnt/NAS/NAS_FLAC May 21 17:43:01 volumio sudo[2552]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:43:01 volumio kernel: Key type cifs.spnego registered May 21 17:43:01 volumio kernel: Key type cifs.idmap registered May 21 17:43:01 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 17:43:01 volumio kernel: CIFS: Attempting to mount //192.168.9.65/music May 21 17:43:01 volumio kernel: cryptd: max_cpu_qlen set to 1000 May 21 17:43:01 volumio sudo[2552]: pam_unix(sudo:session): session closed for user root May 21 17:43:01 volumio volumio[897]: info: Permission denied for NAS FLAC on IP 192.168.9.65 May 21 17:43:01 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE May 21 17:43:01 volumio kernel: CIFS: VFS: \\192.168.9.65 Send error in SessSetup = -13 May 21 17:43:01 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 May 21 17:43:02 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 21 17:43:03 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 17:43:07 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 17:43:11 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 17:43:15 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 17:43:19 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 17:43:22 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare May 21 17:43:22 volumio volumio[897]: info: Share NAS FLAC successfully unmounted May 21 17:43:22 volumio sudo[2627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=guest,password=guest01,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.9.65/music /mnt/NAS/NAS_FLAC May 21 17:43:22 volumio sudo[2627]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:43:22 volumio kernel: CIFS: Attempting to mount //192.168.9.65/music May 21 17:43:22 volumio sudo[2627]: pam_unix(sudo:session): session closed for user root May 21 17:43:22 volumio volumio[897]: info: An error occurred mounting the new share. Rolling back configuration May 21 17:43:22 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE May 21 17:43:22 volumio kernel: CIFS: VFS: \\192.168.9.65 Send error in SessSetup = -13 May 21 17:43:22 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 May 21 17:43:23 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 17:43:27 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 17:43:31 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 17:43:35 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 17:43:39 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 21 17:43:41 volumio volumio[897]: info: CoreCommandRouter::volumioGetState May 21 17:43:41 volumio volumio[897]: info: CorePlayQueue::getTrack 0 May 21 17:43:43 volumio volumio[897]: info: Executing endpoint metavolumio May 21 17:43:43 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 21 17:43:43 volumio volumio[897]: info: Executing endpoint metavolumio May 21 17:43:43 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 21 17:43:43 volumio volumio[897]: info: Executing endpoint metavolumio May 21 17:43:43 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 21 17:43:43 volumio volumio[897]: info: CoreCommandRouter::volumioGetQueue May 21 17:43:43 volumio volumio[897]: info: CoreStateMachine::getQueue May 21 17:43:43 volumio volumio[897]: info: CorePlayQueue::getQueue May 21 17:43:46 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 21 17:43:46 volumio volumio[897]: info: CURURI: playlists May 21 17:43:46 volumio volumio[897]: info: Listing playlists May 21 17:43:46 volumio volumio[897]: info: Preload queue cleared May 21 17:43:51 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 21 17:43:51 volumio volumio[897]: info: CURURI: music-library May 21 17:43:51 volumio volumio[897]: info: Preload queue cleared May 21 17:43:52 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 21 17:43:52 volumio volumio[897]: info: CURURI: music-library/INTERNAL May 21 17:43:52 volumio volumio[897]: info: Preload queue cleared May 21 17:44:14 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 21 17:44:14 volumio volumio[897]: info: Preload queue cleared May 21 17:44:16 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 21 17:44:16 volumio volumio[897]: info: Preload queue cleared May 21 17:44:18 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 21 17:44:18 volumio volumio[897]: info: Preload queue cleared May 21 17:44:21 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 21 17:44:21 volumio volumio[897]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 17:44:21 volumio volumio[897]: TypeError: Cannot read property 'length' of undefined May 21 17:44:21 volumio volumio[897]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 21 17:44:21 volumio volumio[897]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 21 17:44:21 volumio volumio[897]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 21 17:44:21 volumio volumio[897]: at Parser.emit (events.js:315:20) May 21 17:44:21 volumio volumio[897]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 21 17:44:21 volumio volumio[897]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 21 17:44:21 volumio volumio[897]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 21 17:44:21 volumio volumio[897]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 21 17:44:21 volumio volumio[897]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 21 17:44:21 volumio volumio[897]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 21 17:44:21 volumio volumio[897]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 21 17:44:21 volumio volumio[897]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 21 17:44:21 volumio volumio[897]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 21 17:44:21 volumio volumio[897]: at IncomingMessage.emit (events.js:327:22) May 21 17:44:21 volumio volumio[897]: at endReadableNT (internal/streams/readable.js:1327:12) May 21 17:44:21 volumio volumio[897]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 21 17:44:21 volumio volumio[897]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 17:44:21 volumio sudo[2776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-21 17:43 May 21 17:44:21 volumio sudo[2776]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"