-- Logs begin at Wed 2025-07-02 08:12:23 BST, end at Thu 2025-07-03 12:19:20 BST. -- Jul 03 12:18:00 dexys-rivo volumio[17748]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 9 Jul 03 12:18:00 dexys-rivo volumio[17748]: info: CoreCommandRouter::volumioGetState Jul 03 12:18:01 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 03 12:18:01 dexys-rivo volumio[17748]: info: Received Get System Info Jul 03 12:18:01 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 12:18:01 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 12:18:01 dexys-rivo volumio[17748]: info: Discovery: Getting this device information Jul 03 12:18:01 dexys-rivo volumio[17748]: info: CoreCommandRouter::volumioGetState Jul 03 12:18:01 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 12:18:01 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 03 12:18:01 dexys-rivo volumio[17748]: info: Received Get System Info Jul 03 12:18:01 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 12:18:01 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 12:18:01 dexys-rivo volumio[17748]: info: Discovery: Getting this device information Jul 03 12:18:01 dexys-rivo volumio[17748]: info: CoreCommandRouter::volumioGetState Jul 03 12:18:01 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 12:18:01 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 03 12:18:01 dexys-rivo volumio[17748]: info: Listing playlists Jul 03 12:18:01 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 03 12:18:01 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 03 12:18:01 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 03 12:18:01 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 03 12:18:01 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 03 12:18:01 dexys-rivo volumio[17748]: info: Received Get System Info Jul 03 12:18:01 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 12:18:01 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 12:18:01 dexys-rivo volumio[17748]: info: Discovery: Getting this device information Jul 03 12:18:01 dexys-rivo volumio[17748]: info: CoreCommandRouter::volumioGetState Jul 03 12:18:01 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 12:18:02 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 03 12:18:06 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 03 12:18:06 dexys-rivo volumio[17748]: info: Received Get System Info Jul 03 12:18:06 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 12:18:06 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 12:18:06 dexys-rivo volumio[17748]: info: Discovery: Getting this device information Jul 03 12:18:06 dexys-rivo volumio[17748]: info: CoreCommandRouter::volumioGetState Jul 03 12:18:06 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 12:18:18 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 03 12:18:18 dexys-rivo volumio[17748]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 03 12:18:18 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 03 12:18:18 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 03 12:18:18 dexys-rivo volumio[17748]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 03 12:18:18 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 03 12:18:18 dexys-rivo volumio[17748]: info: Received Get System Version Jul 03 12:18:18 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 12:18:18 dexys-rivo volumio[17748]: info: Received Get System Info Jul 03 12:18:18 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 12:18:18 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 12:18:18 dexys-rivo volumio[17748]: info: Discovery: Getting this device information Jul 03 12:18:18 dexys-rivo volumio[17748]: info: CoreCommandRouter::volumioGetState Jul 03 12:18:18 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 12:18:18 dexys-rivo volumio[17748]: info: Received Get System Version Jul 03 12:18:18 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 12:18:18 dexys-rivo volumio[17748]: info: Received Get System Info Jul 03 12:18:18 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 12:18:18 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 12:18:18 dexys-rivo volumio[17748]: info: Discovery: Getting this device information Jul 03 12:18:18 dexys-rivo volumio[17748]: info: CoreCommandRouter::volumioGetState Jul 03 12:18:18 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 12:18:23 dexys-rivo volumio[17748]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 03 12:18:23 dexys-rivo volumio[17748]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 03 12:18:23 dexys-rivo volumio[17748]: info: Retrieving Cloud Streaming UI Jul 03 12:18:23 dexys-rivo volumio[17748]: info: Getting Tidal Cloud Configuration Jul 03 12:18:23 dexys-rivo volumio[17748]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 03 12:18:23 dexys-rivo volumio[17748]: info: Getting Qobuz Cloud Configuration Jul 03 12:18:23 dexys-rivo volumio[17748]: info: Asking plugin for UI Config Jul 03 12:18:23 dexys-rivo volumio[17748]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 03 12:18:23 dexys-rivo volumio[17748]: info: Getting Spotify Cloud Configuration Jul 03 12:18:23 dexys-rivo volumio[17748]: info: Asking plugin for UI Config Jul 03 12:18:23 dexys-rivo volumio[17748]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 03 12:18:23 dexys-rivo volumio[17748]: info: Saving Spotify Acccount Jul 03 12:18:23 dexys-rivo volumio[17748]: info: Got it Jul 03 12:18:23 dexys-rivo volumio[17748]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 03 12:18:23 dexys-rivo volumio[17748]: info: Got Tidal Cloud Configuration Jul 03 12:18:23 dexys-rivo volumio[17748]: info: Got it Jul 03 12:18:23 dexys-rivo volumio[17748]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 03 12:18:23 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 03 12:18:23 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 03 12:18:23 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 03 12:18:23 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 03 12:18:23 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 12:18:23 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 12:18:23 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 12:18:23 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 12:18:23 dexys-rivo volumio[17748]: info: CoreCommandRouter::volumioGetBrowseSources Jul 03 12:18:23 dexys-rivo volumio[17748]: info: CoreCommandRouter::volumioGetBrowseSources Jul 03 12:18:23 dexys-rivo volumio[17748]: info: CoreCommandRouter::volumioGetBrowseSources Jul 03 12:18:23 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 03 12:18:23 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 03 12:18:28 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 03 12:18:31 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 12:18:31 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 12:18:31 dexys-rivo volumio[17748]: info: Discovery: Getting this device information Jul 03 12:18:31 dexys-rivo volumio[17748]: info: CoreCommandRouter::volumioGetState Jul 03 12:18:31 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 12:18:32 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 03 12:18:36 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 03 12:18:41 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 03 12:18:43 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 03 12:18:47 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 03 12:18:52 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 03 12:18:55 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 03 12:18:55 dexys-rivo volumiologrotate[2830]: ls: cannot access '/var/log/samba/log.wb-DEXYS': No such file or directory Jul 03 12:18:55 dexys-rivo volumiologrotate[2830]: ls: cannot access 'RIVO': No such file or directory Jul 03 12:19:00 dexys-rivo volumio[17748]: info: CoreCommandRouter::volumioGetQueue Jul 03 12:19:00 dexys-rivo volumio[17748]: info: CoreStateMachine::getQueue Jul 03 12:19:00 dexys-rivo volumio[17748]: info: CorePlayQueue::getQueue Jul 03 12:19:00 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 12:19:00 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 12:19:00 dexys-rivo volumio[17748]: info: Discovery: Getting this device information Jul 03 12:19:00 dexys-rivo volumio[17748]: info: CoreCommandRouter::volumioGetState Jul 03 12:19:00 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 12:19:02 dexys-rivo volumio[17748]: info: CoreCommandRouter::volumioGetVisibleSources Jul 03 12:19:02 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 12:19:02 dexys-rivo volumio[17748]: info: Listing playlists Jul 03 12:19:05 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 03 12:19:05 dexys-rivo volumio[17748]: info: Preload queue cleared Jul 03 12:19:10 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 03 12:19:10 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 03 12:19:13 dexys-rivo volumio[17748]: info: Preload queue cleared Jul 03 12:19:13 dexys-rivo volumio[17748]: info: Preload queue cleared Jul 03 12:19:16 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 03 12:19:16 dexys-rivo volumio[17748]: info: Preload queue cleared Jul 03 12:19:19 dexys-rivo volumio[17748]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 03 12:19:19 dexys-rivo volumio[17748]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 12:19:19 dexys-rivo volumio[17748]: TypeError: Cannot read property 'length' of undefined Jul 03 12:19:19 dexys-rivo volumio[17748]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jul 03 12:19:19 dexys-rivo volumio[17748]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jul 03 12:19:19 dexys-rivo volumio[17748]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jul 03 12:19:19 dexys-rivo volumio[17748]: at Parser.emit (events.js:400:28) Jul 03 12:19:19 dexys-rivo volumio[17748]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jul 03 12:19:19 dexys-rivo volumio[17748]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jul 03 12:19:19 dexys-rivo volumio[17748]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jul 03 12:19:19 dexys-rivo volumio[17748]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jul 03 12:19:19 dexys-rivo volumio[17748]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jul 03 12:19:19 dexys-rivo volumio[17748]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jul 03 12:19:19 dexys-rivo volumio[17748]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jul 03 12:19:19 dexys-rivo volumio[17748]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jul 03 12:19:19 dexys-rivo volumio[17748]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jul 03 12:19:19 dexys-rivo volumio[17748]: at IncomingMessage.emit (events.js:412:35) Jul 03 12:19:19 dexys-rivo volumio[17748]: at endReadableNT (internal/streams/readable.js:1333:12) Jul 03 12:19:19 dexys-rivo volumio[17748]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jul 03 12:19:19 dexys-rivo volumio[17748]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 12:19:20 dexys-rivo sudo[2938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-03 12:18 Jul 03 12:19:20 dexys-rivo sudo[2938]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 06:01:16 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="5a6f819282ea0c6f84bf87456dce95cb"