-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Tue 2025-07-01 12:05:13 CEST. -- Jul 01 12:04:00 volumio sudo[5456]: pam_unix(sudo:session): session closed for user root Jul 01 12:04:02 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 01 12:04:02 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 01 12:04:02 volumio volumio[1060]: info: Retrieving Cloud Streaming UI Jul 01 12:04:02 volumio volumio[1060]: info: Getting Tidal Cloud Configuration Jul 01 12:04:02 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 01 12:04:02 volumio volumio[1060]: info: Getting Qobuz Cloud Configuration Jul 01 12:04:02 volumio volumio[1060]: info: Asking plugin for UI Config Jul 01 12:04:02 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 01 12:04:02 volumio volumio[1060]: info: Getting Spotify Cloud Configuration Jul 01 12:04:02 volumio volumio[1060]: info: Asking plugin for UI Config Jul 01 12:04:02 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 01 12:04:02 volumio volumio[1060]: info: Saving Spotify Acccount Jul 01 12:04:02 volumio volumio[1060]: info: Got Tidal Cloud Configuration Jul 01 12:04:02 volumio volumio[1060]: info: Got it Jul 01 12:04:02 volumio volumio[1060]: info: Got it Jul 01 12:04:02 volumio volumio[1060]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 01 12:04:02 volumio volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 01 12:04:02 volumio volumio[1060]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jul 01 12:04:02 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 01 12:04:02 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 01 12:04:02 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 01 12:04:02 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 01 12:04:02 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 01 12:04:02 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 01 12:04:02 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 01 12:04:02 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 01 12:04:02 volumio volumio[1060]: info: CoreCommandRouter::volumioGetBrowseSources Jul 01 12:04:02 volumio volumio[1060]: info: CoreCommandRouter::volumioGetBrowseSources Jul 01 12:04:02 volumio volumio[1060]: info: CoreCommandRouter::volumioGetBrowseSources Jul 01 12:04:02 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 01 12:04:02 volumio mpd[1294]: exception: No database Jul 01 12:04:02 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 01 12:04:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 01 12:04:06 volumio mpd[1294]: exception: No database Jul 01 12:04:06 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jul 01 12:04:06 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand rescan Jul 01 12:04:10 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 01 12:04:10 volumio mpd[1294]: exception: No database Jul 01 12:04:10 volumio wpa_supplicant[926]: RRM: Ignoring radio measurement request: Not RRM network Jul 01 12:04:13 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jul 01 12:04:13 volumio volumio[1060]: verbose: ControllerMpd::sendMpdCommand update Jul 01 12:04:17 volumio wpa_supplicant[926]: RRM: Ignoring radio measurement request: Not RRM network Jul 01 12:04:18 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 01 12:04:18 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 01 12:04:18 volumio volumio[1060]: info: Discovery: Getting this device information Jul 01 12:04:18 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Jul 01 12:04:18 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 01 12:04:18 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 01 12:04:21 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 01 12:04:21 volumio volumio[1060]: info: Preload queue cleared Jul 01 12:04:22 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 01 12:04:23 volumio wpa_supplicant[926]: RRM: Ignoring radio measurement request: Not RRM network Jul 01 12:04:23 volumio volumio[1060]: info: Preload queue cleared Jul 01 12:04:25 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 01 12:04:25 volumio volumio[1060]: info: Preload queue cleared Jul 01 12:04:27 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 01 12:04:27 volumio volumio[1060]: info: Preload queue cleared Jul 01 12:04:28 volumio wpa_supplicant[926]: RRM: Ignoring radio measurement request: Not RRM network Jul 01 12:04:28 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 01 12:04:29 volumio volumio[1060]: info: Preload queue cleared Jul 01 12:04:29 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 01 12:04:30 volumio volumio[1060]: info: Preload queue cleared Jul 01 12:04:30 volumio volumio[1060]: info: Preloading song: upnp/http://192.168.178.1:49000/MediaServer/ContentDirectory/Control@5:audio5:part21:12:23 Jul 01 12:04:30 volumio volumio[1060]: info: Exploding uri upnp/http://192.168.178.1:49000/MediaServer/ContentDirectory/Control@5:audio5:part21:12:23 in service upnp_browser Jul 01 12:04:30 volumio volumio[1060]: info: No valid Plugin REST Endpoint Jul 01 12:04:30 volumio volumio[1060]: info: No valid Plugin REST Endpoint Jul 01 12:04:41 volumio kernel: usb 1-1.1: USB disconnect, device number 3 Jul 01 12:04:41 volumio volumio[1060]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-partuuid/7a5053fc-01 /dev/disk/by-id/usb-Mass_Storage_Device_121220160204-0:0-part1 /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.1:1.0-scsi-0:0:0:0-part1 /dev/disk/by-uuid/4C9E-CDDB /dev/disk/by-label/1_1TB","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.1/1-1.1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"28","ID_BUS":"usb","ID_FS_LABEL":"1_1TB","ID_FS_LABEL_ENC":"1_1TB","ID_FS_TYPE":"exfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"4C9E-CDDB","ID_FS_UUID_ENC":"4C9E-CDDB","ID_FS_VERSION":"1.0","ID_INSTANCE":"0:0","ID_MODEL":"Storage_Device","ID_MODEL_ENC":"Storage\\x20Device\\x20\\x20","ID_MODEL_ID":"1212","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"2097149696","ID_PART_ENTRY_TYPE":"0x7","ID_PART_ENTRY_UUID":"7a5053fc-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"7a5053fc","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_1_1_0-scsi-0_0_0_0","ID_REVISION":"1.00","ID_SERIAL":"Mass_Storage_Device_121220160204-0:0","ID_SERIAL_SHORT":"121220160204","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Mass","ID_VENDOR_ENC":"Mass\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"14cd","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2445","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"3521115"} Jul 01 12:04:41 volumio sudo[5530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 Jul 01 12:04:41 volumio sudo[5530]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 12:04:41 volumio systemd[1]: media-1_1TB.mount: Succeeded. Jul 01 12:04:41 volumio sudo[5530]: pam_unix(sudo:session): session closed for user root Jul 01 12:04:41 volumio mount.exfat[1154]: failed to write super block Jul 01 12:04:45 volumio volumio[1060]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/1_1TB Jul 01 12:04:46 volumio volumio[1060]: MPD error: No database Jul 01 12:04:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 01 12:04:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 01 12:04:48 volumio volumio[1060]: info: Discovery: Getting this device information Jul 01 12:04:48 volumio volumio[1060]: info: CoreCommandRouter::volumioGetState Jul 01 12:04:48 volumio volumio[1060]: info: CorePlayQueue::getTrack 0 Jul 01 12:04:48 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 01 12:05:00 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 01 12:05:00 volumio volumio[1060]: info: Preload queue cleared Jul 01 12:05:01 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 01 12:05:01 volumio volumio[1060]: info: Preload queue cleared Jul 01 12:05:03 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 01 12:05:03 volumio volumio[1060]: info: Preload queue cleared Jul 01 12:05:04 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 01 12:05:04 volumio volumio[1060]: info: Preload queue cleared Jul 01 12:05:04 volumio volumio[1060]: info: No valid Plugin REST Endpoint Jul 01 12:05:04 volumio volumio[1060]: info: No valid Plugin REST Endpoint Jul 01 12:05:09 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 01 12:05:09 volumio volumio[1060]: info: Preload queue cleared Jul 01 12:05:09 volumio volumio[1060]: info: Preloading song: upnp/http://192.168.178.1:49000/MediaServer/ContentDirectory/Control@indexation_upnp_id Jul 01 12:05:09 volumio volumio[1060]: info: Exploding uri upnp/http://192.168.178.1:49000/MediaServer/ContentDirectory/Control@indexation_upnp_id in service upnp_browser Jul 01 12:05:11 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 01 12:05:11 volumio volumio[1060]: info: Preload queue cleared Jul 01 12:05:13 volumio volumio[1060]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 01 12:05:13 volumio volumio[1060]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 01 12:05:13 volumio volumio[1060]: TypeError: Cannot read property 'length' of undefined Jul 01 12:05:13 volumio volumio[1060]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jul 01 12:05:13 volumio volumio[1060]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jul 01 12:05:13 volumio volumio[1060]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jul 01 12:05:13 volumio volumio[1060]: at Parser.emit (events.js:315:20) Jul 01 12:05:13 volumio volumio[1060]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jul 01 12:05:13 volumio volumio[1060]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jul 01 12:05:13 volumio volumio[1060]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jul 01 12:05:13 volumio volumio[1060]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jul 01 12:05:13 volumio volumio[1060]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jul 01 12:05:13 volumio volumio[1060]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jul 01 12:05:13 volumio volumio[1060]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jul 01 12:05:13 volumio volumio[1060]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jul 01 12:05:13 volumio volumio[1060]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jul 01 12:05:13 volumio volumio[1060]: at IncomingMessage.emit (events.js:327:22) Jul 01 12:05:13 volumio volumio[1060]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 01 12:05:13 volumio volumio[1060]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 01 12:05:13 volumio volumio[1060]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 01 12:05:13 volumio sudo[5631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-01 12:04 Jul 01 12:05:13 volumio sudo[5631]: 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"