-- Logs begin at Thu 2019-02-14 19:12:00 JST, end at Thu 2025-05-15 23:00:38 JST. -- May 15 22:59:01 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 22:59:05 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 22:59:09 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 22:59:12 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare May 15 22:59:12 tomio1 volumio[17467]: info: Adding a new share May 15 22:59:12 tomio1 volumio[17467]: info: Checking key NasMounts.6ea0ff9f-0270-4942-8c89-22f06396fe17 May 15 22:59:12 tomio1 volumio[17467]: info: No correspondence found in configuration for share soundgeneric on IP 192.168.0.88 May 15 22:59:12 tomio1 volumio[17467]: info: Executing SMB command: smbclient --debuglevel=4 -L 192.168.0.88 -N May 15 22:59:13 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 22:59:13 tomio1 volumio[17467]: info: Negotiated SMB version with soundgeneric: SMB3_11 May 15 22:59:13 tomio1 volumio[17467]: info: Detected device soundgeneric with version SMB3_11 May 15 22:59:13 tomio1 volumio[17467]: info: Guest mount: forcing fallback SMB version 2.1 for soundgeneric May 15 22:59:13 tomio1 volumio[17467]: info: Set SMB version 3.11 in CIFS options: vers=2.1 May 15 22:59:13 tomio1 sudo[18321]: 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.0.88/contents /mnt/NAS/soundgeneric May 15 22:59:13 tomio1 sudo[18321]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 22:59:13 tomio1 kernel: CIFS: Attempting to mount //192.168.0.88/contents May 15 22:59:14 tomio1 sudo[18321]: pam_unix(sudo:session): session closed for user root May 15 22:59:14 tomio1 volumio[17467]: verbose: ControllerMpd::sendMpdCommand status May 15 22:59:14 tomio1 volumio[17467]: info: May 15 22:59:14 tomio1 volumio[17467]: ---------------------------- MPD announces state update: update May 15 22:59:14 tomio1 volumio[17467]: info: ControllerMpd::getState May 15 22:59:14 tomio1 volumio[17467]: verbose: ControllerMpd::sendMpdCommand status May 15 22:59:14 tomio1 volumio[17467]: verbose: ControllerMpd::sendMpdCommand status May 15 22:59:14 tomio1 volumio[17467]: info: May 15 22:59:14 tomio1 volumio[17467]: ---------------------------- MPD announces state update: update May 15 22:59:14 tomio1 volumio[17467]: info: ControllerMpd::getState May 15 22:59:14 tomio1 volumio[17467]: verbose: ControllerMpd::sendMpdCommand status May 15 22:59:14 tomio1 volumio[17467]: verbose: ControllerMpd::sendMpdCommand status May 15 22:59:14 tomio1 volumio[17467]: info: May 15 22:59:14 tomio1 volumio[17467]: ---------------------------- MPD announces state update: update May 15 22:59:14 tomio1 volumio[17467]: info: ControllerMpd::getState May 15 22:59:14 tomio1 volumio[17467]: verbose: ControllerMpd::sendMpdCommand status May 15 22:59:14 tomio1 volumio[17467]: info: sendMpdCommand status took 31 milliseconds May 15 22:59:14 tomio1 volumio[17467]: info: sendMpdCommand status took 23 milliseconds May 15 22:59:14 tomio1 volumio[17467]: info: sendMpdCommand status took 21 milliseconds May 15 22:59:14 tomio1 volumio[17467]: info: sendMpdCommand status took 18 milliseconds May 15 22:59:14 tomio1 volumio[17467]: info: sendMpdCommand status took 16 milliseconds May 15 22:59:14 tomio1 volumio[17467]: info: sendMpdCommand status took 13 milliseconds May 15 22:59:14 tomio1 volumio[17467]: verbose: ControllerMpd::parseState May 15 22:59:15 tomio1 volumio[17467]: info: Command Router : Notfying DB Updatetrue May 15 22:59:15 tomio1 volumio[17467]: verbose: ControllerMpd::parseState May 15 22:59:15 tomio1 volumio[17467]: verbose: ControllerMpd::parseState May 15 22:59:15 tomio1 volumio[17467]: info: Command Router : Notfying DB Updatetrue May 15 22:59:15 tomio1 volumio[17467]: info: CoreCommandRouter::Close All Modals sent May 15 22:59:15 tomio1 volumio[17467]: verbose: ControllerMpd::parseState May 15 22:59:15 tomio1 volumio[17467]: verbose: ControllerMpd::parseState May 15 22:59:15 tomio1 volumio[17467]: info: Command Router : Notfying DB Updatetrue May 15 22:59:15 tomio1 volumio[17467]: info: CoreCommandRouter::Close All Modals sent May 15 22:59:15 tomio1 volumio[17467]: verbose: ControllerMpd::parseState May 15 22:59:15 tomio1 volumio[17467]: info: ControllerMpd::pushState May 15 22:59:15 tomio1 volumio[17467]: info: CoreCommandRouter::servicePushState May 15 22:59:15 tomio1 volumio[17467]: info: CoreStateMachine::pushState May 15 22:59:15 tomio1 volumio[17467]: info: CorePlayQueue::getTrack 0 May 15 22:59:15 tomio1 volumio[17467]: info: CoreCommandRouter::volumioPushState May 15 22:59:15 tomio1 volumio[17467]: info: CorePlayQueue::getTrack 0 May 15 22:59:15 tomio1 volumio[17467]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 15 22:59:15 tomio1 volumio[17467]: info: ControllerMpd::pushState May 15 22:59:15 tomio1 volumio[17467]: info: CoreCommandRouter::servicePushState May 15 22:59:15 tomio1 volumio[17467]: info: CoreStateMachine::pushState May 15 22:59:15 tomio1 volumio[17467]: info: CorePlayQueue::getTrack 0 May 15 22:59:15 tomio1 volumio[17467]: info: CoreCommandRouter::volumioPushState May 15 22:59:15 tomio1 volumio[17467]: info: CorePlayQueue::getTrack 0 May 15 22:59:15 tomio1 volumio[17467]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 15 22:59:15 tomio1 volumio[17467]: info: ControllerMpd::pushState May 15 22:59:15 tomio1 volumio[17467]: info: CoreCommandRouter::servicePushState May 15 22:59:15 tomio1 volumio[17467]: info: CoreStateMachine::pushState May 15 22:59:15 tomio1 volumio[17467]: info: CorePlayQueue::getTrack 0 May 15 22:59:15 tomio1 volumio[17467]: info: CoreCommandRouter::volumioPushState May 15 22:59:15 tomio1 volumio[17467]: info: CorePlayQueue::getTrack 0 May 15 22:59:15 tomio1 volumio[17467]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 15 22:59:15 tomio1 volumio[17467]: info: ------------------------------ 1044ms May 15 22:59:15 tomio1 volumio[17467]: info: ------------------------------ 1036ms May 15 22:59:15 tomio1 volumio[17467]: info: ------------------------------ 1031ms May 15 22:59:15 tomio1 volumio[17467]: info: [1747317555646] Database update started May 15 22:59:15 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 15 22:59:17 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 22:59:21 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 22:59:25 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 22:59:29 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 22:59:33 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 22:59:33 tomio1 volumio[17467]: error: error May 15 22:59:37 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 22:59:41 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 22:59:45 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 22:59:46 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare May 15 22:59:47 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 15 22:59:49 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 22:59:53 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 22:59:57 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 23:00:01 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 23:00:05 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 23:00:09 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 23:00:13 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 23:00:17 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 23:00:21 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 23:00:23 tomio1 volumio[17467]: error: error May 15 23:00:25 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 15 23:00:29 tomio1 volumio[17467]: info: CoreCommandRouter::volumioGetState May 15 23:00:29 tomio1 volumio[17467]: info: CorePlayQueue::getTrack 0 May 15 23:00:34 tomio1 volumio[17467]: info: CoreCommandRouter::volumioGetQueue May 15 23:00:34 tomio1 volumio[17467]: info: CoreStateMachine::getQueue May 15 23:00:34 tomio1 volumio[17467]: info: CorePlayQueue::getQueue May 15 23:00:37 tomio1 volumio[17467]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 15 23:00:37 tomio1 volumio[17467]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 23:00:37 tomio1 volumio[17467]: TypeError: Cannot read property 'length' of undefined May 15 23:00:37 tomio1 volumio[17467]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 15 23:00:37 tomio1 volumio[17467]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 15 23:00:37 tomio1 volumio[17467]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 15 23:00:37 tomio1 volumio[17467]: at Parser.emit (events.js:315:20) May 15 23:00:37 tomio1 volumio[17467]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 15 23:00:37 tomio1 volumio[17467]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 15 23:00:37 tomio1 volumio[17467]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 15 23:00:37 tomio1 volumio[17467]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 15 23:00:37 tomio1 volumio[17467]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 15 23:00:37 tomio1 volumio[17467]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 15 23:00:37 tomio1 volumio[17467]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 15 23:00:37 tomio1 volumio[17467]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 15 23:00:37 tomio1 volumio[17467]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 15 23:00:37 tomio1 volumio[17467]: at IncomingMessage.emit (events.js:327:22) May 15 23:00:37 tomio1 volumio[17467]: at endReadableNT (internal/streams/readable.js:1327:12) May 15 23:00:37 tomio1 volumio[17467]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 15 23:00:37 tomio1 volumio[17467]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 23:00:38 tomio1 sudo[18569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-15 22:59 May 15 23:00:38 tomio1 sudo[18569]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"