-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2025-06-14 18:53:51 CEST. -- Jun 14 18:52:03 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 18:52:07 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 18:52:11 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 18:52:15 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 18:52:19 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 18:52:23 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 18:52:23 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Jun 14 18:52:23 volumiotakeru volumio[1049]: info: Adding a new share Jun 14 18:52:23 volumiotakeru volumio[1049]: info: No correspondence found in configuration for share Takeru_Personal on IP 192.168.50.187 Jun 14 18:52:23 volumiotakeru sudo[5292]: volumio : unable to resolve host volumiotakeru Jun 14 18:52:23 volumiotakeru sudo[5292]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jun 14 18:52:23 volumiotakeru sudo[5292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.50.187:/Takeru_Personal /mnt/NAS/Takeru_Personal Jun 14 18:52:23 volumiotakeru sudo[5292]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 18:52:24 volumiotakeru 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. Jun 14 18:52:24 volumiotakeru systemd[1]: Starting Preprocess NFS configuration... Jun 14 18:52:24 volumiotakeru systemd[1]: Reached target Host and Network Name Lookups. Jun 14 18:52:24 volumiotakeru systemd[1]: nfs-config.service: Succeeded. Jun 14 18:52:24 volumiotakeru systemd[1]: Started Preprocess NFS configuration. Jun 14 18:52:24 volumiotakeru systemd[1]: Starting Notify NFS peers of a restart... Jun 14 18:52:24 volumiotakeru systemd[1]: Starting NFS status monitor for NFSv2/3 locking.... Jun 14 18:52:24 volumiotakeru sm-notify[5304]: Version 1.3.3 starting Jun 14 18:52:24 volumiotakeru systemd[1]: rpc-statd-notify.service: Succeeded. Jun 14 18:52:24 volumiotakeru systemd[1]: Started Notify NFS peers of a restart. Jun 14 18:52:24 volumiotakeru rpc.statd[5306]: Version 1.3.3 starting Jun 14 18:52:24 volumiotakeru rpc.statd[5306]: Flags: TI-RPC Jun 14 18:52:24 volumiotakeru rpc.statd[5306]: Failed to read /var/lib/nfs/state: Success Jun 14 18:52:24 volumiotakeru rpc.statd[5306]: Initializing NSM state Jun 14 18:52:24 volumiotakeru systemd[1]: Started NFS status monitor for NFSv2/3 locking.. Jun 14 18:52:26 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 18:52:26 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 18:52:26 volumiotakeru volumio[1049]: info: Discovery: Getting this device information Jun 14 18:52:26 volumiotakeru volumio[1049]: info: CoreCommandRouter::volumioGetState Jun 14 18:52:26 volumiotakeru volumio[1049]: info: CorePlayQueue::getTrack 0 Jun 14 18:52:26 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 18:52:27 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 18:52:28 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jun 14 18:52:28 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jun 14 18:52:30 volumiotakeru volumio[1049]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L naspit.local. Jun 14 18:52:31 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 18:52:35 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 18:52:38 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Jun 14 18:52:38 volumiotakeru volumio[1049]: info: Adding a new share Jun 14 18:52:38 volumiotakeru volumio[1049]: info: Checking key NasMounts.aad6e3e7-e5e6-4449-924f-7391627c198b Jun 14 18:52:38 volumiotakeru volumio[1049]: info: No correspondence found in configuration for share Takeru_Personal on IP 192.168.50.187 Jun 14 18:52:38 volumiotakeru volumio[1049]: info: Executing SMB command: smbclient --debuglevel=4 -L 192.168.50.187 -N Jun 14 18:52:38 volumiotakeru volumio[1049]: info: Negotiated SMB version with Takeru_Personal: SMB3_11 Jun 14 18:52:38 volumiotakeru volumio[1049]: info: Detected device Takeru_Personal with version SMB3_11 Jun 14 18:52:38 volumiotakeru volumio[1049]: info: Guest mount: forcing fallback SMB version 2.1 for Takeru_Personal Jun 14 18:52:38 volumiotakeru volumio[1049]: info: Set SMB version 3.11 in CIFS options: vers=2.1 Jun 14 18:52:38 volumiotakeru sudo[5353]: volumio : unable to resolve host volumiotakeru Jun 14 18:52:38 volumiotakeru sudo[5353]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jun 14 18:52:38 volumiotakeru sudo[5353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.1 //192.168.50.187/Takeru_Personal /mnt/NAS/Takeru_Personal Jun 14 18:52:38 volumiotakeru sudo[5353]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 18:52:38 volumiotakeru kernel: Key type cifs.spnego registered Jun 14 18:52:38 volumiotakeru kernel: Key type cifs.idmap registered Jun 14 18:52:38 volumiotakeru kernel: CIFS: Attempting to mount //192.168.50.187/Takeru_Personal Jun 14 18:52:38 volumiotakeru kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Jun 14 18:52:38 volumiotakeru sudo[5353]: pam_unix(sudo:session): session closed for user root Jun 14 18:52:38 volumiotakeru volumio[1049]: info: Permission denied for Takeru_Personal on IP 192.168.50.187 Jun 14 18:52:39 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 18:52:39 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 14 18:52:43 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 18:52:47 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 18:52:51 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 18:52:55 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 18:52:56 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 18:52:56 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 18:52:56 volumiotakeru volumio[1049]: info: Discovery: Getting this device information Jun 14 18:52:56 volumiotakeru volumio[1049]: info: CoreCommandRouter::volumioGetState Jun 14 18:52:56 volumiotakeru volumio[1049]: info: CorePlayQueue::getTrack 0 Jun 14 18:52:56 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 18:52:59 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 18:53:03 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 18:53:03 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Jun 14 18:53:03 volumiotakeru volumio[1049]: info: Share Takeru_Personal successfully unmounted Jun 14 18:53:04 volumiotakeru sudo[5469]: volumio : unable to resolve host volumiotakeru Jun 14 18:53:04 volumiotakeru sudo[5469]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jun 14 18:53:04 volumiotakeru sudo[5469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=takeru,password=9102,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.1 //192.168.50.187/Takeru_Personal /mnt/NAS/Takeru_Personal Jun 14 18:53:04 volumiotakeru sudo[5469]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 18:53:04 volumiotakeru kernel: CIFS: Attempting to mount //192.168.50.187/Takeru_Personal Jun 14 18:53:04 volumiotakeru sudo[5469]: pam_unix(sudo:session): session closed for user root Jun 14 18:53:04 volumiotakeru volumio[1049]: verbose: ControllerMpd::sendMpdCommand status Jun 14 18:53:04 volumiotakeru volumio[1049]: info: Jun 14 18:53:04 volumiotakeru volumio[1049]: ---------------------------- MPD announces state update: update Jun 14 18:53:04 volumiotakeru volumio[1049]: info: ControllerMpd::getState Jun 14 18:53:04 volumiotakeru volumio[1049]: verbose: ControllerMpd::sendMpdCommand status Jun 14 18:53:04 volumiotakeru volumio[1049]: info: sendMpdCommand status took 6 milliseconds Jun 14 18:53:04 volumiotakeru volumio[1049]: info: sendMpdCommand status took 2 milliseconds Jun 14 18:53:04 volumiotakeru volumio[1049]: verbose: ControllerMpd::parseState Jun 14 18:53:04 volumiotakeru volumio[1049]: info: Command Router : Notfying DB Updatetrue Jun 14 18:53:04 volumiotakeru volumio[1049]: verbose: ControllerMpd::parseState Jun 14 18:53:04 volumiotakeru volumio[1049]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 18:53:04 volumiotakeru volumio[1049]: info: [1749919984351] Database update started Jun 14 18:53:04 volumiotakeru volumio[1049]: info: sendMpdCommand playlistinfo took 30 milliseconds Jun 14 18:53:04 volumiotakeru volumio[1049]: verbose: ControllerMpd::parseTrackInfo Jun 14 18:53:04 volumiotakeru volumio[1049]: info: ControllerMpd::pushState Jun 14 18:53:04 volumiotakeru volumio[1049]: info: CoreCommandRouter::servicePushState Jun 14 18:53:04 volumiotakeru volumio[1049]: info: CoreStateMachine::pushState Jun 14 18:53:04 volumiotakeru volumio[1049]: info: CorePlayQueue::getTrack 0 Jun 14 18:53:04 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 18:53:04 volumiotakeru volumio[1049]: info: CoreCommandRouter::volumioPushState Jun 14 18:53:04 volumiotakeru volumio[1049]: info: CorePlayQueue::getTrack 0 Jun 14 18:53:04 volumiotakeru volumio[1049]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current youtube2 Received mpd Jun 14 18:53:04 volumiotakeru volumio[1049]: info: ------------------------------ 169ms Jun 14 18:53:04 volumiotakeru volumio[1049]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 Jun 14 18:53:05 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 14 18:53:07 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 18:53:08 volumiotakeru volumio[1049]: info: CoreCommandRouter::Close All Modals sent Jun 14 18:53:10 volumiotakeru sudo[5292]: pam_unix(sudo:session): session closed for user root Jun 14 18:53:10 volumiotakeru volumio[1049]: info: Error mounting Takeru_Personal on IP 192.168.50.187 : exec error Error: Command failed: /usr/bin/sudo /bin/mount -t nfs -o ro,soft,noauto '192.168.50.187:/Takeru_Personal' '/mnt/NAS/Takeru_Personal' Jun 14 18:53:10 volumiotakeru volumio[1049]: sudo: unable to resolve host volumiotakeru: System error Jun 14 18:53:10 volumiotakeru volumio[1049]: mount.nfs: mount to NFS server '192.168.50.187:/Takeru_Personal' failed: RPC Error: Unable to receive Jun 14 18:53:11 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 18:53:11 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 14 18:53:15 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 18:53:19 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 18:53:23 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 18:53:25 volumiotakeru volumio[1049]: info: CoreCommandRouter::volumioGetState Jun 14 18:53:25 volumiotakeru volumio[1049]: info: CorePlayQueue::getTrack 0 Jun 14 18:53:26 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 18:53:26 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 18:53:26 volumiotakeru volumio[1049]: info: Discovery: Getting this device information Jun 14 18:53:26 volumiotakeru volumio[1049]: info: CoreCommandRouter::volumioGetState Jun 14 18:53:26 volumiotakeru volumio[1049]: info: CorePlayQueue::getTrack 0 Jun 14 18:53:26 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 18:53:40 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 18:53:40 volumiotakeru volumio[1049]: info: Preload queue cleared Jun 14 18:53:43 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 18:53:43 volumiotakeru volumio[1049]: info: Preload queue cleared Jun 14 18:53:45 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 18:53:45 volumiotakeru volumio[1049]: info: Preload queue cleared Jun 14 18:53:50 volumiotakeru volumio[1049]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 18:53:50 volumiotakeru volumio[1049]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 18:53:50 volumiotakeru volumio[1049]: TypeError: Cannot read property 'length' of undefined Jun 14 18:53:50 volumiotakeru volumio[1049]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jun 14 18:53:50 volumiotakeru volumio[1049]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jun 14 18:53:50 volumiotakeru volumio[1049]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jun 14 18:53:50 volumiotakeru volumio[1049]: at Parser.emit (events.js:315:20) Jun 14 18:53:50 volumiotakeru volumio[1049]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jun 14 18:53:50 volumiotakeru volumio[1049]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jun 14 18:53:50 volumiotakeru volumio[1049]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jun 14 18:53:50 volumiotakeru volumio[1049]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jun 14 18:53:50 volumiotakeru volumio[1049]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jun 14 18:53:50 volumiotakeru volumio[1049]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jun 14 18:53:50 volumiotakeru volumio[1049]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jun 14 18:53:50 volumiotakeru volumio[1049]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jun 14 18:53:50 volumiotakeru volumio[1049]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jun 14 18:53:50 volumiotakeru volumio[1049]: at IncomingMessage.emit (events.js:327:22) Jun 14 18:53:50 volumiotakeru volumio[1049]: at endReadableNT (internal/streams/readable.js:1327:12) Jun 14 18:53:50 volumiotakeru volumio[1049]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jun 14 18:53:50 volumiotakeru volumio[1049]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 18:53:51 volumiotakeru sudo[5594]: volumio : unable to resolve host volumiotakeru Jun 14 18:53:51 volumiotakeru sudo[5594]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jun 14 18:53:51 volumiotakeru sudo[5594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-14 18:52 Jun 14 18:53:51 volumiotakeru sudo[5594]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"