-- Logs begin at Fri 2024-08-16 08:53:47 -05, end at Fri 2024-08-16 16:49:34 -05. -- Aug 16 16:48:01 volumio volumio[942]: info: Enabling plugin minidlna Aug 16 16:48:01 volumio volumio[942]: info: Loading plugin "minidlna"... Aug 16 16:48:01 volumio volumio[942]: info: PLUGIN START: minidlna Aug 16 16:48:01 volumio volumio[942]: info: Loading i18n strings for locale en Aug 16 16:48:01 volumio volumio[942]: info: minidlna: Found miniDLNA version 1.2.1 Aug 16 16:48:01 volumio volumio[942]: info: minidlna: /data/minidlna.conf written Aug 16 16:48:01 volumio volumio[942]: info: minidlna: Starting minidlna.service Aug 16 16:48:01 volumio sudo[27528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Aug 16 16:48:01 volumio sudo[27528]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 16:48:01 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Aug 16 16:48:01 volumio sudo[27528]: pam_unix(sudo:session): session closed for user root Aug 16 16:48:01 volumio volumio[942]: info: minidlna: systemctl start minidlna.service succeeded. Aug 16 16:48:01 volumio volumio[942]: info: Done. Aug 16 16:48:13 volumio volumio[942]: info: CoreCommandRouter::volumioGetState Aug 16 16:48:13 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Aug 16 16:48:17 volumio volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 16 16:48:17 volumio volumio[942]: xcb_connection_has_error() returned true Aug 16 16:48:17 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 16 16:48:17 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 16 16:48:17 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 16 16:48:17 volumio volumio[942]: xcb_connection_has_error() returned true Aug 16 16:48:17 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 16 16:48:17 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 16 16:48:17 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 16 16:48:17 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 16 16:48:17 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 16 16:48:17 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 16 16:48:17 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 16 16:48:17 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 16 16:48:17 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 16 16:48:26 volumio volumio[942]: info: CoreCommandRouter::volumioGetState Aug 16 16:48:26 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Aug 16 16:48:30 volumio volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 16 16:48:30 volumio volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 16 16:48:30 volumio volumio[942]: info: Retrieving Cloud Streaming UI Aug 16 16:48:30 volumio volumio[942]: info: Getting Tidal Cloud Configuration Aug 16 16:48:30 volumio volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 16 16:48:30 volumio volumio[942]: info: Getting Qobuz Cloud Configuration Aug 16 16:48:30 volumio volumio[942]: info: Asking plugin for UI Config Aug 16 16:48:30 volumio volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 16 16:48:30 volumio volumio[942]: info: Getting Spotify Cloud Configuration Aug 16 16:48:30 volumio volumio[942]: info: Asking plugin for UI Config Aug 16 16:48:30 volumio volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 16 16:48:30 volumio volumio[942]: info: Saving Spotify Acccount Aug 16 16:48:30 volumio volumio[942]: info: Got it Aug 16 16:48:30 volumio volumio[942]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 16 16:48:30 volumio volumio[942]: info: Got Tidal Cloud Configuration Aug 16 16:48:30 volumio volumio[942]: info: Got it Aug 16 16:48:30 volumio volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 16 16:48:30 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 16 16:48:30 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 16 16:48:30 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 16 16:48:30 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 16 16:48:30 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 16 16:48:30 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 16 16:48:30 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 16 16:48:30 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 16 16:48:30 volumio volumio[942]: info: CoreCommandRouter::volumioGetBrowseSources Aug 16 16:48:30 volumio volumio[942]: info: CoreCommandRouter::volumioGetBrowseSources Aug 16 16:48:30 volumio volumio[942]: info: CoreCommandRouter::volumioGetBrowseSources Aug 16 16:48:30 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 16 16:48:30 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 16 16:48:34 volumio volumio[942]: info: CoreCommandRouter::volumioGetState Aug 16 16:48:34 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Aug 16 16:48:35 volumio volumio[942]: info: Tunnel connection is inactive, restarting it Aug 16 16:48:35 volumio volumio[942]: info: Starting Tunnel 1 Aug 16 16:48:35 volumio volumio[942]: info: Starting Tunnel Connection Checker Aug 16 16:48:35 volumio sudo[27547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Aug 16 16:48:35 volumio sudo[27547]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 16:48:35 volumio autossh[26837]: received signal to exit (15) Aug 16 16:48:35 volumio systemd[1]: Stopping MyVolumio SSH Tunnel... Aug 16 16:48:35 volumio systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Aug 16 16:48:35 volumio systemd[1]: sshtunnel.service: Succeeded. Aug 16 16:48:35 volumio volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 16 16:48:35 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Aug 16 16:48:35 volumio volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 16 16:48:35 volumio systemd[1]: Started MyVolumio SSH Tunnel. Aug 16 16:48:35 volumio sudo[27547]: pam_unix(sudo:session): session closed for user root Aug 16 16:48:35 volumio volumio[942]: info: Remote SSH Started Aug 16 16:48:35 volumio autossh[27551]: port set to 0, monitoring disabled Aug 16 16:48:35 volumio autossh[27551]: starting ssh (count 1) Aug 16 16:48:35 volumio autossh[27551]: ssh child pid is 27554 Aug 16 16:48:35 volumio volumio[942]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Aug 16 16:48:35 volumio volumio[942]: info: CoreCommandRouter::volumioGetState Aug 16 16:48:35 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Aug 16 16:48:36 volumio volumiossh-tunnel[27549]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Aug 16 16:48:40 volumio volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 16 16:48:40 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 16 16:48:40 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 16 16:48:40 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 16 16:48:40 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Aug 16 16:48:40 volumio volumio[942]: info: Received Get System Version Aug 16 16:48:40 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 16 16:49:08 volumio volumio[942]: info: CoreCommandRouter::volumioGetState Aug 16 16:49:08 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Aug 16 16:49:12 volumio volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 16 16:49:12 volumio volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 16 16:49:12 volumio volumio[942]: info: Retrieving Cloud Streaming UI Aug 16 16:49:12 volumio volumio[942]: info: Getting Tidal Cloud Configuration Aug 16 16:49:12 volumio volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 16 16:49:12 volumio volumio[942]: info: Getting Qobuz Cloud Configuration Aug 16 16:49:12 volumio volumio[942]: info: Asking plugin for UI Config Aug 16 16:49:12 volumio volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 16 16:49:12 volumio volumio[942]: info: Getting Spotify Cloud Configuration Aug 16 16:49:12 volumio volumio[942]: info: Asking plugin for UI Config Aug 16 16:49:12 volumio volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 16 16:49:12 volumio volumio[942]: info: Saving Spotify Acccount Aug 16 16:49:12 volumio volumio[942]: info: Got it Aug 16 16:49:12 volumio volumio[942]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 16 16:49:12 volumio volumio[942]: info: Got Tidal Cloud Configuration Aug 16 16:49:12 volumio volumio[942]: info: Got it Aug 16 16:49:12 volumio volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 16 16:49:12 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 16 16:49:12 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 16 16:49:12 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 16 16:49:12 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 16 16:49:12 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 16 16:49:12 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 16 16:49:12 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 16 16:49:12 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 16 16:49:12 volumio volumio[942]: info: CoreCommandRouter::volumioGetBrowseSources Aug 16 16:49:12 volumio volumio[942]: info: CoreCommandRouter::volumioGetBrowseSources Aug 16 16:49:12 volumio volumio[942]: info: CoreCommandRouter::volumioGetBrowseSources Aug 16 16:49:12 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 16 16:49:12 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 16 16:49:16 volumio volumio[942]: info: CoreCommandRouter::volumioGetState Aug 16 16:49:16 volumio volumio[942]: info: CorePlayQueue::getTrack 0 Aug 16 16:49:21 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 16 16:49:21 volumio volumio[942]: info: Preload queue cleared Aug 16 16:49:24 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 16 16:49:24 volumio volumio[942]: info: Preload queue cleared Aug 16 16:49:32 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 16 16:49:32 volumio volumio[942]: info: Preload queue cleared Aug 16 16:49:34 volumio volumio[942]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 16 16:49:34 volumio volumio[942]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 16 16:49:34 volumio volumio[942]: TypeError: Cannot read property 'length' of undefined Aug 16 16:49:34 volumio volumio[942]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Aug 16 16:49:34 volumio volumio[942]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Aug 16 16:49:34 volumio volumio[942]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Aug 16 16:49:34 volumio volumio[942]: at Parser.emit (events.js:315:20) Aug 16 16:49:34 volumio volumio[942]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Aug 16 16:49:34 volumio volumio[942]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Aug 16 16:49:34 volumio volumio[942]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Aug 16 16:49:34 volumio volumio[942]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Aug 16 16:49:34 volumio volumio[942]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Aug 16 16:49:34 volumio volumio[942]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Aug 16 16:49:34 volumio volumio[942]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Aug 16 16:49:34 volumio volumio[942]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Aug 16 16:49:34 volumio volumio[942]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Aug 16 16:49:34 volumio volumio[942]: at IncomingMessage.emit (events.js:327:22) Aug 16 16:49:34 volumio volumio[942]: at endReadableNT (internal/streams/readable.js:1327:12) Aug 16 16:49:34 volumio volumio[942]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 16 16:49:34 volumio volumio[942]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 16 16:49:34 volumio sudo[27636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-16 16:48 Aug 16 16:49:34 volumio sudo[27636]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"