-- Logs begin at Sat 2025-03-15 12:41:01 -03, end at Sat 2025-03-15 12:45:31 -03. -- Mar 15 12:44:00 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Mar 15 12:44:00 volumio sudo[2328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/shared Mar 15 12:44:00 volumio sudo[2328]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 12:44:00 volumio systemd[1]: mnt-NAS-shared.mount: Succeeded. Mar 15 12:44:01 volumio sudo[2328]: pam_unix(sudo:session): session closed for user root Mar 15 12:44:01 volumio volumio[1030]: info: Share shared successfully unmounted Mar 15 12:44:01 volumio sudo[2332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.0.110:shared /mnt/NAS/shared Mar 15 12:44:01 volumio sudo[2332]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 12:44:01 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 12:44:01 volumio mpd[1562]: exception: No database Mar 15 12:44:01 volumio kernel: NFS: Registering the id_resolver key type Mar 15 12:44:01 volumio kernel: Key type id_resolver registered Mar 15 12:44:01 volumio kernel: Key type id_legacy registered Mar 15 12:44:01 volumio systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Mar 15 12:44:01 volumio systemd[1]: Starting Preprocess NFS configuration... Mar 15 12:44:01 volumio systemd[1]: nfs-config.service: Succeeded. Mar 15 12:44:01 volumio systemd[1]: Started Preprocess NFS configuration. Mar 15 12:44:01 volumio systemd[1]: Starting NFS status monitor for NFSv2/3 locking.... Mar 15 12:44:01 volumio systemd[1]: Starting Notify NFS peers of a restart... Mar 15 12:44:01 volumio sm-notify[2354]: Version 1.3.3 starting Mar 15 12:44:01 volumio systemd[1]: rpc-statd-notify.service: Succeeded. Mar 15 12:44:01 volumio systemd[1]: Started Notify NFS peers of a restart. Mar 15 12:44:01 volumio rpc.statd[2355]: Version 1.3.3 starting Mar 15 12:44:01 volumio rpc.statd[2355]: Flags: TI-RPC Mar 15 12:44:01 volumio rpc.statd[2355]: Failed to read /var/lib/nfs/state: Success Mar 15 12:44:01 volumio rpc.statd[2355]: Initializing NSM state Mar 15 12:44:01 volumio systemd[1]: Started NFS status monitor for NFSv2/3 locking.. Mar 15 12:44:01 volumio sudo[2332]: pam_unix(sudo:session): session closed for user root Mar 15 12:44:01 volumio volumio[1030]: info: An error occurred mounting the new share. Rolling back configuration Mar 15 12:44:02 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 15 12:44:05 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 12:44:05 volumio mpd[1562]: exception: No database Mar 15 12:44:09 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 12:44:09 volumio mpd[1562]: exception: No database Mar 15 12:44:13 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 12:44:13 volumio mpd[1562]: exception: No database Mar 15 12:44:17 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 12:44:17 volumio mpd[1562]: exception: No database Mar 15 12:44:18 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Mar 15 12:44:18 volumio volumio[1030]: info: Share shared successfully unmounted Mar 15 12:44:18 volumio sudo[2423]: 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.0.110/shared /mnt/NAS/shared Mar 15 12:44:18 volumio sudo[2423]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 12:44:18 volumio kernel: CIFS: Attempting to mount //192.168.0.110/shared Mar 15 12:44:18 volumio sudo[2423]: pam_unix(sudo:session): session closed for user root Mar 15 12:44:18 volumio volumio[1030]: info: [1742053458593] Database scan error: Error: Command failed: /usr/bin/mpc update Mar 15 12:44:18 volumio volumio[1030]: MPD error: No database Mar 15 12:44:19 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 15 12:44:21 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 12:44:21 volumio mpd[1562]: exception: No database Mar 15 12:44:25 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 12:44:25 volumio mpd[1562]: exception: No database Mar 15 12:44:26 volumio go-librespot[1581]: time="2025-03-15T12:44:26-03:00" level=trace msg="sent dealer ping" Mar 15 12:44:26 volumio go-librespot[1581]: time="2025-03-15T12:44:26-03:00" level=trace msg="received dealer pong" Mar 15 12:44:29 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 12:44:29 volumio mpd[1562]: exception: No database Mar 15 12:44:33 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 12:44:33 volumio mpd[1562]: exception: No database Mar 15 12:44:33 volumio ntpd[1098]: Soliciting pool server 2606:4700:f1::123 Mar 15 12:44:37 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 12:44:37 volumio mpd[1562]: exception: No database Mar 15 12:44:37 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Mar 15 12:44:37 volumio sudo[2469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/shared Mar 15 12:44:37 volumio sudo[2469]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 12:44:37 volumio systemd[1]: mnt-NAS-shared.mount: Succeeded. Mar 15 12:44:37 volumio sudo[2469]: pam_unix(sudo:session): session closed for user root Mar 15 12:44:37 volumio volumio[1030]: info: Share shared successfully unmounted Mar 15 12:44:38 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 15 12:44:40 volumio volumio[1030]: info: [1742053480772] Database scan error: Error: Command failed: /usr/bin/mpc update Mar 15 12:44:40 volumio volumio[1030]: MPD error: No database Mar 15 12:44:41 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 12:44:41 volumio mpd[1562]: exception: No database Mar 15 12:44:45 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 12:44:45 volumio mpd[1562]: exception: No database Mar 15 12:44:49 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 12:44:49 volumio mpd[1562]: exception: No database Mar 15 12:44:51 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Mar 15 12:44:51 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand update Mar 15 12:44:53 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 12:44:53 volumio mpd[1562]: exception: No database Mar 15 12:44:54 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Mar 15 12:44:54 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Mar 15 12:44:56 volumio go-librespot[1581]: time="2025-03-15T12:44:56-03:00" level=trace msg="sent dealer ping" Mar 15 12:44:56 volumio go-librespot[1581]: time="2025-03-15T12:44:56-03:00" level=trace msg="received dealer pong" Mar 15 12:44:57 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 12:44:57 volumio mpd[1562]: exception: No database Mar 15 12:45:01 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 12:45:01 volumio mpd[1562]: exception: No database Mar 15 12:45:01 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Mar 15 12:45:01 volumio volumio[1030]: info: Adding a new share Mar 15 12:45:01 volumio volumio[1030]: info: No correspondence found in configuration for share shared on IP 192.168.0.110 Mar 15 12:45:01 volumio sudo[2529]: 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.0.110/shared /mnt/NAS/shared Mar 15 12:45:01 volumio sudo[2529]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 12:45:01 volumio kernel: CIFS: Attempting to mount //192.168.0.110/shared Mar 15 12:45:01 volumio sudo[2529]: pam_unix(sudo:session): session closed for user root Mar 15 12:45:01 volumio volumio[1030]: info: [1742053501434] Database scan error: Error: Command failed: /usr/bin/mpc update Mar 15 12:45:01 volumio volumio[1030]: MPD error: No database Mar 15 12:45:02 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 15 12:45:05 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 12:45:05 volumio mpd[1562]: exception: No database Mar 15 12:45:07 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Mar 15 12:45:07 volumio volumio[1030]: verbose: ControllerMpd::sendMpdCommand rescan Mar 15 12:45:09 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 12:45:09 volumio mpd[1562]: exception: No database Mar 15 12:45:13 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 12:45:13 volumio mpd[1562]: exception: No database Mar 15 12:45:14 volumio volumio[1030]: info: CoreCommandRouter::volumioGetState Mar 15 12:45:14 volumio go-librespot[1581]: time="2025-03-15T12:45:14-03:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Mar 15 12:45:14 volumio go-librespot[1581]: time="2025-03-15T12:45:14-03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1680" Mar 15 12:45:19 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 15 12:45:19 volumio volumio[1030]: info: CURURI: music-library Mar 15 12:45:19 volumio mpd[1562]: exception: No database Mar 15 12:45:19 volumio volumio[1030]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Mar 15 12:45:19 volumio volumio[1030]: info: Preload queue cleared Mar 15 12:45:26 volumio go-librespot[1581]: time="2025-03-15T12:45:26-03:00" level=trace msg="received accesspoint ping" Mar 15 12:45:26 volumio go-librespot[1581]: time="2025-03-15T12:45:26-03:00" level=trace msg="received accesspoint pong ack" Mar 15 12:45:26 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 15 12:45:26 volumio volumio[1030]: info: Preload queue cleared Mar 15 12:45:26 volumio go-librespot[1581]: time="2025-03-15T12:45:26-03:00" level=trace msg="sent dealer ping" Mar 15 12:45:26 volumio go-librespot[1581]: time="2025-03-15T12:45:26-03:00" level=trace msg="received dealer pong" Mar 15 12:45:28 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 15 12:45:28 volumio volumio[1030]: info: Preload queue cleared Mar 15 12:45:29 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 15 12:45:29 volumio volumio[1030]: info: Preload queue cleared Mar 15 12:45:31 volumio volumio[1030]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 15 12:45:31 volumio volumio[1030]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 15 12:45:31 volumio volumio[1030]: TypeError: Cannot read property 'length' of undefined Mar 15 12:45:31 volumio volumio[1030]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Mar 15 12:45:31 volumio volumio[1030]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Mar 15 12:45:31 volumio volumio[1030]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Mar 15 12:45:31 volumio volumio[1030]: at Parser.emit (events.js:400:28) Mar 15 12:45:31 volumio volumio[1030]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Mar 15 12:45:31 volumio volumio[1030]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Mar 15 12:45:31 volumio volumio[1030]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Mar 15 12:45:31 volumio volumio[1030]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Mar 15 12:45:31 volumio volumio[1030]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Mar 15 12:45:31 volumio volumio[1030]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Mar 15 12:45:31 volumio volumio[1030]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Mar 15 12:45:31 volumio volumio[1030]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Mar 15 12:45:31 volumio volumio[1030]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Mar 15 12:45:31 volumio volumio[1030]: at IncomingMessage.emit (events.js:412:35) Mar 15 12:45:31 volumio volumio[1030]: at endReadableNT (internal/streams/readable.js:1333:12) Mar 15 12:45:31 volumio volumio[1030]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Mar 15 12:45:31 volumio volumio[1030]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 15 12:45:31 volumio sudo[2644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-15 12:44 Mar 15 12:45:31 volumio sudo[2644]: 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="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:15:38 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9e0e048b157c8fb0f19240808f3da58b"