-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Mon 2025-01-13 19:05:52 CET. -- Jan 13 19:04:42 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 19:04:42 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 19:04:42 volumiorf volumio[821]: info: Discovery: Getting this device information Jan 13 19:04:42 volumiorf volumio[821]: info: CoreCommandRouter::volumioGetState Jan 13 19:04:42 volumiorf volumio[821]: info: CorePlayQueue::getTrack 4 Jan 13 19:04:42 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 19:04:42 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 19:04:42 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 19:04:42 volumiorf volumio[821]: info: Discovery: Getting this device information Jan 13 19:04:42 volumiorf volumio[821]: info: CoreCommandRouter::volumioGetState Jan 13 19:04:42 volumiorf volumio[821]: info: CorePlayQueue::getTrack 4 Jan 13 19:04:42 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 19:04:43 volumiorf volumio[821]: verbose: New Socket.io Connection to 192.168.1.168:3000 from 192.168.1.53 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jan 13 19:04:44 volumiorf volumio[821]: verbose: New Socket.io Connection to 192.168.1.168 from 192.168.1.53 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_2_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Jan 13 19:04:44 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 13 19:04:44 volumiorf volumio[821]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 19:04:44 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 19:04:44 volumiorf volumio[821]: info: CoreCommandRouter::volumioGetState Jan 13 19:04:44 volumiorf volumio[821]: info: CorePlayQueue::getTrack 4 Jan 13 19:04:44 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 13 19:04:44 volumiorf volumio[821]: info: CoreCommandRouter::volumioGetQueue Jan 13 19:04:44 volumiorf volumio[821]: info: CoreStateMachine::getQueue Jan 13 19:04:44 volumiorf volumio[821]: info: CorePlayQueue::getQueue Jan 13 19:04:44 volumiorf volumio[821]: info: Listing playlists Jan 13 19:04:44 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 13 19:04:44 volumiorf volumio[821]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 13 19:04:44 volumiorf volumio[821]: info: Received Get System Info Jan 13 19:04:44 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 19:04:44 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 19:04:44 volumiorf volumio[821]: info: Discovery: Getting this device information Jan 13 19:04:44 volumiorf volumio[821]: info: CoreCommandRouter::volumioGetState Jan 13 19:04:44 volumiorf volumio[821]: info: CorePlayQueue::getTrack 4 Jan 13 19:04:44 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 19:04:44 volumiorf volumio[821]: info: CoreCommandRouter::volumioGetState Jan 13 19:04:44 volumiorf volumio[821]: info: CorePlayQueue::getTrack 4 Jan 13 19:04:44 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 13 19:04:52 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 13 19:04:53 volumiorf volumio[821]: info: Preload queue cleared Jan 13 19:04:54 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 19:04:54 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 19:04:54 volumiorf volumio[821]: info: Discovery: Getting this device information Jan 13 19:04:54 volumiorf volumio[821]: info: CoreCommandRouter::volumioGetState Jan 13 19:04:54 volumiorf volumio[821]: info: CorePlayQueue::getTrack 4 Jan 13 19:04:54 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 19:04:54 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 13 19:04:54 volumiorf volumio[821]: info: Preload queue cleared Jan 13 19:04:56 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 13 19:04:56 volumiorf volumio[821]: info: Preload queue cleared Jan 13 19:05:04 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 13 19:05:04 volumiorf volumio[821]: info: Preload queue cleared Jan 13 19:05:07 volumiorf volumio[821]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Various%20Artists/6c36dfb6-23cc-42be-8a9e-401647c344af.jpg' Jan 13 19:05:21 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 13 19:05:21 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 13 19:05:24 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 19:05:24 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 19:05:24 volumiorf volumio[821]: info: Discovery: Getting this device information Jan 13 19:05:24 volumiorf volumio[821]: info: CoreCommandRouter::volumioGetState Jan 13 19:05:24 volumiorf volumio[821]: info: CorePlayQueue::getTrack 4 Jan 13 19:05:24 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 19:05:24 volumiorf volumio[821]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 19:05:27 volumiorf volumio[821]: info: CALLMETHOD: music_service minidlna saveConf [object Object] Jan 13 19:05:27 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: minidlna , saveConf Jan 13 19:05:29 volumiorf volumio[821]: info: CALLMETHOD: music_service minidlna forceRescan R Jan 13 19:05:29 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: minidlna , forceRescan Jan 13 19:05:29 volumiorf sudo[25387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e s/^Environment=DAEMON_OPTS=$/Environment=DAEMON_OPTS=-R/ /etc/systemd/system/minidlna.service Jan 13 19:05:29 volumiorf sudo[25387]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 19:05:29 volumiorf sudo[25387]: pam_unix(sudo:session): session closed for user root Jan 13 19:05:29 volumiorf sudo[25390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 13 19:05:29 volumiorf sudo[25390]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 19:05:29 volumiorf systemd[1]: Reloading. Jan 13 19:05:30 volumiorf systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 13 19:05:30 volumiorf sudo[25390]: pam_unix(sudo:session): session closed for user root Jan 13 19:05:30 volumiorf volumio[821]: info: minidlna: systemctl daemon-reload succeeded. Jan 13 19:05:30 volumiorf sudo[25407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart minidlna.service Jan 13 19:05:30 volumiorf sudo[25407]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 19:05:30 volumiorf systemd[1]: Stopping MiniDLNA lightweight DLNA/UPnP-AV server... Jan 13 19:05:30 volumiorf volumio[821]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 13 19:05:30 volumiorf systemd[1]: minidlna.service: Succeeded. Jan 13 19:05:30 volumiorf systemd[1]: Stopped MiniDLNA lightweight DLNA/UPnP-AV server. Jan 13 19:05:30 volumiorf volumio[821]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 13 19:05:30 volumiorf systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Jan 13 19:05:30 volumiorf sudo[25407]: pam_unix(sudo:session): session closed for user root Jan 13 19:05:30 volumiorf volumio[821]: info: minidlna: systemctl restart minidlna.service succeeded. Jan 13 19:05:30 volumiorf volumio[821]: info: minidlna: Rescanning the media directories. Jan 13 19:05:30 volumiorf sudo[25412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e s/^Environment=DAEMON_OPTS=-R$/Environment=DAEMON_OPTS=/ /etc/systemd/system/minidlna.service Jan 13 19:05:30 volumiorf sudo[25412]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 19:05:30 volumiorf sudo[25412]: pam_unix(sudo:session): session closed for user root Jan 13 19:05:30 volumiorf sudo[25415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 13 19:05:30 volumiorf sudo[25415]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 19:05:30 volumiorf systemd[1]: Reloading. Jan 13 19:05:30 volumiorf systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 13 19:05:31 volumiorf sudo[25415]: pam_unix(sudo:session): session closed for user root Jan 13 19:05:31 volumiorf volumio[821]: info: minidlna: systemctl daemon-reload succeeded. Jan 13 19:05:40 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 13 19:05:40 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 13 19:05:44 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 13 19:05:44 volumiorf volumio[821]: info: Preload queue cleared Jan 13 19:05:45 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 13 19:05:45 volumiorf volumio[821]: info: Preload queue cleared Jan 13 19:05:48 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 13 19:05:48 volumiorf volumio[821]: info: Preload queue cleared Jan 13 19:05:51 volumiorf volumio[821]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 13 19:05:51 volumiorf volumio[821]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 19:05:51 volumiorf volumio[821]: TypeError: Cannot read property 'length' of undefined Jan 13 19:05:51 volumiorf volumio[821]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jan 13 19:05:51 volumiorf volumio[821]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jan 13 19:05:51 volumiorf volumio[821]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jan 13 19:05:51 volumiorf volumio[821]: at Parser.emit (events.js:315:20) Jan 13 19:05:51 volumiorf volumio[821]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jan 13 19:05:51 volumiorf volumio[821]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jan 13 19:05:51 volumiorf volumio[821]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jan 13 19:05:51 volumiorf volumio[821]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jan 13 19:05:51 volumiorf volumio[821]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jan 13 19:05:51 volumiorf volumio[821]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jan 13 19:05:51 volumiorf volumio[821]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jan 13 19:05:51 volumiorf volumio[821]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jan 13 19:05:51 volumiorf volumio[821]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jan 13 19:05:51 volumiorf volumio[821]: at IncomingMessage.emit (events.js:327:22) Jan 13 19:05:51 volumiorf volumio[821]: at endReadableNT (internal/streams/readable.js:1327:12) Jan 13 19:05:51 volumiorf volumio[821]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jan 13 19:05:51 volumiorf volumio[821]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 19:05:52 volumiorf sudo[25500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-13 19:04 Jan 13 19:05:52 volumiorf sudo[25500]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"