-- Logs begin at Tue 2024-06-04 20:46:46 CEST, end at Fri 2024-07-26 15:58:13 CEST. -- Jul 26 15:57:01 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 26 15:57:05 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 26 15:57:07 volumio volumio[706]: info: CoreCommandRouter::volumioGetState Jul 26 15:57:07 volumio volumio[706]: info: Listing playlists Jul 26 15:57:07 volumio volumio[706]: info: Listing playlists Jul 26 15:57:09 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 26 15:57:13 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 26 15:57:17 volumio volumio[706]: info: CoreCommandRouter::volumioGetState Jul 26 15:57:17 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 26 15:57:21 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 26 15:57:25 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 26 15:57:27 volumio volumio[706]: info: CoreCommandRouter::volumioGetState Jul 26 15:57:27 volumio volumio[706]: info: Listing playlists Jul 26 15:57:27 volumio volumio[706]: info: Listing playlists Jul 26 15:57:29 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 26 15:57:33 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Jul 26 15:57:33 volumio volumio[706]: info: Adding a new share Jul 26 15:57:33 volumio volumio[706]: info: No correspondence found in configuration for share muziek on IP 192.168.2.20 Jul 26 15:57:33 volumio sudo[1907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=W@@rOm1983,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.20/muziek /mnt/NAS/muziek Jul 26 15:57:33 volumio sudo[1907]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 15:57:33 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 26 15:57:34 volumio kernel: Key type cifs.spnego registered Jul 26 15:57:34 volumio kernel: Key type cifs.idmap registered Jul 26 15:57:34 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. Jul 26 15:57:34 volumio kernel: CIFS: Attempting to mount \\192.168.2.20\muziek Jul 26 15:57:34 volumio sudo[1907]: pam_unix(sudo:session): session closed for user root Jul 26 15:57:34 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status Jul 26 15:57:35 volumio volumio[706]: info: Jul 26 15:57:35 volumio volumio[706]: ---------------------------- MPD announces state update: update Jul 26 15:57:35 volumio volumio[706]: info: ControllerMpd::getState Jul 26 15:57:35 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status Jul 26 15:57:35 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status Jul 26 15:57:35 volumio volumio[706]: info: Jul 26 15:57:35 volumio volumio[706]: ---------------------------- MPD announces state update: update Jul 26 15:57:35 volumio volumio[706]: info: ControllerMpd::getState Jul 26 15:57:35 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status Jul 26 15:57:35 volumio volumio[706]: info: sendMpdCommand status took 55 milliseconds Jul 26 15:57:35 volumio volumio[706]: info: sendMpdCommand status took 34 milliseconds Jul 26 15:57:35 volumio volumio[706]: info: sendMpdCommand status took 30 milliseconds Jul 26 15:57:35 volumio volumio[706]: info: sendMpdCommand status took 21 milliseconds Jul 26 15:57:35 volumio volumio[706]: verbose: ControllerMpd::parseState Jul 26 15:57:35 volumio volumio[706]: info: Command Router : Notfying DB Updatetrue Jul 26 15:57:35 volumio volumio[706]: verbose: ControllerMpd::parseState Jul 26 15:57:35 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 15:57:35 volumio volumio[706]: verbose: ControllerMpd::parseState Jul 26 15:57:35 volumio volumio[706]: info: Command Router : Notfying DB Updatetrue Jul 26 15:57:35 volumio volumio[706]: info: CoreCommandRouter::Close All Modals sent Jul 26 15:57:35 volumio volumio[706]: verbose: ControllerMpd::parseState Jul 26 15:57:35 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 15:57:35 volumio volumio[706]: info: [1722002255539] Database update started Jul 26 15:57:35 volumio volumio[706]: info: sendMpdCommand playlistinfo took 287 milliseconds Jul 26 15:57:35 volumio volumio[706]: info: sendMpdCommand playlistinfo took 193 milliseconds Jul 26 15:57:35 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo Jul 26 15:57:35 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo Jul 26 15:57:35 volumio volumio[706]: info: ControllerMpd::pushState Jul 26 15:57:35 volumio volumio[706]: info: CoreCommandRouter::servicePushState Jul 26 15:57:35 volumio volumio[706]: info: CoreStateMachine::pushState Jul 26 15:57:35 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 15:57:35 volumio volumio[706]: info: CoreCommandRouter::volumioPushState Jul 26 15:57:35 volumio volumio[706]: info: CorePlayQueue::getTrack 0 Jul 26 15:57:35 volumio volumio[706]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_fourfm","artist":null,"album":null,"uri":"http://stream.live.vc.bbcmedia.co.uk/bbc_radio_fourfm","trackType":""} Jul 26 15:57:35 volumio volumio[706]: verbose: CURRENT POSITION 0 Jul 26 15:57:35 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop Jul 26 15:57:35 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop Jul 26 15:57:35 volumio volumio[706]: info: CoreStateMachine::pushState Jul 26 15:57:35 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 15:57:35 volumio volumio[706]: info: CoreCommandRouter::volumioPushState Jul 26 15:57:35 volumio volumio[706]: info: No code Jul 26 15:57:35 volumio volumio[706]: info: CoreStateMachine::pushState Jul 26 15:57:35 volumio volumio[706]: info: CoreCommandRouter::volumioPushState Jul 26 15:57:35 volumio volumio[706]: info: ControllerMpd::pushState Jul 26 15:57:35 volumio volumio[706]: info: CoreCommandRouter::servicePushState Jul 26 15:57:35 volumio volumio[706]: info: CoreStateMachine::pushState Jul 26 15:57:35 volumio volumio[706]: info: CoreCommandRouter::volumioPushState Jul 26 15:57:35 volumio volumio[706]: info: CorePlayQueue::getTrack 0 Jul 26 15:57:35 volumio volumio[706]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_fourfm","artist":null,"album":null,"uri":"http://stream.live.vc.bbcmedia.co.uk/bbc_radio_fourfm","trackType":""} Jul 26 15:57:35 volumio volumio[706]: verbose: CURRENT POSITION 0 Jul 26 15:57:35 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop Jul 26 15:57:35 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop Jul 26 15:57:35 volumio volumio[706]: info: CoreStateMachine::pushState Jul 26 15:57:35 volumio volumio[706]: info: CoreCommandRouter::volumioPushState Jul 26 15:57:35 volumio volumio[706]: info: No code Jul 26 15:57:35 volumio volumio[706]: info: CoreStateMachine::pushState Jul 26 15:57:35 volumio volumio[706]: info: CoreCommandRouter::volumioPushState Jul 26 15:57:36 volumio volumio[706]: info: ------------------------------ 1476ms Jul 26 15:57:36 volumio volumio[706]: info: ------------------------------ 1472ms Jul 26 15:57:36 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 26 15:57:37 volumio volumio[706]: info: CoreCommandRouter::volumioGetState Jul 26 15:57:37 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 26 15:57:41 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 26 15:57:43 volumio volumio[706]: error: error Jul 26 15:57:43 volumio volumio[706]: error: error Jul 26 15:57:43 volumio volumio[706]: error: error Jul 26 15:57:43 volumio volumio[706]: error: error Jul 26 15:57:44 volumio volumio[706]: error: error Jul 26 15:57:44 volumio volumio[706]: error: error Jul 26 15:57:44 volumio volumio[706]: error: error Jul 26 15:57:44 volumio volumio[706]: error: error Jul 26 15:57:44 volumio volumio[706]: error: error Jul 26 15:57:44 volumio volumio[706]: error: error Jul 26 15:57:45 volumio volumio[706]: error: error Jul 26 15:57:45 volumio volumio[706]: error: error Jul 26 15:57:45 volumio volumio[706]: error: error Jul 26 15:57:45 volumio volumio[706]: error: error Jul 26 15:57:45 volumio volumio[706]: error: error Jul 26 15:57:45 volumio volumio[706]: error: error Jul 26 15:57:45 volumio volumio[706]: error: error Jul 26 15:57:45 volumio volumio[706]: error: error Jul 26 15:57:50 volumio volumio-remote-updater[449]: [2024-07-26 13:57:50] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Jul 26 15:57:52 volumio volumio[706]: info: CoreCommandRouter::volumioGetState Jul 26 15:57:52 volumio volumio[706]: info: Listing playlists Jul 26 15:57:52 volumio volumio[706]: info: Listing playlists Jul 26 15:57:52 volumio volumio[706]: info: CoreCommandRouter::volumioGetState Jul 26 15:57:53 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 26 15:57:53 volumio volumio[706]: info: Preload queue cleared Jul 26 15:57:54 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 26 15:57:55 volumio volumio-remote-updater[449]: [2024-07-26 13:57:55] [connect] Successful connection Jul 26 15:57:55 volumio volumio-remote-updater[449]: [2024-07-26 13:57:55] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1722002275 101 Jul 26 15:57:55 volumio volumio[706]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 5 Jul 26 15:57:56 volumio volumio[706]: info: Preload queue cleared Jul 26 15:57:57 volumio volumio[706]: info: CoreCommandRouter::volumioGetState Jul 26 15:57:58 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 26 15:57:59 volumio volumio[706]: info: Preload queue cleared Jul 26 15:58:02 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 26 15:58:03 volumio volumio[706]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 26 15:58:03 volumio volumio[706]: TypeError: Cannot read property 'length' of undefined Jul 26 15:58:03 volumio volumio[706]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jul 26 15:58:03 volumio volumio[706]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jul 26 15:58:03 volumio volumio[706]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jul 26 15:58:03 volumio volumio[706]: at Parser.emit (events.js:315:20) Jul 26 15:58:03 volumio volumio[706]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jul 26 15:58:03 volumio volumio[706]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jul 26 15:58:03 volumio volumio[706]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jul 26 15:58:03 volumio volumio[706]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jul 26 15:58:03 volumio volumio[706]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jul 26 15:58:03 volumio volumio[706]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jul 26 15:58:03 volumio volumio[706]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jul 26 15:58:03 volumio volumio[706]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jul 26 15:58:03 volumio volumio[706]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jul 26 15:58:03 volumio volumio[706]: at IncomingMessage.emit (events.js:327:22) Jul 26 15:58:03 volumio volumio[706]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 26 15:58:03 volumio volumio[706]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 26 15:58:03 volumio volumio[706]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 26 15:58:13 volumio sudo[2012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-26 15:57 Jul 26 15:58:13 volumio sudo[2012]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"